builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0265 starttime: 1495705757.5 results: success (0) revision: 0ed0fd886134b6698f593edbf0d362ac9e12fe07 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:17.497555) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:17.498067) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:17.498436) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:17.524583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:17.524946) ========= 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.wWlel9oOFy/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.7VMSqKq54y/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-25 02:49:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.8M=0s 2017-05-25 02:49:17 (63.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.171987 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:17.722490) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:17.722845) ========= 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.wWlel9oOFy/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.7VMSqKq54y/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.027048 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:17.772316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 02:49:17.772690) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0ed0fd886134b6698f593edbf0d362ac9e12fe07 --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 0ed0fd886134b6698f593edbf0d362ac9e12fe07 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wWlel9oOFy/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.7VMSqKq54y/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-25 02:49:17,871 truncating revision to first 12 chars 2017-05-25 02:49:17,872 Setting DEBUG logging. 2017-05-25 02:49:17,872 attempt 1/10 2017-05-25 02:49:17,872 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0ed0fd886134?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 02:49:18,964 unpacking tar archive at: mozilla-central-0ed0fd886134/testing/mozharness/ program finished with exit code 0 elapsedTime=1.378343 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 02:49:19.174481) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:19.175498) ========= script_repo_revision: 0ed0fd886134b6698f593edbf0d362ac9e12fe07 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:19.176698) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:19.177312) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 02:49:19.197513) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-25 02:49:19.198085) ========= /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.wWlel9oOFy/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.7VMSqKq54y/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:49:19 INFO - MultiFileLogger online at 20170525 02:49:19 in /builds/slave/test 02:49:19 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 02:49:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:49:19 INFO - {'append_to_log': False, 02:49:19 INFO - 'base_work_dir': '/builds/slave/test', 02:49:19 INFO - 'blob_upload_branch': 'Firefox', 02:49:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:49:19 INFO - 'branch': 'Firefox', 02:49:19 INFO - 'buildbot_json_path': 'buildprops.json', 02:49:19 INFO - 'config_files': ('talos/mac_config.py',), 02:49:19 INFO - 'default_actions': ('clobber', 02:49:19 INFO - 'read-buildbot-config', 02:49:19 INFO - 'download-and-extract', 02:49:19 INFO - 'populate-webroot', 02:49:19 INFO - 'create-virtualenv', 02:49:19 INFO - 'install', 02:49:19 INFO - 'run-tests'), 02:49:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:49:19 INFO - 'download_minidump_stackwalk': True, 02:49:19 INFO - 'download_symbols': 'ondemand', 02:49:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:49:19 INFO - 'tooltool.py': '/tools/tooltool.py', 02:49:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:49:19 INFO - '/tools/misc-python/virtualenv.py')}, 02:49:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:49:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:49:19 INFO - 'gecko_profile': False, 02:49:19 INFO - 'gecko_profile_interval': 0, 02:49:19 INFO - 'installer_path': 'installer.exe', 02:49:19 INFO - 'log_level': 'info', 02:49:19 INFO - 'log_name': 'talos', 02:49:19 INFO - 'log_to_console': True, 02:49:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:49:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:49:19 INFO - 'opt_config_files': (), 02:49:19 INFO - 'pip_index': False, 02:49:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:49:19 INFO - 'cmd': ('bash', 02:49:19 INFO - '-c', 02:49:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:49:19 INFO - 'enabled': True, 02:49:19 INFO - 'halt_on_failure': False, 02:49:19 INFO - 'name': 'check_screen_resolution'},), 02:49:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:49:19 INFO - 'cmd': ('bash', 02:49:19 INFO - '-c', 02:49:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:49:19 INFO - 'enabled': True, 02:49:19 INFO - 'halt_on_failure': False, 02:49:19 INFO - 'name': 'check_screen_resolution'},), 02:49:19 INFO - 'run_cmd_checks_enabled': True, 02:49:19 INFO - 'suite': 'dromaeojs-e10s', 02:49:19 INFO - 'system_bits': '32', 02:49:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:49:19 INFO - 'title': 't-yosemite-r7-0265', 02:49:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:49:19 INFO - 'use_talos_json': True, 02:49:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:49:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:49:19 INFO - 'work_dir': 'build'} 02:49:19 INFO - [mozharness: 2017-05-25 09:49:19.410844Z] Running clobber step. 02:49:19 INFO - Running pre-action listener: _resource_record_pre_action 02:49:19 INFO - Running main action method: clobber 02:49:19 INFO - rmtree: /builds/slave/test/build 02:49:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:49:21 INFO - Running post-action listener: _resource_record_post_action 02:49:21 INFO - [mozharness: 2017-05-25 09:49:21.947087Z] Finished clobber step (success) 02:49:21 INFO - [mozharness: 2017-05-25 09:49:21.947253Z] Running read-buildbot-config step. 02:49:21 INFO - Running pre-action listener: _resource_record_pre_action 02:49:21 INFO - Running main action method: read_buildbot_config 02:49:21 INFO - Using buildbot properties: 02:49:21 INFO - { 02:49:21 INFO - "project": "", 02:49:21 INFO - "product": "firefox", 02:49:21 INFO - "who": "archaeopteryx@coole-files.de", 02:49:21 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 02:49:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 02:49:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 02:49:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 02:49:21 INFO - "stage_platform": "macosx64", 02:49:21 INFO - "basedir": "/builds/slave/test", 02:49:21 INFO - "buildnumber": 164, 02:49:21 INFO - "platform": "yosemite_r7", 02:49:21 INFO - "parent_task_id": "dMxKD2XFS9OubeMdiJxsow", 02:49:21 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:49:21 INFO - "slavebuilddir": "test", 02:49:21 INFO - "taskId": "YT06K0MKQ72ROWpoVYp-Yw", 02:49:21 INFO - "branch": "mozilla-central", 02:49:21 INFO - "script_repo_revision": "production", 02:49:21 INFO - "revision": "0ed0fd886134b6698f593edbf0d362ac9e12fe07", 02:49:21 INFO - "slavename": "t-yosemite-r7-0265", 02:49:21 INFO - "repo_path": "mozilla-central" 02:49:21 INFO - } 02:49:21 INFO - Finding installer, test and symbols from parent task. 02:49:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YT06K0MKQ72ROWpoVYp-Yw'}, attempt #1 02:49:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YT06K0MKQ72ROWpoVYp-Yw'}, attempt #1 02:49:22 INFO - Task dependencies: KP2Cn1L2RpCzFgFl1M2RTw dMxKD2XFS9OubeMdiJxsow 02:49:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow'}, attempt #1 02:49:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:49:23 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/test_packages.json 02:49:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/target.crashreporter-symbols.zip 02:49:23 INFO - Running post-action listener: _resource_record_post_action 02:49:23 INFO - [mozharness: 2017-05-25 09:49:23.333416Z] Finished read-buildbot-config step (success) 02:49:23 INFO - [mozharness: 2017-05-25 09:49:23.333537Z] Running download-and-extract step. 02:49:23 INFO - Running pre-action listener: _resource_record_pre_action 02:49:23 INFO - Running main action method: download_and_extract 02:49:23 INFO - mkdir: /builds/slave/test/build/tests 02:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:23 INFO - trying https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:49:23 INFO - Downloading https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/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 02:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/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 02:49:24 INFO - Downloaded 1328 bytes. 02:49:24 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:49:24 INFO - Using the following test package requirements: 02:49:24 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:49:24 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:49:24 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:49:24 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:49:24 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:49:24 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:49:24 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:49:24 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:49:24 INFO - u'jsshell-mac.zip'], 02:49:24 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:49:24 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:49:24 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:49:24 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:49:24 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:49:24 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:49:24 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:49:24 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:49:24 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 02:49:24 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:49:24 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:49:24 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:49:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:49:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:49:24 INFO - Fetch https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:49:25 INFO - Content-Length response header: 57626803 02:49:25 INFO - Bytes received: 57626803 02:49:44 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 02:49:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:49:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:49:44 INFO - Fetch https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:49:46 INFO - Content-Length response header: 57626803 02:49:46 INFO - Bytes received: 57626803 02:50:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:50:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:50:03 INFO - Fetch https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:50:04 INFO - Content-Length response header: 14411859 02:50:04 INFO - Bytes received: 14411859 02:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:04 INFO - trying https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:50:04 INFO - Downloading https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:50:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/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 02:50:06 INFO - Downloaded 67202145 bytes. 02:50:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:50:06 INFO - mkdir: /builds/slave/test/properties 02:50:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:50:06 INFO - Writing to file /builds/slave/test/properties/build_url 02:50:06 INFO - Contents: 02:50:06 INFO - build_url:https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:50:06 INFO - Running post-action listener: _resource_record_post_action 02:50:06 INFO - Running post-action listener: set_extra_try_arguments 02:50:06 INFO - [mozharness: 2017-05-25 09:50:06.888643Z] Finished download-and-extract step (success) 02:50:06 INFO - [mozharness: 2017-05-25 09:50:06.888803Z] Running populate-webroot step. 02:50:06 INFO - Running pre-action listener: _resource_record_pre_action 02:50:06 INFO - Running main action method: populate_webroot 02:50:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:50:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:50:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:50:06 INFO - '--tppagecycles', 02:50:06 INFO - '7'], 02:50:06 INFO - 'tests': ['tsvgm']}}, 02:50:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:50:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:50:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:50:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:50:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:50:06 INFO - 'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:50:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:50:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:50:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:50:06 INFO - 'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['damp', 'tps']}, 02:50:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:50:06 INFO - 'tests': ['damp', 'tps']}, 02:50:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['dromaeo_dom']}, 02:50:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:50:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:50:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:50:06 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:50:06 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:50:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['a11yr', 02:50:06 INFO - 'ts_paint', 02:50:06 INFO - 'tpaint', 02:50:06 INFO - 'sessionrestore', 02:50:06 INFO - 'sessionrestore_no_auto_restore', 02:50:06 INFO - 'tabpaint']}, 02:50:06 INFO - 'other-e10s': {'tests': ['a11yr', 02:50:06 INFO - 'ts_paint', 02:50:06 INFO - 'tpaint', 02:50:06 INFO - 'sessionrestore', 02:50:06 INFO - 'sessionrestore_no_auto_restore', 02:50:06 INFO - 'tabpaint']}, 02:50:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:50:06 INFO - 'ts_paint', 02:50:06 INFO - 'tpaint', 02:50:06 INFO - 'sessionrestore', 02:50:06 INFO - 'sessionrestore_no_auto_restore', 02:50:06 INFO - 'tabpaint']}, 02:50:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:50:06 INFO - 'ts_paint', 02:50:06 INFO - 'tpaint', 02:50:06 INFO - 'sessionrestore', 02:50:06 INFO - 'sessionrestore_no_auto_restore', 02:50:06 INFO - 'tabpaint']}, 02:50:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['a11yr', 02:50:06 INFO - 'ts_paint', 02:50:06 INFO - 'tpaint', 02:50:06 INFO - 'sessionrestore', 02:50:06 INFO - 'sessionrestore_no_auto_restore', 02:50:06 INFO - 'tabpaint']}, 02:50:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['a11yr', 02:50:06 INFO - 'ts_paint', 02:50:06 INFO - 'tpaint', 02:50:06 INFO - 'sessionrestore', 02:50:06 INFO - 'sessionrestore_no_auto_restore', 02:50:06 INFO - 'tabpaint']}, 02:50:06 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:50:06 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:50:06 INFO - 'bloom_basic_ref']}, 02:50:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['tsvgx', 02:50:06 INFO - 'tsvgr_opacity', 02:50:06 INFO - 'tart', 02:50:06 INFO - 'tscrollx', 02:50:06 INFO - 'cart', 02:50:06 INFO - 'tsvg_static']}, 02:50:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:50:06 INFO - 'tsvgr_opacity', 02:50:06 INFO - 'tart', 02:50:06 INFO - 'tscrollx', 02:50:06 INFO - 'cart', 02:50:06 INFO - 'tsvg_static']}, 02:50:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:50:06 INFO - 'talos_options': ['--disable-e10s'], 02:50:06 INFO - 'tests': ['tp5o']}, 02:50:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:50:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:50:06 INFO - 'talos_options': ['--disable-e10s', 02:50:06 INFO - '--xperf_path', 02:50:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:50:06 INFO - 'tests': ['tp5n']}, 02:50:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:50:06 INFO - 'talos_options': ['--xperf_path', 02:50:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:50:06 INFO - 'tests': ['tp5n']}}, 02:50:06 INFO - 'talos.zip': {'path': '', 02:50:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:50:06 INFO - Running post-action listener: _resource_record_post_action 02:50:06 INFO - [mozharness: 2017-05-25 09:50:06.910449Z] Finished populate-webroot step (success) 02:50:06 INFO - [mozharness: 2017-05-25 09:50:06.910605Z] Running create-virtualenv step. 02:50:06 INFO - Running pre-action listener: _resource_record_pre_action 02:50:06 INFO - Running main action method: create_virtualenv 02:50:06 INFO - Creating virtualenv /builds/slave/test/build/venv 02:50:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:50:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:50:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:50:07 INFO - Using real prefix '/tools/python27' 02:50:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:50:07 INFO - Installing distribute.............................................................................................................................................................................................done. 02:50:10 INFO - Installing pip.................done. 02:50:10 INFO - Return code: 0 02:50:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:50:10 INFO - Reading from file tmpfile_stdout 02:50:10 INFO - Output received: 02:50:10 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 02:50:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:50:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:10 INFO - 'HOME': '/Users/cltbld', 02:50:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:10 INFO - 'LOGNAME': 'cltbld', 02:50:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:10 INFO - 'MOZ_NO_REMOTE': '1', 02:50:10 INFO - 'NO_EM_RESTART': '1', 02:50:10 INFO - 'PAGER': '/bin/cat', 02:50:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:10 INFO - 'PWD': '/builds/slave/test', 02:50:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:10 INFO - 'SHELL': '/bin/bash', 02:50:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:10 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:10 INFO - 'USER': 'cltbld', 02:50:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:10 INFO - 'XPC_FLAGS': '0x0', 02:50:10 INFO - 'XPC_SERVICE_NAME': '0', 02:50:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:11 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 02:50:11 INFO - Cleaning up... 02:50:11 INFO - Return code: 0 02:50:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:50:11 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')]} 02:50:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:11 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')]} 02:50:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:11 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 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:11 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 02:50:11 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 02:50:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:11 INFO - 'HOME': '/Users/cltbld', 02:50:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:11 INFO - 'LOGNAME': 'cltbld', 02:50:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:11 INFO - 'MOZ_NO_REMOTE': '1', 02:50:11 INFO - 'NO_EM_RESTART': '1', 02:50:11 INFO - 'PAGER': '/bin/cat', 02:50:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:11 INFO - 'PWD': '/builds/slave/test', 02:50:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:11 INFO - 'SHELL': '/bin/bash', 02:50:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:11 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:11 INFO - 'USER': 'cltbld', 02:50:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:11 INFO - 'XPC_FLAGS': '0x0', 02:50:11 INFO - 'XPC_SERVICE_NAME': '0', 02:50:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:11 INFO - Downloading/unpacking psutil>=3.1.1 02:50:11 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 02:50:11 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 02:50:11 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 02:50:11 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 02:50:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:50:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:50:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:50:13 INFO - Installing collected packages: psutil 02:50:13 INFO - Running setup.py install for psutil 02:50:13 INFO - building 'psutil._psutil_osx' extension 02:50:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:50:14 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 02:50:14 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 02:50:14 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 02:50:14 INFO - building 'psutil._psutil_posix' extension 02:50:14 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 02:50:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:50:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:50:14 INFO - ^ 02:50:14 INFO - 1 warning generated. 02:50:14 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 02:50:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:50:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:50:14 INFO - Successfully installed psutil 02:50:14 INFO - Cleaning up... 02:50:14 INFO - Return code: 0 02:50:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:15 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 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:15 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 02:50:15 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 02:50:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:15 INFO - 'HOME': '/Users/cltbld', 02:50:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:15 INFO - 'LOGNAME': 'cltbld', 02:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:15 INFO - 'MOZ_NO_REMOTE': '1', 02:50:15 INFO - 'NO_EM_RESTART': '1', 02:50:15 INFO - 'PAGER': '/bin/cat', 02:50:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:15 INFO - 'PWD': '/builds/slave/test', 02:50:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:15 INFO - 'SHELL': '/bin/bash', 02:50:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:15 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:15 INFO - 'USER': 'cltbld', 02:50:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:15 INFO - 'XPC_FLAGS': '0x0', 02:50:15 INFO - 'XPC_SERVICE_NAME': '0', 02:50:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:50:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:50:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:50:17 INFO - Installing collected packages: mozsystemmonitor 02:50:17 INFO - Running setup.py install for mozsystemmonitor 02:50:17 INFO - Successfully installed mozsystemmonitor 02:50:17 INFO - Cleaning up... 02:50:17 INFO - Return code: 0 02:50:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:50:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:50:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:17 INFO - 'HOME': '/Users/cltbld', 02:50:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:17 INFO - 'LOGNAME': 'cltbld', 02:50:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:17 INFO - 'MOZ_NO_REMOTE': '1', 02:50:17 INFO - 'NO_EM_RESTART': '1', 02:50:17 INFO - 'PAGER': '/bin/cat', 02:50:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:17 INFO - 'PWD': '/builds/slave/test', 02:50:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:17 INFO - 'SHELL': '/bin/bash', 02:50:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:17 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:17 INFO - 'USER': 'cltbld', 02:50:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:17 INFO - 'XPC_FLAGS': '0x0', 02:50:17 INFO - 'XPC_SERVICE_NAME': '0', 02:50:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:17 INFO - Downloading/unpacking jsonschema==2.5.1 02:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:50:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:50:19 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 02:50:19 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 02:50:19 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 02:50:19 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 02:50:19 INFO - Downloading functools32-3.2.3-2.tar.gz 02:50:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:50:19 INFO - warning: no files found matching '*.txt' 02:50:19 INFO - no previously-included directories found matching 'build' 02:50:19 INFO - no previously-included directories found matching 'dist' 02:50:19 INFO - no previously-included directories found matching '.git*' 02:50:19 INFO - Installing collected packages: jsonschema, functools32 02:50:19 INFO - Running setup.py install for functools32 02:50:19 INFO - warning: no files found matching '*.txt' 02:50:19 INFO - no previously-included directories found matching 'build' 02:50:19 INFO - no previously-included directories found matching 'dist' 02:50:19 INFO - no previously-included directories found matching '.git*' 02:50:19 INFO - Successfully installed jsonschema functools32 02:50:19 INFO - Cleaning up... 02:50:19 INFO - Return code: 0 02:50:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:50:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:50:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:19 INFO - 'HOME': '/Users/cltbld', 02:50:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:19 INFO - 'LOGNAME': 'cltbld', 02:50:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:19 INFO - 'MOZ_NO_REMOTE': '1', 02:50:19 INFO - 'NO_EM_RESTART': '1', 02:50:19 INFO - 'PAGER': '/bin/cat', 02:50:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:19 INFO - 'PWD': '/builds/slave/test', 02:50:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:19 INFO - 'SHELL': '/bin/bash', 02:50:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:19 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:19 INFO - 'USER': 'cltbld', 02:50:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:19 INFO - 'XPC_FLAGS': '0x0', 02:50:19 INFO - 'XPC_SERVICE_NAME': '0', 02:50:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:50:20 INFO - Cleaning up... 02:50:20 INFO - Return code: 0 02:50:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:50:20 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')]} 02:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:20 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')]} 02:50:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:20 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 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:20 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 02:50:20 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 02:50:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:20 INFO - 'HOME': '/Users/cltbld', 02:50:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:20 INFO - 'LOGNAME': 'cltbld', 02:50:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:20 INFO - 'MOZ_NO_REMOTE': '1', 02:50:20 INFO - 'NO_EM_RESTART': '1', 02:50:20 INFO - 'PAGER': '/bin/cat', 02:50:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:20 INFO - 'PWD': '/builds/slave/test', 02:50:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:20 INFO - 'SHELL': '/bin/bash', 02:50:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:20 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:20 INFO - 'USER': 'cltbld', 02:50:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:20 INFO - 'XPC_FLAGS': '0x0', 02:50:20 INFO - 'XPC_SERVICE_NAME': '0', 02:50:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:20 INFO - Downloading/unpacking blobuploader==1.2.4 02:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:21 INFO - Downloading blobuploader-1.2.4.tar.gz 02:50:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:50:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:50:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:22 INFO - Downloading docopt-0.6.1.tar.gz 02:50:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:50:22 INFO - Installing collected packages: blobuploader, requests, docopt 02:50:22 INFO - Running setup.py install for blobuploader 02:50:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:50:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:50:22 INFO - Running setup.py install for requests 02:50:23 INFO - Running setup.py install for docopt 02:50:23 INFO - Successfully installed blobuploader requests docopt 02:50:23 INFO - Cleaning up... 02:50:23 INFO - Return code: 0 02:50:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:50:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:50:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:23 INFO - 'HOME': '/Users/cltbld', 02:50:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:23 INFO - 'LOGNAME': 'cltbld', 02:50:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:23 INFO - 'MOZ_NO_REMOTE': '1', 02:50:23 INFO - 'NO_EM_RESTART': '1', 02:50:23 INFO - 'PAGER': '/bin/cat', 02:50:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:23 INFO - 'PWD': '/builds/slave/test', 02:50:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:23 INFO - 'SHELL': '/bin/bash', 02:50:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:23 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:23 INFO - 'USER': 'cltbld', 02:50:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:23 INFO - 'XPC_FLAGS': '0x0', 02:50:23 INFO - 'XPC_SERVICE_NAME': '0', 02:50:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-ThCQ3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-g32ui6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-oe5rrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-cb3IrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Y3tEVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-FSwH3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-MAqfMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-1HriQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-QDr65Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:50:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:50:24 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-bdXM5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:50:25 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-1mLshw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:50:25 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-KVFA_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:50:25 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-mphf6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:50:25 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-sii2Od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:25 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-OiBOCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:50:25 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-0Mavjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:50:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:50:25 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-FBGoq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:50:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:50:25 INFO - Running setup.py install for manifestparser 02:50:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:50:25 INFO - Running setup.py install for mozcrash 02:50:26 INFO - Running setup.py install for mozdebug 02:50:26 INFO - Running setup.py install for mozdevice 02:50:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:50:26 INFO - Running setup.py install for mozfile 02:50:26 INFO - Running setup.py install for mozhttpd 02:50:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:50:26 INFO - Running setup.py install for mozinfo 02:50:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:50:26 INFO - Running setup.py install for mozInstall 02:50:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:50:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:50:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:50:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:50:26 INFO - Running setup.py install for mozleak 02:50:27 INFO - Running setup.py install for mozlog 02:50:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:50:27 INFO - Running setup.py install for moznetwork 02:50:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:50:27 INFO - Running setup.py install for mozprocess 02:50:27 INFO - Running setup.py install for mozprofile 02:50:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:50:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:50:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:50:27 INFO - Running setup.py install for mozrunner 02:50:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:50:27 INFO - Running setup.py install for mozscreenshot 02:50:28 INFO - Running setup.py install for moztest 02:50:28 INFO - Running setup.py install for mozversion 02:50:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:50:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:50:28 INFO - Cleaning up... 02:50:28 INFO - Return code: 0 02:50:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50: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')]} 02:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50: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')]} 02:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:28 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 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:28 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 02:50:28 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 02:50:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:28 INFO - 'HOME': '/Users/cltbld', 02:50:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:28 INFO - 'LOGNAME': 'cltbld', 02:50:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:28 INFO - 'MOZ_NO_REMOTE': '1', 02:50:28 INFO - 'NO_EM_RESTART': '1', 02:50:28 INFO - 'PAGER': '/bin/cat', 02:50:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:28 INFO - 'PWD': '/builds/slave/test', 02:50:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:28 INFO - 'SHELL': '/bin/bash', 02:50:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:28 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:28 INFO - 'USER': 'cltbld', 02:50:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:28 INFO - 'XPC_FLAGS': '0x0', 02:50:28 INFO - 'XPC_SERVICE_NAME': '0', 02:50:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:50:28 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-2Jd0Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:50:28 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-zWh0XM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:50:28 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Y_RWtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:50:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:50:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:50:28 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-SZykrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:50:29 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)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-lB4vdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:50:29 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)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-1Lzsrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:50:29 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)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-XcNScZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:50:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-xGL87E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:50:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-Cko_ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:50:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-9Vm9Sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:50:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-yDWxqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:50:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-y9daOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:50:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-APYT35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:50:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:50:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:50:29 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-T9MBYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:50:30 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)) 02:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:30 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-47DZ7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:50:30 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)) 02:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:50:30 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-GGHfyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:50:30 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)) 02:50:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:50:30 INFO - Running setup.py (path:/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/pip-D1pbTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:50:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:31 INFO - Downloading blessings-1.6.tar.gz 02:50:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:50:31 INFO - Installing collected packages: blessings 02:50:31 INFO - Running setup.py install for blessings 02:50:32 INFO - Successfully installed blessings 02:50:32 INFO - Cleaning up... 02:50:32 INFO - Return code: 0 02:50:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:50:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:32 INFO - Reading from file tmpfile_stdout 02:50:32 INFO - Current package versions: 02:50:32 INFO - blessings == 1.6 02:50:32 INFO - blobuploader == 1.2.4 02:50:32 INFO - docopt == 0.6.1 02:50:32 INFO - functools32 == 3.2.3-2 02:50:32 INFO - jsonschema == 2.5.1 02:50:32 INFO - manifestparser == 1.1 02:50:32 INFO - mozInstall == 1.12 02:50:32 INFO - mozcrash == 1.0 02:50:32 INFO - mozdebug == 0.1 02:50:32 INFO - mozdevice == 0.51 02:50:32 INFO - mozfile == 1.2 02:50:32 INFO - mozhttpd == 0.7 02:50:32 INFO - mozinfo == 0.9 02:50:32 INFO - mozleak == 0.1 02:50:32 INFO - mozlog == 3.4 02:50:32 INFO - moznetwork == 0.27 02:50:32 INFO - mozprocess == 0.25 02:50:32 INFO - mozprofile == 0.28 02:50:32 INFO - mozrunner == 6.13 02:50:32 INFO - mozscreenshot == 0.1 02:50:32 INFO - mozsystemmonitor == 0.3 02:50:32 INFO - moztest == 0.8 02:50:32 INFO - mozversion == 1.4 02:50:32 INFO - psutil == 3.1.1 02:50:32 INFO - requests == 1.2.3 02:50:32 INFO - wsgiref == 0.1.2 02:50:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:50:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:32 INFO - 'HOME': '/Users/cltbld', 02:50:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:32 INFO - 'LOGNAME': 'cltbld', 02:50:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:32 INFO - 'MOZ_NO_REMOTE': '1', 02:50:32 INFO - 'NO_EM_RESTART': '1', 02:50:32 INFO - 'PAGER': '/bin/cat', 02:50:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:32 INFO - 'PWD': '/builds/slave/test', 02:50:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:32 INFO - 'SHELL': '/bin/bash', 02:50:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:32 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:32 INFO - 'USER': 'cltbld', 02:50:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:32 INFO - 'XPC_FLAGS': '0x0', 02:50:32 INFO - 'XPC_SERVICE_NAME': '0', 02:50:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:50:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:50:32 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:50:34 INFO - Downloading marionette_driver-1.4.0.tar.gz 02:50:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 02:50:34 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)) 02:50:34 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)) 02:50:34 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)) 02:50:34 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)) 02:50:34 INFO - Installing collected packages: marionette-driver 02:50:34 INFO - Running setup.py install for marionette-driver 02:50:34 INFO - Successfully installed marionette-driver 02:50:34 INFO - Cleaning up... 02:50:34 INFO - Return code: 0 02:50:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:50:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:50:34 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 0x10fd5db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd61768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f982a4ba820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, '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.wWlel9oOFy/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.7VMSqKq54y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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 02:50:34 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 02:50:34 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 02:50:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:34 INFO - 'HOME': '/Users/cltbld', 02:50:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:34 INFO - 'LOGNAME': 'cltbld', 02:50:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:34 INFO - 'MOZ_NO_REMOTE': '1', 02:50:34 INFO - 'NO_EM_RESTART': '1', 02:50:34 INFO - 'PAGER': '/bin/cat', 02:50:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:34 INFO - 'PWD': '/builds/slave/test', 02:50:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:34 INFO - 'SHELL': '/bin/bash', 02:50:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:34 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:34 INFO - 'USER': 'cltbld', 02:50:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:34 INFO - 'XPC_FLAGS': '0x0', 02:50:34 INFO - 'XPC_SERVICE_NAME': '0', 02:50:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:50:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:50:34 INFO - Cleaning up... 02:50:34 INFO - Return code: 0 02:50:34 INFO - Running post-action listener: _resource_record_post_action 02:50:34 INFO - Running post-action listener: _start_resource_monitoring 02:50:34 INFO - Starting resource monitoring. 02:50:34 INFO - [mozharness: 2017-05-25 09:50:34.791987Z] Finished create-virtualenv step (success) 02:50:34 INFO - [mozharness: 2017-05-25 09:50:34.793017Z] Running install step. 02:50:34 INFO - Running pre-action listener: _resource_record_pre_action 02:50:34 INFO - Running main action method: install 02:50:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:50:35 INFO - Reading from file tmpfile_stdout 02:50:35 INFO - Detecting whether we're running mozinstall >=1.0... 02:50:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:50:35 INFO - Reading from file tmpfile_stdout 02:50:35 INFO - Output received: 02:50:35 INFO - Usage: mozinstall [options] installer 02:50:35 INFO - Options: 02:50:35 INFO - -h, --help show this help message and exit 02:50:35 INFO - -d DEST, --destination=DEST 02:50:35 INFO - Directory to install application into. [default: 02:50:35 INFO - "/builds/slave/test"] 02:50:35 INFO - --app=APP Application being installed. [default: firefox] 02:50:35 INFO - mkdir: /builds/slave/test/build/application 02:50:35 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'] 02:50:35 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 02:50:56 INFO - Reading from file tmpfile_stdout 02:50:56 INFO - Output received: 02:50:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:50:56 INFO - Running post-action listener: _resource_record_post_action 02:50:56 INFO - [mozharness: 2017-05-25 09:50:56.330164Z] Finished install step (success) 02:50:56 INFO - [mozharness: 2017-05-25 09:50:56.330291Z] Running run-tests step. 02:50:56 INFO - Running pre-action listener: _resource_record_pre_action 02:50:56 INFO - Running main action method: run_tests 02:50:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:50:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:50:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:50:56 INFO - 2017-05-25 02:50:56.344 screenresolution[1631:7445] starting screenresolution argv=screenresolution get 02:50:56 INFO - 2017-05-25 02:50:56.347 screenresolution[1631:7445] Display 0: 1600x1200x32@60 02:50:56 INFO - 2017-05-25 02:50:56.353 screenresolution[1632:7448] starting screenresolution argv=screenresolution list 02:50:56 INFO - Available Modes on Display 0 02:50:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:50:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:50:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:50:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:50:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:50:56 INFO - Intel Iris: 02:50:56 INFO - Chipset Model: Intel Iris 02:50:56 INFO - Type: GPU 02:50:56 INFO - Bus: Built-In 02:50:56 INFO - VRAM (Dynamic, Max): 1536 MB 02:50:56 INFO - Vendor: Intel (0x8086) 02:50:56 INFO - Device ID: 0x0a2e 02:50:56 INFO - Revision ID: 0x0009 02:50:56 INFO - Displays: 02:50:56 INFO - Display: 02:50:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:50:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:50:56 INFO - Display Serial Number: 1600x1200 60 02:50:56 INFO - Main Display: Yes 02:50:56 INFO - Mirror: Off 02:50:56 INFO - Online: Yes 02:50:56 INFO - Rotation: Supported 02:50:56 INFO - Return code: 0 02:50:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:50:56 INFO - Python 2.7.3 02:50:56 INFO - Return code: 0 02:50:56 INFO - grabbing minidump binary from tooltool 02:50:56 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')]} 02:50:56 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 0x10fa6dd30>, '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 0x10fa21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa62be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:50:56 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 02:50:56 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 02:50:56 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 02:50:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:50:56 INFO - Return code: 0 02:50:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:50:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:50:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:50:56 INFO - ENV: RUST_BACKTRACE is now 1 02:50:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:50:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:50:56 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/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 02:50:56 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/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0265 --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 02:50:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wWlel9oOFy/Render', 02:50:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:50:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:50:56 INFO - 'HOME': '/Users/cltbld', 02:50:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:50:56 INFO - 'LOGNAME': 'cltbld', 02:50:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:50:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:50:56 INFO - 'MOZ_NO_REMOTE': '1', 02:50:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:50:56 INFO - 'NO_EM_RESTART': '1', 02:50:56 INFO - 'PAGER': '/bin/cat', 02:50:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:50:56 INFO - 'PWD': '/builds/slave/test', 02:50:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:50:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:50:56 INFO - 'RUST_BACKTRACE': '1', 02:50:56 INFO - 'SHELL': '/bin/bash', 02:50:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7VMSqKq54y/Listeners', 02:50:56 INFO - 'TMPDIR': '/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/', 02:50:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:50:56 INFO - 'USER': 'cltbld', 02:50:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:50:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:50:56 INFO - 'XPC_FLAGS': '0x0', 02:50:56 INFO - 'XPC_SERVICE_NAME': '0', 02:50:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:50:56 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/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0265', '--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 02:50:56 INFO - mozversion application_buildid: 20170525013555 02:50:56 INFO - mozversion application_changeset: 0ed0fd886134b6698f593edbf0d362ac9e12fe07 02:50:56 INFO - mozversion application_display_name: Nightly 02:50:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:50:56 INFO - mozversion application_name: Firefox 02:50:56 INFO - mozversion application_remotingname: firefox 02:50:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:50:56 INFO - mozversion application_vendor: Mozilla 02:50:56 INFO - mozversion application_version: 55.0a1 02:50:56 INFO - mozversion platform_buildid: 20170525013555 02:50:56 INFO - mozversion platform_changeset: 0ed0fd886134b6698f593edbf0d362ac9e12fe07 02:50:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:50:56 INFO - mozversion platform_version: 55.0a1 02:50:56 INFO - using testdate: 1495705856 02:50:56 INFO - actual date: 1495705856 02:50:56 INFO - starting webserver on 'localhost:49228' 02:50:56 INFO - SUITE-START | Running 2 tests 02:50:56 INFO - TEST-START | dromaeo_css 02:50:56 INFO - operating with platform_type : mac_ 02:50:56 INFO - Initialising browser for dromaeo_css test... 02:50:56 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpIUjXg3/profile 02:50:56 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 02:51:30 INFO - TEST-INFO | 1642: killed by SIGTERM 02:51:30 INFO - Browser initialized. 02:51:30 INFO - Running cycle 1/1 for dromaeo_css test... 02:51:30 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmpIUjXg3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:32 INFO - PID 1652 | 2017-05-25 02:51:32.780 plugin-container[1653:7996] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 02:51:32 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:32 INFO - PID 1652 | 2017-05-25 02:51:32.781 plugin-container[1653:7996] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 02:51:32 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:32 INFO - PID 1652 | 2017-05-25 02:51:32.781 plugin-container[1653:7996] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:51:33 INFO - PID 1652 | 2017-05-25 02:51:33.282 plugin-container[1654:8043] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 02:51:33 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:33 INFO - PID 1652 | 2017-05-25 02:51:33.282 plugin-container[1654:8043] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9917, name = 'com.apple.CFPasteboardClient' 02:51:33 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:51:33 INFO - PID 1652 | 2017-05-25 02:51:33.282 plugin-container[1654:8043] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:51:42 INFO - PID 1652 | RSS: Main: 245989376 02:51:42 INFO - PID 1652 | 02:54:46 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 02:54:47 INFO - PID 1652 | RSS: Main: 257273856 02:54:47 INFO - PID 1652 | 02:57:49 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 02:57:50 INFO - PID 1652 | RSS: Main: 273883136 02:57:50 INFO - PID 1652 | 03:00:51 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 03:00:52 INFO - PID 1652 | RSS: Main: 275034112 03:00:52 INFO - PID 1652 | 03:03:53 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 03:03:54 INFO - PID 1652 | RSS: Main: 278528000 03:03:54 INFO - PID 1652 | 03:06:57 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 03:06:58 INFO - PID 1652 | RSS: Main: 279564288 03:06:58 INFO - PID 1652 | 03:09:59 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 03:09:59 INFO - PID 1652 | RSS: Main: 279474176 03:09:59 INFO - PID 1652 | 03:09:59 INFO - PID 1652 | __start_tp_report 03:09:59 INFO - PID 1652 | _x_x_mozilla_page_load 03:09:59 INFO - PID 1652 | _x_x_mozilla_page_load_details 03:09:59 INFO - PID 1652 | |i|pagename|runs| 03:09:59 INFO - PID 1652 | |0;dojo.html;3051.389722055589;3586.820658967051;3605.819709014552;3617.9638203617974;3630.5824830144534;6142.87714245716;6164.383561643839;6240.7191676374505;6247.937520624792;6256.530760192986;6183.505319574435;6202.782902598398;6204;6247;6304.180456540644;5330.3603567571945;5417.972910135444;5432.375276843174;5434.701091439969;5449.536789372898;5478.123500239963;5505.174223866412;5518.392976772552;5518.834434966937;5539.695316757577;5389.083855744494;5436.184572314145;5449.155380915964;5452.727363631842;5504.4770746778995;4522.796474158671;5351.491608297206;5427.213054107132;5469.206964990054;5498.3127109111365;4754.952450475485;5419.864503387407;5465.562754979592;5476.370217424967;5487.945120548823;4938.432080310774;5729.538967563286;6099.976550065598;6184.96907515464;6194.380561943814;6461.19235095613;6526.412622863918;6561.671916404209;6659.567128136656;6662.600243985376;6808.931910680879;6811.931880681179;6816.454683625348;6822.9317706823285;6829.590224586541;6326.620402775849;6343.651099189497;6348.936510634927;6352.951762959106;6358.300586935434;6675.5660882043485;6745.662716864137;6826.385625293748;6866.931330686629;6929.1684997800585;6321.241451025814;6338.207724034496;6440.677966101676;6520.315366886503;6595.373439523238;6752.3922846944015;6755.695993680198;6762.526623136333;6794.388505034571;6807.285235050347;6622.106015687917;6649.60102393858;6662.866742665216;6670.266270710218;6677.432418244406;6454.806355809334;6514.478841692653;6589.769358072444;6635.767748128889;6659.433948114368;5353.571714262927;5375.838724838261;5388.7305634718105;5390.4879036491475;5427.891442171134;4782.210935195723;4815.7351345676325;4831.613470922318;4844.249141383091;4854.247591623304;5658.688772117573;6171.506279497629;6295.118683384329;6316.905246421307;6355.745770169234;6651.700673469705;6717.462602991749;6762.357576030269;6769.627670478171;6782.830429239309;6341.778037768749;6346.333634968354;6357.237131544244;6365.49076073913;6385;5761.942380576141;5763.0202865512965;5934;5965.403459654087;5997.100434934708;6761.01965215041;6827.385535301847;6884.793456196322;6894.724211031503;6917.308269173042;6686.732530698718;6782.830429239111;6787.423069039187;6798.52410331272;6801.557898736566;5802.970985145047;6907.102076729993;6928.307169283032;6932.861342773217;6980.895286570604;5554.444555544414;6454.870902582016;6545.280019197789;6553.279139294578;6669.1330127083165;6665;6751.392374686302;6753.986901964548;6766.18805743314;6766.951122576009;6273.780417685359;6423.8394040147505;6539.967300163659;6557.442617377576;6594.4724422045165;5353.544948679406;6519.152510173647;6522.119513865568;6524.412802847766;6531.836704082246;6301.527385446201;6368.5860419072615;6403.391677790602;6423.325550817284;6433.131527243762;6461.256955450183;6484.1246431731115;6556.541042127005;6582.341765823379;6583.407493325623;6415.326390729093;6447.290798012121;6449.419552240321;6450.483961282992;6475.158229430144 03:09:59 INFO - PID 1652 | |1;ext.html;652.3411354531922;773.7812944612238;810.5622963599657;1194.4744312502498;1261.123519154187;37549.24901501968;38279.617203827955;38328.61671383285;38352.808235958815;38411.03972400688;38218.61781382185;38664.806675966654;38734.22531549367;38747.806260968726;38786.8363949082;7706.229377062305;8242.876356854622;8465.238128568428;8469.66121355148;8474.152584741538;7151.928480715178;8303.294219991309;8396.286315663176;8410.453320534145;8423.15768423159;8369.414141010131;8421.621027053798;8429.873551896726;8438.409311348209;8460.069392366835;8371.790705232357;8393.454425462325;8403.07566167721;8424.115467875847;8426.536540490271;8079.353651707908;8143.796405089861;8145.674173033071;8150.470219435718;8152.75541733743;35634.93095207148;35718.642813571794;35852.5184351956;36007.09982250439;36090.27819443597;3572.2676851245433;3616.27674465108;3617.240379520304;3617.6924961378313;3621.7826930384263;29531.55702664462;29669.703302967126;29676;29720.10839674813;29727.851360743272;2986.925326866846;3066.6320041594927;3068.2176045108577;3075.8769649214005;3094.071778466451;233908;234169;267918.66040669865;286042.1395786057;287042;45663;45695.54304456979;45770.08459830785;45795.77102114468;45881.77059114683;47650.285245721345;48046.27930581044;48280.27579586309;48303.75848120737;48322;11544.59593914209;11552.884471155348;13588.796168057486;13749.587512374645;13758.862411375958;11306.434678266054;11314.264572802742;11319.094472442184;11327;11330.773384532264;10345.637902673521;10423.21825863048;12868.163569367962;12933.741325173443;13009.284489353176;5948.553858460633;5954.285485741739;5962.37395073511;5990.610610310316;6035.939640603538;1005.9346142500714;1011.8937511561327;1023.2581378500528;1044.8432735089646;1061.1828891753462;23307;24441.338698206186;24504.019839206234;24504.01983920659;24510.387240319134;1019.2202964731986;1031.6750223679517;1118.8545489086366;1137.5165554639245;1148.5979907032472;9380.530973451436;10162.745931351628;12182.695432614079;12237.694057648452;12343.13598048146;275575;277496;277978;278360;278522;51122.74438627857;51377;51708.48291517112;51738.741306293225;52600;1503.7218114648824;1546.3428043081644;1547.7833103365538;1549.5273941447967;1563.4684207369548;20056.59886802285;22614.773852261926;23798.40503987345;23947.76052239525;24043;35987.100322492144;36106.45840312345;36163.638363616024;36196.63803362038;36292.09269768171;260980;261417;261455;262403;262486;31703.207419814687;32210.516842246918;32296;33268.66731332656;33358.66641333556;41884.16231675288;43756.34365484457;43758;43893;44162.779186103864;28601;29394.559081613366;29628.111156665334;30684.23289417693;30790.076297711104;29556.96550620718;30100.548491772202;30391.54412683856;30546.84726576353;30710.232244194074;35326;36626.63373366339;36939.26121477609;37062.814685926394;37095.814520927226;29922.850385747934;30116.849415752782;30704;30726.539101913044;31053.8447307762;20586.58826823485;22204.555908882056;23858.284251472483;24078.03687852466;24291.63562546528 03:10:00 INFO - PID 1652 | |2;jquery.html;374.9475073489711;387.79059307973694;419.73976134796413;428.25056151734464;456.392997313573;19627.60744785107;21076.789232107694;21563.706177629352;21934.341969740894;21980;22090.226842060532;22606.208782692567;23112.07551697934;23201.955911983958;23242.88378558109;5842.298924129111;6407.967960160193;6411.711472983704;6441.484681225515;6447.419732224099;6184.319724830265;6286.277078136027;6322.6838658067145;6348.428641422271;6374;6288.528360372968;6289.7169627366875;6294.055891616271;6294.181756371665;6298.559100862942;6093.573449858467;6193.442590166884;6269.686515674221;6270.65511396873;6289.779857705004;5573.5819813514145;5582.609217354787;5646.153077038436;5647.774089036434;5650.350209725893;21915.561688766134;27570.759315830837;27719;27901.441971160464;28127.437451251066;2139.2619546256674;2215.9335219943428;2229.2086309360106;2230.888455577231;2233.4416395901026;18029.7295540567;18123.54691132732;18483;18513.7222941656;18548.814511854845;2141.764405915348;2150.6451435513122;2186.365953873375;2187.628103222456;2188.6758511100716;142210.28894855562;160117;164827;168205;168331.47502787344;12590.937045314899;12991.610251692464;13026;13039.478420863059;13084.214947103204;16604.003759774452;16909.83090169107;16933.407330743612;16960.660786784196;16978.830211697972;8881.289496840238;9151.267898568232;9177.036411176863;9272.119148681;9285.350025498285;7031.855284138284;8533.146685331541;8664.436811607226;8664.48013119215;8790.033096359395;6698.4306333961185;8067.677292908335;8210;8214.507129572245;8294.419390642717;1751.544598404424;1848.6949653307054;1856.8793028453108;1930.2086144680895;2407.3139155340996;829.289634873922;885.2519620920312;892.4154678685471;960.7694153403133;996.2329006664828;17884.821151788576;17938.641227175383;20764.896175519025;20961.89519052395;21139.47151321229;973.600823662295;981.5877331520778;995.9651412200539;1002.754325190333;1007.7228762090338;9585.712428627014;10008.999100090079;10413.583456661649;10584.100351470057;10642.308249963728;557855;558930;560053;562762;565009;118880;122651;123503;124629.37685311516;135109;1073;1136.9829452558217;1156.444906444911;1170.0931777872047;1176.6293617510457;16928.915355423145;17403.47789566315;17777.46667599974;17788.733169002215;17922.283108675507;51860.74069629628;51967.87708107283;52369.167079152045;52493.11287879203;53146;53477;70996;71616.56766864737;72209.638951807;72610.27389726035;7263.34629883313;8774.561271936505;8876.733697989072;8930.86603700932;9103.544822758968;12380.62858114258;13536.526221582197;13878.861211387757;14295.35670894833;14376.28118594087;10629.94685026601;11164.218504704593;11192.608258711232;11588.536458541565;12050.939745301568;10937.507812148631;10952.452377381258;11091.279066860629;11183.72040698989;11225.438728063727;10973.927300355594;11334.376609286568;11613.070954323803;11901.642950711492;12257.387130643254;8389.286910612665;8581.828363432822;8608.48349099056;8700.434471759316;8834.337424693302;10867.891321086689;11238.83141752858;11324.32054076758;11351.458622742739;11403.657890263305 03:10:00 INFO - PID 1652 | |3;mootools.html;1175.25958646053;1443.7800058950197;1450.7026059657771;1457.3587621446563;1468.559432170349;11519.942400287997;11918.582849600265;12080.939595302023;12147.453364598592;12487;12128.332941688204;12153.210041347305;12350.691232719186;12365.690857728561;12382.442790074445;2545.4018305698146;2595.5587550116525;2596.662433883602;2597.0780372967606;2599.129291687287;2518.5718427867305;2554.961675574868;2568.704598971114;2571.54997875372;2572.961405578918;2504.0734928076636;2544.8473091614565;2551.1581178211145;2553.00432831196;2553.5403627347164;2503.336615796806;2512.208654273907;2525.684289463817;2526.6967963844454;2530.607755797845;2483.118492935;2495.950080998388;2509.1092662105057;2563.9871800641063;2582.289870285668;11464.828027579593;11507.884921150766;11646.941765291203;11682.065434765198;11689.357085360301;5234.581233501311;6650.50121240904;6664.200295964467;6668.433183179387;6674;4023.6579890709327;4183.226103170923;4249.150169965988;4252.936205956913;4261.2329780639475;1027.4554486122292;1184.413715210976;1225.8774122587758;1228.2753175626424;1250.2436026204107;15417.922910385489;15761.448349307719;16332.591685207846;16668.166591670368;16704.83295167057;5971.940280597225;6003.669798161143;6134.202553667995;6159.84600384994;6199.132121502993;13952.511662091778;13991.930040349733;14054.437822486985;14084.15495070299;14095.36570854314;6605.438537724252;6714.630695311707;6723.932760672428;6724.495662825308;6738.8652226956165;6049.21360223177;6065.120557519135;6133.26400831903;6137.1101190328045;6198.876022479525;4634.745089020069;4684.344191813149;4814.494478079821;4844.491328410537;4872.074305881871;1194.9581764638197;1255.4789762248747;1421.1402101728306;1445.768677011694;1455.3378212913099;792.1959211400433;798.9414026415023;812.959352032396;820.1306614988132;836.8493671139254;5928.614640048383;7186.8203294917;7353.742619008309;7401.703931842666;7435.665395057235;910.2717825739383;940.849464085743;942.0955882352881;956.7034219392011;959.9248841297791;3384.966150338514;4008.719389642697;4321.913561728811;4430.712003719748;4594.839180628662;11499.942500287612;14480.203588802506;14903.180325082008;15126.168060756763;15139;14740.852591473948;14991.550253492413;14996.850031499764;15029.549113526611;15031.624209394853;7360.263973602597;8395.244428001388;8521.105283945097;8543.444676096035;8585.227329540372;6256.7810126645345;7142.107236595426;7173.784786456415;7185.353318201387;7195.532290401107;11417.972382485616;12706.30115343665;12797.680057998625;12948.870511295143;12991.155574887602;12736.74526509483;12965.481380744664;12966.157199781985;13141.277229752455;13270.469181232642;9367.250619950524;9440.244780417688;9465.905340946778;9477.194438472807;9590.08894155054;10786.298890572089;10900.400477973788;10901.182411319025;10901.454927253764;10921.126309895031;9059.411138275991;9177.908220917705;9276.489793061446;9324;9439.858402123837;8989.820203596022;9041.457512549268;9106.726798196065;9142.040085790895;9224.03147669485;8204.671813127408;8236.546989915612;8261.297789687944;8337.708180213664;8358.45670031446;5319.627626066138;5559.916601250903;6014.518838492998;6233.345498722569;6363.459105976044;5774.220480235283;6132.356102609162;6302.65335406557;6352.142460767737;6386.712597933197 03:10:00 INFO - PID 1652 | |4;prototype.html;390.25656125081713;390.3559127439724;393.65358484533607;397.7156333221746;402.53306164848783;5874.588778785476;6243.188385509883;6329.7845753986085;6334.968325158379;6341.175647165867;5858.73635686394;6141.692915354237;6153.81538553843;6176.413240742122;6177.876442471148;3418.2992486540325;4580.862574122782;4675.7895894684825;4700.788464519088;4712.835050773224;4127.195196936598;4460.219461594224;4712.64655150865;4738.597219236369;4746.667733258673;3693.8522459101605;4124.42258083867;4610.700304480215;4657.951960808825;4658.720476771405;4006.599340065999;4527.96626260403;4630.398048253739;4642.303654451843;4662.277347011219;4074.572169922175;4074.5925407459313;4101.405296232029;4103.343465045578;4125.29869922114;5806.390329015477;6058.96970515149;6104.206453161105;6106.847328816771;6128.264608246995;5368.758405871745;5371.495981125291;5433.402325744165;5440.91838622421;5472.206530053159;6414.967925160391;6554.9512078067755;6710.362515561014;6759.695813688396;6760.42536384408;4043.353063509824;4430.601245887853;4436.755978421185;4437.755923424211;4439.689221754478;5196.2592531502;5782.9012487627215;6415.839604009844;6990.720371185146;7118.39493643041;4362.062156636339;4421.7398041558645;4426.822927082945;4463.575960283768;4464.151811155935;3813.2945405100145;4544.909221786747;4545.909081818345;4551.904704800701;4570.520095390003;5212.8957420851375;5399.78400863961;5413.269208656781;5419.3225846769155;5452.40023597404;5948.8810223796145;6114.327423983358;6851.657417129124;6872.158665134595;6874.556343167891;1487.248939285654;1523.8095238095239;1569.1761825041945;1570.1521178563448;1579.155151993678;1009.5154325923597;1009.7273736091214;1013.5641674080069;1014.7666036811427;1028.2314419198965;5140.485951404904;6463.7737679181;6467.676616169173;6514.609123452608;6539.967300163468;5798.6810725410505;7067.717291308393;7071.646417679095;7130.144382674112;7132.964335178291;6272.09054687077;6335.4931605472375;6394.1687580614225;6407.372977079375;6415.326390728999;3068.401661675952;3088.6139232595924;3142.638596561379;3671.155634204148;3698.389765688631;5354.491323324278;6264.59280146789;6691.5650482718465;7146.285371462812;7244.601546914865;6274.458844168119;6665.666716664147;6952.304769523007;7259.310365515267;7302.96348518254;5456.645318054314;6255.060121742774;7200.711971521186;7207;7258.419326453976;6159.599626024294;6512.446442052478;6773.89839152423;7003.59928014389;7046.541974771623;5439.728013599383;6044.818655440344;6834.863302733818;6835.350641689031;6948.20095688982;6922.480813939075;7092.397146242627;7168.211496735459;7308.671109800178;7330.560166390033;4480.439945006874;4511.661625378044;4519.728816271035;4537.753811863149;4541.250693635645;6877.243503214542;6983.930160698328;7061.187963384071;7132.145642132979;7141.53580017318;5821.94178058231;6203.472704820141;6392.137061496638;6819.761308354184;6823.488238382042;6424.678766061772;6488.772892948724;6721;6780.220274668476;6823.249442561413;5001.024800164078;6245.750169993331;6564.4748420127235;6616;6704.7988560343265;6700.590390679482;6707.664616769044;6725.361090696376;6803.319668033157;6821.113255276782;6586.209654841449;6596.868062638624;6609.537332386687;6634.33656634352;6675.933240667531 03:10:00 INFO - PID 1652 | |5;yui.html;821.2608652213012;836.5858899844578;838.2623291503477;857;868.1318681318681;16346;17001.064941428216;17027.82972170278;19274.807251927476;19831.305904293353;15409.383624655;16739.83260167398;17707.46877593674;18056.27774889006;18983.81016189838;744.5569885917884;917.2524392619994;917.6421195733669;920.6225447566499;922.1930810540777;951.9381240219363;953.4231789767196;955.3790036476277;958.3004406783052;959.9520023998807;1000.4797505297241;1008.8032833597451;1010.0051449322411;1010.540204207084;1014.4876837197205;1008.0524307151254;1008.702432782331;1008.9293749437543;1009.0010889219693;1009.5961615353843;732.3921145449317;737.3732327521617;745.1430854517295;745.4334705623711;746.0301607909696;13158.07893447459;17330.913345433317;17340.91329543357;17378.217980190922;17394.304227831;14027.368768405344;17150.142492875308;17323.307067717276;17541.912290438595;17660.293588256456;14407.991440599162;14608.269586520631;14770.852291477162;14796.260186990607;14798.48205312809;3444.2422667013216;3531.346700860349;3546.716262698978;3557.3774589447003;3559.394902866519;123843;141279;143539.28230358887;144677;144802;13121.278329691959;13357.599272021855;13373.799393009112;13504.257265850472;13542.322883855964;25720.485590288088;25778.613320800203;26207.344816379744;26229.475410491683;26390.208293751217;7803.492772969739;7988.680452781824;8051.838963220703;8052.798680032929;8125.9187408126345;3897.395903634941;3955.6439920406724;3966.3852102924093;3968.543617483969;3976.701747368959;4201.6008479194425;4210.347396153601;4280.636145927631;4287.678424118204;4434.7560884151035;927.2535608834818;945.6170251048336;951.5622813505727;955.2739917662626;957.712686194139;543.4701315796021;648.1282674802477;651.5211319680031;664.1797380235405;685.7120009595982;10888.257878739521;11964.341961192218;12192;12199.634010979686;12215.69460763488;634.8888944434706;646.8253571535753;648.1735786871682;656.7963931181349;659.0147729144959;5413.810516631899;5652.406497317805;5658.896515179583;5734.569907256889;5744.942550574524;132905;135050;136575;137437;138937;119519.20721189189;120299.39850300693;120307;121510;125291;96167;107581.17728772387;107610.46194769132;108107.37838932422;108908.4554577222;10884.346939183675;11247.550097995989;11286.830697539708;11293.548258069584;11483.081353491534;45846.62460126202;45986.08027839357;46212.07575848531;46234;46312.30531542097;59337.703311483165;59783;59940.40059599523;60324;61287.38712612817;12297.50809967627;14425.927870360581;14518;14816.851831481978;14873.405063797327;15889.682206355577;16092.517224482815;16192.68225858667;16228.51314460568;16236.51290461288;11229.71925701864;14693.55919322422;14927.776083358976;15141.848581514043;15242;12258.816117758062;14749.705005900036;14781.260936953324;14930.954833161575;15079.698406031599;14449.060811047248;14591.781123282946;14726.410943562567;14792.11247325164;14868.925655371653;10039.698809035448;10439.634612788515;10449.58201671955;10479.42363170033;10626.681199564026;10293.330933489298;11238.325700458;11376.601818936297;11720.23818451832;11770.117241206772 03:10:00 INFO - PID 1652 | __end_tp_report 03:10:00 INFO - PID 1652 | __start_cc_report 03:10:00 INFO - PID 1652 | _x_x_mozilla_cycle_collect,406 03:10:00 INFO - PID 1652 | __end_cc_report 03:10:00 INFO - PID 1652 | __startTimestamp1495706999993__endTimestamp 03:10:00 INFO - PID 1652 | 03:10:00 INFO - PID 1652 | ------- Summary: start ------- 03:10:00 INFO - PID 1652 | Number of tests: 6 03:10:00 INFO - PID 1652 | 03:10:00 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6140.24 Median:6385.00 stddev:743.23 (11.6%) stddev-sans-first:704.91 03:10:00 INFO - PID 1652 | Values: 3051.4 3586.8 3605.8 3618.0 3630.6 6142.9 6164.4 6240.7 6247.9 6256.5 6183.5 6202.8 6204.0 6247.0 6304.2 5330.4 5418.0 5432.4 5434.7 5449.5 5478.1 5505.2 5518.4 5518.8 5539.7 5389.1 5436.2 5449.2 5452.7 5504.5 4522.8 5351.5 5427.2 5469.2 5498.3 4755.0 5419.9 5465.6 5476.4 5487.9 4938.4 5729.5 6100.0 6185.0 6194.4 6461.2 6526.4 6561.7 6659.6 6662.6 6808.9 6811.9 6816.5 6822.9 6829.6 6326.6 6343.7 6348.9 6353.0 6358.3 6675.6 6745.7 6826.4 6866.9 6929.2 6321.2 6338.2 6440.7 6520.3 6595.4 6752.4 6755.7 6762.5 6794.4 6807.3 6622.1 6649.6 6662.9 6670.3 6677.4 6454.8 6514.5 6589.8 6635.8 6659.4 5353.6 5375.8 5388.7 5390.5 5427.9 4782.2 4815.7 4831.6 4844.2 4854.2 5658.7 6171.5 6295.1 6316.9 6355.7 6651.7 6717.5 6762.4 6769.6 6782.8 6341.8 6346.3 6357.2 6365.5 6385.0 5761.9 5763.0 5934.0 5965.4 5997.1 6761.0 6827.4 6884.8 6894.7 6917.3 6686.7 6782.8 6787.4 6798.5 6801.6 5803.0 6907.1 6928.3 6932.9 6980.9 5554.4 6454.9 6545.3 6553.3 6669.1 6665.0 6751.4 6754.0 6766.2 6767.0 6273.8 6423.8 6540.0 6557.4 6594.5 5353.5 6519.2 6522.1 6524.4 6531.8 6301.5 6368.6 6403.4 6423.3 6433.1 6461.3 6484.1 6556.5 6582.3 6583.4 6415.3 6447.3 6449.4 6450.5 6475.2 03:10:00 INFO - PID 1652 | 03:10:00 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:41794.11 Median:31378.53 stddev:69905.98 (222.8%) stddev-sans-first:70033.83 03:10:00 INFO - PID 1652 | Values: 652.3 773.8 810.6 1194.5 1261.1 37549.2 38279.6 38328.6 38352.8 38411.0 38218.6 38664.8 38734.2 38747.8 38786.8 7706.2 8242.9 8465.2 8469.7 8474.2 7151.9 8303.3 8396.3 8410.5 8423.2 8369.4 8421.6 8429.9 8438.4 8460.1 8371.8 8393.5 8403.1 8424.1 8426.5 8079.4 8143.8 8145.7 8150.5 8152.8 35634.9 35718.6 35852.5 36007.1 36090.3 3572.3 3616.3 3617.2 3617.7 3621.8 29531.6 29669.7 29676.0 29720.1 29727.9 2986.9 3066.6 3068.2 3075.9 3094.1 233908.0 234169.0 267918.7 286042.1 287042.0 45663.0 45695.5 45770.1 45795.8 45881.8 47650.3 48046.3 48280.3 48303.8 48322.0 11544.6 11552.9 13588.8 13749.6 13758.9 11306.4 11314.3 11319.1 11327.0 11330.8 10345.6 10423.2 12868.2 12933.7 13009.3 5948.6 5954.3 5962.4 5990.6 6035.9 1005.9 1011.9 1023.3 1044.8 1061.2 23307.0 24441.3 24504.0 24504.0 24510.4 1019.2 1031.7 1118.9 1137.5 1148.6 9380.5 10162.7 12182.7 12237.7 12343.1 275575.0 277496.0 277978.0 278360.0 278522.0 51122.7 51377.0 51708.5 51738.7 52600.0 1503.7 1546.3 1547.8 1549.5 1563.5 20056.6 22614.8 23798.4 23947.8 24043.0 35987.1 36106.5 36163.6 36196.6 36292.1 260980.0 261417.0 261455.0 262403.0 262486.0 31703.2 32210.5 32296.0 33268.7 33358.7 41884.2 43756.3 43758.0 43893.0 44162.8 28601.0 29394.6 29628.1 30684.2 30790.1 29557.0 30100.5 30391.5 30546.8 30710.2 35326.0 36626.6 36939.3 37062.8 37095.8 29922.9 30116.8 30704.0 30726.5 31053.8 20586.6 22204.6 23858.3 24078.0 24291.6 03:10:00 INFO - PID 1652 | 03:10:00 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:35436.42 Median:2232.17 stddev:94987.53 (4255.4%) stddev-sans-first:95217.51 03:10:00 INFO - PID 1652 | Values: 374.9 387.8 419.7 428.3 456.4 19627.6 21076.8 21563.7 21934.3 21980.0 22090.2 22606.2 23112.1 23202.0 23242.9 5842.3 6408.0 6411.7 6441.5 6447.4 6184.3 6286.3 6322.7 6348.4 6374.0 6288.5 6289.7 6294.1 6294.2 6298.6 6093.6 6193.4 6269.7 6270.7 6289.8 5573.6 5582.6 5646.2 5647.8 5650.4 21915.6 27570.8 27719.0 27901.4 28127.4 2139.3 2215.9 2229.2 2230.9 2233.4 18029.7 18123.5 18483.0 18513.7 18548.8 2141.8 2150.6 2186.4 2187.6 2188.7 142210.3 160117.0 164827.0 168205.0 168331.5 12590.9 12991.6 13026.0 13039.5 13084.2 16604.0 16909.8 16933.4 16960.7 16978.8 8881.3 9151.3 9177.0 9272.1 9285.4 7031.9 8533.1 8664.4 8664.5 8790.0 6698.4 8067.7 8210.0 8214.5 8294.4 1751.5 1848.7 1856.9 1930.2 2407.3 829.3 885.3 892.4 960.8 996.2 17884.8 17938.6 20764.9 20961.9 21139.5 973.6 981.6 996.0 1002.8 1007.7 9585.7 10009.0 10413.6 10584.1 10642.3 557855.0 558930.0 560053.0 562762.0 565009.0 118880.0 122651.0 123503.0 124629.4 135109.0 1073.0 1137.0 1156.4 1170.1 1176.6 16928.9 17403.5 17777.5 17788.7 17922.3 51860.7 51967.9 52369.2 52493.1 53146.0 53477.0 70996.0 71616.6 72209.6 72610.3 7263.3 8774.6 8876.7 8930.9 9103.5 12380.6 13536.5 13878.9 14295.4 14376.3 10629.9 11164.2 11192.6 11588.5 12050.9 10937.5 10952.5 11091.3 11183.7 11225.4 10973.9 11334.4 11613.1 11901.6 12257.4 8389.3 8581.8 8608.5 8700.4 8834.3 10867.9 11238.8 11324.3 11351.5 11403.7 03:10:00 INFO - PID 1652 | 03:10:00 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:7197.97 Median:3696.84 stddev:4496.24 (121.6%) stddev-sans-first:4486.07 03:10:00 INFO - PID 1652 | Values: 1175.3 1443.8 1450.7 1457.4 1468.6 11519.9 11918.6 12080.9 12147.5 12487.0 12128.3 12153.2 12350.7 12365.7 12382.4 2545.4 2595.6 2596.7 2597.1 2599.1 2518.6 2555.0 2568.7 2571.5 2573.0 2504.1 2544.8 2551.2 2553.0 2553.5 2503.3 2512.2 2525.7 2526.7 2530.6 2483.1 2496.0 2509.1 2564.0 2582.3 11464.8 11507.9 11646.9 11682.1 11689.4 5234.6 6650.5 6664.2 6668.4 6674.0 4023.7 4183.2 4249.2 4252.9 4261.2 1027.5 1184.4 1225.9 1228.3 1250.2 15417.9 15761.4 16332.6 16668.2 16704.8 5971.9 6003.7 6134.2 6159.8 6199.1 13952.5 13991.9 14054.4 14084.2 14095.4 6605.4 6714.6 6723.9 6724.5 6738.9 6049.2 6065.1 6133.3 6137.1 6198.9 4634.7 4684.3 4814.5 4844.5 4872.1 1195.0 1255.5 1421.1 1445.8 1455.3 792.2 798.9 813.0 820.1 836.8 5928.6 7186.8 7353.7 7401.7 7435.7 910.3 940.8 942.1 956.7 959.9 3385.0 4008.7 4321.9 4430.7 4594.8 11499.9 14480.2 14903.2 15126.2 15139.0 14740.9 14991.6 14996.9 15029.5 15031.6 7360.3 8395.2 8521.1 8543.4 8585.2 6256.8 7142.1 7173.8 7185.4 7195.5 11418.0 12706.3 12797.7 12948.9 12991.2 12736.7 12965.5 12966.2 13141.3 13270.5 9367.3 9440.2 9465.9 9477.2 9590.1 10786.3 10900.4 10901.2 10901.5 10921.1 9059.4 9177.9 9276.5 9324.0 9439.9 8989.8 9041.5 9106.7 9142.0 9224.0 8204.7 8236.5 8261.3 8337.7 8358.5 5319.6 5559.9 6014.5 6233.3 6363.5 5774.2 6132.4 6302.7 6352.1 6386.7 03:10:00 INFO - PID 1652 | 03:10:00 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5390.80 Median:6105.53 stddev:1720.16 (28.2%) stddev-sans-first:1683.54 03:10:00 INFO - PID 1652 | Values: 390.3 390.4 393.7 397.7 402.5 5874.6 6243.2 6329.8 6335.0 6341.2 5858.7 6141.7 6153.8 6176.4 6177.9 3418.3 4580.9 4675.8 4700.8 4712.8 4127.2 4460.2 4712.6 4738.6 4746.7 3693.9 4124.4 4610.7 4658.0 4658.7 4006.6 4528.0 4630.4 4642.3 4662.3 4074.6 4074.6 4101.4 4103.3 4125.3 5806.4 6059.0 6104.2 6106.8 6128.3 5368.8 5371.5 5433.4 5440.9 5472.2 6415.0 6555.0 6710.4 6759.7 6760.4 4043.4 4430.6 4436.8 4437.8 4439.7 5196.3 5782.9 6415.8 6990.7 7118.4 4362.1 4421.7 4426.8 4463.6 4464.2 3813.3 4544.9 4545.9 4551.9 4570.5 5212.9 5399.8 5413.3 5419.3 5452.4 5948.9 6114.3 6851.7 6872.2 6874.6 1487.2 1523.8 1569.2 1570.2 1579.2 1009.5 1009.7 1013.6 1014.8 1028.2 5140.5 6463.8 6467.7 6514.6 6540.0 5798.7 7067.7 7071.6 7130.1 7133.0 6272.1 6335.5 6394.2 6407.4 6415.3 3068.4 3088.6 3142.6 3671.2 3698.4 5354.5 6264.6 6691.6 7146.3 7244.6 6274.5 6665.7 6952.3 7259.3 7303.0 5456.6 6255.1 7200.7 7207.0 7258.4 6159.6 6512.4 6773.9 7003.6 7046.5 5439.7 6044.8 6834.9 6835.4 6948.2 6922.5 7092.4 7168.2 7308.7 7330.6 4480.4 4511.7 4519.7 4537.8 4541.3 6877.2 6983.9 7061.2 7132.1 7141.5 5821.9 6203.5 6392.1 6819.8 6823.5 6424.7 6488.8 6721.0 6780.2 6823.2 5001.0 6245.8 6564.5 6616.0 6704.8 6700.6 6707.7 6725.4 6803.3 6821.1 6586.2 6596.9 6609.5 6634.3 6675.9 03:10:00 INFO - PID 1652 | 03:10:00 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:24535.91 Median:17088.99 stddev:38152.23 (223.3%) stddev-sans-first:38217.71 03:10:00 INFO - PID 1652 | Values: 821.3 836.6 838.3 857.0 868.1 16346.0 17001.1 17027.8 19274.8 19831.3 15409.4 16739.8 17707.5 18056.3 18983.8 744.6 917.3 917.6 920.6 922.2 951.9 953.4 955.4 958.3 960.0 1000.5 1008.8 1010.0 1010.5 1014.5 1008.1 1008.7 1008.9 1009.0 1009.6 732.4 737.4 745.1 745.4 746.0 13158.1 17330.9 17340.9 17378.2 17394.3 14027.4 17150.1 17323.3 17541.9 17660.3 14408.0 14608.3 14770.9 14796.3 14798.5 3444.2 3531.3 3546.7 3557.4 3559.4 123843.0 141279.0 143539.3 144677.0 144802.0 13121.3 13357.6 13373.8 13504.3 13542.3 25720.5 25778.6 26207.3 26229.5 26390.2 7803.5 7988.7 8051.8 8052.8 8125.9 3897.4 3955.6 3966.4 3968.5 3976.7 4201.6 4210.3 4280.6 4287.7 4434.8 927.3 945.6 951.6 955.3 957.7 543.5 648.1 651.5 664.2 685.7 10888.3 11964.3 12192.0 12199.6 12215.7 634.9 646.8 648.2 656.8 659.0 5413.8 5652.4 5658.9 5734.6 5744.9 132905.0 135050.0 136575.0 137437.0 138937.0 119519.2 120299.4 120307.0 121510.0 125291.0 96167.0 107581.2 107610.5 108107.4 108908.5 10884.3 11247.6 11286.8 11293.5 11483.1 45846.6 45986.1 46212.1 46234.0 46312.3 59337.7 59783.0 59940.4 60324.0 61287.4 12297.5 14425.9 14518.0 14816.9 14873.4 15889.7 16092.5 16192.7 16228.5 16236.5 11229.7 14693.6 14927.8 15141.8 15242.0 12258.8 14749.7 14781.3 14931.0 15079.7 14449.1 14591.8 14726.4 14792.1 14868.9 10039.7 10439.6 10449.6 10479.4 10626.7 10293.3 11238.3 11376.6 11720.2 11770.1 03:10:00 INFO - PID 1652 | -------- Summary: end -------- 03:10:00 INFO - PID 1652 | 03:10:00 INFO - TEST-INFO | 1652: exit 0 03:10:00 INFO - TEST-OK | dromaeo_css | took 1143580ms 03:10:00 INFO - TEST-START | kraken 03:10:00 INFO - operating with platform_type : mac_ 03:10:00 INFO - Initialising browser for kraken test... 03:10:00 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmptTMzKz/profile 03:10:00 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 03:10:33 INFO - TEST-INFO | 1663: killed by SIGTERM 03:10:33 INFO - Browser initialized. 03:10:33 INFO - Running cycle 1/1 for kraken test... 03:10:33 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/T/tmptTMzKz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:10:36 INFO - PID 1667 | 2017-05-25 03:10:36.036 plugin-container[1668:10741] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 03:10:36 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:10:36 INFO - PID 1667 | 2017-05-25 03:10:36.036 plugin-container[1668:10741] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 03:10:36 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:10:36 INFO - PID 1667 | 2017-05-25 03:10:36.036 plugin-container[1668:10741] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:10:36 INFO - PID 1667 | 2017-05-25 03:10:36.538 plugin-container[1669:10789] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a43, name = 'com.apple.tsm.portname' 03:10:36 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:10:36 INFO - PID 1667 | 2017-05-25 03:10:36.538 plugin-container[1669:10789] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 03:10:36 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:10:36 INFO - PID 1667 | 2017-05-25 03:10:36.539 plugin-container[1669:10789] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:10:45 INFO - PID 1667 | RSS: Main: 245510144 03:10:45 INFO - PID 1667 | 03:10:48 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 03:10:49 INFO - PID 1667 | RSS: Main: 260276224 03:10:49 INFO - PID 1667 | 03:10:52 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 03:10:52 INFO - PID 1667 | RSS: Main: 269332480 03:10:52 INFO - PID 1667 | 03:10:56 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 03:10:56 INFO - PID 1667 | RSS: Main: 271970304 03:10:56 INFO - PID 1667 | 03:10:58 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 03:10:59 INFO - PID 1667 | RSS: Main: 274472960 03:10:59 INFO - PID 1667 | 03:11:01 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:11:02 INFO - PID 1667 | RSS: Main: 274808832 03:11:02 INFO - PID 1667 | 03:11:04 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 03:11:05 INFO - PID 1667 | RSS: Main: 279040000 03:11:05 INFO - PID 1667 | 03:11:08 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 03:11:09 INFO - PID 1667 | RSS: Main: 281948160 03:11:09 INFO - PID 1667 | 03:11:12 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 03:11:12 INFO - PID 1667 | RSS: Main: 281055232 03:11:12 INFO - PID 1667 | 03:11:14 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:11:14 INFO - PID 1667 | RSS: Main: 280690688 03:11:14 INFO - PID 1667 | 03:11:16 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:11:17 INFO - PID 1667 | RSS: Main: 280547328 03:11:17 INFO - PID 1667 | 03:11:19 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:11:20 INFO - PID 1667 | RSS: Main: 280551424 03:11:20 INFO - PID 1667 | 03:11:23 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:11:23 INFO - PID 1667 | RSS: Main: 280178688 03:11:23 INFO - PID 1667 | 03:11:26 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:11:27 INFO - PID 1667 | RSS: Main: 280346624 03:11:27 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 03:11:29 INFO - PID 1667 | RSS: Main: 282193920 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | __start_tp_report 03:11:29 INFO - PID 1667 | _x_x_mozilla_page_load 03:11:29 INFO - PID 1667 | _x_x_mozilla_page_load_details 03:11:29 INFO - PID 1667 | |i|pagename|runs| 03:11:29 INFO - PID 1667 | |0;ai-astar;104;114;117;112;116;115;113;139;168;151 03:11:29 INFO - PID 1667 | |1;audio-beat-detection;132;112;111;152;109;135;152;151;136;111 03:11:29 INFO - PID 1667 | |2;audio-dft;200;204;198;215;214;220;197;197;201;200 03:11:29 INFO - PID 1667 | |3;audio-fft;77;56;57;86;61;79;88;90;93;59 03:11:29 INFO - PID 1667 | |4;audio-oscillator;86;71;82;70;72;95;106;72;75;103 03:11:29 INFO - PID 1667 | |5;imaging-gaussian-blur;92;92;91;94;89;91;90;98;91;90 03:11:29 INFO - PID 1667 | |6;imaging-darkroom;97;99;97;124;100;97;98;98;98;98 03:11:29 INFO - PID 1667 | |7;imaging-desaturate;91;96;90;92;93;105;147;140;90;97 03:11:29 INFO - PID 1667 | |8;json-parse-financial;51;49;50;49;50;50;54;52;52;51 03:11:29 INFO - PID 1667 | |9;json-stringify-tinderbox;54;56;62;62;83;90;85;55;57;57 03:11:29 INFO - PID 1667 | |10;stanford-crypto-aes;104;85;89;87;87;85;89;89;147;89 03:11:29 INFO - PID 1667 | |11;stanford-crypto-ccm;137;155;140;136;124;128;138;128;135;133 03:11:29 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;186;182;184;190;186;181;185;184;203;235 03:11:29 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;69;69;68;67;66;67;63;67;70;68 03:11:29 INFO - PID 1667 | __end_tp_report 03:11:29 INFO - PID 1667 | __start_cc_report 03:11:29 INFO - PID 1667 | _x_x_mozilla_cycle_collect,1083 03:11:29 INFO - PID 1667 | __end_cc_report 03:11:29 INFO - PID 1667 | __startTimestamp1495707089500__endTimestamp 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | ------- Summary: start ------- 03:11:29 INFO - PID 1667 | Number of tests: 14 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:124.90 Median:116.50 stddev:20.66 (17.7%) stddev-sans-first:20.48 03:11:29 INFO - PID 1667 | Values: 104.0 114.0 117.0 112.0 116.0 115.0 113.0 139.0 168.0 151.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:135.50 stddev:18.14 (13.4%) stddev-sans-first:19.23 03:11:29 INFO - PID 1667 | Values: 132.0 112.0 111.0 152.0 109.0 135.0 152.0 151.0 136.0 111.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:204.60 Median:202.50 stddev:8.49 (4.2%) stddev-sans-first:8.84 03:11:29 INFO - PID 1667 | Values: 200.0 204.0 198.0 215.0 214.0 220.0 197.0 197.0 201.0 200.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:74.60 Median:82.50 stddev:14.89 (18.0%) stddev-sans-first:15.76 03:11:29 INFO - PID 1667 | Values: 77.0 56.0 57.0 86.0 61.0 79.0 88.0 90.0 93.0 59.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:73.50 stddev:13.75 (18.7%) stddev-sans-first:14.55 03:11:29 INFO - PID 1667 | Values: 86.0 71.0 82.0 70.0 72.0 95.0 106.0 72.0 75.0 103.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:91.50 stddev:2.57 (2.8%) stddev-sans-first:2.73 03:11:29 INFO - PID 1667 | Values: 92.0 92.0 91.0 94.0 89.0 91.0 90.0 98.0 91.0 90.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:98.00 stddev:8.28 (8.4%) stddev-sans-first:8.67 03:11:29 INFO - PID 1667 | Values: 97.0 99.0 97.0 124.0 100.0 97.0 98.0 98.0 98.0 98.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:104.10 Median:91.50 stddev:21.30 (23.3%) stddev-sans-first:22.06 03:11:29 INFO - PID 1667 | Values: 91.0 96.0 90.0 92.0 93.0 105.0 147.0 140.0 90.0 97.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:1.55 (3.0%) stddev-sans-first:1.64 03:11:29 INFO - PID 1667 | Values: 51.0 49.0 50.0 49.0 50.0 50.0 54.0 52.0 52.0 51.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:62.00 stddev:14.08 (22.7%) stddev-sans-first:14.24 03:11:29 INFO - PID 1667 | Values: 54.0 56.0 62.0 62.0 83.0 90.0 85.0 55.0 57.0 57.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:95.10 Median:88.00 stddev:19.02 (21.6%) stddev-sans-first:19.90 03:11:29 INFO - PID 1667 | Values: 104.0 85.0 89.0 87.0 87.0 85.0 89.0 89.0 147.0 89.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:135.40 Median:136.50 stddev:8.57 (6.3%) stddev-sans-first:9.07 03:11:29 INFO - PID 1667 | Values: 137.0 155.0 140.0 136.0 124.0 128.0 138.0 128.0 135.0 133.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.60 Median:186.00 stddev:16.47 (8.9%) stddev-sans-first:17.35 03:11:29 INFO - PID 1667 | Values: 186.0 182.0 184.0 190.0 186.0 181.0 185.0 184.0 203.0 235.0 03:11:29 INFO - PID 1667 | 03:11:29 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.96 (2.9%) stddev-sans-first:1.99 03:11:29 INFO - PID 1667 | Values: 69.0 69.0 68.0 67.0 66.0 67.0 63.0 67.0 70.0 68.0 03:11:29 INFO - PID 1667 | -------- Summary: end -------- 03:11:29 INFO - PID 1667 | 03:11:30 INFO - TEST-INFO | 1667: exit 0 03:11:30 INFO - TEST-OK | kraken | took 89854ms 03:11:30 INFO - SUITE-END | took 1233s 03:11:30 INFO - Completed test suite (00:20:33) 03:11:30 INFO - javascript benchmark 03:11:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7862.044734890162, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3051.389722055589, 3586.820658967051, 3605.819709014552, 3617.9638203617974, 3630.5824830144534, 6142.87714245716, 6164.383561643839, 6240.7191676374505, 6247.937520624792, 6256.530760192986, 6183.505319574435, 6202.782902598398, 6204.0, 6247.0, 6304.180456540644, 5330.3603567571945, 5417.972910135444, 5432.375276843174, 5434.701091439969, 5449.536789372898, 5478.123500239963, 5505.174223866412, 5518.392976772552, 5518.834434966937, 5539.695316757577, 5389.083855744494, 5436.184572314145, 5449.155380915964, 5452.727363631842, 5504.4770746778995, 4522.796474158671, 5351.491608297206, 5427.213054107132, 5469.206964990054, 5498.3127109111365, 4754.952450475485, 5419.864503387407, 5465.562754979592, 5476.370217424967, 5487.945120548823, 4938.432080310774, 5729.538967563286, 6099.976550065598, 6184.96907515464, 6194.380561943814, 6461.19235095613, 6526.412622863918, 6561.671916404209, 6659.567128136656, 6662.600243985376, 6808.931910680879, 6811.931880681179, 6816.454683625348, 6822.9317706823285, 6829.590224586541, 6326.620402775849, 6343.651099189497, 6348.936510634927, 6352.951762959106, 6358.300586935434, 6675.5660882043485, 6745.662716864137, 6826.385625293748, 6866.931330686629, 6929.1684997800585, 6321.241451025814, 6338.207724034496, 6440.677966101676, 6520.315366886503, 6595.373439523238, 6752.3922846944015, 6755.695993680198, 6762.526623136333, 6794.388505034571, 6807.285235050347, 6622.106015687917, 6649.60102393858, 6662.866742665216, 6670.266270710218, 6677.432418244406, 6454.806355809334, 6514.478841692653, 6589.769358072444, 6635.767748128889, 6659.433948114368, 5353.571714262927, 5375.838724838261, 5388.7305634718105, 5390.4879036491475, 5427.891442171134, 4782.210935195723, 4815.7351345676325, 4831.613470922318, 4844.249141383091, 4854.247591623304, 5658.688772117573, 6171.506279497629, 6295.118683384329, 6316.905246421307, 6355.745770169234, 6651.700673469705, 6717.462602991749, 6762.357576030269, 6769.627670478171, 6782.830429239309, 6341.778037768749, 6346.333634968354, 6357.237131544244, 6365.49076073913, 6385.0, 5761.942380576141, 5763.0202865512965, 5934.0, 5965.403459654087, 5997.100434934708, 6761.01965215041, 6827.385535301847, 6884.793456196322, 6894.724211031503, 6917.308269173042, 6686.732530698718, 6782.830429239111, 6787.423069039187, 6798.52410331272, 6801.557898736566, 5802.970985145047, 6907.102076729993, 6928.307169283032, 6932.861342773217, 6980.895286570604, 5554.444555544414, 6454.870902582016, 6545.280019197789, 6553.279139294578, 6669.1330127083165, 6665.0, 6751.392374686302, 6753.986901964548, 6766.18805743314, 6766.951122576009, 6273.780417685359, 6423.8394040147505, 6539.967300163659, 6557.442617377576, 6594.4724422045165, 5353.544948679406, 6519.152510173647, 6522.119513865568, 6524.412802847766, 6531.836704082246, 6301.527385446201, 6368.5860419072615, 6403.391677790602, 6423.325550817284, 6433.131527243762, 6461.256955450183, 6484.1246431731115, 6556.541042127005, 6582.341765823379, 6583.407493325623, 6415.326390729093, 6447.290798012121, 6449.419552240321, 6450.483961282992, 6475.158229430144], "value": 6090.509727343599, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.3411354531922, 773.7812944612238, 810.5622963599657, 1194.4744312502498, 1261.123519154187, 37549.24901501968, 38279.617203827955, 38328.61671383285, 38352.808235958815, 38411.03972400688, 38218.61781382185, 38664.806675966654, 38734.22531549367, 38747.806260968726, 38786.8363949082, 7706.229377062305, 8242.876356854622, 8465.238128568428, 8469.66121355148, 8474.152584741538, 7151.928480715178, 8303.294219991309, 8396.286315663176, 8410.453320534145, 8423.15768423159, 8369.414141010131, 8421.621027053798, 8429.873551896726, 8438.409311348209, 8460.069392366835, 8371.790705232357, 8393.454425462325, 8403.07566167721, 8424.115467875847, 8426.536540490271, 8079.353651707908, 8143.796405089861, 8145.674173033071, 8150.470219435718, 8152.75541733743, 35634.93095207148, 35718.642813571794, 35852.5184351956, 36007.09982250439, 36090.27819443597, 3572.2676851245433, 3616.27674465108, 3617.240379520304, 3617.6924961378313, 3621.7826930384263, 29531.55702664462, 29669.703302967126, 29676.0, 29720.10839674813, 29727.851360743272, 2986.925326866846, 3066.6320041594927, 3068.2176045108577, 3075.8769649214005, 3094.071778466451, 233908.0, 234169.0, 267918.66040669865, 286042.1395786057, 287042.0, 45663.0, 45695.54304456979, 45770.08459830785, 45795.77102114468, 45881.77059114683, 47650.285245721345, 48046.27930581044, 48280.27579586309, 48303.75848120737, 48322.0, 11544.59593914209, 11552.884471155348, 13588.796168057486, 13749.587512374645, 13758.862411375958, 11306.434678266054, 11314.264572802742, 11319.094472442184, 11327.0, 11330.773384532264, 10345.637902673521, 10423.21825863048, 12868.163569367962, 12933.741325173443, 13009.284489353176, 5948.553858460633, 5954.285485741739, 5962.37395073511, 5990.610610310316, 6035.939640603538, 1005.9346142500714, 1011.8937511561327, 1023.2581378500528, 1044.8432735089646, 1061.1828891753462, 23307.0, 24441.338698206186, 24504.019839206234, 24504.01983920659, 24510.387240319134, 1019.2202964731986, 1031.6750223679517, 1118.8545489086366, 1137.5165554639245, 1148.5979907032472, 9380.530973451436, 10162.745931351628, 12182.695432614079, 12237.694057648452, 12343.13598048146, 275575.0, 277496.0, 277978.0, 278360.0, 278522.0, 51122.74438627857, 51377.0, 51708.48291517112, 51738.741306293225, 52600.0, 1503.7218114648824, 1546.3428043081644, 1547.7833103365538, 1549.5273941447967, 1563.4684207369548, 20056.59886802285, 22614.773852261926, 23798.40503987345, 23947.76052239525, 24043.0, 35987.100322492144, 36106.45840312345, 36163.638363616024, 36196.63803362038, 36292.09269768171, 260980.0, 261417.0, 261455.0, 262403.0, 262486.0, 31703.207419814687, 32210.516842246918, 32296.0, 33268.66731332656, 33358.66641333556, 41884.16231675288, 43756.34365484457, 43758.0, 43893.0, 44162.779186103864, 28601.0, 29394.559081613366, 29628.111156665334, 30684.23289417693, 30790.076297711104, 29556.96550620718, 30100.548491772202, 30391.54412683856, 30546.84726576353, 30710.232244194074, 35326.0, 36626.63373366339, 36939.26121477609, 37062.814685926394, 37095.814520927226, 29922.850385747934, 30116.849415752782, 30704.0, 30726.539101913044, 31053.8447307762, 20586.58826823485, 22204.555908882056, 23858.284251472483, 24078.03687852466, 24291.63562546528], "value": 16961.59989924089, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.9475073489711, 387.79059307973694, 419.73976134796413, 428.25056151734464, 456.392997313573, 19627.60744785107, 21076.789232107694, 21563.706177629352, 21934.341969740894, 21980.0, 22090.226842060532, 22606.208782692567, 23112.07551697934, 23201.955911983958, 23242.88378558109, 5842.298924129111, 6407.967960160193, 6411.711472983704, 6441.484681225515, 6447.419732224099, 6184.319724830265, 6286.277078136027, 6322.6838658067145, 6348.428641422271, 6374.0, 6288.528360372968, 6289.7169627366875, 6294.055891616271, 6294.181756371665, 6298.559100862942, 6093.573449858467, 6193.442590166884, 6269.686515674221, 6270.65511396873, 6289.779857705004, 5573.5819813514145, 5582.609217354787, 5646.153077038436, 5647.774089036434, 5650.350209725893, 21915.561688766134, 27570.759315830837, 27719.0, 27901.441971160464, 28127.437451251066, 2139.2619546256674, 2215.9335219943428, 2229.2086309360106, 2230.888455577231, 2233.4416395901026, 18029.7295540567, 18123.54691132732, 18483.0, 18513.7222941656, 18548.814511854845, 2141.764405915348, 2150.6451435513122, 2186.365953873375, 2187.628103222456, 2188.6758511100716, 142210.28894855562, 160117.0, 164827.0, 168205.0, 168331.47502787344, 12590.937045314899, 12991.610251692464, 13026.0, 13039.478420863059, 13084.214947103204, 16604.003759774452, 16909.83090169107, 16933.407330743612, 16960.660786784196, 16978.830211697972, 8881.289496840238, 9151.267898568232, 9177.036411176863, 9272.119148681, 9285.350025498285, 7031.855284138284, 8533.146685331541, 8664.436811607226, 8664.48013119215, 8790.033096359395, 6698.4306333961185, 8067.677292908335, 8210.0, 8214.507129572245, 8294.419390642717, 1751.544598404424, 1848.6949653307054, 1856.8793028453108, 1930.2086144680895, 2407.3139155340996, 829.289634873922, 885.2519620920312, 892.4154678685471, 960.7694153403133, 996.2329006664828, 17884.821151788576, 17938.641227175383, 20764.896175519025, 20961.89519052395, 21139.47151321229, 973.600823662295, 981.5877331520778, 995.9651412200539, 1002.754325190333, 1007.7228762090338, 9585.712428627014, 10008.999100090079, 10413.583456661649, 10584.100351470057, 10642.308249963728, 557855.0, 558930.0, 560053.0, 562762.0, 565009.0, 118880.0, 122651.0, 123503.0, 124629.37685311516, 135109.0, 1073.0, 1136.9829452558217, 1156.444906444911, 1170.0931777872047, 1176.6293617510457, 16928.915355423145, 17403.47789566315, 17777.46667599974, 17788.733169002215, 17922.283108675507, 51860.74069629628, 51967.87708107283, 52369.167079152045, 52493.11287879203, 53146.0, 53477.0, 70996.0, 71616.56766864737, 72209.638951807, 72610.27389726035, 7263.34629883313, 8774.561271936505, 8876.733697989072, 8930.86603700932, 9103.544822758968, 12380.62858114258, 13536.526221582197, 13878.861211387757, 14295.35670894833, 14376.28118594087, 10629.94685026601, 11164.218504704593, 11192.608258711232, 11588.536458541565, 12050.939745301568, 10937.507812148631, 10952.452377381258, 11091.279066860629, 11183.72040698989, 11225.438728063727, 10973.927300355594, 11334.376609286568, 11613.070954323803, 11901.642950711492, 12257.387130643254, 8389.286910612665, 8581.828363432822, 8608.48349099056, 8700.434471759316, 8834.337424693302, 10867.891321086689, 11238.83141752858, 11324.32054076758, 11351.458622742739, 11403.657890263305], "value": 10271.614566057933, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1175.25958646053, 1443.7800058950197, 1450.7026059657771, 1457.3587621446563, 1468.559432170349, 11519.942400287997, 11918.582849600265, 12080.939595302023, 12147.453364598592, 12487.0, 12128.332941688204, 12153.210041347305, 12350.691232719186, 12365.690857728561, 12382.442790074445, 2545.4018305698146, 2595.5587550116525, 2596.662433883602, 2597.0780372967606, 2599.129291687287, 2518.5718427867305, 2554.961675574868, 2568.704598971114, 2571.54997875372, 2572.961405578918, 2504.0734928076636, 2544.8473091614565, 2551.1581178211145, 2553.00432831196, 2553.5403627347164, 2503.336615796806, 2512.208654273907, 2525.684289463817, 2526.6967963844454, 2530.607755797845, 2483.118492935, 2495.950080998388, 2509.1092662105057, 2563.9871800641063, 2582.289870285668, 11464.828027579593, 11507.884921150766, 11646.941765291203, 11682.065434765198, 11689.357085360301, 5234.581233501311, 6650.50121240904, 6664.200295964467, 6668.433183179387, 6674.0, 4023.6579890709327, 4183.226103170923, 4249.150169965988, 4252.936205956913, 4261.2329780639475, 1027.4554486122292, 1184.413715210976, 1225.8774122587758, 1228.2753175626424, 1250.2436026204107, 15417.922910385489, 15761.448349307719, 16332.591685207846, 16668.166591670368, 16704.83295167057, 5971.940280597225, 6003.669798161143, 6134.202553667995, 6159.84600384994, 6199.132121502993, 13952.511662091778, 13991.930040349733, 14054.437822486985, 14084.15495070299, 14095.36570854314, 6605.438537724252, 6714.630695311707, 6723.932760672428, 6724.495662825308, 6738.8652226956165, 6049.21360223177, 6065.120557519135, 6133.26400831903, 6137.1101190328045, 6198.876022479525, 4634.745089020069, 4684.344191813149, 4814.494478079821, 4844.491328410537, 4872.074305881871, 1194.9581764638197, 1255.4789762248747, 1421.1402101728306, 1445.768677011694, 1455.3378212913099, 792.1959211400433, 798.9414026415023, 812.959352032396, 820.1306614988132, 836.8493671139254, 5928.614640048383, 7186.8203294917, 7353.742619008309, 7401.703931842666, 7435.665395057235, 910.2717825739383, 940.849464085743, 942.0955882352881, 956.7034219392011, 959.9248841297791, 3384.966150338514, 4008.719389642697, 4321.913561728811, 4430.712003719748, 4594.839180628662, 11499.942500287612, 14480.203588802506, 14903.180325082008, 15126.168060756763, 15139.0, 14740.852591473948, 14991.550253492413, 14996.850031499764, 15029.549113526611, 15031.624209394853, 7360.263973602597, 8395.244428001388, 8521.105283945097, 8543.444676096035, 8585.227329540372, 6256.7810126645345, 7142.107236595426, 7173.784786456415, 7185.353318201387, 7195.532290401107, 11417.972382485616, 12706.30115343665, 12797.680057998625, 12948.870511295143, 12991.155574887602, 12736.74526509483, 12965.481380744664, 12966.157199781985, 13141.277229752455, 13270.469181232642, 9367.250619950524, 9440.244780417688, 9465.905340946778, 9477.194438472807, 9590.08894155054, 10786.298890572089, 10900.400477973788, 10901.182411319025, 10901.454927253764, 10921.126309895031, 9059.411138275991, 9177.908220917705, 9276.489793061446, 9324.0, 9439.858402123837, 8989.820203596022, 9041.457512549268, 9106.726798196065, 9142.040085790895, 9224.03147669485, 8204.671813127408, 8236.546989915612, 8261.297789687944, 8337.708180213664, 8358.45670031446, 5319.627626066138, 5559.916601250903, 6014.518838492998, 6233.345498722569, 6363.459105976044, 5774.220480235283, 6132.356102609162, 6302.65335406557, 6352.142460767737, 6386.712597933197], "value": 5463.472556324017, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.25656125081713, 390.3559127439724, 393.65358484533607, 397.7156333221746, 402.53306164848783, 5874.588778785476, 6243.188385509883, 6329.7845753986085, 6334.968325158379, 6341.175647165867, 5858.73635686394, 6141.692915354237, 6153.81538553843, 6176.413240742122, 6177.876442471148, 3418.2992486540325, 4580.862574122782, 4675.7895894684825, 4700.788464519088, 4712.835050773224, 4127.195196936598, 4460.219461594224, 4712.64655150865, 4738.597219236369, 4746.667733258673, 3693.8522459101605, 4124.42258083867, 4610.700304480215, 4657.951960808825, 4658.720476771405, 4006.599340065999, 4527.96626260403, 4630.398048253739, 4642.303654451843, 4662.277347011219, 4074.572169922175, 4074.5925407459313, 4101.405296232029, 4103.343465045578, 4125.29869922114, 5806.390329015477, 6058.96970515149, 6104.206453161105, 6106.847328816771, 6128.264608246995, 5368.758405871745, 5371.495981125291, 5433.402325744165, 5440.91838622421, 5472.206530053159, 6414.967925160391, 6554.9512078067755, 6710.362515561014, 6759.695813688396, 6760.42536384408, 4043.353063509824, 4430.601245887853, 4436.755978421185, 4437.755923424211, 4439.689221754478, 5196.2592531502, 5782.9012487627215, 6415.839604009844, 6990.720371185146, 7118.39493643041, 4362.062156636339, 4421.7398041558645, 4426.822927082945, 4463.575960283768, 4464.151811155935, 3813.2945405100145, 4544.909221786747, 4545.909081818345, 4551.904704800701, 4570.520095390003, 5212.8957420851375, 5399.78400863961, 5413.269208656781, 5419.3225846769155, 5452.40023597404, 5948.8810223796145, 6114.327423983358, 6851.657417129124, 6872.158665134595, 6874.556343167891, 1487.248939285654, 1523.8095238095239, 1569.1761825041945, 1570.1521178563448, 1579.155151993678, 1009.5154325923597, 1009.7273736091214, 1013.5641674080069, 1014.7666036811427, 1028.2314419198965, 5140.485951404904, 6463.7737679181, 6467.676616169173, 6514.609123452608, 6539.967300163468, 5798.6810725410505, 7067.717291308393, 7071.646417679095, 7130.144382674112, 7132.964335178291, 6272.09054687077, 6335.4931605472375, 6394.1687580614225, 6407.372977079375, 6415.326390728999, 3068.401661675952, 3088.6139232595924, 3142.638596561379, 3671.155634204148, 3698.389765688631, 5354.491323324278, 6264.59280146789, 6691.5650482718465, 7146.285371462812, 7244.601546914865, 6274.458844168119, 6665.666716664147, 6952.304769523007, 7259.310365515267, 7302.96348518254, 5456.645318054314, 6255.060121742774, 7200.711971521186, 7207.0, 7258.419326453976, 6159.599626024294, 6512.446442052478, 6773.89839152423, 7003.59928014389, 7046.541974771623, 5439.728013599383, 6044.818655440344, 6834.863302733818, 6835.350641689031, 6948.20095688982, 6922.480813939075, 7092.397146242627, 7168.211496735459, 7308.671109800178, 7330.560166390033, 4480.439945006874, 4511.661625378044, 4519.728816271035, 4537.753811863149, 4541.250693635645, 6877.243503214542, 6983.930160698328, 7061.187963384071, 7132.145642132979, 7141.53580017318, 5821.94178058231, 6203.472704820141, 6392.137061496638, 6819.761308354184, 6823.488238382042, 6424.678766061772, 6488.772892948724, 6721.0, 6780.220274668476, 6823.249442561413, 5001.024800164078, 6245.750169993331, 6564.4748420127235, 6616.0, 6704.7988560343265, 6700.590390679482, 6707.664616769044, 6725.361090696376, 6803.319668033157, 6821.113255276782, 6586.209654841449, 6596.868062638624, 6609.537332386687, 6634.33656634352, 6675.933240667531], "value": 4851.5447776210085, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.2608652213012, 836.5858899844578, 838.2623291503477, 857.0, 868.1318681318681, 16346.0, 17001.064941428216, 17027.82972170278, 19274.807251927476, 19831.305904293353, 15409.383624655, 16739.83260167398, 17707.46877593674, 18056.27774889006, 18983.81016189838, 744.5569885917884, 917.2524392619994, 917.6421195733669, 920.6225447566499, 922.1930810540777, 951.9381240219363, 953.4231789767196, 955.3790036476277, 958.3004406783052, 959.9520023998807, 1000.4797505297241, 1008.8032833597451, 1010.0051449322411, 1010.540204207084, 1014.4876837197205, 1008.0524307151254, 1008.702432782331, 1008.9293749437543, 1009.0010889219693, 1009.5961615353843, 732.3921145449317, 737.3732327521617, 745.1430854517295, 745.4334705623711, 746.0301607909696, 13158.07893447459, 17330.913345433317, 17340.91329543357, 17378.217980190922, 17394.304227831, 14027.368768405344, 17150.142492875308, 17323.307067717276, 17541.912290438595, 17660.293588256456, 14407.991440599162, 14608.269586520631, 14770.852291477162, 14796.260186990607, 14798.48205312809, 3444.2422667013216, 3531.346700860349, 3546.716262698978, 3557.3774589447003, 3559.394902866519, 123843.0, 141279.0, 143539.28230358887, 144677.0, 144802.0, 13121.278329691959, 13357.599272021855, 13373.799393009112, 13504.257265850472, 13542.322883855964, 25720.485590288088, 25778.613320800203, 26207.344816379744, 26229.475410491683, 26390.208293751217, 7803.492772969739, 7988.680452781824, 8051.838963220703, 8052.798680032929, 8125.9187408126345, 3897.395903634941, 3955.6439920406724, 3966.3852102924093, 3968.543617483969, 3976.701747368959, 4201.6008479194425, 4210.347396153601, 4280.636145927631, 4287.678424118204, 4434.7560884151035, 927.2535608834818, 945.6170251048336, 951.5622813505727, 955.2739917662626, 957.712686194139, 543.4701315796021, 648.1282674802477, 651.5211319680031, 664.1797380235405, 685.7120009595982, 10888.257878739521, 11964.341961192218, 12192.0, 12199.634010979686, 12215.69460763488, 634.8888944434706, 646.8253571535753, 648.1735786871682, 656.7963931181349, 659.0147729144959, 5413.810516631899, 5652.406497317805, 5658.896515179583, 5734.569907256889, 5744.942550574524, 132905.0, 135050.0, 136575.0, 137437.0, 138937.0, 119519.20721189189, 120299.39850300693, 120307.0, 121510.0, 125291.0, 96167.0, 107581.17728772387, 107610.46194769132, 108107.37838932422, 108908.4554577222, 10884.346939183675, 11247.550097995989, 11286.830697539708, 11293.548258069584, 11483.081353491534, 45846.62460126202, 45986.08027839357, 46212.07575848531, 46234.0, 46312.30531542097, 59337.703311483165, 59783.0, 59940.40059599523, 60324.0, 61287.38712612817, 12297.50809967627, 14425.927870360581, 14518.0, 14816.851831481978, 14873.405063797327, 15889.682206355577, 16092.517224482815, 16192.68225858667, 16228.51314460568, 16236.51290461288, 11229.71925701864, 14693.55919322422, 14927.776083358976, 15141.848581514043, 15242.0, 12258.816117758062, 14749.705005900036, 14781.260936953324, 14930.954833161575, 15079.698406031599, 14449.060811047248, 14591.781123282946, 14726.410943562567, 14792.11247325164, 14868.925655371653, 10039.698809035448, 10439.634612788515, 10449.58201671955, 10479.42363170033, 10626.681199564026, 10293.330933489298, 11238.325700458, 11376.601818936297, 11720.23818451832, 11770.117241206772], "value": 8396.080646353395, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 117.0, 112.0, 116.0, 115.0, 113.0, 139.0, 168.0, 151.0], "value": 124.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 112.0, 111.0, 152.0, 109.0, 135.0, 152.0, 151.0, 136.0, 111.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 204.0, 198.0, 215.0, 214.0, 220.0, 197.0, 197.0, 201.0, 200.0], "value": 204.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 56.0, 57.0, 86.0, 61.0, 79.0, 88.0, 90.0, 93.0, 59.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 82.0, 70.0, 72.0, 95.0, 106.0, 72.0, 75.0, 103.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 91.0, 94.0, 89.0, 91.0, 90.0, 98.0, 91.0, 90.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 97.0, 124.0, 100.0, 97.0, 98.0, 98.0, 98.0, 98.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 90.0, 92.0, 93.0, 105.0, 147.0, 140.0, 90.0, 97.0], "value": 104.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 49.0, 50.0, 50.0, 54.0, 52.0, 52.0, 51.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 56.0, 62.0, 62.0, 83.0, 90.0, 85.0, 55.0, 57.0, 57.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 85.0, 89.0, 87.0, 87.0, 85.0, 89.0, 89.0, 147.0, 89.0], "value": 95.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 155.0, 140.0, 136.0, 124.0, 128.0, 138.0, 128.0, 135.0, 133.0], "value": 135.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 184.0, 190.0, 186.0, 181.0, 185.0, 184.0, 203.0, 235.0], "value": 191.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 67.0, 66.0, 67.0, 63.0, 67.0, 70.0, 68.0], "value": 67.4, "unit": "score"}]}]} 03:11:30 INFO - Return code: 0 03:11:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:11:30 INFO - # TBPL SUCCESS # 03:11:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:11:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:11:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:11:30 INFO - 2017-05-25 03:11:30.824 screenresolution[1674:11174] starting screenresolution argv=screenresolution get 03:11:30 INFO - 2017-05-25 03:11:30.826 screenresolution[1674:11174] Display 0: 1600x1200x32@60 03:11:30 INFO - 2017-05-25 03:11:30.833 screenresolution[1675:11177] starting screenresolution argv=screenresolution list 03:11:30 INFO - Available Modes on Display 0 03:11:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:11:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:11:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:11:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:11:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:11:31 INFO - Intel Iris: 03:11:31 INFO - Chipset Model: Intel Iris 03:11:31 INFO - Type: GPU 03:11:31 INFO - Bus: Built-In 03:11:31 INFO - VRAM (Dynamic, Max): 1536 MB 03:11:31 INFO - Vendor: Intel (0x8086) 03:11:31 INFO - Device ID: 0x0a2e 03:11:31 INFO - Revision ID: 0x0009 03:11:31 INFO - Displays: 03:11:31 INFO - Display: 03:11:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:11:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:11:31 INFO - Display Serial Number: 1600x1200 60 03:11:31 INFO - Main Display: Yes 03:11:31 INFO - Mirror: Off 03:11:31 INFO - Online: Yes 03:11:31 INFO - Rotation: Supported 03:11:31 INFO - Return code: 0 03:11:31 INFO - Running post-action listener: _resource_record_post_action 03:11:31 INFO - [mozharness: 2017-05-25 10:11:31.067670Z] Finished run-tests step (success) 03:11:31 INFO - Running post-run listener: _resource_record_post_run 03:11:31 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 40139776; Write bytes: 560342016; Read time: 267; Write time: 1722 03:11:31 INFO - TinderboxPrint: CPU usage
53.2% 03:11:31 INFO - TinderboxPrint: I/O read bytes / time
40,139,776 / 267 03:11:31 INFO - TinderboxPrint: I/O write bytes / time
560,342,016 / 1,722 03:11:31 INFO - TinderboxPrint: CPU idle
2,350.8 (46.8%) 03:11:31 INFO - TinderboxPrint: CPU system
1,014.9 (20.2%) 03:11:31 INFO - TinderboxPrint: CPU user
1,656.0 (33.0%) 03:11:31 INFO - TinderboxPrint: Swap in / out
284,631,040 / 0 03:11:31 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 233005568; Write bytes: 226447360; Read time: 16809; Write time: 463 03:11:31 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 38198272; Write bytes: 314586112; Read time: 249; Write time: 1209 03:11:31 INFO - Running post-run listener: _upload_blobber_files 03:11:31 INFO - Blob upload gear active. 03:11:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:11:31 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 03:11:31 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'] 03:11:31 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 03:11:31 INFO - (blobuploader) - INFO - Open directory for files ... 03:11:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:11:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:11:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:11:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:11:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:11:33 INFO - (blobuploader) - INFO - Done attempting. 03:11:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:11:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:11:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:11:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:11:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:11:34 INFO - (blobuploader) - INFO - Done attempting. 03:11:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:11:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:11:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:11:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:11:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:11:35 INFO - (blobuploader) - INFO - Done attempting. 03:11:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:11:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:11:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:11:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:11:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:11:37 INFO - (blobuploader) - INFO - Done attempting. 03:11:37 INFO - (blobuploader) - INFO - Iteration through files over. 03:11:37 INFO - Return code: 0 03:11:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:11:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:11:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60fe070f370dc7df326700185d9380675e29ebe6dc55788034675f815f130ad3433bc72c4426802c037ebd196207028d1682884cd0613d0fac51800f0a603690", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a8ca5ab422f1752e2eae99a6ef341f53c44460d284cb07cfc9a32330902c75a2cfdc674713bf49b7ee00fc2aa59e310f5506cbbbd4c1344f3b569c09ebb6b3d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c04c4520990901dcfbabc65c6aafdc784dab38ac46ae64218fe1c8fc9f04696fc323188a240b32c5c9c1f1cc8e718a20768a455f1310d1fa2db73a429e268e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/170c78e839a86920a89179725a1f18983610d177ad4724fccee352bb1f0d82e7d1ef56f82a1a0482e0b37e561733177b256f5c52f9e97ada0a1350f5866ac090"} 03:11:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:11:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:11:37 INFO - Contents: 03:11:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60fe070f370dc7df326700185d9380675e29ebe6dc55788034675f815f130ad3433bc72c4426802c037ebd196207028d1682884cd0613d0fac51800f0a603690", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a8ca5ab422f1752e2eae99a6ef341f53c44460d284cb07cfc9a32330902c75a2cfdc674713bf49b7ee00fc2aa59e310f5506cbbbd4c1344f3b569c09ebb6b3d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c04c4520990901dcfbabc65c6aafdc784dab38ac46ae64218fe1c8fc9f04696fc323188a240b32c5c9c1f1cc8e718a20768a455f1310d1fa2db73a429e268e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/170c78e839a86920a89179725a1f18983610d177ad4724fccee352bb1f0d82e7d1ef56f82a1a0482e0b37e561733177b256f5c52f9e97ada0a1350f5866ac090"} 03:11:37 INFO - Running post-run listener: copy_logs_to_upload_dir 03:11:37 INFO - Copying logs to upload dir... 03:11:37 INFO - mkdir: /builds/slave/test/build/upload/logs 03:11:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.095354 ========= master_lag: 1.81 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-25 03:11:39.099340) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 03:11:39.106557) ========= 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.wWlel9oOFy/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.7VMSqKq54y/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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/60fe070f370dc7df326700185d9380675e29ebe6dc55788034675f815f130ad3433bc72c4426802c037ebd196207028d1682884cd0613d0fac51800f0a603690", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a8ca5ab422f1752e2eae99a6ef341f53c44460d284cb07cfc9a32330902c75a2cfdc674713bf49b7ee00fc2aa59e310f5506cbbbd4c1344f3b569c09ebb6b3d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c04c4520990901dcfbabc65c6aafdc784dab38ac46ae64218fe1c8fc9f04696fc323188a240b32c5c9c1f1cc8e718a20768a455f1310d1fa2db73a429e268e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/170c78e839a86920a89179725a1f18983610d177ad4724fccee352bb1f0d82e7d1ef56f82a1a0482e0b37e561733177b256f5c52f9e97ada0a1350f5866ac090"} build_url:https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011076 build_url: 'https://queue.taskcluster.net/v1/task/dMxKD2XFS9OubeMdiJxsow/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/60fe070f370dc7df326700185d9380675e29ebe6dc55788034675f815f130ad3433bc72c4426802c037ebd196207028d1682884cd0613d0fac51800f0a603690", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a8ca5ab422f1752e2eae99a6ef341f53c44460d284cb07cfc9a32330902c75a2cfdc674713bf49b7ee00fc2aa59e310f5506cbbbd4c1344f3b569c09ebb6b3d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c04c4520990901dcfbabc65c6aafdc784dab38ac46ae64218fe1c8fc9f04696fc323188a240b32c5c9c1f1cc8e718a20768a455f1310d1fa2db73a429e268e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/170c78e839a86920a89179725a1f18983610d177ad4724fccee352bb1f0d82e7d1ef56f82a1a0482e0b37e561733177b256f5c52f9e97ada0a1350f5866ac090"}' ========= master_lag: 0.43 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 03:11:39.552559) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 03:11:39.552957) ========= 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.wWlel9oOFy/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.7VMSqKq54y/Listeners TMPDIR=/var/folders/kh/k_6wkq590f76m8yt0lypmfdm00000w/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.004553 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 03:11:39.584919) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 03:11:39.585242) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 03:11:39.585584) ========= ========= Total master_lag: 2.34 =========