builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0132 starttime: 1496768451.27 results: success (0) revision: c511ad826fe71ba2bb956d2e1c119e86d2266aba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:51.273788) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:51.274294) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:51.274600) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:51.298795) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:51.299185) ========= 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.4l51wTASeE/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.pSfnAdmC4A/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-06 10:00:51-- 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% 8.11M=0.001s 2017-06-06 10:00:51 (8.11 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.182786 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:51.504553) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:51.504887) ========= 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.4l51wTASeE/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.pSfnAdmC4A/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.021059 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:51.553518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:51.553864) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c511ad826fe71ba2bb956d2e1c119e86d2266aba --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 c511ad826fe71ba2bb956d2e1c119e86d2266aba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4l51wTASeE/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.pSfnAdmC4A/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-06 10:00:51,627 truncating revision to first 12 chars 2017-06-06 10:00:51,628 Setting DEBUG logging. 2017-06-06 10:00:51,628 attempt 1/10 2017-06-06 10:00:51,628 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c511ad826fe7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-06 10:00:52,296 unpacking tar archive at: mozilla-central-c511ad826fe7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.965898 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:52.541754) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:52.542353) ========= script_repo_revision: c511ad826fe71ba2bb956d2e1c119e86d2266aba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:52.543068) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:52.543589) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 10:00:52.564012) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-06 10:00:52.564620) ========= /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.4l51wTASeE/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.pSfnAdmC4A/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:00:52 INFO - MultiFileLogger online at 20170606 10:00:52 in /builds/slave/test 10:00:52 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 10:00:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:00:52 INFO - {'append_to_log': False, 10:00:52 INFO - 'base_work_dir': '/builds/slave/test', 10:00:52 INFO - 'blob_upload_branch': 'Firefox', 10:00:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:00:52 INFO - 'branch': 'Firefox', 10:00:52 INFO - 'buildbot_json_path': 'buildprops.json', 10:00:52 INFO - 'config_files': ('talos/mac_config.py',), 10:00:52 INFO - 'default_actions': ('clobber', 10:00:52 INFO - 'read-buildbot-config', 10:00:52 INFO - 'download-and-extract', 10:00:52 INFO - 'populate-webroot', 10:00:52 INFO - 'create-virtualenv', 10:00:52 INFO - 'install', 10:00:52 INFO - 'setup-mitmproxy', 10:00:52 INFO - 'run-tests'), 10:00:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:00:52 INFO - 'download_minidump_stackwalk': True, 10:00:52 INFO - 'download_symbols': 'ondemand', 10:00:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:00:52 INFO - 'tooltool.py': '/tools/tooltool.py', 10:00:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:00:52 INFO - '/tools/misc-python/virtualenv.py')}, 10:00:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:00:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:00:52 INFO - 'gecko_profile': False, 10:00:52 INFO - 'gecko_profile_interval': 0, 10:00:52 INFO - 'installer_path': 'installer.exe', 10:00:52 INFO - 'log_level': 'info', 10:00:52 INFO - 'log_name': 'talos', 10:00:52 INFO - 'log_to_console': True, 10:00:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:00:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:00:52 INFO - 'opt_config_files': (), 10:00:52 INFO - 'pip_index': False, 10:00:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:00:52 INFO - 'cmd': ('bash', 10:00:52 INFO - '-c', 10:00:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:00:52 INFO - 'enabled': True, 10:00:52 INFO - 'halt_on_failure': False, 10:00:52 INFO - 'name': 'check_screen_resolution'},), 10:00:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:00:52 INFO - 'cmd': ('bash', 10:00:52 INFO - '-c', 10:00:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:00:52 INFO - 'enabled': True, 10:00:52 INFO - 'halt_on_failure': False, 10:00:52 INFO - 'name': 'check_screen_resolution'},), 10:00:52 INFO - 'run_cmd_checks_enabled': True, 10:00:52 INFO - 'suite': 'dromaeojs-e10s', 10:00:52 INFO - 'system_bits': '32', 10:00:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:00:52 INFO - 'title': 't-yosemite-r7-0132', 10:00:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:00:52 INFO - 'use_talos_json': True, 10:00:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:00:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:00:52 INFO - 'work_dir': 'build'} 10:00:52 INFO - [mozharness: 2017-06-06 17:00:52.769675Z] Running clobber step. 10:00:52 INFO - Running pre-action listener: _resource_record_pre_action 10:00:52 INFO - Running main action method: clobber 10:00:52 INFO - rmtree: /builds/slave/test/build 10:00:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:00:52 INFO - Running post-action listener: _resource_record_post_action 10:00:52 INFO - [mozharness: 2017-06-06 17:00:52.929111Z] Finished clobber step (success) 10:00:52 INFO - [mozharness: 2017-06-06 17:00:52.929221Z] Running read-buildbot-config step. 10:00:52 INFO - Running pre-action listener: _resource_record_pre_action 10:00:52 INFO - Running main action method: read_buildbot_config 10:00:52 INFO - Using buildbot properties: 10:00:52 INFO - { 10:00:52 INFO - "project": "", 10:00:52 INFO - "product": "firefox", 10:00:52 INFO - "who": "ffxbld@noreply.mozilla.org", 10:00:52 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:00:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:00:52 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:00:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:00:52 INFO - "stage_platform": "macosx64", 10:00:52 INFO - "basedir": "/builds/slave/test", 10:00:52 INFO - "buildnumber": 79, 10:00:52 INFO - "platform": "yosemite_r7", 10:00:52 INFO - "parent_task_id": "becEt9lpSVe-nI3lRbNGYA", 10:00:52 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:00:52 INFO - "slavebuilddir": "test", 10:00:52 INFO - "taskId": "bYzlIbWyQsmc69djIpUDCw", 10:00:52 INFO - "branch": "mozilla-central", 10:00:52 INFO - "script_repo_revision": "production", 10:00:52 INFO - "revision": "c511ad826fe71ba2bb956d2e1c119e86d2266aba", 10:00:52 INFO - "slavename": "t-yosemite-r7-0132", 10:00:52 INFO - "repo_path": "mozilla-central" 10:00:52 INFO - } 10:00:52 INFO - Finding installer, test and symbols from parent task. 10:00:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bYzlIbWyQsmc69djIpUDCw'}, attempt #1 10:00:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bYzlIbWyQsmc69djIpUDCw'}, attempt #1 10:00:54 INFO - Task dependencies: VeyezL3BRvKH4sLIDJrOiQ becEt9lpSVe-nI3lRbNGYA 10:00:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA'}, attempt #1 10:00:54 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:00:54 INFO - Set test_url: https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/test_packages.json 10:00:54 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/target.crashreporter-symbols.zip 10:00:54 INFO - Running post-action listener: _resource_record_post_action 10:00:54 INFO - [mozharness: 2017-06-06 17:00:54.739715Z] Finished read-buildbot-config step (success) 10:00:54 INFO - [mozharness: 2017-06-06 17:00:54.739827Z] Running download-and-extract step. 10:00:54 INFO - Running pre-action listener: _resource_record_pre_action 10:00:54 INFO - Running main action method: download_and_extract 10:00:54 INFO - mkdir: /builds/slave/test/build/tests 10:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:00:54 INFO - trying https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:00:54 INFO - Downloading https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/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 10:00:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/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 10:00:55 INFO - Downloaded 1328 bytes. 10:00:55 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:00:55 INFO - Using the following test package requirements: 10:00:55 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:00:55 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:00:55 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:00:55 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:00:55 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:00:55 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:00:55 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:00:55 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:00:55 INFO - u'jsshell-mac.zip'], 10:00:55 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:00:55 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:00:55 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:00:55 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:00:55 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:00:55 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:00:55 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:00:55 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:00:55 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 10:00:55 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:00:55 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:00:55 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:00:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:00:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:00:55 INFO - Fetch https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:00:57 INFO - Content-Length response header: 57635682 10:00:57 INFO - Bytes received: 57635682 10:01:15 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 10:01:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:01:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:01:15 INFO - Fetch https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:01:17 INFO - Content-Length response header: 57635682 10:01:17 INFO - Bytes received: 57635682 10:01:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:01:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:01:34 INFO - Fetch https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:01:36 INFO - Content-Length response header: 14447479 10:01:36 INFO - Bytes received: 14447479 10:01:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:37 INFO - trying https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:01:37 INFO - Downloading https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:01:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/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 10:01:39 INFO - Downloaded 67984564 bytes. 10:01:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:01:39 INFO - mkdir: /builds/slave/test/properties 10:01:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:01:39 INFO - Writing to file /builds/slave/test/properties/build_url 10:01:39 INFO - Contents: 10:01:39 INFO - build_url:https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:01:39 INFO - Running post-action listener: _resource_record_post_action 10:01:39 INFO - Running post-action listener: set_extra_try_arguments 10:01:39 INFO - [mozharness: 2017-06-06 17:01:39.200285Z] Finished download-and-extract step (success) 10:01:39 INFO - [mozharness: 2017-06-06 17:01:39.200440Z] Running populate-webroot step. 10:01:39 INFO - Running pre-action listener: _resource_record_pre_action 10:01:39 INFO - Running main action method: populate_webroot 10:01:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:01:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:01:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:01:39 INFO - '--tppagecycles', 10:01:39 INFO - '7'], 10:01:39 INFO - 'tests': ['tsvgm']}}, 10:01:39 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:01:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:01:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:01:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:01:39 INFO - 'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:01:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:01:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:01:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:01:39 INFO - 'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['damp', 'tps']}, 10:01:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:01:39 INFO - 'tests': ['damp', 'tps']}, 10:01:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['dromaeo_dom']}, 10:01:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:01:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:01:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:01:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:01:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:01:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['a11yr', 10:01:39 INFO - 'ts_paint', 10:01:39 INFO - 'tpaint', 10:01:39 INFO - 'sessionrestore', 10:01:39 INFO - 'sessionrestore_no_auto_restore', 10:01:39 INFO - 'tabpaint']}, 10:01:39 INFO - 'other-e10s': {'tests': ['a11yr', 10:01:39 INFO - 'ts_paint', 10:01:39 INFO - 'tpaint', 10:01:39 INFO - 'sessionrestore', 10:01:39 INFO - 'sessionrestore_no_auto_restore', 10:01:39 INFO - 'tabpaint']}, 10:01:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:01:39 INFO - 'ts_paint', 10:01:39 INFO - 'tpaint', 10:01:39 INFO - 'sessionrestore', 10:01:39 INFO - 'sessionrestore_no_auto_restore', 10:01:39 INFO - 'tabpaint']}, 10:01:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:01:39 INFO - 'ts_paint', 10:01:39 INFO - 'tpaint', 10:01:39 INFO - 'sessionrestore', 10:01:39 INFO - 'sessionrestore_no_auto_restore', 10:01:39 INFO - 'tabpaint']}, 10:01:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['a11yr', 10:01:39 INFO - 'ts_paint', 10:01:39 INFO - 'tpaint', 10:01:39 INFO - 'sessionrestore', 10:01:39 INFO - 'sessionrestore_no_auto_restore', 10:01:39 INFO - 'tabpaint']}, 10:01:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['a11yr', 10:01:39 INFO - 'ts_paint', 10:01:39 INFO - 'tpaint', 10:01:39 INFO - 'sessionrestore', 10:01:39 INFO - 'sessionrestore_no_auto_restore', 10:01:39 INFO - 'tabpaint']}, 10:01:39 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:01:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:01:39 INFO - 'bloom_basic_ref']}, 10:01:39 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 10:01:39 INFO - 'talos_options': ['--mitmproxy', 10:01:39 INFO - 'mitmproxy-recording-1.mp', 10:01:39 INFO - '--firstNonBlankPaint'], 10:01:39 INFO - 'tests': ['Quantum_1']}, 10:01:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['tsvgx', 10:01:39 INFO - 'tsvgr_opacity', 10:01:39 INFO - 'tart', 10:01:39 INFO - 'tscrollx', 10:01:39 INFO - 'cart', 10:01:39 INFO - 'tsvg_static']}, 10:01:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:01:39 INFO - 'tsvgr_opacity', 10:01:39 INFO - 'tart', 10:01:39 INFO - 'tscrollx', 10:01:39 INFO - 'cart', 10:01:39 INFO - 'tsvg_static']}, 10:01:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:01:39 INFO - 'talos_options': ['--disable-e10s'], 10:01:39 INFO - 'tests': ['tp5o']}, 10:01:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:01:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:01:39 INFO - 'talos_options': ['--disable-e10s', 10:01:39 INFO - '--xperf_path', 10:01:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:39 INFO - 'tests': ['tp5n']}, 10:01:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:01:39 INFO - 'talos_options': ['--xperf_path', 10:01:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:01:39 INFO - 'tests': ['tp5n']}}, 10:01:39 INFO - 'talos.zip': {'path': '', 10:01:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:01:39 INFO - Running post-action listener: _resource_record_post_action 10:01:39 INFO - [mozharness: 2017-06-06 17:01:39.221672Z] Finished populate-webroot step (success) 10:01:39 INFO - [mozharness: 2017-06-06 17:01:39.221845Z] Running create-virtualenv step. 10:01:39 INFO - Running pre-action listener: _resource_record_pre_action 10:01:39 INFO - Running main action method: create_virtualenv 10:01:39 INFO - Creating virtualenv /builds/slave/test/build/venv 10:01:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:01:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:01:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:01:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:01:39 INFO - Using real prefix '/tools/python27' 10:01:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:01:40 INFO - Installing distribute.............................................................................................................................................................................................done. 10:01:42 INFO - Installing pip.................done. 10:01:42 INFO - Return code: 0 10:01:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:01:43 INFO - Reading from file tmpfile_stdout 10:01:43 INFO - Output received: 10:01:43 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:01:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:01:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:01:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:01:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:43 INFO - 'HOME': '/Users/cltbld', 10:01:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:43 INFO - 'LOGNAME': 'cltbld', 10:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:43 INFO - 'MOZ_NO_REMOTE': '1', 10:01:43 INFO - 'NO_EM_RESTART': '1', 10:01:43 INFO - 'PAGER': '/bin/cat', 10:01:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:43 INFO - 'PWD': '/builds/slave/test', 10:01:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:43 INFO - 'SHELL': '/bin/bash', 10:01:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:01:43 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:01:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:43 INFO - 'USER': 'cltbld', 10:01:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:43 INFO - 'XPC_FLAGS': '0x0', 10:01:43 INFO - 'XPC_SERVICE_NAME': '0', 10:01:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:01:43 INFO - Cleaning up... 10:01:43 INFO - Return code: 0 10:01:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:01:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:01:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:01:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:43 INFO - 'HOME': '/Users/cltbld', 10:01:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:43 INFO - 'LOGNAME': 'cltbld', 10:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:43 INFO - 'MOZ_NO_REMOTE': '1', 10:01:43 INFO - 'NO_EM_RESTART': '1', 10:01:43 INFO - 'PAGER': '/bin/cat', 10:01:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:43 INFO - 'PWD': '/builds/slave/test', 10:01:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:43 INFO - 'SHELL': '/bin/bash', 10:01:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:01:43 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:01:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:43 INFO - 'USER': 'cltbld', 10:01:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:43 INFO - 'XPC_FLAGS': '0x0', 10:01:43 INFO - 'XPC_SERVICE_NAME': '0', 10:01:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:43 INFO - Downloading/unpacking psutil>=3.1.1 10:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:01:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:01:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:01:45 INFO - Installing collected packages: psutil 10:01:45 INFO - Running setup.py install for psutil 10:01:45 INFO - building 'psutil._psutil_osx' extension 10:01:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:01:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:01:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:01:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:01:46 INFO - building 'psutil._psutil_posix' extension 10:01:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:01:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:01:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:01:46 INFO - ^ 10:01:46 INFO - 1 warning generated. 10:01:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:01:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:01:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:01:46 INFO - Successfully installed psutil 10:01:46 INFO - Cleaning up... 10:01:46 INFO - Return code: 0 10:01:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:01:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:01:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:46 INFO - 'HOME': '/Users/cltbld', 10:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:46 INFO - 'LOGNAME': 'cltbld', 10:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:46 INFO - 'MOZ_NO_REMOTE': '1', 10:01:46 INFO - 'NO_EM_RESTART': '1', 10:01:46 INFO - 'PAGER': '/bin/cat', 10:01:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:46 INFO - 'PWD': '/builds/slave/test', 10:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:46 INFO - 'SHELL': '/bin/bash', 10:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:01:46 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:46 INFO - 'USER': 'cltbld', 10:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:46 INFO - 'XPC_FLAGS': '0x0', 10:01:46 INFO - 'XPC_SERVICE_NAME': '0', 10:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:01:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:01:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:01:48 INFO - Installing collected packages: mozsystemmonitor 10:01:48 INFO - Running setup.py install for mozsystemmonitor 10:01:48 INFO - Successfully installed mozsystemmonitor 10:01:48 INFO - Cleaning up... 10:01:48 INFO - Return code: 0 10:01:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:49 INFO - 'HOME': '/Users/cltbld', 10:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:49 INFO - 'LOGNAME': 'cltbld', 10:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:49 INFO - 'MOZ_NO_REMOTE': '1', 10:01:49 INFO - 'NO_EM_RESTART': '1', 10:01:49 INFO - 'PAGER': '/bin/cat', 10:01:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:49 INFO - 'PWD': '/builds/slave/test', 10:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:49 INFO - 'SHELL': '/bin/bash', 10:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:01:49 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:49 INFO - 'USER': 'cltbld', 10:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:49 INFO - 'XPC_FLAGS': '0x0', 10:01:49 INFO - 'XPC_SERVICE_NAME': '0', 10:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:49 INFO - Downloading/unpacking jsonschema==2.5.1 10:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:01:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:51 INFO - Downloading functools32-3.2.3-2.tar.gz 10:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:01:51 INFO - warning: no files found matching '*.txt' 10:01:51 INFO - no previously-included directories found matching 'build' 10:01:51 INFO - no previously-included directories found matching 'dist' 10:01:51 INFO - no previously-included directories found matching '.git*' 10:01:51 INFO - Installing collected packages: jsonschema, functools32 10:01:51 INFO - Running setup.py install for functools32 10:01:51 INFO - warning: no files found matching '*.txt' 10:01:51 INFO - no previously-included directories found matching 'build' 10:01:51 INFO - no previously-included directories found matching 'dist' 10:01:51 INFO - no previously-included directories found matching '.git*' 10:01:51 INFO - Successfully installed jsonschema functools32 10:01:51 INFO - Cleaning up... 10:01:51 INFO - Return code: 0 10:01:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:51 INFO - 'HOME': '/Users/cltbld', 10:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:51 INFO - 'LOGNAME': 'cltbld', 10:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:51 INFO - 'MOZ_NO_REMOTE': '1', 10:01:51 INFO - 'NO_EM_RESTART': '1', 10:01:51 INFO - 'PAGER': '/bin/cat', 10:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:51 INFO - 'PWD': '/builds/slave/test', 10:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:51 INFO - 'SHELL': '/bin/bash', 10:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:01:51 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:51 INFO - 'USER': 'cltbld', 10:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:51 INFO - 'XPC_FLAGS': '0x0', 10:01:51 INFO - 'XPC_SERVICE_NAME': '0', 10:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:01:51 INFO - Cleaning up... 10:01:51 INFO - Return code: 0 10:01:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:01:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:01:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:51 INFO - 'HOME': '/Users/cltbld', 10:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:51 INFO - 'LOGNAME': 'cltbld', 10:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:51 INFO - 'MOZ_NO_REMOTE': '1', 10:01:51 INFO - 'NO_EM_RESTART': '1', 10:01:51 INFO - 'PAGER': '/bin/cat', 10:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:51 INFO - 'PWD': '/builds/slave/test', 10:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:51 INFO - 'SHELL': '/bin/bash', 10:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:01:51 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:51 INFO - 'USER': 'cltbld', 10:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:51 INFO - 'XPC_FLAGS': '0x0', 10:01:51 INFO - 'XPC_SERVICE_NAME': '0', 10:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:52 INFO - Downloading/unpacking blobuploader==1.2.4 10:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:53 INFO - Downloading blobuploader-1.2.4.tar.gz 10:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:01:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:01:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:01:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:01:54 INFO - Downloading docopt-0.6.1.tar.gz 10:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:01:54 INFO - Installing collected packages: blobuploader, requests, docopt 10:01:54 INFO - Running setup.py install for blobuploader 10:01:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:01:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:01:54 INFO - Running setup.py install for requests 10:01:55 INFO - Running setup.py install for docopt 10:01:55 INFO - Successfully installed blobuploader requests docopt 10:01:55 INFO - Cleaning up... 10:01:55 INFO - Return code: 0 10:01:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:55 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 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:01:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:01:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:01:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:55 INFO - 'HOME': '/Users/cltbld', 10:01:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:55 INFO - 'LOGNAME': 'cltbld', 10:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:55 INFO - 'MOZ_NO_REMOTE': '1', 10:01:55 INFO - 'NO_EM_RESTART': '1', 10:01:55 INFO - 'PAGER': '/bin/cat', 10:01:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:55 INFO - 'PWD': '/builds/slave/test', 10:01:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:55 INFO - 'SHELL': '/bin/bash', 10:01:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:01:55 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:01:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:55 INFO - 'USER': 'cltbld', 10:01:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:55 INFO - 'XPC_FLAGS': '0x0', 10:01:55 INFO - 'XPC_SERVICE_NAME': '0', 10:01:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:01:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-tU0oc3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:01:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-IEsdaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:01:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-xZO8CO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:01:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-MtWLyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:01:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:01:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-JKocLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-uWC8nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-crx0rF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-l0UHUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-ge77rw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-dNuqRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-KvT9qC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-N_TI32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-o56Ak5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Ku2hw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-7NNE5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:01:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-CtXtKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:01:57 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-LEy1Y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:01:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:01:57 INFO - Running setup.py install for manifestparser 10:01:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:01:57 INFO - Running setup.py install for mozcrash 10:01:57 INFO - Running setup.py install for mozdebug 10:01:57 INFO - Running setup.py install for mozdevice 10:01:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:01:57 INFO - Running setup.py install for mozfile 10:01:58 INFO - Running setup.py install for mozhttpd 10:01:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:01:58 INFO - Running setup.py install for mozinfo 10:01:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:01:58 INFO - Running setup.py install for mozInstall 10:01:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:01:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:01:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:01:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:01:58 INFO - Running setup.py install for mozleak 10:01:58 INFO - Running setup.py install for mozlog 10:01:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:01:58 INFO - Running setup.py install for moznetwork 10:01:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:01:58 INFO - Running setup.py install for mozprocess 10:01:59 INFO - Running setup.py install for mozprofile 10:01:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:01:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:01:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:01:59 INFO - Running setup.py install for mozrunner 10:01:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:01:59 INFO - Running setup.py install for mozscreenshot 10:01:59 INFO - Running setup.py install for moztest 10:01:59 INFO - Running setup.py install for mozversion 10:01:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:01:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:01:59 INFO - Cleaning up... 10:01:59 INFO - Return code: 0 10:01:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:01:59 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 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:01:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:01:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:01:59 INFO - 'HOME': '/Users/cltbld', 10:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:01:59 INFO - 'LOGNAME': 'cltbld', 10:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:01:59 INFO - 'MOZ_NO_REMOTE': '1', 10:01:59 INFO - 'NO_EM_RESTART': '1', 10:01:59 INFO - 'PAGER': '/bin/cat', 10:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:01:59 INFO - 'PWD': '/builds/slave/test', 10:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:01:59 INFO - 'SHELL': '/bin/bash', 10:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:01:59 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:01:59 INFO - 'USER': 'cltbld', 10:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:01:59 INFO - 'XPC_FLAGS': '0x0', 10:01:59 INFO - 'XPC_SERVICE_NAME': '0', 10:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:02:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-cMgx89-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:02:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-kpaJMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:02:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-UiX6Ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:02:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-puSQUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:02:00 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)) 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:02:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-WI4gm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:02:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-LBPBbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:02:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-y0VPsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:02:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-1A4Zfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:02:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-O6UuOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:02:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-IbjuB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:02:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-1bqAcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:02:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-QG8VYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:02:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-6aCAn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:02:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-qG17pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:02:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-DZwvbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:02:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-TOOGzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:02:01 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-eoJs5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:02:01 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)) 10:02:01 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)) 10:02:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:03 INFO - Downloading blessings-1.6.tar.gz 10:02:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:02:03 INFO - Installing collected packages: blessings 10:02:03 INFO - Running setup.py install for blessings 10:02:03 INFO - Successfully installed blessings 10:02:03 INFO - Cleaning up... 10:02:03 INFO - Return code: 0 10:02:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:02:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:02:03 INFO - Reading from file tmpfile_stdout 10:02:03 INFO - Current package versions: 10:02:03 INFO - blessings == 1.6 10:02:03 INFO - blobuploader == 1.2.4 10:02:03 INFO - docopt == 0.6.1 10:02:03 INFO - functools32 == 3.2.3-2 10:02:03 INFO - jsonschema == 2.5.1 10:02:03 INFO - manifestparser == 1.1 10:02:03 INFO - mozInstall == 1.12 10:02:03 INFO - mozcrash == 1.0 10:02:03 INFO - mozdebug == 0.1 10:02:03 INFO - mozdevice == 0.51 10:02:03 INFO - mozfile == 1.2 10:02:03 INFO - mozhttpd == 0.7 10:02:03 INFO - mozinfo == 0.10 10:02:03 INFO - mozleak == 0.1 10:02:03 INFO - mozlog == 3.4 10:02:03 INFO - moznetwork == 0.27 10:02:03 INFO - mozprocess == 0.25 10:02:03 INFO - mozprofile == 0.28 10:02:03 INFO - mozrunner == 6.13 10:02:03 INFO - mozscreenshot == 0.1 10:02:03 INFO - mozsystemmonitor == 0.3 10:02:03 INFO - moztest == 0.8 10:02:03 INFO - mozversion == 1.4 10:02:03 INFO - psutil == 3.1.1 10:02:03 INFO - requests == 1.2.3 10:02:03 INFO - wsgiref == 0.1.2 10:02:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02:03 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 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:02:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:02:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:02:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:02:03 INFO - 'HOME': '/Users/cltbld', 10:02:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:02:03 INFO - 'LOGNAME': 'cltbld', 10:02:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:03 INFO - 'MOZ_NO_REMOTE': '1', 10:02:03 INFO - 'NO_EM_RESTART': '1', 10:02:03 INFO - 'PAGER': '/bin/cat', 10:02:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:02:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:02:03 INFO - 'PWD': '/builds/slave/test', 10:02:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:02:03 INFO - 'SHELL': '/bin/bash', 10:02:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:02:03 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:02:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:02:03 INFO - 'USER': 'cltbld', 10:02:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:02:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:02:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:02:03 INFO - 'XPC_FLAGS': '0x0', 10:02:03 INFO - 'XPC_SERVICE_NAME': '0', 10:02:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:02:04 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:02:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:02:05 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:02:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:02:05 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)) 10:02:05 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)) 10:02:05 INFO - Installing collected packages: marionette-driver 10:02:05 INFO - Running setup.py install for marionette-driver 10:02:05 INFO - Successfully installed marionette-driver 10:02:05 INFO - Cleaning up... 10:02:05 INFO - Return code: 0 10:02:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:02: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')]} 10:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:02: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')]} 10:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:02: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecb7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f9b4728d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, '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.4l51wTASeE/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.pSfnAdmC4A/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:02: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', 'jsonschema'] in /builds/slave/test/build 10:02: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 jsonschema 10:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:02:05 INFO - 'HOME': '/Users/cltbld', 10:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:02:05 INFO - 'LOGNAME': 'cltbld', 10:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:05 INFO - 'MOZ_NO_REMOTE': '1', 10:02:05 INFO - 'NO_EM_RESTART': '1', 10:02:05 INFO - 'PAGER': '/bin/cat', 10:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:02:05 INFO - 'PWD': '/builds/slave/test', 10:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:02:05 INFO - 'SHELL': '/bin/bash', 10:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:02:05 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:02:05 INFO - 'USER': 'cltbld', 10:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:02:05 INFO - 'XPC_FLAGS': '0x0', 10:02:05 INFO - 'XPC_SERVICE_NAME': '0', 10:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:02:06 INFO - Cleaning up... 10:02:06 INFO - Return code: 0 10:02:06 INFO - Running post-action listener: _resource_record_post_action 10:02:06 INFO - Running post-action listener: _start_resource_monitoring 10:02:06 INFO - Starting resource monitoring. 10:02:06 INFO - [mozharness: 2017-06-06 17:02:06.203400Z] Finished create-virtualenv step (success) 10:02:06 INFO - [mozharness: 2017-06-06 17:02:06.204379Z] Running install step. 10:02:06 INFO - Running pre-action listener: _resource_record_pre_action 10:02:06 INFO - Running main action method: install 10:02:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:02:06 INFO - Reading from file tmpfile_stdout 10:02:06 INFO - Detecting whether we're running mozinstall >=1.0... 10:02:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:02:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:02:06 INFO - Reading from file tmpfile_stdout 10:02:06 INFO - Output received: 10:02:06 INFO - Usage: mozinstall [options] installer 10:02:06 INFO - Options: 10:02:06 INFO - -h, --help show this help message and exit 10:02:06 INFO - -d DEST, --destination=DEST 10:02:06 INFO - Directory to install application into. [default: 10:02:06 INFO - "/builds/slave/test"] 10:02:06 INFO - --app=APP Application being installed. [default: firefox] 10:02:06 INFO - mkdir: /builds/slave/test/build/application 10:02:06 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'] 10:02:06 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 10:02:28 INFO - Reading from file tmpfile_stdout 10:02:28 INFO - Output received: 10:02:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:02:28 INFO - Running post-action listener: _resource_record_post_action 10:02:28 INFO - [mozharness: 2017-06-06 17:02:28.069141Z] Finished install step (success) 10:02:28 INFO - [mozharness: 2017-06-06 17:02:28.069301Z] Running setup-mitmproxy step. 10:02:28 INFO - Running pre-action listener: _resource_record_pre_action 10:02:28 INFO - Running main action method: setup_mitmproxy 10:02:28 INFO - Skipping: mitmproxy is not required 10:02:28 INFO - Running post-action listener: _resource_record_post_action 10:02:28 INFO - [mozharness: 2017-06-06 17:02:28.069870Z] Finished setup-mitmproxy step (success) 10:02:28 INFO - [mozharness: 2017-06-06 17:02:28.069983Z] Running run-tests step. 10:02:28 INFO - Running pre-action listener: _resource_record_pre_action 10:02:28 INFO - Running main action method: run_tests 10:02:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:02:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:02:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:02:28 INFO - 2017-06-06 10:02:28.084 screenresolution[1885:8924] starting screenresolution argv=screenresolution get 10:02:28 INFO - 2017-06-06 10:02:28.087 screenresolution[1885:8924] Display 0: 1600x1200x32@60 10:02:28 INFO - 2017-06-06 10:02:28.093 screenresolution[1886:8926] starting screenresolution argv=screenresolution list 10:02:28 INFO - Available Modes on Display 0 10:02:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:02:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:02:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:02:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:02:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:02:28 INFO - Intel Iris: 10:02:28 INFO - Chipset Model: Intel Iris 10:02:28 INFO - Type: GPU 10:02:28 INFO - Bus: Built-In 10:02:28 INFO - VRAM (Dynamic, Max): 1536 MB 10:02:28 INFO - Vendor: Intel (0x8086) 10:02:28 INFO - Device ID: 0x0a2e 10:02:28 INFO - Revision ID: 0x0009 10:02:28 INFO - Displays: 10:02:28 INFO - Display: 10:02:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:02:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:02:28 INFO - Display Serial Number: 1600x1200 60 10:02:28 INFO - Main Display: Yes 10:02:28 INFO - Mirror: Off 10:02:28 INFO - Online: Yes 10:02:28 INFO - Rotation: Supported 10:02:28 INFO - Return code: 0 10:02:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:02:28 INFO - Python 2.7.3 10:02:28 INFO - Return code: 0 10:02:28 INFO - grabbing minidump binary from tooltool 10:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:02:28 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 0x10ec3cc60>, '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 0x10e9d1cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e9fabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:02:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:02:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:02:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:02:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:02:28 INFO - Return code: 0 10:02:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:02:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:02:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:02:28 INFO - ENV: RUST_BACKTRACE is now 1 10:02:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:02:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:02:28 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/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:02:28 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/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0132 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4l51wTASeE/Render', 10:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:02:28 INFO - 'HOME': '/Users/cltbld', 10:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:02:28 INFO - 'LOGNAME': 'cltbld', 10:02:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:02:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:02:28 INFO - 'MOZ_NO_REMOTE': '1', 10:02:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:02:28 INFO - 'NO_EM_RESTART': '1', 10:02:28 INFO - 'PAGER': '/bin/cat', 10:02:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:02:28 INFO - 'PWD': '/builds/slave/test', 10:02:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:02:28 INFO - 'RUST_BACKTRACE': '1', 10:02:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:02:28 INFO - 'SHELL': '/bin/bash', 10:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pSfnAdmC4A/Listeners', 10:02:28 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 10:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:02:28 INFO - 'USER': 'cltbld', 10:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:02:28 INFO - 'XPC_FLAGS': '0x0', 10:02:28 INFO - 'XPC_SERVICE_NAME': '0', 10:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:02:28 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/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:02:28 INFO - mozversion application_buildid: 20170606084241 10:02:28 INFO - mozversion application_changeset: c511ad826fe71ba2bb956d2e1c119e86d2266aba 10:02:28 INFO - mozversion application_display_name: Nightly 10:02:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:02:28 INFO - mozversion application_name: Firefox 10:02:28 INFO - mozversion application_remotingname: firefox 10:02:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:02:28 INFO - mozversion application_vendor: Mozilla 10:02:28 INFO - mozversion application_version: 55.0a1 10:02:28 INFO - mozversion platform_buildid: 20170606084241 10:02:28 INFO - mozversion platform_changeset: c511ad826fe71ba2bb956d2e1c119e86d2266aba 10:02:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:02:28 INFO - mozversion platform_version: 55.0a1 10:02:28 INFO - using testdate: 1496768548 10:02:28 INFO - actual date: 1496768548 10:02:28 INFO - starting webserver on 'localhost:49354' 10:02:28 INFO - SUITE-START | Running 2 tests 10:02:28 INFO - TEST-START | dromaeo_css 10:02:28 INFO - operating with platform_type : mac_ 10:02:28 INFO - Initialising browser for dromaeo_css test... 10:02:28 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49354/getInfo.html -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpTPhl67/profile 10:02:28 INFO - TEST-INFO | started process 1896 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49354/getInfo.html -foreground) 10:03:02 INFO - TEST-INFO | 1896: killed by SIGTERM 10:03:02 INFO - Browser initialized. 10:03:02 INFO - Running cycle 1/1 for dromaeo_css test... 10:03:02 INFO - TEST-INFO | started process 1902 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpTPhl67/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:03:04 INFO - PID 1902 | 2017-06-06 10:03:04.188 plugin-container[1903:9563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 10:03:04 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:04 INFO - PID 1902 | 2017-06-06 10:03:04.189 plugin-container[1903:9563] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 10:03:04 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:04 INFO - PID 1902 | 2017-06-06 10:03:04.189 plugin-container[1903:9563] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:03:04 INFO - PID 1902 | 2017-06-06 10:03:04.870 plugin-container[1904:9611] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 10:03:04 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:04 INFO - PID 1902 | 2017-06-06 10:03:04.870 plugin-container[1904:9611] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 10:03:04 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:03:04 INFO - PID 1902 | 2017-06-06 10:03:04.870 plugin-container[1904:9611] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:03:14 INFO - PID 1902 | RSS: Main: 259039232 10:03:14 INFO - PID 1902 | 10:06:16 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49354/tests/dromaeo/cssquery-ext.html) 10:06:17 INFO - PID 1902 | RSS: Main: 276361216 10:06:17 INFO - PID 1902 | 10:09:19 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-ext.html (next: http://localhost:49354/tests/dromaeo/cssquery-jquery.html) 10:09:20 INFO - PID 1902 | RSS: Main: 289374208 10:09:20 INFO - PID 1902 | 10:12:21 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49354/tests/dromaeo/cssquery-mootools.html) 10:12:22 INFO - PID 1902 | RSS: Main: 289210368 10:12:22 INFO - PID 1902 | 10:15:24 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49354/tests/dromaeo/cssquery-prototype.html) 10:15:25 INFO - PID 1902 | RSS: Main: 294076416 10:15:25 INFO - PID 1902 | 10:18:27 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49354/tests/dromaeo/cssquery-yui.html) 10:18:28 INFO - PID 1902 | RSS: Main: 294662144 10:18:28 INFO - PID 1902 | 10:21:30 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49354/tests/dromaeo/cssquery-yui.html (next: http://localhost:49354/tests/dromaeo/cssquery-dojo.html) 10:21:30 INFO - PID 1902 | RSS: Main: 297168896 10:21:30 INFO - PID 1902 | 10:21:30 INFO - PID 1902 | __start_tp_report 10:21:30 INFO - PID 1902 | _x_x_mozilla_page_load 10:21:30 INFO - PID 1902 | _x_x_mozilla_page_load_details 10:21:30 INFO - PID 1902 | |i|pagename|runs| 10:21:30 INFO - PID 1902 | |0;dojo.html;3075.6463006754225;3548.1839176989274;3574.374484465218;3603.711703063755;3610.169660977975;4665.83669571565;6050.667213303266;6077.7568897244055;6192.41172088652;6207.441330280274;6122.755089796403;6131.804871721961;6136.335271946679;6214.5649804513705;6218.937810621893;4461.888452788674;5239.973800130994;5405.243265942771;5418.593605479585;5436.91844622331;5472.781088756465;5496.697681627509;5496.780128794843;5498.1752737089355;5531.3915469298545;5265.921011184835;5334.839954801362;5387.4612538746205;5409.26974858393;5414.539764120054;5415.891682166334;5439.265699130607;5445.83662490126;5467.234587157801;5477.068898287301;4796.904061918742;5002.649814512985;5346.331708536433;5355.847305180918;5469.425710300441;6084.391560843925;6146.29317628474;6155.322914479404;6233.470155036827;6236.688165591702;6502.577332473374;6580.4406625436895;6585;6593.241777195636;6627.204735431731;6778.254392016875;6791.015302781069;6796.830079248009;6798.966005169992;6802.97955306703;6154.876902461926;6234.096056071935;6289.7169627366875;6315.400036996524;6315.621062736204;6729.22613899398;6803.217629972518;6849.554778939353;6850.143732033496;6853.485988550879;6295.622262664255;6326.43062124411;6388.776392826228;6547.672616369163;6561.507886908501;6723.159605049369;6739.090222819955;6762.323767623198;6768.120144381199;6781.830454238585;6385.2337719473035;6435.485161187094;6610.975298828689;6614.603123812587;6680.699368528428;5503.972480137654;6506.95888657822;6514.57655252407;6538.8365290868105;6599.70301336441;4557.293619488984;4990.8752281193265;5322.600804939645;5328.49379308965;5340.572754179656;4616.630669546428;4762.142814293391;4762.738049407247;4765.261384485341;4774.164521208774;6082.878342433127;6179;6248;6250.4374606285655;6261.029540421242;5530.557555395559;5952.166696662471;6775.93224067753;6779.93220067793;6783.287754785775;6034.879302413927;6283.434490895841;6290.968545157245;6309.905351419732;6311.526635502356;5664.801731939445;5741.741621627037;5749.223854779635;5764.7117644117625;5888.234529511136;6791.626460544718;6795.674843405489;6858.965705171542;6863.588184708933;6877.449804015767;5532.280803495681;6702.061711360511;6723.899141512979;6728.091707619409;6753.15585551806;5127;5347.786088556414;6815.114035175594;6825.283345248876;6846.281140480178;6188;6464.5798023128345;6525.119108920425;6561.835954100995;6569.9343006569325;6710.932890671226;6719.059331693469;6724.226713927766;6739.292374300824;6751.729930802713;6302.810915672537;6327.905081423874;6460.612363258031;6539.0191471277785;6547.639879806466;6524.641144737085;6546.705398257185;6558.934410655832;6562.343765623399;6592.538522303393;6137.4382736082025;6214.5960512566535;6230.252369715663;6233.283172435228;6288.968555157195;6580.21037475506;6587.604743715392;6588.110605068255;6598.274189839018;6609.834754130993;6406.551541392244;6408.327125651926;6413.615183089029;6420.26166990802;6428.325025872403 10:21:30 INFO - PID 1902 | |1;ext.html;795.5902710104298;813.6460639621764;831.0775039705925;1250.712336162682;1306.3598836570072;38023.80988095063;39168.412473812845;39275.80362098189;39290.21419571611;39442.211155776866;34881;36661.90014299575;39671;39951;40061.59938400615;7820.843583128334;7997.840043199132;8290.33677305817;8498.022727386367;8506.74479765608;8343.16568343167;8430;8442.704505342313;8450.366222533305;8454.070052294242;8410.957945210266;8456.915430845675;8469.95765021174;8475;8479.957600212021;8400.537970411626;8438.987321521396;8445.28215101714;8446.450980686235;8452.154784521561;8211.876821847676;8255.339572834158;8294.709685160991;8307.335413166933;8331.625076871554;36210.8189459051;36379;36452.27095458103;36544.81727591345;36585.45121823175;3596.5504311961004;3625.021244264034;3628.38317486028;3628.6189950055127;3633.091727068233;30089.849550752326;30436.086917392513;30566.54150187749;30575.694243057507;30602.234944126354;3045.7715671324518;3056.5262384330463;3079.414911166871;3083.8149711017186;3090.7218350348353;237213.81393093098;241388;242854;295592;295872;45429.54570454319;45437.54562454333;45466.863328416395;45612.543874561496;45615.54384456113;47131.764341178074;47433.762831186315;49305.0138997218;49870.75064624654;50141.74929125404;14442.783358249635;14484.913631477471;14613.634659133606;14649.780253296209;14687.55937321882;11640.476178571984;11664.708382290512;11844.585439509576;11869.762604747857;11885.762284754257;13913.513027044208;13931.094478859044;13935.721285574231;13962.51131210423;14032.859671403156;6129.049997250433;6165.568410211242;6172.753089876444;6184.567080304336;6193.659348735863;944.6552008516842;960.711786464058;962.8988956159502;981.9803603927882;1007.1841808135288;22465;24238.75761242365;24363;24561.037826678406;24603.261902142964;1080.2600218850114;1091.9890801091888;1120.9215354925077;1145.0724912820642;1150.7583407484522;11555.653330400102;11769.234999724991;12469.75060498803;14871.33079011447;15784.763228551581;326114;327879;328435.7891052695;328974;329733;58172.418275817545;58734.4126558729;58791.70604146952;58838.705806470694;58870.41129588649;1485.9405623775144;1494.7309484292932;1534.2712211699577;1534.3555706603254;1540.075954427357;27778;28791.568126478538;30192;30527.08418747441;30530.542041868946;38031.619683802805;38268.425973610974;38309.6169038306;38342.04144896288;38393.61606383901;284403;286752;287257;288426;288911;34253.82873085718;34782.9565113047;34938;35013;35070;43123.56876431195;43861;43887.56112438963;43960.780196098814;43964.120717586105;24993.75006249914;26290.816913238566;28906.83198760059;29642.703572964856;30381.392372152874;29648.40703185881;29787.55318670265;30110;30581;30705.385892282477;35113;35249.94250172499;36700.26599468049;37078.44382334321;37090.814545927096;29375.412491750474;29440;30496.390072197988;30843.845780770953;30869.817180387738;22324.66513002274;23928.641070384307;24150.51698966046;24159.75840241575;24194.395140121636 10:21:30 INFO - PID 1902 | |2;jquery.html;423.61165968875906;425.45754163441626;431.7495852405605;439.79769306119164;448.2133855084327;22035.449113772123;22056;22816.65775013376;22921.19775807847;24838.627420588706;24212.636810447857;24504.75495245047;24774.75225247747;25037.123700670436;25062.624060639104;6051.2133422655;6197.535184861131;6204.9069263960855;6213.161223234873;6216.036514340262;5965.373635768247;6098.237720284965;6137.754489820424;6196.101565273033;6200.193974783272;6145.539084568675;6158.168647232634;6188.288346840103;6197.2563292404975;6205.441510264075;6179.474744646711;6210.254769427697;6210.813675589739;6215.782447614357;6222.253329600454;5508.779648814043;5524.917126243109;5530.004599172147;5534.916976245359;5541.778328866841;28623.27897373349;29717;29734.85132574345;30044.098677039725;30107.69892301071;2137.636601777686;2168.8915554222226;2175.2495389090736;2176.1295481807247;2176.249194028058;18829.529261768566;19064.33274835374;19371.4188574343;19392.545559083126;19459.513512162168;2148.677698345245;2196.0556960506974;2228.097620463715;2235.6422972324513;2244.6745221942892;195649.06526402116;199468.0053199464;199705;211632;212298.93850530803;13097;13155;13211.273379964194;13284.73430531384;13731.931340343433;16807.41174058902;16932.40736574214;17018.48944531666;17031.31874724997;17038.659226815642;9075.13786190311;9108.908910910939;9256.259499240045;9294.349395542247;9328.720138395858;8933.776655583662;8965.237954773916;8978.10218978097;8998;9014.684486042957;8266.462679925786;8292.709755158541;8345.499270043696;8421.915780842113;8427.95786021066;2183.6942828004094;2186.0599942024764;2188.835837312208;2205.812505937013;2363.491849252419;867.3668222197705;869.8216865542676;927.9072092790667;967.5162418790604;983.9704808855746;18737.829352621105;21435.035423405672;21644.891775541022;22340.329790106323;22582.435439113826;965.8502932045544;971.3249291742312;1011.0900189829066;1016.913562347194;1067.2315932528568;8037.638306276232;9785.804283914282;10624.628138015132;10726.302790318605;11571.01646360052;554848;557102;557677;561144.194279026;566041;155228;157042.4295757028;157931.42068579397;158426;160957.19521402317;1125.5497800879714;1165.953361865516;1253.7993920972783;1258.0250306012913;1260.4454040222456;22610.434739131655;23857.164999224944;24173.758262417152;24287.75712242855;24438.63342049835;68414;68473.63052738817;68575;68898.31101688919;69094.3090569088;74468.88296675446;101674;101899;101961;102003;8381.916180838358;10321.48392580353;10361.948190259302;10392.116670083127;10398.480075996018;17880.943691475648;18255.361062362754;18282.451526454228;18283.908580457013;18375.17311720949;13971.790423143864;14760.33578488949;15083.095014299177;15113.924430377778;15136;13412.865871341162;13543.32283385807;13594.592162235149;13690.315484225948;13810.447582097006;15182.772258415911;15252.923735381251;15591.142487163315;15625.14061726616;15656.53030409044;10209.438480883622;10246.077852993267;10328.380297182193;10332.690019299433;10419.843702344318;11950.462229199533;14311.57065288043;14514.782278265624;14800.889933254834;14944.252787360805 10:21:30 INFO - PID 1902 | |3;mootools.html;1403.9087459315144;1455.3596417576273;1467.449706360115;1469.478335191007;1485.4206859324859;11767.411629418528;11809.173357864931;12299.569515066985;12313.815292770605;12480.81278780819;12046.57836975706;12110.69723256919;12253.938730306358;12282.938585307083;12312.876871231285;2508.573542497775;2540.9364765880814;2566.1275166443406;2571.5242680104147;2574.4979728952862;2493.7256901740884;2524.3815265260037;2525.7726804587674;2531.4430825218424;2532.962005569918;2468.395243165427;2492.239866840613;2504.686914135733;2508.4732206236713;2516.9119080832174;2443.584590619599;2464.7658472445087;2475.900963961439;2476.4799392127675;2480.6155045967903;2446.2049833804085;2459.2499287717237;2496.3509487533192;2547.3631592101974;2555.923322300334;9708.563114659857;11688.123390745643;11778.941105294418;11877.762444751143;11897;5660.858478538028;6455.193100862392;6776.288489708559;6786.083878676365;6788.558743681645;4133.627973482401;4279.807408666617;4290.935635965463;4336.978315108404;4379.8467053653285;1266.9619911402672;1277.3421687830776;1286.5111257722099;1307.1960744142284;1311.4950743963643;12554.937225313906;15449.227538623023;16725.163741812983;16875;16885;4692.709504886184;5581.832545023656;5762.94237057624;5811.476967072984;5829.708514574254;11589.710257243636;12428.813567796491;12433.75132497345;12436.067294952914;12475.251484910934;5527.695976721319;6446.355364463516;6563.638999855054;6570.178727659043;6575.112359831457;5097.286379906816;6122.173506576644;6139.447449729546;6170.598911070766;6175.3824617538785;4380.123975204908;4456.398386217884;4459.598636122764;4499.482559505634;4515.074409745994;1286.2089814763854;1299.3698056442618;1310.7640624687647;1322.4776213395655;1324.3643051335414;801.8436404900988;817.2195553246635;825.7902173316121;852.2202185000779;873.3406278260001;6375.394337537927;6444.742210311629;6641.86716265672;6677.565958212701;6888.035675005503;585.689584520205;603.8188543436951;607.5443417436923;614.3273115938041;627.7520379450183;4086.9386959195635;4144.70987030905;4279.2083464559155;4641.675082744243;4645.512221216723;13125.934370328087;15048.32282547288;15177.392904283704;15235.695286094216;15387.307571159325;14680.926595366955;15149.545513634608;15332.15673137988;15340.309686064154;15379.384824607114;8497.2777313929;8625.482471051757;8657.567121643766;8713.085126061798;8715.346349023848;6464.709088091141;7231.819204519929;7375.778726638209;7653.808654783452;7733.304002639789;13397;13687.931560342135;13878.444862205399;14079.788803167756;14155.221462819643;13618;14106.647333816327;14260.286985650884;14624.926875365554;14649.414023438943;10598.576056957943;10635.521401537104;10946.671599852018;10956.397398143179;10970.451477426257;9743.951280243553;11092.944535277273;11209.49557269941;11220.943895280472;11361.977422032056;9210.769730756516;9266.721998340061;9416.246700264099;9422.717318480456;9512.714618561455;8902.154295341932;9039.774005649911;9051.637934482811;9126.954365228132;9208.861867072133;8201.58992050407;8320.500769953822;8353.24820766134;8357.373197010127;8367.790805229917;6012.549058820518;6016.819495415145;6443.613383197023;6451.645159516272;6514.315996820265;6166.660833654192;6197.411245931629;6361.745530178741;6535.954247320406;6642.634655094016 10:21:30 INFO - PID 1902 | |4;prototype.html;370.91692258604877;388.62497689729406;393.9034936440572;398.2135282816438;401.9135885784556;6199.44205021548;6441.581297215677;6467.805965821021;6483.027545868122;6511.609303441797;5343.919841202375;5600.327960644729;6228.813135605939;6234.84412889678;6300.5589608727405;4003.9389561766156;4028.899277518071;4506.684532082755;4649.163150632885;4701.224297990828;4646.279826626878;4657.790399432017;4679.88300292492;4683.180443422404;4702.072150418329;4175.478065241845;4202.0040480771695;4596.379488769024;4598.264277715566;4649.628029757611;3817.980910095446;4628.884277893046;4646.094011667727;4651.581357677808;4669.159551280768;3474.9652503474895;4012.197560487914;4016.2770701273757;4024.5371782245124;4043.4945631796027;6292.496600272013;6300;6309.432151106423;6315.96842015787;6320.767450347184;5534.861628459281;5535.169724541311;5558.970975593919;5564.415736347706;5585.636933599303;6857.862842743167;6860.759873404457;6870.519063665545;6883.8967415488805;6892.620905850176;4028.3151864182864;4566.383538222331;4566.429196350457;4583.197940360424;4593.563611456906;6919.169699636025;6940;7195.748148814819;7334.889976650355;7351.2648735126595;3806.276807406584;4372.191144638252;4446.533114022981;4628.560286772751;4638.397008388888;4099.524171298331;4196.580341965779;4716.976415117902;4723.95276047242;4750.691205071659;5381.892362152813;5479.178123281539;5511.173324001352;5513.889722205534;5528;6327.620342779448;6845.349691779273;6856.862862742717;6877.4841886858685;6888.552244104118;1617.4015614222578;1619.190404797601;1622.886397952132;1624.9187540622922;1629.2342598978462;1030.2633616963758;1052.6736711619421;1067.8573925899368;1068.4497483795851;1070.7323169207698;6389.904151437732;6416.973284274587;6452.161219041494;6487.935120648733;6534.183227096613;7072.328128827753;7106.680199391039;7119.394851437686;7133.82165445868;7150.713971441084;6378.002338900709;6409.647469389135;6420.357964203542;6476.4495017924;6512.479001679854;3085.6605773364913;3139.434901717713;3714.7213958953184;3755.235039531416;3774.584795672474;6789;6892.345227203407;7129.2870712929325;7360;7400.407967362598;6140.815775526741;7255.637218139177;7307.3423391894985;7364.22675619063;7375.275485879596;6681.966590167018;6847.93152068473;7071.221656178162;7374.018030263597;7389;7146.428285737024;7174.42604591621;7186.490836924305;7194.136703595602;7211.927880721336;6151.384861513813;6442.581232220078;7042.964785176041;7057.682404291717;7073.504854660125;6808.795736127924;6871.862562748617;7214.531055481587;7268.200497945328;7317.634118294171;4599.4020777300075;4615.26155815068;4625.676202665781;4631.536846315342;4691.803590084507;5881.0009193748665;7026.402755765817;7071.232191952012;7103.289671032855;7258.963705181441;6897.999580121708;6962.965185174042;7008.948657701386;7023.262557431601;7026.367626913602;5266.025785229592;5791.565632577658;6824;6898.724051037902;6997.335253150943;6307.990721484724;6691.330866913269;6767.26291595875;6811.058848228231;6852.862942741217;5719.742611582572;6888.655567221719;6904.723811047502;6931.93068069333;6969.7909062728195;5417.864553386039;6757.560758550679;6773.898391524032;6786.558873673393;6858 10:21:30 INFO - PID 1902 | |5;yui.html;747.6859718918054;750.8685979953508;766.4366690482491;846.9407141500094;859.8649782287381;12008.039356851452;14222.004459687825;15271.541853744378;17179.226934787934;17397.39109131179;16014.83985160148;17494.65010699785;17734.113294335264;17773.82226177738;17836;978.1587834462374;993.6075250276136;994.9154321882648;1028.9948550257275;1043.4782608695652;1016.3241444439476;1036.9429681367521;1041.198277326462;1042.1298215989657;1042.7758032023114;878.1613559051123;957.9425234485918;1059.3802625464075;1061.1510791366914;1083.030687534656;1059.2585190366774;1060.4114716332472;1061.1775873697868;1061.5647584490362;1062.314806949521;751.7105914223064;766.2261116272549;767.6353731977322;769.2768797330548;769.8421823526164;14574.708505829823;15581.376744930189;15667;15724.29240684172;15759.842401576067;16765.413210537572;17920.92474451537;18337.908310458497;18392.264309427606;18444.170012349477;12663.93668031654;14722.484713034992;14900.180490073042;14910;14977;3618.9819050904575;3654.5614526256754;3666.651668091527;3667;3667.853285868589;115100;160246;161358;162752.9999999988;162992;13751.381187846571;13834.23911684867;13936.790948135786;13977.3710183042;14057.226852523005;27039.32401689934;27095;27443.725562744516;27495;27549.586756198674;6835.487338449636;7617.27635874591;7733.961330193426;7742.729004484929;7821.335186509211;3449.1721986723505;3880.4373365862357;4076.470058892325;4090.8568200112854;4111.09555897702;4233.089885674594;4320.092780516126;4326;4349.456317960255;4350.782460877007;782.3897360059163;821.5112008355019;910.4043382745916;935.4387367579398;953.1612181280567;567.3702190568548;600.2766666167258;655.947524198063;661.4884987802848;680.3196803196803;10669.986351296615;11084.501197446134;12181.451834667461;12306.569270075504;12332.19840710351;634.7905191286865;637.3499030988365;647.624377860849;672.3411057164006;675.8918573028391;5316.46835316473;5327.360716713942;5448.645838020516;5571.637843540157;5969.850753731104;112863.43568282106;137957.62042379647;143959;148319;152999;93986.0601393991;122358;128458;134769.9784503233;137644;109506;110088.4495577517;112972.74054518863;113521.86478135278;114409;10443.216758743276;11456.885431145914;11511.30932144074;11532.077433805107;11727.589534366598;28525;45104.548954510035;45137.77431112823;45323.77338113288;46364.40889777555;61411;61700.69149654223;72941;80389;85636.71544926707;15753.606159846095;17239.913800430917;17612.559686007953;17700.822991770434;17880.55298617493;18790.248390064244;18848.15190780425;19160.425187244404;19210.903945480743;19267.51831204231;17504.212310446313;17630.38293659767;17926.372576959744;18243.996580188217;18465;17379.652406951536;17668.558286042953;17749.911250443663;17921.910390447963;18058.729119062962;17345.65308693794;17786.110694464965;17794.822051779316;17832.286708532032;17924.014179220267;11248.493817778057;11350.602728904449;11950.820737689115;11959.983401410629;12099.971502422393;13660.93169534146;13868.93065534666;14407.207603581903;14440.566782996528;14480.131192128507 10:21:30 INFO - PID 1902 | __end_tp_report 10:21:30 INFO - PID 1902 | __start_cc_report 10:21:30 INFO - PID 1902 | _x_x_mozilla_cycle_collect,454 10:21:30 INFO - PID 1902 | __end_cc_report 10:21:30 INFO - PID 1902 | __startTimestamp1496769690741__endTimestamp 10:21:30 INFO - PID 1902 | 10:21:30 INFO - PID 1902 | ------- Summary: start ------- 10:21:30 INFO - PID 1902 | Number of tests: 6 10:21:30 INFO - PID 1902 | 10:21:30 INFO - PID 1902 | [#0] dojo.html Cycles:165 Average:6067.07 Median:6290.97 stddev:760.08 (12.1%) stddev-sans-first:725.28 10:21:30 INFO - PID 1902 | Values: 3075.6 3548.2 3574.4 3603.7 3610.2 4665.8 6050.7 6077.8 6192.4 6207.4 6122.8 6131.8 6136.3 6214.6 6218.9 4461.9 5240.0 5405.2 5418.6 5436.9 5472.8 5496.7 5496.8 5498.2 5531.4 5265.9 5334.8 5387.5 5409.3 5414.5 5415.9 5439.3 5445.8 5467.2 5477.1 4796.9 5002.6 5346.3 5355.8 5469.4 6084.4 6146.3 6155.3 6233.5 6236.7 6502.6 6580.4 6585.0 6593.2 6627.2 6778.3 6791.0 6796.8 6799.0 6803.0 6154.9 6234.1 6289.7 6315.4 6315.6 6729.2 6803.2 6849.6 6850.1 6853.5 6295.6 6326.4 6388.8 6547.7 6561.5 6723.2 6739.1 6762.3 6768.1 6781.8 6385.2 6435.5 6611.0 6614.6 6680.7 5504.0 6507.0 6514.6 6538.8 6599.7 4557.3 4990.9 5322.6 5328.5 5340.6 4616.6 4762.1 4762.7 4765.3 4774.2 6082.9 6179.0 6248.0 6250.4 6261.0 5530.6 5952.2 6775.9 6779.9 6783.3 6034.9 6283.4 6291.0 6309.9 6311.5 5664.8 5741.7 5749.2 5764.7 5888.2 6791.6 6795.7 6859.0 6863.6 6877.4 5532.3 6702.1 6723.9 6728.1 6753.2 5127.0 5347.8 6815.1 6825.3 6846.3 6188.0 6464.6 6525.1 6561.8 6569.9 6710.9 6719.1 6724.2 6739.3 6751.7 6302.8 6327.9 6460.6 6539.0 6547.6 6524.6 6546.7 6558.9 6562.3 6592.5 6137.4 6214.6 6230.3 6233.3 6289.0 6580.2 6587.6 6588.1 6598.3 6609.8 6406.6 6408.3 6413.6 6420.3 6428.3 10:21:30 INFO - PID 1902 | 10:21:30 INFO - PID 1902 | [#1] ext.html Cycles:180 Average:44635.05 Median:34909.50 stddev:77130.50 (220.9%) stddev-sans-first:77276.64 10:21:30 INFO - PID 1902 | Values: 795.6 813.6 831.1 1250.7 1306.4 38023.8 39168.4 39275.8 39290.2 39442.2 34881.0 36661.9 39671.0 39951.0 40061.6 7820.8 7997.8 8290.3 8498.0 8506.7 8343.2 8430.0 8442.7 8450.4 8454.1 8411.0 8456.9 8470.0 8475.0 8480.0 8400.5 8439.0 8445.3 8446.5 8452.2 8211.9 8255.3 8294.7 8307.3 8331.6 36210.8 36379.0 36452.3 36544.8 36585.5 3596.6 3625.0 3628.4 3628.6 3633.1 30089.8 30436.1 30566.5 30575.7 30602.2 3045.8 3056.5 3079.4 3083.8 3090.7 237213.8 241388.0 242854.0 295592.0 295872.0 45429.5 45437.5 45466.9 45612.5 45615.5 47131.8 47433.8 49305.0 49870.8 50141.7 14442.8 14484.9 14613.6 14649.8 14687.6 11640.5 11664.7 11844.6 11869.8 11885.8 13913.5 13931.1 13935.7 13962.5 14032.9 6129.0 6165.6 6172.8 6184.6 6193.7 944.7 960.7 962.9 982.0 1007.2 22465.0 24238.8 24363.0 24561.0 24603.3 1080.3 1092.0 1120.9 1145.1 1150.8 11555.7 11769.2 12469.8 14871.3 15784.8 326114.0 327879.0 328435.8 328974.0 329733.0 58172.4 58734.4 58791.7 58838.7 58870.4 1485.9 1494.7 1534.3 1534.4 1540.1 27778.0 28791.6 30192.0 30527.1 30530.5 38031.6 38268.4 38309.6 38342.0 38393.6 284403.0 286752.0 287257.0 288426.0 288911.0 34253.8 34783.0 34938.0 35013.0 35070.0 43123.6 43861.0 43887.6 43960.8 43964.1 24993.8 26290.8 28906.8 29642.7 30381.4 29648.4 29787.6 30110.0 30581.0 30705.4 35113.0 35249.9 36700.3 37078.4 37090.8 29375.4 29440.0 30496.4 30843.8 30869.8 22324.7 23928.6 24150.5 24159.8 24194.4 10:21:30 INFO - PID 1902 | 10:21:30 INFO - PID 1902 | [#2] jquery.html Cycles:180 Average:39769.19 Median:12284.21 stddev:97797.17 (796.1%) stddev-sans-first:98026.90 10:21:30 INFO - PID 1902 | Values: 423.6 425.5 431.7 439.8 448.2 22035.4 22056.0 22816.7 22921.2 24838.6 24212.6 24504.8 24774.8 25037.1 25062.6 6051.2 6197.5 6204.9 6213.2 6216.0 5965.4 6098.2 6137.8 6196.1 6200.2 6145.5 6158.2 6188.3 6197.3 6205.4 6179.5 6210.3 6210.8 6215.8 6222.3 5508.8 5524.9 5530.0 5534.9 5541.8 28623.3 29717.0 29734.9 30044.1 30107.7 2137.6 2168.9 2175.2 2176.1 2176.2 18829.5 19064.3 19371.4 19392.5 19459.5 2148.7 2196.1 2228.1 2235.6 2244.7 195649.1 199468.0 199705.0 211632.0 212298.9 13097.0 13155.0 13211.3 13284.7 13731.9 16807.4 16932.4 17018.5 17031.3 17038.7 9075.1 9108.9 9256.3 9294.3 9328.7 8933.8 8965.2 8978.1 8998.0 9014.7 8266.5 8292.7 8345.5 8421.9 8428.0 2183.7 2186.1 2188.8 2205.8 2363.5 867.4 869.8 927.9 967.5 984.0 18737.8 21435.0 21644.9 22340.3 22582.4 965.9 971.3 1011.1 1016.9 1067.2 8037.6 9785.8 10624.6 10726.3 11571.0 554848.0 557102.0 557677.0 561144.2 566041.0 155228.0 157042.4 157931.4 158426.0 160957.2 1125.5 1166.0 1253.8 1258.0 1260.4 22610.4 23857.2 24173.8 24287.8 24438.6 68414.0 68473.6 68575.0 68898.3 69094.3 74468.9 101674.0 101899.0 101961.0 102003.0 8381.9 10321.5 10361.9 10392.1 10398.5 17880.9 18255.4 18282.5 18283.9 18375.2 13971.8 14760.3 15083.1 15113.9 15136.0 13412.9 13543.3 13594.6 13690.3 13810.4 15182.8 15252.9 15591.1 15625.1 15656.5 10209.4 10246.1 10328.4 10332.7 10419.8 11950.5 14311.6 14514.8 14800.9 14944.3 10:21:30 INFO - PID 1902 | 10:21:30 INFO - PID 1902 | [#3] mootools.html Cycles:180 Average:7247.70 Median:2568.83 stddev:4585.67 (178.5%) stddev-sans-first:4577.51 10:21:30 INFO - PID 1902 | Values: 1403.9 1455.4 1467.4 1469.5 1485.4 11767.4 11809.2 12299.6 12313.8 12480.8 12046.6 12110.7 12253.9 12282.9 12312.9 2508.6 2540.9 2566.1 2571.5 2574.5 2493.7 2524.4 2525.8 2531.4 2533.0 2468.4 2492.2 2504.7 2508.5 2516.9 2443.6 2464.8 2475.9 2476.5 2480.6 2446.2 2459.2 2496.4 2547.4 2555.9 9708.6 11688.1 11778.9 11877.8 11897.0 5660.9 6455.2 6776.3 6786.1 6788.6 4133.6 4279.8 4290.9 4337.0 4379.8 1267.0 1277.3 1286.5 1307.2 1311.5 12554.9 15449.2 16725.2 16875.0 16885.0 4692.7 5581.8 5762.9 5811.5 5829.7 11589.7 12428.8 12433.8 12436.1 12475.3 5527.7 6446.4 6563.6 6570.2 6575.1 5097.3 6122.2 6139.4 6170.6 6175.4 4380.1 4456.4 4459.6 4499.5 4515.1 1286.2 1299.4 1310.8 1322.5 1324.4 801.8 817.2 825.8 852.2 873.3 6375.4 6444.7 6641.9 6677.6 6888.0 585.7 603.8 607.5 614.3 627.8 4086.9 4144.7 4279.2 4641.7 4645.5 13125.9 15048.3 15177.4 15235.7 15387.3 14680.9 15149.5 15332.2 15340.3 15379.4 8497.3 8625.5 8657.6 8713.1 8715.3 6464.7 7231.8 7375.8 7653.8 7733.3 13397.0 13687.9 13878.4 14079.8 14155.2 13618.0 14106.6 14260.3 14624.9 14649.4 10598.6 10635.5 10946.7 10956.4 10970.5 9744.0 11092.9 11209.5 11220.9 11362.0 9210.8 9266.7 9416.2 9422.7 9512.7 8902.2 9039.8 9051.6 9127.0 9208.9 8201.6 8320.5 8353.2 8357.4 8367.8 6012.5 6016.8 6443.6 6451.6 6514.3 6166.7 6197.4 6361.7 6536.0 6642.6 10:21:30 INFO - PID 1902 | 10:21:30 INFO - PID 1902 | [#4] prototype.html Cycles:180 Average:5537.22 Median:6308.71 stddev:1782.73 (28.3%) stddev-sans-first:1745.05 10:21:30 INFO - PID 1902 | Values: 370.9 388.6 393.9 398.2 401.9 6199.4 6441.6 6467.8 6483.0 6511.6 5343.9 5600.3 6228.8 6234.8 6300.6 4003.9 4028.9 4506.7 4649.2 4701.2 4646.3 4657.8 4679.9 4683.2 4702.1 4175.5 4202.0 4596.4 4598.3 4649.6 3818.0 4628.9 4646.1 4651.6 4669.2 3475.0 4012.2 4016.3 4024.5 4043.5 6292.5 6300.0 6309.4 6316.0 6320.8 5534.9 5535.2 5559.0 5564.4 5585.6 6857.9 6860.8 6870.5 6883.9 6892.6 4028.3 4566.4 4566.4 4583.2 4593.6 6919.2 6940.0 7195.7 7334.9 7351.3 3806.3 4372.2 4446.5 4628.6 4638.4 4099.5 4196.6 4717.0 4724.0 4750.7 5381.9 5479.2 5511.2 5513.9 5528.0 6327.6 6845.3 6856.9 6877.5 6888.6 1617.4 1619.2 1622.9 1624.9 1629.2 1030.3 1052.7 1067.9 1068.4 1070.7 6389.9 6417.0 6452.2 6487.9 6534.2 7072.3 7106.7 7119.4 7133.8 7150.7 6378.0 6409.6 6420.4 6476.4 6512.5 3085.7 3139.4 3714.7 3755.2 3774.6 6789.0 6892.3 7129.3 7360.0 7400.4 6140.8 7255.6 7307.3 7364.2 7375.3 6682.0 6847.9 7071.2 7374.0 7389.0 7146.4 7174.4 7186.5 7194.1 7211.9 6151.4 6442.6 7043.0 7057.7 7073.5 6808.8 6871.9 7214.5 7268.2 7317.6 4599.4 4615.3 4625.7 4631.5 4691.8 5881.0 7026.4 7071.2 7103.3 7259.0 6898.0 6963.0 7008.9 7023.3 7026.4 5266.0 5791.6 6824.0 6898.7 6997.3 6308.0 6691.3 6767.3 6811.1 6852.9 5719.7 6888.7 6904.7 6931.9 6969.8 5417.9 6757.6 6773.9 6786.6 6858.0 10:21:30 INFO - PID 1902 | 10:21:30 INFO - PID 1902 | [#5] yui.html Cycles:180 Average:25970.56 Median:17813.55 stddev:40500.77 (227.4%) stddev-sans-first:40570.10 10:21:30 INFO - PID 1902 | Values: 747.7 750.9 766.4 846.9 859.9 12008.0 14222.0 15271.5 17179.2 17397.4 16014.8 17494.7 17734.1 17773.8 17836.0 978.2 993.6 994.9 1029.0 1043.5 1016.3 1036.9 1041.2 1042.1 1042.8 878.2 957.9 1059.4 1061.2 1083.0 1059.3 1060.4 1061.2 1061.6 1062.3 751.7 766.2 767.6 769.3 769.8 14574.7 15581.4 15667.0 15724.3 15759.8 16765.4 17920.9 18337.9 18392.3 18444.2 12663.9 14722.5 14900.2 14910.0 14977.0 3619.0 3654.6 3666.7 3667.0 3667.9 115100.0 160246.0 161358.0 162753.0 162992.0 13751.4 13834.2 13936.8 13977.4 14057.2 27039.3 27095.0 27443.7 27495.0 27549.6 6835.5 7617.3 7734.0 7742.7 7821.3 3449.2 3880.4 4076.5 4090.9 4111.1 4233.1 4320.1 4326.0 4349.5 4350.8 782.4 821.5 910.4 935.4 953.2 567.4 600.3 655.9 661.5 680.3 10670.0 11084.5 12181.5 12306.6 12332.2 634.8 637.3 647.6 672.3 675.9 5316.5 5327.4 5448.6 5571.6 5969.9 112863.4 137957.6 143959.0 148319.0 152999.0 93986.1 122358.0 128458.0 134770.0 137644.0 109506.0 110088.4 112972.7 113521.9 114409.0 10443.2 11456.9 11511.3 11532.1 11727.6 28525.0 45104.5 45137.8 45323.8 46364.4 61411.0 61700.7 72941.0 80389.0 85636.7 15753.6 17239.9 17612.6 17700.8 17880.6 18790.2 18848.2 19160.4 19210.9 19267.5 17504.2 17630.4 17926.4 18244.0 18465.0 17379.7 17668.6 17749.9 17921.9 18058.7 17345.7 17786.1 17794.8 17832.3 17924.0 11248.5 11350.6 11950.8 11960.0 12100.0 13660.9 13868.9 14407.2 14440.6 14480.1 10:21:30 INFO - PID 1902 | -------- Summary: end -------- 10:21:30 INFO - PID 1902 | 10:21:31 INFO - TEST-INFO | 1902: exit 0 10:21:31 INFO - TEST-OK | dromaeo_css | took 1142661ms 10:21:31 INFO - TEST-START | kraken 10:21:31 INFO - operating with platform_type : mac_ 10:21:31 INFO - Initialising browser for kraken test... 10:21:31 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49354/getInfo.html -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpM7ZUv1/profile 10:21:31 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49354/getInfo.html -foreground) 10:22:05 INFO - TEST-INFO | 1910: killed by SIGTERM 10:22:05 INFO - Browser initialized. 10:22:05 INFO - Running cycle 1/1 for kraken test... 10:22:05 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpM7ZUv1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:22:07 INFO - PID 1914 | 2017-06-06 10:22:07.334 plugin-container[1915:11980] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 10:22:07 INFO - PID 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:07 INFO - PID 1914 | 2017-06-06 10:22:07.335 plugin-container[1915:11980] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 10:22:07 INFO - PID 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:07 INFO - PID 1914 | 2017-06-06 10:22:07.335 plugin-container[1915:11980] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:22:08 INFO - PID 1914 | 2017-06-06 10:22:08.102 plugin-container[1916:12031] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 10:22:08 INFO - PID 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:08 INFO - PID 1914 | 2017-06-06 10:22:08.103 plugin-container[1916:12031] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 10:22:08 INFO - PID 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:22:08 INFO - PID 1914 | 2017-06-06 10:22:08.103 plugin-container[1916:12031] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:22:17 INFO - PID 1914 | RSS: Main: 260050944 10:22:17 INFO - PID 1914 | 10:22:20 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-beat-detection) 10:22:21 INFO - PID 1914 | RSS: Main: 270422016 10:22:21 INFO - PID 1914 | 10:22:23 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-dft) 10:22:24 INFO - PID 1914 | RSS: Main: 279646208 10:22:24 INFO - PID 1914 | 10:22:27 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-fft) 10:22:28 INFO - PID 1914 | RSS: Main: 281690112 10:22:28 INFO - PID 1914 | 10:22:30 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49354/tests/kraken/driver.html?testName=audio-oscillator) 10:22:30 INFO - PID 1914 | RSS: Main: 283697152 10:22:30 INFO - PID 1914 | 10:22:32 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49354/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:22:33 INFO - PID 1914 | RSS: Main: 284155904 10:22:33 INFO - PID 1914 | 10:22:36 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49354/tests/kraken/driver.html?testName=imaging-darkroom) 10:22:37 INFO - PID 1914 | RSS: Main: 288632832 10:22:37 INFO - PID 1914 | 10:22:40 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49354/tests/kraken/driver.html?testName=imaging-desaturate) 10:22:41 INFO - PID 1914 | RSS: Main: 291508224 10:22:41 INFO - PID 1914 | 10:22:44 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49354/tests/kraken/driver.html?testName=json-parse-financial) 10:22:44 INFO - PID 1914 | RSS: Main: 293769216 10:22:44 INFO - PID 1914 | 10:22:46 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49354/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:22:47 INFO - PID 1914 | RSS: Main: 294031360 10:22:47 INFO - PID 1914 | 10:22:48 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:22:49 INFO - PID 1914 | RSS: Main: 294129664 10:22:49 INFO - PID 1914 | 10:22:51 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:22:52 INFO - PID 1914 | RSS: Main: 293654528 10:22:52 INFO - PID 1914 | 10:22:55 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:22:56 INFO - PID 1914 | RSS: Main: 293830656 10:22:56 INFO - PID 1914 | 10:22:59 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:23:00 INFO - PID 1914 | RSS: Main: 291909632 10:23:00 INFO - PID 1914 | 10:23:01 INFO - PID 1914 | Cycle 1(1): loaded http://localhost:49354/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49354/tests/kraken/driver.html?testName=ai-astar) 10:23:02 INFO - PID 1914 | RSS: Main: 293879808 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | __start_tp_report 10:23:02 INFO - PID 1914 | _x_x_mozilla_page_load 10:23:02 INFO - PID 1914 | _x_x_mozilla_page_load_details 10:23:02 INFO - PID 1914 | |i|pagename|runs| 10:23:02 INFO - PID 1914 | |0;ai-astar;108;117;114;114;112;114;116;116;114;113 10:23:02 INFO - PID 1914 | |1;audio-beat-detection;137;116;111;146;109;108;109;121;160;146 10:23:02 INFO - PID 1914 | |2;audio-dft;207;200;200;218;210;225;236;285;212;199 10:23:02 INFO - PID 1914 | |3;audio-fft;75;60;61;82;61;62;58;77;72;60 10:23:02 INFO - PID 1914 | |4;audio-oscillator;81;74;86;54;90;94;111;109;75;72 10:23:02 INFO - PID 1914 | |5;imaging-gaussian-blur;98;115;90;90;89;90;90;94;95;89 10:23:02 INFO - PID 1914 | |6;imaging-darkroom;96;98;122;104;157;107;155;96;123;96 10:23:02 INFO - PID 1914 | |7;imaging-desaturate;79;144;140;93;91;90;92;136;118;92 10:23:02 INFO - PID 1914 | |8;json-parse-financial;49;51;51;50;50;52;51;50;51;51 10:23:02 INFO - PID 1914 | |9;json-stringify-tinderbox;56;57;64;60;74;74;72;57;49;82 10:23:02 INFO - PID 1914 | |10;stanford-crypto-aes;89;89;93;104;113;89;91;93;131;83 10:23:02 INFO - PID 1914 | |11;stanford-crypto-ccm;138;135;134;139;167;139;146;139;138;137 10:23:02 INFO - PID 1914 | |12;stanford-crypto-pbkdf2;186;231;186;239;248;211;240;246;219;225 10:23:02 INFO - PID 1914 | |13;stanford-crypto-sha256-iterative;71;66;67;66;68;72;66;62;81;95 10:23:02 INFO - PID 1914 | __end_tp_report 10:23:02 INFO - PID 1914 | __start_cc_report 10:23:02 INFO - PID 1914 | _x_x_mozilla_cycle_collect,1223 10:23:02 INFO - PID 1914 | __end_cc_report 10:23:02 INFO - PID 1914 | __startTimestamp1496769782234__endTimestamp 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | ------- Summary: start ------- 10:23:02 INFO - PID 1914 | Number of tests: 14 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:2.53 (2.2%) stddev-sans-first:1.59 10:23:02 INFO - PID 1914 | Values: 108.0 117.0 114.0 114.0 112.0 114.0 116.0 116.0 114.0 113.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:129.00 stddev:19.23 (14.9%) stddev-sans-first:20.00 10:23:02 INFO - PID 1914 | Values: 137.0 116.0 111.0 146.0 109.0 108.0 109.0 121.0 160.0 146.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#2] audio-dft Cycles:10 Average:219.20 Median:215.00 stddev:25.99 (12.1%) stddev-sans-first:27.19 10:23:02 INFO - PID 1914 | Values: 207.0 200.0 200.0 218.0 210.0 225.0 236.0 285.0 212.0 199.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#3] audio-fft Cycles:10 Average:66.80 Median:67.00 stddev:8.75 (13.1%) stddev-sans-first:8.77 10:23:02 INFO - PID 1914 | Values: 75.0 60.0 61.0 82.0 61.0 62.0 58.0 77.0 72.0 60.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#4] audio-oscillator Cycles:10 Average:84.60 Median:78.00 stddev:17.40 (22.3%) stddev-sans-first:18.41 10:23:02 INFO - PID 1914 | Values: 81.0 74.0 86.0 54.0 90.0 94.0 111.0 109.0 75.0 72.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#5] imaging-gaussian-blur Cycles:10 Average:94.00 Median:90.00 stddev:7.97 (8.9%) stddev-sans-first:8.32 10:23:02 INFO - PID 1914 | Values: 98.0 115.0 90.0 90.0 89.0 90.0 90.0 94.0 95.0 89.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#6] imaging-darkroom Cycles:10 Average:115.40 Median:126.50 stddev:23.65 (18.7%) stddev-sans-first:24.02 10:23:02 INFO - PID 1914 | Values: 96.0 98.0 122.0 104.0 157.0 107.0 155.0 96.0 123.0 96.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#7] imaging-desaturate Cycles:10 Average:107.50 Median:90.50 stddev:24.48 (27.0%) stddev-sans-first:23.69 10:23:02 INFO - PID 1914 | Values: 79.0 144.0 140.0 93.0 91.0 90.0 92.0 136.0 118.0 92.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:0.84 (1.7%) stddev-sans-first:0.67 10:23:02 INFO - PID 1914 | Values: 49.0 51.0 51.0 50.0 50.0 52.0 51.0 50.0 51.0 51.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:68.00 stddev:10.48 (15.4%) stddev-sans-first:10.65 10:23:02 INFO - PID 1914 | Values: 56.0 57.0 64.0 60.0 74.0 74.0 72.0 57.0 49.0 82.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#10] stanford-crypto-aes Cycles:10 Average:97.50 Median:89.00 stddev:14.58 (16.4%) stddev-sans-first:15.14 10:23:02 INFO - PID 1914 | Values: 89.0 89.0 93.0 104.0 113.0 89.0 91.0 93.0 131.0 83.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:139.00 stddev:9.61 (6.9%) stddev-sans-first:10.13 10:23:02 INFO - PID 1914 | Values: 138.0 135.0 134.0 139.0 167.0 139.0 146.0 139.0 138.0 137.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:223.10 Median:235.00 stddev:22.77 (9.7%) stddev-sans-first:19.80 10:23:02 INFO - PID 1914 | Values: 186.0 231.0 186.0 239.0 248.0 211.0 240.0 246.0 219.0 225.0 10:23:02 INFO - PID 1914 | 10:23:02 INFO - PID 1914 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:69.50 stddev:9.75 (14.0%) stddev-sans-first:10.35 10:23:02 INFO - PID 1914 | Values: 71.0 66.0 67.0 66.0 68.0 72.0 66.0 62.0 81.0 95.0 10:23:02 INFO - PID 1914 | -------- Summary: end -------- 10:23:02 INFO - PID 1914 | 10:23:03 INFO - TEST-INFO | 1914: exit 0 10:23:03 INFO - TEST-OK | kraken | took 91668ms 10:23:03 INFO - SUITE-END | took 1234s 10:23:03 INFO - Completed test suite (00:20:34) 10:23:03 INFO - javascript benchmark 10:23:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8119.0801197700275, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3075.6463006754225, 3548.1839176989274, 3574.374484465218, 3603.711703063755, 3610.169660977975, 4665.83669571565, 6050.667213303266, 6077.7568897244055, 6192.41172088652, 6207.441330280274, 6122.755089796403, 6131.804871721961, 6136.335271946679, 6214.5649804513705, 6218.937810621893, 4461.888452788674, 5239.973800130994, 5405.243265942771, 5418.593605479585, 5436.91844622331, 5472.781088756465, 5496.697681627509, 5496.780128794843, 5498.1752737089355, 5531.3915469298545, 5265.921011184835, 5334.839954801362, 5387.4612538746205, 5409.26974858393, 5414.539764120054, 5415.891682166334, 5439.265699130607, 5445.83662490126, 5467.234587157801, 5477.068898287301, 4796.904061918742, 5002.649814512985, 5346.331708536433, 5355.847305180918, 5469.425710300441, 6084.391560843925, 6146.29317628474, 6155.322914479404, 6233.470155036827, 6236.688165591702, 6502.577332473374, 6580.4406625436895, 6585.0, 6593.241777195636, 6627.204735431731, 6778.254392016875, 6791.015302781069, 6796.830079248009, 6798.966005169992, 6802.97955306703, 6154.876902461926, 6234.096056071935, 6289.7169627366875, 6315.400036996524, 6315.621062736204, 6729.22613899398, 6803.217629972518, 6849.554778939353, 6850.143732033496, 6853.485988550879, 6295.622262664255, 6326.43062124411, 6388.776392826228, 6547.672616369163, 6561.507886908501, 6723.159605049369, 6739.090222819955, 6762.323767623198, 6768.120144381199, 6781.830454238585, 6385.2337719473035, 6435.485161187094, 6610.975298828689, 6614.603123812587, 6680.699368528428, 5503.972480137654, 6506.95888657822, 6514.57655252407, 6538.8365290868105, 6599.70301336441, 4557.293619488984, 4990.8752281193265, 5322.600804939645, 5328.49379308965, 5340.572754179656, 4616.630669546428, 4762.142814293391, 4762.738049407247, 4765.261384485341, 4774.164521208774, 6082.878342433127, 6179.0, 6248.0, 6250.4374606285655, 6261.029540421242, 5530.557555395559, 5952.166696662471, 6775.93224067753, 6779.93220067793, 6783.287754785775, 6034.879302413927, 6283.434490895841, 6290.968545157245, 6309.905351419732, 6311.526635502356, 5664.801731939445, 5741.741621627037, 5749.223854779635, 5764.7117644117625, 5888.234529511136, 6791.626460544718, 6795.674843405489, 6858.965705171542, 6863.588184708933, 6877.449804015767, 5532.280803495681, 6702.061711360511, 6723.899141512979, 6728.091707619409, 6753.15585551806, 5127.0, 5347.786088556414, 6815.114035175594, 6825.283345248876, 6846.281140480178, 6188.0, 6464.5798023128345, 6525.119108920425, 6561.835954100995, 6569.9343006569325, 6710.932890671226, 6719.059331693469, 6724.226713927766, 6739.292374300824, 6751.729930802713, 6302.810915672537, 6327.905081423874, 6460.612363258031, 6539.0191471277785, 6547.639879806466, 6524.641144737085, 6546.705398257185, 6558.934410655832, 6562.343765623399, 6592.538522303393, 6137.4382736082025, 6214.5960512566535, 6230.252369715663, 6233.283172435228, 6288.968555157195, 6580.21037475506, 6587.604743715392, 6588.110605068255, 6598.274189839018, 6609.834754130993, 6406.551541392244, 6408.327125651926, 6413.615183089029, 6420.26166990802, 6428.325025872403], "value": 6017.085789524901, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.5902710104298, 813.6460639621764, 831.0775039705925, 1250.712336162682, 1306.3598836570072, 38023.80988095063, 39168.412473812845, 39275.80362098189, 39290.21419571611, 39442.211155776866, 34881.0, 36661.90014299575, 39671.0, 39951.0, 40061.59938400615, 7820.843583128334, 7997.840043199132, 8290.33677305817, 8498.022727386367, 8506.74479765608, 8343.16568343167, 8430.0, 8442.704505342313, 8450.366222533305, 8454.070052294242, 8410.957945210266, 8456.915430845675, 8469.95765021174, 8475.0, 8479.957600212021, 8400.537970411626, 8438.987321521396, 8445.28215101714, 8446.450980686235, 8452.154784521561, 8211.876821847676, 8255.339572834158, 8294.709685160991, 8307.335413166933, 8331.625076871554, 36210.8189459051, 36379.0, 36452.27095458103, 36544.81727591345, 36585.45121823175, 3596.5504311961004, 3625.021244264034, 3628.38317486028, 3628.6189950055127, 3633.091727068233, 30089.849550752326, 30436.086917392513, 30566.54150187749, 30575.694243057507, 30602.234944126354, 3045.7715671324518, 3056.5262384330463, 3079.414911166871, 3083.8149711017186, 3090.7218350348353, 237213.81393093098, 241388.0, 242854.0, 295592.0, 295872.0, 45429.54570454319, 45437.54562454333, 45466.863328416395, 45612.543874561496, 45615.54384456113, 47131.764341178074, 47433.762831186315, 49305.0138997218, 49870.75064624654, 50141.74929125404, 14442.783358249635, 14484.913631477471, 14613.634659133606, 14649.780253296209, 14687.55937321882, 11640.476178571984, 11664.708382290512, 11844.585439509576, 11869.762604747857, 11885.762284754257, 13913.513027044208, 13931.094478859044, 13935.721285574231, 13962.51131210423, 14032.859671403156, 6129.049997250433, 6165.568410211242, 6172.753089876444, 6184.567080304336, 6193.659348735863, 944.6552008516842, 960.711786464058, 962.8988956159502, 981.9803603927882, 1007.1841808135288, 22465.0, 24238.75761242365, 24363.0, 24561.037826678406, 24603.261902142964, 1080.2600218850114, 1091.9890801091888, 1120.9215354925077, 1145.0724912820642, 1150.7583407484522, 11555.653330400102, 11769.234999724991, 12469.75060498803, 14871.33079011447, 15784.763228551581, 326114.0, 327879.0, 328435.7891052695, 328974.0, 329733.0, 58172.418275817545, 58734.4126558729, 58791.70604146952, 58838.705806470694, 58870.41129588649, 1485.9405623775144, 1494.7309484292932, 1534.2712211699577, 1534.3555706603254, 1540.075954427357, 27778.0, 28791.568126478538, 30192.0, 30527.08418747441, 30530.542041868946, 38031.619683802805, 38268.425973610974, 38309.6169038306, 38342.04144896288, 38393.61606383901, 284403.0, 286752.0, 287257.0, 288426.0, 288911.0, 34253.82873085718, 34782.9565113047, 34938.0, 35013.0, 35070.0, 43123.56876431195, 43861.0, 43887.56112438963, 43960.780196098814, 43964.120717586105, 24993.75006249914, 26290.816913238566, 28906.83198760059, 29642.703572964856, 30381.392372152874, 29648.40703185881, 29787.55318670265, 30110.0, 30581.0, 30705.385892282477, 35113.0, 35249.94250172499, 36700.26599468049, 37078.44382334321, 37090.814545927096, 29375.412491750474, 29440.0, 30496.390072197988, 30843.845780770953, 30869.817180387738, 22324.66513002274, 23928.641070384307, 24150.51698966046, 24159.75840241575, 24194.395140121636], "value": 17621.51009138408, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.61165968875906, 425.45754163441626, 431.7495852405605, 439.79769306119164, 448.2133855084327, 22035.449113772123, 22056.0, 22816.65775013376, 22921.19775807847, 24838.627420588706, 24212.636810447857, 24504.75495245047, 24774.75225247747, 25037.123700670436, 25062.624060639104, 6051.2133422655, 6197.535184861131, 6204.9069263960855, 6213.161223234873, 6216.036514340262, 5965.373635768247, 6098.237720284965, 6137.754489820424, 6196.101565273033, 6200.193974783272, 6145.539084568675, 6158.168647232634, 6188.288346840103, 6197.2563292404975, 6205.441510264075, 6179.474744646711, 6210.254769427697, 6210.813675589739, 6215.782447614357, 6222.253329600454, 5508.779648814043, 5524.917126243109, 5530.004599172147, 5534.916976245359, 5541.778328866841, 28623.27897373349, 29717.0, 29734.85132574345, 30044.098677039725, 30107.69892301071, 2137.636601777686, 2168.8915554222226, 2175.2495389090736, 2176.1295481807247, 2176.249194028058, 18829.529261768566, 19064.33274835374, 19371.4188574343, 19392.545559083126, 19459.513512162168, 2148.677698345245, 2196.0556960506974, 2228.097620463715, 2235.6422972324513, 2244.6745221942892, 195649.06526402116, 199468.0053199464, 199705.0, 211632.0, 212298.93850530803, 13097.0, 13155.0, 13211.273379964194, 13284.73430531384, 13731.931340343433, 16807.41174058902, 16932.40736574214, 17018.48944531666, 17031.31874724997, 17038.659226815642, 9075.13786190311, 9108.908910910939, 9256.259499240045, 9294.349395542247, 9328.720138395858, 8933.776655583662, 8965.237954773916, 8978.10218978097, 8998.0, 9014.684486042957, 8266.462679925786, 8292.709755158541, 8345.499270043696, 8421.915780842113, 8427.95786021066, 2183.6942828004094, 2186.0599942024764, 2188.835837312208, 2205.812505937013, 2363.491849252419, 867.3668222197705, 869.8216865542676, 927.9072092790667, 967.5162418790604, 983.9704808855746, 18737.829352621105, 21435.035423405672, 21644.891775541022, 22340.329790106323, 22582.435439113826, 965.8502932045544, 971.3249291742312, 1011.0900189829066, 1016.913562347194, 1067.2315932528568, 8037.638306276232, 9785.804283914282, 10624.628138015132, 10726.302790318605, 11571.01646360052, 554848.0, 557102.0, 557677.0, 561144.194279026, 566041.0, 155228.0, 157042.4295757028, 157931.42068579397, 158426.0, 160957.19521402317, 1125.5497800879714, 1165.953361865516, 1253.7993920972783, 1258.0250306012913, 1260.4454040222456, 22610.434739131655, 23857.164999224944, 24173.758262417152, 24287.75712242855, 24438.63342049835, 68414.0, 68473.63052738817, 68575.0, 68898.31101688919, 69094.3090569088, 74468.88296675446, 101674.0, 101899.0, 101961.0, 102003.0, 8381.916180838358, 10321.48392580353, 10361.948190259302, 10392.116670083127, 10398.480075996018, 17880.943691475648, 18255.361062362754, 18282.451526454228, 18283.908580457013, 18375.17311720949, 13971.790423143864, 14760.33578488949, 15083.095014299177, 15113.924430377778, 15136.0, 13412.865871341162, 13543.32283385807, 13594.592162235149, 13690.315484225948, 13810.447582097006, 15182.772258415911, 15252.923735381251, 15591.142487163315, 15625.14061726616, 15656.53030409044, 10209.438480883622, 10246.077852993267, 10328.380297182193, 10332.690019299433, 10419.843702344318, 11950.462229199533, 14311.57065288043, 14514.782278265624, 14800.889933254834, 14944.252787360805], "value": 11386.852752454333, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.9087459315144, 1455.3596417576273, 1467.449706360115, 1469.478335191007, 1485.4206859324859, 11767.411629418528, 11809.173357864931, 12299.569515066985, 12313.815292770605, 12480.81278780819, 12046.57836975706, 12110.69723256919, 12253.938730306358, 12282.938585307083, 12312.876871231285, 2508.573542497775, 2540.9364765880814, 2566.1275166443406, 2571.5242680104147, 2574.4979728952862, 2493.7256901740884, 2524.3815265260037, 2525.7726804587674, 2531.4430825218424, 2532.962005569918, 2468.395243165427, 2492.239866840613, 2504.686914135733, 2508.4732206236713, 2516.9119080832174, 2443.584590619599, 2464.7658472445087, 2475.900963961439, 2476.4799392127675, 2480.6155045967903, 2446.2049833804085, 2459.2499287717237, 2496.3509487533192, 2547.3631592101974, 2555.923322300334, 9708.563114659857, 11688.123390745643, 11778.941105294418, 11877.762444751143, 11897.0, 5660.858478538028, 6455.193100862392, 6776.288489708559, 6786.083878676365, 6788.558743681645, 4133.627973482401, 4279.807408666617, 4290.935635965463, 4336.978315108404, 4379.8467053653285, 1266.9619911402672, 1277.3421687830776, 1286.5111257722099, 1307.1960744142284, 1311.4950743963643, 12554.937225313906, 15449.227538623023, 16725.163741812983, 16875.0, 16885.0, 4692.709504886184, 5581.832545023656, 5762.94237057624, 5811.476967072984, 5829.708514574254, 11589.710257243636, 12428.813567796491, 12433.75132497345, 12436.067294952914, 12475.251484910934, 5527.695976721319, 6446.355364463516, 6563.638999855054, 6570.178727659043, 6575.112359831457, 5097.286379906816, 6122.173506576644, 6139.447449729546, 6170.598911070766, 6175.3824617538785, 4380.123975204908, 4456.398386217884, 4459.598636122764, 4499.482559505634, 4515.074409745994, 1286.2089814763854, 1299.3698056442618, 1310.7640624687647, 1322.4776213395655, 1324.3643051335414, 801.8436404900988, 817.2195553246635, 825.7902173316121, 852.2202185000779, 873.3406278260001, 6375.394337537927, 6444.742210311629, 6641.86716265672, 6677.565958212701, 6888.035675005503, 585.689584520205, 603.8188543436951, 607.5443417436923, 614.3273115938041, 627.7520379450183, 4086.9386959195635, 4144.70987030905, 4279.2083464559155, 4641.675082744243, 4645.512221216723, 13125.934370328087, 15048.32282547288, 15177.392904283704, 15235.695286094216, 15387.307571159325, 14680.926595366955, 15149.545513634608, 15332.15673137988, 15340.309686064154, 15379.384824607114, 8497.2777313929, 8625.482471051757, 8657.567121643766, 8713.085126061798, 8715.346349023848, 6464.709088091141, 7231.819204519929, 7375.778726638209, 7653.808654783452, 7733.304002639789, 13397.0, 13687.931560342135, 13878.444862205399, 14079.788803167756, 14155.221462819643, 13618.0, 14106.647333816327, 14260.286985650884, 14624.926875365554, 14649.414023438943, 10598.576056957943, 10635.521401537104, 10946.671599852018, 10956.397398143179, 10970.451477426257, 9743.951280243553, 11092.944535277273, 11209.49557269941, 11220.943895280472, 11361.977422032056, 9210.769730756516, 9266.721998340061, 9416.246700264099, 9422.717318480456, 9512.714618561455, 8902.154295341932, 9039.774005649911, 9051.637934482811, 9126.954365228132, 9208.861867072133, 8201.58992050407, 8320.500769953822, 8353.24820766134, 8357.373197010127, 8367.790805229917, 6012.549058820518, 6016.819495415145, 6443.613383197023, 6451.645159516272, 6514.315996820265, 6166.660833654192, 6197.411245931629, 6361.745530178741, 6535.954247320406, 6642.634655094016], "value": 5428.600883486479, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.91692258604877, 388.62497689729406, 393.9034936440572, 398.2135282816438, 401.9135885784556, 6199.44205021548, 6441.581297215677, 6467.805965821021, 6483.027545868122, 6511.609303441797, 5343.919841202375, 5600.327960644729, 6228.813135605939, 6234.84412889678, 6300.5589608727405, 4003.9389561766156, 4028.899277518071, 4506.684532082755, 4649.163150632885, 4701.224297990828, 4646.279826626878, 4657.790399432017, 4679.88300292492, 4683.180443422404, 4702.072150418329, 4175.478065241845, 4202.0040480771695, 4596.379488769024, 4598.264277715566, 4649.628029757611, 3817.980910095446, 4628.884277893046, 4646.094011667727, 4651.581357677808, 4669.159551280768, 3474.9652503474895, 4012.197560487914, 4016.2770701273757, 4024.5371782245124, 4043.4945631796027, 6292.496600272013, 6300.0, 6309.432151106423, 6315.96842015787, 6320.767450347184, 5534.861628459281, 5535.169724541311, 5558.970975593919, 5564.415736347706, 5585.636933599303, 6857.862842743167, 6860.759873404457, 6870.519063665545, 6883.8967415488805, 6892.620905850176, 4028.3151864182864, 4566.383538222331, 4566.429196350457, 4583.197940360424, 4593.563611456906, 6919.169699636025, 6940.0, 7195.748148814819, 7334.889976650355, 7351.2648735126595, 3806.276807406584, 4372.191144638252, 4446.533114022981, 4628.560286772751, 4638.397008388888, 4099.524171298331, 4196.580341965779, 4716.976415117902, 4723.95276047242, 4750.691205071659, 5381.892362152813, 5479.178123281539, 5511.173324001352, 5513.889722205534, 5528.0, 6327.620342779448, 6845.349691779273, 6856.862862742717, 6877.4841886858685, 6888.552244104118, 1617.4015614222578, 1619.190404797601, 1622.886397952132, 1624.9187540622922, 1629.2342598978462, 1030.2633616963758, 1052.6736711619421, 1067.8573925899368, 1068.4497483795851, 1070.7323169207698, 6389.904151437732, 6416.973284274587, 6452.161219041494, 6487.935120648733, 6534.183227096613, 7072.328128827753, 7106.680199391039, 7119.394851437686, 7133.82165445868, 7150.713971441084, 6378.002338900709, 6409.647469389135, 6420.357964203542, 6476.4495017924, 6512.479001679854, 3085.6605773364913, 3139.434901717713, 3714.7213958953184, 3755.235039531416, 3774.584795672474, 6789.0, 6892.345227203407, 7129.2870712929325, 7360.0, 7400.407967362598, 6140.815775526741, 7255.637218139177, 7307.3423391894985, 7364.22675619063, 7375.275485879596, 6681.966590167018, 6847.93152068473, 7071.221656178162, 7374.018030263597, 7389.0, 7146.428285737024, 7174.42604591621, 7186.490836924305, 7194.136703595602, 7211.927880721336, 6151.384861513813, 6442.581232220078, 7042.964785176041, 7057.682404291717, 7073.504854660125, 6808.795736127924, 6871.862562748617, 7214.531055481587, 7268.200497945328, 7317.634118294171, 4599.4020777300075, 4615.26155815068, 4625.676202665781, 4631.536846315342, 4691.803590084507, 5881.0009193748665, 7026.402755765817, 7071.232191952012, 7103.289671032855, 7258.963705181441, 6897.999580121708, 6962.965185174042, 7008.948657701386, 7023.262557431601, 7026.367626913602, 5266.025785229592, 5791.565632577658, 6824.0, 6898.724051037902, 6997.335253150943, 6307.990721484724, 6691.330866913269, 6767.26291595875, 6811.058848228231, 6852.862942741217, 5719.742611582572, 6888.655567221719, 6904.723811047502, 6931.93068069333, 6969.7909062728195, 5417.864553386039, 6757.560758550679, 6773.898391524032, 6786.558873673393, 6858.0], "value": 4973.301328069036, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.6859718918054, 750.8685979953508, 766.4366690482491, 846.9407141500094, 859.8649782287381, 12008.039356851452, 14222.004459687825, 15271.541853744378, 17179.226934787934, 17397.39109131179, 16014.83985160148, 17494.65010699785, 17734.113294335264, 17773.82226177738, 17836.0, 978.1587834462374, 993.6075250276136, 994.9154321882648, 1028.9948550257275, 1043.4782608695652, 1016.3241444439476, 1036.9429681367521, 1041.198277326462, 1042.1298215989657, 1042.7758032023114, 878.1613559051123, 957.9425234485918, 1059.3802625464075, 1061.1510791366914, 1083.030687534656, 1059.2585190366774, 1060.4114716332472, 1061.1775873697868, 1061.5647584490362, 1062.314806949521, 751.7105914223064, 766.2261116272549, 767.6353731977322, 769.2768797330548, 769.8421823526164, 14574.708505829823, 15581.376744930189, 15667.0, 15724.29240684172, 15759.842401576067, 16765.413210537572, 17920.92474451537, 18337.908310458497, 18392.264309427606, 18444.170012349477, 12663.93668031654, 14722.484713034992, 14900.180490073042, 14910.0, 14977.0, 3618.9819050904575, 3654.5614526256754, 3666.651668091527, 3667.0, 3667.853285868589, 115100.0, 160246.0, 161358.0, 162752.9999999988, 162992.0, 13751.381187846571, 13834.23911684867, 13936.790948135786, 13977.3710183042, 14057.226852523005, 27039.32401689934, 27095.0, 27443.725562744516, 27495.0, 27549.586756198674, 6835.487338449636, 7617.27635874591, 7733.961330193426, 7742.729004484929, 7821.335186509211, 3449.1721986723505, 3880.4373365862357, 4076.470058892325, 4090.8568200112854, 4111.09555897702, 4233.089885674594, 4320.092780516126, 4326.0, 4349.456317960255, 4350.782460877007, 782.3897360059163, 821.5112008355019, 910.4043382745916, 935.4387367579398, 953.1612181280567, 567.3702190568548, 600.2766666167258, 655.947524198063, 661.4884987802848, 680.3196803196803, 10669.986351296615, 11084.501197446134, 12181.451834667461, 12306.569270075504, 12332.19840710351, 634.7905191286865, 637.3499030988365, 647.624377860849, 672.3411057164006, 675.8918573028391, 5316.46835316473, 5327.360716713942, 5448.645838020516, 5571.637843540157, 5969.850753731104, 112863.43568282106, 137957.62042379647, 143959.0, 148319.0, 152999.0, 93986.0601393991, 122358.0, 128458.0, 134769.9784503233, 137644.0, 109506.0, 110088.4495577517, 112972.74054518863, 113521.86478135278, 114409.0, 10443.216758743276, 11456.885431145914, 11511.30932144074, 11532.077433805107, 11727.589534366598, 28525.0, 45104.548954510035, 45137.77431112823, 45323.77338113288, 46364.40889777555, 61411.0, 61700.69149654223, 72941.0, 80389.0, 85636.71544926707, 15753.606159846095, 17239.913800430917, 17612.559686007953, 17700.822991770434, 17880.55298617493, 18790.248390064244, 18848.15190780425, 19160.425187244404, 19210.903945480743, 19267.51831204231, 17504.212310446313, 17630.38293659767, 17926.372576959744, 18243.996580188217, 18465.0, 17379.652406951536, 17668.558286042953, 17749.911250443663, 17921.910390447963, 18058.729119062962, 17345.65308693794, 17786.110694464965, 17794.822051779316, 17832.286708532032, 17924.014179220267, 11248.493817778057, 11350.602728904449, 11950.820737689115, 11959.983401410629, 12099.971502422393, 13660.93169534146, 13868.93065534666, 14407.207603581903, 14440.566782996528, 14480.131192128507], "value": 8787.078025063309, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1575.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 114.0, 114.0, 112.0, 114.0, 116.0, 116.0, 114.0, 113.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 116.0, 111.0, 146.0, 109.0, 108.0, 109.0, 121.0, 160.0, 146.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 200.0, 200.0, 218.0, 210.0, 225.0, 236.0, 285.0, 212.0, 199.0], "value": 219.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 60.0, 61.0, 82.0, 61.0, 62.0, 58.0, 77.0, 72.0, 60.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 86.0, 54.0, 90.0, 94.0, 111.0, 109.0, 75.0, 72.0], "value": 84.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 115.0, 90.0, 90.0, 89.0, 90.0, 90.0, 94.0, 95.0, 89.0], "value": 94.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 122.0, 104.0, 157.0, 107.0, 155.0, 96.0, 123.0, 96.0], "value": 115.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 144.0, 140.0, 93.0, 91.0, 90.0, 92.0, 136.0, 118.0, 92.0], "value": 107.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 51.0, 51.0, 50.0, 50.0, 52.0, 51.0, 50.0, 51.0, 51.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 64.0, 60.0, 74.0, 74.0, 72.0, 57.0, 49.0, 82.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 93.0, 104.0, 113.0, 89.0, 91.0, 93.0, 131.0, 83.0], "value": 97.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 134.0, 139.0, 167.0, 139.0, 146.0, 139.0, 138.0, 137.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 231.0, 186.0, 239.0, 248.0, 211.0, 240.0, 246.0, 219.0, 225.0], "value": 223.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 67.0, 66.0, 68.0, 72.0, 66.0, 62.0, 81.0, 95.0], "value": 71.4, "unit": "score"}]}]} 10:23:03 INFO - Return code: 0 10:23:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:23:03 INFO - # TBPL SUCCESS # 10:23:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:23:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:23:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:23:03 INFO - 2017-06-06 10:23:03.617 screenresolution[1922:12409] starting screenresolution argv=screenresolution get 10:23:03 INFO - 2017-06-06 10:23:03.620 screenresolution[1922:12409] Display 0: 1600x1200x32@60 10:23:03 INFO - 2017-06-06 10:23:03.626 screenresolution[1923:12412] starting screenresolution argv=screenresolution list 10:23:03 INFO - Available Modes on Display 0 10:23:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:23:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:23:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:23:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:23:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:23:03 INFO - Intel Iris: 10:23:03 INFO - Chipset Model: Intel Iris 10:23:03 INFO - Type: GPU 10:23:03 INFO - Bus: Built-In 10:23:03 INFO - VRAM (Dynamic, Max): 1536 MB 10:23:03 INFO - Vendor: Intel (0x8086) 10:23:03 INFO - Device ID: 0x0a2e 10:23:03 INFO - Revision ID: 0x0009 10:23:03 INFO - Displays: 10:23:03 INFO - Display: 10:23:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:23:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:23:03 INFO - Display Serial Number: 1600x1200 60 10:23:03 INFO - Main Display: Yes 10:23:03 INFO - Mirror: Off 10:23:03 INFO - Online: Yes 10:23:03 INFO - Rotation: Supported 10:23:03 INFO - Return code: 0 10:23:03 INFO - Running post-action listener: _resource_record_post_action 10:23:03 INFO - [mozharness: 2017-06-06 17:23:03.869030Z] Finished run-tests step (success) 10:23:03 INFO - Running post-run listener: _resource_record_post_run 10:23:03 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 3627008; Write bytes: 568706560; Read time: 35; Write time: 2026 10:23:03 INFO - TinderboxPrint: CPU usage
53.1% 10:23:03 INFO - TinderboxPrint: I/O read bytes / time
3,627,008 / 35 10:23:03 INFO - TinderboxPrint: I/O write bytes / time
568,706,560 / 2,026 10:23:03 INFO - TinderboxPrint: CPU idle
2,357.4 (46.9%) 10:23:03 INFO - TinderboxPrint: CPU system
1,013.9 (20.2%) 10:23:03 INFO - TinderboxPrint: CPU user
1,658.4 (33.0%) 10:23:03 INFO - TinderboxPrint: Swap in / out
250,941,440 / 0 10:23:03 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228909568; Write bytes: 221470720; Read time: 16657; Write time: 402 10:23:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:23:03 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 3627008; Write bytes: 320943616; Read time: 35; Write time: 1563 10:23:04 INFO - Running post-run listener: _upload_blobber_files 10:23:04 INFO - Blob upload gear active. 10:23:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:23:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:23:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:23:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:23:04 INFO - (blobuploader) - INFO - Open directory for files ... 10:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:23:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:23:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:23:05 INFO - (blobuploader) - INFO - Done attempting. 10:23:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:23:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:23:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:23:06 INFO - (blobuploader) - INFO - Done attempting. 10:23:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:23:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:23:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:23:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:23:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:23:07 INFO - (blobuploader) - INFO - Done attempting. 10:23:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:23:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:23:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:23:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:23:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:23:09 INFO - (blobuploader) - INFO - Done attempting. 10:23:09 INFO - (blobuploader) - INFO - Iteration through files over. 10:23:09 INFO - Return code: 0 10:23:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:23:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:23:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20affa4feb9f028bb1adcd413220059c827b62db0ad6351d70524d98f4dbb7acc7ed4b829fbd4f02ac24375d8a02182a222b8351f95decf2e5f3bf7910da8803", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715ac1478f77ec00c0172dc0fabd62661cb06b4180446a997cfe0319347b3fdad4338a11439ff5e046b2d0005ae778b4e1851c7964c34bc3d06189d90da8fa0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4365dacf3dd76c7c8d2ca87dc64e55a4e41bd8f4d532a75f3c01f4f91f3bd2d353b9d5ef7400af407b362f896a9d557a5db6c9753af3c873a7eb458a1e05dd1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e1087a5b3fc9e571337dad2e22fd0460c293a4a3b47c748a4445cb920cea6b1e4de342857dac47cccbda9f03b3d2b363451eb7a245b1a0a7f8304fc9fda2f0"} 10:23:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:23:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:23:09 INFO - Contents: 10:23:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20affa4feb9f028bb1adcd413220059c827b62db0ad6351d70524d98f4dbb7acc7ed4b829fbd4f02ac24375d8a02182a222b8351f95decf2e5f3bf7910da8803", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715ac1478f77ec00c0172dc0fabd62661cb06b4180446a997cfe0319347b3fdad4338a11439ff5e046b2d0005ae778b4e1851c7964c34bc3d06189d90da8fa0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4365dacf3dd76c7c8d2ca87dc64e55a4e41bd8f4d532a75f3c01f4f91f3bd2d353b9d5ef7400af407b362f896a9d557a5db6c9753af3c873a7eb458a1e05dd1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e1087a5b3fc9e571337dad2e22fd0460c293a4a3b47c748a4445cb920cea6b1e4de342857dac47cccbda9f03b3d2b363451eb7a245b1a0a7f8304fc9fda2f0"} 10:23:09 INFO - Running post-run listener: copy_logs_to_upload_dir 10:23:09 INFO - Copying logs to upload dir... 10:23:09 INFO - mkdir: /builds/slave/test/build/upload/logs 10:23:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.676828 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-06 10:23:09.320759) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 10:23:09.323860) ========= 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.4l51wTASeE/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.pSfnAdmC4A/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/20affa4feb9f028bb1adcd413220059c827b62db0ad6351d70524d98f4dbb7acc7ed4b829fbd4f02ac24375d8a02182a222b8351f95decf2e5f3bf7910da8803", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715ac1478f77ec00c0172dc0fabd62661cb06b4180446a997cfe0319347b3fdad4338a11439ff5e046b2d0005ae778b4e1851c7964c34bc3d06189d90da8fa0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4365dacf3dd76c7c8d2ca87dc64e55a4e41bd8f4d532a75f3c01f4f91f3bd2d353b9d5ef7400af407b362f896a9d557a5db6c9753af3c873a7eb458a1e05dd1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e1087a5b3fc9e571337dad2e22fd0460c293a4a3b47c748a4445cb920cea6b1e4de342857dac47cccbda9f03b3d2b363451eb7a245b1a0a7f8304fc9fda2f0"} build_url:https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010992 build_url: 'https://queue.taskcluster.net/v1/task/becEt9lpSVe-nI3lRbNGYA/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/20affa4feb9f028bb1adcd413220059c827b62db0ad6351d70524d98f4dbb7acc7ed4b829fbd4f02ac24375d8a02182a222b8351f95decf2e5f3bf7910da8803", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/715ac1478f77ec00c0172dc0fabd62661cb06b4180446a997cfe0319347b3fdad4338a11439ff5e046b2d0005ae778b4e1851c7964c34bc3d06189d90da8fa0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4365dacf3dd76c7c8d2ca87dc64e55a4e41bd8f4d532a75f3c01f4f91f3bd2d353b9d5ef7400af407b362f896a9d557a5db6c9753af3c873a7eb458a1e05dd1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/93e1087a5b3fc9e571337dad2e22fd0460c293a4a3b47c748a4445cb920cea6b1e4de342857dac47cccbda9f03b3d2b363451eb7a245b1a0a7f8304fc9fda2f0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 10:23:09.357181) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:23:09.357606) ========= 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.4l51wTASeE/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.pSfnAdmC4A/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.004690 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 10:23:09.406097) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 10:23:09.406441) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 10:23:09.406782) ========= ========= Total master_lag: 0.22 =========