builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0024 starttime: 1497982179.04 results: success (0) buildid: 20170620094123 builduid: 095883cb1e3f495299ed958285b9cf86 revision: 3a361611ebd95b53ac6fdda873e3a2de028985aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:39.041101) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:39.041697) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:39.042205) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:39.092773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:39.093193) ========= 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.0PpDRKbIIT/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.BXtworqFj8/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-20 11:09:39-- 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% 14.9M=0.001s 2017-06-20 11:09:39 (14.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.475249 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:39.594338) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:39.594971) ========= 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.0PpDRKbIIT/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.BXtworqFj8/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.026748 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:39.644922) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-20 11:09:39.645665) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3a361611ebd95b53ac6fdda873e3a2de028985aa --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 3a361611ebd95b53ac6fdda873e3a2de028985aa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0PpDRKbIIT/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.BXtworqFj8/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-20 11:09:39,724 truncating revision to first 12 chars 2017-06-20 11:09:39,724 Setting DEBUG logging. 2017-06-20 11:09:39,724 attempt 1/10 2017-06-20 11:09:39,724 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3a361611ebd9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-20 11:09:40,507 unpacking tar archive at: mozilla-beta-3a361611ebd9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.047233 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-20 11:09:40.716298) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:40.716772) ========= script_repo_revision: 3a361611ebd95b53ac6fdda873e3a2de028985aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:40.717310) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:40.717625) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 11:09:40.736314) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 11:09:40.736846) ========= /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.0PpDRKbIIT/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.BXtworqFj8/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:09:40 INFO - MultiFileLogger online at 20170620 11:09:40 in /builds/slave/test 11:09:40 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 11:09:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:09:40 INFO - {'append_to_log': False, 11:09:40 INFO - 'base_work_dir': '/builds/slave/test', 11:09:40 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:09:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:09:40 INFO - 'branch': 'Mozilla-Beta', 11:09:40 INFO - 'buildbot_json_path': 'buildprops.json', 11:09:40 INFO - 'config_files': ('talos/mac_config.py',), 11:09:40 INFO - 'default_actions': ('clobber', 11:09:40 INFO - 'read-buildbot-config', 11:09:40 INFO - 'download-and-extract', 11:09:40 INFO - 'populate-webroot', 11:09:40 INFO - 'create-virtualenv', 11:09:40 INFO - 'install', 11:09:40 INFO - 'setup-mitmproxy', 11:09:40 INFO - 'run-tests'), 11:09:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:09:40 INFO - 'download_minidump_stackwalk': True, 11:09:40 INFO - 'download_symbols': 'ondemand', 11:09:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:09:40 INFO - 'tooltool.py': '/tools/tooltool.py', 11:09:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:09:40 INFO - '/tools/misc-python/virtualenv.py')}, 11:09:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:09:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:09:40 INFO - 'gecko_profile': False, 11:09:40 INFO - 'gecko_profile_interval': 0, 11:09:40 INFO - 'installer_path': 'installer.exe', 11:09:40 INFO - 'log_level': 'info', 11:09:40 INFO - 'log_name': 'talos', 11:09:40 INFO - 'log_to_console': True, 11:09:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:09:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:09:40 INFO - 'opt_config_files': (), 11:09:40 INFO - 'pip_index': False, 11:09:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:09:40 INFO - 'cmd': ('bash', 11:09:40 INFO - '-c', 11:09:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:09:40 INFO - 'enabled': True, 11:09:40 INFO - 'halt_on_failure': False, 11:09:40 INFO - 'name': 'check_screen_resolution'},), 11:09:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:09:40 INFO - 'cmd': ('bash', 11:09:40 INFO - '-c', 11:09:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:09:40 INFO - 'enabled': True, 11:09:40 INFO - 'halt_on_failure': False, 11:09:40 INFO - 'name': 'check_screen_resolution'},), 11:09:40 INFO - 'run_cmd_checks_enabled': True, 11:09:40 INFO - 'suite': 'dromaeojs-e10s', 11:09:40 INFO - 'system_bits': '32', 11:09:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:09:40 INFO - 'title': 't-yosemite-r7-0024', 11:09:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:09:40 INFO - 'use_talos_json': True, 11:09:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:09:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:09:40 INFO - 'work_dir': 'build'} 11:09:40 INFO - [mozharness: 2017-06-20 18:09:40.929148Z] Running clobber step. 11:09:40 INFO - Running pre-action listener: _resource_record_pre_action 11:09:40 INFO - Running main action method: clobber 11:09:40 INFO - rmtree: /builds/slave/test/build 11:09:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:09:43 INFO - Running post-action listener: _resource_record_post_action 11:09:43 INFO - [mozharness: 2017-06-20 18:09:43.458863Z] Finished clobber step (success) 11:09:43 INFO - [mozharness: 2017-06-20 18:09:43.458993Z] Running read-buildbot-config step. 11:09:43 INFO - Running pre-action listener: _resource_record_pre_action 11:09:43 INFO - Running main action method: read_buildbot_config 11:09:43 INFO - Using buildbot properties: 11:09:43 INFO - { 11:09:43 INFO - "project": "", 11:09:43 INFO - "product": "firefox", 11:09:43 INFO - "script_repo_revision": "production", 11:09:43 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 11:09:43 INFO - "repository": "", 11:09:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 11:09:43 INFO - "buildid": "20170620094123", 11:09:43 INFO - "pgo_build": "False", 11:09:43 INFO - "basedir": "/builds/slave/test", 11:09:43 INFO - "buildnumber": 3, 11:09:43 INFO - "slavename": "t-yosemite-r7-0024", 11:09:43 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 11:09:43 INFO - "platform": "yosemite_r7_devedition", 11:09:43 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 11:09:43 INFO - "branch": "mozilla-beta", 11:09:43 INFO - "repo_path": "releases/mozilla-beta", 11:09:43 INFO - "revision": "3a361611ebd95b53ac6fdda873e3a2de028985aa", 11:09:43 INFO - "stage_platform": "macosx64-devedition", 11:09:43 INFO - "builduid": "095883cb1e3f495299ed958285b9cf86", 11:09:43 INFO - "slavebuilddir": "test" 11:09:43 INFO - } 11:09:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 11:09:43 INFO - Running post-action listener: _resource_record_post_action 11:09:43 INFO - [mozharness: 2017-06-20 18:09:43.462140Z] Finished read-buildbot-config step (success) 11:09:43 INFO - [mozharness: 2017-06-20 18:09:43.462276Z] Running download-and-extract step. 11:09:43 INFO - Running pre-action listener: _resource_record_pre_action 11:09:43 INFO - Running main action method: download_and_extract 11:09:43 INFO - mkdir: /builds/slave/test/build/tests 11:09:43 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')]} 11:09:43 INFO - trying https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 11:09:43 INFO - Downloading https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 11:09:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 11:09:44 INFO - Downloaded 1290 bytes. 11:09:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 11:09:44 INFO - Using the following test package requirements: 11:09:44 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:09:44 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 11:09:44 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 11:09:44 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:09:44 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 11:09:44 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:09:44 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 11:09:44 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:09:44 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:09:44 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 11:09:44 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 11:09:44 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:09:44 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 11:09:44 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:09:44 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 11:09:44 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:09:44 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 11:09:44 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 11:09:44 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 11:09:44 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 11:09:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 11:09:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 11:09:44 INFO - Fetch https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 11:09:45 INFO - Content-Length response header: 59545599 11:09:45 INFO - Bytes received: 59545599 11:10:04 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 11:10:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 11:10:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 11:10:04 INFO - Fetch https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 11:10:06 INFO - Content-Length response header: 59545599 11:10:06 INFO - Bytes received: 59545599 11:10:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 11:10:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 11:10:23 INFO - Fetch https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 11:10:24 INFO - Content-Length response header: 14454470 11:10:24 INFO - Bytes received: 14454470 11:10:25 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')]} 11:10:25 INFO - trying https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.dmg 11:10:25 INFO - Downloading https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 11:10:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 11:10:26 INFO - Downloaded 66853376 bytes. 11:10:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.dmg 11:10:26 INFO - mkdir: /builds/slave/test/properties 11:10:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:10:26 INFO - Writing to file /builds/slave/test/properties/build_url 11:10:26 INFO - Contents: 11:10:27 INFO - build_url:https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.dmg 11:10:27 INFO - Running post-action listener: _resource_record_post_action 11:10:27 INFO - Running post-action listener: set_extra_try_arguments 11:10:27 INFO - [mozharness: 2017-06-20 18:10:27.631623Z] Finished download-and-extract step (success) 11:10:27 INFO - [mozharness: 2017-06-20 18:10:27.631739Z] Running populate-webroot step. 11:10:27 INFO - Running pre-action listener: _resource_record_pre_action 11:10:27 INFO - Running main action method: populate_webroot 11:10:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:10:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:10:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:10:27 INFO - '--tppagecycles', 11:10:27 INFO - '7'], 11:10:27 INFO - 'tests': ['tsvgm']}}, 11:10:27 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:10:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:10:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:10:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:10:27 INFO - 'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:10:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:10:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:10:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:10:27 INFO - 'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['damp', 'tps']}, 11:10:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:10:27 INFO - 'tests': ['damp', 'tps']}, 11:10:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['dromaeo_dom']}, 11:10:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:10:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:10:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:10:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:10:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:10:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other-e10s': {'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['a11yr', 11:10:27 INFO - 'ts_paint', 11:10:27 INFO - 'tpaint', 11:10:27 INFO - 'sessionrestore', 11:10:27 INFO - 'sessionrestore_no_auto_restore', 11:10:27 INFO - 'tabpaint']}, 11:10:27 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 11:10:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 11:10:27 INFO - 'bloom_basic_ref']}, 11:10:27 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:10:27 INFO - 'talos_options': ['--mitmproxy', 11:10:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:10:27 INFO - '--firstNonBlankPaint'], 11:10:27 INFO - 'tests': ['quantum_pageload_google', 11:10:27 INFO - 'quantum_pageload_youtube', 11:10:27 INFO - 'quantum_pageload_amazon', 11:10:27 INFO - 'quantum_pageload_facebook']}, 11:10:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['tsvgx', 11:10:27 INFO - 'tsvgr_opacity', 11:10:27 INFO - 'tart', 11:10:27 INFO - 'tscrollx', 11:10:27 INFO - 'cart', 11:10:27 INFO - 'tsvg_static']}, 11:10:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:10:27 INFO - 'tsvgr_opacity', 11:10:27 INFO - 'tart', 11:10:27 INFO - 'tscrollx', 11:10:27 INFO - 'cart', 11:10:27 INFO - 'tsvg_static']}, 11:10:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:10:27 INFO - 'talos_options': ['--disable-e10s'], 11:10:27 INFO - 'tests': ['tp5o']}, 11:10:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:10:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:10:27 INFO - 'talos_options': ['--disable-e10s', 11:10:27 INFO - '--xperf_path', 11:10:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:10:27 INFO - 'tests': ['tp5n']}, 11:10:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:10:27 INFO - 'talos_options': ['--xperf_path', 11:10:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:10:27 INFO - 'tests': ['tp5n']}}, 11:10:27 INFO - 'talos.zip': {'path': '', 11:10:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:10:27 INFO - Running post-action listener: _resource_record_post_action 11:10:27 INFO - [mozharness: 2017-06-20 18:10:27.652636Z] Finished populate-webroot step (success) 11:10:27 INFO - [mozharness: 2017-06-20 18:10:27.652729Z] Running create-virtualenv step. 11:10:27 INFO - Running pre-action listener: _resource_record_pre_action 11:10:27 INFO - Running main action method: create_virtualenv 11:10:27 INFO - Creating virtualenv /builds/slave/test/build/venv 11:10:27 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 11:10:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:10:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:10:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:10:27 INFO - Using real prefix '/tools/python27' 11:10:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:10:28 INFO - Installing distribute.............................................................................................................................................................................................done. 11:10:31 INFO - Installing pip.................done. 11:10:31 INFO - Return code: 0 11:10:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:10:31 INFO - Reading from file tmpfile_stdout 11:10:31 INFO - Output received: 11:10:31 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) 11:10:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:10:31 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')]} 11:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:31 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')]} 11:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:31 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 11:10:31 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 11:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:31 INFO - 'HOME': '/Users/cltbld', 11:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:31 INFO - 'LOGNAME': 'cltbld', 11:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:31 INFO - 'MOZ_NO_REMOTE': '1', 11:10:31 INFO - 'NO_EM_RESTART': '1', 11:10:31 INFO - 'PAGER': '/bin/cat', 11:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:31 INFO - 'PWD': '/builds/slave/test', 11:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:31 INFO - 'SHELL': '/bin/bash', 11:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:31 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:31 INFO - 'USER': 'cltbld', 11:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:31 INFO - 'XPC_FLAGS': '0x0', 11:10:31 INFO - 'XPC_SERVICE_NAME': '0', 11:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:31 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 11:10:31 INFO - Cleaning up... 11:10:31 INFO - Return code: 0 11:10:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:10:31 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')]} 11:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:31 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')]} 11:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:31 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 11:10:31 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 11:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:31 INFO - 'HOME': '/Users/cltbld', 11:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:31 INFO - 'LOGNAME': 'cltbld', 11:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:31 INFO - 'MOZ_NO_REMOTE': '1', 11:10:31 INFO - 'NO_EM_RESTART': '1', 11:10:31 INFO - 'PAGER': '/bin/cat', 11:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:31 INFO - 'PWD': '/builds/slave/test', 11:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:31 INFO - 'SHELL': '/bin/bash', 11:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:31 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:31 INFO - 'USER': 'cltbld', 11:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:31 INFO - 'XPC_FLAGS': '0x0', 11:10:31 INFO - 'XPC_SERVICE_NAME': '0', 11:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:32 INFO - Downloading/unpacking psutil>=3.1.1 11:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:10:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:10:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:10:34 INFO - Installing collected packages: psutil 11:10:34 INFO - Running setup.py install for psutil 11:10:34 INFO - building 'psutil._psutil_osx' extension 11:10:34 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 11:10:35 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 11:10:35 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 11:10:35 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 11:10:35 INFO - building 'psutil._psutil_posix' extension 11:10:35 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 11:10:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:10:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:10:35 INFO - ^ 11:10:35 INFO - 1 warning generated. 11:10:35 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 11:10:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:10:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:10:35 INFO - Successfully installed psutil 11:10:35 INFO - Cleaning up... 11:10:35 INFO - Return code: 0 11:10:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:10:35 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')]} 11:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:35 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')]} 11:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:35 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 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:35 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 11:10:35 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 11:10:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:35 INFO - 'HOME': '/Users/cltbld', 11:10:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:35 INFO - 'LOGNAME': 'cltbld', 11:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:35 INFO - 'MOZ_NO_REMOTE': '1', 11:10:35 INFO - 'NO_EM_RESTART': '1', 11:10:35 INFO - 'PAGER': '/bin/cat', 11:10:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:35 INFO - 'PWD': '/builds/slave/test', 11:10:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:35 INFO - 'SHELL': '/bin/bash', 11:10:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:35 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:35 INFO - 'USER': 'cltbld', 11:10:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:35 INFO - 'XPC_FLAGS': '0x0', 11:10:35 INFO - 'XPC_SERVICE_NAME': '0', 11:10:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:10:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:10:37 INFO - Installing collected packages: mozsystemmonitor 11:10:37 INFO - Running setup.py install for mozsystemmonitor 11:10:37 INFO - Successfully installed mozsystemmonitor 11:10:37 INFO - Cleaning up... 11:10:37 INFO - Return code: 0 11:10:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:10:37 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')]} 11:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:37 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')]} 11:10:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:37 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 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:37 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 11:10:37 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 11:10:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:37 INFO - 'HOME': '/Users/cltbld', 11:10:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:37 INFO - 'LOGNAME': 'cltbld', 11:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:37 INFO - 'MOZ_NO_REMOTE': '1', 11:10:37 INFO - 'NO_EM_RESTART': '1', 11:10:37 INFO - 'PAGER': '/bin/cat', 11:10:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:37 INFO - 'PWD': '/builds/slave/test', 11:10:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:37 INFO - 'SHELL': '/bin/bash', 11:10:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:37 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:37 INFO - 'USER': 'cltbld', 11:10:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:37 INFO - 'XPC_FLAGS': '0x0', 11:10:37 INFO - 'XPC_SERVICE_NAME': '0', 11:10:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:37 INFO - Downloading/unpacking jsonschema==2.5.1 11:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:10:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:10:39 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 11:10:39 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 11:10:39 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 11:10:39 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 11:10:39 INFO - Downloading functools32-3.2.3-2.tar.gz 11:10:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:10:39 INFO - warning: no files found matching '*.txt' 11:10:39 INFO - no previously-included directories found matching 'build' 11:10:39 INFO - no previously-included directories found matching 'dist' 11:10:39 INFO - no previously-included directories found matching '.git*' 11:10:39 INFO - Installing collected packages: jsonschema, functools32 11:10:39 INFO - Running setup.py install for functools32 11:10:39 INFO - warning: no files found matching '*.txt' 11:10:39 INFO - no previously-included directories found matching 'build' 11:10:39 INFO - no previously-included directories found matching 'dist' 11:10:39 INFO - no previously-included directories found matching '.git*' 11:10:39 INFO - Successfully installed jsonschema functools32 11:10:39 INFO - Cleaning up... 11:10:39 INFO - Return code: 0 11:10:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:10:39 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')]} 11:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:39 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')]} 11:10:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:39 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 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:39 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 11:10:39 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 11:10:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:39 INFO - 'HOME': '/Users/cltbld', 11:10:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:39 INFO - 'LOGNAME': 'cltbld', 11:10:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:39 INFO - 'MOZ_NO_REMOTE': '1', 11:10:39 INFO - 'NO_EM_RESTART': '1', 11:10:39 INFO - 'PAGER': '/bin/cat', 11:10:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:39 INFO - 'PWD': '/builds/slave/test', 11:10:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:39 INFO - 'SHELL': '/bin/bash', 11:10:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:39 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:39 INFO - 'USER': 'cltbld', 11:10:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:39 INFO - 'XPC_FLAGS': '0x0', 11:10:39 INFO - 'XPC_SERVICE_NAME': '0', 11:10:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:10:40 INFO - Cleaning up... 11:10:40 INFO - Return code: 0 11:10:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:10:40 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')]} 11:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:40 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')]} 11:10:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:40 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 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:40 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 11:10:40 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 11:10:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:40 INFO - 'HOME': '/Users/cltbld', 11:10:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:40 INFO - 'LOGNAME': 'cltbld', 11:10:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:40 INFO - 'MOZ_NO_REMOTE': '1', 11:10:40 INFO - 'NO_EM_RESTART': '1', 11:10:40 INFO - 'PAGER': '/bin/cat', 11:10:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:40 INFO - 'PWD': '/builds/slave/test', 11:10:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:40 INFO - 'SHELL': '/bin/bash', 11:10:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:40 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:40 INFO - 'USER': 'cltbld', 11:10:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:40 INFO - 'XPC_FLAGS': '0x0', 11:10:40 INFO - 'XPC_SERVICE_NAME': '0', 11:10:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:40 INFO - Downloading/unpacking blobuploader==1.2.4 11:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:41 INFO - Downloading blobuploader-1.2.4.tar.gz 11:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:10:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:10:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:10:42 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 11:10:42 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 11:10:42 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 11:10:42 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 11:10:42 INFO - Downloading docopt-0.6.1.tar.gz 11:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:10:42 INFO - Installing collected packages: blobuploader, requests, docopt 11:10:42 INFO - Running setup.py install for blobuploader 11:10:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:10:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:10:42 INFO - Running setup.py install for requests 11:10:43 INFO - Running setup.py install for docopt 11:10:43 INFO - Successfully installed blobuploader requests docopt 11:10:43 INFO - Cleaning up... 11:10:43 INFO - Return code: 0 11:10:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:10:43 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')]} 11:10:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:43 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')]} 11:10:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:43 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 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:43 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 11:10:43 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 11:10:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:43 INFO - 'HOME': '/Users/cltbld', 11:10:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:43 INFO - 'LOGNAME': 'cltbld', 11:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:43 INFO - 'MOZ_NO_REMOTE': '1', 11:10:43 INFO - 'NO_EM_RESTART': '1', 11:10:43 INFO - 'PAGER': '/bin/cat', 11:10:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:43 INFO - 'PWD': '/builds/slave/test', 11:10:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:43 INFO - 'SHELL': '/bin/bash', 11:10:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:43 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:43 INFO - 'USER': 'cltbld', 11:10:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:43 INFO - 'XPC_FLAGS': '0x0', 11:10:43 INFO - 'XPC_SERVICE_NAME': '0', 11:10:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:10:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-lIeRmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:10:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-5UcpF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:10:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:10:43 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-CR9cih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-k6QO3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-2X3fgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-zANJ1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-prRbmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-xAzxXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-f1V9AY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-SmKgyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-vMqCHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-uODVfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:10:44 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-qYQLzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:10:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-xKtXLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-BETOO1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:10:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-TBbOCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:10:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-3mjwAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:10:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:10:45 INFO - Running setup.py install for manifestparser 11:10:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:10:45 INFO - Running setup.py install for mozcrash 11:10:45 INFO - Running setup.py install for mozdebug 11:10:45 INFO - Running setup.py install for mozdevice 11:10:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:10:46 INFO - Running setup.py install for mozfile 11:10:46 INFO - Running setup.py install for mozhttpd 11:10:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:10:46 INFO - Running setup.py install for mozinfo 11:10:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:10:46 INFO - Running setup.py install for mozInstall 11:10:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:10:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:10:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:10:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:10:46 INFO - Running setup.py install for mozleak 11:10:46 INFO - Running setup.py install for mozlog 11:10:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:10:46 INFO - Running setup.py install for moznetwork 11:10:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:10:47 INFO - Running setup.py install for mozprocess 11:10:47 INFO - Running setup.py install for mozprofile 11:10:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:10:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:10:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:10:47 INFO - Running setup.py install for mozrunner 11:10:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:10:47 INFO - Running setup.py install for mozscreenshot 11:10:47 INFO - Running setup.py install for moztest 11:10:47 INFO - Running setup.py install for mozversion 11:10:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:10:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:10:48 INFO - Cleaning up... 11:10:48 INFO - Return code: 0 11:10:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:10:48 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')]} 11:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:48 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')]} 11:10:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:48 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 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:48 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 11:10:48 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 11:10:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:48 INFO - 'HOME': '/Users/cltbld', 11:10:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:48 INFO - 'LOGNAME': 'cltbld', 11:10:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:48 INFO - 'MOZ_NO_REMOTE': '1', 11:10:48 INFO - 'NO_EM_RESTART': '1', 11:10:48 INFO - 'PAGER': '/bin/cat', 11:10:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:48 INFO - 'PWD': '/builds/slave/test', 11:10:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:48 INFO - 'SHELL': '/bin/bash', 11:10:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:48 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:48 INFO - 'USER': 'cltbld', 11:10:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:48 INFO - 'XPC_FLAGS': '0x0', 11:10:48 INFO - 'XPC_SERVICE_NAME': '0', 11:10:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:10:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-MO8lrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:10:48 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)) 11:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:10:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-8q_x18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:10:48 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)) 11:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:10:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Gy6qAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:10:48 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)) 11:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:10:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-6SPxEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:10:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 11:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:10:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-wIhfyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:10:48 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)) 11:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:10:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-pDaOew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:10:48 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)) 11:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:10:48 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-emmRmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 11:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:10:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-l78Jpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 11:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:10:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-BJe8Lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:10:49 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)) 11:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:10:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-BNlKAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:10:49 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)) 11:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:10:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-4sF0bR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:10:49 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)) 11:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:10:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-EvNo7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:10:49 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)) 11:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:10:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-JbTT7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:10:49 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)) 11:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:10:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Ht465y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:10:49 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)) 11:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Cp9EBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:10:49 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)) 11:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:10:49 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-DTbWRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:10:50 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)) 11:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:10:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-OiX4dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:10:50 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)) 11:10:50 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)) 11:10:50 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)) 11:10:50 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)) 11:10:50 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:10:50 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:10:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:10:50 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 11:10:50 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 11:10:50 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 11:10:50 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 11:10:51 INFO - Downloading blessings-1.6.tar.gz 11:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:10:51 INFO - Installing collected packages: blessings 11:10:51 INFO - Running setup.py install for blessings 11:10:51 INFO - Successfully installed blessings 11:10:51 INFO - Cleaning up... 11:10:51 INFO - Return code: 0 11:10:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:10:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:10:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:10:52 INFO - Reading from file tmpfile_stdout 11:10:52 INFO - Current package versions: 11:10:52 INFO - blessings == 1.6 11:10:52 INFO - blobuploader == 1.2.4 11:10:52 INFO - docopt == 0.6.1 11:10:52 INFO - functools32 == 3.2.3-2 11:10:52 INFO - jsonschema == 2.5.1 11:10:52 INFO - manifestparser == 1.1 11:10:52 INFO - mozInstall == 1.13 11:10:52 INFO - mozcrash == 1.0 11:10:52 INFO - mozdebug == 0.1 11:10:52 INFO - mozdevice == 0.51 11:10:52 INFO - mozfile == 1.2 11:10:52 INFO - mozhttpd == 0.7 11:10:52 INFO - mozinfo == 0.10 11:10:52 INFO - mozleak == 0.1 11:10:52 INFO - mozlog == 3.4 11:10:52 INFO - moznetwork == 0.27 11:10:52 INFO - mozprocess == 0.25 11:10:52 INFO - mozprofile == 0.28 11:10:52 INFO - mozrunner == 6.13 11:10:52 INFO - mozscreenshot == 0.1 11:10:52 INFO - mozsystemmonitor == 0.3 11:10:52 INFO - moztest == 0.8 11:10:52 INFO - mozversion == 1.4 11:10:52 INFO - psutil == 3.1.1 11:10:52 INFO - requests == 1.2.3 11:10:52 INFO - wsgiref == 0.1.2 11:10:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:10:52 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')]} 11:10:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:52 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')]} 11:10:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:52 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 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:52 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 11:10:52 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 11:10:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:52 INFO - 'HOME': '/Users/cltbld', 11:10:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:52 INFO - 'LOGNAME': 'cltbld', 11:10:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:52 INFO - 'MOZ_NO_REMOTE': '1', 11:10:52 INFO - 'NO_EM_RESTART': '1', 11:10:52 INFO - 'PAGER': '/bin/cat', 11:10:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:52 INFO - 'PWD': '/builds/slave/test', 11:10:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:52 INFO - 'SHELL': '/bin/bash', 11:10:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:52 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:52 INFO - 'USER': 'cltbld', 11:10:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:52 INFO - 'XPC_FLAGS': '0x0', 11:10:52 INFO - 'XPC_SERVICE_NAME': '0', 11:10:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:52 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)) 11:10:52 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)) 11:10:52 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)) 11:10:52 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)) 11:10:52 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)) 11:10:52 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)) 11:10:52 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)) 11:10:52 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)) 11:10:52 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)) 11:10:52 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:10:52 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 11:10:52 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 11:10:52 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 11:10:52 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 11:10:53 INFO - Downloading marionette_driver-1.4.0.tar.gz 11:10:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 11:10:53 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)) 11:10:53 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)) 11:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:10:53 INFO - Installing collected packages: marionette-driver 11:10:53 INFO - Running setup.py install for marionette-driver 11:10:54 INFO - Successfully installed marionette-driver 11:10:54 INFO - Cleaning up... 11:10:54 INFO - Return code: 0 11:10:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:10:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104f90c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f94768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a3cbb070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, '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.0PpDRKbIIT/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.BXtworqFj8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 11:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:54 INFO - 'HOME': '/Users/cltbld', 11:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:54 INFO - 'LOGNAME': 'cltbld', 11:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:54 INFO - 'MOZ_NO_REMOTE': '1', 11:10:54 INFO - 'NO_EM_RESTART': '1', 11:10:54 INFO - 'PAGER': '/bin/cat', 11:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:54 INFO - 'PWD': '/builds/slave/test', 11:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:54 INFO - 'SHELL': '/bin/bash', 11:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:10:54 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:54 INFO - 'USER': 'cltbld', 11:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:54 INFO - 'XPC_FLAGS': '0x0', 11:10:54 INFO - 'XPC_SERVICE_NAME': '0', 11:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:10:54 INFO - Cleaning up... 11:10:54 INFO - Return code: 0 11:10:54 INFO - Running post-action listener: _resource_record_post_action 11:10:54 INFO - Running post-action listener: _start_resource_monitoring 11:10:54 INFO - Starting resource monitoring. 11:10:54 INFO - [mozharness: 2017-06-20 18:10:54.434142Z] Finished create-virtualenv step (success) 11:10:54 INFO - [mozharness: 2017-06-20 18:10:54.435109Z] Running install step. 11:10:54 INFO - Running pre-action listener: _resource_record_pre_action 11:10:54 INFO - Running main action method: install 11:10:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:10:54 INFO - Reading from file tmpfile_stdout 11:10:54 INFO - Detecting whether we're running mozinstall >=1.0... 11:10:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:10:54 INFO - Reading from file tmpfile_stdout 11:10:54 INFO - Output received: 11:10:54 INFO - Usage: mozinstall [options] installer 11:10:54 INFO - Options: 11:10:54 INFO - -h, --help show this help message and exit 11:10:54 INFO - -d DEST, --destination=DEST 11:10:54 INFO - Directory to install application into. [default: 11:10:54 INFO - "/builds/slave/test"] 11:10:54 INFO - --app=APP Application being installed. [default: firefox] 11:10:54 INFO - mkdir: /builds/slave/test/build/application 11:10:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 11:11:16 INFO - Reading from file tmpfile_stdout 11:11:16 INFO - Output received: 11:11:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:11:16 INFO - Running post-action listener: _resource_record_post_action 11:11:16 INFO - [mozharness: 2017-06-20 18:11:16.364864Z] Finished install step (success) 11:11:16 INFO - [mozharness: 2017-06-20 18:11:16.365029Z] Running setup-mitmproxy step. 11:11:16 INFO - Running pre-action listener: _resource_record_pre_action 11:11:16 INFO - Running main action method: setup_mitmproxy 11:11:16 INFO - Skipping: mitmproxy is not required 11:11:16 INFO - Running post-action listener: _resource_record_post_action 11:11:16 INFO - [mozharness: 2017-06-20 18:11:16.365704Z] Finished setup-mitmproxy step (success) 11:11:16 INFO - [mozharness: 2017-06-20 18:11:16.365846Z] Running run-tests step. 11:11:16 INFO - Running pre-action listener: _resource_record_pre_action 11:11:16 INFO - Running main action method: run_tests 11:11:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:11:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:11:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:11:16 INFO - 2017-06-20 11:11:16.380 screenresolution[1644:10080] starting screenresolution argv=screenresolution get 11:11:16 INFO - 2017-06-20 11:11:16.383 screenresolution[1644:10080] Display 0: 1600x1200x32@60 11:11:16 INFO - 2017-06-20 11:11:16.389 screenresolution[1645:10082] starting screenresolution argv=screenresolution list 11:11:16 INFO - Available Modes on Display 0 11:11:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:11:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:11:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:11:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:11:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:11:16 INFO - Intel Iris: 11:11:16 INFO - Chipset Model: Intel Iris 11:11:16 INFO - Type: GPU 11:11:16 INFO - Bus: Built-In 11:11:16 INFO - VRAM (Dynamic, Max): 1536 MB 11:11:16 INFO - Vendor: Intel (0x8086) 11:11:16 INFO - Device ID: 0x0a2e 11:11:16 INFO - Revision ID: 0x0009 11:11:16 INFO - Displays: 11:11:16 INFO - Display: 11:11:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:11:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:11:16 INFO - Display Serial Number: 1600x1200 60 11:11:16 INFO - Main Display: Yes 11:11:16 INFO - Mirror: Off 11:11:16 INFO - Online: Yes 11:11:16 INFO - Rotation: Supported 11:11:16 INFO - Return code: 0 11:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:11:16 INFO - Python 2.7.3 11:11:16 INFO - Return code: 0 11:11:16 INFO - grabbing minidump binary from tooltool 11:11: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')]} 11:11:16 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 0x104ce6d30>, '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 0x104cb0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cd7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:11:16 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 11:11:16 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 11:11:16 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 11:11:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:11:16 INFO - Return code: 0 11:11:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:11:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:11:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:11:16 INFO - ENV: RUST_BACKTRACE is now 1 11:11:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:11:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:11:16 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/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 11:11:16 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/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --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 11:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PpDRKbIIT/Render', 11:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:11:16 INFO - 'HOME': '/Users/cltbld', 11:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:11:16 INFO - 'LOGNAME': 'cltbld', 11:11:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:11:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:11:16 INFO - 'MOZ_NO_REMOTE': '1', 11:11:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:11:16 INFO - 'NO_EM_RESTART': '1', 11:11:16 INFO - 'PAGER': '/bin/cat', 11:11:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:11:16 INFO - 'PWD': '/builds/slave/test', 11:11:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:11:16 INFO - 'RUST_BACKTRACE': '1', 11:11:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:11:16 INFO - 'SHELL': '/bin/bash', 11:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BXtworqFj8/Listeners', 11:11:16 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 11:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:11:16 INFO - 'USER': 'cltbld', 11:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:11:16 INFO - 'XPC_FLAGS': '0x0', 11:11:16 INFO - 'XPC_SERVICE_NAME': '0', 11:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:11:16 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/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 11:11:17 INFO - mozversion application_buildid: 20170620094123 11:11:17 INFO - mozversion application_changeset: 3a361611ebd95b53ac6fdda873e3a2de028985aa 11:11:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:11:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:11:17 INFO - mozversion application_name: Firefox 11:11:17 INFO - mozversion application_remotingname: firefox-dev 11:11:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:11:17 INFO - mozversion application_vendor: Mozilla 11:11:17 INFO - mozversion application_version: 55.0 11:11:17 INFO - mozversion platform_buildid: 20170620094123 11:11:17 INFO - mozversion platform_changeset: 3a361611ebd95b53ac6fdda873e3a2de028985aa 11:11:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:11:17 INFO - mozversion platform_version: 55.0 11:11:17 INFO - using testdate: 1497982277 11:11:17 INFO - actual date: 1497982277 11:11:17 INFO - starting webserver on 'localhost:49233' 11:11:17 INFO - SUITE-START | Running 2 tests 11:11:17 INFO - TEST-START | dromaeo_css 11:11:17 INFO - operating with platform_type : mac_ 11:11:17 INFO - Initialising browser for dromaeo_css test... 11:11:17 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpMhP39t/profile 11:11:17 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 11:11:50 INFO - TEST-INFO | 1655: killed by SIGTERM 11:11:50 INFO - Browser initialized. 11:11:50 INFO - Running cycle 1/1 for dromaeo_css test... 11:11:50 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpMhP39t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:11:52 INFO - PID 1664 | 2017-06-20 11:11:52.348 plugin-container[1665:10621] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953b, name = 'com.apple.tsm.portname' 11:11:52 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:11:52 INFO - PID 1664 | 2017-06-20 11:11:52.349 plugin-container[1665:10621] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 11:11:52 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:11:52 INFO - PID 1664 | 2017-06-20 11:11:52.349 plugin-container[1665:10621] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:11:53 INFO - PID 1664 | 2017-06-20 11:11:53.065 plugin-container[1666:10665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 11:11:53 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:11:53 INFO - PID 1664 | 2017-06-20 11:11:53.066 plugin-container[1666:10665] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 11:11:53 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:11:53 INFO - PID 1664 | 2017-06-20 11:11:53.066 plugin-container[1666:10665] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:12:02 INFO - PID 1664 | RSS: Main: 214843392 11:12:02 INFO - PID 1664 | 11:15:07 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49233/tests/dromaeo/cssquery-ext.html) 11:15:10 INFO - PID 1664 | RSS: Main: 239017984 11:15:10 INFO - PID 1664 | 11:18:12 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-ext.html (next: http://localhost:49233/tests/dromaeo/cssquery-jquery.html) 11:18:12 INFO - PID 1664 | RSS: Main: 246247424 11:18:12 INFO - PID 1664 | 11:21:14 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49233/tests/dromaeo/cssquery-mootools.html) 11:21:15 INFO - PID 1664 | RSS: Main: 249966592 11:21:15 INFO - PID 1664 | 11:24:16 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49233/tests/dromaeo/cssquery-prototype.html) 11:24:17 INFO - PID 1664 | RSS: Main: 252755968 11:24:17 INFO - PID 1664 | 11:27:20 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49233/tests/dromaeo/cssquery-yui.html) 11:27:21 INFO - PID 1664 | RSS: Main: 252362752 11:27:21 INFO - PID 1664 | 11:30:22 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49233/tests/dromaeo/cssquery-yui.html (next: http://localhost:49233/tests/dromaeo/cssquery-dojo.html) 11:30:22 INFO - PID 1664 | RSS: Main: 250441728 11:30:22 INFO - PID 1664 | 11:30:22 INFO - PID 1664 | __start_tp_report 11:30:22 INFO - PID 1664 | _x_x_mozilla_page_load 11:30:22 INFO - PID 1664 | _x_x_mozilla_page_load_details 11:30:22 INFO - PID 1664 | |i|pagename|runs| 11:30:22 INFO - PID 1664 | |0;dojo.html;3573.4461158520407;3630.473581330707;3631.8728844490433;3646.453032045193;3694.7044236461084;6294.08735733319;6301.779437719681;6301.968490157555;6310.4636105930995;6315.1790267265305;6404.647744374058;6412.230532336126;6414.102025716392;6422.807315780522;6426.293107758154;5524.033294173523;5568.55451563876;5577.609567330289;5708.08670612702;5722.084466485363;5615.073512870392;5624;5700.6579605223615;5719.941810765022;5730.770769169249;4193.580641935812;5512.862178445531;5528.640638358494;5624.718764061821;5653.943460565383;5581.134924086732;5604;5618.15727640853;5646.604737668365;5654.688992105433;5480.342358916955;5492.47821456961;5560.7219639017885;5603.775848966036;5609.691466969315;6387.329330420285;6395.52033597482;6398.744050237985;6412.93587064128;6425.453836423862;6671.766488172939;6693.665316734144;6918.204406493267;6952.617606031667;6990.580565166056;6870.038194652752;6916.930830691729;6923.7576684816295;6984.965075174642;6989.5456795308655;5435.809746658927;6491.156149700509;6527;6537.607743535308;6542.116814230113;6965.582065076112;7034.6482675866;7252.456065795086;7259.201487836333;7262.346388825031;6702.162229721285;6715.7985260442265;6721.294264102296;6722.0253063305545;6748.831279218059;6446.645434501053;6461.612303261725;6919.861602767916;7000.649967501605;7007.684654190573;6938.583684978817;6948.583085014814;6959.652017399211;6975.616341101187;6984;6747.865042699119;6811.352921472452;6968.477364197604;6970.616616086164;6988.615626140611;5439.83680489578;5454.53636440899;5469.8085567005755;5487.286652735199;5519.889602207933;3860.652541271299;4877.244027175793;4966.826161084345;5001.399832020181;5024.171011783088;6336.58812177207;6476.5466417350335;6537.477001839841;6543.247526534509;6565.835854103686;7070.222275549685;7072.71709131629;7072.8939065914055;7092.113485814273;7093.467989900778;6184.783532576428;6465.3857883501;6501.2198536175965;6513.023046543057;6523.804285871432;5609.663420194802;6032.336442991267;6050.062240352752;6065.423784740355;6172.444479996821;7024.4029257513685;7032.824179395556;7052.647367631701;7101.147862256562;7182.497225194186;5864.765409383747;7056.788296351117;7063.328983746535;7075.82310442243;7202.207757146605;7192.748253811092;7243.20324764286;7245.927540724525;7251.56490610565;7277.345038946521;6941.236463989058;6941.34057264559;6970.477214209056;6972.511924165463;6976.127984002;6710.463162946855;7081.362677358857;7084.114485689289;7111.644417779194;7114.50198486101;6214.974529202631;6630.60216386999;6648.90026649591;6698.799036028927;6780.38976492118;6317.905231421624;6694.799156025327;6753.257141714505;6793.796186114425;6813.080234168324;6454.644994525346;6531.542792004514;6663.36698013688;6673.099131617169;6705.731770729116;6629.502787291069;6644.734210631714;6656.201255849329;6664.8000559983275;6691;5477.808276710296;6439.678016099083;6633.668316584055;6641.402273795382;6667.233268174028 11:30:22 INFO - PID 1664 | |1;ext.html;751.9887201691973;855.5337341149075;1097.4403054442232;1283.326253716798;1290.9741805163892;30709.69290307096;34383.828080859625;34584;35861.10347241317;38553;39269.41095883564;39557.615483458016;39577.61478348258;39673.60326396735;39776.403353949645;7826.9608651956805;7970.641321140533;7972.083210430787;7982.28159465648;7992.160823113577;7881.487703299267;7938.087119981218;7953.647879860438;8142.796430089265;8416.074231834493;8379.790505237388;8424.157584241557;8483.74548763538;8492.108328625498;8500.064992850812;8338.916610833874;8377.162283771635;8441.746747597583;8443.746687599381;8450.915490845106;8250.504969701777;8313.002439707214;8317.459365141247;8327.084020757711;8339.8332033359;36630.63369366299;36644.81677591622;36881.262374752354;36948.630513694785;36984.26031479382;3068.8925887593828;3122.5472306515653;3152.0543836848856;3161.4151381994407;3173.9206519836957;30143;30544.32006239656;30564.54153187704;30632.69367306343;30673.38653226923;2998.100569829064;3068.186930463429;3098.984505077483;3102.550130231104;3114.6418161911442;290636;293929;294109;294674;294936;36518.45222321667;40036.19837107337;48126.55620331396;48352.791180220775;48467.51532484701;38303;38359.42460863089;49559.0088198234;49963.25055124176;50204.4938651841;15272.923635381752;15303.387864485296;15422.305996230196;15432.459863904935;15462.14958177311;12469.625911222678;12581.748365032649;12604.55884044054;12645.241285522898;12656.050796190322;13396.129251598615;13565.660858478419;13676.974226932822;13697.589072327846;13700.177989320673;6078.756849725962;6096.542759293071;6130.816075517741;6145.631262124287;6151.507879369639;973.6981535723949;983.6213057972732;998.2498217411131;1026.6817286641165;1058.9735256618492;22578.435539111655;22729.431764206027;22835.423042748676;23092.191773287854;24196.87901560481;931.6459745296744;1007.4912169354429;1102.1788767368203;1163.3717792392183;1171.0690001448868;9143.3599648024;9462.952685236529;11672.124590655734;12133.090018248668;12255.387230638433;310687;320003;320306;320952;322716;46974.29538556925;54212;54569.90860182854;54585.45414545883;54804.17793733097;1421.969072422506;1546.9767953480707;1582.1377349344634;1588.7140314743226;1589.4675283779832;18994.430167095008;22820.543589127792;23663.935122919167;23841.88079059663;25033.499330013663;37230;37546.06134846542;37560.2487950234;37697.62302376941;37836.05409864776;257527;279274;285099;285355;286248;28191;28788;28815;34224.65775342215;34321.656783432845;36109.45835812517;39207.80396098001;41792.58207417887;42097.78951105225;43914.341284880116;28484.572731408633;28903.277418064717;29417.264568385963;29515.40969180647;30865.228369290948;29498.705012950453;29554.67003984869;30922.845385772926;30968.761249549767;31070.067897963097;33801.15259818839;36200.637993619726;36576.45135322919;36605.81697091497;36667.81666091653;28436.715632843407;28548.85725571359;29052.854735726185;29133;29869.551956720232;21852.781472185074;22163.335099947028;24015.75984240205;24067.518649627258;24134 11:30:23 INFO - PID 1664 | |2;jquery.html;427.4165763732505;499.48303505871417;508.82954210339534;524.4314955186292;536.4662161149656;22082.44793880154;22246.443838904037;22938.967746451388;22965.19621813237;23047.962841672124;24699.50600987979;24986.00055997758;25175.62236566453;25383.365415864613;25403.364915877068;6647;6802.727890884359;6814.522983391164;6816.38652521273;6824.931750682479;6687.832804179886;6691.73233070679;6694.163229596301;6748.966255168742;6764.0530325754435;6082.60463069899;6610.933890661104;6632.137822083147;6643.701033453482;6646;6610.239822420435;6648.634325112117;6664.533482656216;6667.399934005938;6677.2988836172235;6114.785982490592;6141.815745527641;6142.416470435302;6167.136600875881;6185.072239164117;21266.936653167435;28689.856550717323;30355.696443035507;30412.695873041208;30562;2003.6193123306525;2287.6225422805383;2329.848559843622;2332.265336419023;2339.0526836631193;19693.704594431096;19894.403167904988;20320.08559614821;20339;20354.592908141753;2217.1463986365206;2244.124791331382;2292.1289909834154;2292.8624282542933;2293.472501324688;162767.1861640684;178686;186106.06946965313;187733.0613346938;215203.9239803791;12387.938060309642;14694.63263418423;15378.461753838563;15566.533004009898;16554;20499.4875128123;20700;20803.95980200984;20831.895840521003;20858.582828343348;8219.671213151527;8704.782380440412;10084;10613.57545698179;10616.840747388795;9861.605535778488;9891.851622225673;9921;9978.950105249427;10025.749356266151;9375.249980001583;9580.616775329048;9591;9629.903700962901;9636.373635713657;2257.7742225777292;2425.466397392571;2672.532306846294;2674.652295201611;2696;862.741177646716;944.5607792376578;946.9763255918657;1001.0740065439439;1013.7110923386799;21807.42623872485;21839.781602183775;21887;22077.558448830932;22728.772712272665;826.3926014379426;1025.7640742629087;1028.681108856257;1046.100353695821;1059.682095371401;11175.776484470265;11233.157513186543;11329.71675708114;11344.886551134383;11625.6512304631;537258;566366.1681691565;592652.0367398135;599948;608609;126375.3681231588;154993;159412.21763347118;160051.39948600598;163231;1160.4255893332686;1237.1401875696486;1262.4003598290922;1265.6962329040964;1266.3984607311454;18095.45713628593;18889.811101888805;19077.141528630968;19087.141078651774;19723.605527889074;50937.745311273204;67410.32589674041;67857.66071169612;69571.21646144855;69585.30414695991;57920;97787.51106244423;99083;102746;103251.48374258081;12132.090093243218;12208.511659533875;12279.631611051684;12366.876331236932;12369.443375047964;15411.691766164839;18511.81488185101;18742;18828.6234275311;18914.43256702301;14440;14825.777613335593;15300.464483743015;15328.156951367782;15337.539873803804;13267;13659.180449173082;13675.316234188449;13889.652758681113;14344.924130690031;15065.548033559011;15878.444254451038;15913.124778136851;15934.60163495922;16040.037597744173;10068.899311006797;11643.650690479299;12287.93856030714;12314.938425307817;12366.814497782361;15537.689246214786;15591;15666.764998524803;15714;15714.978526395747 11:30:23 INFO - PID 1664 | |3;mootools.html;1244.9937750311246;1423.3523746695255;1436.1957303909812;1439.6185010972094;1455.2141843404563;13079.54221602244;14012.64968375791;14159.504417345383;14166.504172353969;14173.362198701057;13836.930815345935;14041;14096.083754555946;14106.15363078216;14107.224102674349;2809.087046709812;2854.3149644085374;2855.785816063793;2859.9990003498815;2870.354170311684;2673.1445937300073;2776.6529183852017;2781.3880946191807;2824.6892841787385;2828.5898544711004;2834.8015638905285;2875.1518302100826;2875.985620071897;2884.4519541287195;2887.4513842369884;2779.916602501918;2803;2823.449427361665;2832.6317578714843;2833.8583070846375;2771.3071732066983;2794.175718163147;2824.068057541027;2854.1009581981816;2857.39994601134;13724.93137534306;13768.793468097987;13795;13824;13845.723085538333;7569.091708994901;7766.766996990099;7767.805804854924;7778.377729781604;7845;4573.474050484204;4797.088553174921;4828.130936431441;4883.8290659826735;4896.583790377822;1366.6173471428017;1370.9794353084712;1380.2201756007823;1395.323268214925;1407.3596513586294;20650.58698826015;20803.37589872306;20823.271185508434;20830.062647180763;20871.95640217983;6619.702113404908;6659.933400666028;6682.899756503656;6785.830354241085;6786.287439818847;11770.52917883275;14388.640283992983;14399.136051836715;14421.711565768626;14434.278286085653;7370.115586129591;7463.70145194197;7484.1393239777035;7515.511491753128;7517.17311095779;6136.417040381157;6549.115869357671;6818.454523638097;7009.9649501752165;7036.9296307036275;5022.271770593244;5176.275321454999;5178.145605974972;5203.297554830126;5226.268322434862;1042.5829668132808;1244.440001999104;1381.9930900345435;1408.60559043468;1425.1520345394474;708.7058870568657;800.5036877136213;820.2084987986622;837.0290463062818;849.1423662101199;5853.7365818538265;7864.9606751965875;7901.328387087047;8086;8272.586370681442;952.9285303602257;971.820213260549;976.0727309056425;996.0736515041082;1020.5254556631202;3585.533880595558;4990.451050384455;5066.544011038951;5251.474852514718;5326.493983071602;18521.907390462962;18571;18947.90526047361;18970.14634341458;19065.237390504226;18827;18912.527186820163;18953.24187032531;19099.045047747833;19284.614307713768;8090.757277281691;9726.36778609374;9736.902630973742;9765.169960553432;9801.411915285105;7666.156722760604;8181.427300088937;8206.712765053195;8216.383771217063;8555.599920143592;16751.74872376891;16762.988441386624;17109.315627375265;17117.486475405756;17270.82729172692;16284.104374259055;16904.07027613493;17234.569135771708;17350.39273625417;17524.91237543804;10989.175811813986;11130.721731956766;11138.610148644759;11170;11183.552657893919;12382.071344649006;12496.25022498653;12606.306653133799;12734.235945843278;12734.299613521345;8387.874181887155;9890.752731181778;10010.099091081838;10271;10594.993475619804;9859;9994.600215991279;9997.700068997941;10156.847647285149;10363.740906477398;9257.814843703223;9343.34596578233;9380.530973451436;9386.95306523463;9399.577019034297;6583.012548117821;6811.591304521745;6817.113775209191;7006.649667516705;7163.892541611775;6833.829154270984;6965.129358830146;7061.293870612898;7223.061002069698;7245.565266083841 11:30:23 INFO - PID 1664 | |4;prototype.html;391.702306247252;440.2164147816885;451.91540303272143;457.90841831633674;469.2890271239073;5293.470652934705;6159.692015399223;6485.562651794545;6485.643289619069;6545.639989800559;5140;6099.817005489831;6175.475084617813;6270.80189103557;6370.649614271213;4957.479464656213;4967.403911530603;4968.577670897969;4993.375828021497;4993.626752643016;4951.77717002734;4953.900921981558;4976.825811096613;4983.302337672729;4990.950090499085;4919.286703428001;4922.0402021605805;4925.75371231437;4931.482194369593;4968.378952630921;4059.599438193833;4294.162638285536;4589.334546490773;4786.521347865186;4808.8797780055775;4183.49798024239;4192.916141677179;4199.9370009449885;4210.200061988213;4219.451471308741;6209.596376235575;6299;6307.684615769193;6317.905231421532;6319.966416120288;5641.266635337421;5665.348484924245;5682.829515114553;5731.484166425;5743.770249190027;5885.382034886361;6932.376086152271;6948.826279343007;6993.160820701498;6997.860042799166;4751.809927602892;4834.299026641117;4846.321514987904;4846.7576621169155;4847.2002119650215;6061.393860613947;6317.494600432;7348.044754181976;7352.059176329458;7363.6318184091115;4540.500544940054;4697.060587882437;4774.904501909942;4922.852314430573;4936.925946110811;4740.312654665055;4999.500049994971;5040.6219533534395;5043.823466178666;5059.241113832881;5743.10981797822;5751.367349591542;5752.13717942312;5762.711864406763;5781.5952883298605;6420.871582568322;6584.835379115465;6809.7276108956075;6945.513814032969;6945.861082778417;1280.5966120672144;1677.6644671065592;1690.5689049292353;1717.158592289769;1717.4590004148654;987.8073775613686;1100.878903320634;1142.3431526872082;1145.5475087340453;1157.427073598574;5354.946450535523;6093.501664317609;6243.033444464979;6449.32282110381;6467.159269294962;6511.446527045254;7003.789886303419;7037.7184912602925;7160.140783106061;7206.747763828241;5604.41153678866;6005.939940600625;6471.805845824632;6472.935270647327;6486.902696459557;3152.4010438016703;3252.3170134271595;3673.430618254175;3700.7610395710744;3703.79629120401;6951.377334072785;7328.3404493595835;7332.120145582564;7339.192688804227;7347.595882226422;5813.82558523245;6056.849198652242;6234.844128896723;7360.631968401559;7384.670759263384;5924.7333869975955;7273.89089163663;7312.268773122645;7324.523905946096;7346.52247603904;7115.719170549351;7132.465065120241;7138.678759455733;7141.928580714334;7184.353408193288;6077.665728384804;6684.966575167093;6741.898871517029;6999.440044796506;7047.894281585678;6633.9336606633315;6833.863322733617;7351.558906465629;7403.888941665987;7413.3296023504845;4035.636792688672;4847.854564363075;4862.440819305825;4870.975645121752;4895.8041678332465;6183.505319574513;7129.607871567114;7146.749863754743;7160.248173941661;7171.318724721144;6708.8993665094085;6796.932030679828;6839.623820689711;6845.555038922654;6853.383195512229;6782.25395206517;6793.252742198255;6807.795766127024;6812.216595091628;6853.691583878638;6585.440237579859;6672.633005184762;6682.665866706742;6701.832454188684;6729.360710732472;5958.314793798595;6410.775622853178;6646.501512386687;6841.521093523405;6895.620740859301;5520.337559492887;6626;6656.567323123982;6660.300668429745;6661.566998145105 11:30:23 INFO - PID 1664 | |5;yui.html;829.5769157729563;832.4838600067957;839.1398816213384;857.386968317653;858.2190206911712;16908.239129239184;17181.570460738487;17324.653506929855;17346.219420126108;17365.56586085347;17277.481675549723;18788;18865.717014244798;18954.905225473853;19008.954507502083;977.9608815647383;978.9965285582264;995.6614750984663;995.810795948771;1001.7445551384424;822.461287856455;1015.3907655406771;1015.791762688647;1016.1768967136607;1018.6587493189753;916.8762217100673;1028.9948550257275;1049.826778581533;1051.5478344311944;1066.2269854355568;947.3842002698233;1031.5306535526317;1037.258360272405;1038.6105210546045;1039.8336266197448;724.6557885004634;738.6897503048732;744.0959234530072;745.4595418321727;747.1407880936912;16821.243044063045;17737.822621773874;17805.664575156785;17911.283548658168;17937.730934036;17345;17510.299588016464;17543.473695789147;17789.46631601054;17859.46421607354;14797.704045919128;14842.332095055748;14853.925730371388;14906.776398354034;14934.47729329479;3506.158521954738;3521.6126226115107;3550.3076900004507;3553.236054248349;3553.9466907996402;129976.7002329974;135684.64315356818;139206.30396848053;139475;168863.15568422078;13623.318834058258;13739.656508587364;13849.446022159002;14004.159750415009;14019.29903504841;26569.202923912315;27039.324016899736;27237.86381068082;27611.72388276132;27651.308717281827;7875.173106823816;8152.4293299469655;8176.264136227768;8226.341892648574;8270;3524.594671612746;4055.270051390719;4076.103257283393;4118.382242663624;4135.545090040093;4195.979020104941;4410.338449232577;4648.674592778473;4680.4149481314835;4788.377510923558;828.668532586958;876.640577363278;1013.1995723378595;1026.3995562595817;1030.4384110659707;523.241300114835;585.420433770564;631.5452873930763;646.5183438338468;684.7089986755609;11073.280236784583;11320.716982075513;12858.871411285954;12929.224246545238;12991.805122923164;627.1783963008473;633.9587926784744;645.3353046362255;671.8924972004554;680.5236334565824;4395.362672412482;4463.598276155161;5536.75084621193;5718.25662663851;5838.153467747238;144589;146587;148337.2583137099;149233.2538337323;150619.49380506272;103239;126377;128906.06640900394;130051.04923426156;130493;106298.46850765852;109252;111004.33493497604;113221;113350.43324783488;11179;11179.267213581908;11186;11534.480948357512;11583.362915039417;45886.541134588224;46350.30474542817;48065.5193448061;48608;49646.00707985892;57451;57730;60213.69893150506;61044.69477652583;61338;14354.784678229626;14789.408423662933;14799.334029968893;14801.851981480479;15068.924655376653;15400.15299158557;15615;15753.369865205721;16272.349106035626;16285.02289862612;14906.031107977948;15061.47284845069;16362.263698133851;18174.000429976477;18184;14610.488632897797;16692.666146677242;16733.079680617684;17578.20898059616;17825.910870445565;16179.43371981975;16391;16437.917810410872;16588.50234492967;17104.315827366765;11451.541938322374;11801.468933898166;11838.408079596158;12217.328046957502;12332.50669973191;10392.48037598102;12914.870851291742;13139.868601313865;13151.53969611059;13618.591442256748 11:30:23 INFO - PID 1664 | __end_tp_report 11:30:23 INFO - PID 1664 | __start_cc_report 11:30:23 INFO - PID 1664 | _x_x_mozilla_cycle_collect,397 11:30:23 INFO - PID 1664 | __end_cc_report 11:30:23 INFO - PID 1664 | __startTimestamp1497983422990__endTimestamp 11:30:23 INFO - PID 1664 | 11:30:23 INFO - PID 1664 | ------- Summary: start ------- 11:30:23 INFO - PID 1664 | Number of tests: 6 11:30:23 INFO - PID 1664 | 11:30:23 INFO - PID 1664 | [#0] dojo.html Cycles:165 Average:6345.39 Median:6543.25 stddev:801.47 (12.2%) stddev-sans-first:773.86 11:30:23 INFO - PID 1664 | Values: 3573.4 3630.5 3631.9 3646.5 3694.7 6294.1 6301.8 6302.0 6310.5 6315.2 6404.6 6412.2 6414.1 6422.8 6426.3 5524.0 5568.6 5577.6 5708.1 5722.1 5615.1 5624.0 5700.7 5719.9 5730.8 4193.6 5512.9 5528.6 5624.7 5653.9 5581.1 5604.0 5618.2 5646.6 5654.7 5480.3 5492.5 5560.7 5603.8 5609.7 6387.3 6395.5 6398.7 6412.9 6425.5 6671.8 6693.7 6918.2 6952.6 6990.6 6870.0 6916.9 6923.8 6985.0 6989.5 5435.8 6491.2 6527.0 6537.6 6542.1 6965.6 7034.6 7252.5 7259.2 7262.3 6702.2 6715.8 6721.3 6722.0 6748.8 6446.6 6461.6 6919.9 7000.6 7007.7 6938.6 6948.6 6959.7 6975.6 6984.0 6747.9 6811.4 6968.5 6970.6 6988.6 5439.8 5454.5 5469.8 5487.3 5519.9 3860.7 4877.2 4966.8 5001.4 5024.2 6336.6 6476.5 6537.5 6543.2 6565.8 7070.2 7072.7 7072.9 7092.1 7093.5 6184.8 6465.4 6501.2 6513.0 6523.8 5609.7 6032.3 6050.1 6065.4 6172.4 7024.4 7032.8 7052.6 7101.1 7182.5 5864.8 7056.8 7063.3 7075.8 7202.2 7192.7 7243.2 7245.9 7251.6 7277.3 6941.2 6941.3 6970.5 6972.5 6976.1 6710.5 7081.4 7084.1 7111.6 7114.5 6215.0 6630.6 6648.9 6698.8 6780.4 6317.9 6694.8 6753.3 6793.8 6813.1 6454.6 6531.5 6663.4 6673.1 6705.7 6629.5 6644.7 6656.2 6664.8 6691.0 5477.8 6439.7 6633.7 6641.4 6667.2 11:30:23 INFO - PID 1664 | 11:30:23 INFO - PID 1664 | [#1] ext.html Cycles:180 Average:44150.71 Median:3167.67 stddev:78073.56 (2464.7%) stddev-sans-first:78224.58 11:30:23 INFO - PID 1664 | Values: 752.0 855.5 1097.4 1283.3 1291.0 30709.7 34383.8 34584.0 35861.1 38553.0 39269.4 39557.6 39577.6 39673.6 39776.4 7827.0 7970.6 7972.1 7982.3 7992.2 7881.5 7938.1 7953.6 8142.8 8416.1 8379.8 8424.2 8483.7 8492.1 8500.1 8338.9 8377.2 8441.7 8443.7 8450.9 8250.5 8313.0 8317.5 8327.1 8339.8 36630.6 36644.8 36881.3 36948.6 36984.3 3068.9 3122.5 3152.1 3161.4 3173.9 30143.0 30544.3 30564.5 30632.7 30673.4 2998.1 3068.2 3099.0 3102.6 3114.6 290636.0 293929.0 294109.0 294674.0 294936.0 36518.5 40036.2 48126.6 48352.8 48467.5 38303.0 38359.4 49559.0 49963.3 50204.5 15272.9 15303.4 15422.3 15432.5 15462.1 12469.6 12581.7 12604.6 12645.2 12656.1 13396.1 13565.7 13677.0 13697.6 13700.2 6078.8 6096.5 6130.8 6145.6 6151.5 973.7 983.6 998.2 1026.7 1059.0 22578.4 22729.4 22835.4 23092.2 24196.9 931.6 1007.5 1102.2 1163.4 1171.1 9143.4 9463.0 11672.1 12133.1 12255.4 310687.0 320003.0 320306.0 320952.0 322716.0 46974.3 54212.0 54569.9 54585.5 54804.2 1422.0 1547.0 1582.1 1588.7 1589.5 18994.4 22820.5 23663.9 23841.9 25033.5 37230.0 37546.1 37560.2 37697.6 37836.1 257527.0 279274.0 285099.0 285355.0 286248.0 28191.0 28788.0 28815.0 34224.7 34321.7 36109.5 39207.8 41792.6 42097.8 43914.3 28484.6 28903.3 29417.3 29515.4 30865.2 29498.7 29554.7 30922.8 30968.8 31070.1 33801.2 36200.6 36576.5 36605.8 36667.8 28436.7 28548.9 29052.9 29133.0 29869.6 21852.8 22163.3 24015.8 24067.5 24134.0 11:30:23 INFO - PID 1664 | 11:30:23 INFO - PID 1664 | [#2] jquery.html Cycles:180 Average:39898.84 Median:21863.39 stddev:100064.44 (457.7%) stddev-sans-first:100301.26 11:30:23 INFO - PID 1664 | Values: 427.4 499.5 508.8 524.4 536.5 22082.4 22246.4 22939.0 22965.2 23048.0 24699.5 24986.0 25175.6 25383.4 25403.4 6647.0 6802.7 6814.5 6816.4 6824.9 6687.8 6691.7 6694.2 6749.0 6764.1 6082.6 6610.9 6632.1 6643.7 6646.0 6610.2 6648.6 6664.5 6667.4 6677.3 6114.8 6141.8 6142.4 6167.1 6185.1 21266.9 28689.9 30355.7 30412.7 30562.0 2003.6 2287.6 2329.8 2332.3 2339.1 19693.7 19894.4 20320.1 20339.0 20354.6 2217.1 2244.1 2292.1 2292.9 2293.5 162767.2 178686.0 186106.1 187733.1 215203.9 12387.9 14694.6 15378.5 15566.5 16554.0 20499.5 20700.0 20804.0 20831.9 20858.6 8219.7 8704.8 10084.0 10613.6 10616.8 9861.6 9891.9 9921.0 9979.0 10025.7 9375.2 9580.6 9591.0 9629.9 9636.4 2257.8 2425.5 2672.5 2674.7 2696.0 862.7 944.6 947.0 1001.1 1013.7 21807.4 21839.8 21887.0 22077.6 22728.8 826.4 1025.8 1028.7 1046.1 1059.7 11175.8 11233.2 11329.7 11344.9 11625.7 537258.0 566366.2 592652.0 599948.0 608609.0 126375.4 154993.0 159412.2 160051.4 163231.0 1160.4 1237.1 1262.4 1265.7 1266.4 18095.5 18889.8 19077.1 19087.1 19723.6 50937.7 67410.3 67857.7 69571.2 69585.3 57920.0 97787.5 99083.0 102746.0 103251.5 12132.1 12208.5 12279.6 12366.9 12369.4 15411.7 18511.8 18742.0 18828.6 18914.4 14440.0 14825.8 15300.5 15328.2 15337.5 13267.0 13659.2 13675.3 13889.7 14344.9 15065.5 15878.4 15913.1 15934.6 16040.0 10068.9 11643.7 12287.9 12314.9 12366.8 15537.7 15591.0 15666.8 15714.0 15715.0 11:30:23 INFO - PID 1664 | 11:30:23 INFO - PID 1664 | [#3] mootools.html Cycles:180 Average:8440.45 Median:2844.45 stddev:5657.16 (198.9%) stddev-sans-first:5647.19 11:30:23 INFO - PID 1664 | Values: 1245.0 1423.4 1436.2 1439.6 1455.2 13079.5 14012.6 14159.5 14166.5 14173.4 13836.9 14041.0 14096.1 14106.2 14107.2 2809.1 2854.3 2855.8 2860.0 2870.4 2673.1 2776.7 2781.4 2824.7 2828.6 2834.8 2875.2 2876.0 2884.5 2887.5 2779.9 2803.0 2823.4 2832.6 2833.9 2771.3 2794.2 2824.1 2854.1 2857.4 13724.9 13768.8 13795.0 13824.0 13845.7 7569.1 7766.8 7767.8 7778.4 7845.0 4573.5 4797.1 4828.1 4883.8 4896.6 1366.6 1371.0 1380.2 1395.3 1407.4 20650.6 20803.4 20823.3 20830.1 20872.0 6619.7 6659.9 6682.9 6785.8 6786.3 11770.5 14388.6 14399.1 14421.7 14434.3 7370.1 7463.7 7484.1 7515.5 7517.2 6136.4 6549.1 6818.5 7010.0 7036.9 5022.3 5176.3 5178.1 5203.3 5226.3 1042.6 1244.4 1382.0 1408.6 1425.2 708.7 800.5 820.2 837.0 849.1 5853.7 7865.0 7901.3 8086.0 8272.6 952.9 971.8 976.1 996.1 1020.5 3585.5 4990.5 5066.5 5251.5 5326.5 18521.9 18571.0 18947.9 18970.1 19065.2 18827.0 18912.5 18953.2 19099.0 19284.6 8090.8 9726.4 9736.9 9765.2 9801.4 7666.2 8181.4 8206.7 8216.4 8555.6 16751.7 16763.0 17109.3 17117.5 17270.8 16284.1 16904.1 17234.6 17350.4 17524.9 10989.2 11130.7 11138.6 11170.0 11183.6 12382.1 12496.3 12606.3 12734.2 12734.3 8387.9 9890.8 10010.1 10271.0 10595.0 9859.0 9994.6 9997.7 10156.8 10363.7 9257.8 9343.3 9380.5 9387.0 9399.6 6583.0 6811.6 6817.1 7006.6 7163.9 6833.8 6965.1 7061.3 7223.1 7245.6 11:30:23 INFO - PID 1664 | 11:30:23 INFO - PID 1664 | [#4] prototype.html Cycles:180 Average:5590.20 Median:6179.49 stddev:1705.86 (27.6%) stddev-sans-first:1665.43 11:30:23 INFO - PID 1664 | Values: 391.7 440.2 451.9 457.9 469.3 5293.5 6159.7 6485.6 6485.6 6545.6 5140.0 6099.8 6175.5 6270.8 6370.6 4957.5 4967.4 4968.6 4993.4 4993.6 4951.8 4953.9 4976.8 4983.3 4991.0 4919.3 4922.0 4925.8 4931.5 4968.4 4059.6 4294.2 4589.3 4786.5 4808.9 4183.5 4192.9 4199.9 4210.2 4219.5 6209.6 6299.0 6307.7 6317.9 6320.0 5641.3 5665.3 5682.8 5731.5 5743.8 5885.4 6932.4 6948.8 6993.2 6997.9 4751.8 4834.3 4846.3 4846.8 4847.2 6061.4 6317.5 7348.0 7352.1 7363.6 4540.5 4697.1 4774.9 4922.9 4936.9 4740.3 4999.5 5040.6 5043.8 5059.2 5743.1 5751.4 5752.1 5762.7 5781.6 6420.9 6584.8 6809.7 6945.5 6945.9 1280.6 1677.7 1690.6 1717.2 1717.5 987.8 1100.9 1142.3 1145.5 1157.4 5354.9 6093.5 6243.0 6449.3 6467.2 6511.4 7003.8 7037.7 7160.1 7206.7 5604.4 6005.9 6471.8 6472.9 6486.9 3152.4 3252.3 3673.4 3700.8 3703.8 6951.4 7328.3 7332.1 7339.2 7347.6 5813.8 6056.8 6234.8 7360.6 7384.7 5924.7 7273.9 7312.3 7324.5 7346.5 7115.7 7132.5 7138.7 7141.9 7184.4 6077.7 6685.0 6741.9 6999.4 7047.9 6633.9 6833.9 7351.6 7403.9 7413.3 4035.6 4847.9 4862.4 4871.0 4895.8 6183.5 7129.6 7146.7 7160.2 7171.3 6708.9 6796.9 6839.6 6845.6 6853.4 6782.3 6793.3 6807.8 6812.2 6853.7 6585.4 6672.6 6682.7 6701.8 6729.4 5958.3 6410.8 6646.5 6841.5 6895.6 5520.3 6626.0 6656.6 6660.3 6661.6 11:30:23 INFO - PID 1664 | 11:30:23 INFO - PID 1664 | [#5] yui.html Cycles:180 Average:25582.03 Median:17301.07 stddev:39832.23 (230.2%) stddev-sans-first:39900.61 11:30:23 INFO - PID 1664 | Values: 829.6 832.5 839.1 857.4 858.2 16908.2 17181.6 17324.7 17346.2 17365.6 17277.5 18788.0 18865.7 18954.9 19009.0 978.0 979.0 995.7 995.8 1001.7 822.5 1015.4 1015.8 1016.2 1018.7 916.9 1029.0 1049.8 1051.5 1066.2 947.4 1031.5 1037.3 1038.6 1039.8 724.7 738.7 744.1 745.5 747.1 16821.2 17737.8 17805.7 17911.3 17937.7 17345.0 17510.3 17543.5 17789.5 17859.5 14797.7 14842.3 14853.9 14906.8 14934.5 3506.2 3521.6 3550.3 3553.2 3553.9 129976.7 135684.6 139206.3 139475.0 168863.2 13623.3 13739.7 13849.4 14004.2 14019.3 26569.2 27039.3 27237.9 27611.7 27651.3 7875.2 8152.4 8176.3 8226.3 8270.0 3524.6 4055.3 4076.1 4118.4 4135.5 4196.0 4410.3 4648.7 4680.4 4788.4 828.7 876.6 1013.2 1026.4 1030.4 523.2 585.4 631.5 646.5 684.7 11073.3 11320.7 12858.9 12929.2 12991.8 627.2 634.0 645.3 671.9 680.5 4395.4 4463.6 5536.8 5718.3 5838.2 144589.0 146587.0 148337.3 149233.3 150619.5 103239.0 126377.0 128906.1 130051.0 130493.0 106298.5 109252.0 111004.3 113221.0 113350.4 11179.0 11179.3 11186.0 11534.5 11583.4 45886.5 46350.3 48065.5 48608.0 49646.0 57451.0 57730.0 60213.7 61044.7 61338.0 14354.8 14789.4 14799.3 14801.9 15068.9 15400.2 15615.0 15753.4 16272.3 16285.0 14906.0 15061.5 16362.3 18174.0 18184.0 14610.5 16692.7 16733.1 17578.2 17825.9 16179.4 16391.0 16437.9 16588.5 17104.3 11451.5 11801.5 11838.4 12217.3 12332.5 10392.5 12914.9 13139.9 13151.5 13618.6 11:30:23 INFO - PID 1664 | -------- Summary: end -------- 11:30:23 INFO - PID 1664 | 11:30:23 INFO - TEST-INFO | 1664: exit 0 11:30:23 INFO - TEST-OK | dromaeo_css | took 1146588ms 11:30:23 INFO - TEST-START | kraken 11:30:23 INFO - operating with platform_type : mac_ 11:30:23 INFO - Initialising browser for kraken test... 11:30:23 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpvFEH7_/profile 11:30:23 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49233/getInfo.html -foreground) 11:30:56 INFO - TEST-INFO | 1673: killed by SIGTERM 11:30:56 INFO - Browser initialized. 11:30:56 INFO - Running cycle 1/1 for kraken test... 11:30:56 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpvFEH7_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:30:58 INFO - PID 1677 | 2017-06-20 11:30:58.585 plugin-container[1678:13047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 11:30:58 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:30:58 INFO - PID 1677 | 2017-06-20 11:30:58.586 plugin-container[1678:13047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4853, name = 'com.apple.CFPasteboardClient' 11:30:58 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:30:58 INFO - PID 1677 | 2017-06-20 11:30:58.586 plugin-container[1678:13047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:30:59 INFO - PID 1677 | 2017-06-20 11:30:59.374 plugin-container[1679:13091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 11:30:59 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:30:59 INFO - PID 1677 | 2017-06-20 11:30:59.374 plugin-container[1679:13091] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 11:30:59 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:30:59 INFO - PID 1677 | 2017-06-20 11:30:59.374 plugin-container[1679:13091] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:31:08 INFO - PID 1677 | RSS: Main: 215035904 11:31:08 INFO - PID 1677 | 11:31:11 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection) 11:31:12 INFO - PID 1677 | RSS: Main: 228204544 11:31:12 INFO - PID 1677 | 11:31:15 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-dft) 11:31:15 INFO - PID 1677 | RSS: Main: 239185920 11:31:15 INFO - PID 1677 | 11:31:19 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-fft) 11:31:19 INFO - PID 1677 | RSS: Main: 241369088 11:31:19 INFO - PID 1677 | 11:31:22 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator) 11:31:22 INFO - PID 1677 | RSS: Main: 244191232 11:31:22 INFO - PID 1677 | 11:31:24 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:31:25 INFO - PID 1677 | RSS: Main: 244895744 11:31:25 INFO - PID 1677 | 11:31:28 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom) 11:31:28 INFO - PID 1677 | RSS: Main: 252239872 11:31:28 INFO - PID 1677 | 11:31:31 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate) 11:31:32 INFO - PID 1677 | RSS: Main: 252325888 11:31:32 INFO - PID 1677 | 11:31:35 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial) 11:31:36 INFO - PID 1677 | RSS: Main: 252198912 11:31:36 INFO - PID 1677 | 11:31:37 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:31:38 INFO - PID 1677 | RSS: Main: 251916288 11:31:38 INFO - PID 1677 | 11:31:39 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:31:40 INFO - PID 1677 | RSS: Main: 251809792 11:31:40 INFO - PID 1677 | 11:31:42 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:31:43 INFO - PID 1677 | RSS: Main: 251760640 11:31:43 INFO - PID 1677 | 11:31:46 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:31:46 INFO - PID 1677 | RSS: Main: 252137472 11:31:46 INFO - PID 1677 | 11:31:50 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:31:50 INFO - PID 1677 | RSS: Main: 252317696 11:31:50 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | Cycle 1(1): loaded http://localhost:49233/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49233/tests/kraken/driver.html?testName=ai-astar) 11:31:52 INFO - PID 1677 | RSS: Main: 253681664 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | __start_tp_report 11:31:52 INFO - PID 1677 | _x_x_mozilla_page_load 11:31:52 INFO - PID 1677 | _x_x_mozilla_page_load_details 11:31:52 INFO - PID 1677 | |i|pagename|runs| 11:31:52 INFO - PID 1677 | |0;ai-astar;108;118;98;125;158;170;168;114;120;169 11:31:52 INFO - PID 1677 | |1;audio-beat-detection;133;111;128;145;108;124;115;142;138;152 11:31:52 INFO - PID 1677 | |2;audio-dft;274;272;198;230;280;194;207;212;252;216 11:31:52 INFO - PID 1677 | |3;audio-fft;59;107;96;139;65;74;86;86;170;83 11:31:52 INFO - PID 1677 | |4;audio-oscillator;69;72;107;72;70;70;104;71;71;103 11:31:52 INFO - PID 1677 | |5;imaging-gaussian-blur;95;95;96;95;97;96;95;95;95;91 11:31:52 INFO - PID 1677 | |6;imaging-darkroom;99;99;99;98;98;106;99;99;98;97 11:31:52 INFO - PID 1677 | |7;imaging-desaturate;90;93;93;93;98;79;134;145;82;143 11:31:52 INFO - PID 1677 | |8;json-parse-financial;51;50;50;54;52;51;54;52;54;53 11:31:52 INFO - PID 1677 | |9;json-stringify-tinderbox;53;55;58;62;55;63;54;56;56;56 11:31:52 INFO - PID 1677 | |10;stanford-crypto-aes;92;78;79;80;71;101;104;105;68;89 11:31:52 INFO - PID 1677 | |11;stanford-crypto-ccm;131;140;131;154;134;133;138;133;143;185 11:31:52 INFO - PID 1677 | |12;stanford-crypto-pbkdf2;177;183;237;210;177;189;234;194;180;178 11:31:52 INFO - PID 1677 | |13;stanford-crypto-sha256-iterative;68;66;68;63;65;65;64;66;63;65 11:31:52 INFO - PID 1677 | __end_tp_report 11:31:52 INFO - PID 1677 | __start_cc_report 11:31:52 INFO - PID 1677 | _x_x_mozilla_cycle_collect,1033 11:31:52 INFO - PID 1677 | __end_cc_report 11:31:52 INFO - PID 1677 | __startTimestamp1497983512585__endTimestamp 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | ------- Summary: start ------- 11:31:52 INFO - PID 1677 | Number of tests: 14 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#0] ai-astar Cycles:10 Average:134.80 Median:163.00 stddev:28.19 (17.3%) stddev-sans-first:28.18 11:31:52 INFO - PID 1677 | Values: 108.0 118.0 98.0 125.0 158.0 170.0 168.0 114.0 120.0 169.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#1] audio-beat-detection Cycles:10 Average:129.60 Median:135.50 stddev:15.03 (11.1%) stddev-sans-first:15.90 11:31:52 INFO - PID 1677 | Values: 133.0 111.0 128.0 145.0 108.0 124.0 115.0 142.0 138.0 152.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#2] audio-dft Cycles:10 Average:233.50 Median:241.00 stddev:33.22 (13.8%) stddev-sans-first:31.84 11:31:52 INFO - PID 1677 | Values: 274.0 272.0 198.0 230.0 280.0 194.0 207.0 212.0 252.0 216.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#3] audio-fft Cycles:10 Average:96.50 Median:78.50 stddev:34.36 (43.8%) stddev-sans-first:33.66 11:31:52 INFO - PID 1677 | Values: 59.0 107.0 96.0 139.0 65.0 74.0 86.0 86.0 170.0 83.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:70.50 stddev:16.45 (23.3%) stddev-sans-first:16.88 11:31:52 INFO - PID 1677 | Values: 69.0 72.0 107.0 72.0 70.0 70.0 104.0 71.0 71.0 103.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:95.00 stddev:1.56 (1.6%) stddev-sans-first:1.66 11:31:52 INFO - PID 1677 | Values: 95.0 95.0 96.0 95.0 97.0 96.0 95.0 95.0 95.0 91.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#6] imaging-darkroom Cycles:10 Average:99.20 Median:99.00 stddev:2.49 (2.5%) stddev-sans-first:2.64 11:31:52 INFO - PID 1677 | Values: 99.0 99.0 99.0 98.0 98.0 106.0 99.0 99.0 98.0 97.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#7] imaging-desaturate Cycles:10 Average:105.00 Median:91.50 stddev:25.38 (27.7%) stddev-sans-first:26.33 11:31:52 INFO - PID 1677 | Values: 90.0 93.0 93.0 93.0 98.0 79.0 134.0 145.0 82.0 143.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.50 stddev:1.60 (3.0%) stddev-sans-first:1.64 11:31:52 INFO - PID 1677 | Values: 51.0 50.0 50.0 54.0 52.0 51.0 54.0 52.0 54.0 53.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:56.80 Median:56.00 stddev:3.29 (5.9%) stddev-sans-first:3.19 11:31:52 INFO - PID 1677 | Values: 53.0 55.0 58.0 62.0 55.0 63.0 54.0 56.0 56.0 56.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:78.50 stddev:13.53 (17.2%) stddev-sans-first:14.22 11:31:52 INFO - PID 1677 | Values: 92.0 78.0 79.0 80.0 71.0 101.0 104.0 105.0 68.0 89.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:139.00 stddev:16.61 (11.9%) stddev-sans-first:17.11 11:31:52 INFO - PID 1677 | Values: 131.0 140.0 131.0 154.0 134.0 133.0 138.0 133.0 143.0 185.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.90 Median:191.50 stddev:23.20 (12.1%) stddev-sans-first:23.58 11:31:52 INFO - PID 1677 | Values: 177.0 183.0 237.0 210.0 177.0 189.0 234.0 194.0 180.0 178.0 11:31:52 INFO - PID 1677 | 11:31:52 INFO - PID 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:1.77 (2.7%) stddev-sans-first:1.58 11:31:52 INFO - PID 1677 | Values: 68.0 66.0 68.0 63.0 65.0 65.0 64.0 66.0 63.0 65.0 11:31:52 INFO - PID 1677 | -------- Summary: end -------- 11:31:52 INFO - PID 1677 | 11:31:53 INFO - TEST-INFO | 1677: exit 0 11:31:53 INFO - TEST-OK | kraken | took 89754ms 11:31:53 INFO - SUITE-END | took 1236s 11:31:53 INFO - Completed test suite (00:20:36) 11:31:53 INFO - javascript benchmark 11:31:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8397.837968824306, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3573.4461158520407, 3630.473581330707, 3631.8728844490433, 3646.453032045193, 3694.7044236461084, 6294.08735733319, 6301.779437719681, 6301.968490157555, 6310.4636105930995, 6315.1790267265305, 6404.647744374058, 6412.230532336126, 6414.102025716392, 6422.807315780522, 6426.293107758154, 5524.033294173523, 5568.55451563876, 5577.609567330289, 5708.08670612702, 5722.084466485363, 5615.073512870392, 5624.0, 5700.6579605223615, 5719.941810765022, 5730.770769169249, 4193.580641935812, 5512.862178445531, 5528.640638358494, 5624.718764061821, 5653.943460565383, 5581.134924086732, 5604.0, 5618.15727640853, 5646.604737668365, 5654.688992105433, 5480.342358916955, 5492.47821456961, 5560.7219639017885, 5603.775848966036, 5609.691466969315, 6387.329330420285, 6395.52033597482, 6398.744050237985, 6412.93587064128, 6425.453836423862, 6671.766488172939, 6693.665316734144, 6918.204406493267, 6952.617606031667, 6990.580565166056, 6870.038194652752, 6916.930830691729, 6923.7576684816295, 6984.965075174642, 6989.5456795308655, 5435.809746658927, 6491.156149700509, 6527.0, 6537.607743535308, 6542.116814230113, 6965.582065076112, 7034.6482675866, 7252.456065795086, 7259.201487836333, 7262.346388825031, 6702.162229721285, 6715.7985260442265, 6721.294264102296, 6722.0253063305545, 6748.831279218059, 6446.645434501053, 6461.612303261725, 6919.861602767916, 7000.649967501605, 7007.684654190573, 6938.583684978817, 6948.583085014814, 6959.652017399211, 6975.616341101187, 6984.0, 6747.865042699119, 6811.352921472452, 6968.477364197604, 6970.616616086164, 6988.615626140611, 5439.83680489578, 5454.53636440899, 5469.8085567005755, 5487.286652735199, 5519.889602207933, 3860.652541271299, 4877.244027175793, 4966.826161084345, 5001.399832020181, 5024.171011783088, 6336.58812177207, 6476.5466417350335, 6537.477001839841, 6543.247526534509, 6565.835854103686, 7070.222275549685, 7072.71709131629, 7072.8939065914055, 7092.113485814273, 7093.467989900778, 6184.783532576428, 6465.3857883501, 6501.2198536175965, 6513.023046543057, 6523.804285871432, 5609.663420194802, 6032.336442991267, 6050.062240352752, 6065.423784740355, 6172.444479996821, 7024.4029257513685, 7032.824179395556, 7052.647367631701, 7101.147862256562, 7182.497225194186, 5864.765409383747, 7056.788296351117, 7063.328983746535, 7075.82310442243, 7202.207757146605, 7192.748253811092, 7243.20324764286, 7245.927540724525, 7251.56490610565, 7277.345038946521, 6941.236463989058, 6941.34057264559, 6970.477214209056, 6972.511924165463, 6976.127984002, 6710.463162946855, 7081.362677358857, 7084.114485689289, 7111.644417779194, 7114.50198486101, 6214.974529202631, 6630.60216386999, 6648.90026649591, 6698.799036028927, 6780.38976492118, 6317.905231421624, 6694.799156025327, 6753.257141714505, 6793.796186114425, 6813.080234168324, 6454.644994525346, 6531.542792004514, 6663.36698013688, 6673.099131617169, 6705.731770729116, 6629.502787291069, 6644.734210631714, 6656.201255849329, 6664.8000559983275, 6691.0, 5477.808276710296, 6439.678016099083, 6633.668316584055, 6641.402273795382, 6667.233268174028], "value": 6289.98706574467, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.9887201691973, 855.5337341149075, 1097.4403054442232, 1283.326253716798, 1290.9741805163892, 30709.69290307096, 34383.828080859625, 34584.0, 35861.10347241317, 38553.0, 39269.41095883564, 39557.615483458016, 39577.61478348258, 39673.60326396735, 39776.403353949645, 7826.9608651956805, 7970.641321140533, 7972.083210430787, 7982.28159465648, 7992.160823113577, 7881.487703299267, 7938.087119981218, 7953.647879860438, 8142.796430089265, 8416.074231834493, 8379.790505237388, 8424.157584241557, 8483.74548763538, 8492.108328625498, 8500.064992850812, 8338.916610833874, 8377.162283771635, 8441.746747597583, 8443.746687599381, 8450.915490845106, 8250.504969701777, 8313.002439707214, 8317.459365141247, 8327.084020757711, 8339.8332033359, 36630.63369366299, 36644.81677591622, 36881.262374752354, 36948.630513694785, 36984.26031479382, 3068.8925887593828, 3122.5472306515653, 3152.0543836848856, 3161.4151381994407, 3173.9206519836957, 30143.0, 30544.32006239656, 30564.54153187704, 30632.69367306343, 30673.38653226923, 2998.100569829064, 3068.186930463429, 3098.984505077483, 3102.550130231104, 3114.6418161911442, 290636.0, 293929.0, 294109.0, 294674.0, 294936.0, 36518.45222321667, 40036.19837107337, 48126.55620331396, 48352.791180220775, 48467.51532484701, 38303.0, 38359.42460863089, 49559.0088198234, 49963.25055124176, 50204.4938651841, 15272.923635381752, 15303.387864485296, 15422.305996230196, 15432.459863904935, 15462.14958177311, 12469.625911222678, 12581.748365032649, 12604.55884044054, 12645.241285522898, 12656.050796190322, 13396.129251598615, 13565.660858478419, 13676.974226932822, 13697.589072327846, 13700.177989320673, 6078.756849725962, 6096.542759293071, 6130.816075517741, 6145.631262124287, 6151.507879369639, 973.6981535723949, 983.6213057972732, 998.2498217411131, 1026.6817286641165, 1058.9735256618492, 22578.435539111655, 22729.431764206027, 22835.423042748676, 23092.191773287854, 24196.87901560481, 931.6459745296744, 1007.4912169354429, 1102.1788767368203, 1163.3717792392183, 1171.0690001448868, 9143.3599648024, 9462.952685236529, 11672.124590655734, 12133.090018248668, 12255.387230638433, 310687.0, 320003.0, 320306.0, 320952.0, 322716.0, 46974.29538556925, 54212.0, 54569.90860182854, 54585.45414545883, 54804.17793733097, 1421.969072422506, 1546.9767953480707, 1582.1377349344634, 1588.7140314743226, 1589.4675283779832, 18994.430167095008, 22820.543589127792, 23663.935122919167, 23841.88079059663, 25033.499330013663, 37230.0, 37546.06134846542, 37560.2487950234, 37697.62302376941, 37836.05409864776, 257527.0, 279274.0, 285099.0, 285355.0, 286248.0, 28191.0, 28788.0, 28815.0, 34224.65775342215, 34321.656783432845, 36109.45835812517, 39207.80396098001, 41792.58207417887, 42097.78951105225, 43914.341284880116, 28484.572731408633, 28903.277418064717, 29417.264568385963, 29515.40969180647, 30865.228369290948, 29498.705012950453, 29554.67003984869, 30922.845385772926, 30968.761249549767, 31070.067897963097, 33801.15259818839, 36200.637993619726, 36576.45135322919, 36605.81697091497, 36667.81666091653, 28436.715632843407, 28548.85725571359, 29052.854735726185, 29133.0, 29869.551956720232, 21852.781472185074, 22163.335099947028, 24015.75984240205, 24067.518649627258, 24134.0], "value": 17139.92488003742, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.4165763732505, 499.48303505871417, 508.82954210339534, 524.4314955186292, 536.4662161149656, 22082.44793880154, 22246.443838904037, 22938.967746451388, 22965.19621813237, 23047.962841672124, 24699.50600987979, 24986.00055997758, 25175.62236566453, 25383.365415864613, 25403.364915877068, 6647.0, 6802.727890884359, 6814.522983391164, 6816.38652521273, 6824.931750682479, 6687.832804179886, 6691.73233070679, 6694.163229596301, 6748.966255168742, 6764.0530325754435, 6082.60463069899, 6610.933890661104, 6632.137822083147, 6643.701033453482, 6646.0, 6610.239822420435, 6648.634325112117, 6664.533482656216, 6667.399934005938, 6677.2988836172235, 6114.785982490592, 6141.815745527641, 6142.416470435302, 6167.136600875881, 6185.072239164117, 21266.936653167435, 28689.856550717323, 30355.696443035507, 30412.695873041208, 30562.0, 2003.6193123306525, 2287.6225422805383, 2329.848559843622, 2332.265336419023, 2339.0526836631193, 19693.704594431096, 19894.403167904988, 20320.08559614821, 20339.0, 20354.592908141753, 2217.1463986365206, 2244.124791331382, 2292.1289909834154, 2292.8624282542933, 2293.472501324688, 162767.1861640684, 178686.0, 186106.06946965313, 187733.0613346938, 215203.9239803791, 12387.938060309642, 14694.63263418423, 15378.461753838563, 15566.533004009898, 16554.0, 20499.4875128123, 20700.0, 20803.95980200984, 20831.895840521003, 20858.582828343348, 8219.671213151527, 8704.782380440412, 10084.0, 10613.57545698179, 10616.840747388795, 9861.605535778488, 9891.851622225673, 9921.0, 9978.950105249427, 10025.749356266151, 9375.249980001583, 9580.616775329048, 9591.0, 9629.903700962901, 9636.373635713657, 2257.7742225777292, 2425.466397392571, 2672.532306846294, 2674.652295201611, 2696.0, 862.741177646716, 944.5607792376578, 946.9763255918657, 1001.0740065439439, 1013.7110923386799, 21807.42623872485, 21839.781602183775, 21887.0, 22077.558448830932, 22728.772712272665, 826.3926014379426, 1025.7640742629087, 1028.681108856257, 1046.100353695821, 1059.682095371401, 11175.776484470265, 11233.157513186543, 11329.71675708114, 11344.886551134383, 11625.6512304631, 537258.0, 566366.1681691565, 592652.0367398135, 599948.0, 608609.0, 126375.3681231588, 154993.0, 159412.21763347118, 160051.39948600598, 163231.0, 1160.4255893332686, 1237.1401875696486, 1262.4003598290922, 1265.6962329040964, 1266.3984607311454, 18095.45713628593, 18889.811101888805, 19077.141528630968, 19087.141078651774, 19723.605527889074, 50937.745311273204, 67410.32589674041, 67857.66071169612, 69571.21646144855, 69585.30414695991, 57920.0, 97787.51106244423, 99083.0, 102746.0, 103251.48374258081, 12132.090093243218, 12208.511659533875, 12279.631611051684, 12366.876331236932, 12369.443375047964, 15411.691766164839, 18511.81488185101, 18742.0, 18828.6234275311, 18914.43256702301, 14440.0, 14825.777613335593, 15300.464483743015, 15328.156951367782, 15337.539873803804, 13267.0, 13659.180449173082, 13675.316234188449, 13889.652758681113, 14344.924130690031, 15065.548033559011, 15878.444254451038, 15913.124778136851, 15934.60163495922, 16040.037597744173, 10068.899311006797, 11643.650690479299, 12287.93856030714, 12314.938425307817, 12366.814497782361, 15537.689246214786, 15591.0, 15666.764998524803, 15714.0, 15714.978526395747], "value": 11908.866027171147, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1244.9937750311246, 1423.3523746695255, 1436.1957303909812, 1439.6185010972094, 1455.2141843404563, 13079.54221602244, 14012.64968375791, 14159.504417345383, 14166.504172353969, 14173.362198701057, 13836.930815345935, 14041.0, 14096.083754555946, 14106.15363078216, 14107.224102674349, 2809.087046709812, 2854.3149644085374, 2855.785816063793, 2859.9990003498815, 2870.354170311684, 2673.1445937300073, 2776.6529183852017, 2781.3880946191807, 2824.6892841787385, 2828.5898544711004, 2834.8015638905285, 2875.1518302100826, 2875.985620071897, 2884.4519541287195, 2887.4513842369884, 2779.916602501918, 2803.0, 2823.449427361665, 2832.6317578714843, 2833.8583070846375, 2771.3071732066983, 2794.175718163147, 2824.068057541027, 2854.1009581981816, 2857.39994601134, 13724.93137534306, 13768.793468097987, 13795.0, 13824.0, 13845.723085538333, 7569.091708994901, 7766.766996990099, 7767.805804854924, 7778.377729781604, 7845.0, 4573.474050484204, 4797.088553174921, 4828.130936431441, 4883.8290659826735, 4896.583790377822, 1366.6173471428017, 1370.9794353084712, 1380.2201756007823, 1395.323268214925, 1407.3596513586294, 20650.58698826015, 20803.37589872306, 20823.271185508434, 20830.062647180763, 20871.95640217983, 6619.702113404908, 6659.933400666028, 6682.899756503656, 6785.830354241085, 6786.287439818847, 11770.52917883275, 14388.640283992983, 14399.136051836715, 14421.711565768626, 14434.278286085653, 7370.115586129591, 7463.70145194197, 7484.1393239777035, 7515.511491753128, 7517.17311095779, 6136.417040381157, 6549.115869357671, 6818.454523638097, 7009.9649501752165, 7036.9296307036275, 5022.271770593244, 5176.275321454999, 5178.145605974972, 5203.297554830126, 5226.268322434862, 1042.5829668132808, 1244.440001999104, 1381.9930900345435, 1408.60559043468, 1425.1520345394474, 708.7058870568657, 800.5036877136213, 820.2084987986622, 837.0290463062818, 849.1423662101199, 5853.7365818538265, 7864.9606751965875, 7901.328387087047, 8086.0, 8272.586370681442, 952.9285303602257, 971.820213260549, 976.0727309056425, 996.0736515041082, 1020.5254556631202, 3585.533880595558, 4990.451050384455, 5066.544011038951, 5251.474852514718, 5326.493983071602, 18521.907390462962, 18571.0, 18947.90526047361, 18970.14634341458, 19065.237390504226, 18827.0, 18912.527186820163, 18953.24187032531, 19099.045047747833, 19284.614307713768, 8090.757277281691, 9726.36778609374, 9736.902630973742, 9765.169960553432, 9801.411915285105, 7666.156722760604, 8181.427300088937, 8206.712765053195, 8216.383771217063, 8555.599920143592, 16751.74872376891, 16762.988441386624, 17109.315627375265, 17117.486475405756, 17270.82729172692, 16284.104374259055, 16904.07027613493, 17234.569135771708, 17350.39273625417, 17524.91237543804, 10989.175811813986, 11130.721731956766, 11138.610148644759, 11170.0, 11183.552657893919, 12382.071344649006, 12496.25022498653, 12606.306653133799, 12734.235945843278, 12734.299613521345, 8387.874181887155, 9890.752731181778, 10010.099091081838, 10271.0, 10594.993475619804, 9859.0, 9994.600215991279, 9997.700068997941, 10156.847647285149, 10363.740906477398, 9257.814843703223, 9343.34596578233, 9380.530973451436, 9386.95306523463, 9399.577019034297, 6583.012548117821, 6811.591304521745, 6817.113775209191, 7006.649667516705, 7163.892541611775, 6833.829154270984, 6965.129358830146, 7061.293870612898, 7223.061002069698, 7245.565266083841], "value": 6199.32888583859, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.702306247252, 440.2164147816885, 451.91540303272143, 457.90841831633674, 469.2890271239073, 5293.470652934705, 6159.692015399223, 6485.562651794545, 6485.643289619069, 6545.639989800559, 5140.0, 6099.817005489831, 6175.475084617813, 6270.80189103557, 6370.649614271213, 4957.479464656213, 4967.403911530603, 4968.577670897969, 4993.375828021497, 4993.626752643016, 4951.77717002734, 4953.900921981558, 4976.825811096613, 4983.302337672729, 4990.950090499085, 4919.286703428001, 4922.0402021605805, 4925.75371231437, 4931.482194369593, 4968.378952630921, 4059.599438193833, 4294.162638285536, 4589.334546490773, 4786.521347865186, 4808.8797780055775, 4183.49798024239, 4192.916141677179, 4199.9370009449885, 4210.200061988213, 4219.451471308741, 6209.596376235575, 6299.0, 6307.684615769193, 6317.905231421532, 6319.966416120288, 5641.266635337421, 5665.348484924245, 5682.829515114553, 5731.484166425, 5743.770249190027, 5885.382034886361, 6932.376086152271, 6948.826279343007, 6993.160820701498, 6997.860042799166, 4751.809927602892, 4834.299026641117, 4846.321514987904, 4846.7576621169155, 4847.2002119650215, 6061.393860613947, 6317.494600432, 7348.044754181976, 7352.059176329458, 7363.6318184091115, 4540.500544940054, 4697.060587882437, 4774.904501909942, 4922.852314430573, 4936.925946110811, 4740.312654665055, 4999.500049994971, 5040.6219533534395, 5043.823466178666, 5059.241113832881, 5743.10981797822, 5751.367349591542, 5752.13717942312, 5762.711864406763, 5781.5952883298605, 6420.871582568322, 6584.835379115465, 6809.7276108956075, 6945.513814032969, 6945.861082778417, 1280.5966120672144, 1677.6644671065592, 1690.5689049292353, 1717.158592289769, 1717.4590004148654, 987.8073775613686, 1100.878903320634, 1142.3431526872082, 1145.5475087340453, 1157.427073598574, 5354.946450535523, 6093.501664317609, 6243.033444464979, 6449.32282110381, 6467.159269294962, 6511.446527045254, 7003.789886303419, 7037.7184912602925, 7160.140783106061, 7206.747763828241, 5604.41153678866, 6005.939940600625, 6471.805845824632, 6472.935270647327, 6486.902696459557, 3152.4010438016703, 3252.3170134271595, 3673.430618254175, 3700.7610395710744, 3703.79629120401, 6951.377334072785, 7328.3404493595835, 7332.120145582564, 7339.192688804227, 7347.595882226422, 5813.82558523245, 6056.849198652242, 6234.844128896723, 7360.631968401559, 7384.670759263384, 5924.7333869975955, 7273.89089163663, 7312.268773122645, 7324.523905946096, 7346.52247603904, 7115.719170549351, 7132.465065120241, 7138.678759455733, 7141.928580714334, 7184.353408193288, 6077.665728384804, 6684.966575167093, 6741.898871517029, 6999.440044796506, 7047.894281585678, 6633.9336606633315, 6833.863322733617, 7351.558906465629, 7403.888941665987, 7413.3296023504845, 4035.636792688672, 4847.854564363075, 4862.440819305825, 4870.975645121752, 4895.8041678332465, 6183.505319574513, 7129.607871567114, 7146.749863754743, 7160.248173941661, 7171.318724721144, 6708.8993665094085, 6796.932030679828, 6839.623820689711, 6845.555038922654, 6853.383195512229, 6782.25395206517, 6793.252742198255, 6807.795766127024, 6812.216595091628, 6853.691583878638, 6585.440237579859, 6672.633005184762, 6682.665866706742, 6701.832454188684, 6729.360710732472, 5958.314793798595, 6410.775622853178, 6646.501512386687, 6841.521093523405, 6895.620740859301, 5520.337559492887, 6626.0, 6656.567323123982, 6660.300668429745, 6661.566998145105], "value": 5063.647387793467, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.5769157729563, 832.4838600067957, 839.1398816213384, 857.386968317653, 858.2190206911712, 16908.239129239184, 17181.570460738487, 17324.653506929855, 17346.219420126108, 17365.56586085347, 17277.481675549723, 18788.0, 18865.717014244798, 18954.905225473853, 19008.954507502083, 977.9608815647383, 978.9965285582264, 995.6614750984663, 995.810795948771, 1001.7445551384424, 822.461287856455, 1015.3907655406771, 1015.791762688647, 1016.1768967136607, 1018.6587493189753, 916.8762217100673, 1028.9948550257275, 1049.826778581533, 1051.5478344311944, 1066.2269854355568, 947.3842002698233, 1031.5306535526317, 1037.258360272405, 1038.6105210546045, 1039.8336266197448, 724.6557885004634, 738.6897503048732, 744.0959234530072, 745.4595418321727, 747.1407880936912, 16821.243044063045, 17737.822621773874, 17805.664575156785, 17911.283548658168, 17937.730934036, 17345.0, 17510.299588016464, 17543.473695789147, 17789.46631601054, 17859.46421607354, 14797.704045919128, 14842.332095055748, 14853.925730371388, 14906.776398354034, 14934.47729329479, 3506.158521954738, 3521.6126226115107, 3550.3076900004507, 3553.236054248349, 3553.9466907996402, 129976.7002329974, 135684.64315356818, 139206.30396848053, 139475.0, 168863.15568422078, 13623.318834058258, 13739.656508587364, 13849.446022159002, 14004.159750415009, 14019.29903504841, 26569.202923912315, 27039.324016899736, 27237.86381068082, 27611.72388276132, 27651.308717281827, 7875.173106823816, 8152.4293299469655, 8176.264136227768, 8226.341892648574, 8270.0, 3524.594671612746, 4055.270051390719, 4076.103257283393, 4118.382242663624, 4135.545090040093, 4195.979020104941, 4410.338449232577, 4648.674592778473, 4680.4149481314835, 4788.377510923558, 828.668532586958, 876.640577363278, 1013.1995723378595, 1026.3995562595817, 1030.4384110659707, 523.241300114835, 585.420433770564, 631.5452873930763, 646.5183438338468, 684.7089986755609, 11073.280236784583, 11320.716982075513, 12858.871411285954, 12929.224246545238, 12991.805122923164, 627.1783963008473, 633.9587926784744, 645.3353046362255, 671.8924972004554, 680.5236334565824, 4395.362672412482, 4463.598276155161, 5536.75084621193, 5718.25662663851, 5838.153467747238, 144589.0, 146587.0, 148337.2583137099, 149233.2538337323, 150619.49380506272, 103239.0, 126377.0, 128906.06640900394, 130051.04923426156, 130493.0, 106298.46850765852, 109252.0, 111004.33493497604, 113221.0, 113350.43324783488, 11179.0, 11179.267213581908, 11186.0, 11534.480948357512, 11583.362915039417, 45886.541134588224, 46350.30474542817, 48065.5193448061, 48608.0, 49646.00707985892, 57451.0, 57730.0, 60213.69893150506, 61044.69477652583, 61338.0, 14354.784678229626, 14789.408423662933, 14799.334029968893, 14801.851981480479, 15068.924655376653, 15400.15299158557, 15615.0, 15753.369865205721, 16272.349106035626, 16285.02289862612, 14906.031107977948, 15061.47284845069, 16362.263698133851, 18174.000429976477, 18184.0, 14610.488632897797, 16692.666146677242, 16733.079680617684, 17578.20898059616, 17825.910870445565, 16179.43371981975, 16391.0, 16437.917810410872, 16588.50234492967, 17104.315827366765, 11451.541938322374, 11801.468933898166, 11838.408079596158, 12217.328046957502, 12332.50669973191, 10392.48037598102, 12914.870851291742, 13139.868601313865, 13151.53969611059, 13618.591442256748], "value": 8702.448353755584, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1573.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 98.0, 125.0, 158.0, 170.0, 168.0, 114.0, 120.0, 169.0], "value": 134.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 111.0, 128.0, 145.0, 108.0, 124.0, 115.0, 142.0, 138.0, 152.0], "value": 129.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [274.0, 272.0, 198.0, 230.0, 280.0, 194.0, 207.0, 212.0, 252.0, 216.0], "value": 233.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 107.0, 96.0, 139.0, 65.0, 74.0, 86.0, 86.0, 170.0, 83.0], "value": 96.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 107.0, 72.0, 70.0, 70.0, 104.0, 71.0, 71.0, 103.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 97.0, 96.0, 95.0, 95.0, 95.0, 91.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 99.0, 98.0, 98.0, 106.0, 99.0, 99.0, 98.0, 97.0], "value": 99.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 93.0, 93.0, 98.0, 79.0, 134.0, 145.0, 82.0, 143.0], "value": 105.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 54.0, 52.0, 51.0, 54.0, 52.0, 54.0, 53.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 58.0, 62.0, 55.0, 63.0, 54.0, 56.0, 56.0, 56.0], "value": 56.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 78.0, 79.0, 80.0, 71.0, 101.0, 104.0, 105.0, 68.0, 89.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 140.0, 131.0, 154.0, 134.0, 133.0, 138.0, 133.0, 143.0, 185.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 183.0, 237.0, 210.0, 177.0, 189.0, 234.0, 194.0, 180.0, 178.0], "value": 195.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 63.0, 65.0, 65.0, 64.0, 66.0, 63.0, 65.0], "value": 65.3, "unit": "score"}]}]} 11:31:53 INFO - Return code: 0 11:31:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:31:53 INFO - # TBPL SUCCESS # 11:31:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:31:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:31:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:31:53 INFO - 2017-06-20 11:31:53.994 screenresolution[1685:13483] starting screenresolution argv=screenresolution get 11:31:53 INFO - 2017-06-20 11:31:53.997 screenresolution[1685:13483] Display 0: 1600x1200x32@60 11:31:54 INFO - 2017-06-20 11:31:54.003 screenresolution[1686:13486] starting screenresolution argv=screenresolution list 11:31:54 INFO - Available Modes on Display 0 11:31:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:31:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:31:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:31:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:31:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:31:54 INFO - Intel Iris: 11:31:54 INFO - Chipset Model: Intel Iris 11:31:54 INFO - Type: GPU 11:31:54 INFO - Bus: Built-In 11:31:54 INFO - VRAM (Dynamic, Max): 1536 MB 11:31:54 INFO - Vendor: Intel (0x8086) 11:31:54 INFO - Device ID: 0x0a2e 11:31:54 INFO - Revision ID: 0x0009 11:31:54 INFO - Displays: 11:31:54 INFO - Display: 11:31:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:31:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:31:54 INFO - Display Serial Number: 1600x1200 60 11:31:54 INFO - Main Display: Yes 11:31:54 INFO - Mirror: Off 11:31:54 INFO - Online: Yes 11:31:54 INFO - Rotation: Supported 11:31:54 INFO - Return code: 0 11:31:54 INFO - Running post-action listener: _resource_record_post_action 11:31:54 INFO - [mozharness: 2017-06-20 18:31:54.239434Z] Finished run-tests step (success) 11:31:54 INFO - Running post-run listener: _resource_record_post_run 11:31:54 INFO - Total resource usage - Wall time: 1259s; CPU: 53.0%; Read bytes: 36348416; Write bytes: 562935808; Read time: 333; Write time: 1990 11:31:54 INFO - TinderboxPrint: CPU usage
53.1% 11:31:54 INFO - TinderboxPrint: I/O read bytes / time
36,348,416 / 333 11:31:54 INFO - TinderboxPrint: I/O write bytes / time
562,935,808 / 1,990 11:31:54 INFO - TinderboxPrint: CPU idle
2,364.8 (46.9%) 11:31:54 INFO - TinderboxPrint: CPU system
1,021.6 (20.3%) 11:31:54 INFO - TinderboxPrint: CPU user
1,651.3 (32.8%) 11:31:54 INFO - TinderboxPrint: Swap in / out
275,349,504 / 0 11:31:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 223195648; Write bytes: 217298944; Read time: 16659; Write time: 431 11:31:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:31:54 INFO - run-tests - Wall time: 1238s; CPU: 53.0%; Read bytes: 35394048; Write bytes: 317321216; Read time: 318; Write time: 1491 11:31:54 INFO - Running post-run listener: _upload_blobber_files 11:31:54 INFO - Blob upload gear active. 11:31:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:31:54 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 11:31:54 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'] 11:31:54 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 11:31:55 INFO - (blobuploader) - INFO - Open directory for files ... 11:31:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:31:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:31:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:31:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:31:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:31:56 INFO - (blobuploader) - INFO - Done attempting. 11:31:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:31:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:31:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:31:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:31:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:31:57 INFO - (blobuploader) - INFO - Done attempting. 11:31:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:31:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:31:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:31:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:31:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:31:58 INFO - (blobuploader) - INFO - Done attempting. 11:31:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:31:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:31:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:32:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:00 INFO - (blobuploader) - INFO - Done attempting. 11:32:00 INFO - (blobuploader) - INFO - Iteration through files over. 11:32:00 INFO - Return code: 0 11:32:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:32:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:32:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3c86119d7ccf7e62bc9dbb5a3111821a8151265a24ead1535cfef1a2fa9be426db82625a67ef52d8988a2686ebde9ea7bc85777f27c304546cb56a666a7b019", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46f3817904259f688dff2588f11493c7cdfbb88bcac499b0357e98538ebdcc3341709ab7478c37deec3ef4e3127f0080b6d86184a7211104f9859bcefc9a3af8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc604aeacfb808246543b2ff7ffc81c0ae2ec5f4a0183f38671d652f967e45ac854b662d743ed9f5d8568576adaca4196c8eccab2337a679f2db8153277a313a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f63bf12977ff6c84aa3b01756a0ae9bbce13c339a05c145861c4a246616ea82e0ed55e488f0111c184fc6a522cab73aedc0f57c9dc9523e485b3d5f404699473"} 11:32:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:32:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:32:00 INFO - Contents: 11:32:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3c86119d7ccf7e62bc9dbb5a3111821a8151265a24ead1535cfef1a2fa9be426db82625a67ef52d8988a2686ebde9ea7bc85777f27c304546cb56a666a7b019", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46f3817904259f688dff2588f11493c7cdfbb88bcac499b0357e98538ebdcc3341709ab7478c37deec3ef4e3127f0080b6d86184a7211104f9859bcefc9a3af8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc604aeacfb808246543b2ff7ffc81c0ae2ec5f4a0183f38671d652f967e45ac854b662d743ed9f5d8568576adaca4196c8eccab2337a679f2db8153277a313a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f63bf12977ff6c84aa3b01756a0ae9bbce13c339a05c145861c4a246616ea82e0ed55e488f0111c184fc6a522cab73aedc0f57c9dc9523e485b3d5f404699473"} 11:32:00 INFO - Running post-run listener: copy_logs_to_upload_dir 11:32:00 INFO - Copying logs to upload dir... 11:32:00 INFO - mkdir: /builds/slave/test/build/upload/logs 11:32:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.509801 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 11:32:00.300991) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 11:32:00.304744) ========= 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.0PpDRKbIIT/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.BXtworqFj8/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/a3c86119d7ccf7e62bc9dbb5a3111821a8151265a24ead1535cfef1a2fa9be426db82625a67ef52d8988a2686ebde9ea7bc85777f27c304546cb56a666a7b019", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46f3817904259f688dff2588f11493c7cdfbb88bcac499b0357e98538ebdcc3341709ab7478c37deec3ef4e3127f0080b6d86184a7211104f9859bcefc9a3af8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc604aeacfb808246543b2ff7ffc81c0ae2ec5f4a0183f38671d652f967e45ac854b662d743ed9f5d8568576adaca4196c8eccab2337a679f2db8153277a313a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f63bf12977ff6c84aa3b01756a0ae9bbce13c339a05c145861c4a246616ea82e0ed55e488f0111c184fc6a522cab73aedc0f57c9dc9523e485b3d5f404699473"} build_url:https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011476 build_url: 'https://queue.taskcluster.net/v1/task/MH3XabySQDmS3uPXskJv2w/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3c86119d7ccf7e62bc9dbb5a3111821a8151265a24ead1535cfef1a2fa9be426db82625a67ef52d8988a2686ebde9ea7bc85777f27c304546cb56a666a7b019", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/46f3817904259f688dff2588f11493c7cdfbb88bcac499b0357e98538ebdcc3341709ab7478c37deec3ef4e3127f0080b6d86184a7211104f9859bcefc9a3af8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dc604aeacfb808246543b2ff7ffc81c0ae2ec5f4a0183f38671d652f967e45ac854b662d743ed9f5d8568576adaca4196c8eccab2337a679f2db8153277a313a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f63bf12977ff6c84aa3b01756a0ae9bbce13c339a05c145861c4a246616ea82e0ed55e488f0111c184fc6a522cab73aedc0f57c9dc9523e485b3d5f404699473"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 11:32:00.344425) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 11:32:00.345104) ========= 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.0PpDRKbIIT/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.BXtworqFj8/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.004756 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 11:32:00.396787) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 11:32:00.397378) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 11:32:00.397802) ========= ========= Total master_lag: 0.20 =========