builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0068 starttime: 1497336470.02 results: success (0) revision: 6872377277a618b2b9e0d2b4c2b9e51765ac199e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:50.020601) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:50.021086) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:50.021368) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:50.046433) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:50.046734) ========= 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.zeVODQcMys/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.QND1Ph07GR/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-12 23:47:50-- 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% 168M=0s 2017-06-12 23:47:50 (168 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.495964 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:50.605144) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:50.605462) ========= 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.zeVODQcMys/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.QND1Ph07GR/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.027885 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:50.654081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 23:47:50.654397) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6872377277a618b2b9e0d2b4c2b9e51765ac199e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6872377277a618b2b9e0d2b4c2b9e51765ac199e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zeVODQcMys/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.QND1Ph07GR/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-12 23:47:50,749 truncating revision to first 12 chars 2017-06-12 23:47:50,749 Setting DEBUG logging. 2017-06-12 23:47:50,749 attempt 1/10 2017-06-12 23:47:50,749 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6872377277a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 23:47:51,421 unpacking tar archive at: mozilla-beta-6872377277a6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.948710 ========= master_lag: 0.63 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-12 23:47:52.237812) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:52.238123) ========= script_repo_revision: 6872377277a618b2b9e0d2b4c2b9e51765ac199e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:52.238498) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:52.238758) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 23:47:52.323498) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2017-06-12 23:47:52.323819) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zeVODQcMys/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.QND1Ph07GR/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:47:52 INFO - MultiFileLogger online at 20170612 23:47:52 in /builds/slave/test 23:47:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 23:47:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:47:52 INFO - {'append_to_log': False, 23:47:52 INFO - 'base_work_dir': '/builds/slave/test', 23:47:52 INFO - 'blob_upload_branch': 'Mozilla-Beta', 23:47:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:47:52 INFO - 'branch': 'Mozilla-Beta', 23:47:52 INFO - 'buildbot_json_path': 'buildprops.json', 23:47:52 INFO - 'config_files': ('talos/mac_config.py',), 23:47:52 INFO - 'default_actions': ('clobber', 23:47:52 INFO - 'read-buildbot-config', 23:47:52 INFO - 'download-and-extract', 23:47:52 INFO - 'populate-webroot', 23:47:52 INFO - 'create-virtualenv', 23:47:52 INFO - 'install', 23:47:52 INFO - 'setup-mitmproxy', 23:47:52 INFO - 'run-tests'), 23:47:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:47:52 INFO - 'download_minidump_stackwalk': True, 23:47:52 INFO - 'download_symbols': 'ondemand', 23:47:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:47:52 INFO - 'tooltool.py': '/tools/tooltool.py', 23:47:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:47:52 INFO - '/tools/misc-python/virtualenv.py')}, 23:47:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:47:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:47:52 INFO - 'gecko_profile': False, 23:47:52 INFO - 'gecko_profile_interval': 0, 23:47:52 INFO - 'installer_path': 'installer.exe', 23:47:52 INFO - 'log_level': 'info', 23:47:52 INFO - 'log_name': 'talos', 23:47:52 INFO - 'log_to_console': True, 23:47:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:47:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:47:52 INFO - 'opt_config_files': (), 23:47:52 INFO - 'pip_index': False, 23:47:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:47:52 INFO - 'cmd': ('bash', 23:47:52 INFO - '-c', 23:47:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:47:52 INFO - 'enabled': True, 23:47:52 INFO - 'halt_on_failure': False, 23:47:52 INFO - 'name': 'check_screen_resolution'},), 23:47:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:47:52 INFO - 'cmd': ('bash', 23:47:52 INFO - '-c', 23:47:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:47:52 INFO - 'enabled': True, 23:47:52 INFO - 'halt_on_failure': False, 23:47:52 INFO - 'name': 'check_screen_resolution'},), 23:47:52 INFO - 'run_cmd_checks_enabled': True, 23:47:52 INFO - 'suite': 'dromaeojs-e10s', 23:47:52 INFO - 'system_bits': '32', 23:47:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:47:52 INFO - 'title': 't-yosemite-r7-0068', 23:47:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:47:52 INFO - 'use_talos_json': True, 23:47:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:47:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:47:52 INFO - 'work_dir': 'build'} 23:47:52 INFO - [mozharness: 2017-06-13 06:47:52.533950Z] Running clobber step. 23:47:52 INFO - Running pre-action listener: _resource_record_pre_action 23:47:52 INFO - Running main action method: clobber 23:47:52 INFO - rmtree: /builds/slave/test/build 23:47:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:47:55 INFO - Running post-action listener: _resource_record_post_action 23:47:55 INFO - [mozharness: 2017-06-13 06:47:55.054723Z] Finished clobber step (success) 23:47:55 INFO - [mozharness: 2017-06-13 06:47:55.054891Z] Running read-buildbot-config step. 23:47:55 INFO - Running pre-action listener: _resource_record_pre_action 23:47:55 INFO - Running main action method: read_buildbot_config 23:47:55 INFO - Using buildbot properties: 23:47:55 INFO - { 23:47:55 INFO - "project": "", 23:47:55 INFO - "product": "firefox", 23:47:55 INFO - "who": "jlund@mozilla.com", 23:47:55 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 23:47:55 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 23:47:55 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 23:47:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 23:47:55 INFO - "stage_platform": "macosx64", 23:47:55 INFO - "basedir": "/builds/slave/test", 23:47:55 INFO - "buildnumber": 53, 23:47:55 INFO - "platform": "yosemite_r7", 23:47:55 INFO - "parent_task_id": "f3_Z8RJ9RDKB9YyNolQEKQ", 23:47:55 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 23:47:55 INFO - "slavebuilddir": "test", 23:47:55 INFO - "taskId": "U61yDqpzSBuG9fgDQWUZug", 23:47:55 INFO - "branch": "mozilla-beta", 23:47:55 INFO - "script_repo_revision": "production", 23:47:55 INFO - "revision": "6872377277a618b2b9e0d2b4c2b9e51765ac199e", 23:47:55 INFO - "slavename": "t-yosemite-r7-0068", 23:47:55 INFO - "repo_path": "releases/mozilla-beta" 23:47:55 INFO - } 23:47:55 INFO - Finding installer, test and symbols from parent task. 23:47:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U61yDqpzSBuG9fgDQWUZug'}, attempt #1 23:47:55 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U61yDqpzSBuG9fgDQWUZug'}, attempt #1 23:47:56 INFO - Task dependencies: DHSazEfmRuiiuKn7PxI1EQ f3_Z8RJ9RDKB9YyNolQEKQ 23:47:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ'}, attempt #1 23:47:56 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 23:47:56 INFO - Set test_url: https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/test_packages.json 23:47:56 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/target.crashreporter-symbols.zip 23:47:56 INFO - Running post-action listener: _resource_record_post_action 23:47:56 INFO - [mozharness: 2017-06-13 06:47:56.770710Z] Finished read-buildbot-config step (success) 23:47:56 INFO - [mozharness: 2017-06-13 06:47:56.770844Z] Running download-and-extract step. 23:47:56 INFO - Running pre-action listener: _resource_record_pre_action 23:47:56 INFO - Running main action method: download_and_extract 23:47:56 INFO - mkdir: /builds/slave/test/build/tests 23:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:47:56 INFO - trying https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 23:47:56 INFO - Downloading https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 23:47:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 23:47:57 INFO - Downloaded 1290 bytes. 23:47:57 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 23:47:57 INFO - Using the following test package requirements: 23:47:57 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:47:57 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 23:47:57 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 23:47:57 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:47:57 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 23:47:57 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:47:57 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 23:47:57 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 23:47:57 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:47:57 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 23:47:57 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 23:47:57 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:47:57 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 23:47:57 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:47:57 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 23:47:57 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:47:57 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 23:47:57 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:47:57 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 23:47:57 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 23:47:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 23:47:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 23:47:57 INFO - Fetch https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 23:47:59 INFO - Content-Length response header: 58981191 23:47:59 INFO - Bytes received: 58981191 23:48:17 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 23:48:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 23:48:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 23:48:17 INFO - Fetch https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 23:48:19 INFO - Content-Length response header: 58981191 23:48:19 INFO - Bytes received: 58981191 23:48:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 23:48:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 23:48:36 INFO - Fetch https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 23:48:37 INFO - Content-Length response header: 14450018 23:48:37 INFO - Bytes received: 14450018 23:48:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:37 INFO - trying https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 23:48:37 INFO - Downloading https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 23:48:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 23:48:39 INFO - Downloaded 51079213 bytes. 23:48:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 23:48:39 INFO - mkdir: /builds/slave/test/properties 23:48:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:48:39 INFO - Writing to file /builds/slave/test/properties/build_url 23:48:39 INFO - Contents: 23:48:39 INFO - build_url:https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 23:48:39 INFO - Running post-action listener: _resource_record_post_action 23:48:39 INFO - Running post-action listener: set_extra_try_arguments 23:48:39 INFO - [mozharness: 2017-06-13 06:48:39.440696Z] Finished download-and-extract step (success) 23:48:39 INFO - [mozharness: 2017-06-13 06:48:39.440849Z] Running populate-webroot step. 23:48:39 INFO - Running pre-action listener: _resource_record_pre_action 23:48:39 INFO - Running main action method: populate_webroot 23:48:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:48:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:48:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:48:39 INFO - '--tppagecycles', 23:48:39 INFO - '7'], 23:48:39 INFO - 'tests': ['tsvgm']}}, 23:48:39 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:48:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:48:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:48:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:48:39 INFO - 'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:48:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:48:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:48:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:48:39 INFO - 'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['damp', 'tps']}, 23:48:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:48:39 INFO - 'tests': ['damp', 'tps']}, 23:48:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['dromaeo_dom']}, 23:48:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:48:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 23:48:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 23:48:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 23:48:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 23:48:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['a11yr', 23:48:39 INFO - 'ts_paint', 23:48:39 INFO - 'tpaint', 23:48:39 INFO - 'sessionrestore', 23:48:39 INFO - 'sessionrestore_no_auto_restore', 23:48:39 INFO - 'tabpaint']}, 23:48:39 INFO - 'other-e10s': {'tests': ['a11yr', 23:48:39 INFO - 'ts_paint', 23:48:39 INFO - 'tpaint', 23:48:39 INFO - 'sessionrestore', 23:48:39 INFO - 'sessionrestore_no_auto_restore', 23:48:39 INFO - 'tabpaint']}, 23:48:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:48:39 INFO - 'ts_paint', 23:48:39 INFO - 'tpaint', 23:48:39 INFO - 'sessionrestore', 23:48:39 INFO - 'sessionrestore_no_auto_restore', 23:48:39 INFO - 'tabpaint']}, 23:48:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:48:39 INFO - 'ts_paint', 23:48:39 INFO - 'tpaint', 23:48:39 INFO - 'sessionrestore', 23:48:39 INFO - 'sessionrestore_no_auto_restore', 23:48:39 INFO - 'tabpaint']}, 23:48:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['a11yr', 23:48:39 INFO - 'ts_paint', 23:48:39 INFO - 'tpaint', 23:48:39 INFO - 'sessionrestore', 23:48:39 INFO - 'sessionrestore_no_auto_restore', 23:48:39 INFO - 'tabpaint']}, 23:48:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['a11yr', 23:48:39 INFO - 'ts_paint', 23:48:39 INFO - 'tpaint', 23:48:39 INFO - 'sessionrestore', 23:48:39 INFO - 'sessionrestore_no_auto_restore', 23:48:39 INFO - 'tabpaint']}, 23:48:39 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 23:48:39 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 23:48:39 INFO - 'bloom_basic_ref']}, 23:48:39 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 23:48:39 INFO - 'talos_options': ['--mitmproxy', 23:48:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 23:48:39 INFO - '--firstNonBlankPaint'], 23:48:39 INFO - 'tests': ['quantum_pageload_google', 23:48:39 INFO - 'quantum_pageload_youtube', 23:48:39 INFO - 'quantum_pageload_amazon', 23:48:39 INFO - 'quantum_pageload_facebook']}, 23:48:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['tsvgx', 23:48:39 INFO - 'tsvgr_opacity', 23:48:39 INFO - 'tart', 23:48:39 INFO - 'tscrollx', 23:48:39 INFO - 'cart', 23:48:39 INFO - 'tsvg_static']}, 23:48:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:48:39 INFO - 'tsvgr_opacity', 23:48:39 INFO - 'tart', 23:48:39 INFO - 'tscrollx', 23:48:39 INFO - 'cart', 23:48:39 INFO - 'tsvg_static']}, 23:48:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:48:39 INFO - 'talos_options': ['--disable-e10s'], 23:48:39 INFO - 'tests': ['tp5o']}, 23:48:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:48:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:48:39 INFO - 'talos_options': ['--disable-e10s', 23:48:39 INFO - '--xperf_path', 23:48:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:48:39 INFO - 'tests': ['tp5n']}, 23:48:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:48:39 INFO - 'talos_options': ['--xperf_path', 23:48:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:48:39 INFO - 'tests': ['tp5n']}}, 23:48:39 INFO - 'talos.zip': {'path': '', 23:48:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:48:39 INFO - Running post-action listener: _resource_record_post_action 23:48:39 INFO - [mozharness: 2017-06-13 06:48:39.463288Z] Finished populate-webroot step (success) 23:48:39 INFO - [mozharness: 2017-06-13 06:48:39.463424Z] Running create-virtualenv step. 23:48:39 INFO - Running pre-action listener: _resource_record_pre_action 23:48:39 INFO - Running main action method: create_virtualenv 23:48:39 INFO - Creating virtualenv /builds/slave/test/build/venv 23:48:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 23:48:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:48:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 23:48:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:48:39 INFO - Using real prefix '/tools/python27' 23:48:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:48:40 INFO - Installing distribute.............................................................................................................................................................................................done. 23:48:43 INFO - Installing pip.................done. 23:48:43 INFO - Return code: 0 23:48:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:48:43 INFO - Reading from file tmpfile_stdout 23:48:43 INFO - Output received: 23:48:43 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:48:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:48: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')]} 23:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48: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')]} 23:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:43 INFO - 'HOME': '/Users/cltbld', 23:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:43 INFO - 'LOGNAME': 'cltbld', 23:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:43 INFO - 'MOZ_NO_REMOTE': '1', 23:48:43 INFO - 'NO_EM_RESTART': '1', 23:48:43 INFO - 'PAGER': '/bin/cat', 23:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:43 INFO - 'PWD': '/builds/slave/test', 23:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:43 INFO - 'SHELL': '/bin/bash', 23:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:48:43 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:43 INFO - 'USER': 'cltbld', 23:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:43 INFO - 'XPC_FLAGS': '0x0', 23:48:43 INFO - 'XPC_SERVICE_NAME': '0', 23:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 23:48:43 INFO - Cleaning up... 23:48:43 INFO - Return code: 0 23:48:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:48: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')]} 23:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48: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')]} 23:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:43 INFO - 'HOME': '/Users/cltbld', 23:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:43 INFO - 'LOGNAME': 'cltbld', 23:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:43 INFO - 'MOZ_NO_REMOTE': '1', 23:48:43 INFO - 'NO_EM_RESTART': '1', 23:48:43 INFO - 'PAGER': '/bin/cat', 23:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:43 INFO - 'PWD': '/builds/slave/test', 23:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:43 INFO - 'SHELL': '/bin/bash', 23:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:48:43 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:43 INFO - 'USER': 'cltbld', 23:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:43 INFO - 'XPC_FLAGS': '0x0', 23:48:43 INFO - 'XPC_SERVICE_NAME': '0', 23:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:43 INFO - Downloading/unpacking psutil>=3.1.1 23:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:48:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:48:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:48:46 INFO - Installing collected packages: psutil 23:48:46 INFO - Running setup.py install for psutil 23:48:46 INFO - building 'psutil._psutil_osx' extension 23:48:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:48:47 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 23:48:47 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 23:48:47 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 23:48:47 INFO - building 'psutil._psutil_posix' extension 23:48:47 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 23:48:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:48:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:48:47 INFO - ^ 23:48:47 INFO - 1 warning generated. 23:48:47 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 23:48:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:48:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:48:47 INFO - Successfully installed psutil 23:48:47 INFO - Cleaning up... 23:48:47 INFO - Return code: 0 23:48:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:48:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:47 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 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:48:47 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 23:48:47 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 23:48:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:48:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:47 INFO - 'HOME': '/Users/cltbld', 23:48:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:47 INFO - 'LOGNAME': 'cltbld', 23:48:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:47 INFO - 'MOZ_NO_REMOTE': '1', 23:48:47 INFO - 'NO_EM_RESTART': '1', 23:48:47 INFO - 'PAGER': '/bin/cat', 23:48:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:47 INFO - 'PWD': '/builds/slave/test', 23:48:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:47 INFO - 'SHELL': '/bin/bash', 23:48:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:48:47 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:48:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:47 INFO - 'USER': 'cltbld', 23:48:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:47 INFO - 'XPC_FLAGS': '0x0', 23:48:47 INFO - 'XPC_SERVICE_NAME': '0', 23:48:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:48:47 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 23:48:47 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 23:48:47 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 23:48:47 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 23:48:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:48:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:48:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:48:49 INFO - Installing collected packages: mozsystemmonitor 23:48:49 INFO - Running setup.py install for mozsystemmonitor 23:48:49 INFO - Successfully installed mozsystemmonitor 23:48:49 INFO - Cleaning up... 23:48:49 INFO - Return code: 0 23:48:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:48:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:48:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:48:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:48:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:49 INFO - 'HOME': '/Users/cltbld', 23:48:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:49 INFO - 'LOGNAME': 'cltbld', 23:48:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:49 INFO - 'MOZ_NO_REMOTE': '1', 23:48:49 INFO - 'NO_EM_RESTART': '1', 23:48:49 INFO - 'PAGER': '/bin/cat', 23:48:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:49 INFO - 'PWD': '/builds/slave/test', 23:48:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:49 INFO - 'SHELL': '/bin/bash', 23:48:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:48:49 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:48:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:49 INFO - 'USER': 'cltbld', 23:48:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:49 INFO - 'XPC_FLAGS': '0x0', 23:48:49 INFO - 'XPC_SERVICE_NAME': '0', 23:48:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:49 INFO - Downloading/unpacking jsonschema==2.5.1 23:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:48:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:48:51 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 23:48:51 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 23:48:51 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 23:48:51 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 23:48:51 INFO - Downloading functools32-3.2.3-2.tar.gz 23:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:48:51 INFO - warning: no files found matching '*.txt' 23:48:51 INFO - no previously-included directories found matching 'build' 23:48:51 INFO - no previously-included directories found matching 'dist' 23:48:51 INFO - no previously-included directories found matching '.git*' 23:48:51 INFO - Installing collected packages: jsonschema, functools32 23:48:51 INFO - Running setup.py install for functools32 23:48:51 INFO - warning: no files found matching '*.txt' 23:48:51 INFO - no previously-included directories found matching 'build' 23:48:51 INFO - no previously-included directories found matching 'dist' 23:48:51 INFO - no previously-included directories found matching '.git*' 23:48:51 INFO - Successfully installed jsonschema functools32 23:48:51 INFO - Cleaning up... 23:48:51 INFO - Return code: 0 23:48:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:48:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:48:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:51 INFO - 'HOME': '/Users/cltbld', 23:48:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:51 INFO - 'LOGNAME': 'cltbld', 23:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:51 INFO - 'MOZ_NO_REMOTE': '1', 23:48:51 INFO - 'NO_EM_RESTART': '1', 23:48:51 INFO - 'PAGER': '/bin/cat', 23:48:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:51 INFO - 'PWD': '/builds/slave/test', 23:48:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:51 INFO - 'SHELL': '/bin/bash', 23:48:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:48:51 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:48:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:51 INFO - 'USER': 'cltbld', 23:48:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:51 INFO - 'XPC_FLAGS': '0x0', 23:48:51 INFO - 'XPC_SERVICE_NAME': '0', 23:48:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:48:51 INFO - Cleaning up... 23:48:51 INFO - Return code: 0 23:48:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:48:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:48:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:51 INFO - 'HOME': '/Users/cltbld', 23:48:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:51 INFO - 'LOGNAME': 'cltbld', 23:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:51 INFO - 'MOZ_NO_REMOTE': '1', 23:48:51 INFO - 'NO_EM_RESTART': '1', 23:48:51 INFO - 'PAGER': '/bin/cat', 23:48:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:51 INFO - 'PWD': '/builds/slave/test', 23:48:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:51 INFO - 'SHELL': '/bin/bash', 23:48:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:48:51 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:48:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:51 INFO - 'USER': 'cltbld', 23:48:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:51 INFO - 'XPC_FLAGS': '0x0', 23:48:51 INFO - 'XPC_SERVICE_NAME': '0', 23:48:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:52 INFO - Downloading/unpacking blobuploader==1.2.4 23:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:53 INFO - Downloading blobuploader-1.2.4.tar.gz 23:48:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:48:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:48:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:48:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:48:54 INFO - Downloading docopt-0.6.1.tar.gz 23:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:48:54 INFO - Installing collected packages: blobuploader, requests, docopt 23:48:54 INFO - Running setup.py install for blobuploader 23:48:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:48:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:48:54 INFO - Running setup.py install for requests 23:48:55 INFO - Running setup.py install for docopt 23:48:55 INFO - Successfully installed blobuploader requests docopt 23:48:55 INFO - Cleaning up... 23:48:55 INFO - Return code: 0 23:48:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:48:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:48:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:48:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:48:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:55 INFO - 'HOME': '/Users/cltbld', 23:48:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:55 INFO - 'LOGNAME': 'cltbld', 23:48:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:55 INFO - 'MOZ_NO_REMOTE': '1', 23:48:55 INFO - 'NO_EM_RESTART': '1', 23:48:55 INFO - 'PAGER': '/bin/cat', 23:48:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:55 INFO - 'PWD': '/builds/slave/test', 23:48:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:55 INFO - 'SHELL': '/bin/bash', 23:48:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:48:55 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:48:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:55 INFO - 'USER': 'cltbld', 23:48:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:55 INFO - 'XPC_FLAGS': '0x0', 23:48:55 INFO - 'XPC_SERVICE_NAME': '0', 23:48:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:48:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:48:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-3BYtZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:48:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ZNtXUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:48:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-dp0PcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:48:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:48:55 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-8Q65vO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:48:56 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-q1VQT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:48:56 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-CYF3PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:48:56 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-demPi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:48:56 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-pjsqF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:48:56 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-XneTdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:48:56 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-pdMCq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:48:56 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-WXTHEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:48:56 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-uhQ42H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:48:56 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-otb3eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:48:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-jgA8gK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:48:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-w2skyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:48:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-tVYZL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:48:57 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-CvraFx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:48:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:48:57 INFO - Running setup.py install for manifestparser 23:48:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:48:57 INFO - Running setup.py install for mozcrash 23:48:57 INFO - Running setup.py install for mozdebug 23:48:57 INFO - Running setup.py install for mozdevice 23:48:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:48:58 INFO - Running setup.py install for mozfile 23:48:58 INFO - Running setup.py install for mozhttpd 23:48:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:48:58 INFO - Running setup.py install for mozinfo 23:48:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:48:58 INFO - Running setup.py install for mozInstall 23:48:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:48:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:48:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:48:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:48:58 INFO - Running setup.py install for mozleak 23:48:58 INFO - Running setup.py install for mozlog 23:48:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:48:58 INFO - Running setup.py install for moznetwork 23:48:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:48:59 INFO - Running setup.py install for mozprocess 23:48:59 INFO - Running setup.py install for mozprofile 23:48:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:48:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:48:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:48:59 INFO - Running setup.py install for mozrunner 23:48:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:48:59 INFO - Running setup.py install for mozscreenshot 23:48:59 INFO - Running setup.py install for moztest 23:48:59 INFO - Running setup.py install for mozversion 23:48:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:48:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:48:59 INFO - Cleaning up... 23:48:59 INFO - Return code: 0 23:48:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:48:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:48:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:48:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:48:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:48:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:48:59 INFO - 'HOME': '/Users/cltbld', 23:48:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:48:59 INFO - 'LOGNAME': 'cltbld', 23:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:48:59 INFO - 'MOZ_NO_REMOTE': '1', 23:48:59 INFO - 'NO_EM_RESTART': '1', 23:48:59 INFO - 'PAGER': '/bin/cat', 23:48:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:48:59 INFO - 'PWD': '/builds/slave/test', 23:48:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:48:59 INFO - 'SHELL': '/bin/bash', 23:48:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:48:59 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:48:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:48:59 INFO - 'USER': 'cltbld', 23:48:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:48:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:48:59 INFO - 'XPC_FLAGS': '0x0', 23:48:59 INFO - 'XPC_SERVICE_NAME': '0', 23:48:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:49:00 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Bius06-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 23:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:49:00 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-NZNErN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:49:00 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-KgKmll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 23:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:49:00 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-5Fj0hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:49:00 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-rcSGcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 23:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:49:00 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ZjC3n7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 23:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:49:00 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-QAmi_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:49:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 23:49:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:49:00 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-mr3PUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:49:01 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-_UAZ22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 23:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:49:01 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-i5mcOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:49:01 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-OXufNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 23:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:49:01 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-0Wma0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 23:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:49:01 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-mVEHpn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 23:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:49:01 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-LE6cJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 23:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:01 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-vuJgCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 23:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:49:01 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-DsmYzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 23:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:49:01 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-t1nqiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:49:02 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)) 23:49:02 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)) 23:49:02 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)) 23:49:02 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)) 23:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:49:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:49:02 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 23:49:02 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 23:49:02 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 23:49:02 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 23:49:03 INFO - Downloading blessings-1.6.tar.gz 23:49:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:49:03 INFO - Installing collected packages: blessings 23:49:03 INFO - Running setup.py install for blessings 23:49:03 INFO - Successfully installed blessings 23:49:03 INFO - Cleaning up... 23:49:03 INFO - Return code: 0 23:49:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:49:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:49:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:49:04 INFO - Reading from file tmpfile_stdout 23:49:04 INFO - Current package versions: 23:49:04 INFO - blessings == 1.6 23:49:04 INFO - blobuploader == 1.2.4 23:49:04 INFO - docopt == 0.6.1 23:49:04 INFO - functools32 == 3.2.3-2 23:49:04 INFO - jsonschema == 2.5.1 23:49:04 INFO - manifestparser == 1.1 23:49:04 INFO - mozInstall == 1.13 23:49:04 INFO - mozcrash == 1.0 23:49:04 INFO - mozdebug == 0.1 23:49:04 INFO - mozdevice == 0.51 23:49:04 INFO - mozfile == 1.2 23:49:04 INFO - mozhttpd == 0.7 23:49:04 INFO - mozinfo == 0.10 23:49:04 INFO - mozleak == 0.1 23:49:04 INFO - mozlog == 3.4 23:49:04 INFO - moznetwork == 0.27 23:49:04 INFO - mozprocess == 0.25 23:49:04 INFO - mozprofile == 0.28 23:49:04 INFO - mozrunner == 6.13 23:49:04 INFO - mozscreenshot == 0.1 23:49:04 INFO - mozsystemmonitor == 0.3 23:49:04 INFO - moztest == 0.8 23:49:04 INFO - mozversion == 1.4 23:49:04 INFO - psutil == 3.1.1 23:49:04 INFO - requests == 1.2.3 23:49:04 INFO - wsgiref == 0.1.2 23:49:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:49: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')]} 23:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49: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')]} 23:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:04 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 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:49:04 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 23:49:04 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 23:49:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:49:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:49:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:49:04 INFO - 'HOME': '/Users/cltbld', 23:49:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:49:04 INFO - 'LOGNAME': 'cltbld', 23:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:04 INFO - 'MOZ_NO_REMOTE': '1', 23:49:04 INFO - 'NO_EM_RESTART': '1', 23:49:04 INFO - 'PAGER': '/bin/cat', 23:49:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:04 INFO - 'PWD': '/builds/slave/test', 23:49:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:49:04 INFO - 'SHELL': '/bin/bash', 23:49:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:49:04 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:49:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:49:04 INFO - 'USER': 'cltbld', 23:49:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:49:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:04 INFO - 'XPC_FLAGS': '0x0', 23:49:04 INFO - 'XPC_SERVICE_NAME': '0', 23:49:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 23:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 23:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 23:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 23:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 23:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 23:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 23:49:04 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 23:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:49:05 INFO - Downloading marionette_driver-1.4.0.tar.gz 23:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 23:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 23:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 23:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 23:49:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 23:49:05 INFO - Installing collected packages: marionette-driver 23:49:05 INFO - Running setup.py install for marionette-driver 23:49:06 INFO - Successfully installed marionette-driver 23:49:06 INFO - Cleaning up... 23:49:06 INFO - Return code: 0 23:49:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:49:06 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')]} 23:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:49:06 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')]} 23:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:49:06 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 0x10f47bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f47f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc6bcb4970>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, '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.zeVODQcMys/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.QND1Ph07GR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 23:49:06 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 23:49:06 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 23:49:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:49:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:49:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:49:06 INFO - 'HOME': '/Users/cltbld', 23:49:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:49:06 INFO - 'LOGNAME': 'cltbld', 23:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:06 INFO - 'MOZ_NO_REMOTE': '1', 23:49:06 INFO - 'NO_EM_RESTART': '1', 23:49:06 INFO - 'PAGER': '/bin/cat', 23:49:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:06 INFO - 'PWD': '/builds/slave/test', 23:49:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:49:06 INFO - 'SHELL': '/bin/bash', 23:49:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:49:06 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:49:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:49:06 INFO - 'USER': 'cltbld', 23:49:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:49:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:06 INFO - 'XPC_FLAGS': '0x0', 23:49:06 INFO - 'XPC_SERVICE_NAME': '0', 23:49:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:49:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:49:06 INFO - Cleaning up... 23:49:06 INFO - Return code: 0 23:49:06 INFO - Running post-action listener: _resource_record_post_action 23:49:06 INFO - Running post-action listener: _start_resource_monitoring 23:49:06 INFO - Starting resource monitoring. 23:49:06 INFO - [mozharness: 2017-06-13 06:49:06.398622Z] Finished create-virtualenv step (success) 23:49:06 INFO - [mozharness: 2017-06-13 06:49:06.399605Z] Running install step. 23:49:06 INFO - Running pre-action listener: _resource_record_pre_action 23:49:06 INFO - Running main action method: install 23:49:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:49:06 INFO - Reading from file tmpfile_stdout 23:49:06 INFO - Detecting whether we're running mozinstall >=1.0... 23:49:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:49:06 INFO - Reading from file tmpfile_stdout 23:49:06 INFO - Output received: 23:49:06 INFO - Usage: mozinstall [options] installer 23:49:06 INFO - Options: 23:49:06 INFO - -h, --help show this help message and exit 23:49:06 INFO - -d DEST, --destination=DEST 23:49:06 INFO - Directory to install application into. [default: 23:49:06 INFO - "/builds/slave/test"] 23:49:06 INFO - --app=APP Application being installed. [default: firefox] 23:49:06 INFO - mkdir: /builds/slave/test/build/application 23:49:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:49:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 23:49:22 INFO - Reading from file tmpfile_stdout 23:49:22 INFO - Output received: 23:49:22 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 23:49:22 INFO - Running post-action listener: _resource_record_post_action 23:49:22 INFO - [mozharness: 2017-06-13 06:49:22.482198Z] Finished install step (success) 23:49:22 INFO - [mozharness: 2017-06-13 06:49:22.482357Z] Running setup-mitmproxy step. 23:49:22 INFO - Running pre-action listener: _resource_record_pre_action 23:49:22 INFO - Running main action method: setup_mitmproxy 23:49:22 INFO - Skipping: mitmproxy is not required 23:49:22 INFO - Running post-action listener: _resource_record_post_action 23:49:22 INFO - [mozharness: 2017-06-13 06:49:22.483081Z] Finished setup-mitmproxy step (success) 23:49:22 INFO - [mozharness: 2017-06-13 06:49:22.483215Z] Running run-tests step. 23:49:22 INFO - Running pre-action listener: _resource_record_pre_action 23:49:22 INFO - Running main action method: run_tests 23:49:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:49:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:49:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:49:22 INFO - 2017-06-12 23:49:22.496 screenresolution[1641:9705] starting screenresolution argv=screenresolution get 23:49:22 INFO - 2017-06-12 23:49:22.499 screenresolution[1641:9705] Display 0: 1600x1200x32@60 23:49:22 INFO - 2017-06-12 23:49:22.505 screenresolution[1642:9707] starting screenresolution argv=screenresolution list 23:49:22 INFO - Available Modes on Display 0 23:49:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:49:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:49:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:49:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:49:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:49:22 INFO - Intel Iris: 23:49:22 INFO - Chipset Model: Intel Iris 23:49:22 INFO - Type: GPU 23:49:22 INFO - Bus: Built-In 23:49:22 INFO - VRAM (Dynamic, Max): 1536 MB 23:49:22 INFO - Vendor: Intel (0x8086) 23:49:22 INFO - Device ID: 0x0a2e 23:49:22 INFO - Revision ID: 0x0009 23:49:22 INFO - Displays: 23:49:22 INFO - Display: 23:49:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:49:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:49:22 INFO - Display Serial Number: 1600x1200 60 23:49:22 INFO - Main Display: Yes 23:49:22 INFO - Mirror: Off 23:49:22 INFO - Online: Yes 23:49:22 INFO - Rotation: Supported 23:49:22 INFO - Return code: 0 23:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:49:22 INFO - Python 2.7.3 23:49:22 INFO - Return code: 0 23:49:22 INFO - grabbing minidump binary from tooltool 23:49: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')]} 23:49:22 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 0x10f1d1d30>, '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 0x10f19bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f1c2be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:49:22 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 23:49:22 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 23:49:22 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 23:49:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:49:22 INFO - Return code: 0 23:49:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:49:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:49:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:49:22 INFO - ENV: RUST_BACKTRACE is now 1 23:49:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:49:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 23:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 23:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zeVODQcMys/Render', 23:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:49:22 INFO - 'HOME': '/Users/cltbld', 23:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:49:22 INFO - 'LOGNAME': 'cltbld', 23:49:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:49:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:49:22 INFO - 'MOZ_NO_REMOTE': '1', 23:49:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:49:22 INFO - 'NO_EM_RESTART': '1', 23:49:22 INFO - 'PAGER': '/bin/cat', 23:49:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:49:22 INFO - 'PWD': '/builds/slave/test', 23:49:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:49:22 INFO - 'RUST_BACKTRACE': '1', 23:49:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 23:49:22 INFO - 'SHELL': '/bin/bash', 23:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QND1Ph07GR/Listeners', 23:49:22 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 23:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:49:22 INFO - 'USER': 'cltbld', 23:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:49:22 INFO - 'XPC_FLAGS': '0x0', 23:49:22 INFO - 'XPC_SERVICE_NAME': '0', 23:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:49:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 23:49:23 INFO - mozversion application_buildid: 20170612224315 23:49:23 INFO - mozversion application_changeset: 6872377277a618b2b9e0d2b4c2b9e51765ac199e 23:49:23 INFO - mozversion application_display_name: Firefox 23:49:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:49:23 INFO - mozversion application_name: Firefox 23:49:23 INFO - mozversion application_remotingname: firefox 23:49:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 23:49:23 INFO - mozversion application_vendor: Mozilla 23:49:23 INFO - mozversion application_version: 55.0 23:49:23 INFO - mozversion platform_buildid: 20170612224315 23:49:23 INFO - mozversion platform_changeset: 6872377277a618b2b9e0d2b4c2b9e51765ac199e 23:49:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 23:49:23 INFO - mozversion platform_version: 55.0 23:49:23 INFO - using testdate: 1497336563 23:49:23 INFO - actual date: 1497336563 23:49:23 INFO - starting webserver on 'localhost:49235' 23:49:23 INFO - SUITE-START | Running 2 tests 23:49:23 INFO - TEST-START | dromaeo_css 23:49:23 INFO - operating with platform_type : mac_ 23:49:23 INFO - Initialising browser for dromaeo_css test... 23:49:23 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49235/getInfo.html -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpwj1hDi/profile 23:49:23 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49235/getInfo.html -foreground) 23:49:55 INFO - TEST-INFO | 1652: killed by SIGTERM 23:49:55 INFO - Browser initialized. 23:49:55 INFO - Running cycle 1/1 for dromaeo_css test... 23:49:56 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpwj1hDi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:49:57 INFO - PID 1661 | 2017-06-12 23:49:57.599 plugin-container[1662:10332] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 23:49:57 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:49:57 INFO - PID 1661 | 2017-06-12 23:49:57.599 plugin-container[1662:10332] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 23:49:57 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:49:57 INFO - PID 1661 | 2017-06-12 23:49:57.600 plugin-container[1662:10332] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:50:07 INFO - PID 1661 | RSS: Main: 185049088 23:50:07 INFO - PID 1661 | 23:53:10 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 23:53:11 INFO - PID 1661 | RSS: Main: 203042816 23:53:11 INFO - PID 1661 | 23:56:13 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 23:56:14 INFO - PID 1661 | RSS: Main: 211050496 23:56:14 INFO - PID 1661 | 23:59:15 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 23:59:16 INFO - PID 1661 | RSS: Main: 212893696 23:59:16 INFO - PID 1661 | 00:02:18 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 00:02:18 INFO - PID 1661 | RSS: Main: 215220224 00:02:18 INFO - PID 1661 | 00:05:21 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 00:05:22 INFO - PID 1661 | RSS: Main: 213307392 00:05:22 INFO - PID 1661 | 00:08:23 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 00:08:23 INFO - PID 1661 | RSS: Main: 215310336 00:08:23 INFO - PID 1661 | 00:08:23 INFO - PID 1661 | __start_tp_report 00:08:23 INFO - PID 1661 | _x_x_mozilla_page_load 00:08:23 INFO - PID 1661 | _x_x_mozilla_page_load_details 00:08:23 INFO - PID 1661 | |i|pagename|runs| 00:08:23 INFO - PID 1661 | |0;dojo.html;3742.494763206966;3762.9623703762954;3854.6916246700266;3865.4781604483387;3893.766374017559;6303.9054414183765;6366.108744775729;6485.740570377179;6493.026046093077;6557.082008518812;6499.610023398599;6516.315786842384;6518.511111666632;6522.57603255788;6548.836279093025;5543.750531226105;5699.857503562403;5706.3437704663875;5738.713064346787;5899.528037756991;4227;5621.128725047624;5670.0927851543765;5786.103154011135;5804.129380592903;5656.321241451032;5762.942370576304;5813.128030795372;5817.970910145465;5841.123831425278;4874.926876096862;5615.410381909923;5770.336411312711;5788.623739456964;5837.824865254049;5678.9432105678825;5699.2590963174935;5766.798162064308;5770.134479828017;5808.419158084158;6500.544961852672;6513.185851768529;6544.411003009752;6555.115059467006;6564.93435065648;6799.320067993211;6879.415249703781;6886.449084073262;6905.861882762317;7005.369516743517;7158.427325813975;7160.749373771945;7194.928050719478;7195.280471952815;7245.855082898365;6673.299303573152;6674.299198584175;6684.766033188815;6694.364035416627;6697.464202863759;7139.785806425815;7249.818754531074;7360.963195184097;7389.852202956018;7392.260773922565;6817.693203805741;6827.590344579341;6831.975203719382;6838.555493892881;6847;7083.7520686775715;7122.92877071233;7142.21435642079;7180.92819071813;7187.532810367309;7057.89413158803;7074.752383666547;7089.89365159523;7090;7100.964495177491;7045.823854403681;7091.89362159568;7104.538205016657;7104.6447677615915;7114;5667.8866422672145;5679.545636349165;5689.7724091036725;5695.373508914016;5713.514351280188;4876.683015603974;4902.7058376497525;4903.681260718042;4928.359313289321;4972.254161875675;6414.64719440426;6519.185101862267;6519.348065193538;6520.804375868732;6610.438112760469;5991.281046274387;7075.21476010332;7184.964075179695;7216.5670059796585;7260.891086633705;6719.529632925648;6736.966315168491;6737.764178253738;6737.764178253738;6752.493562982796;5238.842834714965;5936.317323507766;6269.8746025079245;6274.901892168852;6310.242770867525;7118.893216601651;7424.034875466263;7424.165243360384;7452.776416707507;7466.626668666545;5975.760969561343;7066.929330706627;7089.255628159175;7092.468064895051;7099.183593886769;6940.826479338057;7364.705411783683;7409.444291678038;7457.627118643937;7458.552486850806;7094.432445404252;7122.216556178712;7125.144982602121;7136.821579460555;7165.247648996989;6213.937860621517;7253.492255542062;7257.818554536178;7279.890801638086;7348.70605175787;5682.715864206856;6769.289224631343;6783.559068660521;6837.35045170708;6984.26665200167;6829.624370659462;6979.092717946634;6982;6986.860262794818;7011.053507776589;5708.942910570841;6360.300366959538;6464.09502669636;6834.1115654966525;6891.517593768388;5819.94180058194;6991.370776630128;6999.160100787938;7034.261402552863;7039.472039596948;6595.043718660671;6680.231773346128;6683.7660681875905;6695.832604184934;6753.223379311442 00:08:23 INFO - PID 1661 | |1;ext.html;808.9757307280781;866.4281574161051;894.1460904835881;905.5789058087988;1102.9062529684977;32372.352552948927;40876.386854197175;41206.175876482455;41403.58596414035;41437.792811035906;41984.790076049576;42020.57979420205;42307;42446;42508.36237456433;8323.875141872877;8389.622466988969;8465;8470.78823029423;8476.872846907301;8746.037935827055;8888.555572221396;8960.775980600505;8961.551922403887;8984.281257499384;8972.955135224347;8992.64029438825;9047.095290470967;9052.321075919299;9064.728058158265;8947.239484643846;8970.86543701845;8994.145556172152;9036.051214622501;9052.321075919299;8795.60419781105;8812.471251724917;8859.689910853154;8869.423487473357;8880.644774209024;39117.217655647015;39163;39171.41242881359;39256.80371598152;39464.40803387952;3676.761010534307;3687.207250441168;3714.3499887519574;3719.776813391178;3720.4605332226674;32110.876119335946;32149.35701285961;32185.35629287401;32195;32241.87153449618;3209.7753157279;3286.3098749262685;3303.4549299365576;3344.6488118747666;3365.9495107573407;274602.2539774597;277420;278258;320588;322220;50238.748806256466;50716;50858.49141508537;50878.49121508738;50896;53319;53458;53510.66223344448;53534;53563;16082;16112.516624501284;16184;16367.263473143737;16410.343586256655;13235.139715918434;13259.933700331436;13285.800712989312;13322.600321990356;13329.866701333056;14109.5061672843;14179.929100354433;14247.643808904653;14255.85744142566;14358.56924292273;5338.9466105339225;5916.704164791743;6001.939980600226;6042.909356359658;6062.939370606326;1036.290141253244;1051.106559424483;1053.5943661690305;1104.845321654969;1106.750981029262;24021.399465013514;25475.10837120692;25776.711164441702;25793.355166120622;25795.097171598904;1108.800415925125;1139.4132022008673;1195.1693572966706;1207.5169932027259;1223.4555622747969;11143.33140011602;12638.60975292717;13138.211707297593;13192.944564434822;13231.073824832361;289109;351052;353165;354204.22897885344;355269;57889.421105789246;58676.82646347049;58744.41255587475;58765.70617146887;58873;1331.6737399337223;1392.8746412822895;1493.0444515510083;1581.9288132034317;1613.6369316903604;20948.952552372626;22463.43841403978;23782.88108559446;23907.880460597586;25299.74700252974;39243.607563925136;39556;39585.406218906166;39608.40587391134;39672.80163599164;299871;300428;300506;300897.4955125298;301312;30230.395392091596;30595.08214753561;30625.387492249585;33302.16744581405;35201.64798351983;44611.77694111509;44632.77683611561;44739.55260447354;45506.31740523829;46193.53806461892;29407.705922940495;29751;30481.695183047887;31071;32292.515612265364;30769.538456923612;30774.091012923247;31489.370212596077;31626.893058743753;31743.523847142773;38079.42880856734;38256.42615360828;38268;38374.61625383822;38449;25257.61083140445;27612.58581121325;29221;30128.787047474845;30865.074047778602;23166.76833231646;25000.3749606296;25261.62107568425;25294.747052529976;25343.36641583975 00:08:23 INFO - PID 1661 | |2;jquery.html;538.1336048961172;542.4250294687629;548.9560835133187;549.8432946610217;564.4581202046036;24541.263762087143;25291.367715807115;25311.493770124587;25318.36704082394;25339.746602533993;25763.74236257637;26276.605850912205;26885.462290754174;26978;27029.324266893338;6847.8630427391545;6903.620300883449;6958.686859091327;6962.582245065312;6966.860662786741;6951.860962780741;6964.721411143573;7012;7013.193482749472;7032.613206273653;6735.989601559756;6787.117674702282;6819.829504262409;6823.351781580766;6952.791416257521;6760.560563563377;6813.863722725568;6822.283660215656;6827.180738311409;6846.315368463163;5320.228566857784;6236.095766113934;6256.311805701369;6306.527010474187;6314.463270622003;27788.722112779018;31465;31542.211444713837;31879;32458.863939761995;2327.848689835172;2340.0756701103137;2341.1688850458013;2355.5642206191737;2365.3731761083154;20503;21061.5787684247;21075.68386474204;21076.367708968603;21107.05018274166;2326.8720220387872;2349.624060150385;2380.6310021946624;2384.938702277487;2400.1599440195964;178876;183100;189340;192337;220488;13446.394912228972;14328.283585820667;16276.674466510603;16396.75404868928;16650.50048498547;22377.55244895093;22387.44031399228;22391.10435582591;22410.663840042413;22449;11038.392888391056;11090.667279981613;11129.220954533266;11131;11160.274582152297;10230.437325947145;10339.948300258451;10367.94816025915;10371.377717336833;10390.58437662485;9831;9899.65351212704;9926.602935882484;9990.850137247802;9992.950035249778;2405.9879700601387;2832.745052945245;2835.0644287385526;2846.7153284671367;2978.9871443709244;925.9814803703888;974.5224839828433;989.1246247071393;998.7303428074524;1005.4017859373661;27079.781409836603;29620.555691664642;29794.255143621584;29920.40159196804;29950.55074173889;1052.905238528536;1074.0601973273385;1076.6608518316862;1082.9729256768644;1083.0956151613334;9367.718968430958;11859;11888.167828251939;12036.277823330453;12149.149559530748;598154;618444;621731;642833;645110;169734;171836;173046;175200;175243;1094.370736826328;1168.7662467506532;1264.791309433933;1316.7951324538133;1332.7934170203634;22898.198563050213;24447.777611119018;24525.632115518638;24636.753632463446;24728.88720007568;73090;73437.26562734305;73882;76427.61786191033;76434;97224;97964;99405.00594994247;100514;101768;12416.81374779397;12694.746105078031;12695.87304126947;12712.618621441372;12713.982881369653;19429.708554371413;19458.902705486384;19504.60990780205;20060.59878802445;20073.598528029066;15547.455839045579;15811.762823557427;15814.367425302858;15965.840341596435;16235.43175988882;14056.78914816299;14147.151170929777;14199.503017394341;14422.350994205495;14781.81745460382;16293.67412651764;16374.017558946502;16481.34074637001;16605.588524975512;16637.667246654757;12474.126811123135;12493.625191244277;12530.561430349893;12559.37203139821;12626;14623.780643290573;15789.052656840167;15817.920910395374;15867.920660396625;15881.682366352377 00:08:23 INFO - PID 1661 | |3;mootools.html;1435.13891665001;1497.8577035181654;1513.424898538556;1521.2849960518558;1545.4822634417474;13956.720865582682;14763.77854332186;14777.852221477782;14782.334794934226;14787.630309242275;13876.306184690775;14663.41346346145;14673.266336683177;14675.486357977474;14701.779473307908;2933.633295838026;2939.7354238118564;2983.1348908816417;2984.2837718947403;2985.179075754174;2629.4609605030923;2832.8017038807293;2921.853907304637;2949.2921698792243;2955.2168675301064;2912.184588315275;2941.64700235972;2944.0431859645596;2963.5850980862697;2966.377060817231;2890.956635650467;2905.2446363945314;2918.693537178587;2923.736863682268;2926.268432891777;2416.8549887006834;2852.272670469038;2863.3271181272594;2918.4746745585785;2941.735243828066;13286.933565332112;14084.43662253519;14126.717465650732;14221;14228.430862765581;7766.766996990099;8051.275385215359;8052.677892884278;8060.234277743605;8064.758057258292;4787.6409269304595;5013.47358527353;5090.160123579642;5110.160342276778;5138.794448222104;1459.408939379553;1489.3074720255133;1494.3424893047134;1500.932458039391;1514.3261248744313;21381.679274811046;21523.569528609496;21557.67663485049;21619.243326483655;21713.457163570878;6796.320367963164;6887.070245516791;6938.791836244921;7081.46888983318;7096.183938846995;14604.707905841824;14812.481563145238;14850.5544833653;14864.256787160599;14864.554063378117;7620.161782203953;7754.030746156731;7758.262965018314;7759.418043646636;7783.066032076187;6154.815355539341;6217.160683307696;6249.406306400884;7164.032855564432;7357.705691772377;4294.871153865389;5350.38470575881;5365.839024829262;5430.294061771944;5448.863778405572;1373.505538006317;1601.9038857668345;1623.553522781221;1627.4710719016366;1651.4797838681018;690.7893092606703;827.9089300176976;854.5855260198798;860.1226748716274;876.5529579914289;6521.413072823469;7296;7380.151282602462;7425.962870185723;7603.923960760432;615.4122812713937;692.6467501574133;737.8044818123202;745.3105877063695;745.3776096958992;4281.807318670667;4406.361077643788;5030.616580440408;5051.6716413433005;5372.167314066326;18493.445196644123;19498.70751938722;19543.315983940494;19555.804441955683;19586.118624661925;18341.541461463305;19189.808101919083;19411;19634;19642.90178549098;8795.296376289882;9985.550650220757;10064.547095380725;10148.391096534231;10327.122194613392;8073.546761582971;8168.673253070048;8337.374696897636;8420.957895210484;8800.955995219983;13059;16741.83258167403;17771.933683979;17786.822131779034;17807.732884007008;15811.288492017657;16417.91791041037;16925.984440933582;16961.236744346286;17007.23467443943;11255.718607034889;11337.603183888525;11546.884531154581;11556.133290003116;11574.18980671345;13285.335733213495;13342.399592018577;13378.799318010042;13444.932775336452;13452.865471345161;10371.68884933423;10392.324498907547;10609.575616975544;10620.575176993143;10705.357678539312;10099.242556808122;10204.897951020395;10346.275760696679;10580.629677961235;10799.136069114295;9409.341346105706;9485.146336829719;9525.428474291564;9556.426614403159;9582.952085239807;6837.1111755471475;6837.89743153863;6978.476614254053;7215.062041934515;7274.127104747464;6253.312135664983;6988.510804243654;7063.975723519958;7225.891611625935;7312 00:08:23 INFO - PID 1661 | |4;prototype.html;422.96827737919654;456.2859125468639;460.2980454806422;486.75418913448704;489.67436654624663;6075.878482430348;6627.569208001477;6689.230738465078;6691.498137639672;6717.021850553012;6251.687415629223;6550.951847704368;6579.7498475289785;6608.933910660892;6621.814801115633;5261.448846747565;5327.600429967749;5342.599305052117;5343.572514198874;5386.030514507387;5173.732435553295;5192.454792246816;5282.920756188661;5347.903679745643;5358.490943360394;4508.684392092555;4508.864734057967;5007.9232964912535;5213.348331458567;5222.138347172712;3736.7197460190596;4783.139034973703;4954.529319714621;5084.415292241402;5149.382074151126;4679.15775160471;4684.227102528079;4686.2267725825195;4695.5069717679835;4698.506656801021;6270.435660790551;6307.747690092391;6312.842178945517;6331.8417039573915;6334.714937827809;5088.389393272794;5408.729563521808;5725.570582206309;6059.303180134297;6064.787732429344;5526.231605886118;6872.759453419157;7039.718411263543;7073.646317684095;7088.787336379917;5434.538064264542;5456.890862182774;5476.972615136939;5485.451454854522;5512.751926163332;5910.556708246856;7543.605377580111;7562;7590.658420371096;7633.008467806397;4307.396964424983;4906.460289368167;5547.44525547442;5560.77756889728;5564.805231816867;4742.73914934675;5705.800296989669;5715.228444160069;5715.628484148517;5748.425157484218;6210.50315974721;6214.844628884314;6228.5951413158;6232.937670623326;6246.75012999484;6896.930975698775;7038.894416583653;7056.7530136446;7091.290870912868;7110.893336599956;1542.3445035859716;1776.8134345893754;1944.6110777844206;1959.8824070555813;1980.722698822166;1398.9160650361011;1405.9507917222847;1430.6208854653526;1434.4405681784112;1442.4662874736414;6453.515986300952;6579.967100164468;6596.736130554724;6634.668266586747;6743.898841517381;5878.647281163144;7211.495195336276;7294;7294.854102917912;7348.493558820429;5846.976779063647;6655.105029312168;6736.831579210559;6761.971408577407;6775.356341147583;3101.286704058079;3315.668433156665;3753.8123093845197;3770.2271034437877;3797.3924172132324;6873.5875847448315;6959.652017399109;7363.374113200438;7484.577930193354;7492.250774922464;6876.518643694893;7098.254683258287;7504.699812007459;7522;7569.583672898044;6913.343232392915;7494.962525187449;7548.924510754822;7607;7631.9472211116;6841.144856892888;7225.205227425085;7278.454115941219;7281.927180728125;7282.563046217244;6938.861222775416;7089.432845372461;7101.396381307647;7140.46446516503;7212.134543854771;5761.5390768739235;6090.512758979183;6664.169226542833;7143.571385716874;7636.923630763844;4168.7707176105605;4304.28979218438;4756.595689366304;4867.464578896389;5040.974795126001;6181;6349.746010159542;6994.208966857719;7222.74720384784;7408.740694075682;5619.522340601095;5831.650100993954;6555.934440655532;6797.388235058868;6817.89773153413;7063.39961103312;7083.724929512738;7104.573725576482;7109.715611375487;7131.322524360177;6094.177286066324;6289.465395441439;6893.965530172317;6913.861722765617;6980.616066116209;6519.152510173647;7068.5758854468895;7081.398081163159;7098.148222213161;7107.786766397016;5842.620229685057;6846.691898864464;6866.725330986905;6894.827629309308;6930.861382772216 00:08:23 INFO - PID 1661 | |5;yui.html;882.7263548300024;897.3852910756132;905.551751882818;905.9320550958676;924.325242572922;18264;18351.990640514767;18470.907645461786;18668.346607868727;18685.719714204282;16768.916155419207;16776.993380397184;17466.56333591661;17549.29802807886;17567.560810979732;1016.6340117557673;1051.9000694933968;1052.2423854824553;1053.0312112856152;1055.2876808154504;1052.973675658107;1060.734816295926;1063.441693111119;1065.8934106589356;1066.5200659703164;1098.653924013933;1104.9005589496944;1115.2137742891243;1116.2855772305732;1117.3966058328492;1064.7710742190466;1071.6624263357019;1100.2848148703326;1104.1498046504187;1107.800595892739;797.6889013284823;800.7397595781395;806.7942674617993;809.3767798794895;814.8899898513685;16078.356865725356;16104.516864494084;16222.188890555424;16274.104924229163;16277.837221627751;14053.578392648236;14197.929010354985;14524.854751452456;14620;14659.780103298459;13787.724245515134;13851.099678520864;13880.652983675387;14791.926040369837;17483.300667973264;5753.712314384264;5790.507806836424;5796.217510636052;5800.651960882361;5804.390538993387;129965.35017324948;157869;168754;169017;176198.11900940412;13814.171149731048;14699;14924.328405221573;14993.700125997419;15043.849561504463;28319.858400708275;28474.857625712153;28846.27884302867;29185.56221656677;29239;8434;8475.57622118904;8488.151184881586;8534.829303413897;8539.871901921477;4139.482564679415;4155.688323375698;4191.224623444673;4219.282721937278;4222.0500387412585;4257.616814486649;4887.997960418177;4968.205087186105;4973.776180071905;4981.302617633534;992.7418871093568;1047.2721458586361;1059.3114475590928;1064.494365176535;1064.7604289034905;674.780696273713;680.9523333366716;694.770725660541;704.3027402871121;717.4188906985358;10674.946625266824;12165.087618428477;12249.448774805156;12801.423935922905;13019.218846869218;662.3575132121834;664.0305154474424;675.4835394539193;691.6230654293422;696.9825754356182;5455.863603409867;5663.207150998864;5730.570207234474;6025.758969641165;6461.838454038687;153912;155224;158021;160241;161832;120265;132611.33694331467;134415;135544.322278388;145923.2703836474;116714.41642791731;117511;118249.8175018256;123001.38499307267;123468.38265808614;12213.45039473208;12762.042846786713;13715.45138194501;13731.107478013897;13819.963502737133;50145.74927125341;51148.74425627848;51925;52686.473135268156;52692.736536317076;66152.00771988327;67555.3111172205;67580;69123.3087669117;70142.29857701357;17220.569485762957;17676.02781847011;18500.537486562942;18697.813021869606;18703.438896833115;18778.342758003404;19206;19472.51318716987;19658.247845301954;19730.1121449538;18188.99960502185;18295.634087318445;18394.816051839847;18511.444656660322;18981.715274271173;17693.557661058578;18067.819321806615;18707;18785.24859005679;18834.52913677169;16093.195340232707;16240.26918788681;16448.095354755602;16581.25384357731;16655.916720416804;11909.464074116859;11922.344271064827;11982.64052078439;12555.372231388577;12582.559610413591;13568.728625427239;14119.152850828983;14193.645158871112;14211.50259740904;14242.501512447014 00:08:23 INFO - PID 1661 | __end_tp_report 00:08:23 INFO - PID 1661 | __start_cc_report 00:08:23 INFO - PID 1661 | _x_x_mozilla_cycle_collect,382 00:08:23 INFO - PID 1661 | __end_cc_report 00:08:23 INFO - PID 1661 | __startTimestamp1497337703884__endTimestamp 00:08:23 INFO - PID 1661 | 00:08:23 INFO - PID 1661 | ------- Summary: start ------- 00:08:23 INFO - PID 1661 | Number of tests: 6 00:08:23 INFO - PID 1661 | 00:08:23 INFO - PID 1661 | [#0] dojo.html Cycles:165 Average:6480.27 Median:6719.53 stddev:813.88 (12.1%) stddev-sans-first:787.53 00:08:23 INFO - PID 1661 | Values: 3742.5 3763.0 3854.7 3865.5 3893.8 6303.9 6366.1 6485.7 6493.0 6557.1 6499.6 6516.3 6518.5 6522.6 6548.8 5543.8 5699.9 5706.3 5738.7 5899.5 4227.0 5621.1 5670.1 5786.1 5804.1 5656.3 5762.9 5813.1 5818.0 5841.1 4874.9 5615.4 5770.3 5788.6 5837.8 5678.9 5699.3 5766.8 5770.1 5808.4 6500.5 6513.2 6544.4 6555.1 6564.9 6799.3 6879.4 6886.4 6905.9 7005.4 7158.4 7160.7 7194.9 7195.3 7245.9 6673.3 6674.3 6684.8 6694.4 6697.5 7139.8 7249.8 7361.0 7389.9 7392.3 6817.7 6827.6 6832.0 6838.6 6847.0 7083.8 7122.9 7142.2 7180.9 7187.5 7057.9 7074.8 7089.9 7090.0 7101.0 7045.8 7091.9 7104.5 7104.6 7114.0 5667.9 5679.5 5689.8 5695.4 5713.5 4876.7 4902.7 4903.7 4928.4 4972.3 6414.6 6519.2 6519.3 6520.8 6610.4 5991.3 7075.2 7185.0 7216.6 7260.9 6719.5 6737.0 6737.8 6737.8 6752.5 5238.8 5936.3 6269.9 6274.9 6310.2 7118.9 7424.0 7424.2 7452.8 7466.6 5975.8 7066.9 7089.3 7092.5 7099.2 6940.8 7364.7 7409.4 7457.6 7458.6 7094.4 7122.2 7125.1 7136.8 7165.2 6213.9 7253.5 7257.8 7279.9 7348.7 5682.7 6769.3 6783.6 6837.4 6984.3 6829.6 6979.1 6982.0 6986.9 7011.1 5708.9 6360.3 6464.1 6834.1 6891.5 5819.9 6991.4 6999.2 7034.3 7039.5 6595.0 6680.2 6683.8 6695.8 6753.2 00:08:23 INFO - PID 1661 | 00:08:23 INFO - PID 1661 | [#1] ext.html Cycles:180 Average:46909.50 Median:3355.30 stddev:82177.14 (2449.2%) stddev-sans-first:82334.77 00:08:23 INFO - PID 1661 | Values: 809.0 866.4 894.1 905.6 1102.9 32372.4 40876.4 41206.2 41403.6 41437.8 41984.8 42020.6 42307.0 42446.0 42508.4 8323.9 8389.6 8465.0 8470.8 8476.9 8746.0 8888.6 8960.8 8961.6 8984.3 8973.0 8992.6 9047.1 9052.3 9064.7 8947.2 8970.9 8994.1 9036.1 9052.3 8795.6 8812.5 8859.7 8869.4 8880.6 39117.2 39163.0 39171.4 39256.8 39464.4 3676.8 3687.2 3714.3 3719.8 3720.5 32110.9 32149.4 32185.4 32195.0 32241.9 3209.8 3286.3 3303.5 3344.6 3365.9 274602.3 277420.0 278258.0 320588.0 322220.0 50238.7 50716.0 50858.5 50878.5 50896.0 53319.0 53458.0 53510.7 53534.0 53563.0 16082.0 16112.5 16184.0 16367.3 16410.3 13235.1 13259.9 13285.8 13322.6 13329.9 14109.5 14179.9 14247.6 14255.9 14358.6 5338.9 5916.7 6001.9 6042.9 6062.9 1036.3 1051.1 1053.6 1104.8 1106.8 24021.4 25475.1 25776.7 25793.4 25795.1 1108.8 1139.4 1195.2 1207.5 1223.5 11143.3 12638.6 13138.2 13192.9 13231.1 289109.0 351052.0 353165.0 354204.2 355269.0 57889.4 58676.8 58744.4 58765.7 58873.0 1331.7 1392.9 1493.0 1581.9 1613.6 20949.0 22463.4 23782.9 23907.9 25299.7 39243.6 39556.0 39585.4 39608.4 39672.8 299871.0 300428.0 300506.0 300897.5 301312.0 30230.4 30595.1 30625.4 33302.2 35201.6 44611.8 44632.8 44739.6 45506.3 46193.5 29407.7 29751.0 30481.7 31071.0 32292.5 30769.5 30774.1 31489.4 31626.9 31743.5 38079.4 38256.4 38268.0 38374.6 38449.0 25257.6 27612.6 29221.0 30128.8 30865.1 23166.8 25000.4 25261.6 25294.7 25343.4 00:08:23 INFO - PID 1661 | 00:08:23 INFO - PID 1661 | [#2] jquery.html Cycles:180 Average:43199.52 Median:2340.62 stddev:107559.06 (4595.3%) stddev-sans-first:107813.10 00:08:23 INFO - PID 1661 | Values: 538.1 542.4 549.0 549.8 564.5 24541.3 25291.4 25311.5 25318.4 25339.7 25763.7 26276.6 26885.5 26978.0 27029.3 6847.9 6903.6 6958.7 6962.6 6966.9 6951.9 6964.7 7012.0 7013.2 7032.6 6736.0 6787.1 6819.8 6823.4 6952.8 6760.6 6813.9 6822.3 6827.2 6846.3 5320.2 6236.1 6256.3 6306.5 6314.5 27788.7 31465.0 31542.2 31879.0 32458.9 2327.8 2340.1 2341.2 2355.6 2365.4 20503.0 21061.6 21075.7 21076.4 21107.1 2326.9 2349.6 2380.6 2384.9 2400.2 178876.0 183100.0 189340.0 192337.0 220488.0 13446.4 14328.3 16276.7 16396.8 16650.5 22377.6 22387.4 22391.1 22410.7 22449.0 11038.4 11090.7 11129.2 11131.0 11160.3 10230.4 10339.9 10367.9 10371.4 10390.6 9831.0 9899.7 9926.6 9990.9 9993.0 2406.0 2832.7 2835.1 2846.7 2979.0 926.0 974.5 989.1 998.7 1005.4 27079.8 29620.6 29794.3 29920.4 29950.6 1052.9 1074.1 1076.7 1083.0 1083.1 9367.7 11859.0 11888.2 12036.3 12149.1 598154.0 618444.0 621731.0 642833.0 645110.0 169734.0 171836.0 173046.0 175200.0 175243.0 1094.4 1168.8 1264.8 1316.8 1332.8 22898.2 24447.8 24525.6 24636.8 24728.9 73090.0 73437.3 73882.0 76427.6 76434.0 97224.0 97964.0 99405.0 100514.0 101768.0 12416.8 12694.7 12695.9 12712.6 12714.0 19429.7 19458.9 19504.6 20060.6 20073.6 15547.5 15811.8 15814.4 15965.8 16235.4 14056.8 14147.2 14199.5 14422.4 14781.8 16293.7 16374.0 16481.3 16605.6 16637.7 12474.1 12493.6 12530.6 12559.4 12626.0 14623.8 15789.1 15817.9 15867.9 15881.7 00:08:23 INFO - PID 1661 | 00:08:23 INFO - PID 1661 | [#3] mootools.html Cycles:180 Average:8639.27 Median:2915.33 stddev:5788.39 (198.6%) stddev-sans-first:5779.32 00:08:23 INFO - PID 1661 | Values: 1435.1 1497.9 1513.4 1521.3 1545.5 13956.7 14763.8 14777.9 14782.3 14787.6 13876.3 14663.4 14673.3 14675.5 14701.8 2933.6 2939.7 2983.1 2984.3 2985.2 2629.5 2832.8 2921.9 2949.3 2955.2 2912.2 2941.6 2944.0 2963.6 2966.4 2891.0 2905.2 2918.7 2923.7 2926.3 2416.9 2852.3 2863.3 2918.5 2941.7 13286.9 14084.4 14126.7 14221.0 14228.4 7766.8 8051.3 8052.7 8060.2 8064.8 4787.6 5013.5 5090.2 5110.2 5138.8 1459.4 1489.3 1494.3 1500.9 1514.3 21381.7 21523.6 21557.7 21619.2 21713.5 6796.3 6887.1 6938.8 7081.5 7096.2 14604.7 14812.5 14850.6 14864.3 14864.6 7620.2 7754.0 7758.3 7759.4 7783.1 6154.8 6217.2 6249.4 7164.0 7357.7 4294.9 5350.4 5365.8 5430.3 5448.9 1373.5 1601.9 1623.6 1627.5 1651.5 690.8 827.9 854.6 860.1 876.6 6521.4 7296.0 7380.2 7426.0 7603.9 615.4 692.6 737.8 745.3 745.4 4281.8 4406.4 5030.6 5051.7 5372.2 18493.4 19498.7 19543.3 19555.8 19586.1 18341.5 19189.8 19411.0 19634.0 19642.9 8795.3 9985.6 10064.5 10148.4 10327.1 8073.5 8168.7 8337.4 8421.0 8801.0 13059.0 16741.8 17771.9 17786.8 17807.7 15811.3 16417.9 16926.0 16961.2 17007.2 11255.7 11337.6 11546.9 11556.1 11574.2 13285.3 13342.4 13378.8 13444.9 13452.9 10371.7 10392.3 10609.6 10620.6 10705.4 10099.2 10204.9 10346.3 10580.6 10799.1 9409.3 9485.1 9525.4 9556.4 9583.0 6837.1 6837.9 6978.5 7215.1 7274.1 6253.3 6988.5 7064.0 7225.9 7312.0 00:08:23 INFO - PID 1661 | 00:08:23 INFO - PID 1661 | [#4] prototype.html Cycles:180 Average:5803.61 Median:6310.29 stddev:1704.63 (27.0%) stddev-sans-first:1660.88 00:08:23 INFO - PID 1661 | Values: 423.0 456.3 460.3 486.8 489.7 6075.9 6627.6 6689.2 6691.5 6717.0 6251.7 6551.0 6579.7 6608.9 6621.8 5261.4 5327.6 5342.6 5343.6 5386.0 5173.7 5192.5 5282.9 5347.9 5358.5 4508.7 4508.9 5007.9 5213.3 5222.1 3736.7 4783.1 4954.5 5084.4 5149.4 4679.2 4684.2 4686.2 4695.5 4698.5 6270.4 6307.7 6312.8 6331.8 6334.7 5088.4 5408.7 5725.6 6059.3 6064.8 5526.2 6872.8 7039.7 7073.6 7088.8 5434.5 5456.9 5477.0 5485.5 5512.8 5910.6 7543.6 7562.0 7590.7 7633.0 4307.4 4906.5 5547.4 5560.8 5564.8 4742.7 5705.8 5715.2 5715.6 5748.4 6210.5 6214.8 6228.6 6232.9 6246.8 6896.9 7038.9 7056.8 7091.3 7110.9 1542.3 1776.8 1944.6 1959.9 1980.7 1398.9 1406.0 1430.6 1434.4 1442.5 6453.5 6580.0 6596.7 6634.7 6743.9 5878.6 7211.5 7294.0 7294.9 7348.5 5847.0 6655.1 6736.8 6762.0 6775.4 3101.3 3315.7 3753.8 3770.2 3797.4 6873.6 6959.7 7363.4 7484.6 7492.3 6876.5 7098.3 7504.7 7522.0 7569.6 6913.3 7495.0 7548.9 7607.0 7631.9 6841.1 7225.2 7278.5 7281.9 7282.6 6938.9 7089.4 7101.4 7140.5 7212.1 5761.5 6090.5 6664.2 7143.6 7636.9 4168.8 4304.3 4756.6 4867.5 5041.0 6181.0 6349.7 6994.2 7222.7 7408.7 5619.5 5831.7 6555.9 6797.4 6817.9 7063.4 7083.7 7104.6 7109.7 7131.3 6094.2 6289.5 6894.0 6913.9 6980.6 6519.2 7068.6 7081.4 7098.1 7107.8 5842.6 6846.7 6866.7 6894.8 6930.9 00:08:23 INFO - PID 1661 | 00:08:23 INFO - PID 1661 | [#5] yui.html Cycles:180 Average:27673.88 Median:17474.93 stddev:43536.61 (249.1%) stddev-sans-first:43612.27 00:08:23 INFO - PID 1661 | Values: 882.7 897.4 905.6 905.9 924.3 18264.0 18352.0 18470.9 18668.3 18685.7 16768.9 16777.0 17466.6 17549.3 17567.6 1016.6 1051.9 1052.2 1053.0 1055.3 1053.0 1060.7 1063.4 1065.9 1066.5 1098.7 1104.9 1115.2 1116.3 1117.4 1064.8 1071.7 1100.3 1104.1 1107.8 797.7 800.7 806.8 809.4 814.9 16078.4 16104.5 16222.2 16274.1 16277.8 14053.6 14197.9 14524.9 14620.0 14659.8 13787.7 13851.1 13880.7 14791.9 17483.3 5753.7 5790.5 5796.2 5800.7 5804.4 129965.4 157869.0 168754.0 169017.0 176198.1 13814.2 14699.0 14924.3 14993.7 15043.8 28319.9 28474.9 28846.3 29185.6 29239.0 8434.0 8475.6 8488.2 8534.8 8539.9 4139.5 4155.7 4191.2 4219.3 4222.1 4257.6 4888.0 4968.2 4973.8 4981.3 992.7 1047.3 1059.3 1064.5 1064.8 674.8 681.0 694.8 704.3 717.4 10674.9 12165.1 12249.4 12801.4 13019.2 662.4 664.0 675.5 691.6 697.0 5455.9 5663.2 5730.6 6025.8 6461.8 153912.0 155224.0 158021.0 160241.0 161832.0 120265.0 132611.3 134415.0 135544.3 145923.3 116714.4 117511.0 118249.8 123001.4 123468.4 12213.5 12762.0 13715.5 13731.1 13820.0 50145.7 51148.7 51925.0 52686.5 52692.7 66152.0 67555.3 67580.0 69123.3 70142.3 17220.6 17676.0 18500.5 18697.8 18703.4 18778.3 19206.0 19472.5 19658.2 19730.1 18189.0 18295.6 18394.8 18511.4 18981.7 17693.6 18067.8 18707.0 18785.2 18834.5 16093.2 16240.3 16448.1 16581.3 16655.9 11909.5 11922.3 11982.6 12555.4 12582.6 13568.7 14119.2 14193.6 14211.5 14242.5 00:08:23 INFO - PID 1661 | -------- Summary: end -------- 00:08:23 INFO - PID 1661 | 00:08:24 INFO - TEST-INFO | 1661: exit 0 00:08:24 INFO - TEST-OK | dromaeo_css | took 1141306ms 00:08:24 INFO - TEST-START | kraken 00:08:24 INFO - operating with platform_type : mac_ 00:08:24 INFO - Initialising browser for kraken test... 00:08:24 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49235/getInfo.html -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpGzjm4x/profile 00:08:24 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49235/getInfo.html -foreground) 00:08:57 INFO - TEST-INFO | 1670: killed by SIGTERM 00:08:57 INFO - Browser initialized. 00:08:57 INFO - Running cycle 1/1 for kraken test... 00:08:57 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmpGzjm4x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:08:58 INFO - PID 1673 | 2017-06-13 00:08:58.868 plugin-container[1674:13183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9643, name = 'com.apple.tsm.portname' 00:08:58 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:08:58 INFO - PID 1673 | 2017-06-13 00:08:58.871 plugin-container[1674:13183] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x450f, name = 'com.apple.CFPasteboardClient' 00:08:58 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:08:58 INFO - PID 1673 | 2017-06-13 00:08:58.871 plugin-container[1674:13183] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:09:09 INFO - PID 1673 | RSS: Main: 184455168 00:09:09 INFO - PID 1673 | 00:09:11 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 00:09:12 INFO - PID 1673 | RSS: Main: 198586368 00:09:12 INFO - PID 1673 | 00:09:15 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 00:09:15 INFO - PID 1673 | RSS: Main: 208924672 00:09:15 INFO - PID 1673 | 00:09:19 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 00:09:19 INFO - PID 1673 | RSS: Main: 213966848 00:09:19 INFO - PID 1673 | 00:09:21 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 00:09:22 INFO - PID 1673 | RSS: Main: 214151168 00:09:22 INFO - PID 1673 | 00:09:24 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:09:25 INFO - PID 1673 | RSS: Main: 215986176 00:09:25 INFO - PID 1673 | 00:09:28 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 00:09:28 INFO - PID 1673 | RSS: Main: 219750400 00:09:28 INFO - PID 1673 | 00:09:31 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 00:09:32 INFO - PID 1673 | RSS: Main: 220246016 00:09:32 INFO - PID 1673 | 00:09:35 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 00:09:35 INFO - PID 1673 | RSS: Main: 220135424 00:09:35 INFO - PID 1673 | 00:09:37 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:09:37 INFO - PID 1673 | RSS: Main: 219660288 00:09:37 INFO - PID 1673 | 00:09:39 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:09:40 INFO - PID 1673 | RSS: Main: 219656192 00:09:40 INFO - PID 1673 | 00:09:42 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:09:42 INFO - PID 1673 | RSS: Main: 219648000 00:09:42 INFO - PID 1673 | 00:09:45 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:09:46 INFO - PID 1673 | RSS: Main: 219729920 00:09:46 INFO - PID 1673 | 00:09:49 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:09:49 INFO - PID 1673 | RSS: Main: 219860992 00:09:49 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 00:09:51 INFO - PID 1673 | RSS: Main: 221368320 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | __start_tp_report 00:09:51 INFO - PID 1673 | _x_x_mozilla_page_load 00:09:51 INFO - PID 1673 | _x_x_mozilla_page_load_details 00:09:51 INFO - PID 1673 | |i|pagename|runs| 00:09:51 INFO - PID 1673 | |0;ai-astar;109;171;115;133;166;168;114;102;114;117 00:09:51 INFO - PID 1673 | |1;audio-beat-detection;125;109;127;138;130;149;149;160;143;111 00:09:51 INFO - PID 1673 | |2;audio-dft;207;200;200;224;271;195;199;197;205;198 00:09:51 INFO - PID 1673 | |3;audio-fft;59;93;116;148;84;87;88;86;132;56 00:09:51 INFO - PID 1673 | |4;audio-oscillator;69;71;117;56;100;107;105;72;102;151 00:09:51 INFO - PID 1673 | |5;imaging-gaussian-blur;89;90;90;90;89;89;90;90;90;90 00:09:51 INFO - PID 1673 | |6;imaging-darkroom;98;105;96;95;100;95;106;96;98;97 00:09:51 INFO - PID 1673 | |7;imaging-desaturate;91;95;91;94;97;92;91;91;93;92 00:09:51 INFO - PID 1673 | |8;json-parse-financial;47;48;47;49;50;50;52;52;49;51 00:09:51 INFO - PID 1673 | |9;json-stringify-tinderbox;53;54;56;59;61;56;60;53;54;55 00:09:51 INFO - PID 1673 | |10;stanford-crypto-aes;81;78;77;79;77;96;100;83;77;77 00:09:51 INFO - PID 1673 | |11;stanford-crypto-ccm;132;128;128;136;133;146;151;148;151;166 00:09:51 INFO - PID 1673 | |12;stanford-crypto-pbkdf2;176;178;186;173;173;180;222;206;172;179 00:09:51 INFO - PID 1673 | |13;stanford-crypto-sha256-iterative;57;74;57;69;94;92;92;92;93;85 00:09:51 INFO - PID 1673 | __end_tp_report 00:09:51 INFO - PID 1673 | __start_cc_report 00:09:51 INFO - PID 1673 | _x_x_mozilla_cycle_collect,976 00:09:51 INFO - PID 1673 | __end_cc_report 00:09:51 INFO - PID 1673 | __startTimestamp1497337791947__endTimestamp 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | ------- Summary: start ------- 00:09:51 INFO - PID 1673 | Number of tests: 14 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#0] ai-astar Cycles:10 Average:130.90 Median:125.00 stddev:26.98 (21.6%) stddev-sans-first:27.43 00:09:51 INFO - PID 1673 | Values: 109.0 171.0 115.0 133.0 166.0 168.0 114.0 102.0 114.0 117.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:140.50 stddev:16.74 (11.9%) stddev-sans-first:17.43 00:09:51 INFO - PID 1673 | Values: 125.0 109.0 127.0 138.0 130.0 149.0 149.0 160.0 143.0 111.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#2] audio-dft Cycles:10 Average:209.60 Median:202.50 stddev:23.11 (11.4%) stddev-sans-first:24.50 00:09:51 INFO - PID 1673 | Values: 207.0 200.0 200.0 224.0 271.0 195.0 199.0 197.0 205.0 198.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#3] audio-fft Cycles:10 Average:94.90 Median:85.00 stddev:29.35 (34.5%) stddev-sans-first:28.11 00:09:51 INFO - PID 1673 | Values: 59.0 93.0 116.0 148.0 84.0 87.0 88.0 86.0 132.0 56.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:103.50 stddev:28.36 (27.4%) stddev-sans-first:28.48 00:09:51 INFO - PID 1673 | Values: 69.0 71.0 117.0 56.0 100.0 107.0 105.0 72.0 102.0 151.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:90.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 00:09:51 INFO - PID 1673 | Values: 89.0 90.0 90.0 90.0 89.0 89.0 90.0 90.0 90.0 90.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#6] imaging-darkroom Cycles:10 Average:98.60 Median:96.00 stddev:3.95 (4.1%) stddev-sans-first:4.18 00:09:51 INFO - PID 1673 | Values: 98.0 105.0 96.0 95.0 100.0 95.0 106.0 96.0 98.0 97.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:92.50 stddev:2.06 (2.2%) stddev-sans-first:2.09 00:09:51 INFO - PID 1673 | Values: 91.0 95.0 91.0 94.0 97.0 92.0 91.0 91.0 93.0 92.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:50.00 stddev:1.84 (3.7%) stddev-sans-first:1.72 00:09:51 INFO - PID 1673 | Values: 47.0 48.0 47.0 49.0 50.0 50.0 52.0 52.0 49.0 51.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:56.00 stddev:2.92 (5.2%) stddev-sans-first:2.88 00:09:51 INFO - PID 1673 | Values: 53.0 54.0 56.0 59.0 61.0 56.0 60.0 53.0 54.0 55.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:78.50 stddev:8.46 (10.8%) stddev-sans-first:8.96 00:09:51 INFO - PID 1673 | Values: 81.0 78.0 77.0 79.0 77.0 96.0 100.0 83.0 77.0 77.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:141.90 Median:147.00 stddev:12.47 (8.5%) stddev-sans-first:12.70 00:09:51 INFO - PID 1673 | Values: 132.0 128.0 128.0 136.0 133.0 146.0 151.0 148.0 151.0 166.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.50 Median:179.50 stddev:16.52 (9.2%) stddev-sans-first:17.23 00:09:51 INFO - PID 1673 | Values: 176.0 178.0 186.0 173.0 173.0 180.0 222.0 206.0 172.0 179.0 00:09:51 INFO - PID 1673 | 00:09:51 INFO - PID 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.50 Median:92.00 stddev:15.04 (16.3%) stddev-sans-first:13.33 00:09:51 INFO - PID 1673 | Values: 57.0 74.0 57.0 69.0 94.0 92.0 92.0 92.0 93.0 85.0 00:09:51 INFO - PID 1673 | -------- Summary: end -------- 00:09:51 INFO - PID 1673 | 00:09:52 INFO - TEST-INFO | 1673: exit 0 00:09:52 INFO - TEST-OK | kraken | took 88267ms 00:09:52 INFO - SUITE-END | took 1229s 00:09:53 INFO - Completed test suite (00:20:30) 00:09:53 INFO - javascript benchmark 00:09:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8812.915080974975, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3742.494763206966, 3762.9623703762954, 3854.6916246700266, 3865.4781604483387, 3893.766374017559, 6303.9054414183765, 6366.108744775729, 6485.740570377179, 6493.026046093077, 6557.082008518812, 6499.610023398599, 6516.315786842384, 6518.511111666632, 6522.57603255788, 6548.836279093025, 5543.750531226105, 5699.857503562403, 5706.3437704663875, 5738.713064346787, 5899.528037756991, 4227.0, 5621.128725047624, 5670.0927851543765, 5786.103154011135, 5804.129380592903, 5656.321241451032, 5762.942370576304, 5813.128030795372, 5817.970910145465, 5841.123831425278, 4874.926876096862, 5615.410381909923, 5770.336411312711, 5788.623739456964, 5837.824865254049, 5678.9432105678825, 5699.2590963174935, 5766.798162064308, 5770.134479828017, 5808.419158084158, 6500.544961852672, 6513.185851768529, 6544.411003009752, 6555.115059467006, 6564.93435065648, 6799.320067993211, 6879.415249703781, 6886.449084073262, 6905.861882762317, 7005.369516743517, 7158.427325813975, 7160.749373771945, 7194.928050719478, 7195.280471952815, 7245.855082898365, 6673.299303573152, 6674.299198584175, 6684.766033188815, 6694.364035416627, 6697.464202863759, 7139.785806425815, 7249.818754531074, 7360.963195184097, 7389.852202956018, 7392.260773922565, 6817.693203805741, 6827.590344579341, 6831.975203719382, 6838.555493892881, 6847.0, 7083.7520686775715, 7122.92877071233, 7142.21435642079, 7180.92819071813, 7187.532810367309, 7057.89413158803, 7074.752383666547, 7089.89365159523, 7090.0, 7100.964495177491, 7045.823854403681, 7091.89362159568, 7104.538205016657, 7104.6447677615915, 7114.0, 5667.8866422672145, 5679.545636349165, 5689.7724091036725, 5695.373508914016, 5713.514351280188, 4876.683015603974, 4902.7058376497525, 4903.681260718042, 4928.359313289321, 4972.254161875675, 6414.64719440426, 6519.185101862267, 6519.348065193538, 6520.804375868732, 6610.438112760469, 5991.281046274387, 7075.21476010332, 7184.964075179695, 7216.5670059796585, 7260.891086633705, 6719.529632925648, 6736.966315168491, 6737.764178253738, 6737.764178253738, 6752.493562982796, 5238.842834714965, 5936.317323507766, 6269.8746025079245, 6274.901892168852, 6310.242770867525, 7118.893216601651, 7424.034875466263, 7424.165243360384, 7452.776416707507, 7466.626668666545, 5975.760969561343, 7066.929330706627, 7089.255628159175, 7092.468064895051, 7099.183593886769, 6940.826479338057, 7364.705411783683, 7409.444291678038, 7457.627118643937, 7458.552486850806, 7094.432445404252, 7122.216556178712, 7125.144982602121, 7136.821579460555, 7165.247648996989, 6213.937860621517, 7253.492255542062, 7257.818554536178, 7279.890801638086, 7348.70605175787, 5682.715864206856, 6769.289224631343, 6783.559068660521, 6837.35045170708, 6984.26665200167, 6829.624370659462, 6979.092717946634, 6982.0, 6986.860262794818, 7011.053507776589, 5708.942910570841, 6360.300366959538, 6464.09502669636, 6834.1115654966525, 6891.517593768388, 5819.94180058194, 6991.370776630128, 6999.160100787938, 7034.261402552863, 7039.472039596948, 6595.043718660671, 6680.231773346128, 6683.7660681875905, 6695.832604184934, 6753.223379311442], "value": 6426.585837434519, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.9757307280781, 866.4281574161051, 894.1460904835881, 905.5789058087988, 1102.9062529684977, 32372.352552948927, 40876.386854197175, 41206.175876482455, 41403.58596414035, 41437.792811035906, 41984.790076049576, 42020.57979420205, 42307.0, 42446.0, 42508.36237456433, 8323.875141872877, 8389.622466988969, 8465.0, 8470.78823029423, 8476.872846907301, 8746.037935827055, 8888.555572221396, 8960.775980600505, 8961.551922403887, 8984.281257499384, 8972.955135224347, 8992.64029438825, 9047.095290470967, 9052.321075919299, 9064.728058158265, 8947.239484643846, 8970.86543701845, 8994.145556172152, 9036.051214622501, 9052.321075919299, 8795.60419781105, 8812.471251724917, 8859.689910853154, 8869.423487473357, 8880.644774209024, 39117.217655647015, 39163.0, 39171.41242881359, 39256.80371598152, 39464.40803387952, 3676.761010534307, 3687.207250441168, 3714.3499887519574, 3719.776813391178, 3720.4605332226674, 32110.876119335946, 32149.35701285961, 32185.35629287401, 32195.0, 32241.87153449618, 3209.7753157279, 3286.3098749262685, 3303.4549299365576, 3344.6488118747666, 3365.9495107573407, 274602.2539774597, 277420.0, 278258.0, 320588.0, 322220.0, 50238.748806256466, 50716.0, 50858.49141508537, 50878.49121508738, 50896.0, 53319.0, 53458.0, 53510.66223344448, 53534.0, 53563.0, 16082.0, 16112.516624501284, 16184.0, 16367.263473143737, 16410.343586256655, 13235.139715918434, 13259.933700331436, 13285.800712989312, 13322.600321990356, 13329.866701333056, 14109.5061672843, 14179.929100354433, 14247.643808904653, 14255.85744142566, 14358.56924292273, 5338.9466105339225, 5916.704164791743, 6001.939980600226, 6042.909356359658, 6062.939370606326, 1036.290141253244, 1051.106559424483, 1053.5943661690305, 1104.845321654969, 1106.750981029262, 24021.399465013514, 25475.10837120692, 25776.711164441702, 25793.355166120622, 25795.097171598904, 1108.800415925125, 1139.4132022008673, 1195.1693572966706, 1207.5169932027259, 1223.4555622747969, 11143.33140011602, 12638.60975292717, 13138.211707297593, 13192.944564434822, 13231.073824832361, 289109.0, 351052.0, 353165.0, 354204.22897885344, 355269.0, 57889.421105789246, 58676.82646347049, 58744.41255587475, 58765.70617146887, 58873.0, 1331.6737399337223, 1392.8746412822895, 1493.0444515510083, 1581.9288132034317, 1613.6369316903604, 20948.952552372626, 22463.43841403978, 23782.88108559446, 23907.880460597586, 25299.74700252974, 39243.607563925136, 39556.0, 39585.406218906166, 39608.40587391134, 39672.80163599164, 299871.0, 300428.0, 300506.0, 300897.4955125298, 301312.0, 30230.395392091596, 30595.08214753561, 30625.387492249585, 33302.16744581405, 35201.64798351983, 44611.77694111509, 44632.77683611561, 44739.55260447354, 45506.31740523829, 46193.53806461892, 29407.705922940495, 29751.0, 30481.695183047887, 31071.0, 32292.515612265364, 30769.538456923612, 30774.091012923247, 31489.370212596077, 31626.893058743753, 31743.523847142773, 38079.42880856734, 38256.42615360828, 38268.0, 38374.61625383822, 38449.0, 25257.61083140445, 27612.58581121325, 29221.0, 30128.787047474845, 30865.074047778602, 23166.76833231646, 25000.3749606296, 25261.62107568425, 25294.747052529976, 25343.36641583975], "value": 18195.46196439078, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.1336048961172, 542.4250294687629, 548.9560835133187, 549.8432946610217, 564.4581202046036, 24541.263762087143, 25291.367715807115, 25311.493770124587, 25318.36704082394, 25339.746602533993, 25763.74236257637, 26276.605850912205, 26885.462290754174, 26978.0, 27029.324266893338, 6847.8630427391545, 6903.620300883449, 6958.686859091327, 6962.582245065312, 6966.860662786741, 6951.860962780741, 6964.721411143573, 7012.0, 7013.193482749472, 7032.613206273653, 6735.989601559756, 6787.117674702282, 6819.829504262409, 6823.351781580766, 6952.791416257521, 6760.560563563377, 6813.863722725568, 6822.283660215656, 6827.180738311409, 6846.315368463163, 5320.228566857784, 6236.095766113934, 6256.311805701369, 6306.527010474187, 6314.463270622003, 27788.722112779018, 31465.0, 31542.211444713837, 31879.0, 32458.863939761995, 2327.848689835172, 2340.0756701103137, 2341.1688850458013, 2355.5642206191737, 2365.3731761083154, 20503.0, 21061.5787684247, 21075.68386474204, 21076.367708968603, 21107.05018274166, 2326.8720220387872, 2349.624060150385, 2380.6310021946624, 2384.938702277487, 2400.1599440195964, 178876.0, 183100.0, 189340.0, 192337.0, 220488.0, 13446.394912228972, 14328.283585820667, 16276.674466510603, 16396.75404868928, 16650.50048498547, 22377.55244895093, 22387.44031399228, 22391.10435582591, 22410.663840042413, 22449.0, 11038.392888391056, 11090.667279981613, 11129.220954533266, 11131.0, 11160.274582152297, 10230.437325947145, 10339.948300258451, 10367.94816025915, 10371.377717336833, 10390.58437662485, 9831.0, 9899.65351212704, 9926.602935882484, 9990.850137247802, 9992.950035249778, 2405.9879700601387, 2832.745052945245, 2835.0644287385526, 2846.7153284671367, 2978.9871443709244, 925.9814803703888, 974.5224839828433, 989.1246247071393, 998.7303428074524, 1005.4017859373661, 27079.781409836603, 29620.555691664642, 29794.255143621584, 29920.40159196804, 29950.55074173889, 1052.905238528536, 1074.0601973273385, 1076.6608518316862, 1082.9729256768644, 1083.0956151613334, 9367.718968430958, 11859.0, 11888.167828251939, 12036.277823330453, 12149.149559530748, 598154.0, 618444.0, 621731.0, 642833.0, 645110.0, 169734.0, 171836.0, 173046.0, 175200.0, 175243.0, 1094.370736826328, 1168.7662467506532, 1264.791309433933, 1316.7951324538133, 1332.7934170203634, 22898.198563050213, 24447.777611119018, 24525.632115518638, 24636.753632463446, 24728.88720007568, 73090.0, 73437.26562734305, 73882.0, 76427.61786191033, 76434.0, 97224.0, 97964.0, 99405.00594994247, 100514.0, 101768.0, 12416.81374779397, 12694.746105078031, 12695.87304126947, 12712.618621441372, 12713.982881369653, 19429.708554371413, 19458.902705486384, 19504.60990780205, 20060.59878802445, 20073.598528029066, 15547.455839045579, 15811.762823557427, 15814.367425302858, 15965.840341596435, 16235.43175988882, 14056.78914816299, 14147.151170929777, 14199.503017394341, 14422.350994205495, 14781.81745460382, 16293.67412651764, 16374.017558946502, 16481.34074637001, 16605.588524975512, 16637.667246654757, 12474.126811123135, 12493.625191244277, 12530.561430349893, 12559.37203139821, 12626.0, 14623.780643290573, 15789.052656840167, 15817.920910395374, 15867.920660396625, 15881.682366352377], "value": 12732.165530011129, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.13891665001, 1497.8577035181654, 1513.424898538556, 1521.2849960518558, 1545.4822634417474, 13956.720865582682, 14763.77854332186, 14777.852221477782, 14782.334794934226, 14787.630309242275, 13876.306184690775, 14663.41346346145, 14673.266336683177, 14675.486357977474, 14701.779473307908, 2933.633295838026, 2939.7354238118564, 2983.1348908816417, 2984.2837718947403, 2985.179075754174, 2629.4609605030923, 2832.8017038807293, 2921.853907304637, 2949.2921698792243, 2955.2168675301064, 2912.184588315275, 2941.64700235972, 2944.0431859645596, 2963.5850980862697, 2966.377060817231, 2890.956635650467, 2905.2446363945314, 2918.693537178587, 2923.736863682268, 2926.268432891777, 2416.8549887006834, 2852.272670469038, 2863.3271181272594, 2918.4746745585785, 2941.735243828066, 13286.933565332112, 14084.43662253519, 14126.717465650732, 14221.0, 14228.430862765581, 7766.766996990099, 8051.275385215359, 8052.677892884278, 8060.234277743605, 8064.758057258292, 4787.6409269304595, 5013.47358527353, 5090.160123579642, 5110.160342276778, 5138.794448222104, 1459.408939379553, 1489.3074720255133, 1494.3424893047134, 1500.932458039391, 1514.3261248744313, 21381.679274811046, 21523.569528609496, 21557.67663485049, 21619.243326483655, 21713.457163570878, 6796.320367963164, 6887.070245516791, 6938.791836244921, 7081.46888983318, 7096.183938846995, 14604.707905841824, 14812.481563145238, 14850.5544833653, 14864.256787160599, 14864.554063378117, 7620.161782203953, 7754.030746156731, 7758.262965018314, 7759.418043646636, 7783.066032076187, 6154.815355539341, 6217.160683307696, 6249.406306400884, 7164.032855564432, 7357.705691772377, 4294.871153865389, 5350.38470575881, 5365.839024829262, 5430.294061771944, 5448.863778405572, 1373.505538006317, 1601.9038857668345, 1623.553522781221, 1627.4710719016366, 1651.4797838681018, 690.7893092606703, 827.9089300176976, 854.5855260198798, 860.1226748716274, 876.5529579914289, 6521.413072823469, 7296.0, 7380.151282602462, 7425.962870185723, 7603.923960760432, 615.4122812713937, 692.6467501574133, 737.8044818123202, 745.3105877063695, 745.3776096958992, 4281.807318670667, 4406.361077643788, 5030.616580440408, 5051.6716413433005, 5372.167314066326, 18493.445196644123, 19498.70751938722, 19543.315983940494, 19555.804441955683, 19586.118624661925, 18341.541461463305, 19189.808101919083, 19411.0, 19634.0, 19642.90178549098, 8795.296376289882, 9985.550650220757, 10064.547095380725, 10148.391096534231, 10327.122194613392, 8073.546761582971, 8168.673253070048, 8337.374696897636, 8420.957895210484, 8800.955995219983, 13059.0, 16741.83258167403, 17771.933683979, 17786.822131779034, 17807.732884007008, 15811.288492017657, 16417.91791041037, 16925.984440933582, 16961.236744346286, 17007.23467443943, 11255.718607034889, 11337.603183888525, 11546.884531154581, 11556.133290003116, 11574.18980671345, 13285.335733213495, 13342.399592018577, 13378.799318010042, 13444.932775336452, 13452.865471345161, 10371.68884933423, 10392.324498907547, 10609.575616975544, 10620.575176993143, 10705.357678539312, 10099.242556808122, 10204.897951020395, 10346.275760696679, 10580.629677961235, 10799.136069114295, 9409.341346105706, 9485.146336829719, 9525.428474291564, 9556.426614403159, 9582.952085239807, 6837.1111755471475, 6837.89743153863, 6978.476614254053, 7215.062041934515, 7274.127104747464, 6253.312135664983, 6988.510804243654, 7063.975723519958, 7225.891611625935, 7312.0], "value": 6324.234662040413, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.96827737919654, 456.2859125468639, 460.2980454806422, 486.75418913448704, 489.67436654624663, 6075.878482430348, 6627.569208001477, 6689.230738465078, 6691.498137639672, 6717.021850553012, 6251.687415629223, 6550.951847704368, 6579.7498475289785, 6608.933910660892, 6621.814801115633, 5261.448846747565, 5327.600429967749, 5342.599305052117, 5343.572514198874, 5386.030514507387, 5173.732435553295, 5192.454792246816, 5282.920756188661, 5347.903679745643, 5358.490943360394, 4508.684392092555, 4508.864734057967, 5007.9232964912535, 5213.348331458567, 5222.138347172712, 3736.7197460190596, 4783.139034973703, 4954.529319714621, 5084.415292241402, 5149.382074151126, 4679.15775160471, 4684.227102528079, 4686.2267725825195, 4695.5069717679835, 4698.506656801021, 6270.435660790551, 6307.747690092391, 6312.842178945517, 6331.8417039573915, 6334.714937827809, 5088.389393272794, 5408.729563521808, 5725.570582206309, 6059.303180134297, 6064.787732429344, 5526.231605886118, 6872.759453419157, 7039.718411263543, 7073.646317684095, 7088.787336379917, 5434.538064264542, 5456.890862182774, 5476.972615136939, 5485.451454854522, 5512.751926163332, 5910.556708246856, 7543.605377580111, 7562.0, 7590.658420371096, 7633.008467806397, 4307.396964424983, 4906.460289368167, 5547.44525547442, 5560.77756889728, 5564.805231816867, 4742.73914934675, 5705.800296989669, 5715.228444160069, 5715.628484148517, 5748.425157484218, 6210.50315974721, 6214.844628884314, 6228.5951413158, 6232.937670623326, 6246.75012999484, 6896.930975698775, 7038.894416583653, 7056.7530136446, 7091.290870912868, 7110.893336599956, 1542.3445035859716, 1776.8134345893754, 1944.6110777844206, 1959.8824070555813, 1980.722698822166, 1398.9160650361011, 1405.9507917222847, 1430.6208854653526, 1434.4405681784112, 1442.4662874736414, 6453.515986300952, 6579.967100164468, 6596.736130554724, 6634.668266586747, 6743.898841517381, 5878.647281163144, 7211.495195336276, 7294.0, 7294.854102917912, 7348.493558820429, 5846.976779063647, 6655.105029312168, 6736.831579210559, 6761.971408577407, 6775.356341147583, 3101.286704058079, 3315.668433156665, 3753.8123093845197, 3770.2271034437877, 3797.3924172132324, 6873.5875847448315, 6959.652017399109, 7363.374113200438, 7484.577930193354, 7492.250774922464, 6876.518643694893, 7098.254683258287, 7504.699812007459, 7522.0, 7569.583672898044, 6913.343232392915, 7494.962525187449, 7548.924510754822, 7607.0, 7631.9472211116, 6841.144856892888, 7225.205227425085, 7278.454115941219, 7281.927180728125, 7282.563046217244, 6938.861222775416, 7089.432845372461, 7101.396381307647, 7140.46446516503, 7212.134543854771, 5761.5390768739235, 6090.512758979183, 6664.169226542833, 7143.571385716874, 7636.923630763844, 4168.7707176105605, 4304.28979218438, 4756.595689366304, 4867.464578896389, 5040.974795126001, 6181.0, 6349.746010159542, 6994.208966857719, 7222.74720384784, 7408.740694075682, 5619.522340601095, 5831.650100993954, 6555.934440655532, 6797.388235058868, 6817.89773153413, 7063.39961103312, 7083.724929512738, 7104.573725576482, 7109.715611375487, 7131.322524360177, 6094.177286066324, 6289.465395441439, 6893.965530172317, 6913.861722765617, 6980.616066116209, 6519.152510173647, 7068.5758854468895, 7081.398081163159, 7098.148222213161, 7107.786766397016, 5842.620229685057, 6846.691898864464, 6866.725330986905, 6894.827629309308, 6930.861382772216], "value": 5301.565776083109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [882.7263548300024, 897.3852910756132, 905.551751882818, 905.9320550958676, 924.325242572922, 18264.0, 18351.990640514767, 18470.907645461786, 18668.346607868727, 18685.719714204282, 16768.916155419207, 16776.993380397184, 17466.56333591661, 17549.29802807886, 17567.560810979732, 1016.6340117557673, 1051.9000694933968, 1052.2423854824553, 1053.0312112856152, 1055.2876808154504, 1052.973675658107, 1060.734816295926, 1063.441693111119, 1065.8934106589356, 1066.5200659703164, 1098.653924013933, 1104.9005589496944, 1115.2137742891243, 1116.2855772305732, 1117.3966058328492, 1064.7710742190466, 1071.6624263357019, 1100.2848148703326, 1104.1498046504187, 1107.800595892739, 797.6889013284823, 800.7397595781395, 806.7942674617993, 809.3767798794895, 814.8899898513685, 16078.356865725356, 16104.516864494084, 16222.188890555424, 16274.104924229163, 16277.837221627751, 14053.578392648236, 14197.929010354985, 14524.854751452456, 14620.0, 14659.780103298459, 13787.724245515134, 13851.099678520864, 13880.652983675387, 14791.926040369837, 17483.300667973264, 5753.712314384264, 5790.507806836424, 5796.217510636052, 5800.651960882361, 5804.390538993387, 129965.35017324948, 157869.0, 168754.0, 169017.0, 176198.11900940412, 13814.171149731048, 14699.0, 14924.328405221573, 14993.700125997419, 15043.849561504463, 28319.858400708275, 28474.857625712153, 28846.27884302867, 29185.56221656677, 29239.0, 8434.0, 8475.57622118904, 8488.151184881586, 8534.829303413897, 8539.871901921477, 4139.482564679415, 4155.688323375698, 4191.224623444673, 4219.282721937278, 4222.0500387412585, 4257.616814486649, 4887.997960418177, 4968.205087186105, 4973.776180071905, 4981.302617633534, 992.7418871093568, 1047.2721458586361, 1059.3114475590928, 1064.494365176535, 1064.7604289034905, 674.780696273713, 680.9523333366716, 694.770725660541, 704.3027402871121, 717.4188906985358, 10674.946625266824, 12165.087618428477, 12249.448774805156, 12801.423935922905, 13019.218846869218, 662.3575132121834, 664.0305154474424, 675.4835394539193, 691.6230654293422, 696.9825754356182, 5455.863603409867, 5663.207150998864, 5730.570207234474, 6025.758969641165, 6461.838454038687, 153912.0, 155224.0, 158021.0, 160241.0, 161832.0, 120265.0, 132611.33694331467, 134415.0, 135544.322278388, 145923.2703836474, 116714.41642791731, 117511.0, 118249.8175018256, 123001.38499307267, 123468.38265808614, 12213.45039473208, 12762.042846786713, 13715.45138194501, 13731.107478013897, 13819.963502737133, 50145.74927125341, 51148.74425627848, 51925.0, 52686.473135268156, 52692.736536317076, 66152.00771988327, 67555.3111172205, 67580.0, 69123.3087669117, 70142.29857701357, 17220.569485762957, 17676.02781847011, 18500.537486562942, 18697.813021869606, 18703.438896833115, 18778.342758003404, 19206.0, 19472.51318716987, 19658.247845301954, 19730.1121449538, 18188.99960502185, 18295.634087318445, 18394.816051839847, 18511.444656660322, 18981.715274271173, 17693.557661058578, 18067.819321806615, 18707.0, 18785.24859005679, 18834.52913677169, 16093.195340232707, 16240.26918788681, 16448.095354755602, 16581.25384357731, 16655.916720416804, 11909.464074116859, 11922.344271064827, 11982.64052078439, 12555.372231388577, 12582.559610413591, 13568.728625427239, 14119.152850828983, 14193.645158871112, 14211.50259740904, 14242.501512447014], "value": 9384.971708301387, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 171.0, 115.0, 133.0, 166.0, 168.0, 114.0, 102.0, 114.0, 117.0], "value": 130.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 109.0, 127.0, 138.0, 130.0, 149.0, 149.0, 160.0, 143.0, 111.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 200.0, 200.0, 224.0, 271.0, 195.0, 199.0, 197.0, 205.0, 198.0], "value": 209.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 93.0, 116.0, 148.0, 84.0, 87.0, 88.0, 86.0, 132.0, 56.0], "value": 94.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 117.0, 56.0, 100.0, 107.0, 105.0, 72.0, 102.0, 151.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 90.0, 90.0, 89.0, 89.0, 90.0, 90.0, 90.0, 90.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 105.0, 96.0, 95.0, 100.0, 95.0, 106.0, 96.0, 98.0, 97.0], "value": 98.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 91.0, 94.0, 97.0, 92.0, 91.0, 91.0, 93.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 48.0, 47.0, 49.0, 50.0, 50.0, 52.0, 52.0, 49.0, 51.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 56.0, 59.0, 61.0, 56.0, 60.0, 53.0, 54.0, 55.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 77.0, 79.0, 77.0, 96.0, 100.0, 83.0, 77.0, 77.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 128.0, 128.0, 136.0, 133.0, 146.0, 151.0, 148.0, 151.0, 166.0], "value": 141.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 178.0, 186.0, 173.0, 173.0, 180.0, 222.0, 206.0, 172.0, 179.0], "value": 184.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 74.0, 57.0, 69.0, 94.0, 92.0, 92.0, 92.0, 93.0, 85.0], "value": 80.5, "unit": "score"}]}]} 00:09:53 INFO - Return code: 0 00:09:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:09:53 INFO - # TBPL SUCCESS # 00:09:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:09:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:09:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:09:53 INFO - 2017-06-13 00:09:53.349 screenresolution[1680:13923] starting screenresolution argv=screenresolution get 00:09:53 INFO - 2017-06-13 00:09:53.351 screenresolution[1680:13923] Display 0: 1600x1200x32@60 00:09:53 INFO - 2017-06-13 00:09:53.357 screenresolution[1681:13926] starting screenresolution argv=screenresolution list 00:09:53 INFO - Available Modes on Display 0 00:09:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:09:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:09:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:09:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:09:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:09:53 INFO - Intel Iris: 00:09:53 INFO - Chipset Model: Intel Iris 00:09:53 INFO - Type: GPU 00:09:53 INFO - Bus: Built-In 00:09:53 INFO - VRAM (Dynamic, Max): 1536 MB 00:09:53 INFO - Vendor: Intel (0x8086) 00:09:53 INFO - Device ID: 0x0a2e 00:09:53 INFO - Revision ID: 0x0009 00:09:53 INFO - Displays: 00:09:53 INFO - Display: 00:09:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:09:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:09:53 INFO - Display Serial Number: 1600x1200 60 00:09:53 INFO - Main Display: Yes 00:09:53 INFO - Mirror: Off 00:09:53 INFO - Online: Yes 00:09:53 INFO - Rotation: Supported 00:09:53 INFO - Return code: 0 00:09:53 INFO - Running post-action listener: _resource_record_post_action 00:09:53 INFO - [mozharness: 2017-06-13 07:09:53.590508Z] Finished run-tests step (success) 00:09:53 INFO - Running post-run listener: _resource_record_post_run 00:09:53 INFO - Total resource usage - Wall time: 1246s; CPU: 53.0%; Read bytes: 42474496; Write bytes: 480483328; Read time: 517; Write time: 2024 00:09:53 INFO - TinderboxPrint: CPU usage
52.9% 00:09:53 INFO - TinderboxPrint: I/O read bytes / time
42,474,496 / 517 00:09:53 INFO - TinderboxPrint: I/O write bytes / time
480,483,328 / 2,024 00:09:53 INFO - TinderboxPrint: CPU idle
2,346.8 (47.1%) 00:09:53 INFO - TinderboxPrint: CPU system
1,005.9 (20.2%) 00:09:53 INFO - TinderboxPrint: CPU user
1,633.0 (32.8%) 00:09:53 INFO - TinderboxPrint: Swap in / out
190,644,224 / 0 00:09:53 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 154679808; Write bytes: 156952064; Read time: 12166; Write time: 359 00:09:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 00:09:53 INFO - run-tests - Wall time: 1231s; CPU: 53.0%; Read bytes: 37264384; Write bytes: 322130432; Read time: 480; Write time: 1661 00:09:54 INFO - Running post-run listener: _upload_blobber_files 00:09:54 INFO - Blob upload gear active. 00:09:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:09:54 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 00:09:54 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 00:09:54 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 00:09:54 INFO - (blobuploader) - INFO - Open directory for files ... 00:09:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:09:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:09:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:55 INFO - (blobuploader) - INFO - Done attempting. 00:09:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:09:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:09:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:56 INFO - (blobuploader) - INFO - Done attempting. 00:09:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:09:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:09:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:57 INFO - (blobuploader) - INFO - Done attempting. 00:09:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:09:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:09:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:09:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:09:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:09:59 INFO - (blobuploader) - INFO - Done attempting. 00:09:59 INFO - (blobuploader) - INFO - Iteration through files over. 00:09:59 INFO - Return code: 0 00:09:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:09:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:09:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/206c3e658db67936f59f2574bb7eb5979c63a6b0703a275e097de4f2d3dc289bc3ff0fa41843b3d27ea52e8cad9dfe3e4e9ba7e8f3a250a0faf11daaecd552a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9dedb0dc54fc4addbc823ba0fb7297eb22bb0d80090280642c6928e50d4bf7c619c44f5a44ff51b8034ea2421b1a0779c4c0d313ff1c3460896e6d44be75b44e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a327dccaa71bd6b1c17f7ddf548ab5aa73bae6f628821c5f5931389cddd241e26561a67309157b899a6e356028e35c036c759553d377f107c7b6bd886dae271", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/058dd52f51bfefe3c712e98c43ece9d7fbb18d4faa89359cfe56d9c9b6b653e059fd344add20392e6cfa062b9ec617ce063af66df2ee4ebee73c700438266af2"} 00:09:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:09:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:09:59 INFO - Contents: 00:09:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/206c3e658db67936f59f2574bb7eb5979c63a6b0703a275e097de4f2d3dc289bc3ff0fa41843b3d27ea52e8cad9dfe3e4e9ba7e8f3a250a0faf11daaecd552a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9dedb0dc54fc4addbc823ba0fb7297eb22bb0d80090280642c6928e50d4bf7c619c44f5a44ff51b8034ea2421b1a0779c4c0d313ff1c3460896e6d44be75b44e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a327dccaa71bd6b1c17f7ddf548ab5aa73bae6f628821c5f5931389cddd241e26561a67309157b899a6e356028e35c036c759553d377f107c7b6bd886dae271", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/058dd52f51bfefe3c712e98c43ece9d7fbb18d4faa89359cfe56d9c9b6b653e059fd344add20392e6cfa062b9ec617ce063af66df2ee4ebee73c700438266af2"} 00:09:59 INFO - Running post-run listener: copy_logs_to_upload_dir 00:09:59 INFO - Copying logs to upload dir... 00:09:59 INFO - mkdir: /builds/slave/test/build/upload/logs 00:09:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1327.552743 ========= master_lag: 0.41 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 7 secs) (at 2017-06-13 00:10:00.283180) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 00:10:00.286410) ========= 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.zeVODQcMys/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.QND1Ph07GR/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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/Mozilla-Beta/sha512/206c3e658db67936f59f2574bb7eb5979c63a6b0703a275e097de4f2d3dc289bc3ff0fa41843b3d27ea52e8cad9dfe3e4e9ba7e8f3a250a0faf11daaecd552a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9dedb0dc54fc4addbc823ba0fb7297eb22bb0d80090280642c6928e50d4bf7c619c44f5a44ff51b8034ea2421b1a0779c4c0d313ff1c3460896e6d44be75b44e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a327dccaa71bd6b1c17f7ddf548ab5aa73bae6f628821c5f5931389cddd241e26561a67309157b899a6e356028e35c036c759553d377f107c7b6bd886dae271", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/058dd52f51bfefe3c712e98c43ece9d7fbb18d4faa89359cfe56d9c9b6b653e059fd344add20392e6cfa062b9ec617ce063af66df2ee4ebee73c700438266af2"} build_url:https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011082 build_url: 'https://queue.taskcluster.net/v1/task/f3_Z8RJ9RDKB9YyNolQEKQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/206c3e658db67936f59f2574bb7eb5979c63a6b0703a275e097de4f2d3dc289bc3ff0fa41843b3d27ea52e8cad9dfe3e4e9ba7e8f3a250a0faf11daaecd552a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9dedb0dc54fc4addbc823ba0fb7297eb22bb0d80090280642c6928e50d4bf7c619c44f5a44ff51b8034ea2421b1a0779c4c0d313ff1c3460896e6d44be75b44e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a327dccaa71bd6b1c17f7ddf548ab5aa73bae6f628821c5f5931389cddd241e26561a67309157b899a6e356028e35c036c759553d377f107c7b6bd886dae271", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/058dd52f51bfefe3c712e98c43ece9d7fbb18d4faa89359cfe56d9c9b6b653e059fd344add20392e6cfa062b9ec617ce063af66df2ee4ebee73c700438266af2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 00:10:00.319284) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 00:10:00.319606) ========= 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.zeVODQcMys/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.QND1Ph07GR/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.005178 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 00:10:00.367924) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 00:10:00.368531) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 00:10:00.369055) ========= ========= Total master_lag: 1.19 =========