builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0110 starttime: 1495733177.79 results: success (0) buildid: 20170525093444 builduid: 6cea08e737b8452ab18ddb0c798c0ddc revision: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:17.787853) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:17.788266) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:17.788567) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:17.813769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:17.814107) ========= 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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-25 10:26:17-- 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% 23.9M=0s 2017-05-25 10:26:17 (23.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111576 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:17.948753) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:17.949090) ========= 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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.029323 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:17.999123) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 10:26:17.999495) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d3ce3e3812c9372a12b6416f0ceedd2e768b5277 --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 d3ce3e3812c9372a12b6416f0ceedd2e768b5277 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qFM8Y45LrC/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.fCRFWkaVfd/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-25 10:26:18,097 truncating revision to first 12 chars 2017-05-25 10:26:18,097 Setting DEBUG logging. 2017-05-25 10:26:18,097 attempt 1/10 2017-05-25 10:26:18,097 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d3ce3e3812c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 10:26:19,097 unpacking tar archive at: mozilla-beta-d3ce3e3812c9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.389462 ========= master_lag: 1.58 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-25 10:26:20.966571) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:20.966893) ========= script_repo_revision: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:20.967287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:20.967550) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 10:26:21.576276) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-25 10:26:21.576653) ========= /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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:26:21 INFO - MultiFileLogger online at 20170525 10:26:21 in /builds/slave/test 10:26:21 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 10:26:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:26:21 INFO - {'append_to_log': False, 10:26:21 INFO - 'base_work_dir': '/builds/slave/test', 10:26:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:26:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:26:21 INFO - 'branch': 'Mozilla-Beta', 10:26:21 INFO - 'buildbot_json_path': 'buildprops.json', 10:26:21 INFO - 'config_files': ('talos/mac_config.py',), 10:26:21 INFO - 'default_actions': ('clobber', 10:26:21 INFO - 'read-buildbot-config', 10:26:21 INFO - 'download-and-extract', 10:26:21 INFO - 'populate-webroot', 10:26:21 INFO - 'create-virtualenv', 10:26:21 INFO - 'install', 10:26:21 INFO - 'run-tests'), 10:26:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:26:21 INFO - 'download_minidump_stackwalk': True, 10:26:21 INFO - 'download_symbols': 'ondemand', 10:26:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:26:21 INFO - 'tooltool.py': '/tools/tooltool.py', 10:26:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:26:21 INFO - '/tools/misc-python/virtualenv.py')}, 10:26:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:26:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:26:21 INFO - 'gecko_profile': False, 10:26:21 INFO - 'gecko_profile_interval': 0, 10:26:21 INFO - 'installer_path': 'installer.exe', 10:26:21 INFO - 'log_level': 'info', 10:26:21 INFO - 'log_name': 'talos', 10:26:21 INFO - 'log_to_console': True, 10:26:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:26:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:26:21 INFO - 'opt_config_files': (), 10:26:21 INFO - 'pip_index': False, 10:26:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:26:21 INFO - 'cmd': ('bash', 10:26:21 INFO - '-c', 10:26:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:26:21 INFO - 'enabled': True, 10:26:21 INFO - 'halt_on_failure': False, 10:26:21 INFO - 'name': 'check_screen_resolution'},), 10:26:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:26:21 INFO - 'cmd': ('bash', 10:26:21 INFO - '-c', 10:26:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:26:21 INFO - 'enabled': True, 10:26:21 INFO - 'halt_on_failure': False, 10:26:21 INFO - 'name': 'check_screen_resolution'},), 10:26:21 INFO - 'run_cmd_checks_enabled': True, 10:26:21 INFO - 'suite': 'dromaeojs-e10s', 10:26:21 INFO - 'system_bits': '32', 10:26:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:26:21 INFO - 'title': 't-yosemite-r7-0110', 10:26:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:26:21 INFO - 'use_talos_json': True, 10:26:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:26:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:26:21 INFO - 'work_dir': 'build'} 10:26:21 INFO - [mozharness: 2017-05-25 17:26:21.789122Z] Running clobber step. 10:26:21 INFO - Running pre-action listener: _resource_record_pre_action 10:26:21 INFO - Running main action method: clobber 10:26:21 INFO - rmtree: /builds/slave/test/build 10:26:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:26:26 INFO - Running post-action listener: _resource_record_post_action 10:26:26 INFO - [mozharness: 2017-05-25 17:26:26.705316Z] Finished clobber step (success) 10:26:26 INFO - [mozharness: 2017-05-25 17:26:26.705433Z] Running read-buildbot-config step. 10:26:26 INFO - Running pre-action listener: _resource_record_pre_action 10:26:26 INFO - Running main action method: read_buildbot_config 10:26:26 INFO - Using buildbot properties: 10:26:26 INFO - { 10:26:26 INFO - "project": "", 10:26:26 INFO - "product": "firefox", 10:26:26 INFO - "script_repo_revision": "production", 10:26:26 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 10:26:26 INFO - "repository": "", 10:26:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 10:26:26 INFO - "buildid": "20170525093444", 10:26:26 INFO - "pgo_build": "False", 10:26:26 INFO - "basedir": "/builds/slave/test", 10:26:26 INFO - "buildnumber": 3, 10:26:26 INFO - "slavename": "t-yosemite-r7-0110", 10:26:26 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:26:26 INFO - "platform": "yosemite_r7_devedition", 10:26:26 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 10:26:26 INFO - "branch": "mozilla-beta", 10:26:26 INFO - "repo_path": "releases/mozilla-beta", 10:26:26 INFO - "revision": "d3ce3e3812c9372a12b6416f0ceedd2e768b5277", 10:26:26 INFO - "stage_platform": "macosx64-devedition", 10:26:26 INFO - "builduid": "6cea08e737b8452ab18ddb0c798c0ddc", 10:26:26 INFO - "slavebuilddir": "test" 10:26:26 INFO - } 10:26:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:26:26 INFO - Running post-action listener: _resource_record_post_action 10:26:26 INFO - [mozharness: 2017-05-25 17:26:26.708832Z] Finished read-buildbot-config step (success) 10:26:26 INFO - [mozharness: 2017-05-25 17:26:26.708935Z] Running download-and-extract step. 10:26:26 INFO - Running pre-action listener: _resource_record_pre_action 10:26:26 INFO - Running main action method: download_and_extract 10:26:26 INFO - mkdir: /builds/slave/test/build/tests 10:26:26 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')]} 10:26:26 INFO - trying https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:26:26 INFO - Downloading https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/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 10:26:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/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 10:26:27 INFO - Downloaded 1287 bytes. 10:26:27 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:26:27 INFO - Using the following test package requirements: 10:26:27 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:26:27 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:26:27 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:26:27 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:26:27 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:26:27 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:26:27 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:26:27 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:26:27 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:26:27 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:26:27 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:26:27 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:26:27 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:26:27 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:26:27 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:26:27 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:26:27 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:26:27 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:26:27 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:26:27 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:26:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:26:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:26:27 INFO - Fetch https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:26:29 INFO - Expected file size: 51965427 10:26:29 INFO - Obtained file size: 51965427 10:26:45 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 10:26:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:26:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:26:45 INFO - Fetch https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:26:46 INFO - Expected file size: 51965427 10:26:46 INFO - Obtained file size: 51965427 10:27:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:27:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:27:01 INFO - Fetch https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:27:02 INFO - Expected file size: 14404449 10:27:02 INFO - Obtained file size: 14404449 10:27: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')]} 10:27:03 INFO - trying https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:27:03 INFO - Downloading https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:27:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/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 10:27:05 INFO - Downloaded 74890819 bytes. 10:27:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:27:05 INFO - mkdir: /builds/slave/test/properties 10:27:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:27:05 INFO - Writing to file /builds/slave/test/properties/build_url 10:27:05 INFO - Contents: 10:27:05 INFO - build_url:https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:27:06 INFO - Running post-action listener: _resource_record_post_action 10:27:06 INFO - Running post-action listener: set_extra_try_arguments 10:27:06 INFO - [mozharness: 2017-05-25 17:27:06.171673Z] Finished download-and-extract step (success) 10:27:06 INFO - [mozharness: 2017-05-25 17:27:06.171822Z] Running populate-webroot step. 10:27:06 INFO - Running pre-action listener: _resource_record_pre_action 10:27:06 INFO - Running main action method: populate_webroot 10:27:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:27:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:27:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:27:06 INFO - '--tppagecycles', 10:27:06 INFO - '7'], 10:27:06 INFO - 'tests': ['tsvgm']}}, 10:27:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:27:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:27:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:27:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:27:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:27:06 INFO - 'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:27:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:27:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:27:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:27:06 INFO - 'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['damp', 'tps']}, 10:27:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:27:06 INFO - 'tests': ['damp', 'tps']}, 10:27:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['dromaeo_dom']}, 10:27:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:27:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['basic_compositor_video']}, 10:27:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:27:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['a11yr', 10:27:06 INFO - 'ts_paint', 10:27:06 INFO - 'tpaint', 10:27:06 INFO - 'sessionrestore', 10:27:06 INFO - 'sessionrestore_no_auto_restore', 10:27:06 INFO - 'tabpaint']}, 10:27:06 INFO - 'other-e10s': {'tests': ['a11yr', 10:27:06 INFO - 'ts_paint', 10:27:06 INFO - 'tpaint', 10:27:06 INFO - 'sessionrestore', 10:27:06 INFO - 'sessionrestore_no_auto_restore', 10:27:06 INFO - 'tabpaint']}, 10:27:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:27:06 INFO - 'ts_paint', 10:27:06 INFO - 'tpaint', 10:27:06 INFO - 'sessionrestore', 10:27:06 INFO - 'sessionrestore_no_auto_restore', 10:27:06 INFO - 'tabpaint']}, 10:27:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:27:06 INFO - 'ts_paint', 10:27:06 INFO - 'tpaint', 10:27:06 INFO - 'sessionrestore', 10:27:06 INFO - 'sessionrestore_no_auto_restore', 10:27:06 INFO - 'tabpaint']}, 10:27:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['a11yr', 10:27:06 INFO - 'ts_paint', 10:27:06 INFO - 'tpaint', 10:27:06 INFO - 'sessionrestore', 10:27:06 INFO - 'sessionrestore_no_auto_restore', 10:27:06 INFO - 'tabpaint']}, 10:27:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['a11yr', 10:27:06 INFO - 'ts_paint', 10:27:06 INFO - 'tpaint', 10:27:06 INFO - 'sessionrestore', 10:27:06 INFO - 'sessionrestore_no_auto_restore', 10:27:06 INFO - 'tabpaint']}, 10:27:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['tsvgx', 10:27:06 INFO - 'tsvgr_opacity', 10:27:06 INFO - 'tart', 10:27:06 INFO - 'tscrollx', 10:27:06 INFO - 'cart', 10:27:06 INFO - 'tsvg_static']}, 10:27:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:27:06 INFO - 'tsvgr_opacity', 10:27:06 INFO - 'tart', 10:27:06 INFO - 'tscrollx', 10:27:06 INFO - 'cart', 10:27:06 INFO - 'tsvg_static']}, 10:27:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:27:06 INFO - 'talos_options': ['--disable-e10s'], 10:27:06 INFO - 'tests': ['tp5o']}, 10:27:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:27:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:27:06 INFO - 'talos_options': ['--disable-e10s', 10:27:06 INFO - '--xperf_path', 10:27:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:27:06 INFO - 'tests': ['tp5n']}, 10:27:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:27:06 INFO - 'talos_options': ['--xperf_path', 10:27:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:27:06 INFO - 'tests': ['tp5n']}}, 10:27:06 INFO - 'talos.zip': {'path': '', 10:27:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:27:06 INFO - Running post-action listener: _resource_record_post_action 10:27:06 INFO - [mozharness: 2017-05-25 17:27:06.190099Z] Finished populate-webroot step (success) 10:27:06 INFO - [mozharness: 2017-05-25 17:27:06.190228Z] Running create-virtualenv step. 10:27:06 INFO - Running pre-action listener: _resource_record_pre_action 10:27:06 INFO - Running main action method: create_virtualenv 10:27:06 INFO - Creating virtualenv /builds/slave/test/build/venv 10:27:06 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 10:27:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:27:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:27:06 INFO - Using real prefix '/tools/python27' 10:27:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:27:07 INFO - Installing distribute.............................................................................................................................................................................................done. 10:27:09 INFO - Installing pip.................done. 10:27:09 INFO - Return code: 0 10:27:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:27:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:27:10 INFO - Reading from file tmpfile_stdout 10:27:10 INFO - Output received: 10:27:10 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) 10:27:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:27:10 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')]} 10:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:10 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')]} 10:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:10 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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:10 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 10:27:10 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 10:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:10 INFO - 'HOME': '/Users/cltbld', 10:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:10 INFO - 'LOGNAME': 'cltbld', 10:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:10 INFO - 'MOZ_NO_REMOTE': '1', 10:27:10 INFO - 'NO_EM_RESTART': '1', 10:27:10 INFO - 'PAGER': '/bin/cat', 10:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:10 INFO - 'PWD': '/builds/slave/test', 10:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:10 INFO - 'SHELL': '/bin/bash', 10:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:10 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:10 INFO - 'USER': 'cltbld', 10:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:10 INFO - 'XPC_FLAGS': '0x0', 10:27:10 INFO - 'XPC_SERVICE_NAME': '0', 10:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:10 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 10:27:10 INFO - Cleaning up... 10:27:10 INFO - Return code: 0 10:27:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:27:10 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')]} 10:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:10 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')]} 10:27:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:10 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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:10 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 10:27:10 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 10:27:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:10 INFO - 'HOME': '/Users/cltbld', 10:27:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:10 INFO - 'LOGNAME': 'cltbld', 10:27:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:10 INFO - 'MOZ_NO_REMOTE': '1', 10:27:10 INFO - 'NO_EM_RESTART': '1', 10:27:10 INFO - 'PAGER': '/bin/cat', 10:27:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:10 INFO - 'PWD': '/builds/slave/test', 10:27:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:10 INFO - 'SHELL': '/bin/bash', 10:27:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:10 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:10 INFO - 'USER': 'cltbld', 10:27:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:10 INFO - 'XPC_FLAGS': '0x0', 10:27:10 INFO - 'XPC_SERVICE_NAME': '0', 10:27:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:10 INFO - Downloading/unpacking psutil>=3.1.1 10:27:10 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 10:27:10 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 10:27:10 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 10:27:10 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 10:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:27:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:27:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:27:12 INFO - Installing collected packages: psutil 10:27:12 INFO - Running setup.py install for psutil 10:27:12 INFO - building 'psutil._psutil_osx' extension 10:27:12 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 10:27:13 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 10:27:13 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 10:27:13 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 10:27:13 INFO - building 'psutil._psutil_posix' extension 10:27:13 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 10:27:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:27:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:27:13 INFO - ^ 10:27:13 INFO - 1 warning generated. 10:27:13 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 10:27:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:27:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:27:13 INFO - Successfully installed psutil 10:27:13 INFO - Cleaning up... 10:27:13 INFO - Return code: 0 10:27:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:27:13 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')]} 10:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:14 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')]} 10:27:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:14 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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:14 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 10:27:14 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 10:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:14 INFO - 'HOME': '/Users/cltbld', 10:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:14 INFO - 'LOGNAME': 'cltbld', 10:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:14 INFO - 'MOZ_NO_REMOTE': '1', 10:27:14 INFO - 'NO_EM_RESTART': '1', 10:27:14 INFO - 'PAGER': '/bin/cat', 10:27:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:14 INFO - 'PWD': '/builds/slave/test', 10:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:14 INFO - 'SHELL': '/bin/bash', 10:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:14 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:14 INFO - 'USER': 'cltbld', 10:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:14 INFO - 'XPC_FLAGS': '0x0', 10:27:14 INFO - 'XPC_SERVICE_NAME': '0', 10:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:27:14 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 10:27:14 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 10:27:14 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 10:27:14 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 10:27:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:27:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:27:16 INFO - Installing collected packages: mozsystemmonitor 10:27:16 INFO - Running setup.py install for mozsystemmonitor 10:27:16 INFO - Successfully installed mozsystemmonitor 10:27:16 INFO - Cleaning up... 10:27:16 INFO - Return code: 0 10:27:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:27:16 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')]} 10:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:17 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')]} 10:27:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:17 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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:17 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 10:27:17 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 10:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:17 INFO - 'HOME': '/Users/cltbld', 10:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:17 INFO - 'LOGNAME': 'cltbld', 10:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:17 INFO - 'MOZ_NO_REMOTE': '1', 10:27:17 INFO - 'NO_EM_RESTART': '1', 10:27:17 INFO - 'PAGER': '/bin/cat', 10:27:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:17 INFO - 'PWD': '/builds/slave/test', 10:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:17 INFO - 'SHELL': '/bin/bash', 10:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:17 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:17 INFO - 'USER': 'cltbld', 10:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:17 INFO - 'XPC_FLAGS': '0x0', 10:27:17 INFO - 'XPC_SERVICE_NAME': '0', 10:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:17 INFO - Downloading/unpacking jsonschema==2.5.1 10:27:17 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 10:27:17 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 10:27:17 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 10:27:17 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 10:27:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:27:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:19 INFO - Downloading functools32-3.2.3-2.tar.gz 10:27:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:27:19 INFO - warning: no files found matching '*.txt' 10:27:19 INFO - no previously-included directories found matching 'build' 10:27:19 INFO - no previously-included directories found matching 'dist' 10:27:19 INFO - no previously-included directories found matching '.git*' 10:27:19 INFO - Installing collected packages: jsonschema, functools32 10:27:19 INFO - Running setup.py install for functools32 10:27:19 INFO - warning: no files found matching '*.txt' 10:27:19 INFO - no previously-included directories found matching 'build' 10:27:19 INFO - no previously-included directories found matching 'dist' 10:27:19 INFO - no previously-included directories found matching '.git*' 10:27:19 INFO - Successfully installed jsonschema functools32 10:27:19 INFO - Cleaning up... 10:27:19 INFO - Return code: 0 10:27:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:27:19 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')]} 10:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:19 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')]} 10:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:19 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 10:27:19 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 10:27:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:19 INFO - 'HOME': '/Users/cltbld', 10:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:19 INFO - 'LOGNAME': 'cltbld', 10:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:19 INFO - 'MOZ_NO_REMOTE': '1', 10:27:19 INFO - 'NO_EM_RESTART': '1', 10:27:19 INFO - 'PAGER': '/bin/cat', 10:27:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:19 INFO - 'PWD': '/builds/slave/test', 10:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:19 INFO - 'SHELL': '/bin/bash', 10:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:19 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:19 INFO - 'USER': 'cltbld', 10:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:19 INFO - 'XPC_FLAGS': '0x0', 10:27:19 INFO - 'XPC_SERVICE_NAME': '0', 10:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:27:19 INFO - Cleaning up... 10:27:19 INFO - Return code: 0 10:27:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:27:19 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')]} 10:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:19 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')]} 10:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:19 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 10:27:19 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 10:27:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:19 INFO - 'HOME': '/Users/cltbld', 10:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:19 INFO - 'LOGNAME': 'cltbld', 10:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:19 INFO - 'MOZ_NO_REMOTE': '1', 10:27:19 INFO - 'NO_EM_RESTART': '1', 10:27:19 INFO - 'PAGER': '/bin/cat', 10:27:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:19 INFO - 'PWD': '/builds/slave/test', 10:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:19 INFO - 'SHELL': '/bin/bash', 10:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:19 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:19 INFO - 'USER': 'cltbld', 10:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:19 INFO - 'XPC_FLAGS': '0x0', 10:27:19 INFO - 'XPC_SERVICE_NAME': '0', 10:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:20 INFO - Downloading/unpacking blobuploader==1.2.4 10:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:21 INFO - Downloading blobuploader-1.2.4.tar.gz 10:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:27:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:27:21 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 10:27:21 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 10:27:21 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 10:27:21 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 10:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:27:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:27:22 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 10:27:22 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 10:27:22 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 10:27:22 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 10:27:22 INFO - Downloading docopt-0.6.1.tar.gz 10:27:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:27:22 INFO - Installing collected packages: blobuploader, requests, docopt 10:27:22 INFO - Running setup.py install for blobuploader 10:27:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:27:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:27:22 INFO - Running setup.py install for requests 10:27:23 INFO - Running setup.py install for docopt 10:27:23 INFO - Successfully installed blobuploader requests docopt 10:27:23 INFO - Cleaning up... 10:27:23 INFO - Return code: 0 10:27:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:27:23 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')]} 10:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:23 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')]} 10:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:23 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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:23 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 10:27:23 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 10:27:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:23 INFO - 'HOME': '/Users/cltbld', 10:27:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:23 INFO - 'LOGNAME': 'cltbld', 10:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:23 INFO - 'MOZ_NO_REMOTE': '1', 10:27:23 INFO - 'NO_EM_RESTART': '1', 10:27:23 INFO - 'PAGER': '/bin/cat', 10:27:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:23 INFO - 'PWD': '/builds/slave/test', 10:27:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:23 INFO - 'SHELL': '/bin/bash', 10:27:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:23 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:23 INFO - 'USER': 'cltbld', 10:27:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:23 INFO - 'XPC_FLAGS': '0x0', 10:27:23 INFO - 'XPC_SERVICE_NAME': '0', 10:27:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:27:23 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6Xmgfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:27:23 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-M3Sdd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:27:23 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-id7NRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:27:23 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ysLIeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:27:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:27:23 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-jECzgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:27:24 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-TJll2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:27:24 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-jmonqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:27:24 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QDOuC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:27:24 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-TtqjPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:27:24 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-SjLCKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:27:24 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-opYy8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:27:24 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-YPzZqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:27:24 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-_Hl4Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:27:24 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-bLtJw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:27:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-TTmbhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:27:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-cXG9cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:27:25 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-RjQqIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:27:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:27:25 INFO - Running setup.py install for manifestparser 10:27:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:27:25 INFO - Running setup.py install for mozcrash 10:27:25 INFO - Running setup.py install for mozdebug 10:27:25 INFO - Running setup.py install for mozdevice 10:27:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:27:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:27:25 INFO - Running setup.py install for mozfile 10:27:26 INFO - Running setup.py install for mozhttpd 10:27:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:27:26 INFO - Running setup.py install for mozinfo 10:27:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:27:26 INFO - Running setup.py install for mozInstall 10:27:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:27:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:27:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:27:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:27:26 INFO - Running setup.py install for mozleak 10:27:26 INFO - Running setup.py install for mozlog 10:27:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:27:26 INFO - Running setup.py install for moznetwork 10:27:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:27:26 INFO - Running setup.py install for mozprocess 10:27:27 INFO - Running setup.py install for mozprofile 10:27:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:27:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:27:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:27:27 INFO - Running setup.py install for mozrunner 10:27:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:27:27 INFO - Running setup.py install for mozscreenshot 10:27:27 INFO - Running setup.py install for moztest 10:27:27 INFO - Running setup.py install for mozversion 10:27:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:27:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:27:27 INFO - Cleaning up... 10:27:27 INFO - Return code: 0 10:27:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:27:27 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')]} 10:27:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:27 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')]} 10:27:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:27 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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:27 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 10:27:27 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 10:27:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:27 INFO - 'HOME': '/Users/cltbld', 10:27:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:27 INFO - 'LOGNAME': 'cltbld', 10:27:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:27 INFO - 'MOZ_NO_REMOTE': '1', 10:27:27 INFO - 'NO_EM_RESTART': '1', 10:27:27 INFO - 'PAGER': '/bin/cat', 10:27:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:27 INFO - 'PWD': '/builds/slave/test', 10:27:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:27 INFO - 'SHELL': '/bin/bash', 10:27:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:27 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:27 INFO - 'USER': 'cltbld', 10:27:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:27 INFO - 'XPC_FLAGS': '0x0', 10:27:27 INFO - 'XPC_SERVICE_NAME': '0', 10:27:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-PfwqFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:27:28 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)) 10:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-4wd5pP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:27:28 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)) 10:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Inz7hs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:27:28 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)) 10:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-x5jCFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:27:28 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)) 10:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-jqib4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:27:28 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)) 10:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-fWs7gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:27:28 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)) 10:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-R2zZIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:27:28 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)) 10:27:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:27:28 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-mVv11B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:27:29 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)) 10:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:27:29 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-G8KQbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:27:29 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)) 10:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:27:29 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-nKPeAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:27:29 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)) 10:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:27:29 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-4VOadu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:27:29 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)) 10:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:27:29 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-KngYEp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:27:29 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)) 10:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:27:29 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Q4RmDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:27:29 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)) 10:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:27:29 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-WPO2rH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:27:29 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)) 10:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:27:29 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-cWARFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:27:29 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)) 10:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:27:29 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-xsDAEr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:27:29 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)) 10:27:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:27:29 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-nWBSaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:27:30 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)) 10:27:30 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)) 10:27:30 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)) 10:27:30 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)) 10:27:30 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)) 10:27:30 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)) 10:27:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:31 INFO - Downloading blessings-1.6.tar.gz 10:27:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:27:31 INFO - Installing collected packages: blessings 10:27:31 INFO - Running setup.py install for blessings 10:27:31 INFO - Successfully installed blessings 10:27:31 INFO - Cleaning up... 10:27:31 INFO - Return code: 0 10:27:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:27:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:27:32 INFO - Reading from file tmpfile_stdout 10:27:32 INFO - Current package versions: 10:27:32 INFO - blessings == 1.6 10:27:32 INFO - blobuploader == 1.2.4 10:27:32 INFO - docopt == 0.6.1 10:27:32 INFO - functools32 == 3.2.3-2 10:27:32 INFO - jsonschema == 2.5.1 10:27:32 INFO - manifestparser == 1.1 10:27:32 INFO - mozInstall == 1.12 10:27:32 INFO - mozcrash == 1.0 10:27:32 INFO - mozdebug == 0.1 10:27:32 INFO - mozdevice == 0.49 10:27:32 INFO - mozfile == 1.2 10:27:32 INFO - mozhttpd == 0.7 10:27:32 INFO - mozinfo == 0.9 10:27:32 INFO - mozleak == 0.1 10:27:32 INFO - mozlog == 3.4 10:27:32 INFO - moznetwork == 0.27 10:27:32 INFO - mozprocess == 0.25 10:27:32 INFO - mozprofile == 0.28 10:27:32 INFO - mozrunner == 6.13 10:27:32 INFO - mozscreenshot == 0.1 10:27:32 INFO - mozsystemmonitor == 0.3 10:27:32 INFO - moztest == 0.8 10:27:32 INFO - mozversion == 1.4 10:27:32 INFO - psutil == 3.1.1 10:27:32 INFO - requests == 1.2.3 10:27:32 INFO - wsgiref == 0.1.2 10:27:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:27:32 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')]} 10:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:32 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')]} 10:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:32 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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:32 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 10:27:32 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 10:27:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:32 INFO - 'HOME': '/Users/cltbld', 10:27:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:32 INFO - 'LOGNAME': 'cltbld', 10:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:32 INFO - 'MOZ_NO_REMOTE': '1', 10:27:32 INFO - 'NO_EM_RESTART': '1', 10:27:32 INFO - 'PAGER': '/bin/cat', 10:27:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:32 INFO - 'PWD': '/builds/slave/test', 10:27:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:32 INFO - 'SHELL': '/bin/bash', 10:27:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:32 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:32 INFO - 'USER': 'cltbld', 10:27:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:32 INFO - 'XPC_FLAGS': '0x0', 10:27:32 INFO - 'XPC_SERVICE_NAME': '0', 10:27:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:32 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)) 10:27:32 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)) 10:27:32 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)) 10:27:32 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)) 10:27:32 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)) 10:27:32 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)) 10:27:32 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)) 10:27:32 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)) 10:27:32 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)) 10:27:32 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)) 10:27:32 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)) 10:27:32 INFO - Cleaning up... 10:27:32 INFO - Return code: 0 10:27:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:27:32 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')]} 10:27:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:32 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')]} 10:27:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10dc61b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc64768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f859ac79490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, '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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 10:27:32 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 10:27:32 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 10:27:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:32 INFO - 'HOME': '/Users/cltbld', 10:27:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:32 INFO - 'LOGNAME': 'cltbld', 10:27:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:32 INFO - 'MOZ_NO_REMOTE': '1', 10:27:32 INFO - 'NO_EM_RESTART': '1', 10:27:32 INFO - 'PAGER': '/bin/cat', 10:27:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:32 INFO - 'PWD': '/builds/slave/test', 10:27:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:32 INFO - 'SHELL': '/bin/bash', 10:27:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:32 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:32 INFO - 'USER': 'cltbld', 10:27:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:32 INFO - 'XPC_FLAGS': '0x0', 10:27:32 INFO - 'XPC_SERVICE_NAME': '0', 10:27:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:27:32 INFO - Cleaning up... 10:27:32 INFO - Return code: 0 10:27:32 INFO - Running post-action listener: _resource_record_post_action 10:27:32 INFO - Running post-action listener: _start_resource_monitoring 10:27:32 INFO - Starting resource monitoring. 10:27:32 INFO - [mozharness: 2017-05-25 17:27:32.591800Z] Finished create-virtualenv step (success) 10:27:32 INFO - [mozharness: 2017-05-25 17:27:32.592748Z] Running install step. 10:27:32 INFO - Running pre-action listener: _resource_record_pre_action 10:27:32 INFO - Running main action method: install 10:27:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:27:32 INFO - Reading from file tmpfile_stdout 10:27:32 INFO - Detecting whether we're running mozinstall >=1.0... 10:27:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:27:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:27:32 INFO - Reading from file tmpfile_stdout 10:27:32 INFO - Output received: 10:27:32 INFO - Usage: mozinstall [options] installer 10:27:32 INFO - Options: 10:27:32 INFO - -h, --help show this help message and exit 10:27:32 INFO - -d DEST, --destination=DEST 10:27:32 INFO - Directory to install application into. [default: 10:27:32 INFO - "/builds/slave/test"] 10:27:32 INFO - --app=APP Application being installed. [default: firefox] 10:27:32 INFO - mkdir: /builds/slave/test/build/application 10:27:32 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'] 10:27:32 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 10:27:53 INFO - Reading from file tmpfile_stdout 10:27:53 INFO - Output received: 10:27:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:27:53 INFO - Running post-action listener: _resource_record_post_action 10:27:53 INFO - [mozharness: 2017-05-25 17:27:53.347783Z] Finished install step (success) 10:27:53 INFO - [mozharness: 2017-05-25 17:27:53.347953Z] Running run-tests step. 10:27:53 INFO - Running pre-action listener: _resource_record_pre_action 10:27:53 INFO - Running main action method: run_tests 10:27:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:27:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:27:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:27:53 INFO - 2017-05-25 10:27:53.363 screenresolution[1624:7500] starting screenresolution argv=screenresolution get 10:27:53 INFO - 2017-05-25 10:27:53.365 screenresolution[1624:7500] Display 0: 1600x1200x32@60 10:27:53 INFO - 2017-05-25 10:27:53.371 screenresolution[1625:7503] starting screenresolution argv=screenresolution list 10:27:53 INFO - Available Modes on Display 0 10:27:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:27:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:27:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:27:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:27:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:27:53 INFO - Intel Iris: 10:27:53 INFO - Chipset Model: Intel Iris 10:27:53 INFO - Type: GPU 10:27:53 INFO - Bus: Built-In 10:27:53 INFO - VRAM (Dynamic, Max): 1536 MB 10:27:53 INFO - Vendor: Intel (0x8086) 10:27:53 INFO - Device ID: 0x0a2e 10:27:53 INFO - Revision ID: 0x0009 10:27:53 INFO - Displays: 10:27:53 INFO - Display: 10:27:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:27:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:27:53 INFO - Display Serial Number: 1600x1200 60 10:27:53 INFO - Main Display: Yes 10:27:53 INFO - Mirror: Off 10:27:53 INFO - Online: Yes 10:27:53 INFO - Rotation: Supported 10:27:53 INFO - Return code: 0 10:27:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:27:53 INFO - Python 2.7.3 10:27:53 INFO - Return code: 0 10:27:53 INFO - grabbing minidump binary from tooltool 10:27: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')]} 10:27:53 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 0x10da3fd30>, '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 0x10d966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10da34be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:27:53 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 10:27:53 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 10:27:53 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 10:27:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:27:53 INFO - Return code: 0 10:27:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:27:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:27:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:27:53 INFO - ENV: RUST_BACKTRACE is now 1 10:27:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:27:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:27:53 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/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 10:27:53 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/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --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 10:27:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qFM8Y45LrC/Render', 10:27:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:53 INFO - 'HOME': '/Users/cltbld', 10:27:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:53 INFO - 'LOGNAME': 'cltbld', 10:27:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:27:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:53 INFO - 'MOZ_NO_REMOTE': '1', 10:27:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:27:53 INFO - 'NO_EM_RESTART': '1', 10:27:53 INFO - 'PAGER': '/bin/cat', 10:27:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:53 INFO - 'PWD': '/builds/slave/test', 10:27:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:27:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:53 INFO - 'RUST_BACKTRACE': '1', 10:27:53 INFO - 'SHELL': '/bin/bash', 10:27:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fCRFWkaVfd/Listeners', 10:27:53 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 10:27:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:53 INFO - 'USER': 'cltbld', 10:27:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:53 INFO - 'XPC_FLAGS': '0x0', 10:27:53 INFO - 'XPC_SERVICE_NAME': '0', 10:27:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:53 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/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 10:27:53 INFO - mozversion application_buildid: 20170525093444 10:27:53 INFO - mozversion application_changeset: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 10:27:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:27:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:27:53 INFO - mozversion application_name: Firefox 10:27:53 INFO - mozversion application_remotingname: firefox-dev 10:27:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:27:53 INFO - mozversion application_vendor: Mozilla 10:27:53 INFO - mozversion application_version: 54.0 10:27:53 INFO - mozversion platform_buildid: 20170525093444 10:27:53 INFO - mozversion platform_changeset: d3ce3e3812c9372a12b6416f0ceedd2e768b5277 10:27:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:27:53 INFO - mozversion platform_version: 54.0 10:27:53 INFO - using testdate: 1495733273 10:27:53 INFO - actual date: 1495733273 10:27:53 INFO - starting webserver on 'localhost:49225' 10:27:53 INFO - SUITE-START | Running 2 tests 10:27:53 INFO - TEST-START | dromaeo_css 10:27:53 INFO - operating with platform_type : mac_ 10:27:53 INFO - Initialising browser for dromaeo_css test... 10:27:54 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpQZaFrR/profile http://localhost:49225/getInfo.html) 10:27:56 INFO - PROCESS | 1635 | 2017-05-25 10:27:56.884 plugin-container[1638:7676] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4b, name = 'com.apple.tsm.portname' 10:27:56 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:56 INFO - PROCESS | 1635 | 2017-05-25 10:27:56.885 plugin-container[1638:7676] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 10:27:56 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:56 INFO - PROCESS | 1635 | 2017-05-25 10:27:56.885 plugin-container[1638:7676] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:27:56 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 10:27:56 INFO - PROCESS | 1635 | colorDepth:24 10:27:56 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 10:27:56 INFO - PROCESS | 1635 | __metrics 10:27:57 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:27:57 INFO - TEST-INFO | 1635: exit 0 10:27:57 INFO - Browser initialized. 10:27:57 INFO - Running cycle 1/1 for dromaeo_css test... 10:27:57 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpQZaFrR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:27:59 INFO - PROCESS | 1639 | 2017-05-25 10:27:59.883 plugin-container[1640:7806] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 10:27:59 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:59 INFO - PROCESS | 1639 | 2017-05-25 10:27:59.884 plugin-container[1640:7806] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 10:27:59 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:27:59 INFO - PROCESS | 1639 | 2017-05-25 10:27:59.884 plugin-container[1640:7806] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:28:09 INFO - PROCESS | 1639 | RSS: Main: 202928128 10:28:09 INFO - PROCESS | 1639 | 10:31:13 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 10:31:15 INFO - PROCESS | 1639 | RSS: Main: 218247168 10:31:15 INFO - PROCESS | 1639 | 10:34:17 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 10:34:17 INFO - PROCESS | 1639 | RSS: Main: 230428672 10:34:17 INFO - PROCESS | 1639 | 10:37:19 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 10:37:20 INFO - PROCESS | 1639 | RSS: Main: 232628224 10:37:20 INFO - PROCESS | 1639 | 10:40:22 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 10:40:23 INFO - PROCESS | 1639 | RSS: Main: 235798528 10:40:23 INFO - PROCESS | 1639 | 10:43:25 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 10:43:26 INFO - PROCESS | 1639 | RSS: Main: 237858816 10:43:26 INFO - PROCESS | 1639 | 10:46:29 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 10:46:29 INFO - PROCESS | 1639 | RSS: Main: 235929600 10:46:29 INFO - PROCESS | 1639 | 10:46:29 INFO - PROCESS | 1639 | __start_tp_report 10:46:29 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 10:46:29 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 10:46:29 INFO - PROCESS | 1639 | |i|pagename|runs| 10:46:29 INFO - PROCESS | 1639 | |0;dojo.html;3477.808720520371;3513.8243087845617;3538.52229948957;3558.768680035799;3567.9286414271705;5989.3112292086425;6045.274567051954;6064.272287325522;6064.4541991220785;6075.210222671058;5822.068469044953;5961.701914904248;6039.1545183674325;6040.335563088068;6066.484348830355;4717;5408.8377348679405;5409.56723462122;5435.347758269013;5552.222688823568;5470.070088085035;5489.890202195954;5491.176323551459;5493.945060549383;5510.91733623996;5326.920096198541;5338.412774594792;5471.069918113911;5479.013777520045;5494.36814766301;5425.13197888338;5448.8365349039395;5493.148561972901;5531.529819965308;5536.197251398525;5331.493508116761;5423.322084739408;5436.782528698847;5440.782368705287;5480.561555075585;5729.054705973509;6005;6065.332813390523;6095.116208149793;6106.938930610681;6352.872942541169;6363.7454501819875;6367.968160159169;6399.13611662429;6411.262704788963;6479.805605831785;6484.805455836333;6490.221173459168;6505.544611877171;6512.120863683437;6119.082137679295;6195.721192546346;6211.565190436626;6216.409441103088;6221.751129954841;6232.75068997235;6368.10846481493;6465.062565927914;6472.255690595642;6479.416852483299;6380;6389.297177310492;6404;6409.743610255631;6414;5943.900378430004;6387.489000879918;6436.420722135031;6464.321246269168;6491.67541622917;6264.154339164154;6317.905231421532;6321;6335.841603959938;6391.872162556723;6246.844333798262;6373.362663733589;6378.808635740842;6394.200724909387;6418.165638467063;4600.332951722048;4754.263089221176;4866.562009419169;4931.161702510582;4940.308356830046;3901.785401802928;4404.977975110168;4437.911241775211;4439.755813430228;4456.108778244364;4959.82640607577;5896;5944.4352786485215;6006.399360063959;6036.034234522455;5700.800471983461;6217.626942383381;6391.776287829904;6425.775097871552;6463.7091330890225;5925.525957923356;5974.163617093611;5988.371221021817;6006.489448396847;6031.125486804389;4787.808487660524;5413.648112872651;5427.48438898304;5481.808136715275;5541.251930989345;5759.308882934075;6136.519078024374;6582.901256481061;6594.571352862049;6660.6336651484635;6409.327020662763;6418.229812422539;6427.421532062137;6431.453326467303;6489.480841532761;6297.842553936188;6344.492440604833;6589.209294884645;6606.6036037837885;6633.3035031321015;6335.1130841683125;6337.588056776295;6404.807855764335;6414.390632889868;6416.358364163733;5603.971980140073;5870.618409803349;6430.64631445275;6440.4525615321345;6446.323136070645;6150.200473938538;6214.875702486015;6383.170187875547;6392.360763923756;6405.7117429714845;4621.468531118964;5108.974455127701;6241.531885108544;6324.335944725922;6385.329540398191;6145.201123853871;6261.405166509175;6291.905621415591;6358.936410635834;6359.459445946961;6165.260168779596;6205.565610407228;6237.438630523275;6250.9062364065485;6348.333424990309;6276.9372306276355;6278.6546739929745;6280.842978925563;6296.024116261987;6300.59046161998 10:46:29 INFO - PROCESS | 1639 | |1;ext.html;792.7553740627218;812.1107387410785;830.4228561150003;838.3795990966687;974.2352253481018;38894.61105388945;39917.40123898144;40814.59185408145;40887.18225635489;40968;39785.81713462923;39877.80061099698;39988.00029999251;40087.59912400875;40166.59833401665;8056.8388632227325;8205.220504052106;11039.282446640982;11321.490532926038;11541.942290288536;11233.943830280838;11337.37644429556;11364.204505684605;11370.772584548304;11378.317300961928;8384.203500667427;9359.766005849875;10767.084797792197;11046.889531104707;11393.54425822966;9391.859122113208;11318.377489238088;11364.886351136507;11376.976072153546;11390.373529455877;11002.614908478165;11038.17213708967;11045.171612129043;11070.225084244106;11070.778584428346;34882.65117348819;35177;35189.29621407558;35330;35361;3808.1241314497543;3875.767453952772;3890.0469385000574;3899.220155968789;3909.550401703784;25872.482550349076;26452.73547264522;26488.60267095995;26829;26921;3075.9538606920914;3117.984410077935;3131.8434078295995;3143.167060728909;3189.617245930503;310220.89779102145;312223;352332.2383388092;394566.0543394558;421121;43418.78290608527;43644.563554364686;43805;43813.34279985803;43888;45517.77241113773;45544.77227613907;45595.316070258974;45650;45742.542574574494;13872.375743091585;13924.86075139256;13942.024058316023;13943.860561394458;14012.859871401359;12210.694732631755;12213.511459541696;12220.266783992989;12228.899399054128;12239.95960343363;11783.941080294544;11868.940655296668;11895;11914;11998.280103193836;4817.000944607948;5631.549476041907;5687.317521897398;5697.487226149667;5699.430056994351;913.6939125393068;960.9471479068718;1150.9942450287863;1195.485941045359;1217.2696382170627;25782.09762658298;26356.72802735113;26396.47207055848;26482.940682372875;26513.86743066272;1270.8856202941595;1273.446050967832;1274.439246731435;1328.7873940169527;1340.986590134086;13328;13728.244946528037;13858.4456621734;13984.230867302396;14185.78721319181;407439;410474;410726;410817.9459102685;410963;54506.727466363205;54966.17550736742;54967.90064198773;55021;55134;1327.6172037062693;1449.3115770009372;1511.3350125944548;1514.35639853062;1519.7112548615728;21288;23920.905244045847;25613.871930640227;26010.34974125662;26014.86992565025;45005.09989800251;45022;45166.548334516236;45186.77406612946;45238.77380613076;366848;368625;369776;370127;370581;25540.48919021572;26133.60799588046;26664.466710666067;31385;31951.520727189574;44037.779811100736;45127.77436112799;45156.548434516546;46220.53779462297;46501;25353;31079.378412432077;31286.374272513967;31490.212744681565;31644.683553164174;30713.232169195948;30902;31029.8448507756;31558.211044724067;31862;35832.104197395274;36164;37503.43744843775;37521;37824;30586.08241752751;30651.54022689706;31099.84450077735;31560.368792623558;32249.74251802351;24553.75446245586;24968.500629986935;25046;25248.74751252464;25422.364440889127 10:46:29 INFO - PROCESS | 1639 | |2;jquery.html;392.80359820089956;408.2956899348622;408.69143796433667;413.1447902841119;422.7548022147155;18321.633567328645;20990.37028889132;21169.364919052434;22109.336719898387;22647.207347742813;20999.895000525015;22467.76427296498;22546.323610291678;22890.084396624115;23040;6737.494687898403;6952.478564107687;6955.269696681851;7016.333448322402;7021.192562855286;6645.474719606543;6732;6883;6883.621400822953;6915.550489218184;6065.2418447694035;6593.604383736991;6801.93198068018;6826.590404575716;6930.826729331782;6802.3537763912345;6808.421284190849;6812.489063320246;6855.108835851357;6888.586684798928;6354.80935571929;6524.249711283216;6624.8012559623285;6641.933580664228;6651.60090394573;27172;27520;27740.445191096267;27806.748696308714;27938.742756576004;2921.3134913295357;2921.6347956505438;2980.925476863074;3003.594514740504;3024.3951209757915;17475.737863932052;17580.472585822445;17640.38258660941;17819.554511137194;18010.279588816433;2072.89100331322;2086.645270304052;2129.8516038277908;2155.234891613486;2158.460384903774;103957.62955509296;109468;115080;125693;130186;18248.087595620167;19144;23262.022728067965;23850.04599815988;23897.283081507583;38087.80956095202;38137.42793858094;38984;39066.02334941649;39155.216895662496;9245.815083698422;11065.008298506185;11135;11175.552977880956;11419;8276.710315138942;9143.862842057375;9147.679831205876;9158.771030724285;9283.675071372469;9252.7508786313;11187.888121118685;11296.98327150544;11314.717132071599;11338.829917551244;3649.616790236987;3726.2547490502006;4420.596140971498;4457.910841783146;4475.7985890635;909.6361455417754;937.9963439120212;949.1552518258756;977.423320241047;988.7132731507783;22173.556528869332;23386.298411047697;23415;23430.414239644146;23469.530609388057;808.789714674189;891.5141248019845;904.7240591619487;975.317277905469;1005.8541511480794;11521.711957201138;11999.280043197436;12131.878681213251;12159.392030398445;12162.513499459923;452693.73653131525;635485;777767;779272;799510;158108;160135;162077.18961405117;166157;166947;1208.6434501821984;1281.4425724809737;1314.9868501314863;1323.3912400295778;1324.920504769717;18696;19375;20386.490337741674;20509.077091531217;21314.467138321044;52898.94202116013;53699;66372.668136659;68963.65518172376;69266.30733692598;97238;97474.51262743642;99934;101054;102362;11335.149863760085;11647.708807279547;11731.765364692828;11770.293782373084;12080.939595301967;11647.776983416958;12772.552960646333;13039.022073344348;13264.204147751167;13360.331983400985;10906.34561926287;11216.990470857661;11357.659270221906;11499.482523286639;11502.82745758831;10225.948870255601;10236.53935572886;10290.639827605997;10586.206034547286;10666.306690065432;9405.85891211646;11720.70698232551;11749.177557570887;12038.939805301268;12048.337341446304;11672.774358692215;11806.586769463027;11864.881351186377;11917.880821191677;12162.087843411957;9483.430994140374;11328.980391764451;11414.714632134263;11447.542098315975;11799.882001179878 10:46:29 INFO - PROCESS | 1639 | |3;mootools.html;1527.8777697784178;1581.351645825211;1583.8020247469067;1591.9840801591984;1592.522243327002;8985.460872347656;11241.718957026078;11496.71258218545;11529.365884876328;11582.536698532049;11226.438678066104;11288.09695224382;11501.597444089459;11512.079033677306;11530.538778448872;2074.3051077888886;2459.827812053157;2464.8890799913956;2465.174166654173;2467.592847180213;2251.448395143192;2381.071382160959;2473.4311108445067;2477.2939712182033;2477.8513289202615;2392.1986134644917;2452.6688896998944;2461.064795377763;2469.8641574713383;2476.9628455573093;2392.3899405651446;2445.0464318915638;2457.7296497385273;2458.323960910746;2463.4826686395877;2433.2943446400523;2449.7060352757603;2465.3836540864786;2465.7410971847876;2466.8889899954547;11078.947499987487;11186.272892261977;11265;11274.774504509945;11281.774364512747;5919.674417907014;6056.242969628796;6105.389461053904;6106.938930610681;6110.052941794029;3344.5819272590925;3408.4887266909914;3456.2050728332624;3649.215418684969;3703.0742314421395;1191.559123124441;1205.873383294759;1239.0583156800806;1253.3357320620087;1265.33569875815;13149.539766108235;13796.586102416944;14080.859191408057;15081.849181508045;15761.527154185393;3849.3840985442193;4035.959640403617;4242.745435273893;4620.867887367616;4674.392328997209;11973.580924667594;11994.700132496757;12001.219920705125;12005.159638825373;12029;5984.401559843981;5984.491318237911;5985.281766188085;6000.609960352563;6008.24896887889;5212.218167274863;5348.5988550858065;5360.597955153379;5425.891482170335;5435.402105768362;3864;3950;3950.5259368875345;4001.239764444746;4039.171969746195;1037.2946396450336;1039.0492699180165;1043.7547176413536;1051.9316244444087;1052.032130439997;953.6519170502717;964.8697425847421;978.4716253223322;1000.5247507433911;1006.3961623026125;6473;6683.933160668428;6813.625250611264;6843.178818541707;6890.724371025103;956.7560272130562;979.153032626787;1007.2697294461457;1008.7780688248573;1014.3812274512542;4201.579842015835;4213.620774130343;4234.407182994383;4272.551382104834;4382.452193475816;13547.796783048263;14223.715525689428;14225.075370101118;14234.359453824603;14242.287885605678;11727.530898764124;14021.158730476203;14138.363773630212;14154.433822647185;14163.504277350243;8671.956640216758;10320.638777642747;10395.220358473145;10402.739931501774;10586.417747023988;6461.19235095613;6863.142107236596;6944.861102778017;7026.437884969292;7284.089488813898;10485.842712359168;12209.877901220874;12214.38928053576;12302.384880755748;12372.81440778407;12589;12627.052971027393;12638.368081596067;12825.038122141063;12861.485540578273;8561.871571926302;8580.227779499624;8592.097829727789;8619.482831030158;8684.175003374668;10400.531976061247;10442.477876106317;10482.68551943443;10558.472076396303;10582.259241852997;7535.773926782205;8944.195022448012;9009.909900900908;9066.818663626558;9209.861852072358;8573.657053717781;8739.694110706096;8865.778355540904;8900.510471924106;8960;7673.53958762476;7992.40056995716;8051.677932882619;8106.270435660819;8135.79660508492;6021.54838387114;6051.213342265477;6054.66699331541;6063.818085457443;6152.292486364126;5972.970135149297;6173.166622505904;6245.094461303176;6250.874982500415;6265.498760099273 10:46:29 INFO - PROCESS | 1639 | |4;prototype.html;309.24852608162183;371.7620722737449;386.5612529778701;389.376996805112;394.27256711367517;4518.457785065797;4704.952950470494;5210.270562121296;5361.57107431406;5425.864353391167;5112.514311140445;5125.205593133071;5147.588192944565;5175.792968281274;5179.430262671112;3642.708583313342;3895.7272990890647;3951.9604803952025;4036.031352475414;4227.682923780713;3535.497413599223;3554.9822250888838;3866.4586957825786;3991.740536865109;4348.956510434903;3655.1593133579295;3724.720645951551;4012.5786792386816;4195.412642230091;4380.9552361109045;4119.340905455113;4322.962489002648;4338.327559228324;4342.19928146347;4355.607995280408;3607.5310209672575;4040.272750904836;4043.6765058795227;4044.876439869063;4080.204360149772;4539.277792210389;4553.954460455386;4780.737059461728;5040.042391945519;5154.974225128888;4284.957150428519;4991.851874068948;5017.397912250517;5056.292119103328;5115.030713175417;5516.475934786189;5517.834464966058;5726.656400615976;5730;5751.712414379306;3987.766850563665;4281.636060934824;4328.069465065026;4449.221386257392;4461.330800379969;4553.795079221443;4888.902221955541;5109.182530795092;5395.381385584348;5644.6613203207935;3859.749116307431;3870.200356834029;3886.667999200457;4537.841175558906;4592;4166.458360413127;4268.371883898723;4502.662300327489;4621.907561848744;4652.232381656989;4447.888027993002;4503.121891231244;5030.252461915896;5038.143515602356;5051.444341122474;5352.464753524617;5548.0845660466375;5549.38956714761;5598.468145526169;5657.623798004848;1435.7343891380128;1499.3852520466555;1534.9309281082378;1572.042806705636;1586.4288856011826;1082.3235477826358;1107.379867274329;1183.5325046606542;1210.2738356985737;1218.561317925546;4559.726416415026;4575.522602821879;4895.388076490439;5116.9744151279;5121.590272778169;4972;5567.526760225345;5843.065109582446;5881.5588830837005;5933.228680271537;4647.442306923191;5072.289879416885;5101.821436249714;5165.431802501722;5190;2616.9476610468064;2643.947121057568;2957.8816847326298;3001.789874708793;3048.4512787697986;5796.608983744472;5831.309263039678;6006.459418652343;6007.789727359522;6016.195141457545;5147.202183661539;5151.6908985461;5799.88400231993;5809.7966571170655;5927.644341339534;5406.756446017359;5718.113022702415;5861.648301101863;5928.614640048383;6043.456088951927;5093.821716239914;5407.5944304176555;5724.742386592697;5797.362290148165;5822.737976791139;5140.254663073908;5659.302209337265;5724.771009159587;5728.856778580569;5800.868830577997;5093.567046801063;5285.497877701612;5517.36550296721;5841.007028805028;5973.581849270509;3983.346911032005;4327.415798867239;4566.383538222298;4635.814567417266;4640.443146822403;4932.704037757746;5155.871103222449;5728.6562806230095;5827.766889324549;5890.911636325544;4835.582208895552;5080.644354895122;5180.404253510744;5550.028744969695;5653.434656534314;4699.3420921071765;5581.441855814549;5639.351474580311;5647.661140331593;5730.742116604846;4593.436120529654;4834.670465621982;4976.651634385703;5163.347728726922;5470.9179362308805;4991.425986011655;4997.3253610762085;5482.972585137049;5637.237753500837;5697.943020569907;4619.399478067964;4792.525419071285;5088.6946783193125;5359.571234301168;5481.835544933659 10:46:29 INFO - PROCESS | 1639 | |5;yui.html;726.5422783646302;759.392486011191;784.6469088909988;841.1504380575618;870.5386145342967;15198.468053618126;16015.958962667419;16162.35350585978;16199.190040497971;16422.26099825508;15326.386944522206;15456.922715386407;15715.764263536055;15799.210039498008;15819.288132034057;964.5322018820884;981.5779214937573;984.5815528400437;987.7086259553413;991.4745185051934;959.6497278493334;980.8136454073704;982.773961988743;986.1814693804162;993.9353941993784;1036.5231993283062;1038.3717850700332;1040.973975650611;1041.7603951091253;1041.9426931518763;1023.6417253961129;1028.6451174344838;1032.4682788363962;1036.3937096798381;1036.6371769880518;761.2615762710218;773.1225059557387;774.8682723936944;775.0505630602523;777.1723114882632;16525.421610243706;16815.159242037847;16986.830131698647;17424.30302787887;17430.215640296217;14059.71880562383;14267.429302827875;14340.42638294467;14354.282285885663;14361.818001782334;11824.52701891932;11959.880401195964;12030.578929737505;12058.819117713241;12616.621501354974;3694.4273637586216;3727.4967879336214;3781.0736369589627;3855.652991230803;3889.2027134437653;111130.98032947324;115697;117011;117894;119077;10836.349819010884;10998.945005274922;11152.641781400698;11380.260283081574;11496.425178741198;23133.88433057824;23290.65064024041;23327.883360583088;23357.76642233556;23636.7636323638;8017.79733040037;8021.478603890729;8028.878141788854;8066;8146.389020823462;4396.714213576107;4396.868093957187;4528.50186479487;4533.818647254139;4550.635949124062;3567.4827150063097;3591.7665351752053;3652.3608368535934;3675.467057276733;3695;529.7483695244805;644.6969924135649;646.2374398210063;658.9769358072444;660.359451332207;656.2551504042946;656.5010591950153;670.8222321084917;686.2177118085386;715.8747219236612;10340.275158325245;10511.369317840954;10569.63006294776;10767.246292759588;10854.403007834644;569.9601027928088;588.807664479424;592.8251165906069;610.5451438678214;617.5183356981472;5070.923936140961;5167;5180.792768289226;5182.663126896739;5196.740162991835;84962.72555911666;85949;87246.56376718076;88342;88686.66969995455;88873;89804;92120.07879921114;93947.53026234826;95051.52474237584;72338.63830680813;73553.26446735571;75178;76625.61687191528;77548.22451775522;9659;9773.85339219898;9859.901400985898;9884.851727223953;9918.950405247928;51773.48226517686;52670.73664631652;52998.940021200135;54142.458575415316;54289;64701.67649161724;64755.028674568974;65831.67084164548;69817.60364792777;72443;13209.273489958145;13355.198688078746;13451.117436799936;13474.730505390033;13568.660783480493;12076.21504602198;12525.311107889154;13025.869741302466;14969.850301496846;15361.6159596011;11640.825387619361;11790.351530665552;11863.644090677293;11943.522259109539;12451.128421010804;11608.187426880037;12136.939315303367;12150.453229604866;12255.54889022217;12542.372881356077;11969.521219151486;11990.520379184734;12040;12046.939765301116;12289.446974885972;8533.487990720576;8931.560465104738;9003.234725048182;9022.233110185445;9080.500572468578;9360.719178424659;9798.608055677978;10102.797944041225;10210.897891020995;10435.634752783617 10:46:29 INFO - PROCESS | 1639 | __end_tp_report 10:46:29 INFO - PROCESS | 1639 | __start_cc_report 10:46:29 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,375 10:46:29 INFO - PROCESS | 1639 | __end_cc_report 10:46:29 INFO - PROCESS | 1639 | __startTimestamp1495734389885__endTimestamp 10:46:29 INFO - PROCESS | 1639 | 10:46:29 INFO - PROCESS | 1639 | ------- Summary: start ------- 10:46:29 INFO - PROCESS | 1639 | Number of tests: 6 10:46:29 INFO - PROCESS | 1639 | 10:46:29 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5901.19 Median:6195.72 stddev:694.19 (11.2%) stddev-sans-first:669.78 10:46:29 INFO - PROCESS | 1639 | Values: 3477.8 3513.8 3538.5 3558.8 3567.9 5989.3 6045.3 6064.3 6064.5 6075.2 5822.1 5961.7 6039.2 6040.3 6066.5 4717.0 5408.8 5409.6 5435.3 5552.2 5470.1 5489.9 5491.2 5493.9 5510.9 5326.9 5338.4 5471.1 5479.0 5494.4 5425.1 5448.8 5493.1 5531.5 5536.2 5331.5 5423.3 5436.8 5440.8 5480.6 5729.1 6005.0 6065.3 6095.1 6106.9 6352.9 6363.7 6368.0 6399.1 6411.3 6479.8 6484.8 6490.2 6505.5 6512.1 6119.1 6195.7 6211.6 6216.4 6221.8 6232.8 6368.1 6465.1 6472.3 6479.4 6380.0 6389.3 6404.0 6409.7 6414.0 5943.9 6387.5 6436.4 6464.3 6491.7 6264.2 6317.9 6321.0 6335.8 6391.9 6246.8 6373.4 6378.8 6394.2 6418.2 4600.3 4754.3 4866.6 4931.2 4940.3 3901.8 4405.0 4437.9 4439.8 4456.1 4959.8 5896.0 5944.4 6006.4 6036.0 5700.8 6217.6 6391.8 6425.8 6463.7 5925.5 5974.2 5988.4 6006.5 6031.1 4787.8 5413.6 5427.5 5481.8 5541.3 5759.3 6136.5 6582.9 6594.6 6660.6 6409.3 6418.2 6427.4 6431.5 6489.5 6297.8 6344.5 6589.2 6606.6 6633.3 6335.1 6337.6 6404.8 6414.4 6416.4 5604.0 5870.6 6430.6 6440.5 6446.3 6150.2 6214.9 6383.2 6392.4 6405.7 4621.5 5109.0 6241.5 6324.3 6385.3 6145.2 6261.4 6291.9 6358.9 6359.5 6165.3 6205.6 6237.4 6250.9 6348.3 6276.9 6278.7 6280.8 6296.0 6300.6 10:46:29 INFO - PROCESS | 1639 | 10:46:29 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:51961.73 Median:17264.08 stddev:100558.30 (582.5%) stddev-sans-first:100767.00 10:46:29 INFO - PROCESS | 1639 | Values: 792.8 812.1 830.4 838.4 974.2 38894.6 39917.4 40814.6 40887.2 40968.0 39785.8 39877.8 39988.0 40087.6 40166.6 8056.8 8205.2 11039.3 11321.5 11541.9 11233.9 11337.4 11364.2 11370.8 11378.3 8384.2 9359.8 10767.1 11046.9 11393.5 9391.9 11318.4 11364.9 11377.0 11390.4 11002.6 11038.2 11045.2 11070.2 11070.8 34882.7 35177.0 35189.3 35330.0 35361.0 3808.1 3875.8 3890.0 3899.2 3909.6 25872.5 26452.7 26488.6 26829.0 26921.0 3076.0 3118.0 3131.8 3143.2 3189.6 310220.9 312223.0 352332.2 394566.1 421121.0 43418.8 43644.6 43805.0 43813.3 43888.0 45517.8 45544.8 45595.3 45650.0 45742.5 13872.4 13924.9 13942.0 13943.9 14012.9 12210.7 12213.5 12220.3 12228.9 12240.0 11783.9 11868.9 11895.0 11914.0 11998.3 4817.0 5631.5 5687.3 5697.5 5699.4 913.7 960.9 1151.0 1195.5 1217.3 25782.1 26356.7 26396.5 26482.9 26513.9 1270.9 1273.4 1274.4 1328.8 1341.0 13328.0 13728.2 13858.4 13984.2 14185.8 407439.0 410474.0 410726.0 410817.9 410963.0 54506.7 54966.2 54967.9 55021.0 55134.0 1327.6 1449.3 1511.3 1514.4 1519.7 21288.0 23920.9 25613.9 26010.3 26014.9 45005.1 45022.0 45166.5 45186.8 45238.8 366848.0 368625.0 369776.0 370127.0 370581.0 25540.5 26133.6 26664.5 31385.0 31951.5 44037.8 45127.8 45156.5 46220.5 46501.0 25353.0 31079.4 31286.4 31490.2 31644.7 30713.2 30902.0 31029.8 31558.2 31862.0 35832.1 36164.0 37503.4 37521.0 37824.0 30586.1 30651.5 31099.8 31560.4 32249.7 24553.8 24968.5 25046.0 25248.7 25422.4 10:46:29 INFO - PROCESS | 1639 | 10:46:29 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:41461.39 Median:22965.04 stddev:117091.62 (509.9%) stddev-sans-first:117379.49 10:46:29 INFO - PROCESS | 1639 | Values: 392.8 408.3 408.7 413.1 422.8 18321.6 20990.4 21169.4 22109.3 22647.2 20999.9 22467.8 22546.3 22890.1 23040.0 6737.5 6952.5 6955.3 7016.3 7021.2 6645.5 6732.0 6883.0 6883.6 6915.6 6065.2 6593.6 6801.9 6826.6 6930.8 6802.4 6808.4 6812.5 6855.1 6888.6 6354.8 6524.2 6624.8 6641.9 6651.6 27172.0 27520.0 27740.4 27806.7 27938.7 2921.3 2921.6 2980.9 3003.6 3024.4 17475.7 17580.5 17640.4 17819.6 18010.3 2072.9 2086.6 2129.9 2155.2 2158.5 103957.6 109468.0 115080.0 125693.0 130186.0 18248.1 19144.0 23262.0 23850.0 23897.3 38087.8 38137.4 38984.0 39066.0 39155.2 9245.8 11065.0 11135.0 11175.6 11419.0 8276.7 9143.9 9147.7 9158.8 9283.7 9252.8 11187.9 11297.0 11314.7 11338.8 3649.6 3726.3 4420.6 4457.9 4475.8 909.6 938.0 949.2 977.4 988.7 22173.6 23386.3 23415.0 23430.4 23469.5 808.8 891.5 904.7 975.3 1005.9 11521.7 11999.3 12131.9 12159.4 12162.5 452693.7 635485.0 777767.0 779272.0 799510.0 158108.0 160135.0 162077.2 166157.0 166947.0 1208.6 1281.4 1315.0 1323.4 1324.9 18696.0 19375.0 20386.5 20509.1 21314.5 52898.9 53699.0 66372.7 68963.7 69266.3 97238.0 97474.5 99934.0 101054.0 102362.0 11335.1 11647.7 11731.8 11770.3 12080.9 11647.8 12772.6 13039.0 13264.2 13360.3 10906.3 11217.0 11357.7 11499.5 11502.8 10225.9 10236.5 10290.6 10586.2 10666.3 9405.9 11720.7 11749.2 12038.9 12048.3 11672.8 11806.6 11864.9 11917.9 12162.1 9483.4 11329.0 11414.7 11447.5 11799.9 10:46:29 INFO - PROCESS | 1639 | 10:46:29 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6788.06 Median:2466.32 stddev:4213.44 (170.8%) stddev-sans-first:4206.72 10:46:29 INFO - PROCESS | 1639 | Values: 1527.9 1581.4 1583.8 1592.0 1592.5 8985.5 11241.7 11496.7 11529.4 11582.5 11226.4 11288.1 11501.6 11512.1 11530.5 2074.3 2459.8 2464.9 2465.2 2467.6 2251.4 2381.1 2473.4 2477.3 2477.9 2392.2 2452.7 2461.1 2469.9 2477.0 2392.4 2445.0 2457.7 2458.3 2463.5 2433.3 2449.7 2465.4 2465.7 2466.9 11078.9 11186.3 11265.0 11274.8 11281.8 5919.7 6056.2 6105.4 6106.9 6110.1 3344.6 3408.5 3456.2 3649.2 3703.1 1191.6 1205.9 1239.1 1253.3 1265.3 13149.5 13796.6 14080.9 15081.8 15761.5 3849.4 4036.0 4242.7 4620.9 4674.4 11973.6 11994.7 12001.2 12005.2 12029.0 5984.4 5984.5 5985.3 6000.6 6008.2 5212.2 5348.6 5360.6 5425.9 5435.4 3864.0 3950.0 3950.5 4001.2 4039.2 1037.3 1039.0 1043.8 1051.9 1052.0 953.7 964.9 978.5 1000.5 1006.4 6473.0 6683.9 6813.6 6843.2 6890.7 956.8 979.2 1007.3 1008.8 1014.4 4201.6 4213.6 4234.4 4272.6 4382.5 13547.8 14223.7 14225.1 14234.4 14242.3 11727.5 14021.2 14138.4 14154.4 14163.5 8672.0 10320.6 10395.2 10402.7 10586.4 6461.2 6863.1 6944.9 7026.4 7284.1 10485.8 12209.9 12214.4 12302.4 12372.8 12589.0 12627.1 12638.4 12825.0 12861.5 8561.9 8580.2 8592.1 8619.5 8684.2 10400.5 10442.5 10482.7 10558.5 10582.3 7535.8 8944.2 9009.9 9066.8 9209.9 8573.7 8739.7 8865.8 8900.5 8960.0 7673.5 7992.4 8051.7 8106.3 8135.8 6021.5 6051.2 6054.7 6063.8 6152.3 5973.0 6173.2 6245.1 6250.9 6265.5 10:46:29 INFO - PROCESS | 1639 | 10:46:29 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4597.91 Median:5007.36 stddev:1305.32 (26.1%) stddev-sans-first:1268.67 10:46:29 INFO - PROCESS | 1639 | Values: 309.2 371.8 386.6 389.4 394.3 4518.5 4705.0 5210.3 5361.6 5425.9 5112.5 5125.2 5147.6 5175.8 5179.4 3642.7 3895.7 3952.0 4036.0 4227.7 3535.5 3555.0 3866.5 3991.7 4349.0 3655.2 3724.7 4012.6 4195.4 4381.0 4119.3 4323.0 4338.3 4342.2 4355.6 3607.5 4040.3 4043.7 4044.9 4080.2 4539.3 4554.0 4780.7 5040.0 5155.0 4285.0 4991.9 5017.4 5056.3 5115.0 5516.5 5517.8 5726.7 5730.0 5751.7 3987.8 4281.6 4328.1 4449.2 4461.3 4553.8 4888.9 5109.2 5395.4 5644.7 3859.7 3870.2 3886.7 4537.8 4592.0 4166.5 4268.4 4502.7 4621.9 4652.2 4447.9 4503.1 5030.3 5038.1 5051.4 5352.5 5548.1 5549.4 5598.5 5657.6 1435.7 1499.4 1534.9 1572.0 1586.4 1082.3 1107.4 1183.5 1210.3 1218.6 4559.7 4575.5 4895.4 5117.0 5121.6 4972.0 5567.5 5843.1 5881.6 5933.2 4647.4 5072.3 5101.8 5165.4 5190.0 2616.9 2643.9 2957.9 3001.8 3048.5 5796.6 5831.3 6006.5 6007.8 6016.2 5147.2 5151.7 5799.9 5809.8 5927.6 5406.8 5718.1 5861.6 5928.6 6043.5 5093.8 5407.6 5724.7 5797.4 5822.7 5140.3 5659.3 5724.8 5728.9 5800.9 5093.6 5285.5 5517.4 5841.0 5973.6 3983.3 4327.4 4566.4 4635.8 4640.4 4932.7 5155.9 5728.7 5827.8 5890.9 4835.6 5080.6 5180.4 5550.0 5653.4 4699.3 5581.4 5639.4 5647.7 5730.7 4593.4 4834.7 4976.7 5163.3 5470.9 4991.4 4997.3 5483.0 5637.2 5697.9 4619.4 4792.5 5088.7 5359.6 5481.8 10:46:29 INFO - PROCESS | 1639 | 10:46:29 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20431.86 Median:3818.36 stddev:29279.43 (766.8%) stddev-sans-first:29324.18 10:46:29 INFO - PROCESS | 1639 | Values: 726.5 759.4 784.6 841.2 870.5 15198.5 16016.0 16162.4 16199.2 16422.3 15326.4 15456.9 15715.8 15799.2 15819.3 964.5 981.6 984.6 987.7 991.5 959.6 980.8 982.8 986.2 993.9 1036.5 1038.4 1041.0 1041.8 1041.9 1023.6 1028.6 1032.5 1036.4 1036.6 761.3 773.1 774.9 775.1 777.2 16525.4 16815.2 16986.8 17424.3 17430.2 14059.7 14267.4 14340.4 14354.3 14361.8 11824.5 11959.9 12030.6 12058.8 12616.6 3694.4 3727.5 3781.1 3855.7 3889.2 111131.0 115697.0 117011.0 117894.0 119077.0 10836.3 10998.9 11152.6 11380.3 11496.4 23133.9 23290.7 23327.9 23357.8 23636.8 8017.8 8021.5 8028.9 8066.0 8146.4 4396.7 4396.9 4528.5 4533.8 4550.6 3567.5 3591.8 3652.4 3675.5 3695.0 529.7 644.7 646.2 659.0 660.4 656.3 656.5 670.8 686.2 715.9 10340.3 10511.4 10569.6 10767.2 10854.4 570.0 588.8 592.8 610.5 617.5 5070.9 5167.0 5180.8 5182.7 5196.7 84962.7 85949.0 87246.6 88342.0 88686.7 88873.0 89804.0 92120.1 93947.5 95051.5 72338.6 73553.3 75178.0 76625.6 77548.2 9659.0 9773.9 9859.9 9884.9 9919.0 51773.5 52670.7 52998.9 54142.5 54289.0 64701.7 64755.0 65831.7 69817.6 72443.0 13209.3 13355.2 13451.1 13474.7 13568.7 12076.2 12525.3 13025.9 14969.9 15361.6 11640.8 11790.4 11863.6 11943.5 12451.1 11608.2 12136.9 12150.5 12255.5 12542.4 11969.5 11990.5 12040.0 12046.9 12289.4 8533.5 8931.6 9003.2 9022.2 9080.5 9360.7 9798.6 10102.8 10210.9 10435.6 10:46:29 INFO - PROCESS | 1639 | -------- Summary: end -------- 10:46:29 INFO - PROCESS | 1639 | 10:46:30 INFO - TEST-INFO | 1639: exit 0 10:46:30 INFO - TEST-OK | dromaeo_css | took 1116360ms 10:46:30 INFO - TEST-START | kraken 10:46:30 INFO - operating with platform_type : mac_ 10:46:30 INFO - Initialising browser for kraken test... 10:46:30 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp_WBGvy/profile http://localhost:49225/getInfo.html) 10:46:33 INFO - PROCESS | 1652 | 2017-05-25 10:46:33.070 plugin-container[1654:10264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x852f, name = 'com.apple.tsm.portname' 10:46:33 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:33 INFO - PROCESS | 1652 | 2017-05-25 10:46:33.070 plugin-container[1654:10264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d03, name = 'com.apple.CFPasteboardClient' 10:46:33 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:33 INFO - PROCESS | 1652 | 2017-05-25 10:46:33.071 plugin-container[1654:10264] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:46:33 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 10:46:33 INFO - PROCESS | 1652 | colorDepth:24 10:46:33 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 10:46:33 INFO - PROCESS | 1652 | __metrics 10:46:33 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:46:33 INFO - TEST-INFO | 1652: exit 0 10:46:33 INFO - Browser initialized. 10:46:33 INFO - Running cycle 1/1 for kraken test... 10:46:33 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmp_WBGvy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:46:36 INFO - PROCESS | 1655 | 2017-05-25 10:46:36.223 plugin-container[1656:10389] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 10:46:36 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:36 INFO - PROCESS | 1655 | 2017-05-25 10:46:36.223 plugin-container[1656:10389] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:46:36 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:46:36 INFO - PROCESS | 1655 | 2017-05-25 10:46:36.223 plugin-container[1656:10389] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:46:45 INFO - PROCESS | 1655 | RSS: Main: 202846208 10:46:45 INFO - PROCESS | 1655 | 10:46:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 10:46:50 INFO - PROCESS | 1655 | RSS: Main: 216514560 10:46:50 INFO - PROCESS | 1655 | 10:46:52 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 10:46:53 INFO - PROCESS | 1655 | RSS: Main: 224464896 10:46:53 INFO - PROCESS | 1655 | 10:46:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 10:46:57 INFO - PROCESS | 1655 | RSS: Main: 228098048 10:46:57 INFO - PROCESS | 1655 | 10:46:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 10:46:59 INFO - PROCESS | 1655 | RSS: Main: 229556224 10:46:59 INFO - PROCESS | 1655 | 10:47:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:47:02 INFO - PROCESS | 1655 | RSS: Main: 229478400 10:47:02 INFO - PROCESS | 1655 | 10:47:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 10:47:06 INFO - PROCESS | 1655 | RSS: Main: 233680896 10:47:06 INFO - PROCESS | 1655 | 10:47:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 10:47:09 INFO - PROCESS | 1655 | RSS: Main: 233136128 10:47:09 INFO - PROCESS | 1655 | 10:47:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 10:47:13 INFO - PROCESS | 1655 | RSS: Main: 233869312 10:47:13 INFO - PROCESS | 1655 | 10:47:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:47:16 INFO - PROCESS | 1655 | RSS: Main: 233398272 10:47:16 INFO - PROCESS | 1655 | 10:47:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:47:18 INFO - PROCESS | 1655 | RSS: Main: 233353216 10:47:18 INFO - PROCESS | 1655 | 10:47:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:47:21 INFO - PROCESS | 1655 | RSS: Main: 233406464 10:47:21 INFO - PROCESS | 1655 | 10:47:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:47:25 INFO - PROCESS | 1655 | RSS: Main: 233824256 10:47:25 INFO - PROCESS | 1655 | 10:47:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:47:28 INFO - PROCESS | 1655 | RSS: Main: 233521152 10:47:28 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 10:47:30 INFO - PROCESS | 1655 | RSS: Main: 235331584 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | __start_tp_report 10:47:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 10:47:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 10:47:30 INFO - PROCESS | 1655 | |i|pagename|runs| 10:47:30 INFO - PROCESS | 1655 | |0;ai-astar;110;151;178;147;120;127;126;120;121;120 10:47:30 INFO - PROCESS | 1655 | |1;audio-beat-detection;144;125;142;148;139;140;143;111;132;104 10:47:30 INFO - PROCESS | 1655 | |2;audio-dft;199;196;196;198;196;201;196;195;196;195 10:47:30 INFO - PROCESS | 1655 | |3;audio-fft;85;62;60;90;64;61;64;70;97;53 10:47:30 INFO - PROCESS | 1655 | |4;audio-oscillator;92;72;116;102;103;72;81;71;70;82 10:47:30 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;91;90;91;99;110;103;92;91;97;90 10:47:30 INFO - PROCESS | 1655 | |6;imaging-darkroom;98;98;99;99;126;154;141;98;99;113 10:47:30 INFO - PROCESS | 1655 | |7;imaging-desaturate;97;116;90;92;93;93;95;91;92;91 10:47:30 INFO - PROCESS | 1655 | |8;json-parse-financial;67;66;66;68;70;70;66;87;102;106 10:47:30 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;56;64;57;64;65;64;58;56;58;60 10:47:30 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;84;88;84;93;104;106;97;84;90;85 10:47:30 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;140;159;139;113;143;166;154;139;208;133 10:47:30 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;166;164;164;168;164;216;194;165;170;168 10:47:30 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;60;83;86;58;71;82;85;85;81;67 10:47:30 INFO - PROCESS | 1655 | __end_tp_report 10:47:30 INFO - PROCESS | 1655 | __start_cc_report 10:47:30 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1007 10:47:30 INFO - PROCESS | 1655 | __end_cc_report 10:47:30 INFO - PROCESS | 1655 | __startTimestamp1495734450762__endTimestamp 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | ------- Summary: start ------- 10:47:30 INFO - PROCESS | 1655 | Number of tests: 14 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:132.00 Median:126.50 stddev:20.55 (16.2%) stddev-sans-first:20.19 10:47:30 INFO - PROCESS | 1655 | Values: 110.0 151.0 178.0 147.0 120.0 127.0 126.0 120.0 121.0 120.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:141.00 stddev:14.91 (10.6%) stddev-sans-first:15.26 10:47:30 INFO - PROCESS | 1655 | Values: 144.0 125.0 142.0 148.0 139.0 140.0 143.0 111.0 132.0 104.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:196.80 Median:196.00 stddev:1.93 (1.0%) stddev-sans-first:1.88 10:47:30 INFO - PROCESS | 1655 | Values: 199.0 196.0 196.0 198.0 196.0 201.0 196.0 195.0 196.0 195.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:70.60 Median:67.00 stddev:14.74 (22.0%) stddev-sans-first:14.69 10:47:30 INFO - PROCESS | 1655 | Values: 85.0 62.0 60.0 90.0 64.0 61.0 64.0 70.0 97.0 53.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:86.10 Median:72.00 stddev:16.31 (22.7%) stddev-sans-first:17.16 10:47:30 INFO - PROCESS | 1655 | Values: 92.0 72.0 116.0 102.0 103.0 72.0 81.0 71.0 70.0 82.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:95.40 Median:91.00 stddev:6.79 (7.5%) stddev-sans-first:7.01 10:47:30 INFO - PROCESS | 1655 | Values: 91.0 90.0 91.0 99.0 110.0 103.0 92.0 91.0 97.0 90.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:112.50 Median:98.00 stddev:20.80 (21.2%) stddev-sans-first:21.39 10:47:30 INFO - PROCESS | 1655 | Values: 98.0 98.0 99.0 99.0 126.0 154.0 141.0 98.0 99.0 113.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:92.50 stddev:7.66 (8.3%) stddev-sans-first:8.09 10:47:30 INFO - PROCESS | 1655 | Values: 97.0 116.0 90.0 92.0 93.0 93.0 95.0 91.0 92.0 91.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:76.80 Median:67.50 stddev:15.66 (23.2%) stddev-sans-first:16.21 10:47:30 INFO - PROCESS | 1655 | Values: 67.0 66.0 66.0 68.0 70.0 70.0 66.0 87.0 102.0 106.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:62.00 stddev:3.68 (5.9%) stddev-sans-first:3.57 10:47:30 INFO - PROCESS | 1655 | Values: 56.0 64.0 57.0 64.0 65.0 64.0 58.0 56.0 58.0 60.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:86.50 stddev:8.33 (9.6%) stddev-sans-first:8.38 10:47:30 INFO - PROCESS | 1655 | Values: 84.0 88.0 84.0 93.0 104.0 106.0 97.0 84.0 90.0 85.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:148.50 stddev:25.30 (17.0%) stddev-sans-first:26.61 10:47:30 INFO - PROCESS | 1655 | Values: 140.0 159.0 139.0 113.0 143.0 166.0 154.0 139.0 208.0 133.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.90 Median:168.00 stddev:17.31 (10.3%) stddev-sans-first:18.12 10:47:30 INFO - PROCESS | 1655 | Values: 166.0 164.0 164.0 168.0 164.0 216.0 194.0 165.0 170.0 168.0 10:47:30 INFO - PROCESS | 1655 | 10:47:30 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:82.50 stddev:10.84 (13.1%) stddev-sans-first:9.88 10:47:30 INFO - PROCESS | 1655 | Values: 60.0 83.0 86.0 58.0 71.0 82.0 85.0 85.0 81.0 67.0 10:47:30 INFO - PROCESS | 1655 | -------- Summary: end -------- 10:47:30 INFO - PROCESS | 1655 | 10:47:31 INFO - TEST-INFO | 1655: exit 0 10:47:31 INFO - TEST-OK | kraken | took 61182ms 10:47:31 INFO - SUITE-END | took 1177s 10:47:31 INFO - Completed test suite (00:19:37) 10:47:31 INFO - javascript benchmark 10:47:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7735.772723802156, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3477.808720520371, 3513.8243087845617, 3538.52229948957, 3558.768680035799, 3567.9286414271705, 5989.3112292086425, 6045.274567051954, 6064.272287325522, 6064.4541991220785, 6075.210222671058, 5822.068469044953, 5961.701914904248, 6039.1545183674325, 6040.335563088068, 6066.484348830355, 4717.0, 5408.8377348679405, 5409.56723462122, 5435.347758269013, 5552.222688823568, 5470.070088085035, 5489.890202195954, 5491.176323551459, 5493.945060549383, 5510.91733623996, 5326.920096198541, 5338.412774594792, 5471.069918113911, 5479.013777520045, 5494.36814766301, 5425.13197888338, 5448.8365349039395, 5493.148561972901, 5531.529819965308, 5536.197251398525, 5331.493508116761, 5423.322084739408, 5436.782528698847, 5440.782368705287, 5480.561555075585, 5729.054705973509, 6005.0, 6065.332813390523, 6095.116208149793, 6106.938930610681, 6352.872942541169, 6363.7454501819875, 6367.968160159169, 6399.13611662429, 6411.262704788963, 6479.805605831785, 6484.805455836333, 6490.221173459168, 6505.544611877171, 6512.120863683437, 6119.082137679295, 6195.721192546346, 6211.565190436626, 6216.409441103088, 6221.751129954841, 6232.75068997235, 6368.10846481493, 6465.062565927914, 6472.255690595642, 6479.416852483299, 6380.0, 6389.297177310492, 6404.0, 6409.743610255631, 6414.0, 5943.900378430004, 6387.489000879918, 6436.420722135031, 6464.321246269168, 6491.67541622917, 6264.154339164154, 6317.905231421532, 6321.0, 6335.841603959938, 6391.872162556723, 6246.844333798262, 6373.362663733589, 6378.808635740842, 6394.200724909387, 6418.165638467063, 4600.332951722048, 4754.263089221176, 4866.562009419169, 4931.161702510582, 4940.308356830046, 3901.785401802928, 4404.977975110168, 4437.911241775211, 4439.755813430228, 4456.108778244364, 4959.82640607577, 5896.0, 5944.4352786485215, 6006.399360063959, 6036.034234522455, 5700.800471983461, 6217.626942383381, 6391.776287829904, 6425.775097871552, 6463.7091330890225, 5925.525957923356, 5974.163617093611, 5988.371221021817, 6006.489448396847, 6031.125486804389, 4787.808487660524, 5413.648112872651, 5427.48438898304, 5481.808136715275, 5541.251930989345, 5759.308882934075, 6136.519078024374, 6582.901256481061, 6594.571352862049, 6660.6336651484635, 6409.327020662763, 6418.229812422539, 6427.421532062137, 6431.453326467303, 6489.480841532761, 6297.842553936188, 6344.492440604833, 6589.209294884645, 6606.6036037837885, 6633.3035031321015, 6335.1130841683125, 6337.588056776295, 6404.807855764335, 6414.390632889868, 6416.358364163733, 5603.971980140073, 5870.618409803349, 6430.64631445275, 6440.4525615321345, 6446.323136070645, 6150.200473938538, 6214.875702486015, 6383.170187875547, 6392.360763923756, 6405.7117429714845, 4621.468531118964, 5108.974455127701, 6241.531885108544, 6324.335944725922, 6385.329540398191, 6145.201123853871, 6261.405166509175, 6291.905621415591, 6358.936410635834, 6359.459445946961, 6165.260168779596, 6205.565610407228, 6237.438630523275, 6250.9062364065485, 6348.333424990309, 6276.9372306276355, 6278.6546739929745, 6280.842978925563, 6296.024116261987, 6300.59046161998], "value": 5857.148647386149, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.7553740627218, 812.1107387410785, 830.4228561150003, 838.3795990966687, 974.2352253481018, 38894.61105388945, 39917.40123898144, 40814.59185408145, 40887.18225635489, 40968.0, 39785.81713462923, 39877.80061099698, 39988.00029999251, 40087.59912400875, 40166.59833401665, 8056.8388632227325, 8205.220504052106, 11039.282446640982, 11321.490532926038, 11541.942290288536, 11233.943830280838, 11337.37644429556, 11364.204505684605, 11370.772584548304, 11378.317300961928, 8384.203500667427, 9359.766005849875, 10767.084797792197, 11046.889531104707, 11393.54425822966, 9391.859122113208, 11318.377489238088, 11364.886351136507, 11376.976072153546, 11390.373529455877, 11002.614908478165, 11038.17213708967, 11045.171612129043, 11070.225084244106, 11070.778584428346, 34882.65117348819, 35177.0, 35189.29621407558, 35330.0, 35361.0, 3808.1241314497543, 3875.767453952772, 3890.0469385000574, 3899.220155968789, 3909.550401703784, 25872.482550349076, 26452.73547264522, 26488.60267095995, 26829.0, 26921.0, 3075.9538606920914, 3117.984410077935, 3131.8434078295995, 3143.167060728909, 3189.617245930503, 310220.89779102145, 312223.0, 352332.2383388092, 394566.0543394558, 421121.0, 43418.78290608527, 43644.563554364686, 43805.0, 43813.34279985803, 43888.0, 45517.77241113773, 45544.77227613907, 45595.316070258974, 45650.0, 45742.542574574494, 13872.375743091585, 13924.86075139256, 13942.024058316023, 13943.860561394458, 14012.859871401359, 12210.694732631755, 12213.511459541696, 12220.266783992989, 12228.899399054128, 12239.95960343363, 11783.941080294544, 11868.940655296668, 11895.0, 11914.0, 11998.280103193836, 4817.000944607948, 5631.549476041907, 5687.317521897398, 5697.487226149667, 5699.430056994351, 913.6939125393068, 960.9471479068718, 1150.9942450287863, 1195.485941045359, 1217.2696382170627, 25782.09762658298, 26356.72802735113, 26396.47207055848, 26482.940682372875, 26513.86743066272, 1270.8856202941595, 1273.446050967832, 1274.439246731435, 1328.7873940169527, 1340.986590134086, 13328.0, 13728.244946528037, 13858.4456621734, 13984.230867302396, 14185.78721319181, 407439.0, 410474.0, 410726.0, 410817.9459102685, 410963.0, 54506.727466363205, 54966.17550736742, 54967.90064198773, 55021.0, 55134.0, 1327.6172037062693, 1449.3115770009372, 1511.3350125944548, 1514.35639853062, 1519.7112548615728, 21288.0, 23920.905244045847, 25613.871930640227, 26010.34974125662, 26014.86992565025, 45005.09989800251, 45022.0, 45166.548334516236, 45186.77406612946, 45238.77380613076, 366848.0, 368625.0, 369776.0, 370127.0, 370581.0, 25540.48919021572, 26133.60799588046, 26664.466710666067, 31385.0, 31951.520727189574, 44037.779811100736, 45127.77436112799, 45156.548434516546, 46220.53779462297, 46501.0, 25353.0, 31079.378412432077, 31286.374272513967, 31490.212744681565, 31644.683553164174, 30713.232169195948, 30902.0, 31029.8448507756, 31558.211044724067, 31862.0, 35832.104197395274, 36164.0, 37503.43744843775, 37521.0, 37824.0, 30586.08241752751, 30651.54022689706, 31099.84450077735, 31560.368792623558, 32249.74251802351, 24553.75446245586, 24968.500629986935, 25046.0, 25248.74751252464, 25422.364440889127], "value": 18508.877122464586, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.80359820089956, 408.2956899348622, 408.69143796433667, 413.1447902841119, 422.7548022147155, 18321.633567328645, 20990.37028889132, 21169.364919052434, 22109.336719898387, 22647.207347742813, 20999.895000525015, 22467.76427296498, 22546.323610291678, 22890.084396624115, 23040.0, 6737.494687898403, 6952.478564107687, 6955.269696681851, 7016.333448322402, 7021.192562855286, 6645.474719606543, 6732.0, 6883.0, 6883.621400822953, 6915.550489218184, 6065.2418447694035, 6593.604383736991, 6801.93198068018, 6826.590404575716, 6930.826729331782, 6802.3537763912345, 6808.421284190849, 6812.489063320246, 6855.108835851357, 6888.586684798928, 6354.80935571929, 6524.249711283216, 6624.8012559623285, 6641.933580664228, 6651.60090394573, 27172.0, 27520.0, 27740.445191096267, 27806.748696308714, 27938.742756576004, 2921.3134913295357, 2921.6347956505438, 2980.925476863074, 3003.594514740504, 3024.3951209757915, 17475.737863932052, 17580.472585822445, 17640.38258660941, 17819.554511137194, 18010.279588816433, 2072.89100331322, 2086.645270304052, 2129.8516038277908, 2155.234891613486, 2158.460384903774, 103957.62955509296, 109468.0, 115080.0, 125693.0, 130186.0, 18248.087595620167, 19144.0, 23262.022728067965, 23850.04599815988, 23897.283081507583, 38087.80956095202, 38137.42793858094, 38984.0, 39066.02334941649, 39155.216895662496, 9245.815083698422, 11065.008298506185, 11135.0, 11175.552977880956, 11419.0, 8276.710315138942, 9143.862842057375, 9147.679831205876, 9158.771030724285, 9283.675071372469, 9252.7508786313, 11187.888121118685, 11296.98327150544, 11314.717132071599, 11338.829917551244, 3649.616790236987, 3726.2547490502006, 4420.596140971498, 4457.910841783146, 4475.7985890635, 909.6361455417754, 937.9963439120212, 949.1552518258756, 977.423320241047, 988.7132731507783, 22173.556528869332, 23386.298411047697, 23415.0, 23430.414239644146, 23469.530609388057, 808.789714674189, 891.5141248019845, 904.7240591619487, 975.317277905469, 1005.8541511480794, 11521.711957201138, 11999.280043197436, 12131.878681213251, 12159.392030398445, 12162.513499459923, 452693.73653131525, 635485.0, 777767.0, 779272.0, 799510.0, 158108.0, 160135.0, 162077.18961405117, 166157.0, 166947.0, 1208.6434501821984, 1281.4425724809737, 1314.9868501314863, 1323.3912400295778, 1324.920504769717, 18696.0, 19375.0, 20386.490337741674, 20509.077091531217, 21314.467138321044, 52898.94202116013, 53699.0, 66372.668136659, 68963.65518172376, 69266.30733692598, 97238.0, 97474.51262743642, 99934.0, 101054.0, 102362.0, 11335.149863760085, 11647.708807279547, 11731.765364692828, 11770.293782373084, 12080.939595301967, 11647.776983416958, 12772.552960646333, 13039.022073344348, 13264.204147751167, 13360.331983400985, 10906.34561926287, 11216.990470857661, 11357.659270221906, 11499.482523286639, 11502.82745758831, 10225.948870255601, 10236.53935572886, 10290.639827605997, 10586.206034547286, 10666.306690065432, 9405.85891211646, 11720.70698232551, 11749.177557570887, 12038.939805301268, 12048.337341446304, 11672.774358692215, 11806.586769463027, 11864.881351186377, 11917.880821191677, 12162.087843411957, 9483.430994140374, 11328.980391764451, 11414.714632134263, 11447.542098315975, 11799.882001179878], "value": 11825.799937337119, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1527.8777697784178, 1581.351645825211, 1583.8020247469067, 1591.9840801591984, 1592.522243327002, 8985.460872347656, 11241.718957026078, 11496.71258218545, 11529.365884876328, 11582.536698532049, 11226.438678066104, 11288.09695224382, 11501.597444089459, 11512.079033677306, 11530.538778448872, 2074.3051077888886, 2459.827812053157, 2464.8890799913956, 2465.174166654173, 2467.592847180213, 2251.448395143192, 2381.071382160959, 2473.4311108445067, 2477.2939712182033, 2477.8513289202615, 2392.1986134644917, 2452.6688896998944, 2461.064795377763, 2469.8641574713383, 2476.9628455573093, 2392.3899405651446, 2445.0464318915638, 2457.7296497385273, 2458.323960910746, 2463.4826686395877, 2433.2943446400523, 2449.7060352757603, 2465.3836540864786, 2465.7410971847876, 2466.8889899954547, 11078.947499987487, 11186.272892261977, 11265.0, 11274.774504509945, 11281.774364512747, 5919.674417907014, 6056.242969628796, 6105.389461053904, 6106.938930610681, 6110.052941794029, 3344.5819272590925, 3408.4887266909914, 3456.2050728332624, 3649.215418684969, 3703.0742314421395, 1191.559123124441, 1205.873383294759, 1239.0583156800806, 1253.3357320620087, 1265.33569875815, 13149.539766108235, 13796.586102416944, 14080.859191408057, 15081.849181508045, 15761.527154185393, 3849.3840985442193, 4035.959640403617, 4242.745435273893, 4620.867887367616, 4674.392328997209, 11973.580924667594, 11994.700132496757, 12001.219920705125, 12005.159638825373, 12029.0, 5984.401559843981, 5984.491318237911, 5985.281766188085, 6000.609960352563, 6008.24896887889, 5212.218167274863, 5348.5988550858065, 5360.597955153379, 5425.891482170335, 5435.402105768362, 3864.0, 3950.0, 3950.5259368875345, 4001.239764444746, 4039.171969746195, 1037.2946396450336, 1039.0492699180165, 1043.7547176413536, 1051.9316244444087, 1052.032130439997, 953.6519170502717, 964.8697425847421, 978.4716253223322, 1000.5247507433911, 1006.3961623026125, 6473.0, 6683.933160668428, 6813.625250611264, 6843.178818541707, 6890.724371025103, 956.7560272130562, 979.153032626787, 1007.2697294461457, 1008.7780688248573, 1014.3812274512542, 4201.579842015835, 4213.620774130343, 4234.407182994383, 4272.551382104834, 4382.452193475816, 13547.796783048263, 14223.715525689428, 14225.075370101118, 14234.359453824603, 14242.287885605678, 11727.530898764124, 14021.158730476203, 14138.363773630212, 14154.433822647185, 14163.504277350243, 8671.956640216758, 10320.638777642747, 10395.220358473145, 10402.739931501774, 10586.417747023988, 6461.19235095613, 6863.142107236596, 6944.861102778017, 7026.437884969292, 7284.089488813898, 10485.842712359168, 12209.877901220874, 12214.38928053576, 12302.384880755748, 12372.81440778407, 12589.0, 12627.052971027393, 12638.368081596067, 12825.038122141063, 12861.485540578273, 8561.871571926302, 8580.227779499624, 8592.097829727789, 8619.482831030158, 8684.175003374668, 10400.531976061247, 10442.477876106317, 10482.68551943443, 10558.472076396303, 10582.259241852997, 7535.773926782205, 8944.195022448012, 9009.909900900908, 9066.818663626558, 9209.861852072358, 8573.657053717781, 8739.694110706096, 8865.778355540904, 8900.510471924106, 8960.0, 7673.53958762476, 7992.40056995716, 8051.677932882619, 8106.270435660819, 8135.79660508492, 6021.54838387114, 6051.213342265477, 6054.66699331541, 6063.818085457443, 6152.292486364126, 5972.970135149297, 6173.166622505904, 6245.094461303176, 6250.874982500415, 6265.498760099273], "value": 5193.9568595488145, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.24852608162183, 371.7620722737449, 386.5612529778701, 389.376996805112, 394.27256711367517, 4518.457785065797, 4704.952950470494, 5210.270562121296, 5361.57107431406, 5425.864353391167, 5112.514311140445, 5125.205593133071, 5147.588192944565, 5175.792968281274, 5179.430262671112, 3642.708583313342, 3895.7272990890647, 3951.9604803952025, 4036.031352475414, 4227.682923780713, 3535.497413599223, 3554.9822250888838, 3866.4586957825786, 3991.740536865109, 4348.956510434903, 3655.1593133579295, 3724.720645951551, 4012.5786792386816, 4195.412642230091, 4380.9552361109045, 4119.340905455113, 4322.962489002648, 4338.327559228324, 4342.19928146347, 4355.607995280408, 3607.5310209672575, 4040.272750904836, 4043.6765058795227, 4044.876439869063, 4080.204360149772, 4539.277792210389, 4553.954460455386, 4780.737059461728, 5040.042391945519, 5154.974225128888, 4284.957150428519, 4991.851874068948, 5017.397912250517, 5056.292119103328, 5115.030713175417, 5516.475934786189, 5517.834464966058, 5726.656400615976, 5730.0, 5751.712414379306, 3987.766850563665, 4281.636060934824, 4328.069465065026, 4449.221386257392, 4461.330800379969, 4553.795079221443, 4888.902221955541, 5109.182530795092, 5395.381385584348, 5644.6613203207935, 3859.749116307431, 3870.200356834029, 3886.667999200457, 4537.841175558906, 4592.0, 4166.458360413127, 4268.371883898723, 4502.662300327489, 4621.907561848744, 4652.232381656989, 4447.888027993002, 4503.121891231244, 5030.252461915896, 5038.143515602356, 5051.444341122474, 5352.464753524617, 5548.0845660466375, 5549.38956714761, 5598.468145526169, 5657.623798004848, 1435.7343891380128, 1499.3852520466555, 1534.9309281082378, 1572.042806705636, 1586.4288856011826, 1082.3235477826358, 1107.379867274329, 1183.5325046606542, 1210.2738356985737, 1218.561317925546, 4559.726416415026, 4575.522602821879, 4895.388076490439, 5116.9744151279, 5121.590272778169, 4972.0, 5567.526760225345, 5843.065109582446, 5881.5588830837005, 5933.228680271537, 4647.442306923191, 5072.289879416885, 5101.821436249714, 5165.431802501722, 5190.0, 2616.9476610468064, 2643.947121057568, 2957.8816847326298, 3001.789874708793, 3048.4512787697986, 5796.608983744472, 5831.309263039678, 6006.459418652343, 6007.789727359522, 6016.195141457545, 5147.202183661539, 5151.6908985461, 5799.88400231993, 5809.7966571170655, 5927.644341339534, 5406.756446017359, 5718.113022702415, 5861.648301101863, 5928.614640048383, 6043.456088951927, 5093.821716239914, 5407.5944304176555, 5724.742386592697, 5797.362290148165, 5822.737976791139, 5140.254663073908, 5659.302209337265, 5724.771009159587, 5728.856778580569, 5800.868830577997, 5093.567046801063, 5285.497877701612, 5517.36550296721, 5841.007028805028, 5973.581849270509, 3983.346911032005, 4327.415798867239, 4566.383538222298, 4635.814567417266, 4640.443146822403, 4932.704037757746, 5155.871103222449, 5728.6562806230095, 5827.766889324549, 5890.911636325544, 4835.582208895552, 5080.644354895122, 5180.404253510744, 5550.028744969695, 5653.434656534314, 4699.3420921071765, 5581.441855814549, 5639.351474580311, 5647.661140331593, 5730.742116604846, 4593.436120529654, 4834.670465621982, 4976.651634385703, 5163.347728726922, 5470.9179362308805, 4991.425986011655, 4997.3253610762085, 5482.972585137049, 5637.237753500837, 5697.943020569907, 4619.399478067964, 4792.525419071285, 5088.6946783193125, 5359.571234301168, 5481.835544933659], "value": 4217.115926321969, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.5422783646302, 759.392486011191, 784.6469088909988, 841.1504380575618, 870.5386145342967, 15198.468053618126, 16015.958962667419, 16162.35350585978, 16199.190040497971, 16422.26099825508, 15326.386944522206, 15456.922715386407, 15715.764263536055, 15799.210039498008, 15819.288132034057, 964.5322018820884, 981.5779214937573, 984.5815528400437, 987.7086259553413, 991.4745185051934, 959.6497278493334, 980.8136454073704, 982.773961988743, 986.1814693804162, 993.9353941993784, 1036.5231993283062, 1038.3717850700332, 1040.973975650611, 1041.7603951091253, 1041.9426931518763, 1023.6417253961129, 1028.6451174344838, 1032.4682788363962, 1036.3937096798381, 1036.6371769880518, 761.2615762710218, 773.1225059557387, 774.8682723936944, 775.0505630602523, 777.1723114882632, 16525.421610243706, 16815.159242037847, 16986.830131698647, 17424.30302787887, 17430.215640296217, 14059.71880562383, 14267.429302827875, 14340.42638294467, 14354.282285885663, 14361.818001782334, 11824.52701891932, 11959.880401195964, 12030.578929737505, 12058.819117713241, 12616.621501354974, 3694.4273637586216, 3727.4967879336214, 3781.0736369589627, 3855.652991230803, 3889.2027134437653, 111130.98032947324, 115697.0, 117011.0, 117894.0, 119077.0, 10836.349819010884, 10998.945005274922, 11152.641781400698, 11380.260283081574, 11496.425178741198, 23133.88433057824, 23290.65064024041, 23327.883360583088, 23357.76642233556, 23636.7636323638, 8017.79733040037, 8021.478603890729, 8028.878141788854, 8066.0, 8146.389020823462, 4396.714213576107, 4396.868093957187, 4528.50186479487, 4533.818647254139, 4550.635949124062, 3567.4827150063097, 3591.7665351752053, 3652.3608368535934, 3675.467057276733, 3695.0, 529.7483695244805, 644.6969924135649, 646.2374398210063, 658.9769358072444, 660.359451332207, 656.2551504042946, 656.5010591950153, 670.8222321084917, 686.2177118085386, 715.8747219236612, 10340.275158325245, 10511.369317840954, 10569.63006294776, 10767.246292759588, 10854.403007834644, 569.9601027928088, 588.807664479424, 592.8251165906069, 610.5451438678214, 617.5183356981472, 5070.923936140961, 5167.0, 5180.792768289226, 5182.663126896739, 5196.740162991835, 84962.72555911666, 85949.0, 87246.56376718076, 88342.0, 88686.66969995455, 88873.0, 89804.0, 92120.07879921114, 93947.53026234826, 95051.52474237584, 72338.63830680813, 73553.26446735571, 75178.0, 76625.61687191528, 77548.22451775522, 9659.0, 9773.85339219898, 9859.901400985898, 9884.851727223953, 9918.950405247928, 51773.48226517686, 52670.73664631652, 52998.940021200135, 54142.458575415316, 54289.0, 64701.67649161724, 64755.028674568974, 65831.67084164548, 69817.60364792777, 72443.0, 13209.273489958145, 13355.198688078746, 13451.117436799936, 13474.730505390033, 13568.660783480493, 12076.21504602198, 12525.311107889154, 13025.869741302466, 14969.850301496846, 15361.6159596011, 11640.825387619361, 11790.351530665552, 11863.644090677293, 11943.522259109539, 12451.128421010804, 11608.187426880037, 12136.939315303367, 12150.453229604866, 12255.54889022217, 12542.372881356077, 11969.521219151486, 11990.520379184734, 12040.0, 12046.939765301116, 12289.446974885972, 8533.487990720576, 8931.560465104738, 9003.234725048182, 9022.233110185445, 9080.500572468578, 9360.719178424659, 9798.608055677978, 10102.797944041225, 10210.897891020995, 10435.634752783617], "value": 7630.807453815813, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 151.0, 178.0, 147.0, 120.0, 127.0, 126.0, 120.0, 121.0, 120.0], "value": 132.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 125.0, 142.0, 148.0, 139.0, 140.0, 143.0, 111.0, 132.0, 104.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 196.0, 196.0, 198.0, 196.0, 201.0, 196.0, 195.0, 196.0, 195.0], "value": 196.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 62.0, 60.0, 90.0, 64.0, 61.0, 64.0, 70.0, 97.0, 53.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 72.0, 116.0, 102.0, 103.0, 72.0, 81.0, 71.0, 70.0, 82.0], "value": 86.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 99.0, 110.0, 103.0, 92.0, 91.0, 97.0, 90.0], "value": 95.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 99.0, 99.0, 126.0, 154.0, 141.0, 98.0, 99.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 116.0, 90.0, 92.0, 93.0, 93.0, 95.0, 91.0, 92.0, 91.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 68.0, 70.0, 70.0, 66.0, 87.0, 102.0, 106.0], "value": 76.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 64.0, 57.0, 64.0, 65.0, 64.0, 58.0, 56.0, 58.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 84.0, 93.0, 104.0, 106.0, 97.0, 84.0, 90.0, 85.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 159.0, 139.0, 113.0, 143.0, 166.0, 154.0, 139.0, 208.0, 133.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 164.0, 168.0, 164.0, 216.0, 194.0, 165.0, 170.0, 168.0], "value": 173.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 83.0, 86.0, 58.0, 71.0, 82.0, 85.0, 85.0, 81.0, 67.0], "value": 75.8, "unit": "score"}]}]} 10:47:32 INFO - Return code: 0 10:47:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:47:32 ERROR - # TBPL SUCCESS # 10:47:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:47:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:47:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:47:32 INFO - 2017-05-25 10:47:32.088 screenresolution[1660:10784] starting screenresolution argv=screenresolution get 10:47:32 INFO - 2017-05-25 10:47:32.091 screenresolution[1660:10784] Display 0: 1600x1200x32@60 10:47:32 INFO - 2017-05-25 10:47:32.097 screenresolution[1661:10787] starting screenresolution argv=screenresolution list 10:47:32 INFO - Available Modes on Display 0 10:47:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:47:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:47:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:47:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:47:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:47:32 INFO - Intel Iris: 10:47:32 INFO - Chipset Model: Intel Iris 10:47:32 INFO - Type: GPU 10:47:32 INFO - Bus: Built-In 10:47:32 INFO - VRAM (Dynamic, Max): 1536 MB 10:47:32 INFO - Vendor: Intel (0x8086) 10:47:32 INFO - Device ID: 0x0a2e 10:47:32 INFO - Revision ID: 0x0009 10:47:32 INFO - Displays: 10:47:32 INFO - Display: 10:47:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:47:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:47:32 INFO - Display Serial Number: 1600x1200 60 10:47:32 INFO - Main Display: Yes 10:47:32 INFO - Mirror: Off 10:47:32 INFO - Online: Yes 10:47:32 INFO - Rotation: Supported 10:47:32 INFO - Return code: 0 10:47:32 INFO - Running post-action listener: _resource_record_post_action 10:47:32 INFO - [mozharness: 2017-05-25 17:47:32.334175Z] Finished run-tests step (success) 10:47:32 INFO - Running post-run listener: _resource_record_post_run 10:47:32 INFO - Total resource usage - Wall time: 1199s; CPU: 55.0%; Read bytes: 49207296; Write bytes: 512918528; Read time: 550; Write time: 2056 10:47:32 INFO - TinderboxPrint: CPU usage
54.6% 10:47:32 INFO - TinderboxPrint: I/O read bytes / time
49,207,296 / 550 10:47:32 INFO - TinderboxPrint: I/O write bytes / time
512,918,528 / 2,056 10:47:32 INFO - TinderboxPrint: CPU idle
2,179.0 (45.4%) 10:47:32 INFO - TinderboxPrint: CPU system
966.7 (20.1%) 10:47:32 INFO - TinderboxPrint: CPU user
1,652.0 (34.4%) 10:47:32 INFO - TinderboxPrint: Swap in / out
250,048,512 / 0 10:47:32 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201142784; Write bytes: 202557440; Read time: 15529; Write time: 407 10:47:32 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 48183296; Write bytes: 300432384; Read time: 526; Write time: 1608 10:47:32 INFO - Running post-run listener: _upload_blobber_files 10:47:32 INFO - Blob upload gear active. 10:47:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:47:32 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 10:47:32 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'] 10:47:32 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 10:47:33 INFO - (blobuploader) - INFO - Open directory for files ... 10:47:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:47:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:47:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:47:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:47:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:47:34 INFO - (blobuploader) - INFO - Done attempting. 10:47:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:47:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:47:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:47:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:47:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:47:35 INFO - (blobuploader) - INFO - Done attempting. 10:47:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:47:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:47:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:47:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:47:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:47:36 INFO - (blobuploader) - INFO - Done attempting. 10:47:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:47:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:47:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:47:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:47:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:47:38 INFO - (blobuploader) - INFO - Done attempting. 10:47:38 INFO - (blobuploader) - INFO - Iteration through files over. 10:47:38 INFO - Return code: 0 10:47:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:47:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:47:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9fc1f2f2235ee21b2c2fd623f2e16a52ec4d61de283356c58b286b36dbada637355bd00df52502184e5f15f7ee7b88008dfd9a40f4437d646739d509ff19011c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4cd31a68dd29407f28389077fa01080b86cb78c8f43b4ce4e88a9a0466d9d9e6cfe6b3ff470bda6d59e30201e638cd7aec17707fd4101eec0d43c5b676c12d9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f19f88e1ada0627b4218ef9cc0a40a3d4b5272d8a90b81ceaaf045a8220d64606799a213458f1f14403bf7103dc78604e63f898457a109dc44c7da48b168a496", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/954923a52cb5adf05e1b908dc88b08cd1258243a1c7e99ef5ecb0b68b643f1423f04b58c8eae04e5d35a71fe2c0133f51f861d8cede33fac3192672ae9c4d1c6"} 10:47:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:47:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:47:38 INFO - Contents: 10:47:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9fc1f2f2235ee21b2c2fd623f2e16a52ec4d61de283356c58b286b36dbada637355bd00df52502184e5f15f7ee7b88008dfd9a40f4437d646739d509ff19011c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4cd31a68dd29407f28389077fa01080b86cb78c8f43b4ce4e88a9a0466d9d9e6cfe6b3ff470bda6d59e30201e638cd7aec17707fd4101eec0d43c5b676c12d9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f19f88e1ada0627b4218ef9cc0a40a3d4b5272d8a90b81ceaaf045a8220d64606799a213458f1f14403bf7103dc78604e63f898457a109dc44c7da48b168a496", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/954923a52cb5adf05e1b908dc88b08cd1258243a1c7e99ef5ecb0b68b643f1423f04b58c8eae04e5d35a71fe2c0133f51f861d8cede33fac3192672ae9c4d1c6"} 10:47:38 INFO - Running post-run listener: copy_logs_to_upload_dir 10:47:38 INFO - Copying logs to upload dir... 10:47:38 INFO - mkdir: /builds/slave/test/build/upload/logs 10:47:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.632645 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-25 10:47:38.249857) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 10:47:38.252976) ========= 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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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/9fc1f2f2235ee21b2c2fd623f2e16a52ec4d61de283356c58b286b36dbada637355bd00df52502184e5f15f7ee7b88008dfd9a40f4437d646739d509ff19011c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4cd31a68dd29407f28389077fa01080b86cb78c8f43b4ce4e88a9a0466d9d9e6cfe6b3ff470bda6d59e30201e638cd7aec17707fd4101eec0d43c5b676c12d9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f19f88e1ada0627b4218ef9cc0a40a3d4b5272d8a90b81ceaaf045a8220d64606799a213458f1f14403bf7103dc78604e63f898457a109dc44c7da48b168a496", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/954923a52cb5adf05e1b908dc88b08cd1258243a1c7e99ef5ecb0b68b643f1423f04b58c8eae04e5d35a71fe2c0133f51f861d8cede33fac3192672ae9c4d1c6"} build_url:https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/KlIGQZy6RByOmf-6GVai7w/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/9fc1f2f2235ee21b2c2fd623f2e16a52ec4d61de283356c58b286b36dbada637355bd00df52502184e5f15f7ee7b88008dfd9a40f4437d646739d509ff19011c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4cd31a68dd29407f28389077fa01080b86cb78c8f43b4ce4e88a9a0466d9d9e6cfe6b3ff470bda6d59e30201e638cd7aec17707fd4101eec0d43c5b676c12d9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f19f88e1ada0627b4218ef9cc0a40a3d4b5272d8a90b81ceaaf045a8220d64606799a213458f1f14403bf7103dc78604e63f898457a109dc44c7da48b168a496", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/954923a52cb5adf05e1b908dc88b08cd1258243a1c7e99ef5ecb0b68b643f1423f04b58c8eae04e5d35a71fe2c0133f51f861d8cede33fac3192672ae9c4d1c6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 10:47:38.293144) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:47:38.293719) ========= 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.qFM8Y45LrC/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.fCRFWkaVfd/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005030 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 10:47:38.343931) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 10:47:38.344289) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 10:47:38.344620) ========= ========= Total master_lag: 1.74 =========