builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0030 starttime: 1494796648.03 results: success (0) revision: e66dedabe582ba7b394aee4f89ed70fe389b3c46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:28.027274) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:28.027700) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:28.027987) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:28.050447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:28.050701) ========= 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.R9MeKzVxaA/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.yk3c2twScn/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-14 14:17:28-- 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% 20.7M=0.001s 2017-05-14 14:17:28 (20.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.502366 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:28.576820) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:28.577145) ========= 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.R9MeKzVxaA/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.yk3c2twScn/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.028339 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:28.625899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-14 14:17:28.626209) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e66dedabe582ba7b394aee4f89ed70fe389b3c46 --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 e66dedabe582ba7b394aee4f89ed70fe389b3c46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R9MeKzVxaA/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.yk3c2twScn/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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-14 14:17:28,728 truncating revision to first 12 chars 2017-05-14 14:17:28,728 Setting DEBUG logging. 2017-05-14 14:17:28,728 attempt 1/10 2017-05-14 14:17:28,728 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e66dedabe582?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-14 14:17:29,822 unpacking tar archive at: mozilla-central-e66dedabe582/testing/mozharness/ program finished with exit code 0 elapsedTime=1.370893 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-14 14:17:30.019395) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:30.019780) ========= script_repo_revision: e66dedabe582ba7b394aee4f89ed70fe389b3c46 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:30.020162) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:30.020589) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-14 14:17:30.039539) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-14 14:17:30.039905) ========= /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.R9MeKzVxaA/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.yk3c2twScn/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:17:30 INFO - MultiFileLogger online at 20170514 14:17:30 in /builds/slave/test 14:17:30 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 14:17:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:17:30 INFO - {'append_to_log': False, 14:17:30 INFO - 'base_work_dir': '/builds/slave/test', 14:17:30 INFO - 'blob_upload_branch': 'Firefox', 14:17:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:17:30 INFO - 'branch': 'Firefox', 14:17:30 INFO - 'buildbot_json_path': 'buildprops.json', 14:17:30 INFO - 'config_files': ('talos/mac_config.py',), 14:17:30 INFO - 'default_actions': ('clobber', 14:17:30 INFO - 'read-buildbot-config', 14:17:30 INFO - 'download-and-extract', 14:17:30 INFO - 'populate-webroot', 14:17:30 INFO - 'create-virtualenv', 14:17:30 INFO - 'install', 14:17:30 INFO - 'run-tests'), 14:17:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:17:30 INFO - 'download_minidump_stackwalk': True, 14:17:30 INFO - 'download_symbols': 'ondemand', 14:17:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:17:30 INFO - 'tooltool.py': '/tools/tooltool.py', 14:17:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:17:30 INFO - '/tools/misc-python/virtualenv.py')}, 14:17:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:17:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:17:30 INFO - 'gecko_profile': False, 14:17:30 INFO - 'gecko_profile_interval': 0, 14:17:30 INFO - 'installer_path': 'installer.exe', 14:17:30 INFO - 'log_level': 'info', 14:17:30 INFO - 'log_name': 'talos', 14:17:30 INFO - 'log_to_console': True, 14:17:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:17:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:17:30 INFO - 'opt_config_files': (), 14:17:30 INFO - 'pip_index': False, 14:17:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:17:30 INFO - 'cmd': ('bash', 14:17:30 INFO - '-c', 14:17:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:17:30 INFO - 'enabled': True, 14:17:30 INFO - 'halt_on_failure': False, 14:17:30 INFO - 'name': 'check_screen_resolution'},), 14:17:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:17:30 INFO - 'cmd': ('bash', 14:17:30 INFO - '-c', 14:17:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:17:30 INFO - 'enabled': True, 14:17:30 INFO - 'halt_on_failure': False, 14:17:30 INFO - 'name': 'check_screen_resolution'},), 14:17:30 INFO - 'run_cmd_checks_enabled': True, 14:17:30 INFO - 'suite': 'dromaeojs-e10s', 14:17:30 INFO - 'system_bits': '32', 14:17:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:17:30 INFO - 'title': 't-yosemite-r7-0030', 14:17:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:17:30 INFO - 'use_talos_json': True, 14:17:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:17:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:17:30 INFO - 'work_dir': 'build'} 14:17:30 INFO - [mozharness: 2017-05-14 21:17:30.256239Z] Running clobber step. 14:17:30 INFO - Running pre-action listener: _resource_record_pre_action 14:17:30 INFO - Running main action method: clobber 14:17:30 INFO - rmtree: /builds/slave/test/build 14:17:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:17:32 INFO - Running post-action listener: _resource_record_post_action 14:17:32 INFO - [mozharness: 2017-05-14 21:17:32.807674Z] Finished clobber step (success) 14:17:32 INFO - [mozharness: 2017-05-14 21:17:32.807806Z] Running read-buildbot-config step. 14:17:32 INFO - Running pre-action listener: _resource_record_pre_action 14:17:32 INFO - Running main action method: read_buildbot_config 14:17:32 INFO - Using buildbot properties: 14:17:32 INFO - { 14:17:32 INFO - "project": "", 14:17:32 INFO - "product": "firefox", 14:17:32 INFO - "who": "archaeopteryx@coole-files.de", 14:17:32 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:17:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:17:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:17:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:17:32 INFO - "stage_platform": "macosx64", 14:17:32 INFO - "basedir": "/builds/slave/test", 14:17:32 INFO - "buildnumber": 216, 14:17:32 INFO - "platform": "yosemite_r7", 14:17:32 INFO - "parent_task_id": "O6ecmveVQkmYzPSDU7BCvw", 14:17:32 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:17:32 INFO - "slavebuilddir": "test", 14:17:32 INFO - "taskId": "KWNdzZsPSwGkl8QRGWeHMw", 14:17:32 INFO - "branch": "mozilla-central", 14:17:32 INFO - "script_repo_revision": "production", 14:17:32 INFO - "revision": "e66dedabe582ba7b394aee4f89ed70fe389b3c46", 14:17:32 INFO - "slavename": "t-yosemite-r7-0030", 14:17:32 INFO - "repo_path": "mozilla-central" 14:17:32 INFO - } 14:17:32 INFO - Finding installer, test and symbols from parent task. 14:17:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KWNdzZsPSwGkl8QRGWeHMw'}, attempt #1 14:17:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KWNdzZsPSwGkl8QRGWeHMw'}, attempt #1 14:17:34 INFO - Task dependencies: ZNcucvVkRuOJEXM39yZGcA 14:17:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw'}, attempt #1 14:17:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:17:34 INFO - Set test_url: https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/test_packages.json 14:17:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/target.crashreporter-symbols.zip 14:17:34 INFO - Running post-action listener: _resource_record_post_action 14:17:34 INFO - [mozharness: 2017-05-14 21:17:34.573183Z] Finished read-buildbot-config step (success) 14:17:34 INFO - [mozharness: 2017-05-14 21:17:34.573324Z] Running download-and-extract step. 14:17:34 INFO - Running pre-action listener: _resource_record_pre_action 14:17:34 INFO - Running main action method: download_and_extract 14:17:34 INFO - mkdir: /builds/slave/test/build/tests 14:17: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')]} 14:17:34 INFO - trying https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:17:34 INFO - Downloading https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/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 14:17:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/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 14:17:35 INFO - Downloaded 1325 bytes. 14:17:35 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:17:35 INFO - Using the following test package requirements: 14:17:35 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:35 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:17:35 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:17:35 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:35 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:17:35 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:35 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:17:35 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:35 INFO - u'jsshell-mac.zip'], 14:17:35 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:35 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:17:35 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:17:35 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:35 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:17:35 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:35 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:17:35 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:35 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:17:35 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:17:35 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:17:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:17:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:17:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:17:35 INFO - Fetch https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:17:37 INFO - Content-Length response header: 56564872 14:17:37 INFO - Bytes received: 56564872 14:17:55 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 14:17:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:17:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:17:55 INFO - Fetch https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:17:57 INFO - Content-Length response header: 56564872 14:17:57 INFO - Bytes received: 56564872 14:18:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:18:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:18:14 INFO - Fetch https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:18:15 INFO - Content-Length response header: 14410013 14:18:15 INFO - Bytes received: 14410013 14:18:15 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')]} 14:18:15 INFO - trying https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:18:15 INFO - Downloading https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:18:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/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 14:18:18 INFO - Downloaded 75959745 bytes. 14:18:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:18:18 INFO - mkdir: /builds/slave/test/properties 14:18:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:18:18 INFO - Writing to file /builds/slave/test/properties/build_url 14:18:18 INFO - Contents: 14:18:18 INFO - build_url:https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:18:18 INFO - Running post-action listener: _resource_record_post_action 14:18:18 INFO - Running post-action listener: set_extra_try_arguments 14:18:18 INFO - [mozharness: 2017-05-14 21:18:18.085064Z] Finished download-and-extract step (success) 14:18:18 INFO - [mozharness: 2017-05-14 21:18:18.085210Z] Running populate-webroot step. 14:18:18 INFO - Running pre-action listener: _resource_record_pre_action 14:18:18 INFO - Running main action method: populate_webroot 14:18:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:18:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:18:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:18:18 INFO - '--tppagecycles', 14:18:18 INFO - '7'], 14:18:18 INFO - 'tests': ['tsvgm']}}, 14:18:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:18:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:18:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:18:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:18:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:18:18 INFO - 'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:18:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:18:18 INFO - 'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['damp', 'tps']}, 14:18:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:18:18 INFO - 'tests': ['damp', 'tps']}, 14:18:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['dromaeo_dom']}, 14:18:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:18:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:18:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:18:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other-e10s': {'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:18:18 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:18:18 INFO - 'bloom_basic_ref']}, 14:18:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['tsvgx', 14:18:18 INFO - 'tsvgr_opacity', 14:18:18 INFO - 'tart', 14:18:18 INFO - 'tscrollx', 14:18:18 INFO - 'cart', 14:18:18 INFO - 'tsvg_static']}, 14:18:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:18:18 INFO - 'tsvgr_opacity', 14:18:18 INFO - 'tart', 14:18:18 INFO - 'tscrollx', 14:18:18 INFO - 'cart', 14:18:18 INFO - 'tsvg_static']}, 14:18:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:18:18 INFO - 'talos_options': ['--disable-e10s'], 14:18:18 INFO - 'tests': ['tp5o']}, 14:18:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:18:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:18:18 INFO - 'talos_options': ['--disable-e10s', 14:18:18 INFO - '--xperf_path', 14:18:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:18 INFO - 'tests': ['tp5n']}, 14:18:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:18:18 INFO - 'talos_options': ['--xperf_path', 14:18:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:18 INFO - 'tests': ['tp5n']}}, 14:18:18 INFO - 'talos.zip': {'path': '', 14:18:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:18:18 INFO - Running post-action listener: _resource_record_post_action 14:18:18 INFO - [mozharness: 2017-05-14 21:18:18.106271Z] Finished populate-webroot step (success) 14:18:18 INFO - [mozharness: 2017-05-14 21:18:18.106424Z] Running create-virtualenv step. 14:18:18 INFO - Running pre-action listener: _resource_record_pre_action 14:18:18 INFO - Running main action method: create_virtualenv 14:18:18 INFO - Creating virtualenv /builds/slave/test/build/venv 14:18:18 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 14:18:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:18:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:18:18 INFO - Using real prefix '/tools/python27' 14:18:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:18:18 INFO - Installing distribute.............................................................................................................................................................................................done. 14:18:21 INFO - Installing pip.................done. 14:18:21 INFO - Return code: 0 14:18:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:18:22 INFO - Reading from file tmpfile_stdout 14:18:22 INFO - Output received: 14:18:22 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) 14:18:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:18:22 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')]} 14:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:22 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')]} 14:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:22 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 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:22 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 14:18:22 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 14:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:22 INFO - 'HOME': '/Users/cltbld', 14:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:22 INFO - 'LOGNAME': 'cltbld', 14:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:22 INFO - 'MOZ_NO_REMOTE': '1', 14:18:22 INFO - 'NO_EM_RESTART': '1', 14:18:22 INFO - 'PAGER': '/bin/cat', 14:18:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:22 INFO - 'PWD': '/builds/slave/test', 14:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:22 INFO - 'SHELL': '/bin/bash', 14:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:22 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:22 INFO - 'USER': 'cltbld', 14:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:22 INFO - 'XPC_FLAGS': '0x0', 14:18:22 INFO - 'XPC_SERVICE_NAME': '0', 14:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:22 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 14:18:22 INFO - Cleaning up... 14:18:22 INFO - Return code: 0 14:18:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:18:22 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')]} 14:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:22 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')]} 14:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:22 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 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:22 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 14:18:22 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 14:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:22 INFO - 'HOME': '/Users/cltbld', 14:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:22 INFO - 'LOGNAME': 'cltbld', 14:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:22 INFO - 'MOZ_NO_REMOTE': '1', 14:18:22 INFO - 'NO_EM_RESTART': '1', 14:18:22 INFO - 'PAGER': '/bin/cat', 14:18:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:22 INFO - 'PWD': '/builds/slave/test', 14:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:22 INFO - 'SHELL': '/bin/bash', 14:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:22 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:22 INFO - 'USER': 'cltbld', 14:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:22 INFO - 'XPC_FLAGS': '0x0', 14:18:22 INFO - 'XPC_SERVICE_NAME': '0', 14:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:22 INFO - Downloading/unpacking psutil>=3.1.1 14:18: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 14:18: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 14:18: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 14:18: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 14:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:18:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:25 INFO - Installing collected packages: psutil 14:18:25 INFO - Running setup.py install for psutil 14:18:25 INFO - building 'psutil._psutil_osx' extension 14:18:25 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 14:18:25 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 14:18:25 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 14:18:25 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 14:18:25 INFO - building 'psutil._psutil_posix' extension 14:18:25 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 14:18:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:18:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:18:26 INFO - ^ 14:18:26 INFO - 1 warning generated. 14:18:26 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 14:18:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:26 INFO - Successfully installed psutil 14:18:26 INFO - Cleaning up... 14:18:26 INFO - Return code: 0 14:18:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:18:26 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')]} 14:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:26 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')]} 14:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:26 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 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:26 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 14:18:26 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 14:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:26 INFO - 'HOME': '/Users/cltbld', 14:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:26 INFO - 'LOGNAME': 'cltbld', 14:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:26 INFO - 'MOZ_NO_REMOTE': '1', 14:18:26 INFO - 'NO_EM_RESTART': '1', 14:18:26 INFO - 'PAGER': '/bin/cat', 14:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:26 INFO - 'PWD': '/builds/slave/test', 14:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:26 INFO - 'SHELL': '/bin/bash', 14:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:26 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:26 INFO - 'USER': 'cltbld', 14:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:26 INFO - 'XPC_FLAGS': '0x0', 14:18:26 INFO - 'XPC_SERVICE_NAME': '0', 14:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:18:26 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 14:18:26 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 14:18:26 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 14:18:26 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 14:18:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:18:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:18:27 INFO - Installing collected packages: mozsystemmonitor 14:18:27 INFO - Running setup.py install for mozsystemmonitor 14:18:28 INFO - Successfully installed mozsystemmonitor 14:18:28 INFO - Cleaning up... 14:18:28 INFO - Return code: 0 14:18:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:18: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')]} 14:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18: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')]} 14:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:28 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 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:28 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 14:18:28 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 14:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:28 INFO - 'HOME': '/Users/cltbld', 14:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:28 INFO - 'LOGNAME': 'cltbld', 14:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:28 INFO - 'MOZ_NO_REMOTE': '1', 14:18:28 INFO - 'NO_EM_RESTART': '1', 14:18:28 INFO - 'PAGER': '/bin/cat', 14:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:28 INFO - 'PWD': '/builds/slave/test', 14:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:28 INFO - 'SHELL': '/bin/bash', 14:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:28 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:28 INFO - 'USER': 'cltbld', 14:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:28 INFO - 'XPC_FLAGS': '0x0', 14:18:28 INFO - 'XPC_SERVICE_NAME': '0', 14:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:28 INFO - Downloading/unpacking jsonschema==2.5.1 14:18:28 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 14:18:28 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 14:18:28 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 14:18:28 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 14:18:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:18:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:18:29 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 14:18:29 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 14:18:29 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 14:18:29 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 14:18:30 INFO - Downloading functools32-3.2.3-2.tar.gz 14:18:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:18:30 INFO - warning: no files found matching '*.txt' 14:18:30 INFO - no previously-included directories found matching 'build' 14:18:30 INFO - no previously-included directories found matching 'dist' 14:18:30 INFO - no previously-included directories found matching '.git*' 14:18:30 INFO - Installing collected packages: jsonschema, functools32 14:18:30 INFO - Running setup.py install for functools32 14:18:30 INFO - warning: no files found matching '*.txt' 14:18:30 INFO - no previously-included directories found matching 'build' 14:18:30 INFO - no previously-included directories found matching 'dist' 14:18:30 INFO - no previously-included directories found matching '.git*' 14:18:30 INFO - Successfully installed jsonschema functools32 14:18:30 INFO - Cleaning up... 14:18:30 INFO - Return code: 0 14:18:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:18:30 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')]} 14:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:30 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')]} 14:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:30 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 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:30 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 14:18:30 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 14:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:30 INFO - 'HOME': '/Users/cltbld', 14:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:30 INFO - 'LOGNAME': 'cltbld', 14:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:30 INFO - 'MOZ_NO_REMOTE': '1', 14:18:30 INFO - 'NO_EM_RESTART': '1', 14:18:30 INFO - 'PAGER': '/bin/cat', 14:18:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:30 INFO - 'PWD': '/builds/slave/test', 14:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:30 INFO - 'SHELL': '/bin/bash', 14:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:30 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:30 INFO - 'USER': 'cltbld', 14:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:30 INFO - 'XPC_FLAGS': '0x0', 14:18:30 INFO - 'XPC_SERVICE_NAME': '0', 14:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:18:30 INFO - Cleaning up... 14:18:30 INFO - Return code: 0 14:18:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:18:30 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')]} 14:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:30 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')]} 14:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:30 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 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:30 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 14:18:30 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 14:18:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:30 INFO - 'HOME': '/Users/cltbld', 14:18:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:30 INFO - 'LOGNAME': 'cltbld', 14:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:30 INFO - 'MOZ_NO_REMOTE': '1', 14:18:30 INFO - 'NO_EM_RESTART': '1', 14:18:30 INFO - 'PAGER': '/bin/cat', 14:18:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:30 INFO - 'PWD': '/builds/slave/test', 14:18:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:30 INFO - 'SHELL': '/bin/bash', 14:18:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:30 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:30 INFO - 'USER': 'cltbld', 14:18:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:30 INFO - 'XPC_FLAGS': '0x0', 14:18:30 INFO - 'XPC_SERVICE_NAME': '0', 14:18:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:30 INFO - Downloading/unpacking blobuploader==1.2.4 14:18: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 14:18: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 14:18: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 14:18: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 14:18:32 INFO - Downloading blobuploader-1.2.4.tar.gz 14:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:18:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:18: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 14:18: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 14:18: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 14:18: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 14:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:18:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:18: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 14:18: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 14:18: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 14:18: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 14:18:33 INFO - Downloading docopt-0.6.1.tar.gz 14:18:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:18:33 INFO - Installing collected packages: blobuploader, requests, docopt 14:18:33 INFO - Running setup.py install for blobuploader 14:18:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:18:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:18:33 INFO - Running setup.py install for requests 14:18:33 INFO - Running setup.py install for docopt 14:18:34 INFO - Successfully installed blobuploader requests docopt 14:18:34 INFO - Cleaning up... 14:18:34 INFO - Return code: 0 14:18:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18: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')]} 14:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18: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')]} 14:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:34 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 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:34 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 14:18:34 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 14:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:34 INFO - 'HOME': '/Users/cltbld', 14:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:34 INFO - 'LOGNAME': 'cltbld', 14:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:34 INFO - 'MOZ_NO_REMOTE': '1', 14:18:34 INFO - 'NO_EM_RESTART': '1', 14:18:34 INFO - 'PAGER': '/bin/cat', 14:18:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:34 INFO - 'PWD': '/builds/slave/test', 14:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:34 INFO - 'SHELL': '/bin/bash', 14:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:34 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:34 INFO - 'USER': 'cltbld', 14:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:34 INFO - 'XPC_FLAGS': '0x0', 14:18:34 INFO - 'XPC_SERVICE_NAME': '0', 14:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-0wHm_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-GaQaqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-lsOd6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-bNuVCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-bmjcxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-DIR9li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:34 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-4LeI4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-C9TWXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-XnW8e1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-VAiJof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-u97NVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-9kKRAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-ZMZ0Uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-KFNEsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-AiRL3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-WZnpRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:35 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-RuLT5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:18:36 INFO - Running setup.py install for manifestparser 14:18:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:18:36 INFO - Running setup.py install for mozcrash 14:18:36 INFO - Running setup.py install for mozdebug 14:18:36 INFO - Running setup.py install for mozdevice 14:18:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:18:36 INFO - Running setup.py install for mozfile 14:18:36 INFO - Running setup.py install for mozhttpd 14:18:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:18:36 INFO - Running setup.py install for mozinfo 14:18:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for mozInstall 14:18:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for mozleak 14:18:37 INFO - Running setup.py install for mozlog 14:18:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for moznetwork 14:18:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for mozprocess 14:18:37 INFO - Running setup.py install for mozprofile 14:18:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for mozrunner 14:18:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:18:38 INFO - Running setup.py install for mozscreenshot 14:18:38 INFO - Running setup.py install for moztest 14:18:38 INFO - Running setup.py install for mozversion 14:18:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:18:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:18:38 INFO - Cleaning up... 14:18:38 INFO - Return code: 0 14:18:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:38 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')]} 14:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:38 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')]} 14:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:38 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 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:38 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 14:18:38 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 14:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:38 INFO - 'HOME': '/Users/cltbld', 14:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:38 INFO - 'LOGNAME': 'cltbld', 14:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:38 INFO - 'MOZ_NO_REMOTE': '1', 14:18:38 INFO - 'NO_EM_RESTART': '1', 14:18:38 INFO - 'PAGER': '/bin/cat', 14:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:38 INFO - 'PWD': '/builds/slave/test', 14:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:38 INFO - 'SHELL': '/bin/bash', 14:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:38 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:38 INFO - 'USER': 'cltbld', 14:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:38 INFO - 'XPC_FLAGS': '0x0', 14:18:38 INFO - 'XPC_SERVICE_NAME': '0', 14:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:38 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-t1SVJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-CiaBZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-7ucHaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-TLnlCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-0KsW0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-N1Mr4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-mifS8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-GCuhhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-DXJE9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-bq5CLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:39 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-4s8Wet-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:40 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-NuBUfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:40 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-1zZuiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:40 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-yBOqB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:40 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-9Ajjl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:40 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-WptTRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:40 INFO - Running setup.py (path:/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/pip-PEEJgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:40 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)) 14:18:40 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)) 14:18:40 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)) 14:18:40 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)) 14:18:40 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:18:40 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:18:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:42 INFO - Downloading blessings-1.6.tar.gz 14:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:18:42 INFO - Installing collected packages: blessings 14:18:42 INFO - Running setup.py install for blessings 14:18:42 INFO - Successfully installed blessings 14:18:42 INFO - Cleaning up... 14:18:42 INFO - Return code: 0 14:18:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:18:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:42 INFO - Reading from file tmpfile_stdout 14:18:42 INFO - Current package versions: 14:18:42 INFO - blessings == 1.6 14:18:42 INFO - blobuploader == 1.2.4 14:18:42 INFO - docopt == 0.6.1 14:18:42 INFO - functools32 == 3.2.3-2 14:18:42 INFO - jsonschema == 2.5.1 14:18:42 INFO - manifestparser == 1.1 14:18:42 INFO - mozInstall == 1.12 14:18:42 INFO - mozcrash == 1.0 14:18:42 INFO - mozdebug == 0.1 14:18:42 INFO - mozdevice == 0.50 14:18:42 INFO - mozfile == 1.2 14:18:42 INFO - mozhttpd == 0.7 14:18:42 INFO - mozinfo == 0.9 14:18:42 INFO - mozleak == 0.1 14:18:42 INFO - mozlog == 3.4 14:18:42 INFO - moznetwork == 0.27 14:18:42 INFO - mozprocess == 0.25 14:18:42 INFO - mozprofile == 0.28 14:18:42 INFO - mozrunner == 6.13 14:18:42 INFO - mozscreenshot == 0.1 14:18:42 INFO - mozsystemmonitor == 0.3 14:18:42 INFO - moztest == 0.8 14:18:42 INFO - mozversion == 1.4 14:18:42 INFO - psutil == 3.1.1 14:18:42 INFO - requests == 1.2.3 14:18:42 INFO - wsgiref == 0.1.2 14:18:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:42 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')]} 14:18:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:42 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')]} 14:18:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:42 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 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:42 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 14:18:42 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 14:18:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:42 INFO - 'HOME': '/Users/cltbld', 14:18:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:42 INFO - 'LOGNAME': 'cltbld', 14:18:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:42 INFO - 'MOZ_NO_REMOTE': '1', 14:18:42 INFO - 'NO_EM_RESTART': '1', 14:18:42 INFO - 'PAGER': '/bin/cat', 14:18:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:42 INFO - 'PWD': '/builds/slave/test', 14:18:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:42 INFO - 'SHELL': '/bin/bash', 14:18:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:42 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:42 INFO - 'USER': 'cltbld', 14:18:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:42 INFO - 'XPC_FLAGS': '0x0', 14:18:42 INFO - 'XPC_SERVICE_NAME': '0', 14:18:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:43 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)) 14:18:43 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)) 14:18:43 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)) 14:18:43 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)) 14:18:43 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)) 14:18:43 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)) 14:18:43 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)) 14:18:43 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)) 14:18:43 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)) 14:18:43 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)) 14:18:43 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)) 14:18:43 INFO - Cleaning up... 14:18:43 INFO - Return code: 0 14:18:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1029deb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029e2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5694c33b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, '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.R9MeKzVxaA/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.yk3c2twScn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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 14:18:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:43 INFO - 'HOME': '/Users/cltbld', 14:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:43 INFO - 'LOGNAME': 'cltbld', 14:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:43 INFO - 'MOZ_NO_REMOTE': '1', 14:18:43 INFO - 'NO_EM_RESTART': '1', 14:18:43 INFO - 'PAGER': '/bin/cat', 14:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:43 INFO - 'PWD': '/builds/slave/test', 14:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:43 INFO - 'SHELL': '/bin/bash', 14:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:18:43 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:43 INFO - 'USER': 'cltbld', 14:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:43 INFO - 'XPC_FLAGS': '0x0', 14:18:43 INFO - 'XPC_SERVICE_NAME': '0', 14:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:18:43 INFO - Cleaning up... 14:18:43 INFO - Return code: 0 14:18:43 INFO - Running post-action listener: _resource_record_post_action 14:18:43 INFO - Running post-action listener: _start_resource_monitoring 14:18:43 INFO - Starting resource monitoring. 14:18:43 INFO - [mozharness: 2017-05-14 21:18:43.364901Z] Finished create-virtualenv step (success) 14:18:43 INFO - [mozharness: 2017-05-14 21:18:43.365877Z] Running install step. 14:18:43 INFO - Running pre-action listener: _resource_record_pre_action 14:18:43 INFO - Running main action method: install 14:18:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:43 INFO - Reading from file tmpfile_stdout 14:18:43 INFO - Detecting whether we're running mozinstall >=1.0... 14:18:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:18:43 INFO - Reading from file tmpfile_stdout 14:18:43 INFO - Output received: 14:18:43 INFO - Usage: mozinstall [options] installer 14:18:43 INFO - Options: 14:18:43 INFO - -h, --help show this help message and exit 14:18:43 INFO - -d DEST, --destination=DEST 14:18:43 INFO - Directory to install application into. [default: 14:18:43 INFO - "/builds/slave/test"] 14:18:43 INFO - --app=APP Application being installed. [default: firefox] 14:18:43 INFO - mkdir: /builds/slave/test/build/application 14:18:43 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'] 14:18:43 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 14:19:04 INFO - Reading from file tmpfile_stdout 14:19:04 INFO - Output received: 14:19:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:19:04 INFO - Running post-action listener: _resource_record_post_action 14:19:04 INFO - [mozharness: 2017-05-14 21:19:04.648630Z] Finished install step (success) 14:19:04 INFO - [mozharness: 2017-05-14 21:19:04.648766Z] Running run-tests step. 14:19:04 INFO - Running pre-action listener: _resource_record_pre_action 14:19:04 INFO - Running main action method: run_tests 14:19:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:19:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:19:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:19:04 INFO - 2017-05-14 14:19:04.662 screenresolution[1629:7953] starting screenresolution argv=screenresolution get 14:19:04 INFO - 2017-05-14 14:19:04.665 screenresolution[1629:7953] Display 0: 1600x1200x32@60 14:19:04 INFO - 2017-05-14 14:19:04.671 screenresolution[1630:7955] starting screenresolution argv=screenresolution list 14:19:04 INFO - Available Modes on Display 0 14:19:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:19:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:19:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:19:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:19:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:19:04 INFO - Intel Iris: 14:19:04 INFO - Chipset Model: Intel Iris 14:19:04 INFO - Type: GPU 14:19:04 INFO - Bus: Built-In 14:19:04 INFO - VRAM (Dynamic, Max): 1536 MB 14:19:04 INFO - Vendor: Intel (0x8086) 14:19:04 INFO - Device ID: 0x0a2e 14:19:04 INFO - Revision ID: 0x0009 14:19:04 INFO - Displays: 14:19:04 INFO - Display: 14:19:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:19:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:19:04 INFO - Display Serial Number: 1600x1200 60 14:19:04 INFO - Main Display: Yes 14:19:04 INFO - Mirror: Off 14:19:04 INFO - Online: Yes 14:19:04 INFO - Rotation: Supported 14:19:04 INFO - Return code: 0 14:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:19:04 INFO - Python 2.7.3 14:19:04 INFO - Return code: 0 14:19:04 INFO - grabbing minidump binary from tooltool 14:19: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')]} 14:19:04 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 0x1026eed30>, '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 0x1026a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1026e3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:19:04 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 14:19:04 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 14:19:04 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 14:19:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:19:05 INFO - Return code: 0 14:19:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:19:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:19:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:19:05 INFO - ENV: RUST_BACKTRACE is now 1 14:19:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:19:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:19:05 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/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 14:19:05 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/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0030 --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 14:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R9MeKzVxaA/Render', 14:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:05 INFO - 'HOME': '/Users/cltbld', 14:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:05 INFO - 'LOGNAME': 'cltbld', 14:19:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:05 INFO - 'MOZ_NO_REMOTE': '1', 14:19:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:05 INFO - 'NO_EM_RESTART': '1', 14:19:05 INFO - 'PAGER': '/bin/cat', 14:19:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:05 INFO - 'PWD': '/builds/slave/test', 14:19:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:05 INFO - 'RUST_BACKTRACE': '1', 14:19:05 INFO - 'SHELL': '/bin/bash', 14:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yk3c2twScn/Listeners', 14:19:05 INFO - 'TMPDIR': '/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/', 14:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:05 INFO - 'USER': 'cltbld', 14:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:05 INFO - 'XPC_FLAGS': '0x0', 14:19:05 INFO - 'XPC_SERVICE_NAME': '0', 14:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:05 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/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0030', '--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 14:19:05 INFO - mozversion application_buildid: 20170514124244 14:19:05 INFO - mozversion application_changeset: e66dedabe582ba7b394aee4f89ed70fe389b3c46 14:19:05 INFO - mozversion application_display_name: Nightly 14:19:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:19:05 INFO - mozversion application_name: Firefox 14:19:05 INFO - mozversion application_remotingname: firefox 14:19:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:19:05 INFO - mozversion application_vendor: Mozilla 14:19:05 INFO - mozversion application_version: 55.0a1 14:19:05 INFO - mozversion platform_buildid: 20170514124244 14:19:05 INFO - mozversion platform_changeset: e66dedabe582ba7b394aee4f89ed70fe389b3c46 14:19:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:19:05 INFO - mozversion platform_version: 55.0a1 14:19:05 INFO - using testdate: 1494796745 14:19:05 INFO - actual date: 1494796745 14:19:05 INFO - starting webserver on 'localhost:49226' 14:19:05 INFO - SUITE-START | Running 2 tests 14:19:05 INFO - TEST-START | dromaeo_css 14:19:05 INFO - operating with platform_type : mac_ 14:19:05 INFO - Initialising browser for dromaeo_css test... 14:19:05 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpVOh0n3/profile http://localhost:49226/getInfo.html) 14:19:05 INFO - PID 1640 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 14:19:07 INFO - PID 1640 | 2017-05-14 14:19:07.877 plugin-container[1642:8159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 14:19:07 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:07 INFO - PID 1640 | 2017-05-14 14:19:07.880 plugin-container[1642:8159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 14:19:07 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:07 INFO - PID 1640 | 2017-05-14 14:19:07.880 plugin-container[1642:8159] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:19:08 INFO - PID 1640 | __metrics Screen width/height:1600/1200 14:19:08 INFO - PID 1640 | colorDepth:24 14:19:08 INFO - PID 1640 | Browser inner width/height: 1024/691 14:19:08 INFO - PID 1640 | __metrics 14:19:08 INFO - PID 1640 | 2017-05-14 14:19:08.357 plugin-container[1643:8231] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x463b, name = 'com.apple.tsm.portname' 14:19:08 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:08 INFO - PID 1640 | 2017-05-14 14:19:08.358 plugin-container[1643:8231] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 14:19:08 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:08 INFO - PID 1640 | 2017-05-14 14:19:08.358 plugin-container[1643:8231] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:19:08 INFO - TEST-INFO | 1640: exit 0 14:19:08 INFO - Browser initialized. 14:19:08 INFO - Running cycle 1/1 for dromaeo_css test... 14:19:08 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpVOh0n3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:10 INFO - PID 1644 | 2017-05-14 14:19:10.596 plugin-container[1645:8331] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 14:19:10 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:10 INFO - PID 1644 | 2017-05-14 14:19:10.597 plugin-container[1645:8331] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9723, name = 'com.apple.CFPasteboardClient' 14:19:10 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:10 INFO - PID 1644 | 2017-05-14 14:19:10.597 plugin-container[1645:8331] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:19:11 INFO - PID 1644 | 2017-05-14 14:19:11.330 plugin-container[1646:8376] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 14:19:11 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:11 INFO - PID 1644 | 2017-05-14 14:19:11.331 plugin-container[1646:8376] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 14:19:11 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:19:11 INFO - PID 1644 | 2017-05-14 14:19:11.331 plugin-container[1646:8376] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:19:20 INFO - PID 1644 | RSS: Main: 234192896 14:19:20 INFO - PID 1644 | 14:22:24 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 14:22:26 INFO - PID 1644 | RSS: Main: 255553536 14:22:26 INFO - PID 1644 | 14:25:28 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 14:25:29 INFO - PID 1644 | RSS: Main: 264384512 14:25:29 INFO - PID 1644 | 14:28:30 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 14:28:31 INFO - PID 1644 | RSS: Main: 271839232 14:28:31 INFO - PID 1644 | 14:31:33 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 14:31:33 INFO - PID 1644 | RSS: Main: 270184448 14:31:33 INFO - PID 1644 | 14:34:36 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 14:34:37 INFO - PID 1644 | RSS: Main: 271761408 14:34:37 INFO - PID 1644 | 14:37:38 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 14:37:39 INFO - PID 1644 | RSS: Main: 273027072 14:37:39 INFO - PID 1644 | 14:37:39 INFO - PID 1644 | __start_tp_report 14:37:39 INFO - PID 1644 | _x_x_mozilla_page_load 14:37:39 INFO - PID 1644 | _x_x_mozilla_page_load_details 14:37:39 INFO - PID 1644 | |i|pagename|runs| 14:37:39 INFO - PID 1644 | |0;dojo.html;3559.5016697662318;3596.4785106159607;3597.1007248187952;3616.168281295302;3617.3127105849894;6098.420650038239;6123.510119190465;6202.906956395657;6204.100405441209;6225.906611400833;6117.24893002886;6172.814815555529;6188.381161883809;6207;6260.499160067195;5381.81163659272;5406.702631355274;5421.484958928895;5424.620276580641;5437.782488700447;5466.863328416802;5519.978803921267;5538.695371754552;5566.582506312023;5576.749046292926;4358.455193100863;5450.645708029005;5473.808416705436;5522.585806064541;5536.861578460531;5396.271503347077;5434.83695489132;5436.130219164915;5492.890142197173;5507.642003269814;5441.047816632113;5457.918131228034;5460.6177567569885;5475.479829416239;5505.146702261156;4618.792154353062;5887;6223.906641400383;6231.750729970796;6279.215098112736;5448.754806033738;6429.710663020175;6512;6644.235912870034;6677.76627818024;6371.171747672819;6761;6783.559068660521;6797.694103765342;6810.080639113706;5734.942650573483;6336.778212762531;6357.300696923335;6362.87274254517;6412.038194270896;6609.6364699941005;6814.557053791487;6871.9656401717675;6894.069300644449;6909.861802764017;6600.372964568358;6609.239937407259;6620.073189753439;6625.3043430440075;6631.734730610818;6685.264620891698;6756.459483241329;6762.932370676329;6771.830704232335;6782.389584937379;6419.967900160563;6498.187726534184;6530.738770449128;6551.475881929434;6598.307177746267;6629.966850165718;6642.601443913284;6655.101561289261;6673.9332606673315;6694.9665251674405;5310.814121505806;5311.442298558596;5329.973350133225;5331.413544510101;5366.838994830161;4692.436907571113;4710.740909249956;4718.834840780556;4760.95239047612;4762.690425122356;6279.560430769893;6294.65379404128;6340.778072767431;6345.936540634627;6366.9681651591445;6771.762988295386;6772.932270677329;6781.355771201728;6781.966090169518;6795.014722865157;6031.733335999402;6207.81376558704;6274.309825919146;6333.081703153109;6357.713902874381;4949.727764972889;5934.554908381802;5935.169076329231;5945.375735547792;5989.64062156272;5999.340072592098;6695.799126026227;6772.932270677329;6905.067815844796;6913.827154321182;5592.86017849557;6525.445337146396;6741.797746067626;6799.966000170165;6803.625800580818;5613.803516876889;5708.8572785679025;6762.695678694372;6796.524243303119;6812.8978065328065;6585.012248162814;6614.702338394687;6675.599464032173;6685.197776266879;6692.698828552824;6607.339266073546;6745.325467453215;6768.864622707617;6776.288489708707;6809.69356378974;5773.134029895381;6401.295857455583;6451.387118223761;6484.837879052872;6587.934120658732;6565.409113179837;6565.507586930904;6566.409023187936;6569.277379488157;6578.901316480353;5773.278340207474;6301.684915754286;6344.936550634435;6462.644554549545;6464.644444555594;6517.641529715722;6576.704048317933;6596.967015164893;6622.602643841385;6627.801165965029;5440.211169380497;6307.05394190875;6353.4599559038;6394.072859435449;6455 14:37:39 INFO - PID 1644 | |1;ext.html;729.212450553402;801.7755028591994;825.785295823086;1113.2374323588338;1260.6722252214422;36379.63620363796;37194.070148246275;37410.4388434173;37414.438783418205;37513.874583762496;37683.62316376835;37894.62105378945;37902.8104859476;37912.43131353032;38057;7656.234376562341;7801.453898227126;8156.510609363428;8351.956005499313;8356.916430835674;7886.211378862096;8306.916930830675;8391.538465384401;8411.70559030434;8426.4944103354;8366.121557236494;8390.37072219586;8421.831563368729;8440.957795211047;8444.451110677786;8348.165183481604;8356.331493480508;8373.95813020937;8393.538355390421;8406;8241.299489543397;8318.875216871751;8324.29243514302;8324.958375208085;8325.333973282122;30872.919447819444;33188.66811331904;34955;35221.82389088064;35278;3537.4870643756512;3550;3580.1407662161155;3583.498310236569;3584.731145164123;29094.690738916586;29166.97915572966;29177.41645167106;29285.24288542673;29425.85287073572;3110.6422761382505;3121.219695076231;3143.2456210509313;3143.3713257348395;3155.384699983504;270267.6486617574;272232;290152.5492372546;300344;307216;38504;46915.530844691115;47009.76495117503;47176.7641161792;47251.52748472471;48334.758326208146;48549.5145048545;48574.514254857706;48626.78433039202;48671.269930951064;13709.725805483835;13830.308484575731;13907.79138312926;13917.58247252584;13922.512712055232;11266.887331126747;11311.321320720619;11423.257488263236;11431.714207144887;11455.656330310105;12729.618111456672;12904.096713229983;13034.08761386694;13053.216806991612;13153.276569788753;5098.209777484496;5325.840224793263;5849.327327357323;5933.169356290123;5945.464908158287;930.8968871886835;970.7039352997263;1002.734275417015;1005.5977608956476;1046.7487802927242;23327.066917323118;23497;23956.52086958251;24047.398815029763;24049.038038478615;1095.8520599719095;1096.9177311701653;1113.259682311254;1129.2377645089532;1146.8853114688632;11390.088792896548;12186.329751863732;12309.446074926649;12318.322492263012;12330.19853709506;334730;335443.32278338453;336131;336847;337935;51957;52186.21720674193;52376;52383.73808130935;52432.737836310574;1329.9601011969462;1457.3514785920163;1506.6384067823863;1507.9698406031819;1524.8246451657978;21644.891775541022;23397.298081057597;23459.530809384058;23806.10572900931;24005.27984160478;37184;38740.6125938737;48325;48628.75685621549;48756;290023.54988224927;291135;292356;292942;293168;33805.66194338025;34545.82727086348;34555;35199.64800352066;35295.294094118486;41867;41990.79004604957;43219.56780432281;43727.56272437235;43903.78048109867;26304.8684756575;29093.85453072721;30176.84911575428;30336.938207162642;30702;30010.849945751004;30064.699353006188;30605.622746975994;30695.846520767253;30910;35721.1069723259;36279.09302267464;36768.81615591905;36772.26455470929;36778.63221367752;27024.72975270222;29884.10347689573;30260.092197234117;30756.84621576878;30779.84610076935;19255.422337329903;24027.51944961126;24215.48378084453;24328.391790205387;24482.63276050825 14:37:39 INFO - PID 1644 | |2;jquery.html;393.3391901605303;427.3440269186799;431.1764529748182;437.3723706481199;453.92283311836985;17366.826331736695;19468;21528.815915124662;21606.243781467652;21611.351659450203;22390.440238994033;23232.65151022736;23622.409439764015;23636.5272694546;24049.879750601223;6126.509879209675;6279.120923070774;6297.2128483939505;6302.023186406114;6302.905456418157;5244.344456942882;5929.495992840613;6201.441870231678;6259.874802503947;6270.404311590391;6151.200343955302;6212.8446788830415;6215;6219.160413344186;6227.314995350508;5335.733213339317;5994.580379373446;6090.390960903918;6172.444479996777;6187.907181392283;5312.203169524563;5459.945400545984;5532.6680399175775;5532.6680399176175;5539.972300138514;27608.171754847386;27645.723542764517;28302.150935471764;28653.42693146146;29045.709542904722;2132.4562236629718;2145.442185031887;2184.235517568854;2186.125549780085;2197.835162362813;17973.281068757235;18053.368132115313;18296.9936653484;18442.723359149622;18750.90624546882;1897.8956157411337;2136.9679454808193;2158.7409510858643;2176.6082105221053;2176.771438998914;125715.74284257132;126191;150590;153441.23279383645;153458;12800.359982000862;13014;13287.734245315232;13300.534481293109;13335.866641333656;16797.58006049834;17083.40208092711;17087.889287196293;17092.40176593813;17098.316067357417;8806.559672016374;9112.179903808688;9121.498317592595;9161.77095572616;9211.355205135576;6731.730730770714;8489.40574159803;8620.181082797124;8808.867866981873;8810.691625793195;7030;7124.750633727795;7302.087239095114;8116.797080073045;8159.673613055411;2184.1154332495366;2194.7366316042176;2197.681336206273;2202.3613152185685;2383.130157492503;932.3892850183141;957.4733896356976;957.8649300578836;969.5636963366513;992.4541502173776;19617.92101434435;20761.377158685264;21186;21196.25813096565;21284.893575532333;892.616175044737;983.1544871410581;1016.588281745894;1016.627552803709;1022.5807418958192;10547.894521054692;10914.945425272823;10935.83596246057;10947.616833410792;10955.397453139994;545409;556975;564326;565389;566539.1673041609;129596;135170.32414837863;136321;137937;142943;1204.9939750301191;1239.0892693869998;1252.3174869696038;1255.7927941889486;1268.543324403214;21344.35966920995;22309.553808924054;22385.44036399038;22400.663990040488;22538.21116260923;55422.44577554173;58915;59577.808443831746;59760.10359844519;61605.691971539854;80960;81304.59347703223;82722.58638706768;83353.16646833454;84013;8019.478733882279;8939.374243803131;9197.172254497127;9211.447313161232;9535.618575256913;12599.18105323151;12718.491260349483;13660.795088073488;14798.6300342488;14821.25893705332;11900.821487677518;12131.75736485283;12232.816507752568;12253.203541769757;12265.38673066361;9903.752406189902;10473.68578942633;10654.709237513662;11529.481173347052;11660.06719462424;10408.219383546415;11415.60045398407;12036.277823330629;12398.132130751123;12541.372931353579;7310.597917114397;7637.350825179922;7980.201979801974;8820.779480513038;8823.205911467998;11025.558977640805;11502.42487875586;11660.825087623862;11809.23239989398;12224.75550489003 14:37:39 INFO - PID 1644 | |3;mootools.html;1164.4410682872222;1272.4782839035997;1460.6275399773056;1464.194692918895;1466.0617204988803;9998.600055997751;11449.313041217532;12068.637940861778;12125.454354554044;12338.568150114725;10236.692899213016;11931.224470409417;11942.522299108025;12184.756304873898;12213.51145954163;2583.9095631652895;2618.725033871445;2634.9077782277623;2637.986810065947;2638.8152829301957;2562.5387430262545;2606.569915963864;2611.6996545397333;2613.529564678357;2614.9738502615014;2575.047232523969;2584.9741502585016;2592.4555843272933;2598.753118453744;2598.8960441582312;1960.304092047317;2449.4121410861444;2466.6176742604744;2540.796736261095;2559.2194380713877;2480.8635525046116;2566.6278389633585;2575.2917947564383;2583.909563165299;2614.790816734657;11766;11848.644540663709;11851.762964740657;11880.405979701067;11883.702907427298;6634.336566343375;6891.827704307382;6896.620685862275;6897.655117244118;6899.62052087135;4089.141280331134;4416.514183439819;4463.062756821071;4463.732176069446;4469.910601787946;1246.5948566715763;1246.644706258721;1284.4541070045286;1286.8327117474669;1289.3682095773097;16322;16578.336866525326;16636.08501532415;16741.748873766905;16863.40978065774;4686.27362758773;5686.687232202268;5694.544436445074;5852.853678658068;5881.705914704333;11839.704007399712;11852.822207666892;11865.940670296593;11871.76256474883;11882.168248222715;6456.451201647818;6540.509461790385;6540.607563546202;6548.443382312556;6561.639109849004;5927.762889484372;6188.721507532172;6222.222222222223;6317.684115794192;6329.68351582419;3758.736888417839;4292.785360731951;4447.932496200889;4465.106978604292;4485.259932111165;1114.543037354697;1291.7868551689055;1300.0652023512635;1314.6853146853148;1326.699834162507;781.6521647866758;796.5539297993142;808.9433739638287;810.854046271665;827.9503229806231;6312.242530896322;6384.744610215632;6544.803655890331;6573.539852210395;6643.900341494881;584.9532037436995;929.330881765128;961.1781926452921;963.3208587945621;965.4014511003223;4392.956070439254;4452.465704115527;4580;4616.261398176276;4675.345451636774;12795.23228606268;14632.122072675673;14632.487862924747;15337.539873803804;15354.385824566893;14609.196494192709;14704.117752934859;15097.39610415593;15186.316615752097;15219.315130819139;7557.697692092364;8408.285295749807;8649.654013839376;8719;8756.299496040301;6799.184097908283;6818.28408017151;7121.679524421517;7250.854982900418;7381.741639042608;12586.307753073668;12808.615741527768;12871.935640321739;13096.017798664947;13123.868761312264;12581.370931453208;13176.011799114913;13238;13238.404271807984;13271.402786874422;7902.960485197537;8847.20375166238;9528.237740980567;9551.808963820824;9563.474008929576;9637.891642460927;10514.316569423268;10537.683869483928;10812.621558245739;10929.672109836718;9176.683082112058;9232.584533696134;9334.159925606727;9364.204042656178;9450.574724137568;8972.865407019031;9022.5939832709;9109.954450227706;9150.908490915272;9235.676751313671;7243.963780181065;7728.4590078693955;8414.074451810418;8445.366597505335;8458.323334133378;5504.559635229092;6159.384061593805;6255;6283.151774510383;6372.267189273293;5380.354357477127;5774.162746401832;6292;6333.904991425225;6453.193350831146 14:37:39 INFO - PID 1644 | |4;prototype.html;392.1490365905985;407.18359688823915;407.3177427808422;409.11630877305026;415.9251334759744;6318.620882747033;6354.428101470867;6431.94177863084;6447.387498187676;6450.903236451457;4519.412476378075;6044.667543285118;6181.3200547939805;6228.359871895433;6265.9686701566425;4727.078219747151;4734.337192793012;4751.881202969919;4756.619470442356;4796;4681.765911704401;4706.811727530911;4753.287006948951;4754.334393184957;4776.829676729206;3538.7345949053797;4192.5597812229735;4537.841175558856;4660.277656963159;4700.2009658358165;4047.959520404788;4583.839565615204;4733.219018861883;4744.979829336675;4748.548887855648;3561.6082230954576;4091.6112969267874;4177.603127702863;4194.3498757692605;4194.847101369653;5986.892424901407;6071.51427885768;6073.301570319381;6082.391760823926;6093.146959425684;5149.897002059938;5347.010803001457;5364.704941228231;5371.462853714636;5602.859928501779;5837;5891.116332550109;6042.637441753465;6635.43598794103;6722.260551339349;4070.857519986816;4888.364512613373;4928.383952005999;4929.556339929388;4946.604271658294;6618.338166183391;6870.965645171792;7049.647517624149;7146.356827885464;7225.638718064076;4088.8160032798596;4697.295405689174;4745.335653008581;4747.121782470254;4759.76201189946;3974.4435778990965;4026.8993275168355;4677.941705649571;4816.012717392996;4856.426094170096;5117.388022772815;5409.26974858397;5503.477169668875;5514.255575497256;5532.86167845799;5533.7786488540005;5791.148885990769;6690.732370705215;6707.993800929802;6725.495587830932;1719.5701074731317;1739.747736578189;1743.7907451105696;1750.291132091505;1756.5608597850537;1215.076541828217;1228.2507670321097;1231.2120243044458;1232.3037483821613;1250.693580072888;6357.618542887349;6359.20509936258;6359.364063593696;6375.521835862236;6406.75068361665;5673.432656734293;6839.93160068393;6926.58440493572;6963.816151254299;6969.2682268361095;5760.0207964646115;6000.5799594028;6184.6598437086395;6310.0534919761485;6373.139626150411;2793.20393687798;3160.8908419055138;3571.2678900825267;3596.5324507813816;3601.1717305019993;5863.618864773862;6059.484943779828;7060;7086.185088714864;7138.678759455837;5668.773249070074;5843.444872737083;6737.056812046318;7094.042304288855;7110.537815042109;5555.722213889289;5961.5230781537375;6234.438900498977;6466.4503517201565;7122.60825654594;5560.4161563035295;6316.494680425463;6328.557001009885;6904.654767261717;6970.769353806733;5542.695151766691;6510.641914694737;6724.327567243236;6767.35710107539;6801.251862295243;5903.339929243724;7185.82035449097;7191.712331506681;7225.602591857499;7244.927550724636;4456.924721941849;4497.752623605602;4501.482329532146;4508.616767574793;4527.547245275446;6741.325867413219;6976.720931162697;6995;7034.613096279549;7090.574565525879;5600.4959553640365;5844.211031510692;6064.545159113172;6488.772892948724;6782.626955517297;5652.039153343859;6485;6630.734770609121;6741.022551729873;6762.241817127484;6308.936910630835;6638;6638.402543771083;6640.236372817188;6718.22740384862;5924.348321684525;6369.90445143314;6387.37122033897;6734.225564060196;6805.319468053353;6062.605930614673;6335.493160547053;6591.176102987127;6620.271770105381;6624.8343791405605 14:37:39 INFO - PID 1644 | |5;yui.html;723.6707298179329;819.4141189049828;845.1464021338446;850.9531975741336;860.8923884514434;15311.387544498206;16186.352545898164;16716.164191790424;16772.83227167728;19329.806701932976;18085.005324707137;18417.987010714405;18483.53791155222;18560.257589696397;18666.533336666555;904.647551909894;954.885413750351;1012.8480727890802;1013.4172850610892;1016.3800081950004;1014.543455445049;1016.2022812092509;1018.9694309170736;1019.938803671782;1021.8313978193589;1048.2295512798125;1060.6393826099124;1062.0653824634312;1063.2079101069676;1063.2344711807525;1050.6637875879721;1056.8256237720766;1057.1014637558092;1057.3919996002292;1062.0335494699825;765.9923400765977;769.8768197088439;782.2685788787445;784.8469548438056;785.8978332816754;17083.145842707814;17485.65028699419;17671.73492397615;17698.93806371609;17706.203220854964;14034.438622455089;17363.392281270222;17413.868098573552;17693.73459398097;17918.372856949944;14168.787468187986;14175.929120354436;14399.92800035993;14457.132572045712;14505;3584.17563960288;3620.31214069326;3627.419612861956;3628.546431696038;3650.7992060436663;87406.56296718476;90023.64964525537;114555;116950.41524792321;117708.41145794302;13447.579435701471;13618.659533511543;13783.51757688476;13793.310334483236;13818.65453363674;22091.912996095773;24570.262892113264;25225.11712090068;25341.61987570188;25487.87256063745;7681.923180768233;7990.720324788721;8067.112617612058;8098.676052957816;8103.756887293391;3963.9009024774036;4050.5746896576024;4058.492688413948;4066.2070896175555;4069.308217603015;4359.80380882861;4380.496242932039;4499.572540608637;4516.728996260234;4521.525239849834;918.6141820435433;942.966996155145;955.5890966884307;959.9808003840024;963.4893506441597;653.5947712418331;671.8555510565154;677.7695583501633;682.9726810927507;696.8327601375634;9756.658516951873;11637.47631356591;12012.219205751773;12139.210951288138;12284.201526900722;647.1651569475324;652.9673516324165;657.6777379084215;663.8506336074441;685.4276678973014;5509.256250406224;5610.298712660918;5688.544916406678;5712.493403299797;5746.798862039808;103833.48083259686;104315.91368172594;105087.94912050782;105234;106510;89184;90429.09570904338;90485;92384.53807731053;92556.53721731484;61769.83805566784;78106.2189378099;80075.59962200152;80482;81472.18527814765;11228.943855280671;11259.605913792979;11385.430728463378;11482.885171148182;11531.769364612828;33402.83298583492;38578.228435431694;42025.159496810506;42132.57867421409;42558;42419.7879010603;55174;55541.444585553625;56026.15960760511;56332.31003069915;16748.497545073667;17192.398266060627;17234.91382543079;17374.305027798746;17499.95000299986;14914.925425372803;18013.72979405336;18037.639247215244;19018.904905475385;19124.904375478032;16864.494065178063;17782.555436114202;17794;18111.909440452713;18144.54638633992;14056.367463463965;16570.668586628442;16848;17026.744598831276;17252.309907604056;14088.295585220903;16634.833651663812;16745.497635070966;17055.829441705424;17128.828711712722;11731.178817482694;11932.70168245766;12152.21010634306;12179.147459677737;12278.631641050782;12175.39123043862;12781.872181278068;13179.472821087049;13239.139455935334;13321 14:37:39 INFO - PID 1644 | __end_tp_report 14:37:39 INFO - PID 1644 | __start_cc_report 14:37:39 INFO - PID 1644 | _x_x_mozilla_cycle_collect,351 14:37:39 INFO - PID 1644 | __end_cc_report 14:37:39 INFO - PID 1644 | __startTimestamp1494797859031__endTimestamp 14:37:39 INFO - PID 1644 | 14:37:39 INFO - PID 1644 | ------- Summary: start ------- 14:37:39 INFO - PID 1644 | Number of tests: 6 14:37:39 INFO - PID 1644 | 14:37:39 INFO - PID 1644 | [#0] dojo.html Cycles:165 Average:6108.94 Median:6357.30 stddev:735.50 (11.6%) stddev-sans-first:710.04 14:37:39 INFO - PID 1644 | Values: 3559.5 3596.5 3597.1 3616.2 3617.3 6098.4 6123.5 6202.9 6204.1 6225.9 6117.2 6172.8 6188.4 6207.0 6260.5 5381.8 5406.7 5421.5 5424.6 5437.8 5466.9 5520.0 5538.7 5566.6 5576.7 4358.5 5450.6 5473.8 5522.6 5536.9 5396.3 5434.8 5436.1 5492.9 5507.6 5441.0 5457.9 5460.6 5475.5 5505.1 4618.8 5887.0 6223.9 6231.8 6279.2 5448.8 6429.7 6512.0 6644.2 6677.8 6371.2 6761.0 6783.6 6797.7 6810.1 5734.9 6336.8 6357.3 6362.9 6412.0 6609.6 6814.6 6872.0 6894.1 6909.9 6600.4 6609.2 6620.1 6625.3 6631.7 6685.3 6756.5 6762.9 6771.8 6782.4 6420.0 6498.2 6530.7 6551.5 6598.3 6630.0 6642.6 6655.1 6673.9 6695.0 5310.8 5311.4 5330.0 5331.4 5366.8 4692.4 4710.7 4718.8 4761.0 4762.7 6279.6 6294.7 6340.8 6345.9 6367.0 6771.8 6772.9 6781.4 6782.0 6795.0 6031.7 6207.8 6274.3 6333.1 6357.7 4949.7 5934.6 5935.2 5945.4 5989.6 5999.3 6695.8 6772.9 6905.1 6913.8 5592.9 6525.4 6741.8 6800.0 6803.6 5613.8 5708.9 6762.7 6796.5 6812.9 6585.0 6614.7 6675.6 6685.2 6692.7 6607.3 6745.3 6768.9 6776.3 6809.7 5773.1 6401.3 6451.4 6484.8 6587.9 6565.4 6565.5 6566.4 6569.3 6578.9 5773.3 6301.7 6344.9 6462.6 6464.6 6517.6 6576.7 6597.0 6622.6 6627.8 5440.2 6307.1 6353.5 6394.1 6455.0 14:37:39 INFO - PID 1644 | 14:37:39 INFO - PID 1644 | [#1] ext.html Cycles:180 Average:45206.33 Median:337391.00 stddev:80432.57 (23.8%) stddev-sans-first:80588.88 14:37:39 INFO - PID 1644 | Values: 729.2 801.8 825.8 1113.2 1260.7 36379.6 37194.1 37410.4 37414.4 37513.9 37683.6 37894.6 37902.8 37912.4 38057.0 7656.2 7801.5 8156.5 8352.0 8356.9 7886.2 8306.9 8391.5 8411.7 8426.5 8366.1 8390.4 8421.8 8441.0 8444.5 8348.2 8356.3 8374.0 8393.5 8406.0 8241.3 8318.9 8324.3 8325.0 8325.3 30872.9 33188.7 34955.0 35221.8 35278.0 3537.5 3550.0 3580.1 3583.5 3584.7 29094.7 29167.0 29177.4 29285.2 29425.9 3110.6 3121.2 3143.2 3143.4 3155.4 270267.6 272232.0 290152.5 300344.0 307216.0 38504.0 46915.5 47009.8 47176.8 47251.5 48334.8 48549.5 48574.5 48626.8 48671.3 13709.7 13830.3 13907.8 13917.6 13922.5 11266.9 11311.3 11423.3 11431.7 11455.7 12729.6 12904.1 13034.1 13053.2 13153.3 5098.2 5325.8 5849.3 5933.2 5945.5 930.9 970.7 1002.7 1005.6 1046.7 23327.1 23497.0 23956.5 24047.4 24049.0 1095.9 1096.9 1113.3 1129.2 1146.9 11390.1 12186.3 12309.4 12318.3 12330.2 334730.0 335443.3 336131.0 336847.0 337935.0 51957.0 52186.2 52376.0 52383.7 52432.7 1330.0 1457.4 1506.6 1508.0 1524.8 21644.9 23397.3 23459.5 23806.1 24005.3 37184.0 38740.6 48325.0 48628.8 48756.0 290023.5 291135.0 292356.0 292942.0 293168.0 33805.7 34545.8 34555.0 35199.6 35295.3 41867.0 41990.8 43219.6 43727.6 43903.8 26304.9 29093.9 30176.8 30336.9 30702.0 30010.8 30064.7 30605.6 30695.8 30910.0 35721.1 36279.1 36768.8 36772.3 36778.6 27024.7 29884.1 30260.1 30756.8 30779.8 19255.4 24027.5 24215.5 24328.4 24482.6 14:37:39 INFO - PID 1644 | 14:37:39 INFO - PID 1644 | [#2] jquery.html Cycles:180 Average:35999.52 Median:22469.44 stddev:94662.79 (421.3%) stddev-sans-first:94890.60 14:37:39 INFO - PID 1644 | Values: 393.3 427.3 431.2 437.4 453.9 17366.8 19468.0 21528.8 21606.2 21611.4 22390.4 23232.7 23622.4 23636.5 24049.9 6126.5 6279.1 6297.2 6302.0 6302.9 5244.3 5929.5 6201.4 6259.9 6270.4 6151.2 6212.8 6215.0 6219.2 6227.3 5335.7 5994.6 6090.4 6172.4 6187.9 5312.2 5459.9 5532.7 5532.7 5540.0 27608.2 27645.7 28302.2 28653.4 29045.7 2132.5 2145.4 2184.2 2186.1 2197.8 17973.3 18053.4 18297.0 18442.7 18750.9 1897.9 2137.0 2158.7 2176.6 2176.8 125715.7 126191.0 150590.0 153441.2 153458.0 12800.4 13014.0 13287.7 13300.5 13335.9 16797.6 17083.4 17087.9 17092.4 17098.3 8806.6 9112.2 9121.5 9161.8 9211.4 6731.7 8489.4 8620.2 8808.9 8810.7 7030.0 7124.8 7302.1 8116.8 8159.7 2184.1 2194.7 2197.7 2202.4 2383.1 932.4 957.5 957.9 969.6 992.5 19617.9 20761.4 21186.0 21196.3 21284.9 892.6 983.2 1016.6 1016.6 1022.6 10547.9 10914.9 10935.8 10947.6 10955.4 545409.0 556975.0 564326.0 565389.0 566539.2 129596.0 135170.3 136321.0 137937.0 142943.0 1205.0 1239.1 1252.3 1255.8 1268.5 21344.4 22309.6 22385.4 22400.7 22538.2 55422.4 58915.0 59577.8 59760.1 61605.7 80960.0 81304.6 82722.6 83353.2 84013.0 8019.5 8939.4 9197.2 9211.4 9535.6 12599.2 12718.5 13660.8 14798.6 14821.3 11900.8 12131.8 12232.8 12253.2 12265.4 9903.8 10473.7 10654.7 11529.5 11660.1 10408.2 11415.6 12036.3 12398.1 12541.4 7310.6 7637.4 7980.2 8820.8 8823.2 11025.6 11502.4 11660.8 11809.2 12224.8 14:37:39 INFO - PID 1644 | 14:37:39 INFO - PID 1644 | [#3] mootools.html Cycles:180 Average:7112.15 Median:4342.87 stddev:4440.21 (102.2%) stddev-sans-first:4430.16 14:37:39 INFO - PID 1644 | Values: 1164.4 1272.5 1460.6 1464.2 1466.1 9998.6 11449.3 12068.6 12125.5 12338.6 10236.7 11931.2 11942.5 12184.8 12213.5 2583.9 2618.7 2634.9 2638.0 2638.8 2562.5 2606.6 2611.7 2613.5 2615.0 2575.0 2585.0 2592.5 2598.8 2598.9 1960.3 2449.4 2466.6 2540.8 2559.2 2480.9 2566.6 2575.3 2583.9 2614.8 11766.0 11848.6 11851.8 11880.4 11883.7 6634.3 6891.8 6896.6 6897.7 6899.6 4089.1 4416.5 4463.1 4463.7 4469.9 1246.6 1246.6 1284.5 1286.8 1289.4 16322.0 16578.3 16636.1 16741.7 16863.4 4686.3 5686.7 5694.5 5852.9 5881.7 11839.7 11852.8 11865.9 11871.8 11882.2 6456.5 6540.5 6540.6 6548.4 6561.6 5927.8 6188.7 6222.2 6317.7 6329.7 3758.7 4292.8 4447.9 4465.1 4485.3 1114.5 1291.8 1300.1 1314.7 1326.7 781.7 796.6 808.9 810.9 828.0 6312.2 6384.7 6544.8 6573.5 6643.9 585.0 929.3 961.2 963.3 965.4 4393.0 4452.5 4580.0 4616.3 4675.3 12795.2 14632.1 14632.5 15337.5 15354.4 14609.2 14704.1 15097.4 15186.3 15219.3 7557.7 8408.3 8649.7 8719.0 8756.3 6799.2 6818.3 7121.7 7250.9 7381.7 12586.3 12808.6 12871.9 13096.0 13123.9 12581.4 13176.0 13238.0 13238.4 13271.4 7903.0 8847.2 9528.2 9551.8 9563.5 9637.9 10514.3 10537.7 10812.6 10929.7 9176.7 9232.6 9334.2 9364.2 9450.6 8972.9 9022.6 9110.0 9150.9 9235.7 7244.0 7728.5 8414.1 8445.4 8458.3 5504.6 6159.4 6255.0 6283.2 6372.3 5380.4 5774.2 6292.0 6333.9 6453.2 14:37:39 INFO - PID 1644 | 14:37:39 INFO - PID 1644 | [#4] prototype.html Cycles:180 Average:5376.10 Median:5897.23 stddev:1637.20 (27.8%) stddev-sans-first:1598.48 14:37:39 INFO - PID 1644 | Values: 392.1 407.2 407.3 409.1 415.9 6318.6 6354.4 6431.9 6447.4 6450.9 4519.4 6044.7 6181.3 6228.4 6266.0 4727.1 4734.3 4751.9 4756.6 4796.0 4681.8 4706.8 4753.3 4754.3 4776.8 3538.7 4192.6 4537.8 4660.3 4700.2 4048.0 4583.8 4733.2 4745.0 4748.5 3561.6 4091.6 4177.6 4194.3 4194.8 5986.9 6071.5 6073.3 6082.4 6093.1 5149.9 5347.0 5364.7 5371.5 5602.9 5837.0 5891.1 6042.6 6635.4 6722.3 4070.9 4888.4 4928.4 4929.6 4946.6 6618.3 6871.0 7049.6 7146.4 7225.6 4088.8 4697.3 4745.3 4747.1 4759.8 3974.4 4026.9 4677.9 4816.0 4856.4 5117.4 5409.3 5503.5 5514.3 5532.9 5533.8 5791.1 6690.7 6708.0 6725.5 1719.6 1739.7 1743.8 1750.3 1756.6 1215.1 1228.3 1231.2 1232.3 1250.7 6357.6 6359.2 6359.4 6375.5 6406.8 5673.4 6839.9 6926.6 6963.8 6969.3 5760.0 6000.6 6184.7 6310.1 6373.1 2793.2 3160.9 3571.3 3596.5 3601.2 5863.6 6059.5 7060.0 7086.2 7138.7 5668.8 5843.4 6737.1 7094.0 7110.5 5555.7 5961.5 6234.4 6466.5 7122.6 5560.4 6316.5 6328.6 6904.7 6970.8 5542.7 6510.6 6724.3 6767.4 6801.3 5903.3 7185.8 7191.7 7225.6 7244.9 4456.9 4497.8 4501.5 4508.6 4527.5 6741.3 6976.7 6995.0 7034.6 7090.6 5600.5 5844.2 6064.5 6488.8 6782.6 5652.0 6485.0 6630.7 6741.0 6762.2 6308.9 6638.0 6638.4 6640.2 6718.2 5924.3 6369.9 6387.4 6734.2 6805.3 6062.6 6335.5 6591.2 6620.3 6624.8 14:37:39 INFO - PID 1644 | 14:37:39 INFO - PID 1644 | [#5] yui.html Cycles:180 Average:21111.12 Median:18098.46 stddev:28602.46 (158.0%) stddev-sans-first:28641.73 14:37:39 INFO - PID 1644 | Values: 723.7 819.4 845.1 851.0 860.9 15311.4 16186.4 16716.2 16772.8 19329.8 18085.0 18418.0 18483.5 18560.3 18666.5 904.6 954.9 1012.8 1013.4 1016.4 1014.5 1016.2 1019.0 1019.9 1021.8 1048.2 1060.6 1062.1 1063.2 1063.2 1050.7 1056.8 1057.1 1057.4 1062.0 766.0 769.9 782.3 784.8 785.9 17083.1 17485.7 17671.7 17698.9 17706.2 14034.4 17363.4 17413.9 17693.7 17918.4 14168.8 14175.9 14399.9 14457.1 14505.0 3584.2 3620.3 3627.4 3628.5 3650.8 87406.6 90023.6 114555.0 116950.4 117708.4 13447.6 13618.7 13783.5 13793.3 13818.7 22091.9 24570.3 25225.1 25341.6 25487.9 7681.9 7990.7 8067.1 8098.7 8103.8 3963.9 4050.6 4058.5 4066.2 4069.3 4359.8 4380.5 4499.6 4516.7 4521.5 918.6 943.0 955.6 960.0 963.5 653.6 671.9 677.8 683.0 696.8 9756.7 11637.5 12012.2 12139.2 12284.2 647.2 653.0 657.7 663.9 685.4 5509.3 5610.3 5688.5 5712.5 5746.8 103833.5 104315.9 105087.9 105234.0 106510.0 89184.0 90429.1 90485.0 92384.5 92556.5 61769.8 78106.2 80075.6 80482.0 81472.2 11228.9 11259.6 11385.4 11482.9 11531.8 33402.8 38578.2 42025.2 42132.6 42558.0 42419.8 55174.0 55541.4 56026.2 56332.3 16748.5 17192.4 17234.9 17374.3 17500.0 14914.9 18013.7 18037.6 19018.9 19124.9 16864.5 17782.6 17794.0 18111.9 18144.5 14056.4 16570.7 16848.0 17026.7 17252.3 14088.3 16634.8 16745.5 17055.8 17128.8 11731.2 11932.7 12152.2 12179.1 12278.6 12175.4 12781.9 13179.5 13239.1 13321.0 14:37:39 INFO - PID 1644 | -------- Summary: end -------- 14:37:39 INFO - PID 1644 | 14:37:39 INFO - TEST-INFO | 1644: exit 0 14:37:39 INFO - TEST-OK | dromaeo_css | took 1114403ms 14:37:39 INFO - TEST-START | kraken 14:37:39 INFO - operating with platform_type : mac_ 14:37:39 INFO - Initialising browser for kraken test... 14:37:39 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpAi77Q3/profile http://localhost:49226/getInfo.html) 14:37:40 INFO - PID 1655 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2932: String.localeCompare is deprecated; use String.prototype.localeCompare instead 14:37:42 INFO - PID 1655 | 2017-05-14 14:37:42.125 plugin-container[1656:10727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 14:37:42 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:37:42 INFO - PID 1655 | 2017-05-14 14:37:42.129 plugin-container[1656:10727] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4427, name = 'com.apple.CFPasteboardClient' 14:37:42 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:37:42 INFO - PID 1655 | 2017-05-14 14:37:42.129 plugin-container[1656:10727] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:37:42 INFO - PID 1655 | __metrics Screen width/height:1600/1200 14:37:42 INFO - PID 1655 | colorDepth:24 14:37:42 INFO - PID 1655 | Browser inner width/height: 1024/691 14:37:42 INFO - PID 1655 | __metrics 14:37:43 INFO - TEST-INFO | 1655: exit 0 14:37:43 INFO - Browser initialized. 14:37:43 INFO - Running cycle 1/1 for kraken test... 14:37:43 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/T/tmpAi77Q3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:37:45 INFO - PID 1658 | 2017-05-14 14:37:45.107 plugin-container[1659:10867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x912b, name = 'com.apple.tsm.portname' 14:37:45 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:37:45 INFO - PID 1658 | 2017-05-14 14:37:45.108 plugin-container[1659:10867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 14:37:45 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:37:45 INFO - PID 1658 | 2017-05-14 14:37:45.108 plugin-container[1659:10867] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:37:45 INFO - PID 1658 | 2017-05-14 14:37:45.753 plugin-container[1660:10914] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 14:37:45 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:37:45 INFO - PID 1658 | 2017-05-14 14:37:45.753 plugin-container[1660:10914] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9803, name = 'com.apple.CFPasteboardClient' 14:37:45 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:37:45 INFO - PID 1658 | 2017-05-14 14:37:45.753 plugin-container[1660:10914] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:37:55 INFO - PID 1658 | RSS: Main: 236052480 14:37:55 INFO - PID 1658 | 14:37:58 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 14:37:58 INFO - PID 1658 | RSS: Main: 248184832 14:37:58 INFO - PID 1658 | 14:38:01 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 14:38:01 INFO - PID 1658 | RSS: Main: 256499712 14:38:01 INFO - PID 1658 | 14:38:05 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 14:38:05 INFO - PID 1658 | RSS: Main: 258215936 14:38:05 INFO - PID 1658 | 14:38:07 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 14:38:08 INFO - PID 1658 | RSS: Main: 259280896 14:38:08 INFO - PID 1658 | 14:38:10 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:38:10 INFO - PID 1658 | RSS: Main: 261152768 14:38:10 INFO - PID 1658 | 14:38:13 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 14:38:14 INFO - PID 1658 | RSS: Main: 267276288 14:38:14 INFO - PID 1658 | 14:38:17 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 14:38:18 INFO - PID 1658 | RSS: Main: 267038720 14:38:18 INFO - PID 1658 | 14:38:21 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 14:38:22 INFO - PID 1658 | RSS: Main: 266485760 14:38:22 INFO - PID 1658 | 14:38:23 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:38:24 INFO - PID 1658 | RSS: Main: 265310208 14:38:24 INFO - PID 1658 | 14:38:26 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:38:26 INFO - PID 1658 | RSS: Main: 265805824 14:38:26 INFO - PID 1658 | 14:38:28 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:38:29 INFO - PID 1658 | RSS: Main: 265547776 14:38:29 INFO - PID 1658 | 14:38:32 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:38:33 INFO - PID 1658 | RSS: Main: 265748480 14:38:33 INFO - PID 1658 | 14:38:36 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:38:37 INFO - PID 1658 | RSS: Main: 265789440 14:38:37 INFO - PID 1658 | 14:38:38 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 14:38:39 INFO - PID 1658 | RSS: Main: 267341824 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | __start_tp_report 14:38:39 INFO - PID 1658 | _x_x_mozilla_page_load 14:38:39 INFO - PID 1658 | _x_x_mozilla_page_load_details 14:38:39 INFO - PID 1658 | |i|pagename|runs| 14:38:39 INFO - PID 1658 | |0;ai-astar;102;116;116;118;107;150;168;150;169;150 14:38:39 INFO - PID 1658 | |1;audio-beat-detection;131;114;114;145;107;112;111;111;137;110 14:38:39 INFO - PID 1658 | |2;audio-dft;200;194;196;201;205;211;207;198;198;197 14:38:39 INFO - PID 1658 | |3;audio-fft;79;62;60;83;61;81;91;53;89;82 14:38:39 INFO - PID 1658 | |4;audio-oscillator;90;70;81;70;71;71;86;70;71;90 14:38:39 INFO - PID 1658 | |5;imaging-gaussian-blur;89;89;89;88;91;89;90;89;89;90 14:38:39 INFO - PID 1658 | |6;imaging-darkroom;97;105;97;97;112;110;138;97;96;122 14:38:39 INFO - PID 1658 | |7;imaging-desaturate;140;75;82;116;147;139;94;87;145;142 14:38:39 INFO - PID 1658 | |8;json-parse-financial;42;47;73;74;64;74;43;45;71;74 14:38:39 INFO - PID 1658 | |9;json-stringify-tinderbox;58;65;58;60;72;69;67;82;81;58 14:38:39 INFO - PID 1658 | |10;stanford-crypto-aes;84;84;84;83;81;85;86;83;83;82 14:38:39 INFO - PID 1658 | |11;stanford-crypto-ccm;140;138;116;143;153;170;147;174;182;155 14:38:39 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;182;182;184;171;226;231;188;184;188;190 14:38:39 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;70;72;82;100;95;79;67;71;71;77 14:38:39 INFO - PID 1658 | __end_tp_report 14:38:39 INFO - PID 1658 | __start_cc_report 14:38:39 INFO - PID 1658 | _x_x_mozilla_cycle_collect,917 14:38:39 INFO - PID 1658 | __end_cc_report 14:38:39 INFO - PID 1658 | __startTimestamp1494797919153__endTimestamp 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | ------- Summary: start ------- 14:38:39 INFO - PID 1658 | Number of tests: 14 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:134.60 Median:150.00 stddev:25.39 (16.9%) stddev-sans-first:24.04 14:38:39 INFO - PID 1658 | Values: 102.0 116.0 116.0 118.0 107.0 150.0 168.0 150.0 169.0 150.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:119.20 Median:114.00 stddev:13.31 (11.7%) stddev-sans-first:13.42 14:38:39 INFO - PID 1658 | Values: 131.0 114.0 114.0 145.0 107.0 112.0 111.0 111.0 137.0 110.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:200.70 Median:200.50 stddev:5.38 (2.7%) stddev-sans-first:5.70 14:38:39 INFO - PID 1658 | Values: 200.0 194.0 196.0 201.0 205.0 211.0 207.0 198.0 198.0 197.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:74.10 Median:81.50 stddev:13.67 (16.8%) stddev-sans-first:14.39 14:38:39 INFO - PID 1658 | Values: 79.0 62.0 60.0 83.0 61.0 81.0 91.0 53.0 89.0 82.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:76.00 stddev:8.76 (11.5%) stddev-sans-first:7.92 14:38:39 INFO - PID 1658 | Values: 90.0 70.0 81.0 70.0 71.0 71.0 86.0 70.0 71.0 90.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:89.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 14:38:39 INFO - PID 1658 | Values: 89.0 89.0 89.0 88.0 91.0 89.0 90.0 89.0 89.0 90.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:107.10 Median:96.50 stddev:13.92 (14.4%) stddev-sans-first:14.28 14:38:39 INFO - PID 1658 | Values: 97.0 105.0 97.0 97.0 112.0 110.0 138.0 97.0 96.0 122.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:116.70 Median:111.00 stddev:29.33 (26.4%) stddev-sans-first:29.87 14:38:39 INFO - PID 1658 | Values: 140.0 75.0 82.0 116.0 147.0 139.0 94.0 87.0 145.0 142.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:60.70 Median:72.00 stddev:14.51 (20.2%) stddev-sans-first:13.73 14:38:39 INFO - PID 1658 | Values: 42.0 47.0 73.0 74.0 64.0 74.0 43.0 45.0 71.0 74.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:68.00 stddev:9.10 (13.4%) stddev-sans-first:9.06 14:38:39 INFO - PID 1658 | Values: 58.0 65.0 58.0 60.0 72.0 69.0 67.0 82.0 81.0 58.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 14:38:39 INFO - PID 1658 | Values: 84.0 84.0 84.0 83.0 81.0 85.0 86.0 83.0 83.0 82.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:151.80 Median:154.00 stddev:19.61 (12.7%) stddev-sans-first:20.33 14:38:39 INFO - PID 1658 | Values: 140.0 138.0 116.0 143.0 153.0 170.0 147.0 174.0 182.0 155.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.60 Median:188.00 stddev:19.66 (10.5%) stddev-sans-first:20.47 14:38:39 INFO - PID 1658 | Values: 182.0 182.0 184.0 171.0 226.0 231.0 188.0 184.0 188.0 190.0 14:38:39 INFO - PID 1658 | 14:38:39 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.40 Median:74.50 stddev:11.10 (14.9%) stddev-sans-first:11.35 14:38:39 INFO - PID 1658 | Values: 70.0 72.0 82.0 100.0 95.0 79.0 67.0 71.0 71.0 77.0 14:38:39 INFO - PID 1658 | -------- Summary: end -------- 14:38:39 INFO - PID 1658 | 14:38:39 INFO - TEST-INFO | 1658: exit 0 14:38:40 INFO - TEST-OK | kraken | took 60379ms 14:38:40 INFO - SUITE-END | took 1174s 14:38:40 INFO - Completed test suite (00:19:35) 14:38:40 INFO - javascript benchmark 14:38:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7907.030071237829, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3559.5016697662318, 3596.4785106159607, 3597.1007248187952, 3616.168281295302, 3617.3127105849894, 6098.420650038239, 6123.510119190465, 6202.906956395657, 6204.100405441209, 6225.906611400833, 6117.24893002886, 6172.814815555529, 6188.381161883809, 6207.0, 6260.499160067195, 5381.81163659272, 5406.702631355274, 5421.484958928895, 5424.620276580641, 5437.782488700447, 5466.863328416802, 5519.978803921267, 5538.695371754552, 5566.582506312023, 5576.749046292926, 4358.455193100863, 5450.645708029005, 5473.808416705436, 5522.585806064541, 5536.861578460531, 5396.271503347077, 5434.83695489132, 5436.130219164915, 5492.890142197173, 5507.642003269814, 5441.047816632113, 5457.918131228034, 5460.6177567569885, 5475.479829416239, 5505.146702261156, 4618.792154353062, 5887.0, 6223.906641400383, 6231.750729970796, 6279.215098112736, 5448.754806033738, 6429.710663020175, 6512.0, 6644.235912870034, 6677.76627818024, 6371.171747672819, 6761.0, 6783.559068660521, 6797.694103765342, 6810.080639113706, 5734.942650573483, 6336.778212762531, 6357.300696923335, 6362.87274254517, 6412.038194270896, 6609.6364699941005, 6814.557053791487, 6871.9656401717675, 6894.069300644449, 6909.861802764017, 6600.372964568358, 6609.239937407259, 6620.073189753439, 6625.3043430440075, 6631.734730610818, 6685.264620891698, 6756.459483241329, 6762.932370676329, 6771.830704232335, 6782.389584937379, 6419.967900160563, 6498.187726534184, 6530.738770449128, 6551.475881929434, 6598.307177746267, 6629.966850165718, 6642.601443913284, 6655.101561289261, 6673.9332606673315, 6694.9665251674405, 5310.814121505806, 5311.442298558596, 5329.973350133225, 5331.413544510101, 5366.838994830161, 4692.436907571113, 4710.740909249956, 4718.834840780556, 4760.95239047612, 4762.690425122356, 6279.560430769893, 6294.65379404128, 6340.778072767431, 6345.936540634627, 6366.9681651591445, 6771.762988295386, 6772.932270677329, 6781.355771201728, 6781.966090169518, 6795.014722865157, 6031.733335999402, 6207.81376558704, 6274.309825919146, 6333.081703153109, 6357.713902874381, 4949.727764972889, 5934.554908381802, 5935.169076329231, 5945.375735547792, 5989.64062156272, 5999.340072592098, 6695.799126026227, 6772.932270677329, 6905.067815844796, 6913.827154321182, 5592.86017849557, 6525.445337146396, 6741.797746067626, 6799.966000170165, 6803.625800580818, 5613.803516876889, 5708.8572785679025, 6762.695678694372, 6796.524243303119, 6812.8978065328065, 6585.012248162814, 6614.702338394687, 6675.599464032173, 6685.197776266879, 6692.698828552824, 6607.339266073546, 6745.325467453215, 6768.864622707617, 6776.288489708707, 6809.69356378974, 5773.134029895381, 6401.295857455583, 6451.387118223761, 6484.837879052872, 6587.934120658732, 6565.409113179837, 6565.507586930904, 6566.409023187936, 6569.277379488157, 6578.901316480353, 5773.278340207474, 6301.684915754286, 6344.936550634435, 6462.644554549545, 6464.644444555594, 6517.641529715722, 6576.704048317933, 6596.967015164893, 6622.602643841385, 6627.801165965029, 5440.211169380497, 6307.05394190875, 6353.4599559038, 6394.072859435449, 6455.0], "value": 6063.009641580071, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.212450553402, 801.7755028591994, 825.785295823086, 1113.2374323588338, 1260.6722252214422, 36379.63620363796, 37194.070148246275, 37410.4388434173, 37414.438783418205, 37513.874583762496, 37683.62316376835, 37894.62105378945, 37902.8104859476, 37912.43131353032, 38057.0, 7656.234376562341, 7801.453898227126, 8156.510609363428, 8351.956005499313, 8356.916430835674, 7886.211378862096, 8306.916930830675, 8391.538465384401, 8411.70559030434, 8426.4944103354, 8366.121557236494, 8390.37072219586, 8421.831563368729, 8440.957795211047, 8444.451110677786, 8348.165183481604, 8356.331493480508, 8373.95813020937, 8393.538355390421, 8406.0, 8241.299489543397, 8318.875216871751, 8324.29243514302, 8324.958375208085, 8325.333973282122, 30872.919447819444, 33188.66811331904, 34955.0, 35221.82389088064, 35278.0, 3537.4870643756512, 3550.0, 3580.1407662161155, 3583.498310236569, 3584.731145164123, 29094.690738916586, 29166.97915572966, 29177.41645167106, 29285.24288542673, 29425.85287073572, 3110.6422761382505, 3121.219695076231, 3143.2456210509313, 3143.3713257348395, 3155.384699983504, 270267.6486617574, 272232.0, 290152.5492372546, 300344.0, 307216.0, 38504.0, 46915.530844691115, 47009.76495117503, 47176.7641161792, 47251.52748472471, 48334.758326208146, 48549.5145048545, 48574.514254857706, 48626.78433039202, 48671.269930951064, 13709.725805483835, 13830.308484575731, 13907.79138312926, 13917.58247252584, 13922.512712055232, 11266.887331126747, 11311.321320720619, 11423.257488263236, 11431.714207144887, 11455.656330310105, 12729.618111456672, 12904.096713229983, 13034.08761386694, 13053.216806991612, 13153.276569788753, 5098.209777484496, 5325.840224793263, 5849.327327357323, 5933.169356290123, 5945.464908158287, 930.8968871886835, 970.7039352997263, 1002.734275417015, 1005.5977608956476, 1046.7487802927242, 23327.066917323118, 23497.0, 23956.52086958251, 24047.398815029763, 24049.038038478615, 1095.8520599719095, 1096.9177311701653, 1113.259682311254, 1129.2377645089532, 1146.8853114688632, 11390.088792896548, 12186.329751863732, 12309.446074926649, 12318.322492263012, 12330.19853709506, 334730.0, 335443.32278338453, 336131.0, 336847.0, 337935.0, 51957.0, 52186.21720674193, 52376.0, 52383.73808130935, 52432.737836310574, 1329.9601011969462, 1457.3514785920163, 1506.6384067823863, 1507.9698406031819, 1524.8246451657978, 21644.891775541022, 23397.298081057597, 23459.530809384058, 23806.10572900931, 24005.27984160478, 37184.0, 38740.6125938737, 48325.0, 48628.75685621549, 48756.0, 290023.54988224927, 291135.0, 292356.0, 292942.0, 293168.0, 33805.66194338025, 34545.82727086348, 34555.0, 35199.64800352066, 35295.294094118486, 41867.0, 41990.79004604957, 43219.56780432281, 43727.56272437235, 43903.78048109867, 26304.8684756575, 29093.85453072721, 30176.84911575428, 30336.938207162642, 30702.0, 30010.849945751004, 30064.699353006188, 30605.622746975994, 30695.846520767253, 30910.0, 35721.1069723259, 36279.09302267464, 36768.81615591905, 36772.26455470929, 36778.63221367752, 27024.72975270222, 29884.10347689573, 30260.092197234117, 30756.84621576878, 30779.84610076935, 19255.422337329903, 24027.51944961126, 24215.48378084453, 24328.391790205387, 24482.63276050825], "value": 17273.248443139564, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.3391901605303, 427.3440269186799, 431.1764529748182, 437.3723706481199, 453.92283311836985, 17366.826331736695, 19468.0, 21528.815915124662, 21606.243781467652, 21611.351659450203, 22390.440238994033, 23232.65151022736, 23622.409439764015, 23636.5272694546, 24049.879750601223, 6126.509879209675, 6279.120923070774, 6297.2128483939505, 6302.023186406114, 6302.905456418157, 5244.344456942882, 5929.495992840613, 6201.441870231678, 6259.874802503947, 6270.404311590391, 6151.200343955302, 6212.8446788830415, 6215.0, 6219.160413344186, 6227.314995350508, 5335.733213339317, 5994.580379373446, 6090.390960903918, 6172.444479996777, 6187.907181392283, 5312.203169524563, 5459.945400545984, 5532.6680399175775, 5532.6680399176175, 5539.972300138514, 27608.171754847386, 27645.723542764517, 28302.150935471764, 28653.42693146146, 29045.709542904722, 2132.4562236629718, 2145.442185031887, 2184.235517568854, 2186.125549780085, 2197.835162362813, 17973.281068757235, 18053.368132115313, 18296.9936653484, 18442.723359149622, 18750.90624546882, 1897.8956157411337, 2136.9679454808193, 2158.7409510858643, 2176.6082105221053, 2176.771438998914, 125715.74284257132, 126191.0, 150590.0, 153441.23279383645, 153458.0, 12800.359982000862, 13014.0, 13287.734245315232, 13300.534481293109, 13335.866641333656, 16797.58006049834, 17083.40208092711, 17087.889287196293, 17092.40176593813, 17098.316067357417, 8806.559672016374, 9112.179903808688, 9121.498317592595, 9161.77095572616, 9211.355205135576, 6731.730730770714, 8489.40574159803, 8620.181082797124, 8808.867866981873, 8810.691625793195, 7030.0, 7124.750633727795, 7302.087239095114, 8116.797080073045, 8159.673613055411, 2184.1154332495366, 2194.7366316042176, 2197.681336206273, 2202.3613152185685, 2383.130157492503, 932.3892850183141, 957.4733896356976, 957.8649300578836, 969.5636963366513, 992.4541502173776, 19617.92101434435, 20761.377158685264, 21186.0, 21196.25813096565, 21284.893575532333, 892.616175044737, 983.1544871410581, 1016.588281745894, 1016.627552803709, 1022.5807418958192, 10547.894521054692, 10914.945425272823, 10935.83596246057, 10947.616833410792, 10955.397453139994, 545409.0, 556975.0, 564326.0, 565389.0, 566539.1673041609, 129596.0, 135170.32414837863, 136321.0, 137937.0, 142943.0, 1204.9939750301191, 1239.0892693869998, 1252.3174869696038, 1255.7927941889486, 1268.543324403214, 21344.35966920995, 22309.553808924054, 22385.44036399038, 22400.663990040488, 22538.21116260923, 55422.44577554173, 58915.0, 59577.808443831746, 59760.10359844519, 61605.691971539854, 80960.0, 81304.59347703223, 82722.58638706768, 83353.16646833454, 84013.0, 8019.478733882279, 8939.374243803131, 9197.172254497127, 9211.447313161232, 9535.618575256913, 12599.18105323151, 12718.491260349483, 13660.795088073488, 14798.6300342488, 14821.25893705332, 11900.821487677518, 12131.75736485283, 12232.816507752568, 12253.203541769757, 12265.38673066361, 9903.752406189902, 10473.68578942633, 10654.709237513662, 11529.481173347052, 11660.06719462424, 10408.219383546415, 11415.60045398407, 12036.277823330629, 12398.132130751123, 12541.372931353579, 7310.597917114397, 7637.350825179922, 7980.201979801974, 8820.779480513038, 8823.205911467998, 11025.558977640805, 11502.42487875586, 11660.825087623862, 11809.23239989398, 12224.75550489003], "value": 10504.541304881894, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1164.4410682872222, 1272.4782839035997, 1460.6275399773056, 1464.194692918895, 1466.0617204988803, 9998.600055997751, 11449.313041217532, 12068.637940861778, 12125.454354554044, 12338.568150114725, 10236.692899213016, 11931.224470409417, 11942.522299108025, 12184.756304873898, 12213.51145954163, 2583.9095631652895, 2618.725033871445, 2634.9077782277623, 2637.986810065947, 2638.8152829301957, 2562.5387430262545, 2606.569915963864, 2611.6996545397333, 2613.529564678357, 2614.9738502615014, 2575.047232523969, 2584.9741502585016, 2592.4555843272933, 2598.753118453744, 2598.8960441582312, 1960.304092047317, 2449.4121410861444, 2466.6176742604744, 2540.796736261095, 2559.2194380713877, 2480.8635525046116, 2566.6278389633585, 2575.2917947564383, 2583.909563165299, 2614.790816734657, 11766.0, 11848.644540663709, 11851.762964740657, 11880.405979701067, 11883.702907427298, 6634.336566343375, 6891.827704307382, 6896.620685862275, 6897.655117244118, 6899.62052087135, 4089.141280331134, 4416.514183439819, 4463.062756821071, 4463.732176069446, 4469.910601787946, 1246.5948566715763, 1246.644706258721, 1284.4541070045286, 1286.8327117474669, 1289.3682095773097, 16322.0, 16578.336866525326, 16636.08501532415, 16741.748873766905, 16863.40978065774, 4686.27362758773, 5686.687232202268, 5694.544436445074, 5852.853678658068, 5881.705914704333, 11839.704007399712, 11852.822207666892, 11865.940670296593, 11871.76256474883, 11882.168248222715, 6456.451201647818, 6540.509461790385, 6540.607563546202, 6548.443382312556, 6561.639109849004, 5927.762889484372, 6188.721507532172, 6222.222222222223, 6317.684115794192, 6329.68351582419, 3758.736888417839, 4292.785360731951, 4447.932496200889, 4465.106978604292, 4485.259932111165, 1114.543037354697, 1291.7868551689055, 1300.0652023512635, 1314.6853146853148, 1326.699834162507, 781.6521647866758, 796.5539297993142, 808.9433739638287, 810.854046271665, 827.9503229806231, 6312.242530896322, 6384.744610215632, 6544.803655890331, 6573.539852210395, 6643.900341494881, 584.9532037436995, 929.330881765128, 961.1781926452921, 963.3208587945621, 965.4014511003223, 4392.956070439254, 4452.465704115527, 4580.0, 4616.261398176276, 4675.345451636774, 12795.23228606268, 14632.122072675673, 14632.487862924747, 15337.539873803804, 15354.385824566893, 14609.196494192709, 14704.117752934859, 15097.39610415593, 15186.316615752097, 15219.315130819139, 7557.697692092364, 8408.285295749807, 8649.654013839376, 8719.0, 8756.299496040301, 6799.184097908283, 6818.28408017151, 7121.679524421517, 7250.854982900418, 7381.741639042608, 12586.307753073668, 12808.615741527768, 12871.935640321739, 13096.017798664947, 13123.868761312264, 12581.370931453208, 13176.011799114913, 13238.0, 13238.404271807984, 13271.402786874422, 7902.960485197537, 8847.20375166238, 9528.237740980567, 9551.808963820824, 9563.474008929576, 9637.891642460927, 10514.316569423268, 10537.683869483928, 10812.621558245739, 10929.672109836718, 9176.683082112058, 9232.584533696134, 9334.159925606727, 9364.204042656178, 9450.574724137568, 8972.865407019031, 9022.5939832709, 9109.954450227706, 9150.908490915272, 9235.676751313671, 7243.963780181065, 7728.4590078693955, 8414.074451810418, 8445.366597505335, 8458.323334133378, 5504.559635229092, 6159.384061593805, 6255.0, 6283.151774510383, 6372.267189273293, 5380.354357477127, 5774.162746401832, 6292.0, 6333.904991425225, 6453.193350831146], "value": 5406.407742868908, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.1490365905985, 407.18359688823915, 407.3177427808422, 409.11630877305026, 415.9251334759744, 6318.620882747033, 6354.428101470867, 6431.94177863084, 6447.387498187676, 6450.903236451457, 4519.412476378075, 6044.667543285118, 6181.3200547939805, 6228.359871895433, 6265.9686701566425, 4727.078219747151, 4734.337192793012, 4751.881202969919, 4756.619470442356, 4796.0, 4681.765911704401, 4706.811727530911, 4753.287006948951, 4754.334393184957, 4776.829676729206, 3538.7345949053797, 4192.5597812229735, 4537.841175558856, 4660.277656963159, 4700.2009658358165, 4047.959520404788, 4583.839565615204, 4733.219018861883, 4744.979829336675, 4748.548887855648, 3561.6082230954576, 4091.6112969267874, 4177.603127702863, 4194.3498757692605, 4194.847101369653, 5986.892424901407, 6071.51427885768, 6073.301570319381, 6082.391760823926, 6093.146959425684, 5149.897002059938, 5347.010803001457, 5364.704941228231, 5371.462853714636, 5602.859928501779, 5837.0, 5891.116332550109, 6042.637441753465, 6635.43598794103, 6722.260551339349, 4070.857519986816, 4888.364512613373, 4928.383952005999, 4929.556339929388, 4946.604271658294, 6618.338166183391, 6870.965645171792, 7049.647517624149, 7146.356827885464, 7225.638718064076, 4088.8160032798596, 4697.295405689174, 4745.335653008581, 4747.121782470254, 4759.76201189946, 3974.4435778990965, 4026.8993275168355, 4677.941705649571, 4816.012717392996, 4856.426094170096, 5117.388022772815, 5409.26974858397, 5503.477169668875, 5514.255575497256, 5532.86167845799, 5533.7786488540005, 5791.148885990769, 6690.732370705215, 6707.993800929802, 6725.495587830932, 1719.5701074731317, 1739.747736578189, 1743.7907451105696, 1750.291132091505, 1756.5608597850537, 1215.076541828217, 1228.2507670321097, 1231.2120243044458, 1232.3037483821613, 1250.693580072888, 6357.618542887349, 6359.20509936258, 6359.364063593696, 6375.521835862236, 6406.75068361665, 5673.432656734293, 6839.93160068393, 6926.58440493572, 6963.816151254299, 6969.2682268361095, 5760.0207964646115, 6000.5799594028, 6184.6598437086395, 6310.0534919761485, 6373.139626150411, 2793.20393687798, 3160.8908419055138, 3571.2678900825267, 3596.5324507813816, 3601.1717305019993, 5863.618864773862, 6059.484943779828, 7060.0, 7086.185088714864, 7138.678759455837, 5668.773249070074, 5843.444872737083, 6737.056812046318, 7094.042304288855, 7110.537815042109, 5555.722213889289, 5961.5230781537375, 6234.438900498977, 6466.4503517201565, 7122.60825654594, 5560.4161563035295, 6316.494680425463, 6328.557001009885, 6904.654767261717, 6970.769353806733, 5542.695151766691, 6510.641914694737, 6724.327567243236, 6767.35710107539, 6801.251862295243, 5903.339929243724, 7185.82035449097, 7191.712331506681, 7225.602591857499, 7244.927550724636, 4456.924721941849, 4497.752623605602, 4501.482329532146, 4508.616767574793, 4527.547245275446, 6741.325867413219, 6976.720931162697, 6995.0, 7034.613096279549, 7090.574565525879, 5600.4959553640365, 5844.211031510692, 6064.545159113172, 6488.772892948724, 6782.626955517297, 5652.039153343859, 6485.0, 6630.734770609121, 6741.022551729873, 6762.241817127484, 6308.936910630835, 6638.0, 6638.402543771083, 6640.236372817188, 6718.22740384862, 5924.348321684525, 6369.90445143314, 6387.37122033897, 6734.225564060196, 6805.319468053353, 6062.605930614673, 6335.493160547053, 6591.176102987127, 6620.271770105381, 6624.8343791405605], "value": 4887.796623947832, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.6707298179329, 819.4141189049828, 845.1464021338446, 850.9531975741336, 860.8923884514434, 15311.387544498206, 16186.352545898164, 16716.164191790424, 16772.83227167728, 19329.806701932976, 18085.005324707137, 18417.987010714405, 18483.53791155222, 18560.257589696397, 18666.533336666555, 904.647551909894, 954.885413750351, 1012.8480727890802, 1013.4172850610892, 1016.3800081950004, 1014.543455445049, 1016.2022812092509, 1018.9694309170736, 1019.938803671782, 1021.8313978193589, 1048.2295512798125, 1060.6393826099124, 1062.0653824634312, 1063.2079101069676, 1063.2344711807525, 1050.6637875879721, 1056.8256237720766, 1057.1014637558092, 1057.3919996002292, 1062.0335494699825, 765.9923400765977, 769.8768197088439, 782.2685788787445, 784.8469548438056, 785.8978332816754, 17083.145842707814, 17485.65028699419, 17671.73492397615, 17698.93806371609, 17706.203220854964, 14034.438622455089, 17363.392281270222, 17413.868098573552, 17693.73459398097, 17918.372856949944, 14168.787468187986, 14175.929120354436, 14399.92800035993, 14457.132572045712, 14505.0, 3584.17563960288, 3620.31214069326, 3627.419612861956, 3628.546431696038, 3650.7992060436663, 87406.56296718476, 90023.64964525537, 114555.0, 116950.41524792321, 117708.41145794302, 13447.579435701471, 13618.659533511543, 13783.51757688476, 13793.310334483236, 13818.65453363674, 22091.912996095773, 24570.262892113264, 25225.11712090068, 25341.61987570188, 25487.87256063745, 7681.923180768233, 7990.720324788721, 8067.112617612058, 8098.676052957816, 8103.756887293391, 3963.9009024774036, 4050.5746896576024, 4058.492688413948, 4066.2070896175555, 4069.308217603015, 4359.80380882861, 4380.496242932039, 4499.572540608637, 4516.728996260234, 4521.525239849834, 918.6141820435433, 942.966996155145, 955.5890966884307, 959.9808003840024, 963.4893506441597, 653.5947712418331, 671.8555510565154, 677.7695583501633, 682.9726810927507, 696.8327601375634, 9756.658516951873, 11637.47631356591, 12012.219205751773, 12139.210951288138, 12284.201526900722, 647.1651569475324, 652.9673516324165, 657.6777379084215, 663.8506336074441, 685.4276678973014, 5509.256250406224, 5610.298712660918, 5688.544916406678, 5712.493403299797, 5746.798862039808, 103833.48083259686, 104315.91368172594, 105087.94912050782, 105234.0, 106510.0, 89184.0, 90429.09570904338, 90485.0, 92384.53807731053, 92556.53721731484, 61769.83805566784, 78106.2189378099, 80075.59962200152, 80482.0, 81472.18527814765, 11228.943855280671, 11259.605913792979, 11385.430728463378, 11482.885171148182, 11531.769364612828, 33402.83298583492, 38578.228435431694, 42025.159496810506, 42132.57867421409, 42558.0, 42419.7879010603, 55174.0, 55541.444585553625, 56026.15960760511, 56332.31003069915, 16748.497545073667, 17192.398266060627, 17234.91382543079, 17374.305027798746, 17499.95000299986, 14914.925425372803, 18013.72979405336, 18037.639247215244, 19018.904905475385, 19124.904375478032, 16864.494065178063, 17782.555436114202, 17794.0, 18111.909440452713, 18144.54638633992, 14056.367463463965, 16570.668586628442, 16848.0, 17026.744598831276, 17252.309907604056, 14088.295585220903, 16634.833651663812, 16745.497635070966, 17055.829441705424, 17128.828711712722, 11731.178817482694, 11932.70168245766, 12152.21010634306, 12179.147459677737, 12278.631641050782, 12175.39123043862, 12781.872181278068, 13179.472821087049, 13239.139455935334, 13321.0], "value": 8406.004967366223, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 116.0, 118.0, 107.0, 150.0, 168.0, 150.0, 169.0, 150.0], "value": 134.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 114.0, 114.0, 145.0, 107.0, 112.0, 111.0, 111.0, 137.0, 110.0], "value": 119.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 194.0, 196.0, 201.0, 205.0, 211.0, 207.0, 198.0, 198.0, 197.0], "value": 200.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 62.0, 60.0, 83.0, 61.0, 81.0, 91.0, 53.0, 89.0, 82.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 81.0, 70.0, 71.0, 71.0, 86.0, 70.0, 71.0, 90.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 88.0, 91.0, 89.0, 90.0, 89.0, 89.0, 90.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 105.0, 97.0, 97.0, 112.0, 110.0, 138.0, 97.0, 96.0, 122.0], "value": 107.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 75.0, 82.0, 116.0, 147.0, 139.0, 94.0, 87.0, 145.0, 142.0], "value": 116.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 47.0, 73.0, 74.0, 64.0, 74.0, 43.0, 45.0, 71.0, 74.0], "value": 60.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 58.0, 60.0, 72.0, 69.0, 67.0, 82.0, 81.0, 58.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 83.0, 81.0, 85.0, 86.0, 83.0, 83.0, 82.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 116.0, 143.0, 153.0, 170.0, 147.0, 174.0, 182.0, 155.0], "value": 151.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 184.0, 171.0, 226.0, 231.0, 188.0, 184.0, 188.0, 190.0], "value": 192.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 82.0, 100.0, 95.0, 79.0, 67.0, 71.0, 71.0, 77.0], "value": 78.4, "unit": "score"}]}]} 14:38:40 INFO - Return code: 0 14:38:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:38:40 INFO - # TBPL SUCCESS # 14:38:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:38:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:38:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:38:40 INFO - 2017-05-14 14:38:40.496 screenresolution[1665:11313] starting screenresolution argv=screenresolution get 14:38:40 INFO - 2017-05-14 14:38:40.499 screenresolution[1665:11313] Display 0: 1600x1200x32@60 14:38:40 INFO - 2017-05-14 14:38:40.505 screenresolution[1666:11316] starting screenresolution argv=screenresolution list 14:38:40 INFO - Available Modes on Display 0 14:38:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:38:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:38:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:38:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:38:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:38:40 INFO - Intel Iris: 14:38:40 INFO - Chipset Model: Intel Iris 14:38:40 INFO - Type: GPU 14:38:40 INFO - Bus: Built-In 14:38:40 INFO - VRAM (Dynamic, Max): 1536 MB 14:38:40 INFO - Vendor: Intel (0x8086) 14:38:40 INFO - Device ID: 0x0a2e 14:38:40 INFO - Revision ID: 0x0009 14:38:40 INFO - Displays: 14:38:40 INFO - Display: 14:38:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:38:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:38:40 INFO - Display Serial Number: 1600x1200 60 14:38:40 INFO - Main Display: Yes 14:38:40 INFO - Mirror: Off 14:38:40 INFO - Online: Yes 14:38:40 INFO - Rotation: Supported 14:38:40 INFO - Return code: 0 14:38:40 INFO - Running post-action listener: _resource_record_post_action 14:38:40 INFO - [mozharness: 2017-05-14 21:38:40.744757Z] Finished run-tests step (success) 14:38:40 INFO - Running post-run listener: _resource_record_post_run 14:38:40 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 37123072; Write bytes: 531598336; Read time: 369; Write time: 1832 14:38:40 INFO - TinderboxPrint: CPU usage
54.1% 14:38:40 INFO - TinderboxPrint: I/O read bytes / time
37,123,072 / 369 14:38:40 INFO - TinderboxPrint: I/O write bytes / time
531,598,336 / 1,832 14:38:40 INFO - TinderboxPrint: CPU idle
2,198.2 (45.9%) 14:38:40 INFO - TinderboxPrint: CPU system
961.9 (20.1%) 14:38:40 INFO - TinderboxPrint: CPU user
1,625.5 (34.0%) 14:38:40 INFO - TinderboxPrint: Swap in / out
245,891,072 / 0 14:38:40 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 216128000; Write bytes: 208558080; Read time: 17031; Write time: 416 14:38:40 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 33121280; Write bytes: 313021440; Read time: 299; Write time: 1397 14:38:41 INFO - Running post-run listener: _upload_blobber_files 14:38:41 INFO - Blob upload gear active. 14:38:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:38:41 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 14:38:41 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'] 14:38:41 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 14:38:41 INFO - (blobuploader) - INFO - Open directory for files ... 14:38:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:38:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:38:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:43 INFO - (blobuploader) - INFO - Done attempting. 14:38:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:38:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:38:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:44 INFO - (blobuploader) - INFO - Done attempting. 14:38:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:38:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:38:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:45 INFO - (blobuploader) - INFO - Done attempting. 14:38:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:38:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:38:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:47 INFO - (blobuploader) - INFO - Done attempting. 14:38:47 INFO - (blobuploader) - INFO - Iteration through files over. 14:38:47 INFO - Return code: 0 14:38:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:38:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:38:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c84f7858d108b0862202a8b797b8b037b60bdacb7998d15f6b6f838e405f26efbacae82bcdec45cd12f4daac535ae9478413a162238a535f78292fe7a4cba8a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76a5583cda46c0b430ca63489ef54f437a93c00761aaa211e741504497b40842a0c1cf83642743983a2c89278f1619ba5696cb4f8c202d467e7617d39ed9e1a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/421014f3e58a4226f05fd0fd10389c60d7433db908b33477ffb3ab744e97fb4490234ecf2ea00df5d32fb1d272f96fad871c0de2d5b2f2658623296957e932e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f02625fd3119aa729b6967ac8266f7d2c21628e387c85691061404fc04fda04b139a78984726724162011a45984f71e4f80920bebe375936c83b979ecab8b17"} 14:38:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:38:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:38:47 INFO - Contents: 14:38:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c84f7858d108b0862202a8b797b8b037b60bdacb7998d15f6b6f838e405f26efbacae82bcdec45cd12f4daac535ae9478413a162238a535f78292fe7a4cba8a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76a5583cda46c0b430ca63489ef54f437a93c00761aaa211e741504497b40842a0c1cf83642743983a2c89278f1619ba5696cb4f8c202d467e7617d39ed9e1a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/421014f3e58a4226f05fd0fd10389c60d7433db908b33477ffb3ab744e97fb4490234ecf2ea00df5d32fb1d272f96fad871c0de2d5b2f2658623296957e932e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f02625fd3119aa729b6967ac8266f7d2c21628e387c85691061404fc04fda04b139a78984726724162011a45984f71e4f80920bebe375936c83b979ecab8b17"} 14:38:47 INFO - Running post-run listener: copy_logs_to_upload_dir 14:38:47 INFO - Copying logs to upload dir... 14:38:47 INFO - mkdir: /builds/slave/test/build/upload/logs 14:38:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.770442 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-14 14:38:47.849176) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-14 14:38:47.853122) ========= 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.R9MeKzVxaA/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.yk3c2twScn/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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/c84f7858d108b0862202a8b797b8b037b60bdacb7998d15f6b6f838e405f26efbacae82bcdec45cd12f4daac535ae9478413a162238a535f78292fe7a4cba8a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76a5583cda46c0b430ca63489ef54f437a93c00761aaa211e741504497b40842a0c1cf83642743983a2c89278f1619ba5696cb4f8c202d467e7617d39ed9e1a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/421014f3e58a4226f05fd0fd10389c60d7433db908b33477ffb3ab744e97fb4490234ecf2ea00df5d32fb1d272f96fad871c0de2d5b2f2658623296957e932e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f02625fd3119aa729b6967ac8266f7d2c21628e387c85691061404fc04fda04b139a78984726724162011a45984f71e4f80920bebe375936c83b979ecab8b17"} build_url:https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011135 build_url: 'https://queue.taskcluster.net/v1/task/O6ecmveVQkmYzPSDU7BCvw/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/c84f7858d108b0862202a8b797b8b037b60bdacb7998d15f6b6f838e405f26efbacae82bcdec45cd12f4daac535ae9478413a162238a535f78292fe7a4cba8a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/76a5583cda46c0b430ca63489ef54f437a93c00761aaa211e741504497b40842a0c1cf83642743983a2c89278f1619ba5696cb4f8c202d467e7617d39ed9e1a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/421014f3e58a4226f05fd0fd10389c60d7433db908b33477ffb3ab744e97fb4490234ecf2ea00df5d32fb1d272f96fad871c0de2d5b2f2658623296957e932e2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f02625fd3119aa729b6967ac8266f7d2c21628e387c85691061404fc04fda04b139a78984726724162011a45984f71e4f80920bebe375936c83b979ecab8b17"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-14 14:38:47.886507) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 14:38:47.886829) ========= 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.R9MeKzVxaA/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.yk3c2twScn/Listeners TMPDIR=/var/folders/s6/nd6_75rn093bvthhj7zyph6r00000w/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.004902 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-14 14:38:47.936652) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-14 14:38:47.937126) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-14 14:38:47.937614) ========= ========= Total master_lag: 0.17 =========