builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0332 starttime: 1497372760.27 results: success (0) revision: 91134c95d68cbcfe984211fa3cbd28d610361ef1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:40.267783) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:40.268241) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:40.268522) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:40.292750) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:40.293011) ========= 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.Lb2I77847Q/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.uPqCug8fCO/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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-13 09:52:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2017-06-13 09:52:40 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.115566 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:40.430974) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:40.431276) ========= 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.Lb2I77847Q/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.uPqCug8fCO/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.038484 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:40.489588) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 09:52:40.489877) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 91134c95d68cbcfe984211fa3cbd28d610361ef1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 91134c95d68cbcfe984211fa3cbd28d610361ef1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Lb2I77847Q/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.uPqCug8fCO/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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-13 09:52:40,525 truncating revision to first 12 chars 2017-06-13 09:52:40,525 Setting DEBUG logging. 2017-06-13 09:52:40,525 attempt 1/10 2017-06-13 09:52:40,525 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/91134c95d68c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 09:52:41,977 unpacking tar archive at: mozilla-central-91134c95d68c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.729767 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 09:52:42.250931) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:42.251294) ========= script_repo_revision: 91134c95d68cbcfe984211fa3cbd28d610361ef1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:42.251719) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:42.252036) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 09:52:42.274078) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-13 09:52:42.274497) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Lb2I77847Q/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.uPqCug8fCO/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:52:42 INFO - MultiFileLogger online at 20170613 09:52:42 in /builds/slave/test 09:52:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:52:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:52:42 INFO - {'append_to_log': False, 09:52:42 INFO - 'base_work_dir': '/builds/slave/test', 09:52:42 INFO - 'blob_upload_branch': 'Firefox', 09:52:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:52:42 INFO - 'branch': 'Firefox', 09:52:42 INFO - 'buildbot_json_path': 'buildprops.json', 09:52:42 INFO - 'config_files': ('talos/mac_config.py',), 09:52:42 INFO - 'default_actions': ('clobber', 09:52:42 INFO - 'read-buildbot-config', 09:52:42 INFO - 'download-and-extract', 09:52:42 INFO - 'populate-webroot', 09:52:42 INFO - 'create-virtualenv', 09:52:42 INFO - 'install', 09:52:42 INFO - 'setup-mitmproxy', 09:52:42 INFO - 'run-tests'), 09:52:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:52:42 INFO - 'download_minidump_stackwalk': True, 09:52:42 INFO - 'download_symbols': 'ondemand', 09:52:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:52:42 INFO - 'tooltool.py': '/tools/tooltool.py', 09:52:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:52:42 INFO - '/tools/misc-python/virtualenv.py')}, 09:52:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:52:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:52:42 INFO - 'gecko_profile': False, 09:52:42 INFO - 'gecko_profile_interval': 0, 09:52:42 INFO - 'installer_path': 'installer.exe', 09:52:42 INFO - 'log_level': 'info', 09:52:42 INFO - 'log_name': 'talos', 09:52:42 INFO - 'log_to_console': True, 09:52:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:52:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:52:42 INFO - 'opt_config_files': (), 09:52:42 INFO - 'pip_index': False, 09:52:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:52:42 INFO - 'cmd': ('bash', 09:52:42 INFO - '-c', 09:52:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:52:42 INFO - 'enabled': True, 09:52:42 INFO - 'halt_on_failure': False, 09:52:42 INFO - 'name': 'check_screen_resolution'},), 09:52:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:52:42 INFO - 'cmd': ('bash', 09:52:42 INFO - '-c', 09:52:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:52:42 INFO - 'enabled': True, 09:52:42 INFO - 'halt_on_failure': False, 09:52:42 INFO - 'name': 'check_screen_resolution'},), 09:52:42 INFO - 'run_cmd_checks_enabled': True, 09:52:42 INFO - 'suite': 'dromaeojs-e10s', 09:52:42 INFO - 'system_bits': '32', 09:52:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:52:42 INFO - 'title': 't-yosemite-r7-0332', 09:52:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:52:42 INFO - 'use_talos_json': True, 09:52:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:52:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:52:42 INFO - 'work_dir': 'build'} 09:52:42 INFO - [mozharness: 2017-06-13 16:52:42.422596Z] Running clobber step. 09:52:42 INFO - Running pre-action listener: _resource_record_pre_action 09:52:42 INFO - Running main action method: clobber 09:52:42 INFO - rmtree: /builds/slave/test/build 09:52:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:52:44 INFO - Running post-action listener: _resource_record_post_action 09:52:44 INFO - [mozharness: 2017-06-13 16:52:44.864130Z] Finished clobber step (success) 09:52:44 INFO - [mozharness: 2017-06-13 16:52:44.864290Z] Running read-buildbot-config step. 09:52:44 INFO - Running pre-action listener: _resource_record_pre_action 09:52:44 INFO - Running main action method: read_buildbot_config 09:52:44 INFO - Using buildbot properties: 09:52:44 INFO - { 09:52:44 INFO - "project": "", 09:52:44 INFO - "product": "firefox", 09:52:44 INFO - "who": "ffxbld@noreply.mozilla.org", 09:52:44 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 09:52:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:52:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:52:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:52:44 INFO - "stage_platform": "macosx64", 09:52:44 INFO - "basedir": "/builds/slave/test", 09:52:44 INFO - "buildnumber": 181, 09:52:44 INFO - "platform": "yosemite_r7", 09:52:44 INFO - "parent_task_id": "CGxDdzZ1RLOP0JvldV_Bew", 09:52:44 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:52:44 INFO - "slavebuilddir": "test", 09:52:44 INFO - "taskId": "QD4ceimJStOC8_V2Xebbzw", 09:52:44 INFO - "branch": "mozilla-central", 09:52:44 INFO - "script_repo_revision": "production", 09:52:44 INFO - "revision": "91134c95d68cbcfe984211fa3cbd28d610361ef1", 09:52:44 INFO - "slavename": "t-yosemite-r7-0332", 09:52:44 INFO - "repo_path": "mozilla-central" 09:52:44 INFO - } 09:52:44 INFO - Finding installer, test and symbols from parent task. 09:52:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QD4ceimJStOC8_V2Xebbzw'}, attempt #1 09:52:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QD4ceimJStOC8_V2Xebbzw'}, attempt #1 09:52:46 INFO - Task dependencies: Ogvhun4uStSUG2Ppx0QOnw CGxDdzZ1RLOP0JvldV_Bew 09:52:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew'}, attempt #1 09:52:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 09:52:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/test_packages.json 09:52:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/target.crashreporter-symbols.zip 09:52:46 INFO - Running post-action listener: _resource_record_post_action 09:52:46 INFO - [mozharness: 2017-06-13 16:52:46.750954Z] Finished read-buildbot-config step (success) 09:52:46 INFO - [mozharness: 2017-06-13 16:52:46.751099Z] Running download-and-extract step. 09:52:46 INFO - Running pre-action listener: _resource_record_pre_action 09:52:46 INFO - Running main action method: download_and_extract 09:52:46 INFO - mkdir: /builds/slave/test/build/tests 09:52:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:46 INFO - trying https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 09:52:46 INFO - Downloading https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 09:52:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 09:52:47 INFO - Downloaded 1328 bytes. 09:52:47 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 09:52:47 INFO - Using the following test package requirements: 09:52:47 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:52:47 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 09:52:47 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 09:52:47 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:52:47 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 09:52:47 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:52:47 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 09:52:47 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:52:47 INFO - u'jsshell-mac.zip'], 09:52:47 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:52:47 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 09:52:47 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 09:52:47 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:52:47 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 09:52:47 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:52:47 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 09:52:47 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:52:47 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 09:52:47 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 09:52:47 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 09:52:47 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:52:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 09:52:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:52:47 INFO - Fetch https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 09:52:49 INFO - Content-Length response header: 59541704 09:52:49 INFO - Bytes received: 59541704 09:53:07 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:53:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 09:53:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:53:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 09:53:09 INFO - Content-Length response header: 59541704 09:53:09 INFO - Bytes received: 59541704 09:53:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 09:53:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:53:26 INFO - Fetch https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 09:53:27 INFO - Content-Length response header: 14450022 09:53:27 INFO - Bytes received: 14450022 09:53:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:28 INFO - trying https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 09:53:28 INFO - Downloading https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 09:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 09:53:30 INFO - Downloaded 69328622 bytes. 09:53:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 09:53:30 INFO - mkdir: /builds/slave/test/properties 09:53:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:53:30 INFO - Writing to file /builds/slave/test/properties/build_url 09:53:30 INFO - Contents: 09:53:30 INFO - build_url:https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 09:53:30 INFO - Running post-action listener: _resource_record_post_action 09:53:30 INFO - Running post-action listener: set_extra_try_arguments 09:53:30 INFO - [mozharness: 2017-06-13 16:53:30.327041Z] Finished download-and-extract step (success) 09:53:30 INFO - [mozharness: 2017-06-13 16:53:30.327181Z] Running populate-webroot step. 09:53:30 INFO - Running pre-action listener: _resource_record_pre_action 09:53:30 INFO - Running main action method: populate_webroot 09:53:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:53:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:53:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:53:30 INFO - '--tppagecycles', 09:53:30 INFO - '7'], 09:53:30 INFO - 'tests': ['tsvgm']}}, 09:53:30 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:53:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:53:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:53:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:53:30 INFO - 'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:53:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:53:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:53:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:53:30 INFO - 'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['damp', 'tps']}, 09:53:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:53:30 INFO - 'tests': ['damp', 'tps']}, 09:53:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['dromaeo_dom']}, 09:53:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:53:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:53:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:53:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:53:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:53:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['a11yr', 09:53:30 INFO - 'ts_paint', 09:53:30 INFO - 'tpaint', 09:53:30 INFO - 'sessionrestore', 09:53:30 INFO - 'sessionrestore_no_auto_restore', 09:53:30 INFO - 'tabpaint']}, 09:53:30 INFO - 'other-e10s': {'tests': ['a11yr', 09:53:30 INFO - 'ts_paint', 09:53:30 INFO - 'tpaint', 09:53:30 INFO - 'sessionrestore', 09:53:30 INFO - 'sessionrestore_no_auto_restore', 09:53:30 INFO - 'tabpaint']}, 09:53:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:53:30 INFO - 'ts_paint', 09:53:30 INFO - 'tpaint', 09:53:30 INFO - 'sessionrestore', 09:53:30 INFO - 'sessionrestore_no_auto_restore', 09:53:30 INFO - 'tabpaint']}, 09:53:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:53:30 INFO - 'ts_paint', 09:53:30 INFO - 'tpaint', 09:53:30 INFO - 'sessionrestore', 09:53:30 INFO - 'sessionrestore_no_auto_restore', 09:53:30 INFO - 'tabpaint']}, 09:53:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['a11yr', 09:53:30 INFO - 'ts_paint', 09:53:30 INFO - 'tpaint', 09:53:30 INFO - 'sessionrestore', 09:53:30 INFO - 'sessionrestore_no_auto_restore', 09:53:30 INFO - 'tabpaint']}, 09:53:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['a11yr', 09:53:30 INFO - 'ts_paint', 09:53:30 INFO - 'tpaint', 09:53:30 INFO - 'sessionrestore', 09:53:30 INFO - 'sessionrestore_no_auto_restore', 09:53:30 INFO - 'tabpaint']}, 09:53:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:53:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:53:30 INFO - 'bloom_basic_ref']}, 09:53:30 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:53:30 INFO - 'talos_options': ['--mitmproxy', 09:53:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:53:30 INFO - '--firstNonBlankPaint'], 09:53:30 INFO - 'tests': ['quantum_pageload_google', 09:53:30 INFO - 'quantum_pageload_youtube', 09:53:30 INFO - 'quantum_pageload_amazon', 09:53:30 INFO - 'quantum_pageload_facebook']}, 09:53:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['tsvgx', 09:53:30 INFO - 'tsvgr_opacity', 09:53:30 INFO - 'tart', 09:53:30 INFO - 'tscrollx', 09:53:30 INFO - 'cart', 09:53:30 INFO - 'tsvg_static']}, 09:53:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:53:30 INFO - 'tsvgr_opacity', 09:53:30 INFO - 'tart', 09:53:30 INFO - 'tscrollx', 09:53:30 INFO - 'cart', 09:53:30 INFO - 'tsvg_static']}, 09:53:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:53:30 INFO - 'talos_options': ['--disable-e10s'], 09:53:30 INFO - 'tests': ['tp5o']}, 09:53:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:53:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:53:30 INFO - 'talos_options': ['--disable-e10s', 09:53:30 INFO - '--xperf_path', 09:53:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:53:30 INFO - 'tests': ['tp5n']}, 09:53:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:53:30 INFO - 'talos_options': ['--xperf_path', 09:53:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:53:30 INFO - 'tests': ['tp5n']}}, 09:53:30 INFO - 'talos.zip': {'path': '', 09:53:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:53:30 INFO - Running post-action listener: _resource_record_post_action 09:53:30 INFO - [mozharness: 2017-06-13 16:53:30.350105Z] Finished populate-webroot step (success) 09:53:30 INFO - [mozharness: 2017-06-13 16:53:30.350251Z] Running create-virtualenv step. 09:53:30 INFO - Running pre-action listener: _resource_record_pre_action 09:53:30 INFO - Running main action method: create_virtualenv 09:53:30 INFO - Creating virtualenv /builds/slave/test/build/venv 09:53:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:53:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:53:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:53:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:53:30 INFO - Using real prefix '/tools/python27' 09:53:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:53:31 INFO - Installing distribute.............................................................................................................................................................................................done. 09:53:34 INFO - Installing pip.................done. 09:53:34 INFO - Return code: 0 09:53:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:53:34 INFO - Reading from file tmpfile_stdout 09:53:34 INFO - Output received: 09:53:34 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:53:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53: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', 'pip>=1.5'] in /builds/slave/test/build 09:53: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 pip>=1.5 09:53:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:35 INFO - 'HOME': '/Users/cltbld', 09:53:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:35 INFO - 'LOGNAME': 'cltbld', 09:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:35 INFO - 'MOZ_NO_REMOTE': '1', 09:53:35 INFO - 'NO_EM_RESTART': '1', 09:53:35 INFO - 'PAGER': '/bin/cat', 09:53:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:35 INFO - 'PWD': '/builds/slave/test', 09:53:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:35 INFO - 'SHELL': '/bin/bash', 09:53:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:35 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:35 INFO - 'USER': 'cltbld', 09:53:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:35 INFO - 'XPC_FLAGS': '0x0', 09:53:35 INFO - 'XPC_SERVICE_NAME': '0', 09:53:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:53:35 INFO - Cleaning up... 09:53:35 INFO - Return code: 0 09:53:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:53: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')]} 09:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53: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')]} 09:53:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53: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', '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 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:53: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 psutil>=3.1.1 09:53:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:35 INFO - 'HOME': '/Users/cltbld', 09:53:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:35 INFO - 'LOGNAME': 'cltbld', 09:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:35 INFO - 'MOZ_NO_REMOTE': '1', 09:53:35 INFO - 'NO_EM_RESTART': '1', 09:53:35 INFO - 'PAGER': '/bin/cat', 09:53:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:35 INFO - 'PWD': '/builds/slave/test', 09:53:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:35 INFO - 'SHELL': '/bin/bash', 09:53:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:35 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:35 INFO - 'USER': 'cltbld', 09:53:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:35 INFO - 'XPC_FLAGS': '0x0', 09:53:35 INFO - 'XPC_SERVICE_NAME': '0', 09:53:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:35 INFO - Downloading/unpacking psutil>=3.1.1 09:53: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 09:53: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 09:53: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 09:53: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 09:53:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:53:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:53:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:53:38 INFO - Installing collected packages: psutil 09:53:38 INFO - Running setup.py install for psutil 09:53:38 INFO - building 'psutil._psutil_osx' extension 09:53:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:53:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:53:38 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:53:38 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:53:39 INFO - building 'psutil._psutil_posix' extension 09:53:39 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:53:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:53:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:53:39 INFO - ^ 09:53:39 INFO - 1 warning generated. 09:53:39 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:53:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:53:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:53:39 INFO - Successfully installed psutil 09:53:39 INFO - Cleaning up... 09:53:39 INFO - Return code: 0 09:53:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:53: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')]} 09:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53: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')]} 09:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:53: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 mozsystemmonitor==0.3 09:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:39 INFO - 'HOME': '/Users/cltbld', 09:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:39 INFO - 'LOGNAME': 'cltbld', 09:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:39 INFO - 'MOZ_NO_REMOTE': '1', 09:53:39 INFO - 'NO_EM_RESTART': '1', 09:53:39 INFO - 'PAGER': '/bin/cat', 09:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:39 INFO - 'PWD': '/builds/slave/test', 09:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:39 INFO - 'SHELL': '/bin/bash', 09:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:39 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:39 INFO - 'USER': 'cltbld', 09:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:39 INFO - 'XPC_FLAGS': '0x0', 09:53:39 INFO - 'XPC_SERVICE_NAME': '0', 09:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:53: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 09:53: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 09:53: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 09:53: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 09:53:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:53:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:53:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:53:41 INFO - Installing collected packages: mozsystemmonitor 09:53:41 INFO - Running setup.py install for mozsystemmonitor 09:53:41 INFO - Successfully installed mozsystemmonitor 09:53:41 INFO - Cleaning up... 09:53:41 INFO - Return code: 0 09:53:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:53:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:53:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:53:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:41 INFO - 'HOME': '/Users/cltbld', 09:53:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:41 INFO - 'LOGNAME': 'cltbld', 09:53:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:41 INFO - 'MOZ_NO_REMOTE': '1', 09:53:41 INFO - 'NO_EM_RESTART': '1', 09:53:41 INFO - 'PAGER': '/bin/cat', 09:53:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:41 INFO - 'PWD': '/builds/slave/test', 09:53:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:41 INFO - 'SHELL': '/bin/bash', 09:53:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:41 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:41 INFO - 'USER': 'cltbld', 09:53:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:41 INFO - 'XPC_FLAGS': '0x0', 09:53:41 INFO - 'XPC_SERVICE_NAME': '0', 09:53:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:41 INFO - Downloading/unpacking jsonschema==2.5.1 09:53: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 09:53: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 09:53: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 09:53: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 09:53:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:53:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:53: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 09:53: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 09:53: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 09:53: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 09:53:43 INFO - Downloading functools32-3.2.3-2.tar.gz 09:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:53:43 INFO - warning: no files found matching '*.txt' 09:53:43 INFO - no previously-included directories found matching 'build' 09:53:43 INFO - no previously-included directories found matching 'dist' 09:53:43 INFO - no previously-included directories found matching '.git*' 09:53:43 INFO - Installing collected packages: jsonschema, functools32 09:53:43 INFO - Running setup.py install for functools32 09:53:43 INFO - warning: no files found matching '*.txt' 09:53:43 INFO - no previously-included directories found matching 'build' 09:53:43 INFO - no previously-included directories found matching 'dist' 09:53:43 INFO - no previously-included directories found matching '.git*' 09:53:43 INFO - Successfully installed jsonschema functools32 09:53:43 INFO - Cleaning up... 09:53:43 INFO - Return code: 0 09:53:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:53: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')]} 09:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53: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')]} 09:53:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:43 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 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:53:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:43 INFO - 'HOME': '/Users/cltbld', 09:53:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:43 INFO - 'LOGNAME': 'cltbld', 09:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:43 INFO - 'MOZ_NO_REMOTE': '1', 09:53:43 INFO - 'NO_EM_RESTART': '1', 09:53:43 INFO - 'PAGER': '/bin/cat', 09:53:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:43 INFO - 'PWD': '/builds/slave/test', 09:53:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:43 INFO - 'SHELL': '/bin/bash', 09:53:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:43 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:43 INFO - 'USER': 'cltbld', 09:53:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:43 INFO - 'XPC_FLAGS': '0x0', 09:53:43 INFO - 'XPC_SERVICE_NAME': '0', 09:53:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:53:43 INFO - Cleaning up... 09:53:43 INFO - Return code: 0 09:53:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:53: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')]} 09:53:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53: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')]} 09:53:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:43 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 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:53:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:43 INFO - 'HOME': '/Users/cltbld', 09:53:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:43 INFO - 'LOGNAME': 'cltbld', 09:53:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:43 INFO - 'MOZ_NO_REMOTE': '1', 09:53:43 INFO - 'NO_EM_RESTART': '1', 09:53:43 INFO - 'PAGER': '/bin/cat', 09:53:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:43 INFO - 'PWD': '/builds/slave/test', 09:53:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:43 INFO - 'SHELL': '/bin/bash', 09:53:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:43 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:43 INFO - 'USER': 'cltbld', 09:53:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:43 INFO - 'XPC_FLAGS': '0x0', 09:53:43 INFO - 'XPC_SERVICE_NAME': '0', 09:53:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:44 INFO - Downloading/unpacking blobuploader==1.2.4 09:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:45 INFO - Downloading blobuploader-1.2.4.tar.gz 09:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:53:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:53:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:46 INFO - Downloading docopt-0.6.1.tar.gz 09:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:53:46 INFO - Installing collected packages: blobuploader, requests, docopt 09:53:46 INFO - Running setup.py install for blobuploader 09:53:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:53:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:53:46 INFO - Running setup.py install for requests 09:53:47 INFO - Running setup.py install for docopt 09:53:47 INFO - Successfully installed blobuploader requests docopt 09:53:47 INFO - Cleaning up... 09:53:47 INFO - Return code: 0 09:53:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:53:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:47 INFO - 'HOME': '/Users/cltbld', 09:53:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:47 INFO - 'LOGNAME': 'cltbld', 09:53:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:47 INFO - 'MOZ_NO_REMOTE': '1', 09:53:47 INFO - 'NO_EM_RESTART': '1', 09:53:47 INFO - 'PAGER': '/bin/cat', 09:53:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:47 INFO - 'PWD': '/builds/slave/test', 09:53:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:47 INFO - 'SHELL': '/bin/bash', 09:53:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:47 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:47 INFO - 'USER': 'cltbld', 09:53:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:47 INFO - 'XPC_FLAGS': '0x0', 09:53:47 INFO - 'XPC_SERVICE_NAME': '0', 09:53:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:53:47 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-8fpSro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:53:47 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-EShklB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:53:47 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-IAekud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:53:47 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-LOR1el-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:53:47 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Il6QFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-zZp_U_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-_OhPcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Zo3DQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-CVF8Bz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-bYUe7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-EmIAWi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-iUFHQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-FsX3YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-mdj6MU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:48 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-7xe4bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:53:49 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-dv_ByM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:53:49 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-jfDPTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:53:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:53:49 INFO - Running setup.py install for manifestparser 09:53:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:53:49 INFO - Running setup.py install for mozcrash 09:53:49 INFO - Running setup.py install for mozdebug 09:53:49 INFO - Running setup.py install for mozdevice 09:53:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:53:49 INFO - Running setup.py install for mozfile 09:53:49 INFO - Running setup.py install for mozhttpd 09:53:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:53:50 INFO - Running setup.py install for mozinfo 09:53:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:53:50 INFO - Running setup.py install for mozInstall 09:53:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:53:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:53:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:53:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:53:50 INFO - Running setup.py install for mozleak 09:53:50 INFO - Running setup.py install for mozlog 09:53:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:53:50 INFO - Running setup.py install for moznetwork 09:53:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:53:50 INFO - Running setup.py install for mozprocess 09:53:51 INFO - Running setup.py install for mozprofile 09:53:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:53:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:53:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:53:51 INFO - Running setup.py install for mozrunner 09:53:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:53:51 INFO - Running setup.py install for mozscreenshot 09:53:51 INFO - Running setup.py install for moztest 09:53:51 INFO - Running setup.py install for mozversion 09:53:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:53:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:53:51 INFO - Cleaning up... 09:53:51 INFO - Return code: 0 09:53:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:51 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 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:53:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:53:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:51 INFO - 'HOME': '/Users/cltbld', 09:53:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:51 INFO - 'LOGNAME': 'cltbld', 09:53:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:51 INFO - 'MOZ_NO_REMOTE': '1', 09:53:51 INFO - 'NO_EM_RESTART': '1', 09:53:51 INFO - 'PAGER': '/bin/cat', 09:53:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:51 INFO - 'PWD': '/builds/slave/test', 09:53:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:51 INFO - 'SHELL': '/bin/bash', 09:53:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:51 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:51 INFO - 'USER': 'cltbld', 09:53:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:51 INFO - 'XPC_FLAGS': '0x0', 09:53:51 INFO - 'XPC_SERVICE_NAME': '0', 09:53:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:53:52 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-WdxsZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:53:52 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-rbQe0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:53:52 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-1aDnu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:53:52 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Lc97Ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:53:52 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)) 09:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:53:52 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-puEEi6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:53:52 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-h2wHc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:53:52 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-y2vIdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:53:52 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)) 09:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:53:52 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-oJoeWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:53:52 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)) 09:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:53:52 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-F0lrSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:53:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-o_a2PF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:53:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-NK5fou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:53:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-O2Um1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:53:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ocTUGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:53:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-mkfiNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-x2LUqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:53:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-pQ_HTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:53:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-kqzjV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:53:53 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)) 09:53:53 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)) 09:53:53 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)) 09:53:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:55 INFO - Downloading blessings-1.6.tar.gz 09:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:53:55 INFO - Installing collected packages: blessings 09:53:55 INFO - Running setup.py install for blessings 09:53:55 INFO - Successfully installed blessings 09:53:55 INFO - Cleaning up... 09:53:55 INFO - Return code: 0 09:53:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:53:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:55 INFO - Reading from file tmpfile_stdout 09:53:55 INFO - Current package versions: 09:53:55 INFO - blessings == 1.6 09:53:55 INFO - blobuploader == 1.2.4 09:53:55 INFO - docopt == 0.6.1 09:53:55 INFO - functools32 == 3.2.3-2 09:53:55 INFO - jsonschema == 2.5.1 09:53:55 INFO - manifestparser == 1.1 09:53:55 INFO - mozInstall == 1.13 09:53:55 INFO - mozcrash == 1.0 09:53:55 INFO - mozdebug == 0.1 09:53:55 INFO - mozdevice == 0.51 09:53:55 INFO - mozfile == 1.2 09:53:55 INFO - mozhttpd == 0.7 09:53:55 INFO - mozinfo == 0.10 09:53:55 INFO - mozleak == 0.1 09:53:55 INFO - mozlog == 3.4 09:53:55 INFO - moznetwork == 0.27 09:53:55 INFO - mozprocess == 0.25 09:53:55 INFO - mozprofile == 0.28 09:53:55 INFO - mozrunner == 6.13 09:53:55 INFO - mozscreenshot == 0.1 09:53:55 INFO - mozsystemmonitor == 0.3 09:53:55 INFO - moztest == 0.8 09:53:55 INFO - mozversion == 1.4 09:53:55 INFO - psutil == 3.1.1 09:53:55 INFO - requests == 1.2.3 09:53:55 INFO - wsgiref == 0.1.2 09:53:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:55 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 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:53:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:53:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:55 INFO - 'HOME': '/Users/cltbld', 09:53:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:55 INFO - 'LOGNAME': 'cltbld', 09:53:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:55 INFO - 'MOZ_NO_REMOTE': '1', 09:53:55 INFO - 'NO_EM_RESTART': '1', 09:53:55 INFO - 'PAGER': '/bin/cat', 09:53:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:55 INFO - 'PWD': '/builds/slave/test', 09:53:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:55 INFO - 'SHELL': '/bin/bash', 09:53:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:55 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:55 INFO - 'USER': 'cltbld', 09:53:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:55 INFO - 'XPC_FLAGS': '0x0', 09:53:55 INFO - 'XPC_SERVICE_NAME': '0', 09:53:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:53:56 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:53:57 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:53:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:53:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:53:57 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)) 09:53:57 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)) 09:53:57 INFO - Installing collected packages: marionette-driver 09:53:57 INFO - Running setup.py install for marionette-driver 09:53:57 INFO - Successfully installed marionette-driver 09:53:57 INFO - Cleaning up... 09:53:57 INFO - Return code: 0 09:53:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:53:57 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 0x102460c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102464768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3c844dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, '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.Lb2I77847Q/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.uPqCug8fCO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:53:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:53:57 INFO - 'HOME': '/Users/cltbld', 09:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:53:57 INFO - 'LOGNAME': 'cltbld', 09:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:53:57 INFO - 'MOZ_NO_REMOTE': '1', 09:53:57 INFO - 'NO_EM_RESTART': '1', 09:53:57 INFO - 'PAGER': '/bin/cat', 09:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:53:57 INFO - 'PWD': '/builds/slave/test', 09:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:53:57 INFO - 'SHELL': '/bin/bash', 09:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:53:57 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:53:57 INFO - 'USER': 'cltbld', 09:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:53:57 INFO - 'XPC_FLAGS': '0x0', 09:53:57 INFO - 'XPC_SERVICE_NAME': '0', 09:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:53:58 INFO - Cleaning up... 09:53:58 INFO - Return code: 0 09:53:58 INFO - Running post-action listener: _resource_record_post_action 09:53:58 INFO - Running post-action listener: _start_resource_monitoring 09:53:58 INFO - Starting resource monitoring. 09:53:58 INFO - [mozharness: 2017-06-13 16:53:58.155422Z] Finished create-virtualenv step (success) 09:53:58 INFO - [mozharness: 2017-06-13 16:53:58.156397Z] Running install step. 09:53:58 INFO - Running pre-action listener: _resource_record_pre_action 09:53:58 INFO - Running main action method: install 09:53:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:53:58 INFO - Reading from file tmpfile_stdout 09:53:58 INFO - Detecting whether we're running mozinstall >=1.0... 09:53:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:53:58 INFO - Reading from file tmpfile_stdout 09:53:58 INFO - Output received: 09:53:58 INFO - Usage: mozinstall [options] installer 09:53:58 INFO - Options: 09:53:58 INFO - -h, --help show this help message and exit 09:53:58 INFO - -d DEST, --destination=DEST 09:53:58 INFO - Directory to install application into. [default: 09:53:58 INFO - "/builds/slave/test"] 09:53:58 INFO - --app=APP Application being installed. [default: firefox] 09:53:58 INFO - mkdir: /builds/slave/test/build/application 09:53:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:54:20 INFO - Reading from file tmpfile_stdout 09:54:20 INFO - Output received: 09:54:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:54:20 INFO - Running post-action listener: _resource_record_post_action 09:54:20 INFO - [mozharness: 2017-06-13 16:54:20.843367Z] Finished install step (success) 09:54:20 INFO - [mozharness: 2017-06-13 16:54:20.843551Z] Running setup-mitmproxy step. 09:54:20 INFO - Running pre-action listener: _resource_record_pre_action 09:54:20 INFO - Running main action method: setup_mitmproxy 09:54:20 INFO - Skipping: mitmproxy is not required 09:54:20 INFO - Running post-action listener: _resource_record_post_action 09:54:20 INFO - [mozharness: 2017-06-13 16:54:20.844206Z] Finished setup-mitmproxy step (success) 09:54:20 INFO - [mozharness: 2017-06-13 16:54:20.844329Z] Running run-tests step. 09:54:20 INFO - Running pre-action listener: _resource_record_pre_action 09:54:20 INFO - Running main action method: run_tests 09:54:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:54:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:54:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:54:20 INFO - 2017-06-13 09:54:20.859 screenresolution[1628:7837] starting screenresolution argv=screenresolution get 09:54:20 INFO - 2017-06-13 09:54:20.861 screenresolution[1628:7837] Display 0: 1600x1200x32@60 09:54:20 INFO - 2017-06-13 09:54:20.867 screenresolution[1629:7840] starting screenresolution argv=screenresolution list 09:54:20 INFO - Available Modes on Display 0 09:54:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:54:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:54:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:54:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:54:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:54:21 INFO - Intel Iris: 09:54:21 INFO - Chipset Model: Intel Iris 09:54:21 INFO - Type: GPU 09:54:21 INFO - Bus: Built-In 09:54:21 INFO - VRAM (Dynamic, Max): 1536 MB 09:54:21 INFO - Vendor: Intel (0x8086) 09:54:21 INFO - Device ID: 0x0a2e 09:54:21 INFO - Revision ID: 0x0009 09:54:21 INFO - Displays: 09:54:21 INFO - Display: 09:54:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:54:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:54:21 INFO - Display Serial Number: 1600x1200 60 09:54:21 INFO - Main Display: Yes 09:54:21 INFO - Mirror: Off 09:54:21 INFO - Online: Yes 09:54:21 INFO - Rotation: Supported 09:54:21 INFO - Return code: 0 09:54:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:54:21 INFO - Python 2.7.3 09:54:21 INFO - Return code: 0 09:54:21 INFO - grabbing minidump binary from tooltool 09:54:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:54:21 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 0x1021b6d30>, '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 0x102180cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1021a7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:54:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:54:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:54:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:54:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:54:21 INFO - Return code: 0 09:54:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:54:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:54:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:54:21 INFO - ENV: RUST_BACKTRACE is now 1 09:54:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:54:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:54:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:54:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0332 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:54:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Lb2I77847Q/Render', 09:54:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:54:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:54:21 INFO - 'HOME': '/Users/cltbld', 09:54:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:54:21 INFO - 'LOGNAME': 'cltbld', 09:54:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:54:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:54:21 INFO - 'MOZ_NO_REMOTE': '1', 09:54:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:54:21 INFO - 'NO_EM_RESTART': '1', 09:54:21 INFO - 'PAGER': '/bin/cat', 09:54:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:54:21 INFO - 'PWD': '/builds/slave/test', 09:54:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:54:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:54:21 INFO - 'RUST_BACKTRACE': '1', 09:54:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:54:21 INFO - 'SHELL': '/bin/bash', 09:54:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uPqCug8fCO/Listeners', 09:54:21 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:54:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:54:21 INFO - 'USER': 'cltbld', 09:54:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:54:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:54:21 INFO - 'XPC_FLAGS': '0x0', 09:54:21 INFO - 'XPC_SERVICE_NAME': '0', 09:54:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:54:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:54:21 INFO - mozversion application_buildid: 20170613084052 09:54:21 INFO - mozversion application_changeset: 91134c95d68cbcfe984211fa3cbd28d610361ef1 09:54:21 INFO - mozversion application_display_name: Nightly 09:54:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:54:21 INFO - mozversion application_name: Firefox 09:54:21 INFO - mozversion application_remotingname: firefox 09:54:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:54:21 INFO - mozversion application_vendor: Mozilla 09:54:21 INFO - mozversion application_version: 56.0a1 09:54:21 INFO - mozversion platform_buildid: 20170613084052 09:54:21 INFO - mozversion platform_changeset: 91134c95d68cbcfe984211fa3cbd28d610361ef1 09:54:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:54:21 INFO - mozversion platform_version: 56.0a1 09:54:21 INFO - using testdate: 1497372861 09:54:21 INFO - actual date: 1497372861 09:54:21 INFO - starting webserver on 'localhost:49227' 09:54:21 INFO - SUITE-START | Running 2 tests 09:54:21 INFO - TEST-START | dromaeo_css 09:54:21 INFO - operating with platform_type : mac_ 09:54:21 INFO - Initialising browser for dromaeo_css test... 09:54:21 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpQ0cxLt/profile 09:54:21 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 09:54:55 INFO - TEST-INFO | 1639: killed by SIGTERM 09:54:55 INFO - Browser initialized. 09:54:55 INFO - Running cycle 1/1 for dromaeo_css test... 09:54:55 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpQ0cxLt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:54:57 INFO - PID 1649 | 2017-06-13 09:54:57.343 plugin-container[1650:8537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 09:54:57 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:57 INFO - PID 1649 | 2017-06-13 09:54:57.344 plugin-container[1650:8537] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a27, name = 'com.apple.CFPasteboardClient' 09:54:57 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:57 INFO - PID 1649 | 2017-06-13 09:54:57.344 plugin-container[1650:8537] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:54:58 INFO - PID 1649 | 2017-06-13 09:54:58.093 plugin-container[1651:8584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x473f, name = 'com.apple.tsm.portname' 09:54:58 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:58 INFO - PID 1649 | 2017-06-13 09:54:58.094 plugin-container[1651:8584] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 09:54:58 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:54:58 INFO - PID 1649 | 2017-06-13 09:54:58.094 plugin-container[1651:8584] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:55:07 INFO - PID 1649 | RSS: Main: 252583936 09:55:07 INFO - PID 1649 | 09:58:09 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 09:58:12 INFO - PID 1649 | RSS: Main: 272539648 09:58:12 INFO - PID 1649 | 10:01:13 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 10:01:14 INFO - PID 1649 | RSS: Main: 279207936 10:01:14 INFO - PID 1649 | 10:04:16 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 10:04:16 INFO - PID 1649 | RSS: Main: 281726976 10:04:16 INFO - PID 1649 | 10:07:18 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 10:07:19 INFO - PID 1649 | RSS: Main: 285892608 10:07:19 INFO - PID 1649 | 10:10:22 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 10:10:22 INFO - PID 1649 | RSS: Main: 287371264 10:10:22 INFO - PID 1649 | 10:13:24 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 10:13:24 INFO - PID 1649 | RSS: Main: 288370688 10:13:24 INFO - PID 1649 | 10:13:24 INFO - PID 1649 | __start_tp_report 10:13:24 INFO - PID 1649 | _x_x_mozilla_page_load 10:13:24 INFO - PID 1649 | _x_x_mozilla_page_load_details 10:13:24 INFO - PID 1649 | |i|pagename|runs| 10:13:24 INFO - PID 1649 | |0;dojo.html;3453.7409694272933;3484.8606055757773;3597.388443964526;3606.8917932462036;3622.058264851138;6103.45068943795;6110.741024289845;6112.959454150319;6134.191681014041;6190.969045154779;6156.846078848031;6189.75240990361;6214.130021796941;6216.720247588858;6220.9377906220925;5424.484673955968;5440.4287549807295;5447.4824891635235;5459.317585301847;5577.749001294951;5455.863603409926;5462;5500.6424582402215;5522.834314970557;5530.142827861668;5291.708956007399;5339.733013349317;5361.195820626937;5379.838604841861;5450.31871016123;5314.202869569595;5314.335708036496;5336.092864213093;5375.892482150374;5413.702246376448;5354;5399.865003374908;5413.593980451482;5453.182022696587;5459.07195776715;6142.385761423867;6142.723577439026;6171.938280617182;6173;6215.7513699451965;5218.243354713583;6418.19772528434;6609;6628.73485060597;6641.80074597758;6610.504212184057;6617;6636.170478690164;6657.101291325657;6683.164604424398;6167.660778657217;6210.316865144876;6236.469900058546;6249;6292.590981586182;6887.139107611549;6897.448204143657;6899.6205208713;6903.723851046002;6912.654367281616;6364.172657554488;6423.614583125028;6447.677616119176;6458.58019228758;6560.212774467094;6592.86814263712;6629.801105966829;6669;6719.160104986877;6738.797836064926;6597.670116494156;6615.76844810439;6619.040239165294;6630.844975382924;6632.303608121174;5584.720763961867;6408.775692850728;6482.060101285381;6612.437942774918;6647.268800431949;5267.183586544015;5268.736563171903;5276.023935571931;5334.733263336895;5366.517013468807;4405.964598319392;4504.459464864237;4695.366125573072;4751.738654373973;4772.331873537708;5193.948060519422;6216.471599914058;6221.81785460761;6298.622082675054;6329.113924050636;6723.798286051427;6734.966325168343;6746.898796518056;6751.0886030386255;6773.72905083801;6201.596896201732;6290.716917738713;6292.528060395489;6304.842378940563;6324.3991820776955;5765.625234359753;5803.303603567599;5827.096799996008;5850.736716847752;5877.118432235199;6729.798106056826;6802.795916122524;6905.551139175937;6906.654667266717;6935.479839012094;5294.311739473844;6749.696263668048;6779.423748981392;6789.388954994074;6802.115724955724;6884.724611015503;6890.345417185359;6910.723571057101;6912.654367281515;6917.239103698488;6614.8346291343105;6629.07192992991;6635.668216589054;6644.335566443317;6663.067170596023;6550.6069635822005;6658.267590565131;6692.899606506004;6759.121314229119;6796.422304104009;5486.643368181056;6528.67356632176;6533.281339052795;6539.346065393613;6542.280349161683;6450.322716114741;6486.708098135689;6503.479721622353;6506.284308726131;6527;6252.812415627538;6324.924762790429;6328.557001009885;6394.360563943755;6420.711068002044;6361.427471527585;6401.231852177769;6413.1021656969;6437.517186210959;6535.705893234911;5374.328208973879;6389.137466441971;6405.679716014088;6408.166938297992;6408.487321014214 10:13:24 INFO - PID 1649 | |1;ext.html;729.2925861913945;789.5894135049773;804.9517028978258;819.6311659753111;854.337888136694;37862;38391.42412863806;38748.61251387489;38784.80607596965;38843.805780971124;34291.14272143191;37696.623033769654;37805.621943780556;37925.620743792555;37963.810180949054;8091.433599648027;8229.382796290272;8250.422470427073;8271.296939760128;8277.296429803473;8082.030156381243;8205.220504052135;8210.958945205266;8215.34277257821;8217.096119426858;8191.467554608961;8213.34293256541;8228.177182281785;8247.257746802787;8256.917430825675;8162.877556836652;8189.672413103469;8213.7125200618;8215.507069575844;8224.218699223564;8003.959980200062;8008.799780005488;8061.395395345314;8062.7581172564915;8064.556449395281;36157.81921090378;36225.63774362249;36282;36356.27287454263;36408.453873191924;3417.7436692247934;3418.384690755663;3448.2758620689683;3451.775634583744;3458.1008937676384;29345.85327073372;29352.412951740847;29421.55867661987;29472.852635736683;29571;2987.2382542451537;3054.266975925762;3088.2896933705147;3089.119600913729;3115.205622566231;300675;302143;302376;303407;303895;46706.299405508944;46731;46800.7659961698;46949.59151225469;47003.76498117556;40140.79929600392;48830.51169488331;48857.511424886005;48922.02155956861;49092.01815963661;14248.643783905278;14261.572152835432;14324.85675143256;14359;14374.85625143756;9753.804923901482;11444.198906076495;11493.885061149449;11632.767344653059;11640.476178571984;11121.777564448666;11348.432578371048;11359.864013598573;11365.431728413547;11420;4415.624671902924;4496;5250.4749525047955;5261.868453288698;5342.198670199501;970.9757256068655;1002.5588740954103;1022.7749895023084;1031.231732359382;1078.4823284823171;22340.110248355282;22719.659205111937;22792.088316467154;23374.7662523376;23850.880745596507;1112.8942750438696;1115.7154925493946;1124.8031594470935;1160.2922217447351;1177.6761390617648;11078.446077696082;11425.428728563538;11563.843615638369;11993.760124797454;12237.571684990982;289783;294538;297413;298078;299155.50422248186;41969.37045944313;41992;42037.57962420337;42149.789251053546;52773;1557.1046648177116;1590.4830929948046;1595.417672549511;1596.6247931736032;1599.6640705451753;24682;25382.235528942118;27930.88276469;29059;29682.851585741933;38045.80977095097;38306.42540361953;38365.040873978374;38377.42433863439;38545.6145438542;298061;300202;300758;300992;301083;35344.46983295201;35391;35572.82213588915;35701.10747231236;35760.02679477477;41723;42049.579504204565;42060;43938.681839544864;44192;26530.734692653597;28179.43641127207;30678.539821902243;30883.227919302197;31172.220694482818;29968.400631987675;30154.3969120612;30593.847030765595;30924.69075309308;31045.379092418476;35549.644503554635;37207.6279237215;37263.813680931424;37385.62614373821;37432;28254.57618135771;28743.712562874105;29899.402011960075;31490.52764208493;31600.683993159775;23307.76692233056;24236.1517346892;24265.75734242635;24319.878400607882;24413.75586244186 10:13:24 INFO - PID 1649 | |2;jquery.html;410.72070991725616;454.67490744117947;492.6969913503194;498.05369797384964;516.1457787361916;21837.781622183775;22511.98696058677;22543.661845072318;22650.433739156528;22697.978590963405;18324.908375458104;18344.899306041614;18395.988220647858;18738.625227495442;22748.31755047351;5874.295084589834;6265.404786545271;6389.329120442357;6404.455621272197;6427.742890284383;6132.816015519542;6441.323661015596;6466.997615369625;6474.579152355105;6476.579022363555;5728.198052272685;6296.905546416808;6422.710978005977;6447.129637498948;6448.097266382711;5554.250176226218;5781.277340332437;6200.255969283715;6238.719257633372;6444.838879028015;5675.772969081232;5709.172170035322;5710.628809127394;5716.428357164292;5739.225204597367;29815.70184298151;29884.252893677833;30286.54570181449;30344.393112137855;30358.482075896336;2181.301983365324;2227.0757635581213;2231.263682984611;2231.955360892773;2232.5646498932715;18578.256869725195;19638.509037274038;19915.90042049795;19990.500237494034;20061.598768024556;2200.6258935980923;2201.4936564590075;2236.083205885579;2241.0363543676212;2255.2783109404995;163146;166979;170984.14507927504;171138;186349;12430.316332601613;14122.505712300226;14544.563663090124;14729.379768225486;15150.924245378923;20012.69980950287;20022.89988550048;20037.49906252326;20052.197912083644;20075.698864517042;7852.646630901623;9593.42439453635;9732.805343893082;9747.268954828416;9843.852342214872;8911.777205569913;8924.152205540464;8965.551722413984;8991.595378207865;8994.550272486349;9069.909300907038;9232.7691807704;9287.58205880737;9295;9319.720408387759;2616.7644911958014;2618.5941179117267;2636.1300770745606;2637.1956553251057;2781;917.8347897378402;927.8747369105084;928.2944961829058;986.9654562090292;987.9407235565889;20109.698354524695;20236.595268094556;20535.58928821445;21422.78577214239;21492.032858521405;951.2437612098398;953.7472569769017;998.0767789794556;1004.9045340692622;1008.2438171371472;8174.591270436574;10720.785584288427;10720.874308197683;10782.946085269523;10976.341419514854;517990.4100479474;521507;527673;534957.6504234908;535045;144110;145897;148825.25587371993;149462;154263;998.6404894238214;1009.3641006166215;1147.913906457019;1161.6050542815315;1189.7679952409371;20810.855402952984;20952.58094838125;22395.888020559792;23059.769402305763;23521;51174.74412627913;65733.34266657272;67564;69310;70766;88912;91091.63362549435;92218;93230.53384733033;93615.53192234268;9235.445873247627;10963.56145754161;11037.72405689832;11122.165837562377;11301.71745706364;14450.638734031734;16753.162341882613;17184.31262749476;17746.46760597184;17788;13822.239776812372;14300.427982880568;14311.141331520143;14622.195779232245;14649.780253295996;11005.669829905115;11276.985071343619;12651.557195498113;13031.47874085026;13246.07277490605;14258.714825703635;14590.489332873298;14796.704065918837;14924.179170145746;15022.924885375503;9517.2862035349;11030.172737044913;11158.274712143684;11229.27009744364;11233.101351891666;11961.222520536137;12217.51129954792;12250.958668513276;12288.447019884305;12296.954758845599 10:13:24 INFO - PID 1649 | |3;mootools.html;1337.665583604099;1481.296384217496;1483.3769816676995;1504.7291487532248;1520.1942970225778;13016.349182540871;13498.190108593479;13510.864891351084;13540;13545.119567228123;11179.776404471906;11435.885641143586;11515.424228788568;13129.080964332501;13475.191488510696;2653.1112077454077;2682.932926676839;2699.500592390404;2701.945961080777;2704.418550011747;2189.824814014875;2639.3269716222335;2671.532481815684;2683.047518131065;2693.2458911504714;2629.8422094674283;2645.629611854342;2647.411553068163;2653.12446892525;2657.8671066446695;2601.479704059186;2603.049886791317;2604.8567328796908;2611.0208671748096;2613.8431694098417;2589.585666293384;2590.1323056776005;2594.4940736556377;2597.06505657963;2599.4281258123183;10567.577296908114;13013.804792928113;13052.54316098932;13113.541026064042;13113.803292950517;6632.602043877335;6868.759593414206;6881.174259088892;6886.552374095668;6890.1731792184755;4323.783810809478;4536.523665015192;4688.929666055012;4702.506236845151;4718.905621887578;1360.081944687332;1381.281733498575;1400.6428360767939;1429.1139493513983;1439.2443966917347;19406.902965485224;19466.416007519656;19566.51083722904;19636.21455141807;19667.311644092388;6650.268470468244;6764.72941082361;6804.319568043156;6946.756863509753;6953.652317384111;16063.919680401523;16214.51356459308;16242.593935151479;16255.51233462998;16277.186140693155;6953.652317384111;7058.611776352247;7089;7105.964470177616;7110.57336559808;5881.705914704247;6066.636001839904;6084;6244.313125556185;6284.937150628435;4467.553244675506;5046.899062018739;5239.73801309933;5246.868828279324;5328.520433161034;1631.2414727151931;1648.2418087679803;1660.1865086107962;1669.7161482547997;1678.2447898445748;815.4373482297196;839.6977088248226;845.226617644848;853.4239388412797;879.9252063574539;6236.719347629368;7203.459740519482;7209.35115839577;7239.384652304613;7275.927240727525;656.2322083162711;672.8116127484312;706.8480276740422;707.4517249131964;710.8507213485123;3598.766080204831;4255.127698821734;4320.3519472078815;4415.889602759957;4552.544745525487;16059.839401606068;16649.3340266388;16693.332266709196;17064.57338566546;17528.035958022178;17417.912910435367;17769.200385982662;17853;18039.909800450914;18147;9210.217131543759;9270.397424167408;9350.766230844283;9426.198773104226;9448.48033358172;6589.439897608565;6990.776614092615;7949.364050875801;7950.125486196398;8175.918240817754;12689.555865545031;15556.766648500055;15771.974821636557;15885.364585416453;15991.520254392386;15966.840331597;16489.752653710442;16559.42042028523;16779.832201678317;16781.91609041947;10413.9479302603;10461.05850473461;10489.79020419572;10556.894431055898;10565.524551395052;12135.696607584881;12316.692082698004;12367;12392.380380980734;12422.254664720145;9553.426794392359;9910.801783964425;10187.84718229242;10217.642382516577;10341.224408169266;9627.229821614394;9630.903690963001;9700.90299097;10013.749656258651;10190.439525825854;9049.32130090259;9114.635414583343;9178.908210917805;9210.618407238968;9223.44659320443;5591.496765291144;5789.189513468202;6452.419282264619;6459.289478157493;6677.966610166918;5987.520998320037;6640.269570347161;6783.491238157257;6946.235914049552;7017.193022802443 10:13:24 INFO - PID 1649 | |4;prototype.html;333.38324100414223;429.8903779536218;438.9626881715055;461.03413349033764;467.1054929809414;5284.233786101018;5457.699826509541;6065.968785306497;6431.356864313572;6531.281559028503;5519.061759500884;6054.182685337478;6294.464970477515;6309.359566512705;6380.106785050097;4172.332426811703;4734.195186818232;4751.5010923853015;4769.284607308914;4769.356136921506;4062.6952978526583;4658.231391820345;4693.202155633533;4739.810407583693;4775.3553270308585;4054.493188351456;4272.50866150392;4400.933985990213;4699.506551812062;4703.882402939919;3963.861264855745;4082.9792551862033;4214.115035842477;4636.814527418899;4737.502562230968;3133.4673105572106;3984.880453586397;4149.294619914622;4154.273002224599;4158.293090174678;5449.863753406157;6117;6133.478654314388;6186.81439556814;6231.22109736283;4896.657233993622;5605.635633683797;5605.859853503654;5627.915581266284;5635.408282130399;6278.811635650892;6342.016987366919;6795.2185498667795;6797.558158719717;6816.284290149512;4129.731567448106;4614.569483460138;4715.575598196145;4726.480087190406;4738.549837765407;7162.4628152888745;7241.384482319009;7244.746433874841;7272.890906636405;7298.635068246619;4028.3554631258858;4806.062817750575;4826.38316164082;4844.95155048452;4855.295982082649;4262.46719160105;4453.421055262795;4879.730236043462;4926.4088309403105;4928.310036594879;4904.926426103611;5595.776168953278;5605.943940560624;5639.492445679908;5641.774329026793;5485.875395543904;6574.704138313788;6843;6848.280930502226;6857.657117144123;1468.5887951373631;1602.8236893941657;1683.9579010524835;1685.4269548353618;1686.3254698120604;1123.674064603759;1124.3366413815888;1131.7679875625477;1136.4373985769566;1137.6075254037344;6405;6433.453156481658;6434.8391290218115;6436.581622194542;6452.22573291208;6507.306107156238;7040.436765058783;7054.717811287594;7069.505134640629;7073.292670732989;6396.825079473001;6448.838779030468;6469.256035555877;6470.935290647033;6495.870082598416;3676.761010534307;3689.335919534456;3701.0960477367594;3701.555813302421;3723.2925744108534;6332.683365831782;6359.3640635936035;6779.15260592426;7287.198408175096;7306.817329566803;6601.2738598754095;7183.066201393785;7248.782536523912;7252.528585641917;7256.637168141572;6320;7228.38558722504;7262.818429539198;7269.527480713737;7275.636218189069;6666.266710661726;6929.925861491477;6995.860082798417;7101.538400003983;7117.4661900358715;6014.699265036643;6951.652417379009;6957.547759395623;6963.7562685305775;7013.964930175316;5894.469497745224;6252.9374706254175;7030.824229394101;7200.603966781668;7213.567185968858;4753.952460475351;4781.848168324198;4791.652090475712;4799.28010798383;4801.4958429365815;6701.296363881722;7069.328413800681;7070.222275549582;7099.858002840017;7135.678894449658;6507.804765857032;6644.4020038196795;6648.301928297653;6798;6812;6012.8797424052145;6448.419642232034;6742.224644165984;6770.32296770319;6777.254502004677;6604.966975165094;6621.768238111643;6623.2182483358065;6647.302033286435;6650.135482387259;6312.873742525216;6421.454176395058;6747.874419557824;6755.662216889037;6793.796186114425;5808.390119037609;6094.390560944047;6587.96706016486;6623.615498420469;6635.63504007265 10:13:24 INFO - PID 1649 | |5;yui.html;828.9212524810143;831.7504748575434;838.6226198210807;862.9266512346451;865.8095219051809;14387.208703521303;16749.1625418729;17197.14014299285;17213;17213.311467541283;12784.872151278485;13072.150310229828;13150.013748968842;13229.603111906634;14117.788233176485;945.3760518058066;956.2541217850088;969.0358093696782;972.2367941166186;972.8735264415651;966.3235734985503;970.7039352997333;974.4250891973734;977.384247923807;980.2011360741009;1009.9798004039915;1013.3008224325229;1013.9746506337327;1014.9695509134738;1015.2132097624363;937.1144268666113;978.921686265097;1016.5577973581516;1054.752133248686;1055.1558752997648;785.3481610263468;788.1291173253529;789.3842802613971;789.8657228271207;790.93277071449;15423.460178893685;16763.83236167635;17346.91326543372;17391.913040434843;17551.73672394915;16253.024818510927;16844.073575953316;16890.98654080759;16960.74558881618;17060.232289547;14182.787258191136;14921.179335136563;15008.324625391884;15030.549083527512;15042.849571504365;3614.638536146391;3654.4335627977707;3659.14010207601;3663.5786884508357;3667.02823751706;101841.98158018399;147755;150050;152771;155552.22223888923;13167.60497185086;13574.185548867099;13829.654258643614;13954.930225348808;14099.224542650252;26451.206463806117;26465.470690586466;26545.734542654325;26572.734272657413;26696.733032669814;7989.360851131895;7994;8011.7195898144455;8054.154313796967;8058.798530036796;3951.209758048402;4018.557958624549;4032.5564187939303;4038.0308725906157;4053.8986525336513;4590.311453282035;4621.907561848744;4634.165850147006;4647.604953578983;4657.464391594942;968.9127978481971;1021.2953062387078;1027.4041056187393;1027.5992362978445;1030.896910308963;641.9632293845406;653.4021370446084;655.7606473637088;677.5494296293009;679.8096532970776;10989.395583242998;11163.553457861757;11436.828447573293;12135.332556709289;12210.816837747441;637.964911929839;644.3813938618885;657.5890068707058;658.5686375424104;673.7843889955266;5490.929268792547;5603.5237004854225;5632.915506267409;5726.828195154068;5869.5010924071075;124728;130223;130778.34610827075;132668;134135.65864341427;93845.53077234571;109001.36497952537;112830.43584782188;114219.42890285661;119350.61298773976;92621.07378926124;94788.52605736926;94876;100962.49518752359;101541;10706.143508519144;10894.836577451186;10940.56237750481;11255.943720281346;11423.543058277908;43490.782546087066;44152.33771493366;46291.76854115708;46475.3028704563;47270.52729472661;61068.38931610627;61416.69291653513;65366;65528;66196;13981.440742370598;14416.855831441551;14811.629709257355;14842.628934276298;14860.108393495992;14214.928925355307;15970.361185552449;16344.427945021867;16481.34074637001;16520;15077.47228847029;16918.746218806973;17744.3789467368;18218.72671909949;18300.81699182991;17138.314467421664;17552.648947021244;17713.911430442764;17723.734143987593;17771.644567108844;14793.852061479678;17019;17274.481765547054;17332.566685832957;17995.190216440555;9345.53272336394;10648.254622176373;11313.886861131612;11394.25937314072;11777.587784427504;12729.536103348233;12882.420291087112;13133.212007279593;13302.800457993331;13421.597352079454 10:13:24 INFO - PID 1649 | __end_tp_report 10:13:24 INFO - PID 1649 | __start_cc_report 10:13:24 INFO - PID 1649 | _x_x_mozilla_cycle_collect,471 10:13:24 INFO - PID 1649 | __end_cc_report 10:13:24 INFO - PID 1649 | __startTimestamp1497374004612__endTimestamp 10:13:24 INFO - PID 1649 | 10:13:24 INFO - PID 1649 | ------- Summary: start ------- 10:13:24 INFO - PID 1649 | Number of tests: 6 10:13:24 INFO - PID 1649 | 10:13:24 INFO - PID 1649 | [#0] dojo.html Cycles:165 Average:6106.29 Median:6329.11 stddev:735.47 (11.6%) stddev-sans-first:707.67 10:13:24 INFO - PID 1649 | Values: 3453.7 3484.9 3597.4 3606.9 3622.1 6103.5 6110.7 6113.0 6134.2 6191.0 6156.8 6189.8 6214.1 6216.7 6220.9 5424.5 5440.4 5447.5 5459.3 5577.7 5455.9 5462.0 5500.6 5522.8 5530.1 5291.7 5339.7 5361.2 5379.8 5450.3 5314.2 5314.3 5336.1 5375.9 5413.7 5354.0 5399.9 5413.6 5453.2 5459.1 6142.4 6142.7 6171.9 6173.0 6215.8 5218.2 6418.2 6609.0 6628.7 6641.8 6610.5 6617.0 6636.2 6657.1 6683.2 6167.7 6210.3 6236.5 6249.0 6292.6 6887.1 6897.4 6899.6 6903.7 6912.7 6364.2 6423.6 6447.7 6458.6 6560.2 6592.9 6629.8 6669.0 6719.2 6738.8 6597.7 6615.8 6619.0 6630.8 6632.3 5584.7 6408.8 6482.1 6612.4 6647.3 5267.2 5268.7 5276.0 5334.7 5366.5 4406.0 4504.5 4695.4 4751.7 4772.3 5193.9 6216.5 6221.8 6298.6 6329.1 6723.8 6735.0 6746.9 6751.1 6773.7 6201.6 6290.7 6292.5 6304.8 6324.4 5765.6 5803.3 5827.1 5850.7 5877.1 6729.8 6802.8 6905.6 6906.7 6935.5 5294.3 6749.7 6779.4 6789.4 6802.1 6884.7 6890.3 6910.7 6912.7 6917.2 6614.8 6629.1 6635.7 6644.3 6663.1 6550.6 6658.3 6692.9 6759.1 6796.4 5486.6 6528.7 6533.3 6539.3 6542.3 6450.3 6486.7 6503.5 6506.3 6527.0 6252.8 6324.9 6328.6 6394.4 6420.7 6361.4 6401.2 6413.1 6437.5 6535.7 5374.3 6389.1 6405.7 6408.2 6408.5 10:13:24 INFO - PID 1649 | 10:13:24 INFO - PID 1649 | [#1] ext.html Cycles:180 Average:44488.81 Median:18869.71 stddev:78538.70 (416.2%) stddev-sans-first:78690.29 10:13:24 INFO - PID 1649 | Values: 729.3 789.6 805.0 819.6 854.3 37862.0 38391.4 38748.6 38784.8 38843.8 34291.1 37696.6 37805.6 37925.6 37963.8 8091.4 8229.4 8250.4 8271.3 8277.3 8082.0 8205.2 8211.0 8215.3 8217.1 8191.5 8213.3 8228.2 8247.3 8256.9 8162.9 8189.7 8213.7 8215.5 8224.2 8004.0 8008.8 8061.4 8062.8 8064.6 36157.8 36225.6 36282.0 36356.3 36408.5 3417.7 3418.4 3448.3 3451.8 3458.1 29345.9 29352.4 29421.6 29472.9 29571.0 2987.2 3054.3 3088.3 3089.1 3115.2 300675.0 302143.0 302376.0 303407.0 303895.0 46706.3 46731.0 46800.8 46949.6 47003.8 40140.8 48830.5 48857.5 48922.0 49092.0 14248.6 14261.6 14324.9 14359.0 14374.9 9753.8 11444.2 11493.9 11632.8 11640.5 11121.8 11348.4 11359.9 11365.4 11420.0 4415.6 4496.0 5250.5 5261.9 5342.2 971.0 1002.6 1022.8 1031.2 1078.5 22340.1 22719.7 22792.1 23374.8 23850.9 1112.9 1115.7 1124.8 1160.3 1177.7 11078.4 11425.4 11563.8 11993.8 12237.6 289783.0 294538.0 297413.0 298078.0 299155.5 41969.4 41992.0 42037.6 42149.8 52773.0 1557.1 1590.5 1595.4 1596.6 1599.7 24682.0 25382.2 27930.9 29059.0 29682.9 38045.8 38306.4 38365.0 38377.4 38545.6 298061.0 300202.0 300758.0 300992.0 301083.0 35344.5 35391.0 35572.8 35701.1 35760.0 41723.0 42049.6 42060.0 43938.7 44192.0 26530.7 28179.4 30678.5 30883.2 31172.2 29968.4 30154.4 30593.8 30924.7 31045.4 35549.6 37207.6 37263.8 37385.6 37432.0 28254.6 28743.7 29899.4 31490.5 31600.7 23307.8 24236.2 24265.8 24319.9 24413.8 10:13:24 INFO - PID 1649 | 10:13:24 INFO - PID 1649 | [#2] jquery.html Cycles:180 Average:37334.90 Median:22527.82 stddev:91217.38 (404.9%) stddev-sans-first:91431.13 10:13:24 INFO - PID 1649 | Values: 410.7 454.7 492.7 498.1 516.1 21837.8 22512.0 22543.7 22650.4 22698.0 18324.9 18344.9 18396.0 18738.6 22748.3 5874.3 6265.4 6389.3 6404.5 6427.7 6132.8 6441.3 6467.0 6474.6 6476.6 5728.2 6296.9 6422.7 6447.1 6448.1 5554.3 5781.3 6200.3 6238.7 6444.8 5675.8 5709.2 5710.6 5716.4 5739.2 29815.7 29884.3 30286.5 30344.4 30358.5 2181.3 2227.1 2231.3 2232.0 2232.6 18578.3 19638.5 19915.9 19990.5 20061.6 2200.6 2201.5 2236.1 2241.0 2255.3 163146.0 166979.0 170984.1 171138.0 186349.0 12430.3 14122.5 14544.6 14729.4 15150.9 20012.7 20022.9 20037.5 20052.2 20075.7 7852.6 9593.4 9732.8 9747.3 9843.9 8911.8 8924.2 8965.6 8991.6 8994.6 9069.9 9232.8 9287.6 9295.0 9319.7 2616.8 2618.6 2636.1 2637.2 2781.0 917.8 927.9 928.3 987.0 987.9 20109.7 20236.6 20535.6 21422.8 21492.0 951.2 953.7 998.1 1004.9 1008.2 8174.6 10720.8 10720.9 10782.9 10976.3 517990.4 521507.0 527673.0 534957.7 535045.0 144110.0 145897.0 148825.3 149462.0 154263.0 998.6 1009.4 1147.9 1161.6 1189.8 20810.9 20952.6 22395.9 23059.8 23521.0 51174.7 65733.3 67564.0 69310.0 70766.0 88912.0 91091.6 92218.0 93230.5 93615.5 9235.4 10963.6 11037.7 11122.2 11301.7 14450.6 16753.2 17184.3 17746.5 17788.0 13822.2 14300.4 14311.1 14622.2 14649.8 11005.7 11277.0 12651.6 13031.5 13246.1 14258.7 14590.5 14796.7 14924.2 15022.9 9517.3 11030.2 11158.3 11229.3 11233.1 11961.2 12217.5 12251.0 12288.4 12297.0 10:13:24 INFO - PID 1649 | 10:13:24 INFO - PID 1649 | [#3] mootools.html Cycles:180 Average:8021.20 Median:2653.12 stddev:5366.55 (202.3%) stddev-sans-first:5358.10 10:13:24 INFO - PID 1649 | Values: 1337.7 1481.3 1483.4 1504.7 1520.2 13016.3 13498.2 13510.9 13540.0 13545.1 11179.8 11435.9 11515.4 13129.1 13475.2 2653.1 2682.9 2699.5 2701.9 2704.4 2189.8 2639.3 2671.5 2683.0 2693.2 2629.8 2645.6 2647.4 2653.1 2657.9 2601.5 2603.0 2604.9 2611.0 2613.8 2589.6 2590.1 2594.5 2597.1 2599.4 10567.6 13013.8 13052.5 13113.5 13113.8 6632.6 6868.8 6881.2 6886.6 6890.2 4323.8 4536.5 4688.9 4702.5 4718.9 1360.1 1381.3 1400.6 1429.1 1439.2 19406.9 19466.4 19566.5 19636.2 19667.3 6650.3 6764.7 6804.3 6946.8 6953.7 16063.9 16214.5 16242.6 16255.5 16277.2 6953.7 7058.6 7089.0 7106.0 7110.6 5881.7 6066.6 6084.0 6244.3 6284.9 4467.6 5046.9 5239.7 5246.9 5328.5 1631.2 1648.2 1660.2 1669.7 1678.2 815.4 839.7 845.2 853.4 879.9 6236.7 7203.5 7209.4 7239.4 7275.9 656.2 672.8 706.8 707.5 710.9 3598.8 4255.1 4320.4 4415.9 4552.5 16059.8 16649.3 16693.3 17064.6 17528.0 17417.9 17769.2 17853.0 18039.9 18147.0 9210.2 9270.4 9350.8 9426.2 9448.5 6589.4 6990.8 7949.4 7950.1 8175.9 12689.6 15556.8 15772.0 15885.4 15991.5 15966.8 16489.8 16559.4 16779.8 16781.9 10413.9 10461.1 10489.8 10556.9 10565.5 12135.7 12316.7 12367.0 12392.4 12422.3 9553.4 9910.8 10187.8 10217.6 10341.2 9627.2 9630.9 9700.9 10013.7 10190.4 9049.3 9114.6 9178.9 9210.6 9223.4 5591.5 5789.2 6452.4 6459.3 6678.0 5987.5 6640.3 6783.5 6946.2 7017.2 10:13:24 INFO - PID 1649 | 10:13:24 INFO - PID 1649 | [#4] prototype.html Cycles:180 Average:5501.28 Median:6242.08 stddev:1715.18 (27.5%) stddev-sans-first:1675.55 10:13:24 INFO - PID 1649 | Values: 333.4 429.9 439.0 461.0 467.1 5284.2 5457.7 6066.0 6431.4 6531.3 5519.1 6054.2 6294.5 6309.4 6380.1 4172.3 4734.2 4751.5 4769.3 4769.4 4062.7 4658.2 4693.2 4739.8 4775.4 4054.5 4272.5 4400.9 4699.5 4703.9 3963.9 4083.0 4214.1 4636.8 4737.5 3133.5 3984.9 4149.3 4154.3 4158.3 5449.9 6117.0 6133.5 6186.8 6231.2 4896.7 5605.6 5605.9 5627.9 5635.4 6278.8 6342.0 6795.2 6797.6 6816.3 4129.7 4614.6 4715.6 4726.5 4738.5 7162.5 7241.4 7244.7 7272.9 7298.6 4028.4 4806.1 4826.4 4845.0 4855.3 4262.5 4453.4 4879.7 4926.4 4928.3 4904.9 5595.8 5605.9 5639.5 5641.8 5485.9 6574.7 6843.0 6848.3 6857.7 1468.6 1602.8 1684.0 1685.4 1686.3 1123.7 1124.3 1131.8 1136.4 1137.6 6405.0 6433.5 6434.8 6436.6 6452.2 6507.3 7040.4 7054.7 7069.5 7073.3 6396.8 6448.8 6469.3 6470.9 6495.9 3676.8 3689.3 3701.1 3701.6 3723.3 6332.7 6359.4 6779.2 7287.2 7306.8 6601.3 7183.1 7248.8 7252.5 7256.6 6320.0 7228.4 7262.8 7269.5 7275.6 6666.3 6929.9 6995.9 7101.5 7117.5 6014.7 6951.7 6957.5 6963.8 7014.0 5894.5 6252.9 7030.8 7200.6 7213.6 4754.0 4781.8 4791.7 4799.3 4801.5 6701.3 7069.3 7070.2 7099.9 7135.7 6507.8 6644.4 6648.3 6798.0 6812.0 6012.9 6448.4 6742.2 6770.3 6777.3 6605.0 6621.8 6623.2 6647.3 6650.1 6312.9 6421.5 6747.9 6755.7 6793.8 5808.4 6094.4 6588.0 6623.6 6635.6 10:13:24 INFO - PID 1649 | 10:13:24 INFO - PID 1649 | [#5] yui.html Cycles:180 Average:24159.03 Median:17339.74 stddev:36768.58 (212.0%) stddev-sans-first:36830.00 10:13:24 INFO - PID 1649 | Values: 828.9 831.8 838.6 862.9 865.8 14387.2 16749.2 17197.1 17213.0 17213.3 12784.9 13072.2 13150.0 13229.6 14117.8 945.4 956.3 969.0 972.2 972.9 966.3 970.7 974.4 977.4 980.2 1010.0 1013.3 1014.0 1015.0 1015.2 937.1 978.9 1016.6 1054.8 1055.2 785.3 788.1 789.4 789.9 790.9 15423.5 16763.8 17346.9 17391.9 17551.7 16253.0 16844.1 16891.0 16960.7 17060.2 14182.8 14921.2 15008.3 15030.5 15042.8 3614.6 3654.4 3659.1 3663.6 3667.0 101842.0 147755.0 150050.0 152771.0 155552.2 13167.6 13574.2 13829.7 13954.9 14099.2 26451.2 26465.5 26545.7 26572.7 26696.7 7989.4 7994.0 8011.7 8054.2 8058.8 3951.2 4018.6 4032.6 4038.0 4053.9 4590.3 4621.9 4634.2 4647.6 4657.5 968.9 1021.3 1027.4 1027.6 1030.9 642.0 653.4 655.8 677.5 679.8 10989.4 11163.6 11436.8 12135.3 12210.8 638.0 644.4 657.6 658.6 673.8 5490.9 5603.5 5632.9 5726.8 5869.5 124728.0 130223.0 130778.3 132668.0 134135.7 93845.5 109001.4 112830.4 114219.4 119350.6 92621.1 94788.5 94876.0 100962.5 101541.0 10706.1 10894.8 10940.6 11255.9 11423.5 43490.8 44152.3 46291.8 46475.3 47270.5 61068.4 61416.7 65366.0 65528.0 66196.0 13981.4 14416.9 14811.6 14842.6 14860.1 14214.9 15970.4 16344.4 16481.3 16520.0 15077.5 16918.7 17744.4 18218.7 18300.8 17138.3 17552.6 17713.9 17723.7 17771.6 14793.9 17019.0 17274.5 17332.6 17995.2 9345.5 10648.3 11313.9 11394.3 11777.6 12729.5 12882.4 13133.2 13302.8 13421.6 10:13:24 INFO - PID 1649 | -------- Summary: end -------- 10:13:24 INFO - PID 1649 | 10:13:25 INFO - TEST-INFO | 1649: exit 0 10:13:25 INFO - TEST-OK | dromaeo_css | took 1143736ms 10:13:25 INFO - TEST-START | kraken 10:13:25 INFO - operating with platform_type : mac_ 10:13:25 INFO - Initialising browser for kraken test... 10:13:25 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpukvjgM/profile 10:13:25 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 10:13:59 INFO - TEST-INFO | 1659: killed by SIGTERM 10:13:59 INFO - Browser initialized. 10:13:59 INFO - Running cycle 1/1 for kraken test... 10:13:59 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpukvjgM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:14:01 INFO - PID 1663 | 2017-06-13 10:14:01.023 plugin-container[1664:11286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a47, name = 'com.apple.tsm.portname' 10:14:01 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:01 INFO - PID 1663 | 2017-06-13 10:14:01.024 plugin-container[1664:11286] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 10:14:01 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:01 INFO - PID 1663 | 2017-06-13 10:14:01.024 plugin-container[1664:11286] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:14:01 INFO - PID 1663 | 2017-06-13 10:14:01.828 plugin-container[1665:11331] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 10:14:01 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:01 INFO - PID 1663 | 2017-06-13 10:14:01.829 plugin-container[1665:11331] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 10:14:01 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:14:01 INFO - PID 1663 | 2017-06-13 10:14:01.829 plugin-container[1665:11331] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:14:11 INFO - PID 1663 | RSS: Main: 253042688 10:14:11 INFO - PID 1663 | 10:14:14 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 10:14:15 INFO - PID 1663 | RSS: Main: 265601024 10:14:15 INFO - PID 1663 | 10:14:17 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 10:14:18 INFO - PID 1663 | RSS: Main: 276418560 10:14:18 INFO - PID 1663 | 10:14:21 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 10:14:22 INFO - PID 1663 | RSS: Main: 279285760 10:14:22 INFO - PID 1663 | 10:14:24 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 10:14:24 INFO - PID 1663 | RSS: Main: 280432640 10:14:24 INFO - PID 1663 | 10:14:26 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:14:27 INFO - PID 1663 | RSS: Main: 282161152 10:14:27 INFO - PID 1663 | 10:14:30 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 10:14:31 INFO - PID 1663 | RSS: Main: 286302208 10:14:31 INFO - PID 1663 | 10:14:33 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 10:14:34 INFO - PID 1663 | RSS: Main: 288108544 10:14:34 INFO - PID 1663 | 10:14:37 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 10:14:38 INFO - PID 1663 | RSS: Main: 287019008 10:14:38 INFO - PID 1663 | 10:14:39 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:14:40 INFO - PID 1663 | RSS: Main: 287027200 10:14:40 INFO - PID 1663 | 10:14:42 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:14:42 INFO - PID 1663 | RSS: Main: 286900224 10:14:42 INFO - PID 1663 | 10:14:44 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:14:45 INFO - PID 1663 | RSS: Main: 288043008 10:14:45 INFO - PID 1663 | 10:14:48 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:14:49 INFO - PID 1663 | RSS: Main: 287891456 10:14:49 INFO - PID 1663 | 10:14:52 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:14:52 INFO - PID 1663 | RSS: Main: 287854592 10:14:52 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 10:14:54 INFO - PID 1663 | RSS: Main: 289136640 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | __start_tp_report 10:14:54 INFO - PID 1663 | _x_x_mozilla_page_load 10:14:54 INFO - PID 1663 | _x_x_mozilla_page_load_details 10:14:54 INFO - PID 1663 | |i|pagename|runs| 10:14:54 INFO - PID 1663 | |0;ai-astar;105;116;116;141;164;158;155;116;119;119 10:14:54 INFO - PID 1663 | |1;audio-beat-detection;147;116;134;150;123;163;156;110;181;143 10:14:54 INFO - PID 1663 | |2;audio-dft;197;206;213;220;219;215;199;202;198;197 10:14:54 INFO - PID 1663 | |3;audio-fft;75;84;74;129;57;60;60;62;80;56 10:14:54 INFO - PID 1663 | |4;audio-oscillator;70;70;119;100;106;104;114;71;74;110 10:14:54 INFO - PID 1663 | |5;imaging-gaussian-blur;93;90;90;90;91;89;94;90;90;89 10:14:54 INFO - PID 1663 | |6;imaging-darkroom;99;98;98;97;98;98;97;97;97;97 10:14:54 INFO - PID 1663 | |7;imaging-desaturate;92;95;93;89;94;134;140;92;93;91 10:14:54 INFO - PID 1663 | |8;json-parse-financial;52;51;50;72;70;78;75;73;73;53 10:14:54 INFO - PID 1663 | |9;json-stringify-tinderbox;57;53;56;57;63;65;57;56;59;56 10:14:54 INFO - PID 1663 | |10;stanford-crypto-aes;81;80;81;81;80;81;81;84;81;82 10:14:54 INFO - PID 1663 | |11;stanford-crypto-ccm;136;147;152;147;144;162;139;142;160;173 10:14:54 INFO - PID 1663 | |12;stanford-crypto-pbkdf2;180;178;206;218;180;179;195;183;178;177 10:14:54 INFO - PID 1663 | |13;stanford-crypto-sha256-iterative;55;82;97;91;78;68;71;94;93;70 10:14:54 INFO - PID 1663 | __end_tp_report 10:14:54 INFO - PID 1663 | __start_cc_report 10:14:54 INFO - PID 1663 | _x_x_mozilla_cycle_collect,1175 10:14:54 INFO - PID 1663 | __end_cc_report 10:14:54 INFO - PID 1663 | __startTimestamp1497374094980__endTimestamp 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | ------- Summary: start ------- 10:14:54 INFO - PID 1663 | Number of tests: 14 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | [#0] ai-astar Cycles:10 Average:130.90 Median:130.00 stddev:21.43 (16.5%) stddev-sans-first:20.58 10:14:54 INFO - PID 1663 | Values: 105.0 116.0 116.0 141.0 164.0 158.0 155.0 116.0 119.0 119.0 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:148.50 stddev:22.04 (14.8%) stddev-sans-first:23.31 10:14:54 INFO - PID 1663 | Values: 147.0 116.0 134.0 150.0 123.0 163.0 156.0 110.0 181.0 143.0 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | [#2] audio-dft Cycles:10 Average:206.60 Median:209.50 stddev:9.32 (4.5%) stddev-sans-first:9.22 10:14:54 INFO - PID 1663 | Values: 197.0 206.0 213.0 220.0 219.0 215.0 199.0 202.0 198.0 197.0 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | [#3] audio-fft Cycles:10 Average:73.70 Median:68.00 stddev:21.88 (32.2%) stddev-sans-first:23.21 10:14:54 INFO - PID 1663 | Values: 75.0 84.0 74.0 129.0 57.0 60.0 60.0 62.0 80.0 56.0 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:94.50 stddev:20.12 (21.3%) stddev-sans-first:19.40 10:14:54 INFO - PID 1663 | Values: 70.0 70.0 119.0 100.0 106.0 104.0 114.0 71.0 74.0 110.0 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:90.00 stddev:1.65 (1.8%) stddev-sans-first:1.50 10:14:54 INFO - PID 1663 | Values: 93.0 90.0 90.0 90.0 91.0 89.0 94.0 90.0 90.0 89.0 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | [#6] imaging-darkroom Cycles:10 Average:97.60 Median:98.00 stddev:0.70 (0.7%) stddev-sans-first:0.53 10:14:54 INFO - PID 1663 | Values: 99.0 98.0 98.0 97.0 98.0 98.0 97.0 97.0 97.0 97.0 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | [#7] imaging-desaturate Cycles:10 Average:101.30 Median:92.50 stddev:18.94 (20.5%) stddev-sans-first:19.79 10:14:54 INFO - PID 1663 | Values: 92.0 95.0 93.0 89.0 94.0 134.0 140.0 92.0 93.0 91.0 10:14:54 INFO - PID 1663 | 10:14:54 INFO - PID 1663 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:72.50 stddev:11.57 (16.0%) stddev-sans-first:11.32 10:14:54 INFO - PID 1663 | Values: 52.0 51.0 50.0 72.0 70.0 78.0 75.0 73.0 73.0 53.0 10:14:54 INFO - PID 1663 | 10:14:55 INFO - PID 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:57.90 Median:57.00 stddev:3.57 (6.3%) stddev-sans-first:3.77 10:14:55 INFO - PID 1663 | Values: 57.0 53.0 56.0 57.0 63.0 65.0 57.0 56.0 59.0 56.0 10:14:55 INFO - PID 1663 | 10:14:55 INFO - PID 1663 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:1.14 (1.4%) stddev-sans-first:1.20 10:14:55 INFO - PID 1663 | Values: 81.0 80.0 81.0 81.0 80.0 81.0 81.0 84.0 81.0 82.0 10:14:55 INFO - PID 1663 | 10:14:55 INFO - PID 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:149.50 stddev:11.60 (7.8%) stddev-sans-first:11.11 10:14:55 INFO - PID 1663 | Values: 136.0 147.0 152.0 147.0 144.0 162.0 139.0 142.0 160.0 173.0 10:14:55 INFO - PID 1663 | 10:14:55 INFO - PID 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.40 Median:181.50 stddev:14.24 (7.8%) stddev-sans-first:14.85 10:14:55 INFO - PID 1663 | Values: 180.0 178.0 206.0 218.0 180.0 179.0 195.0 183.0 178.0 177.0 10:14:55 INFO - PID 1663 | 10:14:55 INFO - PID 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.90 Median:86.50 stddev:13.88 (16.0%) stddev-sans-first:11.42 10:14:55 INFO - PID 1663 | Values: 55.0 82.0 97.0 91.0 78.0 68.0 71.0 94.0 93.0 70.0 10:14:55 INFO - PID 1663 | -------- Summary: end -------- 10:14:55 INFO - PID 1663 | 10:14:55 INFO - TEST-INFO | 1663: exit 0 10:14:55 INFO - TEST-OK | kraken | took 90600ms 10:14:55 INFO - SUITE-END | took 1234s 10:14:56 INFO - Completed test suite (00:20:34) 10:14:56 INFO - javascript benchmark 10:14:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8146.432088896822, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3453.7409694272933, 3484.8606055757773, 3597.388443964526, 3606.8917932462036, 3622.058264851138, 6103.45068943795, 6110.741024289845, 6112.959454150319, 6134.191681014041, 6190.969045154779, 6156.846078848031, 6189.75240990361, 6214.130021796941, 6216.720247588858, 6220.9377906220925, 5424.484673955968, 5440.4287549807295, 5447.4824891635235, 5459.317585301847, 5577.749001294951, 5455.863603409926, 5462.0, 5500.6424582402215, 5522.834314970557, 5530.142827861668, 5291.708956007399, 5339.733013349317, 5361.195820626937, 5379.838604841861, 5450.31871016123, 5314.202869569595, 5314.335708036496, 5336.092864213093, 5375.892482150374, 5413.702246376448, 5354.0, 5399.865003374908, 5413.593980451482, 5453.182022696587, 5459.07195776715, 6142.385761423867, 6142.723577439026, 6171.938280617182, 6173.0, 6215.7513699451965, 5218.243354713583, 6418.19772528434, 6609.0, 6628.73485060597, 6641.80074597758, 6610.504212184057, 6617.0, 6636.170478690164, 6657.101291325657, 6683.164604424398, 6167.660778657217, 6210.316865144876, 6236.469900058546, 6249.0, 6292.590981586182, 6887.139107611549, 6897.448204143657, 6899.6205208713, 6903.723851046002, 6912.654367281616, 6364.172657554488, 6423.614583125028, 6447.677616119176, 6458.58019228758, 6560.212774467094, 6592.86814263712, 6629.801105966829, 6669.0, 6719.160104986877, 6738.797836064926, 6597.670116494156, 6615.76844810439, 6619.040239165294, 6630.844975382924, 6632.303608121174, 5584.720763961867, 6408.775692850728, 6482.060101285381, 6612.437942774918, 6647.268800431949, 5267.183586544015, 5268.736563171903, 5276.023935571931, 5334.733263336895, 5366.517013468807, 4405.964598319392, 4504.459464864237, 4695.366125573072, 4751.738654373973, 4772.331873537708, 5193.948060519422, 6216.471599914058, 6221.81785460761, 6298.622082675054, 6329.113924050636, 6723.798286051427, 6734.966325168343, 6746.898796518056, 6751.0886030386255, 6773.72905083801, 6201.596896201732, 6290.716917738713, 6292.528060395489, 6304.842378940563, 6324.3991820776955, 5765.625234359753, 5803.303603567599, 5827.096799996008, 5850.736716847752, 5877.118432235199, 6729.798106056826, 6802.795916122524, 6905.551139175937, 6906.654667266717, 6935.479839012094, 5294.311739473844, 6749.696263668048, 6779.423748981392, 6789.388954994074, 6802.115724955724, 6884.724611015503, 6890.345417185359, 6910.723571057101, 6912.654367281515, 6917.239103698488, 6614.8346291343105, 6629.07192992991, 6635.668216589054, 6644.335566443317, 6663.067170596023, 6550.6069635822005, 6658.267590565131, 6692.899606506004, 6759.121314229119, 6796.422304104009, 5486.643368181056, 6528.67356632176, 6533.281339052795, 6539.346065393613, 6542.280349161683, 6450.322716114741, 6486.708098135689, 6503.479721622353, 6506.284308726131, 6527.0, 6252.812415627538, 6324.924762790429, 6328.557001009885, 6394.360563943755, 6420.711068002044, 6361.427471527585, 6401.231852177769, 6413.1021656969, 6437.517186210959, 6535.705893234911, 5374.328208973879, 6389.137466441971, 6405.679716014088, 6408.166938297992, 6408.487321014214], "value": 6058.271367270703, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.2925861913945, 789.5894135049773, 804.9517028978258, 819.6311659753111, 854.337888136694, 37862.0, 38391.42412863806, 38748.61251387489, 38784.80607596965, 38843.805780971124, 34291.14272143191, 37696.623033769654, 37805.621943780556, 37925.620743792555, 37963.810180949054, 8091.433599648027, 8229.382796290272, 8250.422470427073, 8271.296939760128, 8277.296429803473, 8082.030156381243, 8205.220504052135, 8210.958945205266, 8215.34277257821, 8217.096119426858, 8191.467554608961, 8213.34293256541, 8228.177182281785, 8247.257746802787, 8256.917430825675, 8162.877556836652, 8189.672413103469, 8213.7125200618, 8215.507069575844, 8224.218699223564, 8003.959980200062, 8008.799780005488, 8061.395395345314, 8062.7581172564915, 8064.556449395281, 36157.81921090378, 36225.63774362249, 36282.0, 36356.27287454263, 36408.453873191924, 3417.7436692247934, 3418.384690755663, 3448.2758620689683, 3451.775634583744, 3458.1008937676384, 29345.85327073372, 29352.412951740847, 29421.55867661987, 29472.852635736683, 29571.0, 2987.2382542451537, 3054.266975925762, 3088.2896933705147, 3089.119600913729, 3115.205622566231, 300675.0, 302143.0, 302376.0, 303407.0, 303895.0, 46706.299405508944, 46731.0, 46800.7659961698, 46949.59151225469, 47003.76498117556, 40140.79929600392, 48830.51169488331, 48857.511424886005, 48922.02155956861, 49092.01815963661, 14248.643783905278, 14261.572152835432, 14324.85675143256, 14359.0, 14374.85625143756, 9753.804923901482, 11444.198906076495, 11493.885061149449, 11632.767344653059, 11640.476178571984, 11121.777564448666, 11348.432578371048, 11359.864013598573, 11365.431728413547, 11420.0, 4415.624671902924, 4496.0, 5250.4749525047955, 5261.868453288698, 5342.198670199501, 970.9757256068655, 1002.5588740954103, 1022.7749895023084, 1031.231732359382, 1078.4823284823171, 22340.110248355282, 22719.659205111937, 22792.088316467154, 23374.7662523376, 23850.880745596507, 1112.8942750438696, 1115.7154925493946, 1124.8031594470935, 1160.2922217447351, 1177.6761390617648, 11078.446077696082, 11425.428728563538, 11563.843615638369, 11993.760124797454, 12237.571684990982, 289783.0, 294538.0, 297413.0, 298078.0, 299155.50422248186, 41969.37045944313, 41992.0, 42037.57962420337, 42149.789251053546, 52773.0, 1557.1046648177116, 1590.4830929948046, 1595.417672549511, 1596.6247931736032, 1599.6640705451753, 24682.0, 25382.235528942118, 27930.88276469, 29059.0, 29682.851585741933, 38045.80977095097, 38306.42540361953, 38365.040873978374, 38377.42433863439, 38545.6145438542, 298061.0, 300202.0, 300758.0, 300992.0, 301083.0, 35344.46983295201, 35391.0, 35572.82213588915, 35701.10747231236, 35760.02679477477, 41723.0, 42049.579504204565, 42060.0, 43938.681839544864, 44192.0, 26530.734692653597, 28179.43641127207, 30678.539821902243, 30883.227919302197, 31172.220694482818, 29968.400631987675, 30154.3969120612, 30593.847030765595, 30924.69075309308, 31045.379092418476, 35549.644503554635, 37207.6279237215, 37263.813680931424, 37385.62614373821, 37432.0, 28254.57618135771, 28743.712562874105, 29899.402011960075, 31490.52764208493, 31600.683993159775, 23307.76692233056, 24236.1517346892, 24265.75734242635, 24319.878400607882, 24413.75586244186], "value": 17032.177917578683, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.72070991725616, 454.67490744117947, 492.6969913503194, 498.05369797384964, 516.1457787361916, 21837.781622183775, 22511.98696058677, 22543.661845072318, 22650.433739156528, 22697.978590963405, 18324.908375458104, 18344.899306041614, 18395.988220647858, 18738.625227495442, 22748.31755047351, 5874.295084589834, 6265.404786545271, 6389.329120442357, 6404.455621272197, 6427.742890284383, 6132.816015519542, 6441.323661015596, 6466.997615369625, 6474.579152355105, 6476.579022363555, 5728.198052272685, 6296.905546416808, 6422.710978005977, 6447.129637498948, 6448.097266382711, 5554.250176226218, 5781.277340332437, 6200.255969283715, 6238.719257633372, 6444.838879028015, 5675.772969081232, 5709.172170035322, 5710.628809127394, 5716.428357164292, 5739.225204597367, 29815.70184298151, 29884.252893677833, 30286.54570181449, 30344.393112137855, 30358.482075896336, 2181.301983365324, 2227.0757635581213, 2231.263682984611, 2231.955360892773, 2232.5646498932715, 18578.256869725195, 19638.509037274038, 19915.90042049795, 19990.500237494034, 20061.598768024556, 2200.6258935980923, 2201.4936564590075, 2236.083205885579, 2241.0363543676212, 2255.2783109404995, 163146.0, 166979.0, 170984.14507927504, 171138.0, 186349.0, 12430.316332601613, 14122.505712300226, 14544.563663090124, 14729.379768225486, 15150.924245378923, 20012.69980950287, 20022.89988550048, 20037.49906252326, 20052.197912083644, 20075.698864517042, 7852.646630901623, 9593.42439453635, 9732.805343893082, 9747.268954828416, 9843.852342214872, 8911.777205569913, 8924.152205540464, 8965.551722413984, 8991.595378207865, 8994.550272486349, 9069.909300907038, 9232.7691807704, 9287.58205880737, 9295.0, 9319.720408387759, 2616.7644911958014, 2618.5941179117267, 2636.1300770745606, 2637.1956553251057, 2781.0, 917.8347897378402, 927.8747369105084, 928.2944961829058, 986.9654562090292, 987.9407235565889, 20109.698354524695, 20236.595268094556, 20535.58928821445, 21422.78577214239, 21492.032858521405, 951.2437612098398, 953.7472569769017, 998.0767789794556, 1004.9045340692622, 1008.2438171371472, 8174.591270436574, 10720.785584288427, 10720.874308197683, 10782.946085269523, 10976.341419514854, 517990.4100479474, 521507.0, 527673.0, 534957.6504234908, 535045.0, 144110.0, 145897.0, 148825.25587371993, 149462.0, 154263.0, 998.6404894238214, 1009.3641006166215, 1147.913906457019, 1161.6050542815315, 1189.7679952409371, 20810.855402952984, 20952.58094838125, 22395.888020559792, 23059.769402305763, 23521.0, 51174.74412627913, 65733.34266657272, 67564.0, 69310.0, 70766.0, 88912.0, 91091.63362549435, 92218.0, 93230.53384733033, 93615.53192234268, 9235.445873247627, 10963.56145754161, 11037.72405689832, 11122.165837562377, 11301.71745706364, 14450.638734031734, 16753.162341882613, 17184.31262749476, 17746.46760597184, 17788.0, 13822.239776812372, 14300.427982880568, 14311.141331520143, 14622.195779232245, 14649.780253295996, 11005.669829905115, 11276.985071343619, 12651.557195498113, 13031.47874085026, 13246.07277490605, 14258.714825703635, 14590.489332873298, 14796.704065918837, 14924.179170145746, 15022.924885375503, 9517.2862035349, 11030.172737044913, 11158.274712143684, 11229.27009744364, 11233.101351891666, 11961.222520536137, 12217.51129954792, 12250.958668513276, 12288.447019884305, 12296.954758845599], "value": 11268.920745898506, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.665583604099, 1481.296384217496, 1483.3769816676995, 1504.7291487532248, 1520.1942970225778, 13016.349182540871, 13498.190108593479, 13510.864891351084, 13540.0, 13545.119567228123, 11179.776404471906, 11435.885641143586, 11515.424228788568, 13129.080964332501, 13475.191488510696, 2653.1112077454077, 2682.932926676839, 2699.500592390404, 2701.945961080777, 2704.418550011747, 2189.824814014875, 2639.3269716222335, 2671.532481815684, 2683.047518131065, 2693.2458911504714, 2629.8422094674283, 2645.629611854342, 2647.411553068163, 2653.12446892525, 2657.8671066446695, 2601.479704059186, 2603.049886791317, 2604.8567328796908, 2611.0208671748096, 2613.8431694098417, 2589.585666293384, 2590.1323056776005, 2594.4940736556377, 2597.06505657963, 2599.4281258123183, 10567.577296908114, 13013.804792928113, 13052.54316098932, 13113.541026064042, 13113.803292950517, 6632.602043877335, 6868.759593414206, 6881.174259088892, 6886.552374095668, 6890.1731792184755, 4323.783810809478, 4536.523665015192, 4688.929666055012, 4702.506236845151, 4718.905621887578, 1360.081944687332, 1381.281733498575, 1400.6428360767939, 1429.1139493513983, 1439.2443966917347, 19406.902965485224, 19466.416007519656, 19566.51083722904, 19636.21455141807, 19667.311644092388, 6650.268470468244, 6764.72941082361, 6804.319568043156, 6946.756863509753, 6953.652317384111, 16063.919680401523, 16214.51356459308, 16242.593935151479, 16255.51233462998, 16277.186140693155, 6953.652317384111, 7058.611776352247, 7089.0, 7105.964470177616, 7110.57336559808, 5881.705914704247, 6066.636001839904, 6084.0, 6244.313125556185, 6284.937150628435, 4467.553244675506, 5046.899062018739, 5239.73801309933, 5246.868828279324, 5328.520433161034, 1631.2414727151931, 1648.2418087679803, 1660.1865086107962, 1669.7161482547997, 1678.2447898445748, 815.4373482297196, 839.6977088248226, 845.226617644848, 853.4239388412797, 879.9252063574539, 6236.719347629368, 7203.459740519482, 7209.35115839577, 7239.384652304613, 7275.927240727525, 656.2322083162711, 672.8116127484312, 706.8480276740422, 707.4517249131964, 710.8507213485123, 3598.766080204831, 4255.127698821734, 4320.3519472078815, 4415.889602759957, 4552.544745525487, 16059.839401606068, 16649.3340266388, 16693.332266709196, 17064.57338566546, 17528.035958022178, 17417.912910435367, 17769.200385982662, 17853.0, 18039.909800450914, 18147.0, 9210.217131543759, 9270.397424167408, 9350.766230844283, 9426.198773104226, 9448.48033358172, 6589.439897608565, 6990.776614092615, 7949.364050875801, 7950.125486196398, 8175.918240817754, 12689.555865545031, 15556.766648500055, 15771.974821636557, 15885.364585416453, 15991.520254392386, 15966.840331597, 16489.752653710442, 16559.42042028523, 16779.832201678317, 16781.91609041947, 10413.9479302603, 10461.05850473461, 10489.79020419572, 10556.894431055898, 10565.524551395052, 12135.696607584881, 12316.692082698004, 12367.0, 12392.380380980734, 12422.254664720145, 9553.426794392359, 9910.801783964425, 10187.84718229242, 10217.642382516577, 10341.224408169266, 9627.229821614394, 9630.903690963001, 9700.90299097, 10013.749656258651, 10190.439525825854, 9049.32130090259, 9114.635414583343, 9178.908210917805, 9210.618407238968, 9223.44659320443, 5591.496765291144, 5789.189513468202, 6452.419282264619, 6459.289478157493, 6677.966610166918, 5987.520998320037, 6640.269570347161, 6783.491238157257, 6946.235914049552, 7017.193022802443], "value": 5902.900133067118, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.38324100414223, 429.8903779536218, 438.9626881715055, 461.03413349033764, 467.1054929809414, 5284.233786101018, 5457.699826509541, 6065.968785306497, 6431.356864313572, 6531.281559028503, 5519.061759500884, 6054.182685337478, 6294.464970477515, 6309.359566512705, 6380.106785050097, 4172.332426811703, 4734.195186818232, 4751.5010923853015, 4769.284607308914, 4769.356136921506, 4062.6952978526583, 4658.231391820345, 4693.202155633533, 4739.810407583693, 4775.3553270308585, 4054.493188351456, 4272.50866150392, 4400.933985990213, 4699.506551812062, 4703.882402939919, 3963.861264855745, 4082.9792551862033, 4214.115035842477, 4636.814527418899, 4737.502562230968, 3133.4673105572106, 3984.880453586397, 4149.294619914622, 4154.273002224599, 4158.293090174678, 5449.863753406157, 6117.0, 6133.478654314388, 6186.81439556814, 6231.22109736283, 4896.657233993622, 5605.635633683797, 5605.859853503654, 5627.915581266284, 5635.408282130399, 6278.811635650892, 6342.016987366919, 6795.2185498667795, 6797.558158719717, 6816.284290149512, 4129.731567448106, 4614.569483460138, 4715.575598196145, 4726.480087190406, 4738.549837765407, 7162.4628152888745, 7241.384482319009, 7244.746433874841, 7272.890906636405, 7298.635068246619, 4028.3554631258858, 4806.062817750575, 4826.38316164082, 4844.95155048452, 4855.295982082649, 4262.46719160105, 4453.421055262795, 4879.730236043462, 4926.4088309403105, 4928.310036594879, 4904.926426103611, 5595.776168953278, 5605.943940560624, 5639.492445679908, 5641.774329026793, 5485.875395543904, 6574.704138313788, 6843.0, 6848.280930502226, 6857.657117144123, 1468.5887951373631, 1602.8236893941657, 1683.9579010524835, 1685.4269548353618, 1686.3254698120604, 1123.674064603759, 1124.3366413815888, 1131.7679875625477, 1136.4373985769566, 1137.6075254037344, 6405.0, 6433.453156481658, 6434.8391290218115, 6436.581622194542, 6452.22573291208, 6507.306107156238, 7040.436765058783, 7054.717811287594, 7069.505134640629, 7073.292670732989, 6396.825079473001, 6448.838779030468, 6469.256035555877, 6470.935290647033, 6495.870082598416, 3676.761010534307, 3689.335919534456, 3701.0960477367594, 3701.555813302421, 3723.2925744108534, 6332.683365831782, 6359.3640635936035, 6779.15260592426, 7287.198408175096, 7306.817329566803, 6601.2738598754095, 7183.066201393785, 7248.782536523912, 7252.528585641917, 7256.637168141572, 6320.0, 7228.38558722504, 7262.818429539198, 7269.527480713737, 7275.636218189069, 6666.266710661726, 6929.925861491477, 6995.860082798417, 7101.538400003983, 7117.4661900358715, 6014.699265036643, 6951.652417379009, 6957.547759395623, 6963.7562685305775, 7013.964930175316, 5894.469497745224, 6252.9374706254175, 7030.824229394101, 7200.603966781668, 7213.567185968858, 4753.952460475351, 4781.848168324198, 4791.652090475712, 4799.28010798383, 4801.4958429365815, 6701.296363881722, 7069.328413800681, 7070.222275549582, 7099.858002840017, 7135.678894449658, 6507.804765857032, 6644.4020038196795, 6648.301928297653, 6798.0, 6812.0, 6012.8797424052145, 6448.419642232034, 6742.224644165984, 6770.32296770319, 6777.254502004677, 6604.966975165094, 6621.768238111643, 6623.2182483358065, 6647.302033286435, 6650.135482387259, 6312.873742525216, 6421.454176395058, 6747.874419557824, 6755.662216889037, 6793.796186114425, 5808.390119037609, 6094.390560944047, 6587.96706016486, 6623.615498420469, 6635.63504007265], "value": 4975.921803449657, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.9212524810143, 831.7504748575434, 838.6226198210807, 862.9266512346451, 865.8095219051809, 14387.208703521303, 16749.1625418729, 17197.14014299285, 17213.0, 17213.311467541283, 12784.872151278485, 13072.150310229828, 13150.013748968842, 13229.603111906634, 14117.788233176485, 945.3760518058066, 956.2541217850088, 969.0358093696782, 972.2367941166186, 972.8735264415651, 966.3235734985503, 970.7039352997333, 974.4250891973734, 977.384247923807, 980.2011360741009, 1009.9798004039915, 1013.3008224325229, 1013.9746506337327, 1014.9695509134738, 1015.2132097624363, 937.1144268666113, 978.921686265097, 1016.5577973581516, 1054.752133248686, 1055.1558752997648, 785.3481610263468, 788.1291173253529, 789.3842802613971, 789.8657228271207, 790.93277071449, 15423.460178893685, 16763.83236167635, 17346.91326543372, 17391.913040434843, 17551.73672394915, 16253.024818510927, 16844.073575953316, 16890.98654080759, 16960.74558881618, 17060.232289547, 14182.787258191136, 14921.179335136563, 15008.324625391884, 15030.549083527512, 15042.849571504365, 3614.638536146391, 3654.4335627977707, 3659.14010207601, 3663.5786884508357, 3667.02823751706, 101841.98158018399, 147755.0, 150050.0, 152771.0, 155552.22223888923, 13167.60497185086, 13574.185548867099, 13829.654258643614, 13954.930225348808, 14099.224542650252, 26451.206463806117, 26465.470690586466, 26545.734542654325, 26572.734272657413, 26696.733032669814, 7989.360851131895, 7994.0, 8011.7195898144455, 8054.154313796967, 8058.798530036796, 3951.209758048402, 4018.557958624549, 4032.5564187939303, 4038.0308725906157, 4053.8986525336513, 4590.311453282035, 4621.907561848744, 4634.165850147006, 4647.604953578983, 4657.464391594942, 968.9127978481971, 1021.2953062387078, 1027.4041056187393, 1027.5992362978445, 1030.896910308963, 641.9632293845406, 653.4021370446084, 655.7606473637088, 677.5494296293009, 679.8096532970776, 10989.395583242998, 11163.553457861757, 11436.828447573293, 12135.332556709289, 12210.816837747441, 637.964911929839, 644.3813938618885, 657.5890068707058, 658.5686375424104, 673.7843889955266, 5490.929268792547, 5603.5237004854225, 5632.915506267409, 5726.828195154068, 5869.5010924071075, 124728.0, 130223.0, 130778.34610827075, 132668.0, 134135.65864341427, 93845.53077234571, 109001.36497952537, 112830.43584782188, 114219.42890285661, 119350.61298773976, 92621.07378926124, 94788.52605736926, 94876.0, 100962.49518752359, 101541.0, 10706.143508519144, 10894.836577451186, 10940.56237750481, 11255.943720281346, 11423.543058277908, 43490.782546087066, 44152.33771493366, 46291.76854115708, 46475.3028704563, 47270.52729472661, 61068.38931610627, 61416.69291653513, 65366.0, 65528.0, 66196.0, 13981.440742370598, 14416.855831441551, 14811.629709257355, 14842.628934276298, 14860.108393495992, 14214.928925355307, 15970.361185552449, 16344.427945021867, 16481.34074637001, 16520.0, 15077.47228847029, 16918.746218806973, 17744.3789467368, 18218.72671909949, 18300.81699182991, 17138.314467421664, 17552.648947021244, 17713.911430442764, 17723.734143987593, 17771.644567108844, 14793.852061479678, 17019.0, 17274.481765547054, 17332.566685832957, 17995.190216440555, 9345.53272336394, 10648.254622176373, 11313.886861131612, 11394.25937314072, 11777.587784427504, 12729.536103348233, 12882.420291087112, 13133.212007279593, 13302.800457993331, 13421.597352079454], "value": 8557.30436469272, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 116.0, 141.0, 164.0, 158.0, 155.0, 116.0, 119.0, 119.0], "value": 130.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 134.0, 150.0, 123.0, 163.0, 156.0, 110.0, 181.0, 143.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 206.0, 213.0, 220.0, 219.0, 215.0, 199.0, 202.0, 198.0, 197.0], "value": 206.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 84.0, 74.0, 129.0, 57.0, 60.0, 60.0, 62.0, 80.0, 56.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 119.0, 100.0, 106.0, 104.0, 114.0, 71.0, 74.0, 110.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 90.0, 90.0, 91.0, 89.0, 94.0, 90.0, 90.0, 89.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 98.0, 97.0, 98.0, 98.0, 97.0, 97.0, 97.0, 97.0], "value": 97.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 93.0, 89.0, 94.0, 134.0, 140.0, 92.0, 93.0, 91.0], "value": 101.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 50.0, 72.0, 70.0, 78.0, 75.0, 73.0, 73.0, 53.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 56.0, 57.0, 63.0, 65.0, 57.0, 56.0, 59.0, 56.0], "value": 57.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 81.0, 81.0, 80.0, 81.0, 81.0, 84.0, 81.0, 82.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 147.0, 152.0, 147.0, 144.0, 162.0, 139.0, 142.0, 160.0, 173.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 178.0, 206.0, 218.0, 180.0, 179.0, 195.0, 183.0, 178.0, 177.0], "value": 187.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 82.0, 97.0, 91.0, 78.0, 68.0, 71.0, 94.0, 93.0, 70.0], "value": 79.9, "unit": "score"}]}]} 10:14:56 INFO - Return code: 0 10:14:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:14:56 INFO - # TBPL SUCCESS # 10:14:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:14:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:14:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:14:56 INFO - 2017-06-13 10:14:56.340 screenresolution[1671:11719] starting screenresolution argv=screenresolution get 10:14:56 INFO - 2017-06-13 10:14:56.343 screenresolution[1671:11719] Display 0: 1600x1200x32@60 10:14:56 INFO - 2017-06-13 10:14:56.349 screenresolution[1672:11722] starting screenresolution argv=screenresolution list 10:14:56 INFO - Available Modes on Display 0 10:14:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:14:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:14:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:14:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:14:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:14:56 INFO - Intel Iris: 10:14:56 INFO - Chipset Model: Intel Iris 10:14:56 INFO - Type: GPU 10:14:56 INFO - Bus: Built-In 10:14:56 INFO - VRAM (Dynamic, Max): 1536 MB 10:14:56 INFO - Vendor: Intel (0x8086) 10:14:56 INFO - Device ID: 0x0a2e 10:14:56 INFO - Revision ID: 0x0009 10:14:56 INFO - Displays: 10:14:56 INFO - Display: 10:14:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:14:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:14:56 INFO - Display Serial Number: 1600x1200 60 10:14:56 INFO - Main Display: Yes 10:14:56 INFO - Mirror: Off 10:14:56 INFO - Online: Yes 10:14:56 INFO - Rotation: Supported 10:14:56 INFO - Return code: 0 10:14:56 INFO - Running post-action listener: _resource_record_post_action 10:14:56 INFO - [mozharness: 2017-06-13 17:14:56.583986Z] Finished run-tests step (success) 10:14:56 INFO - Running post-run listener: _resource_record_post_run 10:14:56 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 38348288; Write bytes: 574056448; Read time: 279; Write time: 1729 10:14:56 INFO - TinderboxPrint: CPU usage
53.4% 10:14:56 INFO - TinderboxPrint: I/O read bytes / time
38,348,288 / 279 10:14:56 INFO - TinderboxPrint: I/O write bytes / time
574,056,448 / 1,729 10:14:56 INFO - TinderboxPrint: CPU idle
2,342.1 (46.6%) 10:14:56 INFO - TinderboxPrint: CPU system
1,017.2 (20.2%) 10:14:56 INFO - TinderboxPrint: CPU user
1,670.4 (33.2%) 10:14:56 INFO - TinderboxPrint: Swap in / out
290,799,616 / 0 10:14:56 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 237513216; Write bytes: 239761408; Read time: 17359; Write time: 536 10:14:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:14:56 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 37385728; Write bytes: 315846656; Read time: 266; Write time: 1147 10:14:57 INFO - Running post-run listener: _upload_blobber_files 10:14:57 INFO - Blob upload gear active. 10:14:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:14:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:14:57 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:14:57 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:14:57 INFO - (blobuploader) - INFO - Open directory for files ... 10:14:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:14:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:14:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:14:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:14:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:14:58 INFO - (blobuploader) - INFO - Done attempting. 10:14:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:14:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:14:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:14:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:14:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:14:59 INFO - (blobuploader) - INFO - Done attempting. 10:14:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:14:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:14:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:15:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:00 INFO - (blobuploader) - INFO - Done attempting. 10:15:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:15:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:15:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:15:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:15:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:15:02 INFO - (blobuploader) - INFO - Done attempting. 10:15:02 INFO - (blobuploader) - INFO - Iteration through files over. 10:15:02 INFO - Return code: 0 10:15:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:15:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:15:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/892f48b6506c2e3bea2c4dab17edd70a9d4129cd70a29dc6cba0fa5e12be1a1d464a84abcfccfddb851727fc8c60eb14251bd4623f38a340a2fa2ec7e3123f65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec1db1b476079ebacb267ab42a1ef4b47698083e14a67876ee29cc9beefb4d06e1852f8898ae57007439100cc65ff29d53c66f18c9a7110c7dc1b0ca3a8f3743", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b10d27f0ae847c15a3d03906168c636f83b8385e1b62d7c128cd9fc51ce3532fa7288ed1fe9e3d90e653837053606b3a54c7a3142921bee0f3b48409a493afb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3d226df2254a5f0264216c332a8f92d24c02967e8691df6640a049d196f350038eda2496ab2603b5a824490c99cac93231303632fc0b5ea369dfa29a2cfd8aa"} 10:15:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:15:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:15:02 INFO - Contents: 10:15:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/892f48b6506c2e3bea2c4dab17edd70a9d4129cd70a29dc6cba0fa5e12be1a1d464a84abcfccfddb851727fc8c60eb14251bd4623f38a340a2fa2ec7e3123f65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec1db1b476079ebacb267ab42a1ef4b47698083e14a67876ee29cc9beefb4d06e1852f8898ae57007439100cc65ff29d53c66f18c9a7110c7dc1b0ca3a8f3743", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b10d27f0ae847c15a3d03906168c636f83b8385e1b62d7c128cd9fc51ce3532fa7288ed1fe9e3d90e653837053606b3a54c7a3142921bee0f3b48409a493afb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3d226df2254a5f0264216c332a8f92d24c02967e8691df6640a049d196f350038eda2496ab2603b5a824490c99cac93231303632fc0b5ea369dfa29a2cfd8aa"} 10:15:02 INFO - Running post-run listener: copy_logs_to_upload_dir 10:15:02 INFO - Copying logs to upload dir... 10:15:02 INFO - mkdir: /builds/slave/test/build/upload/logs 10:15:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.587796 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-13 10:15:02.906733) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 10:15:02.911155) ========= 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.Lb2I77847Q/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.uPqCug8fCO/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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/Firefox/sha512/892f48b6506c2e3bea2c4dab17edd70a9d4129cd70a29dc6cba0fa5e12be1a1d464a84abcfccfddb851727fc8c60eb14251bd4623f38a340a2fa2ec7e3123f65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec1db1b476079ebacb267ab42a1ef4b47698083e14a67876ee29cc9beefb4d06e1852f8898ae57007439100cc65ff29d53c66f18c9a7110c7dc1b0ca3a8f3743", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b10d27f0ae847c15a3d03906168c636f83b8385e1b62d7c128cd9fc51ce3532fa7288ed1fe9e3d90e653837053606b3a54c7a3142921bee0f3b48409a493afb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3d226df2254a5f0264216c332a8f92d24c02967e8691df6640a049d196f350038eda2496ab2603b5a824490c99cac93231303632fc0b5ea369dfa29a2cfd8aa"} build_url:https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011224 build_url: 'https://queue.taskcluster.net/v1/task/CGxDdzZ1RLOP0JvldV_Bew/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/892f48b6506c2e3bea2c4dab17edd70a9d4129cd70a29dc6cba0fa5e12be1a1d464a84abcfccfddb851727fc8c60eb14251bd4623f38a340a2fa2ec7e3123f65", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec1db1b476079ebacb267ab42a1ef4b47698083e14a67876ee29cc9beefb4d06e1852f8898ae57007439100cc65ff29d53c66f18c9a7110c7dc1b0ca3a8f3743", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b10d27f0ae847c15a3d03906168c636f83b8385e1b62d7c128cd9fc51ce3532fa7288ed1fe9e3d90e653837053606b3a54c7a3142921bee0f3b48409a493afb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a3d226df2254a5f0264216c332a8f92d24c02967e8691df6640a049d196f350038eda2496ab2603b5a824490c99cac93231303632fc0b5ea369dfa29a2cfd8aa"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 10:15:02.952048) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:15:02.952647) ========= 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.Lb2I77847Q/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.uPqCug8fCO/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.005087 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 10:15:03.004095) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 10:15:03.004523) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 10:15:03.004861) ========= ========= Total master_lag: 0.19 =========