builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0268 starttime: 1495678451.0 results: success (0) revision: f81bcc23d37d7bec48f08b19a9327e93c54d37b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:10.998666) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:10.999106) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:10.999381) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:11.025234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:11.025540) ========= 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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-24 19:14:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.8M=0s 2017-05-24 19:14:11 (63.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.166828 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:11.215547) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:11.215872) ========= 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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.038377 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:11.275100) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 19:14:11.275431) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f81bcc23d37d7bec48f08b19a9327e93c54d37b5 --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 f81bcc23d37d7bec48f08b19a9327e93c54d37b5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.77scZ3EkJ3/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.S9VZbKzoJo/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-24 19:14:11,373 truncating revision to first 12 chars 2017-05-24 19:14:11,373 Setting DEBUG logging. 2017-05-24 19:14:11,373 attempt 1/10 2017-05-24 19:14:11,373 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f81bcc23d37d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 19:14:12,256 unpacking tar archive at: mozilla-central-f81bcc23d37d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.167467 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 19:14:12.465868) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:12.466369) ========= script_repo_revision: f81bcc23d37d7bec48f08b19a9327e93c54d37b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:12.466914) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:12.467201) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 19:14:12.486244) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-24 19:14:12.486546) ========= /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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:14:12 INFO - MultiFileLogger online at 20170524 19:14:12 in /builds/slave/test 19:14:12 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 19:14:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:14:12 INFO - {'append_to_log': False, 19:14:12 INFO - 'base_work_dir': '/builds/slave/test', 19:14:12 INFO - 'blob_upload_branch': 'Firefox', 19:14:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:14:12 INFO - 'branch': 'Firefox', 19:14:12 INFO - 'buildbot_json_path': 'buildprops.json', 19:14:12 INFO - 'config_files': ('talos/mac_config.py',), 19:14:12 INFO - 'default_actions': ('clobber', 19:14:12 INFO - 'read-buildbot-config', 19:14:12 INFO - 'download-and-extract', 19:14:12 INFO - 'populate-webroot', 19:14:12 INFO - 'create-virtualenv', 19:14:12 INFO - 'install', 19:14:12 INFO - 'run-tests'), 19:14:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:14:12 INFO - 'download_minidump_stackwalk': True, 19:14:12 INFO - 'download_symbols': 'ondemand', 19:14:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:14:12 INFO - 'tooltool.py': '/tools/tooltool.py', 19:14:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:14:12 INFO - '/tools/misc-python/virtualenv.py')}, 19:14:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:14:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:14:12 INFO - 'gecko_profile': False, 19:14:12 INFO - 'gecko_profile_interval': 0, 19:14:12 INFO - 'installer_path': 'installer.exe', 19:14:12 INFO - 'log_level': 'info', 19:14:12 INFO - 'log_name': 'talos', 19:14:12 INFO - 'log_to_console': True, 19:14:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:14:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:14:12 INFO - 'opt_config_files': (), 19:14:12 INFO - 'pip_index': False, 19:14:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:14:12 INFO - 'cmd': ('bash', 19:14:12 INFO - '-c', 19:14:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:14:12 INFO - 'enabled': True, 19:14:12 INFO - 'halt_on_failure': False, 19:14:12 INFO - 'name': 'check_screen_resolution'},), 19:14:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:14:12 INFO - 'cmd': ('bash', 19:14:12 INFO - '-c', 19:14:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:14:12 INFO - 'enabled': True, 19:14:12 INFO - 'halt_on_failure': False, 19:14:12 INFO - 'name': 'check_screen_resolution'},), 19:14:12 INFO - 'run_cmd_checks_enabled': True, 19:14:12 INFO - 'suite': 'dromaeojs-e10s', 19:14:12 INFO - 'system_bits': '32', 19:14:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:14:12 INFO - 'title': 't-yosemite-r7-0268', 19:14:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:14:12 INFO - 'use_talos_json': True, 19:14:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:14:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:14:12 INFO - 'work_dir': 'build'} 19:14:12 INFO - [mozharness: 2017-05-25 02:14:12.693210Z] Running clobber step. 19:14:12 INFO - Running pre-action listener: _resource_record_pre_action 19:14:12 INFO - Running main action method: clobber 19:14:12 INFO - rmtree: /builds/slave/test/build 19:14:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:14:15 INFO - Running post-action listener: _resource_record_post_action 19:14:15 INFO - [mozharness: 2017-05-25 02:14:15.146102Z] Finished clobber step (success) 19:14:15 INFO - [mozharness: 2017-05-25 02:14:15.146299Z] Running read-buildbot-config step. 19:14:15 INFO - Running pre-action listener: _resource_record_pre_action 19:14:15 INFO - Running main action method: read_buildbot_config 19:14:15 INFO - Using buildbot properties: 19:14:15 INFO - { 19:14:15 INFO - "project": "", 19:14:15 INFO - "product": "firefox", 19:14:15 INFO - "who": "ryanvm@gmail.com", 19:14:15 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 19:14:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:14:15 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:14:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:14:15 INFO - "stage_platform": "macosx64", 19:14:15 INFO - "basedir": "/builds/slave/test", 19:14:15 INFO - "buildnumber": 223, 19:14:15 INFO - "platform": "yosemite_r7", 19:14:15 INFO - "parent_task_id": "ZMOJ3jTYQZWXi84RVQSCrA", 19:14:15 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:14:15 INFO - "slavebuilddir": "test", 19:14:15 INFO - "taskId": "aYWJNkbWRxKu3vWLDbbrEw", 19:14:15 INFO - "branch": "mozilla-central", 19:14:15 INFO - "script_repo_revision": "production", 19:14:15 INFO - "revision": "f81bcc23d37d7bec48f08b19a9327e93c54d37b5", 19:14:15 INFO - "slavename": "t-yosemite-r7-0268", 19:14:15 INFO - "repo_path": "mozilla-central" 19:14:15 INFO - } 19:14:15 INFO - Finding installer, test and symbols from parent task. 19:14:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aYWJNkbWRxKu3vWLDbbrEw'}, attempt #1 19:14:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aYWJNkbWRxKu3vWLDbbrEw'}, attempt #1 19:14:16 INFO - Task dependencies: CNxe9DqtSXe5qlJtsULkHA ZMOJ3jTYQZWXi84RVQSCrA 19:14:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA'}, attempt #1 19:14:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:14:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/test_packages.json 19:14:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/target.crashreporter-symbols.zip 19:14:16 INFO - Running post-action listener: _resource_record_post_action 19:14:16 INFO - [mozharness: 2017-05-25 02:14:16.851603Z] Finished read-buildbot-config step (success) 19:14:16 INFO - [mozharness: 2017-05-25 02:14:16.851710Z] Running download-and-extract step. 19:14:16 INFO - Running pre-action listener: _resource_record_pre_action 19:14:16 INFO - Running main action method: download_and_extract 19:14:16 INFO - mkdir: /builds/slave/test/build/tests 19:14:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:16 INFO - trying https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:14:16 INFO - Downloading https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:14:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 19:14:17 INFO - Downloaded 1328 bytes. 19:14:17 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:14:17 INFO - Using the following test package requirements: 19:14:17 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:14:17 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:14:17 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:14:17 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:14:17 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:14:17 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:14:17 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:14:17 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:14:17 INFO - u'jsshell-mac.zip'], 19:14:17 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:14:17 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:14:17 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:14:17 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:14:17 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:14:17 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:14:17 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:14:17 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:14:17 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 19:14:17 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:14:17 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:14:17 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:14:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:14:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:14:17 INFO - Fetch https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:14:19 INFO - Content-Length response header: 57626895 19:14:19 INFO - Bytes received: 57626895 19:14:37 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:14:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:14:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:14:37 INFO - Fetch https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:14:39 INFO - Content-Length response header: 57626895 19:14:39 INFO - Bytes received: 57626895 19:14:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:14:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:14:56 INFO - Fetch https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:14:58 INFO - Content-Length response header: 14411859 19:14:58 INFO - Bytes received: 14411859 19:14:58 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')]} 19:14:58 INFO - trying https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:14:58 INFO - Downloading https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:14:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 19:15:00 INFO - Downloaded 67210850 bytes. 19:15:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:15:00 INFO - mkdir: /builds/slave/test/properties 19:15:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:15:00 INFO - Writing to file /builds/slave/test/properties/build_url 19:15:00 INFO - Contents: 19:15:00 INFO - build_url:https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:15:00 INFO - Running post-action listener: _resource_record_post_action 19:15:00 INFO - Running post-action listener: set_extra_try_arguments 19:15:00 INFO - [mozharness: 2017-05-25 02:15:00.724028Z] Finished download-and-extract step (success) 19:15:00 INFO - [mozharness: 2017-05-25 02:15:00.724168Z] Running populate-webroot step. 19:15:00 INFO - Running pre-action listener: _resource_record_pre_action 19:15:00 INFO - Running main action method: populate_webroot 19:15:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:15:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:15:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:15:00 INFO - '--tppagecycles', 19:15:00 INFO - '7'], 19:15:00 INFO - 'tests': ['tsvgm']}}, 19:15:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:15:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:15:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:15:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:15:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:15:00 INFO - 'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:15:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:15:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:15:00 INFO - 'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['damp', 'tps']}, 19:15:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:15:00 INFO - 'tests': ['damp', 'tps']}, 19:15:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['dromaeo_dom']}, 19:15:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:15:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:15:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:15:00 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:15:00 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:15:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['a11yr', 19:15:00 INFO - 'ts_paint', 19:15:00 INFO - 'tpaint', 19:15:00 INFO - 'sessionrestore', 19:15:00 INFO - 'sessionrestore_no_auto_restore', 19:15:00 INFO - 'tabpaint']}, 19:15:00 INFO - 'other-e10s': {'tests': ['a11yr', 19:15:00 INFO - 'ts_paint', 19:15:00 INFO - 'tpaint', 19:15:00 INFO - 'sessionrestore', 19:15:00 INFO - 'sessionrestore_no_auto_restore', 19:15:00 INFO - 'tabpaint']}, 19:15:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:15:00 INFO - 'ts_paint', 19:15:00 INFO - 'tpaint', 19:15:00 INFO - 'sessionrestore', 19:15:00 INFO - 'sessionrestore_no_auto_restore', 19:15:00 INFO - 'tabpaint']}, 19:15:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:15:00 INFO - 'ts_paint', 19:15:00 INFO - 'tpaint', 19:15:00 INFO - 'sessionrestore', 19:15:00 INFO - 'sessionrestore_no_auto_restore', 19:15:00 INFO - 'tabpaint']}, 19:15:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['a11yr', 19:15:00 INFO - 'ts_paint', 19:15:00 INFO - 'tpaint', 19:15:00 INFO - 'sessionrestore', 19:15:00 INFO - 'sessionrestore_no_auto_restore', 19:15:00 INFO - 'tabpaint']}, 19:15:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['a11yr', 19:15:00 INFO - 'ts_paint', 19:15:00 INFO - 'tpaint', 19:15:00 INFO - 'sessionrestore', 19:15:00 INFO - 'sessionrestore_no_auto_restore', 19:15:00 INFO - 'tabpaint']}, 19:15:00 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:15:00 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:15:00 INFO - 'bloom_basic_ref']}, 19:15:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['tsvgx', 19:15:00 INFO - 'tsvgr_opacity', 19:15:00 INFO - 'tart', 19:15:00 INFO - 'tscrollx', 19:15:00 INFO - 'cart', 19:15:00 INFO - 'tsvg_static']}, 19:15:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:15:00 INFO - 'tsvgr_opacity', 19:15:00 INFO - 'tart', 19:15:00 INFO - 'tscrollx', 19:15:00 INFO - 'cart', 19:15:00 INFO - 'tsvg_static']}, 19:15:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:15:00 INFO - 'talos_options': ['--disable-e10s'], 19:15:00 INFO - 'tests': ['tp5o']}, 19:15:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:15:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:15:00 INFO - 'talos_options': ['--disable-e10s', 19:15:00 INFO - '--xperf_path', 19:15:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:00 INFO - 'tests': ['tp5n']}, 19:15:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:15:00 INFO - 'talos_options': ['--xperf_path', 19:15:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:15:00 INFO - 'tests': ['tp5n']}}, 19:15:00 INFO - 'talos.zip': {'path': '', 19:15:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:15:00 INFO - Running post-action listener: _resource_record_post_action 19:15:00 INFO - [mozharness: 2017-05-25 02:15:00.745558Z] Finished populate-webroot step (success) 19:15:00 INFO - [mozharness: 2017-05-25 02:15:00.745685Z] Running create-virtualenv step. 19:15:00 INFO - Running pre-action listener: _resource_record_pre_action 19:15:00 INFO - Running main action method: create_virtualenv 19:15:00 INFO - Creating virtualenv /builds/slave/test/build/venv 19:15:00 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 19:15:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:15:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:15:00 INFO - Using real prefix '/tools/python27' 19:15:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:15:01 INFO - Installing distribute.............................................................................................................................................................................................done. 19:15:04 INFO - Installing pip.................done. 19:15:04 INFO - Return code: 0 19:15:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:15:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:15:04 INFO - Reading from file tmpfile_stdout 19:15:04 INFO - Output received: 19:15:04 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) 19:15:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:15:04 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')]} 19:15:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:04 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')]} 19:15:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:04 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 19:15:04 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 19:15:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:04 INFO - 'HOME': '/Users/cltbld', 19:15:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:04 INFO - 'LOGNAME': 'cltbld', 19:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:04 INFO - 'MOZ_NO_REMOTE': '1', 19:15:04 INFO - 'NO_EM_RESTART': '1', 19:15:04 INFO - 'PAGER': '/bin/cat', 19:15:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:04 INFO - 'PWD': '/builds/slave/test', 19:15:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:04 INFO - 'SHELL': '/bin/bash', 19:15:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:04 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:04 INFO - 'USER': 'cltbld', 19:15:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:04 INFO - 'XPC_FLAGS': '0x0', 19:15:04 INFO - 'XPC_SERVICE_NAME': '0', 19:15:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:04 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 19:15:04 INFO - Cleaning up... 19:15:05 INFO - Return code: 0 19:15:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:15:05 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')]} 19:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:05 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')]} 19:15:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:05 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 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:05 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 19:15:05 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 19:15:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:05 INFO - 'HOME': '/Users/cltbld', 19:15:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:05 INFO - 'LOGNAME': 'cltbld', 19:15:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:05 INFO - 'MOZ_NO_REMOTE': '1', 19:15:05 INFO - 'NO_EM_RESTART': '1', 19:15:05 INFO - 'PAGER': '/bin/cat', 19:15:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:05 INFO - 'PWD': '/builds/slave/test', 19:15:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:05 INFO - 'SHELL': '/bin/bash', 19:15:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:05 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:05 INFO - 'USER': 'cltbld', 19:15:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:05 INFO - 'XPC_FLAGS': '0x0', 19:15:05 INFO - 'XPC_SERVICE_NAME': '0', 19:15:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:05 INFO - Downloading/unpacking psutil>=3.1.1 19:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:15:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:07 INFO - Installing collected packages: psutil 19:15:07 INFO - Running setup.py install for psutil 19:15:07 INFO - building 'psutil._psutil_osx' extension 19:15:07 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 19:15:08 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 19:15:08 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 19:15:08 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 19:15:08 INFO - building 'psutil._psutil_posix' extension 19:15:08 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 19:15:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:15:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:15:08 INFO - ^ 19:15:08 INFO - 1 warning generated. 19:15:08 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 19:15:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:15:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:15:08 INFO - Successfully installed psutil 19:15:08 INFO - Cleaning up... 19:15:08 INFO - Return code: 0 19:15:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:15:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:15:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:08 INFO - 'HOME': '/Users/cltbld', 19:15:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:08 INFO - 'LOGNAME': 'cltbld', 19:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:08 INFO - 'MOZ_NO_REMOTE': '1', 19:15:08 INFO - 'NO_EM_RESTART': '1', 19:15:08 INFO - 'PAGER': '/bin/cat', 19:15:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:08 INFO - 'PWD': '/builds/slave/test', 19:15:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:08 INFO - 'SHELL': '/bin/bash', 19:15:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:08 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:08 INFO - 'USER': 'cltbld', 19:15:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:08 INFO - 'XPC_FLAGS': '0x0', 19:15:08 INFO - 'XPC_SERVICE_NAME': '0', 19:15:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:15:09 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 19:15:09 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 19:15:09 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 19:15:09 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 19:15:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:15:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:15:10 INFO - Installing collected packages: mozsystemmonitor 19:15:10 INFO - Running setup.py install for mozsystemmonitor 19:15:10 INFO - Successfully installed mozsystemmonitor 19:15:10 INFO - Cleaning up... 19:15:10 INFO - Return code: 0 19:15:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:15:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:10 INFO - 'HOME': '/Users/cltbld', 19:15:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:10 INFO - 'LOGNAME': 'cltbld', 19:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:10 INFO - 'MOZ_NO_REMOTE': '1', 19:15:10 INFO - 'NO_EM_RESTART': '1', 19:15:10 INFO - 'PAGER': '/bin/cat', 19:15:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:10 INFO - 'PWD': '/builds/slave/test', 19:15:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:10 INFO - 'SHELL': '/bin/bash', 19:15:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:10 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:10 INFO - 'USER': 'cltbld', 19:15:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:10 INFO - 'XPC_FLAGS': '0x0', 19:15:10 INFO - 'XPC_SERVICE_NAME': '0', 19:15:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:10 INFO - Downloading/unpacking jsonschema==2.5.1 19:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:15:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:15:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:15:12 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 19:15:12 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 19:15:12 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 19:15:12 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 19:15:12 INFO - Downloading functools32-3.2.3-2.tar.gz 19:15:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:15:12 INFO - warning: no files found matching '*.txt' 19:15:12 INFO - no previously-included directories found matching 'build' 19:15:12 INFO - no previously-included directories found matching 'dist' 19:15:12 INFO - no previously-included directories found matching '.git*' 19:15:12 INFO - Installing collected packages: jsonschema, functools32 19:15:12 INFO - Running setup.py install for functools32 19:15:12 INFO - warning: no files found matching '*.txt' 19:15:12 INFO - no previously-included directories found matching 'build' 19:15:12 INFO - no previously-included directories found matching 'dist' 19:15:12 INFO - no previously-included directories found matching '.git*' 19:15:12 INFO - Successfully installed jsonschema functools32 19:15:12 INFO - Cleaning up... 19:15:13 INFO - Return code: 0 19:15:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:13 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 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:13 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 19:15:13 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 19:15:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:13 INFO - 'HOME': '/Users/cltbld', 19:15:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:13 INFO - 'LOGNAME': 'cltbld', 19:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:13 INFO - 'MOZ_NO_REMOTE': '1', 19:15:13 INFO - 'NO_EM_RESTART': '1', 19:15:13 INFO - 'PAGER': '/bin/cat', 19:15:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:13 INFO - 'PWD': '/builds/slave/test', 19:15:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:13 INFO - 'SHELL': '/bin/bash', 19:15:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:13 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:13 INFO - 'USER': 'cltbld', 19:15:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:13 INFO - 'XPC_FLAGS': '0x0', 19:15:13 INFO - 'XPC_SERVICE_NAME': '0', 19:15:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:15:13 INFO - Cleaning up... 19:15:13 INFO - Return code: 0 19:15:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:13 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 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:13 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 19:15:13 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 19:15:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:13 INFO - 'HOME': '/Users/cltbld', 19:15:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:13 INFO - 'LOGNAME': 'cltbld', 19:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:13 INFO - 'MOZ_NO_REMOTE': '1', 19:15:13 INFO - 'NO_EM_RESTART': '1', 19:15:13 INFO - 'PAGER': '/bin/cat', 19:15:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:13 INFO - 'PWD': '/builds/slave/test', 19:15:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:13 INFO - 'SHELL': '/bin/bash', 19:15:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:13 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:13 INFO - 'USER': 'cltbld', 19:15:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:13 INFO - 'XPC_FLAGS': '0x0', 19:15:13 INFO - 'XPC_SERVICE_NAME': '0', 19:15:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:13 INFO - Downloading/unpacking blobuploader==1.2.4 19:15:13 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 19:15:13 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 19:15:13 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 19:15:13 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 19:15:14 INFO - Downloading blobuploader-1.2.4.tar.gz 19:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:15:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:15:15 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 19:15:15 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 19:15:15 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 19:15:15 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 19:15:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:15:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:15:15 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 19:15:15 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 19:15:15 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 19:15:15 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 19:15:15 INFO - Downloading docopt-0.6.1.tar.gz 19:15:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:15:15 INFO - Installing collected packages: blobuploader, requests, docopt 19:15:15 INFO - Running setup.py install for blobuploader 19:15:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:15:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:15:16 INFO - Running setup.py install for requests 19:15:16 INFO - Running setup.py install for docopt 19:15:16 INFO - Successfully installed blobuploader requests docopt 19:15:16 INFO - Cleaning up... 19:15:16 INFO - Return code: 0 19:15:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:16 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 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:16 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 19:15:16 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 19:15:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:16 INFO - 'HOME': '/Users/cltbld', 19:15:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:16 INFO - 'LOGNAME': 'cltbld', 19:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:16 INFO - 'MOZ_NO_REMOTE': '1', 19:15:16 INFO - 'NO_EM_RESTART': '1', 19:15:16 INFO - 'PAGER': '/bin/cat', 19:15:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:16 INFO - 'PWD': '/builds/slave/test', 19:15:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:16 INFO - 'SHELL': '/bin/bash', 19:15:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:16 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:16 INFO - 'USER': 'cltbld', 19:15:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:16 INFO - 'XPC_FLAGS': '0x0', 19:15:16 INFO - 'XPC_SERVICE_NAME': '0', 19:15:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-SWupeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-DvUfqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-lISorL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-0BICry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-alXLm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ySP8jI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-9ZZXfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Ds8SHl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-FsY_y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:17 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-poFw_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Z9cl5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Uc7sz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-MHKejn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-aneHy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-rVAt8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Fh6goo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:18 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-nAAEvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:15:18 INFO - Running setup.py install for manifestparser 19:15:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:15:18 INFO - Running setup.py install for mozcrash 19:15:19 INFO - Running setup.py install for mozdebug 19:15:19 INFO - Running setup.py install for mozdevice 19:15:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:15:19 INFO - Running setup.py install for mozfile 19:15:19 INFO - Running setup.py install for mozhttpd 19:15:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:15:19 INFO - Running setup.py install for mozinfo 19:15:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:15:19 INFO - Running setup.py install for mozInstall 19:15:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:15:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:15:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:15:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:15:19 INFO - Running setup.py install for mozleak 19:15:20 INFO - Running setup.py install for mozlog 19:15:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:15:20 INFO - Running setup.py install for moznetwork 19:15:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:15:20 INFO - Running setup.py install for mozprocess 19:15:20 INFO - Running setup.py install for mozprofile 19:15:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:15:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:15:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:15:20 INFO - Running setup.py install for mozrunner 19:15:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:15:20 INFO - Running setup.py install for mozscreenshot 19:15:21 INFO - Running setup.py install for moztest 19:15:21 INFO - Running setup.py install for mozversion 19:15:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:15:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:15:21 INFO - Cleaning up... 19:15:21 INFO - Return code: 0 19:15:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15: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')]} 19:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15: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')]} 19:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:21 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 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:21 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 19:15:21 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 19:15:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:21 INFO - 'HOME': '/Users/cltbld', 19:15:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:21 INFO - 'LOGNAME': 'cltbld', 19:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:21 INFO - 'MOZ_NO_REMOTE': '1', 19:15:21 INFO - 'NO_EM_RESTART': '1', 19:15:21 INFO - 'PAGER': '/bin/cat', 19:15:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:21 INFO - 'PWD': '/builds/slave/test', 19:15:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:21 INFO - 'SHELL': '/bin/bash', 19:15:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:21 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:21 INFO - 'USER': 'cltbld', 19:15:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:21 INFO - 'XPC_FLAGS': '0x0', 19:15:21 INFO - 'XPC_SERVICE_NAME': '0', 19:15:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:15:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ER17LM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:15:21 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)) 19:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:15:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-28eZK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:15:21 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)) 19:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:15:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-W2SqSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:15:21 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)) 19:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:15:21 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ZrpfMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:15:22 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)) 19:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:15:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-EbWc69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:15:22 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)) 19:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:15:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-MXhUfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:15:22 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)) 19:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:15:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-33hPZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:15:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-wfkDT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:15:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:15:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-buLmBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:15:22 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)) 19:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:15:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-reIiOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:15:22 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)) 19:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:15:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-P9_v8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:15:22 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)) 19:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:15:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-EGHOKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:15:22 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)) 19:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:15:22 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-YYIOXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:15:23 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)) 19:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:15:23 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-myQfcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:15:23 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)) 19:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:23 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-9EiqjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:15:23 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)) 19:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:15:23 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-z5QURj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:15:23 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)) 19:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:15:23 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-QKgZfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:15:23 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)) 19:15:23 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)) 19:15:23 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)) 19:15:23 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)) 19:15:23 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)) 19:15:23 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)) 19:15:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:15:23 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 19:15:23 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 19:15:23 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 19:15:23 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 19:15:24 INFO - Downloading blessings-1.6.tar.gz 19:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:15:24 INFO - Installing collected packages: blessings 19:15:24 INFO - Running setup.py install for blessings 19:15:25 INFO - Successfully installed blessings 19:15:25 INFO - Cleaning up... 19:15:25 INFO - Return code: 0 19:15:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:15:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:25 INFO - Reading from file tmpfile_stdout 19:15:25 INFO - Current package versions: 19:15:25 INFO - blessings == 1.6 19:15:25 INFO - blobuploader == 1.2.4 19:15:25 INFO - docopt == 0.6.1 19:15:25 INFO - functools32 == 3.2.3-2 19:15:25 INFO - jsonschema == 2.5.1 19:15:25 INFO - manifestparser == 1.1 19:15:25 INFO - mozInstall == 1.12 19:15:25 INFO - mozcrash == 1.0 19:15:25 INFO - mozdebug == 0.1 19:15:25 INFO - mozdevice == 0.51 19:15:25 INFO - mozfile == 1.2 19:15:25 INFO - mozhttpd == 0.7 19:15:25 INFO - mozinfo == 0.9 19:15:25 INFO - mozleak == 0.1 19:15:25 INFO - mozlog == 3.4 19:15:25 INFO - moznetwork == 0.27 19:15:25 INFO - mozprocess == 0.25 19:15:25 INFO - mozprofile == 0.28 19:15:25 INFO - mozrunner == 6.13 19:15:25 INFO - mozscreenshot == 0.1 19:15:25 INFO - mozsystemmonitor == 0.3 19:15:25 INFO - moztest == 0.8 19:15:25 INFO - mozversion == 1.4 19:15:25 INFO - psutil == 3.1.1 19:15:25 INFO - requests == 1.2.3 19:15:25 INFO - wsgiref == 0.1.2 19:15:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:25 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 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:25 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 19:15:25 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 19:15:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:25 INFO - 'HOME': '/Users/cltbld', 19:15:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:25 INFO - 'LOGNAME': 'cltbld', 19:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:25 INFO - 'MOZ_NO_REMOTE': '1', 19:15:25 INFO - 'NO_EM_RESTART': '1', 19:15:25 INFO - 'PAGER': '/bin/cat', 19:15:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:25 INFO - 'PWD': '/builds/slave/test', 19:15:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:25 INFO - 'SHELL': '/bin/bash', 19:15:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:25 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:25 INFO - 'USER': 'cltbld', 19:15:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:25 INFO - 'XPC_FLAGS': '0x0', 19:15:25 INFO - 'XPC_SERVICE_NAME': '0', 19:15:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:25 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)) 19:15:25 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)) 19:15:25 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)) 19:15:25 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)) 19:15:25 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)) 19:15:25 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)) 19:15:25 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)) 19:15:25 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)) 19:15:25 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)) 19:15:25 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:15:25 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 19:15:25 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 19:15:25 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 19:15:25 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 19:15:27 INFO - Downloading marionette_driver-1.4.0.tar.gz 19:15:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 19:15:27 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)) 19:15:27 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)) 19:15:27 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)) 19:15:27 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)) 19:15:27 INFO - Installing collected packages: marionette-driver 19:15:27 INFO - Running setup.py install for marionette-driver 19:15:27 INFO - Successfully installed marionette-driver 19:15:27 INFO - Cleaning up... 19:15:27 INFO - Return code: 0 19:15:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:15:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:15:27 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 0x104719b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10471d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2a945c0f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, '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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 19:15:27 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 19:15:27 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 19:15:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:27 INFO - 'HOME': '/Users/cltbld', 19:15:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:27 INFO - 'LOGNAME': 'cltbld', 19:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:27 INFO - 'MOZ_NO_REMOTE': '1', 19:15:27 INFO - 'NO_EM_RESTART': '1', 19:15:27 INFO - 'PAGER': '/bin/cat', 19:15:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:27 INFO - 'PWD': '/builds/slave/test', 19:15:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:27 INFO - 'SHELL': '/bin/bash', 19:15:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:27 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:27 INFO - 'USER': 'cltbld', 19:15:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:27 INFO - 'XPC_FLAGS': '0x0', 19:15:27 INFO - 'XPC_SERVICE_NAME': '0', 19:15:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:15:27 INFO - Cleaning up... 19:15:27 INFO - Return code: 0 19:15:27 INFO - Running post-action listener: _resource_record_post_action 19:15:27 INFO - Running post-action listener: _start_resource_monitoring 19:15:27 INFO - Starting resource monitoring. 19:15:27 INFO - [mozharness: 2017-05-25 02:15:27.770557Z] Finished create-virtualenv step (success) 19:15:27 INFO - [mozharness: 2017-05-25 02:15:27.771533Z] Running install step. 19:15:27 INFO - Running pre-action listener: _resource_record_pre_action 19:15:27 INFO - Running main action method: install 19:15:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:15:28 INFO - Reading from file tmpfile_stdout 19:15:28 INFO - Detecting whether we're running mozinstall >=1.0... 19:15:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:15:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:15:28 INFO - Reading from file tmpfile_stdout 19:15:28 INFO - Output received: 19:15:28 INFO - Usage: mozinstall [options] installer 19:15:28 INFO - Options: 19:15:28 INFO - -h, --help show this help message and exit 19:15:28 INFO - -d DEST, --destination=DEST 19:15:28 INFO - Directory to install application into. [default: 19:15:28 INFO - "/builds/slave/test"] 19:15:28 INFO - --app=APP Application being installed. [default: firefox] 19:15:28 INFO - mkdir: /builds/slave/test/build/application 19:15:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:15:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:15:50 INFO - Reading from file tmpfile_stdout 19:15:50 INFO - Output received: 19:15:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:15:50 INFO - Running post-action listener: _resource_record_post_action 19:15:50 INFO - [mozharness: 2017-05-25 02:15:50.511128Z] Finished install step (success) 19:15:50 INFO - [mozharness: 2017-05-25 02:15:50.511314Z] Running run-tests step. 19:15:50 INFO - Running pre-action listener: _resource_record_pre_action 19:15:50 INFO - Running main action method: run_tests 19:15:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:15:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:15:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:15:50 INFO - 2017-05-24 19:15:50.525 screenresolution[1630:7620] starting screenresolution argv=screenresolution get 19:15:50 INFO - 2017-05-24 19:15:50.528 screenresolution[1630:7620] Display 0: 1600x1200x32@60 19:15:50 INFO - 2017-05-24 19:15:50.534 screenresolution[1631:7623] starting screenresolution argv=screenresolution list 19:15:50 INFO - Available Modes on Display 0 19:15:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:15:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:15:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:15:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:15:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:15:50 INFO - Intel Iris: 19:15:50 INFO - Chipset Model: Intel Iris 19:15:50 INFO - Type: GPU 19:15:50 INFO - Bus: Built-In 19:15:50 INFO - VRAM (Dynamic, Max): 1536 MB 19:15:50 INFO - Vendor: Intel (0x8086) 19:15:50 INFO - Device ID: 0x0a2e 19:15:50 INFO - Revision ID: 0x0009 19:15:50 INFO - Displays: 19:15:50 INFO - Display: 19:15:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:15:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:15:50 INFO - Display Serial Number: 1600x1200 60 19:15:50 INFO - Main Display: Yes 19:15:50 INFO - Mirror: Off 19:15:50 INFO - Online: Yes 19:15:50 INFO - Rotation: Supported 19:15:50 INFO - Return code: 0 19:15:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:15:50 INFO - Python 2.7.3 19:15:50 INFO - Return code: 0 19:15:50 INFO - grabbing minidump binary from tooltool 19:15:50 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')]} 19:15:50 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 0x104429d30>, '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 0x1043dddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10441ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:15:50 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 19:15:50 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 19:15:50 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 19:15:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:15:50 INFO - Return code: 0 19:15:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:15:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:15:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:15:50 INFO - ENV: RUST_BACKTRACE is now 1 19:15:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:15:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:15:50 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/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 19:15:50 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/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0268 --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 19:15:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.77scZ3EkJ3/Render', 19:15:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:15:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:15:50 INFO - 'HOME': '/Users/cltbld', 19:15:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:15:50 INFO - 'LOGNAME': 'cltbld', 19:15:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:15:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:15:50 INFO - 'MOZ_NO_REMOTE': '1', 19:15:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:15:50 INFO - 'NO_EM_RESTART': '1', 19:15:50 INFO - 'PAGER': '/bin/cat', 19:15:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:15:50 INFO - 'PWD': '/builds/slave/test', 19:15:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:15:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:15:50 INFO - 'RUST_BACKTRACE': '1', 19:15:50 INFO - 'SHELL': '/bin/bash', 19:15:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S9VZbKzoJo/Listeners', 19:15:50 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 19:15:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:15:50 INFO - 'USER': 'cltbld', 19:15:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:15:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:15:50 INFO - 'XPC_FLAGS': '0x0', 19:15:50 INFO - 'XPC_SERVICE_NAME': '0', 19:15:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:15:50 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/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 19:15:51 INFO - mozversion application_buildid: 20170524174935 19:15:51 INFO - mozversion application_changeset: f81bcc23d37d7bec48f08b19a9327e93c54d37b5 19:15:51 INFO - mozversion application_display_name: Nightly 19:15:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:15:51 INFO - mozversion application_name: Firefox 19:15:51 INFO - mozversion application_remotingname: firefox 19:15:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:15:51 INFO - mozversion application_vendor: Mozilla 19:15:51 INFO - mozversion application_version: 55.0a1 19:15:51 INFO - mozversion platform_buildid: 20170524174935 19:15:51 INFO - mozversion platform_changeset: f81bcc23d37d7bec48f08b19a9327e93c54d37b5 19:15:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:15:51 INFO - mozversion platform_version: 55.0a1 19:15:51 INFO - using testdate: 1495678551 19:15:51 INFO - actual date: 1495678551 19:15:51 INFO - starting webserver on 'localhost:49227' 19:15:51 INFO - SUITE-START | Running 2 tests 19:15:51 INFO - TEST-START | dromaeo_css 19:15:51 INFO - operating with platform_type : mac_ 19:15:51 INFO - Initialising browser for dromaeo_css test... 19:15:51 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmp93hBA1/profile 19:15:51 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 19:16:24 INFO - TEST-INFO | 1641: killed by SIGTERM 19:16:24 INFO - Browser initialized. 19:16:24 INFO - Running cycle 1/1 for dromaeo_css test... 19:16:24 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmp93hBA1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:26 INFO - PID 1651 | 2017-05-24 19:16:26.707 plugin-container[1652:8361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 19:16:26 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:26 INFO - PID 1651 | 2017-05-24 19:16:26.707 plugin-container[1652:8361] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 19:16:26 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:26 INFO - PID 1651 | 2017-05-24 19:16:26.707 plugin-container[1652:8361] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:16:27 INFO - PID 1651 | 2017-05-24 19:16:27.141 plugin-container[1653:8403] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 19:16:27 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:27 INFO - PID 1651 | 2017-05-24 19:16:27.142 plugin-container[1653:8403] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 19:16:27 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:27 INFO - PID 1651 | 2017-05-24 19:16:27.142 plugin-container[1653:8403] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:16:36 INFO - PID 1651 | RSS: Main: 246190080 19:16:36 INFO - PID 1651 | 19:19:39 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 19:19:41 INFO - PID 1651 | RSS: Main: 257351680 19:19:41 INFO - PID 1651 | 19:22:43 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 19:22:43 INFO - PID 1651 | RSS: Main: 272789504 19:22:43 INFO - PID 1651 | 19:25:45 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 19:25:45 INFO - PID 1651 | RSS: Main: 271851520 19:25:45 INFO - PID 1651 | 19:28:47 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 19:28:48 INFO - PID 1651 | RSS: Main: 275648512 19:28:48 INFO - PID 1651 | 19:31:51 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 19:31:51 INFO - PID 1651 | RSS: Main: 275804160 19:31:51 INFO - PID 1651 | 19:34:53 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 19:34:53 INFO - PID 1651 | RSS: Main: 276656128 19:34:53 INFO - PID 1651 | 19:34:53 INFO - PID 1651 | __start_tp_report 19:34:53 INFO - PID 1651 | _x_x_mozilla_page_load 19:34:53 INFO - PID 1651 | _x_x_mozilla_page_load_details 19:34:53 INFO - PID 1651 | |i|pagename|runs| 19:34:53 INFO - PID 1651 | |0;dojo.html;2896.1022083154216;3366.528685983962;3439.86240550378;3511.5259439975594;3554.733394995376;4913.631477639178;6033.396660333964;6046.334903160659;6092.390760923905;6096.634201947886;5572.554195664347;5824.359320474744;6107.219158344521;6113.628337950551;6238.968805155968;4444.488883778358;5253.527182553569;5383.946160538393;5384.7846086156505;5407.621466497347;5396.91904621431;5432.402435732066;5460.31746031746;5493.340799104113;5493.533049690761;4111.383292506118;4259.850905218303;5268.341457317835;5337.439568845293;5510.283663123788;5335.986162629088;5391.16436952273;5399;5423.430539793344;5425.701586412746;5354.812581559627;5365.436629153922;5382.6232163748555;5401.054815407328;5401.405845357008;5306.283651707009;5971.313298970631;6195.1946246988055;6207.937920620781;6215.067739839015;6349.8412539686415;6441.355864413568;6505.60966342021;6601.768938087192;6626.635535045571;5820.476157145835;6569.211694596631;6740.93259067408;6768.458523318172;6773.3903948644365;6262.467690246334;6266.655333956585;6278.37216278373;6278.591891527082;6298.653574053471;6613.603183808987;6680.966595166993;6697.196336439659;6822.249552549216;6899.517033807585;6254.656719086833;6279.686015699197;6388.520860935449;6461.19235095613;6504;5565.9443405566235;6680.398764111156;6680.966595166993;6717.059611654372;6731.764388246388;6543.509236807258;6570.868582628416;6573.276939536647;6585.374389432996;6606.636634985122;6370.299267080617;6445.51658625605;6488.7728929488185;6582.44049255809;6589.439897608661;5291.947080529222;5325.041492531382;5326.067938110815;5326.9467305327225;5362.624616276823;4724.055188962221;4736.668433209711;4785.808567657325;4814.253790662453;4816.422029356429;5401.864953376197;6147.200863887666;6308.558400911892;6309.558330916791;6321.683915804191;6546.3453654635105;6571.868562628721;6671.366220209073;6678.365555272241;6678.799636010927;5202.323697919246;5333.7066461345;6225.972714502147;6242.032484964838;6263.279722831841;5877.38287479817;5916.881662366729;5926.436988486087;5933.970330148321;5941.346451890288;6550.181227346582;6651;6741.1236539249585;6816.625085620339;6827.795166145023;6635.701393437211;6713.697883595152;6720.731170753311;6733.966330168318;6736.8652626946205;6807.523473357016;6811.114555107804;6814.659267036727;6825.52221344521;6827.965860170667;5663.5469162467725;6330.49356051524;6390.520710946605;6455.4512866406885;6479.773207937699;6640.103586015826;6711.63086030273;6731.46148308144;6733.124693789776;6734.8653026940165;6294.433500984934;6522.28254891952;6539.836504087436;6540.084388185753;6560.114384558024;6317.494600431863;6373.235211774617;6445.129907462432;6526.118973938647;6537.640429776408;6189.1954045975535;6402.647854368054;6411;6417.069524918954;6418.839529011812;6383.297837237993;6429.7749578764515;6434.613923164625;6507.511936604679;6561.901571476336;6381.521385895983;6392.073149393219;6409.2949775525585;6410.679466026587;6411.51913606472 19:34:53 INFO - PID 1651 | |1;ext.html;739.2164305835814;762.3063012658482;791.378767667381;1201.4713526048533;1262.5013119817677;39106.608933910655;40349.3947590786;40424.79787601065;40427.989300267545;40429.191416171656;41392.793036034855;41398.96502587437;41442.58557414425;41449.963750906245;41452;7579.128400233961;7733.7293194738195;8081.191880811931;8273.751787446387;8291.170882911721;8217.835643287131;8257.21556452136;8297.917020829806;8306.626201820933;8312.085670576262;8250.381221408388;8278.668853245894;8335.333173346147;8338.83322333553;8364.414490985633;8189.303909167698;8236.217559331853;8281.420300578962;8318.085010648854;8318.41771076025;6371.936280637181;8127.2279133482225;8159.9184008159755;8195.754127376129;8234.917650823534;36584.634153658386;36709.71515996954;36713.632863671286;36719;36798.44802327967;3590.4434812603827;3591.1381268495634;3631.8547258109643;3641.85432582699;3652.0504668786043;25281.862316195817;29064.564031539543;30112.397752044835;30435.543466848016;30584;3059.6175478065243;3081.5223640335785;3090.891818786332;3113.797603155788;3124.765642576816;239007.80496097583;242119;273965.63017184986;291490;292455;48033.5196648029;48070;48432.515674843504;48464.27303590449;48518.75740621345;39973.60026399757;49431.50568494341;49938.50061499411;49947;50250.74874625603;13985.3706583204;13989.86010139906;14074.71850562983;14101.576952691436;14124.152550846777;11434.542618295342;11451.427428628534;11455.198136130392;11465.025472834903;11487;13614.795778063337;13635.181889086687;13652.658683532793;13672.65818354549;13678.452861885413;5399.7300134993875;5971.373005834324;6107.4197951194565;6139.2325959255095;6175.135481032659;1001.8447140693204;1022.3967858963099;1025.1747343388645;1052.600011995437;1060.4008735064663;23616.055357785495;24865.5026899461;24923;24969.5006099877;25058;1111.7109551516664;1114.5541783286587;1143.1654891928938;1152.8847115288404;1160.1704781081398;10981.945090274658;12712.364381780873;12723.109382343147;12751.36243187837;12789.424475898606;319099;320470;320490;320626;321758;54854.45145548573;55081.17378239249;55106.17340739892;55173.34479965607;55267.170992435145;1429.178222522054;1437.6190309567974;1495.76067829149;1531.4104069933155;1548.7599422089595;21743.130274788833;23010.654840177747;24190.51618967646;24219.152329668377;25339.98640054377;37996.62003380041;38396.808015959745;38490;38490.615093848704;38543.614563854004;267305.6634716814;267392;268175;269325;270116.59766804945;33996.830015849766;34760;34916.82541587276;35392.646073538934;35411.93764187078;43199;44464;45995.77002115102;46085.539144609465;46126;24405.145819896217;28706.425871482035;28822.702978366444;30474;31072.68927310698;26092.478150437266;27309.86345068262;30450.868439209044;31028.53457198099;32179.678203218606;36363.27273454569;38183.809080954416;38323;38446.615533844306;38447;29222;31013.0696079118;31724.841375792974;31899.521507176945;32242.516362254115;23311.300660980196;24543.754562454145;24550;24882;25015.249542513753 19:34:53 INFO - PID 1651 | |2;jquery.html;325.6596318970407;381.8090954522739;382.87939299120774;417.2343749220181;450.59446498151664;20707.585848283044;22274.665880011813;22291.21980730675;22973.19593814217;24084.03663853444;23346.416339591477;23640;23678.171264005763;23863.761362386373;23892.522149556997;5292.814751483699;5866.442687944649;6172.938270617304;6174.598651087687;6190.442860142586;5837.912431313513;6041.848953776169;6082.665453400084;6088.36072212418;6123.846903827418;6063.18147050147;6082.300535438437;6085.178500902388;6091.664958427285;6101.633901965874;5054.620903432257;5901.468867801918;6036.185115009462;6052.182955301044;6073.3622969588;5398.568114550866;5401.081816091274;5407.3781515125875;5418.133098704188;5440.292761940922;27629.447411051868;28192.577111343347;28206.153815385573;28276.434471310666;28495.715042849515;2109.1141720477285;2169.8481106322565;2172.891355432222;2178.2049551913437;2186.4533866533366;17894.194761235645;18000.729989050174;18195.18121684514;18357.908210458863;18588.442346729484;2103.200783702199;2121.830253579725;2139.689744986984;2163.3618082665653;2166.566686662658;142154;160749;170817;170821;171559.14220428944;12452.377381130907;12796.936015319863;12883.742325153444;12945.417456214493;12967.286799225945;16726.498205053867;16797.748033779502;16841.221198502353;16842.578935526464;16847.83152168487;8740.606672699616;8773.385862989657;8979.595918183697;8995.55022248885;9089.95455022734;8412.411131220755;8420.915790842135;8449;8518.70184543538;8541.658333666708;7262.600556969417;7725.7295994641045;8034.15641357649;8150.184981501921;8223.712170074019;2209.116353458597;2219.622664147099;2221.589006033889;2221.688963545105;2387.558301714154;882.8587426011808;926.8748718922994;956.4452617481844;958.5918137252489;991.7867658453467;17672.469825905242;17796.3771268005;20463.693044604344;20790.48023799387;21502.032408541654;970.8834939807127;996.0985308296003;1001.67946257197;1011.5094179323022;1011.8178727829062;9923.652672156584;10174.338667986705;10673.83989240162;10743;10752.677419677422;461924.3807561949;545837;554997;560145;561806;130204;133702;135637;136282.3185884084;136871.99999999802;1088.9945550272198;1166.6383421139474;1200.1359021504677;1228.5761412312738;1243.657994051643;22423.887880560495;22627.09491620317;22758.20346287872;22863.31410057701;23383.532329352976;60269;60831;61072.38927610667;61335;62435.12694619169;82686.17313827026;83271.75092373499;83411.5829420849;83618.3276334482;84090;8628.094050124646;8783.429077109968;8847.778805529913;8911;12936.22382657006;12452.875471245172;13965.860341396456;13972.161670299814;14290.714185716435;14350.282485875874;11776.941115294369;11784.764304713686;11876.109291803323;12008.279503229835;12128.454219560317;10268.07587317145;10633.149348052291;11290.717732056764;11344.489497972776;11604;11815.527378904748;11993.880061199625;12069.818952715892;12236.082293828176;12279.693007674523;12933.224006559636;13060.608181754562;13293.335333233106;13353.39909704085;13377.53178638743;11727.706807329885;11972.760544788882;12085.335306557872;12110.45502952387;12208.755824883276 19:34:53 INFO - PID 1651 | |3;mootools.html;1337.3580681272988;1424.8147740793709;1428.6999730056687;1433.8027746831401;1448.7971683964247;10498.737531561716;11721.472533735981;12181.817272740916;12191.756164876697;12276.508939642415;12009.339486328248;12050.276983381003;12174.269543827377;12174.573889913854;12188.634340969786;2543.173468622696;2570.6144078388297;2583.9612405813928;2588.327034970912;2590.5207536605694;2309.76427611228;2434.233216536785;2516.169664010872;2558.6801649793774;2563.346346681603;2140.2188201306567;2273.8294627902974;2443.0838435586656;2548.3501707064675;2551.413174969758;2481.5409149307347;2500.2999160235163;2508.2726009457238;2512.6859142607173;2515.371157210697;2190.539986602815;2457;2471.641611966255;2476.5418397596504;2576.4074262919635;11978.820317695241;12052.156349055569;12077.698057548632;12087.818682719766;12094.697632559168;6556.147700798913;6784.694688739019;6800.421964133055;6807.693653785592;6814.216365118025;4163.354680024601;4312.719673221262;4377.781110944472;4393.934090988638;4406.493253275882;1223.5717498875322;1253.7617852607975;1256.9434375453036;1262.1480500662108;1266.2718936611486;15451.459198928096;15779.76330355034;16677.49967500977;16724.331026758915;16750.664986700198;5484.533814625722;5536.501714845603;5681.687507187145;5682.715864206773;5699.515541178963;11909.702257443632;11994.760104797855;12006.699832504082;12027.699307517381;12058.939705301593;6410.134631824737;6478;6577.243616984012;6577.736890524421;6596.86806263872;6109.236345456818;6176.258848938066;6183.938160618426;6235.127082208494;6271.592346497463;4273.893152671208;4325.891852703708;4363.38912552243;4383.846565370261;4396.758178300161;1227.5028613411582;1244.8381710377773;1258.8929940955122;1259.168948494008;1265.240855486719;782.5891407011364;783.8275579372529;802.5144787403536;803.4737247103157;803.5459965119688;6343.460805831556;6721.563098398588;6927.09947706805;7011.824704382431;7080.539764915264;900.1628485508539;947.280067148958;952.8189643967625;954.5227386306847;955.8757361543094;3467.549218601566;4386.298192289219;4449.2658711312915;4459.687821852439;4578.427696537933;14632.56102316911;15149.469768558269;15240.542783716506;15328.310226039855;15396.538103856903;14666.779998300033;15020.248987550578;15220.238988050553;15224.086554806747;15224.847751522342;8378.748637540884;8416.915830841735;8533.146685331541;8564.828703426021;8568.057513673491;6172.382761723792;6944.340287672663;7285.7449989252245;7319.780406587811;7342.118945726547;13460.326983650973;13594.320283985959;13875.79186312186;13924;14161.575152745434;11439.084873209957;13960.301984900918;14106.365213565208;14120.2939853009;14148.646283842987;7763.146053934176;9377.718668439958;9599.992000840091;9628.32601717873;9668.661596844076;10826.837597435888;10910.945445272722;10936.671899843017;10941.890581094405;10975.83536246941;7621.1997740236475;8968.955155224183;9240.584173712065;9258.398204116711;9328.580213890256;7221.566705997657;8954.776130596527;8968.730938071869;9093.818123637358;9174.587143578421;8387;8390.412671112963;8414.6213420395;8458.70394536213;8557.957210213908;5669.574781891292;5733.770649174153;5805.883882322245;5806.925718742046;5974.4324289192455;5476.698781567052;6122.204113465221;6244.812655620339;6500.284968653539;6510.381513756186 19:34:53 INFO - PID 1651 | |4;prototype.html;344.722498388797;386.32971793937514;390.1923019349945;390.6601256906492;402.91740193260375;6142.969285153568;6211.440970312677;6255.874882502348;6267.341929097447;6340.4927605791545;5020.021095886304;5096.719680417576;5783.913241301373;6194.5354098442685;6246.500279977602;3917.2557214129224;4013.418054382113;4246.002189485468;4419.602235798778;4533.886652833689;4487.349334346518;4500.257457519521;4512.864614061567;4515.390422292998;4548.249538826106;3702.4631428442995;4480.0143968327075;4499.1901457737595;4515.142122996636;4516.59985404524;4229.815651617552;4425.291953287458;4477.440319960005;4488.842890498833;4499.820007199708;3722.9441558376648;3835.980820095882;3839.4816699745734;3852.4028775539837;3861.3242682530727;4923.556879880792;6021.1163799349615;6058.787942422038;6062.757489700407;6081.726322315506;5216.191490319006;5343.118385466393;5387.973060134714;5405.783768649249;5409.864753381157;6610;6629.635370054645;6634.440906386995;6650.843222985634;6659.600423974529;4116.115035267432;4252.4046633471335;4351.891202719926;4357.673174511924;4374.8563204030115;5767.942320576783;6689.1972963244725;6851.931480685229;6932.930670693279;6965.825854353631;4261.488621365456;4410.089341034965;4419.845305414295;4422.845200418034;4442.311441726538;4422.010095457057;4484.304932735431;4487.977560112244;4493.28107502798;4524.140413321458;4418.4918734345965;4480.327950807405;4659.487456379796;5080.697546594669;5332.33345831771;5211.983663185718;5948.583599148018;6433.002884552901;6658.5671931324305;6671.299513551105;1280.737448822989;1522.4519173097676;1531.5864716526476;1533.4402942925751;1540.5917431880562;946.6639343033124;953.1945506047379;985.074030411411;1009.9747506312254;1018.2414101494286;5791.768329266866;6230.657313847782;6275.905861412082;6307.716152773136;6312.495000399957;5107.489251074863;6049.911016017071;6277.5605707600025;6873.419113603799;6928.584284942919;5497.752601132958;5850.736716847752;6226.159468471789;6232.034034724625;6297.6536290503545;3606.3328284267495;3624.383854744647;3634;3648.744587878823;3658.32321020612;5727.4845263926445;6957.547759395724;6966.686499107553;7075.82310442243;7113.57318560888;7042.6830792614455;7078.75224367155;7094.858102838018;7113.857722845514;7150.5352152109945;6427.039752875325;6951.443884489228;7097.112860892388;7112.857742845217;7124.608146551989;5763.827085187451;6983.406410454965;6996.300369962963;7002.719891204295;7019.19279282889;5566.415526369835;6522.478201743944;6726.764563240263;6768.289329620319;6859.828504287233;6345.555811093179;6631.767888123893;6863.485238607024;6960.965195174194;7106.857862842611;4421.204183247047;4429.161897812501;4431.401760762255;4446.177457170434;4454.821807127808;5900;6544.7892139954265;6892.551984121016;6944.236134025355;6955.095837541087;5558.777648893999;5793.565482588907;6247.5001999839;6676.732930682719;6692.130023096966;6104.9389506106145;6446.355364463704;6544.4149560924125;6633.800985970429;6707.865842683216;6208.689565521616;6535.738570457129;6553.0681591044995;6588.209414870246;6598.901016484852;5940.9503721216115;6561.606303621798;6615.941449368078;6714;6722.563033402813;5912.556558258062;6471.417572418317;6504.4796416285635;6575.605463672195;6592.274849766618 19:34:53 INFO - PID 1651 | |5;yui.html;780.5394817057942;808.5027707959607;834.4492634860992;846.4624963148402;875.9868601970971;14202.502912398068;14682.559523214293;15996.760048599266;16383.098929558884;16529.669406611876;17768.82231177688;18148.092595370246;18468.261269549235;18538.53653658659;18542.072896355163;964.8600952861831;968.4867020479122;973.3819024919175;978.0903759503659;978.4031740638205;834.8163404051099;935.8175155844579;981.1562056631292;981.3719219699398;994.7363948553639;922.5341202692631;1033.503918119303;1038.9532471038783;1040.6409788622914;1041.0058394233527;1025.7897131088146;1031.4327120084;1032.5714828346227;1032.654060889603;1033.41095575522;642.9132067170918;752.3341842469399;758.0486489455753;758.0751483190498;759.7758661194905;16391.34434622614;16779.66440671192;16811.915940420342;16933.407330743365;16958;17263;17404.912975435043;17716.822831771777;17778.28886844525;17797.555061123447;14568.49010284635;14884.851151488454;14892.255387230594;14917.627059323495;14929.552113436614;3504.491848681927;3591.838367273479;3616.9819150904077;3618.6743193112484;3631.0559254593995;128947.06579401223;144405.2779736105;148045.25977370044;153171;153886;13511.391987360592;13627.591172264847;13746.518871839638;13751.587452376445;13829.723405531833;26334.73665263323;26428.0750173743;26889.731102689115;27019.1894243173;27027.729722702916;6634.469242460592;6825.419839313615;7293.817654558678;7406.481546291816;7616.04799400075;3822.1973385589336;3906.4140378942816;3914.099757055893;3921.980285125888;3926.155876486569;4316.935245971313;4366.388705581221;4380.080183161508;4404.471463424409;4405.889852753643;924.4314746430897;934.303943562037;945.3807755919884;945.669015844449;947.625687853294;646.1632186318728;659.120074700271;664.5414663881907;666.1706175811049;681.57401623985;11064;11336.659900203007;11913.642590722293;12213;12635.810462843065;578.0779656447959;622.4242575617536;643.4144928115393;653.3858173317067;676.6109487044969;4187.413762073312;5538.695371754592;5613.624661957847;5625.634333768292;5881;153032.2348388251;155299;156185.31444056696;156276.43723562846;156445;129901.0514842278;130686.34656826656;131231.34384328016;132320;133230.33384833013;87291.56354218315;92691.60962585566;104590.47704761427;106724.93275067305;108760.91239087666;11124;11267.718307042389;11586.884131158919;11655.242409243372;11848.585299514476;36645.816770915975;45199.77400112979;45930.54069459396;47472.101115954974;48335;71455.64272178606;93838.06161938293;94547.59976402453;96265;98978;14990.175540345386;18248.817511824713;18254.26982920668;18468.907655461637;18574.164162612986;19070.33253836109;19131.33040343581;19162.23351065942;19396.903015484833;19476.318328858422;18054.368097116538;18104.637907242046;18112.818871811112;18368.908155459136;18369.724454133466;18233.817661823214;18299;18308.993005384833;18453.63092738111;18505.629887402447;17561.648767024333;17754.02352870553;17862.642747145244;18005.729914051037;18102;11958.760824783281;12056.336901470504;12084.45619947122;12160.513579456723;12801;13745.725085498034;14571.781423278871;14589.489367872073;14596.12423254608;14651.706965860836 19:34:53 INFO - PID 1651 | __end_tp_report 19:34:53 INFO - PID 1651 | __start_cc_report 19:34:53 INFO - PID 1651 | _x_x_mozilla_cycle_collect,403 19:34:53 INFO - PID 1651 | __end_cc_report 19:34:53 INFO - PID 1651 | __startTimestamp1495679693842__endTimestamp 19:34:53 INFO - PID 1651 | 19:34:53 INFO - PID 1651 | ------- Summary: start ------- 19:34:53 INFO - PID 1651 | Number of tests: 6 19:34:53 INFO - PID 1651 | 19:34:53 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:6042.73 Median:6321.68 stddev:769.63 (12.2%) stddev-sans-first:731.34 19:34:53 INFO - PID 1651 | Values: 2896.1 3366.5 3439.9 3511.5 3554.7 4913.6 6033.4 6046.3 6092.4 6096.6 5572.6 5824.4 6107.2 6113.6 6239.0 4444.5 5253.5 5383.9 5384.8 5407.6 5396.9 5432.4 5460.3 5493.3 5493.5 4111.4 4259.9 5268.3 5337.4 5510.3 5336.0 5391.2 5399.0 5423.4 5425.7 5354.8 5365.4 5382.6 5401.1 5401.4 5306.3 5971.3 6195.2 6207.9 6215.1 6349.8 6441.4 6505.6 6601.8 6626.6 5820.5 6569.2 6740.9 6768.5 6773.4 6262.5 6266.7 6278.4 6278.6 6298.7 6613.6 6681.0 6697.2 6822.2 6899.5 6254.7 6279.7 6388.5 6461.2 6504.0 5565.9 6680.4 6681.0 6717.1 6731.8 6543.5 6570.9 6573.3 6585.4 6606.6 6370.3 6445.5 6488.8 6582.4 6589.4 5291.9 5325.0 5326.1 5326.9 5362.6 4724.1 4736.7 4785.8 4814.3 4816.4 5401.9 6147.2 6308.6 6309.6 6321.7 6546.3 6571.9 6671.4 6678.4 6678.8 5202.3 5333.7 6226.0 6242.0 6263.3 5877.4 5916.9 5926.4 5934.0 5941.3 6550.2 6651.0 6741.1 6816.6 6827.8 6635.7 6713.7 6720.7 6734.0 6736.9 6807.5 6811.1 6814.7 6825.5 6828.0 5663.5 6330.5 6390.5 6455.5 6479.8 6640.1 6711.6 6731.5 6733.1 6734.9 6294.4 6522.3 6539.8 6540.1 6560.1 6317.5 6373.2 6445.1 6526.1 6537.6 6189.2 6402.6 6411.0 6417.1 6418.8 6383.3 6429.8 6434.6 6507.5 6561.9 6381.5 6392.1 6409.3 6410.7 6411.5 19:34:53 INFO - PID 1651 | 19:34:53 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:43987.08 Median:32211.10 stddev:75169.08 (233.4%) stddev-sans-first:75309.82 19:34:53 INFO - PID 1651 | Values: 739.2 762.3 791.4 1201.5 1262.5 39106.6 40349.4 40424.8 40428.0 40429.2 41392.8 41399.0 41442.6 41450.0 41452.0 7579.1 7733.7 8081.2 8273.8 8291.2 8217.8 8257.2 8297.9 8306.6 8312.1 8250.4 8278.7 8335.3 8338.8 8364.4 8189.3 8236.2 8281.4 8318.1 8318.4 6371.9 8127.2 8159.9 8195.8 8234.9 36584.6 36709.7 36713.6 36719.0 36798.4 3590.4 3591.1 3631.9 3641.9 3652.1 25281.9 29064.6 30112.4 30435.5 30584.0 3059.6 3081.5 3090.9 3113.8 3124.8 239007.8 242119.0 273965.6 291490.0 292455.0 48033.5 48070.0 48432.5 48464.3 48518.8 39973.6 49431.5 49938.5 49947.0 50250.7 13985.4 13989.9 14074.7 14101.6 14124.2 11434.5 11451.4 11455.2 11465.0 11487.0 13614.8 13635.2 13652.7 13672.7 13678.5 5399.7 5971.4 6107.4 6139.2 6175.1 1001.8 1022.4 1025.2 1052.6 1060.4 23616.1 24865.5 24923.0 24969.5 25058.0 1111.7 1114.6 1143.2 1152.9 1160.2 10981.9 12712.4 12723.1 12751.4 12789.4 319099.0 320470.0 320490.0 320626.0 321758.0 54854.5 55081.2 55106.2 55173.3 55267.2 1429.2 1437.6 1495.8 1531.4 1548.8 21743.1 23010.7 24190.5 24219.2 25340.0 37996.6 38396.8 38490.0 38490.6 38543.6 267305.7 267392.0 268175.0 269325.0 270116.6 33996.8 34760.0 34916.8 35392.6 35411.9 43199.0 44464.0 45995.8 46085.5 46126.0 24405.1 28706.4 28822.7 30474.0 31072.7 26092.5 27309.9 30450.9 31028.5 32179.7 36363.3 38183.8 38323.0 38446.6 38447.0 29222.0 31013.1 31724.8 31899.5 32242.5 23311.3 24543.8 24550.0 24882.0 25015.2 19:34:53 INFO - PID 1651 | 19:34:53 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:36185.00 Median:22525.49 stddev:92037.79 (408.6%) stddev-sans-first:92256.60 19:34:53 INFO - PID 1651 | Values: 325.7 381.8 382.9 417.2 450.6 20707.6 22274.7 22291.2 22973.2 24084.0 23346.4 23640.0 23678.2 23863.8 23892.5 5292.8 5866.4 6172.9 6174.6 6190.4 5837.9 6041.8 6082.7 6088.4 6123.8 6063.2 6082.3 6085.2 6091.7 6101.6 5054.6 5901.5 6036.2 6052.2 6073.4 5398.6 5401.1 5407.4 5418.1 5440.3 27629.4 28192.6 28206.2 28276.4 28495.7 2109.1 2169.8 2172.9 2178.2 2186.5 17894.2 18000.7 18195.2 18357.9 18588.4 2103.2 2121.8 2139.7 2163.4 2166.6 142154.0 160749.0 170817.0 170821.0 171559.1 12452.4 12796.9 12883.7 12945.4 12967.3 16726.5 16797.7 16841.2 16842.6 16847.8 8740.6 8773.4 8979.6 8995.6 9090.0 8412.4 8420.9 8449.0 8518.7 8541.7 7262.6 7725.7 8034.2 8150.2 8223.7 2209.1 2219.6 2221.6 2221.7 2387.6 882.9 926.9 956.4 958.6 991.8 17672.5 17796.4 20463.7 20790.5 21502.0 970.9 996.1 1001.7 1011.5 1011.8 9923.7 10174.3 10673.8 10743.0 10752.7 461924.4 545837.0 554997.0 560145.0 561806.0 130204.0 133702.0 135637.0 136282.3 136872.0 1089.0 1166.6 1200.1 1228.6 1243.7 22423.9 22627.1 22758.2 22863.3 23383.5 60269.0 60831.0 61072.4 61335.0 62435.1 82686.2 83271.8 83411.6 83618.3 84090.0 8628.1 8783.4 8847.8 8911.0 12936.2 12452.9 13965.9 13972.2 14290.7 14350.3 11776.9 11784.8 11876.1 12008.3 12128.5 10268.1 10633.1 11290.7 11344.5 11604.0 11815.5 11993.9 12069.8 12236.1 12279.7 12933.2 13060.6 13293.3 13353.4 13377.5 11727.7 11972.8 12085.3 12110.5 12208.8 19:34:53 INFO - PID 1651 | 19:34:53 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:7175.27 Median:3815.45 stddev:4552.42 (119.3%) stddev-sans-first:4544.05 19:34:53 INFO - PID 1651 | Values: 1337.4 1424.8 1428.7 1433.8 1448.8 10498.7 11721.5 12181.8 12191.8 12276.5 12009.3 12050.3 12174.3 12174.6 12188.6 2543.2 2570.6 2584.0 2588.3 2590.5 2309.8 2434.2 2516.2 2558.7 2563.3 2140.2 2273.8 2443.1 2548.4 2551.4 2481.5 2500.3 2508.3 2512.7 2515.4 2190.5 2457.0 2471.6 2476.5 2576.4 11978.8 12052.2 12077.7 12087.8 12094.7 6556.1 6784.7 6800.4 6807.7 6814.2 4163.4 4312.7 4377.8 4393.9 4406.5 1223.6 1253.8 1256.9 1262.1 1266.3 15451.5 15779.8 16677.5 16724.3 16750.7 5484.5 5536.5 5681.7 5682.7 5699.5 11909.7 11994.8 12006.7 12027.7 12058.9 6410.1 6478.0 6577.2 6577.7 6596.9 6109.2 6176.3 6183.9 6235.1 6271.6 4273.9 4325.9 4363.4 4383.8 4396.8 1227.5 1244.8 1258.9 1259.2 1265.2 782.6 783.8 802.5 803.5 803.5 6343.5 6721.6 6927.1 7011.8 7080.5 900.2 947.3 952.8 954.5 955.9 3467.5 4386.3 4449.3 4459.7 4578.4 14632.6 15149.5 15240.5 15328.3 15396.5 14666.8 15020.2 15220.2 15224.1 15224.8 8378.7 8416.9 8533.1 8564.8 8568.1 6172.4 6944.3 7285.7 7319.8 7342.1 13460.3 13594.3 13875.8 13924.0 14161.6 11439.1 13960.3 14106.4 14120.3 14148.6 7763.1 9377.7 9600.0 9628.3 9668.7 10826.8 10910.9 10936.7 10941.9 10975.8 7621.2 8969.0 9240.6 9258.4 9328.6 7221.6 8954.8 8968.7 9093.8 9174.6 8387.0 8390.4 8414.6 8458.7 8558.0 5669.6 5733.8 5805.9 5806.9 5974.4 5476.7 6122.2 6244.8 6500.3 6510.4 19:34:53 INFO - PID 1651 | 19:34:53 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5299.79 Median:5984.85 stddev:1692.27 (28.3%) stddev-sans-first:1655.64 19:34:53 INFO - PID 1651 | Values: 344.7 386.3 390.2 390.7 402.9 6143.0 6211.4 6255.9 6267.3 6340.5 5020.0 5096.7 5783.9 6194.5 6246.5 3917.3 4013.4 4246.0 4419.6 4533.9 4487.3 4500.3 4512.9 4515.4 4548.2 3702.5 4480.0 4499.2 4515.1 4516.6 4229.8 4425.3 4477.4 4488.8 4499.8 3722.9 3836.0 3839.5 3852.4 3861.3 4923.6 6021.1 6058.8 6062.8 6081.7 5216.2 5343.1 5388.0 5405.8 5409.9 6610.0 6629.6 6634.4 6650.8 6659.6 4116.1 4252.4 4351.9 4357.7 4374.9 5767.9 6689.2 6851.9 6932.9 6965.8 4261.5 4410.1 4419.8 4422.8 4442.3 4422.0 4484.3 4488.0 4493.3 4524.1 4418.5 4480.3 4659.5 5080.7 5332.3 5212.0 5948.6 6433.0 6658.6 6671.3 1280.7 1522.5 1531.6 1533.4 1540.6 946.7 953.2 985.1 1010.0 1018.2 5791.8 6230.7 6275.9 6307.7 6312.5 5107.5 6049.9 6277.6 6873.4 6928.6 5497.8 5850.7 6226.2 6232.0 6297.7 3606.3 3624.4 3634.0 3648.7 3658.3 5727.5 6957.5 6966.7 7075.8 7113.6 7042.7 7078.8 7094.9 7113.9 7150.5 6427.0 6951.4 7097.1 7112.9 7124.6 5763.8 6983.4 6996.3 7002.7 7019.2 5566.4 6522.5 6726.8 6768.3 6859.8 6345.6 6631.8 6863.5 6961.0 7106.9 4421.2 4429.2 4431.4 4446.2 4454.8 5900.0 6544.8 6892.6 6944.2 6955.1 5558.8 5793.6 6247.5 6676.7 6692.1 6104.9 6446.4 6544.4 6633.8 6707.9 6208.7 6535.7 6553.1 6588.2 6598.9 5941.0 6561.6 6615.9 6714.0 6722.6 5912.6 6471.4 6504.5 6575.6 6592.3 19:34:53 INFO - PID 1651 | 19:34:53 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:26815.31 Median:18460.95 stddev:41641.47 (225.6%) stddev-sans-first:41712.40 19:34:53 INFO - PID 1651 | Values: 780.5 808.5 834.4 846.5 876.0 14202.5 14682.6 15996.8 16383.1 16529.7 17768.8 18148.1 18468.3 18538.5 18542.1 964.9 968.5 973.4 978.1 978.4 834.8 935.8 981.2 981.4 994.7 922.5 1033.5 1039.0 1040.6 1041.0 1025.8 1031.4 1032.6 1032.7 1033.4 642.9 752.3 758.0 758.1 759.8 16391.3 16779.7 16811.9 16933.4 16958.0 17263.0 17404.9 17716.8 17778.3 17797.6 14568.5 14884.9 14892.3 14917.6 14929.6 3504.5 3591.8 3617.0 3618.7 3631.1 128947.1 144405.3 148045.3 153171.0 153886.0 13511.4 13627.6 13746.5 13751.6 13829.7 26334.7 26428.1 26889.7 27019.2 27027.7 6634.5 6825.4 7293.8 7406.5 7616.0 3822.2 3906.4 3914.1 3922.0 3926.2 4316.9 4366.4 4380.1 4404.5 4405.9 924.4 934.3 945.4 945.7 947.6 646.2 659.1 664.5 666.2 681.6 11064.0 11336.7 11913.6 12213.0 12635.8 578.1 622.4 643.4 653.4 676.6 4187.4 5538.7 5613.6 5625.6 5881.0 153032.2 155299.0 156185.3 156276.4 156445.0 129901.1 130686.3 131231.3 132320.0 133230.3 87291.6 92691.6 104590.5 106724.9 108760.9 11124.0 11267.7 11586.9 11655.2 11848.6 36645.8 45199.8 45930.5 47472.1 48335.0 71455.6 93838.1 94547.6 96265.0 98978.0 14990.2 18248.8 18254.3 18468.9 18574.2 19070.3 19131.3 19162.2 19396.9 19476.3 18054.4 18104.6 18112.8 18368.9 18369.7 18233.8 18299.0 18309.0 18453.6 18505.6 17561.6 17754.0 17862.6 18005.7 18102.0 11958.8 12056.3 12084.5 12160.5 12801.0 13745.7 14571.8 14589.5 14596.1 14651.7 19:34:53 INFO - PID 1651 | -------- Summary: end -------- 19:34:53 INFO - PID 1651 | 19:34:54 INFO - TEST-INFO | 1651: exit 0 19:34:54 INFO - TEST-OK | dromaeo_css | took 1143237ms 19:34:54 INFO - TEST-START | kraken 19:34:54 INFO - operating with platform_type : mac_ 19:34:54 INFO - Initialising browser for kraken test... 19:34:54 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpHAUqEy/profile 19:34:54 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 19:35:27 INFO - TEST-INFO | 1660: killed by SIGTERM 19:35:27 INFO - Browser initialized. 19:35:27 INFO - Running cycle 1/1 for kraken test... 19:35:27 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpHAUqEy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:35:29 INFO - PID 1664 | 2017-05-24 19:35:29.595 plugin-container[1665:10954] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 19:35:29 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:35:29 INFO - PID 1664 | 2017-05-24 19:35:29.596 plugin-container[1665:10954] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 19:35:29 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:35:29 INFO - PID 1664 | 2017-05-24 19:35:29.596 plugin-container[1665:10954] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:35:30 INFO - PID 1664 | 2017-05-24 19:35:30.350 plugin-container[1666:11000] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 19:35:30 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:35:30 INFO - PID 1664 | 2017-05-24 19:35:30.350 plugin-container[1666:11000] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9817, name = 'com.apple.CFPasteboardClient' 19:35:30 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:35:30 INFO - PID 1664 | 2017-05-24 19:35:30.350 plugin-container[1666:11000] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:35:39 INFO - PID 1664 | RSS: Main: 246181888 19:35:39 INFO - PID 1664 | 19:35:42 INFO - PID 1664 | 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) 19:35:43 INFO - PID 1664 | RSS: Main: 259571712 19:35:43 INFO - PID 1664 | 19:35:46 INFO - PID 1664 | 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) 19:35:46 INFO - PID 1664 | RSS: Main: 268726272 19:35:46 INFO - PID 1664 | 19:35:50 INFO - PID 1664 | 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) 19:35:51 INFO - PID 1664 | RSS: Main: 271962112 19:35:51 INFO - PID 1664 | 19:35:53 INFO - PID 1664 | 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) 19:35:53 INFO - PID 1664 | RSS: Main: 273657856 19:35:53 INFO - PID 1664 | 19:35:55 INFO - PID 1664 | 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) 19:35:56 INFO - PID 1664 | RSS: Main: 274419712 19:35:56 INFO - PID 1664 | 19:35:59 INFO - PID 1664 | 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) 19:36:00 INFO - PID 1664 | RSS: Main: 282238976 19:36:00 INFO - PID 1664 | 19:36:03 INFO - PID 1664 | 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) 19:36:03 INFO - PID 1664 | RSS: Main: 282267648 19:36:03 INFO - PID 1664 | 19:36:06 INFO - PID 1664 | 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) 19:36:07 INFO - PID 1664 | RSS: Main: 280932352 19:36:07 INFO - PID 1664 | 19:36:08 INFO - PID 1664 | 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) 19:36:09 INFO - PID 1664 | RSS: Main: 280989696 19:36:09 INFO - PID 1664 | 19:36:11 INFO - PID 1664 | 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) 19:36:11 INFO - PID 1664 | RSS: Main: 280846336 19:36:11 INFO - PID 1664 | 19:36:14 INFO - PID 1664 | 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) 19:36:15 INFO - PID 1664 | RSS: Main: 281133056 19:36:15 INFO - PID 1664 | 19:36:17 INFO - PID 1664 | 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) 19:36:18 INFO - PID 1664 | RSS: Main: 281083904 19:36:18 INFO - PID 1664 | 19:36:21 INFO - PID 1664 | 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) 19:36:22 INFO - PID 1664 | RSS: Main: 280711168 19:36:22 INFO - PID 1664 | 19:36:23 INFO - PID 1664 | 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) 19:36:24 INFO - PID 1664 | RSS: Main: 282615808 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | __start_tp_report 19:36:24 INFO - PID 1664 | _x_x_mozilla_page_load 19:36:24 INFO - PID 1664 | _x_x_mozilla_page_load_details 19:36:24 INFO - PID 1664 | |i|pagename|runs| 19:36:24 INFO - PID 1664 | |0;ai-astar;103;117;128;170;144;168;170;116;113;115 19:36:24 INFO - PID 1664 | |1;audio-beat-detection;154;142;112;168;106;117;119;145;161;127 19:36:24 INFO - PID 1664 | |2;audio-dft;195;201;240;261;222;202;200;202;196;203 19:36:24 INFO - PID 1664 | |3;audio-fft;83;59;59;90;65;64;77;87;92;86 19:36:24 INFO - PID 1664 | |4;audio-oscillator;84;71;84;72;71;69;91;72;75;91 19:36:24 INFO - PID 1664 | |5;imaging-gaussian-blur;82;124;137;101;94;94;121;82;138;135 19:36:24 INFO - PID 1664 | |6;imaging-darkroom;153;120;99;99;100;100;101;104;100;100 19:36:24 INFO - PID 1664 | |7;imaging-desaturate;92;89;90;93;95;94;91;94;89;92 19:36:24 INFO - PID 1664 | |8;json-parse-financial;51;52;52;51;50;50;52;51;52;50 19:36:24 INFO - PID 1664 | |9;json-stringify-tinderbox;58;56;63;59;65;59;57;57;57;61 19:36:24 INFO - PID 1664 | |10;stanford-crypto-aes;89;111;97;86;90;86;84;105;141;73 19:36:24 INFO - PID 1664 | |11;stanford-crypto-ccm;139;140;138;150;145;153;155;144;154;138 19:36:24 INFO - PID 1664 | |12;stanford-crypto-pbkdf2;186;186;186;189;188;190;186;189;186;188 19:36:24 INFO - PID 1664 | |13;stanford-crypto-sha256-iterative;68;69;71;72;70;65;70;69;70;65 19:36:24 INFO - PID 1664 | __end_tp_report 19:36:24 INFO - PID 1664 | __start_cc_report 19:36:24 INFO - PID 1664 | _x_x_mozilla_cycle_collect,1065 19:36:24 INFO - PID 1664 | __end_cc_report 19:36:24 INFO - PID 1664 | __startTimestamp1495679784171__endTimestamp 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | ------- Summary: start ------- 19:36:24 INFO - PID 1664 | Number of tests: 14 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#0] ai-astar Cycles:10 Average:134.40 Median:136.00 stddev:26.37 (19.4%) stddev-sans-first:25.40 19:36:24 INFO - PID 1664 | Values: 103.0 117.0 128.0 170.0 144.0 168.0 170.0 116.0 113.0 115.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:143.50 stddev:21.83 (15.2%) stddev-sans-first:22.06 19:36:24 INFO - PID 1664 | Values: 154.0 142.0 112.0 168.0 106.0 117.0 119.0 145.0 161.0 127.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#2] audio-dft Cycles:10 Average:212.20 Median:202.50 stddev:22.05 (10.9%) stddev-sans-first:22.49 19:36:24 INFO - PID 1664 | Values: 195.0 201.0 240.0 261.0 222.0 202.0 200.0 202.0 196.0 203.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#3] audio-fft Cycles:10 Average:76.20 Median:84.50 stddev:13.19 (15.6%) stddev-sans-first:13.76 19:36:24 INFO - PID 1664 | Values: 83.0 59.0 59.0 90.0 65.0 64.0 77.0 87.0 92.0 86.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:79.50 stddev:8.63 (10.9%) stddev-sans-first:8.87 19:36:24 INFO - PID 1664 | Values: 84.0 71.0 84.0 72.0 71.0 69.0 91.0 72.0 75.0 91.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:110.80 Median:110.00 stddev:22.63 (20.6%) stddev-sans-first:21.47 19:36:24 INFO - PID 1664 | Values: 82.0 124.0 137.0 101.0 94.0 94.0 121.0 82.0 138.0 135.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#6] imaging-darkroom Cycles:10 Average:107.60 Median:112.00 stddev:17.16 (15.3%) stddev-sans-first:6.71 19:36:24 INFO - PID 1664 | Values: 153.0 120.0 99.0 99.0 100.0 100.0 101.0 104.0 100.0 100.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:92.50 stddev:2.13 (2.3%) stddev-sans-first:2.26 19:36:24 INFO - PID 1664 | Values: 92.0 89.0 90.0 93.0 95.0 94.0 91.0 94.0 89.0 92.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:0.88 (1.7%) stddev-sans-first:0.93 19:36:24 INFO - PID 1664 | Values: 51.0 52.0 52.0 51.0 50.0 50.0 52.0 51.0 52.0 50.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:2.94 (5.0%) stddev-sans-first:3.08 19:36:24 INFO - PID 1664 | Values: 58.0 56.0 63.0 59.0 65.0 59.0 57.0 57.0 57.0 61.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#10] stanford-crypto-aes Cycles:10 Average:96.20 Median:86.00 stddev:19.12 (22.2%) stddev-sans-first:20.10 19:36:24 INFO - PID 1664 | Values: 89.0 111.0 97.0 86.0 90.0 86.0 84.0 105.0 141.0 73.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:147.50 stddev:6.88 (4.7%) stddev-sans-first:6.87 19:36:24 INFO - PID 1664 | Values: 139.0 140.0 138.0 150.0 145.0 153.0 155.0 144.0 154.0 138.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.40 Median:188.00 stddev:1.58 (0.8%) stddev-sans-first:1.59 19:36:24 INFO - PID 1664 | Values: 186.0 186.0 186.0 189.0 188.0 190.0 186.0 189.0 186.0 188.0 19:36:24 INFO - PID 1664 | 19:36:24 INFO - PID 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:70.00 stddev:2.33 (3.3%) stddev-sans-first:2.45 19:36:24 INFO - PID 1664 | Values: 68.0 69.0 71.0 72.0 70.0 65.0 70.0 69.0 70.0 65.0 19:36:24 INFO - PID 1664 | -------- Summary: end -------- 19:36:24 INFO - PID 1664 | 19:36:24 INFO - TEST-INFO | 1664: exit 0 19:36:24 INFO - TEST-OK | kraken | took 90520ms 19:36:24 INFO - SUITE-END | took 1233s 19:36:25 INFO - Completed test suite (00:20:34) 19:36:25 INFO - javascript benchmark 19:36:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7967.887171379796, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2896.1022083154216, 3366.528685983962, 3439.86240550378, 3511.5259439975594, 3554.733394995376, 4913.631477639178, 6033.396660333964, 6046.334903160659, 6092.390760923905, 6096.634201947886, 5572.554195664347, 5824.359320474744, 6107.219158344521, 6113.628337950551, 6238.968805155968, 4444.488883778358, 5253.527182553569, 5383.946160538393, 5384.7846086156505, 5407.621466497347, 5396.91904621431, 5432.402435732066, 5460.31746031746, 5493.340799104113, 5493.533049690761, 4111.383292506118, 4259.850905218303, 5268.341457317835, 5337.439568845293, 5510.283663123788, 5335.986162629088, 5391.16436952273, 5399.0, 5423.430539793344, 5425.701586412746, 5354.812581559627, 5365.436629153922, 5382.6232163748555, 5401.054815407328, 5401.405845357008, 5306.283651707009, 5971.313298970631, 6195.1946246988055, 6207.937920620781, 6215.067739839015, 6349.8412539686415, 6441.355864413568, 6505.60966342021, 6601.768938087192, 6626.635535045571, 5820.476157145835, 6569.211694596631, 6740.93259067408, 6768.458523318172, 6773.3903948644365, 6262.467690246334, 6266.655333956585, 6278.37216278373, 6278.591891527082, 6298.653574053471, 6613.603183808987, 6680.966595166993, 6697.196336439659, 6822.249552549216, 6899.517033807585, 6254.656719086833, 6279.686015699197, 6388.520860935449, 6461.19235095613, 6504.0, 5565.9443405566235, 6680.398764111156, 6680.966595166993, 6717.059611654372, 6731.764388246388, 6543.509236807258, 6570.868582628416, 6573.276939536647, 6585.374389432996, 6606.636634985122, 6370.299267080617, 6445.51658625605, 6488.7728929488185, 6582.44049255809, 6589.439897608661, 5291.947080529222, 5325.041492531382, 5326.067938110815, 5326.9467305327225, 5362.624616276823, 4724.055188962221, 4736.668433209711, 4785.808567657325, 4814.253790662453, 4816.422029356429, 5401.864953376197, 6147.200863887666, 6308.558400911892, 6309.558330916791, 6321.683915804191, 6546.3453654635105, 6571.868562628721, 6671.366220209073, 6678.365555272241, 6678.799636010927, 5202.323697919246, 5333.7066461345, 6225.972714502147, 6242.032484964838, 6263.279722831841, 5877.38287479817, 5916.881662366729, 5926.436988486087, 5933.970330148321, 5941.346451890288, 6550.181227346582, 6651.0, 6741.1236539249585, 6816.625085620339, 6827.795166145023, 6635.701393437211, 6713.697883595152, 6720.731170753311, 6733.966330168318, 6736.8652626946205, 6807.523473357016, 6811.114555107804, 6814.659267036727, 6825.52221344521, 6827.965860170667, 5663.5469162467725, 6330.49356051524, 6390.520710946605, 6455.4512866406885, 6479.773207937699, 6640.103586015826, 6711.63086030273, 6731.46148308144, 6733.124693789776, 6734.8653026940165, 6294.433500984934, 6522.28254891952, 6539.836504087436, 6540.084388185753, 6560.114384558024, 6317.494600431863, 6373.235211774617, 6445.129907462432, 6526.118973938647, 6537.640429776408, 6189.1954045975535, 6402.647854368054, 6411.0, 6417.069524918954, 6418.839529011812, 6383.297837237993, 6429.7749578764515, 6434.613923164625, 6507.511936604679, 6561.901571476336, 6381.521385895983, 6392.073149393219, 6409.2949775525585, 6410.679466026587, 6411.51913606472], "value": 5989.623021448372, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.2164305835814, 762.3063012658482, 791.378767667381, 1201.4713526048533, 1262.5013119817677, 39106.608933910655, 40349.3947590786, 40424.79787601065, 40427.989300267545, 40429.191416171656, 41392.793036034855, 41398.96502587437, 41442.58557414425, 41449.963750906245, 41452.0, 7579.128400233961, 7733.7293194738195, 8081.191880811931, 8273.751787446387, 8291.170882911721, 8217.835643287131, 8257.21556452136, 8297.917020829806, 8306.626201820933, 8312.085670576262, 8250.381221408388, 8278.668853245894, 8335.333173346147, 8338.83322333553, 8364.414490985633, 8189.303909167698, 8236.217559331853, 8281.420300578962, 8318.085010648854, 8318.41771076025, 6371.936280637181, 8127.2279133482225, 8159.9184008159755, 8195.754127376129, 8234.917650823534, 36584.634153658386, 36709.71515996954, 36713.632863671286, 36719.0, 36798.44802327967, 3590.4434812603827, 3591.1381268495634, 3631.8547258109643, 3641.85432582699, 3652.0504668786043, 25281.862316195817, 29064.564031539543, 30112.397752044835, 30435.543466848016, 30584.0, 3059.6175478065243, 3081.5223640335785, 3090.891818786332, 3113.797603155788, 3124.765642576816, 239007.80496097583, 242119.0, 273965.63017184986, 291490.0, 292455.0, 48033.5196648029, 48070.0, 48432.515674843504, 48464.27303590449, 48518.75740621345, 39973.60026399757, 49431.50568494341, 49938.50061499411, 49947.0, 50250.74874625603, 13985.3706583204, 13989.86010139906, 14074.71850562983, 14101.576952691436, 14124.152550846777, 11434.542618295342, 11451.427428628534, 11455.198136130392, 11465.025472834903, 11487.0, 13614.795778063337, 13635.181889086687, 13652.658683532793, 13672.65818354549, 13678.452861885413, 5399.7300134993875, 5971.373005834324, 6107.4197951194565, 6139.2325959255095, 6175.135481032659, 1001.8447140693204, 1022.3967858963099, 1025.1747343388645, 1052.600011995437, 1060.4008735064663, 23616.055357785495, 24865.5026899461, 24923.0, 24969.5006099877, 25058.0, 1111.7109551516664, 1114.5541783286587, 1143.1654891928938, 1152.8847115288404, 1160.1704781081398, 10981.945090274658, 12712.364381780873, 12723.109382343147, 12751.36243187837, 12789.424475898606, 319099.0, 320470.0, 320490.0, 320626.0, 321758.0, 54854.45145548573, 55081.17378239249, 55106.17340739892, 55173.34479965607, 55267.170992435145, 1429.178222522054, 1437.6190309567974, 1495.76067829149, 1531.4104069933155, 1548.7599422089595, 21743.130274788833, 23010.654840177747, 24190.51618967646, 24219.152329668377, 25339.98640054377, 37996.62003380041, 38396.808015959745, 38490.0, 38490.615093848704, 38543.614563854004, 267305.6634716814, 267392.0, 268175.0, 269325.0, 270116.59766804945, 33996.830015849766, 34760.0, 34916.82541587276, 35392.646073538934, 35411.93764187078, 43199.0, 44464.0, 45995.77002115102, 46085.539144609465, 46126.0, 24405.145819896217, 28706.425871482035, 28822.702978366444, 30474.0, 31072.68927310698, 26092.478150437266, 27309.86345068262, 30450.868439209044, 31028.53457198099, 32179.678203218606, 36363.27273454569, 38183.809080954416, 38323.0, 38446.615533844306, 38447.0, 29222.0, 31013.0696079118, 31724.841375792974, 31899.521507176945, 32242.516362254115, 23311.300660980196, 24543.754562454145, 24550.0, 24882.0, 25015.249542513753], "value": 17431.391820650882, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.6596318970407, 381.8090954522739, 382.87939299120774, 417.2343749220181, 450.59446498151664, 20707.585848283044, 22274.665880011813, 22291.21980730675, 22973.19593814217, 24084.03663853444, 23346.416339591477, 23640.0, 23678.171264005763, 23863.761362386373, 23892.522149556997, 5292.814751483699, 5866.442687944649, 6172.938270617304, 6174.598651087687, 6190.442860142586, 5837.912431313513, 6041.848953776169, 6082.665453400084, 6088.36072212418, 6123.846903827418, 6063.18147050147, 6082.300535438437, 6085.178500902388, 6091.664958427285, 6101.633901965874, 5054.620903432257, 5901.468867801918, 6036.185115009462, 6052.182955301044, 6073.3622969588, 5398.568114550866, 5401.081816091274, 5407.3781515125875, 5418.133098704188, 5440.292761940922, 27629.447411051868, 28192.577111343347, 28206.153815385573, 28276.434471310666, 28495.715042849515, 2109.1141720477285, 2169.8481106322565, 2172.891355432222, 2178.2049551913437, 2186.4533866533366, 17894.194761235645, 18000.729989050174, 18195.18121684514, 18357.908210458863, 18588.442346729484, 2103.200783702199, 2121.830253579725, 2139.689744986984, 2163.3618082665653, 2166.566686662658, 142154.0, 160749.0, 170817.0, 170821.0, 171559.14220428944, 12452.377381130907, 12796.936015319863, 12883.742325153444, 12945.417456214493, 12967.286799225945, 16726.498205053867, 16797.748033779502, 16841.221198502353, 16842.578935526464, 16847.83152168487, 8740.606672699616, 8773.385862989657, 8979.595918183697, 8995.55022248885, 9089.95455022734, 8412.411131220755, 8420.915790842135, 8449.0, 8518.70184543538, 8541.658333666708, 7262.600556969417, 7725.7295994641045, 8034.15641357649, 8150.184981501921, 8223.712170074019, 2209.116353458597, 2219.622664147099, 2221.589006033889, 2221.688963545105, 2387.558301714154, 882.8587426011808, 926.8748718922994, 956.4452617481844, 958.5918137252489, 991.7867658453467, 17672.469825905242, 17796.3771268005, 20463.693044604344, 20790.48023799387, 21502.032408541654, 970.8834939807127, 996.0985308296003, 1001.67946257197, 1011.5094179323022, 1011.8178727829062, 9923.652672156584, 10174.338667986705, 10673.83989240162, 10743.0, 10752.677419677422, 461924.3807561949, 545837.0, 554997.0, 560145.0, 561806.0, 130204.0, 133702.0, 135637.0, 136282.3185884084, 136871.99999999802, 1088.9945550272198, 1166.6383421139474, 1200.1359021504677, 1228.5761412312738, 1243.657994051643, 22423.887880560495, 22627.09491620317, 22758.20346287872, 22863.31410057701, 23383.532329352976, 60269.0, 60831.0, 61072.38927610667, 61335.0, 62435.12694619169, 82686.17313827026, 83271.75092373499, 83411.5829420849, 83618.3276334482, 84090.0, 8628.094050124646, 8783.429077109968, 8847.778805529913, 8911.0, 12936.22382657006, 12452.875471245172, 13965.860341396456, 13972.161670299814, 14290.714185716435, 14350.282485875874, 11776.941115294369, 11784.764304713686, 11876.109291803323, 12008.279503229835, 12128.454219560317, 10268.07587317145, 10633.149348052291, 11290.717732056764, 11344.489497972776, 11604.0, 11815.527378904748, 11993.880061199625, 12069.818952715892, 12236.082293828176, 12279.693007674523, 12933.224006559636, 13060.608181754562, 13293.335333233106, 13353.39909704085, 13377.53178638743, 11727.706807329885, 11972.760544788882, 12085.335306557872, 12110.45502952387, 12208.755824883276], "value": 10671.832673687328, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1337.3580681272988, 1424.8147740793709, 1428.6999730056687, 1433.8027746831401, 1448.7971683964247, 10498.737531561716, 11721.472533735981, 12181.817272740916, 12191.756164876697, 12276.508939642415, 12009.339486328248, 12050.276983381003, 12174.269543827377, 12174.573889913854, 12188.634340969786, 2543.173468622696, 2570.6144078388297, 2583.9612405813928, 2588.327034970912, 2590.5207536605694, 2309.76427611228, 2434.233216536785, 2516.169664010872, 2558.6801649793774, 2563.346346681603, 2140.2188201306567, 2273.8294627902974, 2443.0838435586656, 2548.3501707064675, 2551.413174969758, 2481.5409149307347, 2500.2999160235163, 2508.2726009457238, 2512.6859142607173, 2515.371157210697, 2190.539986602815, 2457.0, 2471.641611966255, 2476.5418397596504, 2576.4074262919635, 11978.820317695241, 12052.156349055569, 12077.698057548632, 12087.818682719766, 12094.697632559168, 6556.147700798913, 6784.694688739019, 6800.421964133055, 6807.693653785592, 6814.216365118025, 4163.354680024601, 4312.719673221262, 4377.781110944472, 4393.934090988638, 4406.493253275882, 1223.5717498875322, 1253.7617852607975, 1256.9434375453036, 1262.1480500662108, 1266.2718936611486, 15451.459198928096, 15779.76330355034, 16677.49967500977, 16724.331026758915, 16750.664986700198, 5484.533814625722, 5536.501714845603, 5681.687507187145, 5682.715864206773, 5699.515541178963, 11909.702257443632, 11994.760104797855, 12006.699832504082, 12027.699307517381, 12058.939705301593, 6410.134631824737, 6478.0, 6577.243616984012, 6577.736890524421, 6596.86806263872, 6109.236345456818, 6176.258848938066, 6183.938160618426, 6235.127082208494, 6271.592346497463, 4273.893152671208, 4325.891852703708, 4363.38912552243, 4383.846565370261, 4396.758178300161, 1227.5028613411582, 1244.8381710377773, 1258.8929940955122, 1259.168948494008, 1265.240855486719, 782.5891407011364, 783.8275579372529, 802.5144787403536, 803.4737247103157, 803.5459965119688, 6343.460805831556, 6721.563098398588, 6927.09947706805, 7011.824704382431, 7080.539764915264, 900.1628485508539, 947.280067148958, 952.8189643967625, 954.5227386306847, 955.8757361543094, 3467.549218601566, 4386.298192289219, 4449.2658711312915, 4459.687821852439, 4578.427696537933, 14632.56102316911, 15149.469768558269, 15240.542783716506, 15328.310226039855, 15396.538103856903, 14666.779998300033, 15020.248987550578, 15220.238988050553, 15224.086554806747, 15224.847751522342, 8378.748637540884, 8416.915830841735, 8533.146685331541, 8564.828703426021, 8568.057513673491, 6172.382761723792, 6944.340287672663, 7285.7449989252245, 7319.780406587811, 7342.118945726547, 13460.326983650973, 13594.320283985959, 13875.79186312186, 13924.0, 14161.575152745434, 11439.084873209957, 13960.301984900918, 14106.365213565208, 14120.2939853009, 14148.646283842987, 7763.146053934176, 9377.718668439958, 9599.992000840091, 9628.32601717873, 9668.661596844076, 10826.837597435888, 10910.945445272722, 10936.671899843017, 10941.890581094405, 10975.83536246941, 7621.1997740236475, 8968.955155224183, 9240.584173712065, 9258.398204116711, 9328.580213890256, 7221.566705997657, 8954.776130596527, 8968.730938071869, 9093.818123637358, 9174.587143578421, 8387.0, 8390.412671112963, 8414.6213420395, 8458.70394536213, 8557.957210213908, 5669.574781891292, 5733.770649174153, 5805.883882322245, 5806.925718742046, 5974.4324289192455, 5476.698781567052, 6122.204113465221, 6244.812655620339, 6500.284968653539, 6510.381513756186], "value": 5417.311702477119, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.722498388797, 386.32971793937514, 390.1923019349945, 390.6601256906492, 402.91740193260375, 6142.969285153568, 6211.440970312677, 6255.874882502348, 6267.341929097447, 6340.4927605791545, 5020.021095886304, 5096.719680417576, 5783.913241301373, 6194.5354098442685, 6246.500279977602, 3917.2557214129224, 4013.418054382113, 4246.002189485468, 4419.602235798778, 4533.886652833689, 4487.349334346518, 4500.257457519521, 4512.864614061567, 4515.390422292998, 4548.249538826106, 3702.4631428442995, 4480.0143968327075, 4499.1901457737595, 4515.142122996636, 4516.59985404524, 4229.815651617552, 4425.291953287458, 4477.440319960005, 4488.842890498833, 4499.820007199708, 3722.9441558376648, 3835.980820095882, 3839.4816699745734, 3852.4028775539837, 3861.3242682530727, 4923.556879880792, 6021.1163799349615, 6058.787942422038, 6062.757489700407, 6081.726322315506, 5216.191490319006, 5343.118385466393, 5387.973060134714, 5405.783768649249, 5409.864753381157, 6610.0, 6629.635370054645, 6634.440906386995, 6650.843222985634, 6659.600423974529, 4116.115035267432, 4252.4046633471335, 4351.891202719926, 4357.673174511924, 4374.8563204030115, 5767.942320576783, 6689.1972963244725, 6851.931480685229, 6932.930670693279, 6965.825854353631, 4261.488621365456, 4410.089341034965, 4419.845305414295, 4422.845200418034, 4442.311441726538, 4422.010095457057, 4484.304932735431, 4487.977560112244, 4493.28107502798, 4524.140413321458, 4418.4918734345965, 4480.327950807405, 4659.487456379796, 5080.697546594669, 5332.33345831771, 5211.983663185718, 5948.583599148018, 6433.002884552901, 6658.5671931324305, 6671.299513551105, 1280.737448822989, 1522.4519173097676, 1531.5864716526476, 1533.4402942925751, 1540.5917431880562, 946.6639343033124, 953.1945506047379, 985.074030411411, 1009.9747506312254, 1018.2414101494286, 5791.768329266866, 6230.657313847782, 6275.905861412082, 6307.716152773136, 6312.495000399957, 5107.489251074863, 6049.911016017071, 6277.5605707600025, 6873.419113603799, 6928.584284942919, 5497.752601132958, 5850.736716847752, 6226.159468471789, 6232.034034724625, 6297.6536290503545, 3606.3328284267495, 3624.383854744647, 3634.0, 3648.744587878823, 3658.32321020612, 5727.4845263926445, 6957.547759395724, 6966.686499107553, 7075.82310442243, 7113.57318560888, 7042.6830792614455, 7078.75224367155, 7094.858102838018, 7113.857722845514, 7150.5352152109945, 6427.039752875325, 6951.443884489228, 7097.112860892388, 7112.857742845217, 7124.608146551989, 5763.827085187451, 6983.406410454965, 6996.300369962963, 7002.719891204295, 7019.19279282889, 5566.415526369835, 6522.478201743944, 6726.764563240263, 6768.289329620319, 6859.828504287233, 6345.555811093179, 6631.767888123893, 6863.485238607024, 6960.965195174194, 7106.857862842611, 4421.204183247047, 4429.161897812501, 4431.401760762255, 4446.177457170434, 4454.821807127808, 5900.0, 6544.7892139954265, 6892.551984121016, 6944.236134025355, 6955.095837541087, 5558.777648893999, 5793.565482588907, 6247.5001999839, 6676.732930682719, 6692.130023096966, 6104.9389506106145, 6446.355364463704, 6544.4149560924125, 6633.800985970429, 6707.865842683216, 6208.689565521616, 6535.738570457129, 6553.0681591044995, 6588.209414870246, 6598.901016484852, 5940.9503721216115, 6561.606303621798, 6615.941449368078, 6714.0, 6722.563033402813, 5912.556558258062, 6471.417572418317, 6504.4796416285635, 6575.605463672195, 6592.274849766618], "value": 4764.893248403663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.5394817057942, 808.5027707959607, 834.4492634860992, 846.4624963148402, 875.9868601970971, 14202.502912398068, 14682.559523214293, 15996.760048599266, 16383.098929558884, 16529.669406611876, 17768.82231177688, 18148.092595370246, 18468.261269549235, 18538.53653658659, 18542.072896355163, 964.8600952861831, 968.4867020479122, 973.3819024919175, 978.0903759503659, 978.4031740638205, 834.8163404051099, 935.8175155844579, 981.1562056631292, 981.3719219699398, 994.7363948553639, 922.5341202692631, 1033.503918119303, 1038.9532471038783, 1040.6409788622914, 1041.0058394233527, 1025.7897131088146, 1031.4327120084, 1032.5714828346227, 1032.654060889603, 1033.41095575522, 642.9132067170918, 752.3341842469399, 758.0486489455753, 758.0751483190498, 759.7758661194905, 16391.34434622614, 16779.66440671192, 16811.915940420342, 16933.407330743365, 16958.0, 17263.0, 17404.912975435043, 17716.822831771777, 17778.28886844525, 17797.555061123447, 14568.49010284635, 14884.851151488454, 14892.255387230594, 14917.627059323495, 14929.552113436614, 3504.491848681927, 3591.838367273479, 3616.9819150904077, 3618.6743193112484, 3631.0559254593995, 128947.06579401223, 144405.2779736105, 148045.25977370044, 153171.0, 153886.0, 13511.391987360592, 13627.591172264847, 13746.518871839638, 13751.587452376445, 13829.723405531833, 26334.73665263323, 26428.0750173743, 26889.731102689115, 27019.1894243173, 27027.729722702916, 6634.469242460592, 6825.419839313615, 7293.817654558678, 7406.481546291816, 7616.04799400075, 3822.1973385589336, 3906.4140378942816, 3914.099757055893, 3921.980285125888, 3926.155876486569, 4316.935245971313, 4366.388705581221, 4380.080183161508, 4404.471463424409, 4405.889852753643, 924.4314746430897, 934.303943562037, 945.3807755919884, 945.669015844449, 947.625687853294, 646.1632186318728, 659.120074700271, 664.5414663881907, 666.1706175811049, 681.57401623985, 11064.0, 11336.659900203007, 11913.642590722293, 12213.0, 12635.810462843065, 578.0779656447959, 622.4242575617536, 643.4144928115393, 653.3858173317067, 676.6109487044969, 4187.413762073312, 5538.695371754592, 5613.624661957847, 5625.634333768292, 5881.0, 153032.2348388251, 155299.0, 156185.31444056696, 156276.43723562846, 156445.0, 129901.0514842278, 130686.34656826656, 131231.34384328016, 132320.0, 133230.33384833013, 87291.56354218315, 92691.60962585566, 104590.47704761427, 106724.93275067305, 108760.91239087666, 11124.0, 11267.718307042389, 11586.884131158919, 11655.242409243372, 11848.585299514476, 36645.816770915975, 45199.77400112979, 45930.54069459396, 47472.101115954974, 48335.0, 71455.64272178606, 93838.06161938293, 94547.59976402453, 96265.0, 98978.0, 14990.175540345386, 18248.817511824713, 18254.26982920668, 18468.907655461637, 18574.164162612986, 19070.33253836109, 19131.33040343581, 19162.23351065942, 19396.903015484833, 19476.318328858422, 18054.368097116538, 18104.637907242046, 18112.818871811112, 18368.908155459136, 18369.724454133466, 18233.817661823214, 18299.0, 18308.993005384833, 18453.63092738111, 18505.629887402447, 17561.648767024333, 17754.02352870553, 17862.642747145244, 18005.729914051037, 18102.0, 11958.760824783281, 12056.336901470504, 12084.45619947122, 12160.513579456723, 12801.0, 13745.725085498034, 14571.781423278871, 14589.489367872073, 14596.12423254608, 14651.706965860836], "value": 8896.522620454798, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 128.0, 170.0, 144.0, 168.0, 170.0, 116.0, 113.0, 115.0], "value": 134.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 142.0, 112.0, 168.0, 106.0, 117.0, 119.0, 145.0, 161.0, 127.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 201.0, 240.0, 261.0, 222.0, 202.0, 200.0, 202.0, 196.0, 203.0], "value": 212.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 59.0, 90.0, 65.0, 64.0, 77.0, 87.0, 92.0, 86.0], "value": 76.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 84.0, 72.0, 71.0, 69.0, 91.0, 72.0, 75.0, 91.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 124.0, 137.0, 101.0, 94.0, 94.0, 121.0, 82.0, 138.0, 135.0], "value": 110.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 99.0, 99.0, 100.0, 100.0, 101.0, 104.0, 100.0, 100.0], "value": 107.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 90.0, 93.0, 95.0, 94.0, 91.0, 94.0, 89.0, 92.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 51.0, 50.0, 50.0, 52.0, 51.0, 52.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 63.0, 59.0, 65.0, 59.0, 57.0, 57.0, 57.0, 61.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 111.0, 97.0, 86.0, 90.0, 86.0, 84.0, 105.0, 141.0, 73.0], "value": 96.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 138.0, 150.0, 145.0, 153.0, 155.0, 144.0, 154.0, 138.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 186.0, 189.0, 188.0, 190.0, 186.0, 189.0, 186.0, 188.0], "value": 187.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 71.0, 72.0, 70.0, 65.0, 70.0, 69.0, 70.0, 65.0], "value": 68.9, "unit": "score"}]}]} 19:36:25 INFO - Return code: 0 19:36:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:36:25 INFO - # TBPL SUCCESS # 19:36:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:36:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:36:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:36:25 INFO - 2017-05-24 19:36:25.490 screenresolution[1671:11378] starting screenresolution argv=screenresolution get 19:36:25 INFO - 2017-05-24 19:36:25.492 screenresolution[1671:11378] Display 0: 1600x1200x32@60 19:36:25 INFO - 2017-05-24 19:36:25.498 screenresolution[1672:11381] starting screenresolution argv=screenresolution list 19:36:25 INFO - Available Modes on Display 0 19:36:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:36:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:36:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:36:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:36:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:36:25 INFO - Intel Iris: 19:36:25 INFO - Chipset Model: Intel Iris 19:36:25 INFO - Type: GPU 19:36:25 INFO - Bus: Built-In 19:36:25 INFO - VRAM (Dynamic, Max): 1536 MB 19:36:25 INFO - Vendor: Intel (0x8086) 19:36:25 INFO - Device ID: 0x0a2e 19:36:25 INFO - Revision ID: 0x0009 19:36:25 INFO - Displays: 19:36:25 INFO - Display: 19:36:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:36:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:36:25 INFO - Display Serial Number: 1600x1200 60 19:36:25 INFO - Main Display: Yes 19:36:25 INFO - Mirror: Off 19:36:25 INFO - Online: Yes 19:36:25 INFO - Rotation: Supported 19:36:25 INFO - Return code: 0 19:36:25 INFO - Running post-action listener: _resource_record_post_action 19:36:25 INFO - [mozharness: 2017-05-25 02:36:25.742182Z] Finished run-tests step (success) 19:36:25 INFO - Running post-run listener: _resource_record_post_run 19:36:25 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 41241600; Write bytes: 565702656; Read time: 289; Write time: 1790 19:36:25 INFO - TinderboxPrint: CPU usage
53.3% 19:36:25 INFO - TinderboxPrint: I/O read bytes / time
41,241,600 / 289 19:36:25 INFO - TinderboxPrint: I/O write bytes / time
565,702,656 / 1,790 19:36:25 INFO - TinderboxPrint: CPU idle
2,347.1 (46.7%) 19:36:25 INFO - TinderboxPrint: CPU system
1,016.6 (20.2%) 19:36:25 INFO - TinderboxPrint: CPU user
1,666.0 (33.1%) 19:36:25 INFO - TinderboxPrint: Swap in / out
284,155,904 / 0 19:36:25 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 228887040; Write bytes: 229509120; Read time: 18030; Write time: 499 19:36:25 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 40242176; Write bytes: 317749248; Read time: 272; Write time: 1241 19:36:26 INFO - Running post-run listener: _upload_blobber_files 19:36:26 INFO - Blob upload gear active. 19:36:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:36:26 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 19:36:26 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'] 19:36:26 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 19:36:26 INFO - (blobuploader) - INFO - Open directory for files ... 19:36:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:36:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:36:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:27 INFO - (blobuploader) - INFO - Done attempting. 19:36:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:36:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:36:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:28 INFO - (blobuploader) - INFO - Done attempting. 19:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:36:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:36:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:29 INFO - (blobuploader) - INFO - Done attempting. 19:36:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:36:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:31 INFO - (blobuploader) - INFO - Done attempting. 19:36:31 INFO - (blobuploader) - INFO - Iteration through files over. 19:36:31 INFO - Return code: 0 19:36:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:36:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:36:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/951a40831aa018335b468e8b09b32b84bfd355763e1e30615655f30f10d015120f2a8b07f9103594b40958520d8365cea06fccddf21050e27bd136209f74d4ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b082be3c4b6692d8d1ac318552425e653cb13c233c1e9071b7c97af5809e634550947c3de6e94c4ac31f5422ab3b0d3e8c7aab2eeaaa345f8f74bdc886ecb79", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1ffbce02394fda94d33b4a1e8013d4f511debc357e0075a857963527b590bdc9abcb0d15fe05ab34211595f1eb75b791ec967732beb346174a9c8bb2776c51b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d89ba267792be59ac48ca7cf3c5225126e104d8ab61a7cab2d874f1b10e1128f8a3f362d94486f2a10905f2ef4e576ffd11777a008893f58d5806092c41c0e53"} 19:36:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:36:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:36:31 INFO - Contents: 19:36:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/951a40831aa018335b468e8b09b32b84bfd355763e1e30615655f30f10d015120f2a8b07f9103594b40958520d8365cea06fccddf21050e27bd136209f74d4ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b082be3c4b6692d8d1ac318552425e653cb13c233c1e9071b7c97af5809e634550947c3de6e94c4ac31f5422ab3b0d3e8c7aab2eeaaa345f8f74bdc886ecb79", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1ffbce02394fda94d33b4a1e8013d4f511debc357e0075a857963527b590bdc9abcb0d15fe05ab34211595f1eb75b791ec967732beb346174a9c8bb2776c51b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d89ba267792be59ac48ca7cf3c5225126e104d8ab61a7cab2d874f1b10e1128f8a3f362d94486f2a10905f2ef4e576ffd11777a008893f58d5806092c41c0e53"} 19:36:31 INFO - Running post-run listener: copy_logs_to_upload_dir 19:36:31 INFO - Copying logs to upload dir... 19:36:31 INFO - mkdir: /builds/slave/test/build/upload/logs 19:36:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.155490 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-24 19:36:31.731714) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2017-05-24 19:36:31.735488) ========= 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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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/951a40831aa018335b468e8b09b32b84bfd355763e1e30615655f30f10d015120f2a8b07f9103594b40958520d8365cea06fccddf21050e27bd136209f74d4ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b082be3c4b6692d8d1ac318552425e653cb13c233c1e9071b7c97af5809e634550947c3de6e94c4ac31f5422ab3b0d3e8c7aab2eeaaa345f8f74bdc886ecb79", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1ffbce02394fda94d33b4a1e8013d4f511debc357e0075a857963527b590bdc9abcb0d15fe05ab34211595f1eb75b791ec967732beb346174a9c8bb2776c51b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d89ba267792be59ac48ca7cf3c5225126e104d8ab61a7cab2d874f1b10e1128f8a3f362d94486f2a10905f2ef4e576ffd11777a008893f58d5806092c41c0e53"} build_url:https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011134 build_url: 'https://queue.taskcluster.net/v1/task/ZMOJ3jTYQZWXi84RVQSCrA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/951a40831aa018335b468e8b09b32b84bfd355763e1e30615655f30f10d015120f2a8b07f9103594b40958520d8365cea06fccddf21050e27bd136209f74d4ee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b082be3c4b6692d8d1ac318552425e653cb13c233c1e9071b7c97af5809e634550947c3de6e94c4ac31f5422ab3b0d3e8c7aab2eeaaa345f8f74bdc886ecb79", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1ffbce02394fda94d33b4a1e8013d4f511debc357e0075a857963527b590bdc9abcb0d15fe05ab34211595f1eb75b791ec967732beb346174a9c8bb2776c51b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d89ba267792be59ac48ca7cf3c5225126e104d8ab61a7cab2d874f1b10e1128f8a3f362d94486f2a10905f2ef4e576ffd11777a008893f58d5806092c41c0e53"}' ========= master_lag: 2.22 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2017-05-24 19:36:33.963851) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:36:33.964212) ========= 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.77scZ3EkJ3/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.S9VZbKzoJo/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.004997 ========= master_lag: 0.20 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:36:34.171767) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 19:36:34.172111) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 19:36:34.172531) ========= ========= Total master_lag: 2.58 =========