builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0057 starttime: 1496673851.6 results: success (0) revision: 2029bc93ed006007f6d5939801338f7003d4b8d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:11.599982) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:11.600441) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:11.600748) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:11.632148) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:11.632561) ========= 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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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-05 07:44:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.4M=0.001s 2017-06-05 07:44:11 (15.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.172612 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:11.828500) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:11.828815) ========= 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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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.033820 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:11.901207) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-05 07:44:11.901569) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2029bc93ed006007f6d5939801338f7003d4b8d7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 2029bc93ed006007f6d5939801338f7003d4b8d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Te6JGnUmlB/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.yOcYFc0xYM/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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-05 07:44:12,006 truncating revision to first 12 chars 2017-06-05 07:44:12,006 Setting DEBUG logging. 2017-06-05 07:44:12,006 attempt 1/10 2017-06-05 07:44:12,006 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2029bc93ed00?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 07:44:13,487 unpacking tar archive at: mozilla-central-2029bc93ed00/testing/mozharness/ program finished with exit code 0 elapsedTime=1.783628 ========= master_lag: 1.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-05 07:44:14.917850) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:14.918126) ========= script_repo_revision: 2029bc93ed006007f6d5939801338f7003d4b8d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:14.918463) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:14.918755) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 07:44:15.021759) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-05 07:44:15.022388) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Te6JGnUmlB/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.yOcYFc0xYM/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:44:15 INFO - MultiFileLogger online at 20170605 07:44:15 in /builds/slave/test 07:44:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:44:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:44:15 INFO - {'append_to_log': False, 07:44:15 INFO - 'base_work_dir': '/builds/slave/test', 07:44:15 INFO - 'blob_upload_branch': 'Firefox', 07:44:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:44:15 INFO - 'branch': 'Firefox', 07:44:15 INFO - 'buildbot_json_path': 'buildprops.json', 07:44:15 INFO - 'config_files': ('talos/mac_config.py',), 07:44:15 INFO - 'default_actions': ('clobber', 07:44:15 INFO - 'read-buildbot-config', 07:44:15 INFO - 'download-and-extract', 07:44:15 INFO - 'populate-webroot', 07:44:15 INFO - 'create-virtualenv', 07:44:15 INFO - 'install', 07:44:15 INFO - 'setup-mitmproxy', 07:44:15 INFO - 'run-tests'), 07:44:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:44:15 INFO - 'download_minidump_stackwalk': True, 07:44:15 INFO - 'download_symbols': 'ondemand', 07:44:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:44:15 INFO - 'tooltool.py': '/tools/tooltool.py', 07:44:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:44:15 INFO - '/tools/misc-python/virtualenv.py')}, 07:44:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:44:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:44:15 INFO - 'gecko_profile': False, 07:44:15 INFO - 'gecko_profile_interval': 0, 07:44:15 INFO - 'installer_path': 'installer.exe', 07:44:15 INFO - 'log_level': 'info', 07:44:15 INFO - 'log_name': 'talos', 07:44:15 INFO - 'log_to_console': True, 07:44:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:44:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:44:15 INFO - 'opt_config_files': (), 07:44:15 INFO - 'pip_index': False, 07:44:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:44:15 INFO - 'cmd': ('bash', 07:44:15 INFO - '-c', 07:44:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:44:15 INFO - 'enabled': True, 07:44:15 INFO - 'halt_on_failure': False, 07:44:15 INFO - 'name': 'check_screen_resolution'},), 07:44:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:44:15 INFO - 'cmd': ('bash', 07:44:15 INFO - '-c', 07:44:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:44:15 INFO - 'enabled': True, 07:44:15 INFO - 'halt_on_failure': False, 07:44:15 INFO - 'name': 'check_screen_resolution'},), 07:44:15 INFO - 'run_cmd_checks_enabled': True, 07:44:15 INFO - 'suite': 'dromaeojs-e10s', 07:44:15 INFO - 'system_bits': '32', 07:44:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:44:15 INFO - 'title': 't-yosemite-r7-0057', 07:44:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:44:15 INFO - 'use_talos_json': True, 07:44:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:44:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:44:15 INFO - 'work_dir': 'build'} 07:44:15 INFO - [mozharness: 2017-06-05 14:44:15.235178Z] Running clobber step. 07:44:15 INFO - Running pre-action listener: _resource_record_pre_action 07:44:15 INFO - Running main action method: clobber 07:44:15 INFO - rmtree: /builds/slave/test/build 07:44:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:44:17 INFO - Running post-action listener: _resource_record_post_action 07:44:17 INFO - [mozharness: 2017-06-05 14:44:17.890734Z] Finished clobber step (success) 07:44:17 INFO - [mozharness: 2017-06-05 14:44:17.890882Z] Running read-buildbot-config step. 07:44:17 INFO - Running pre-action listener: _resource_record_pre_action 07:44:17 INFO - Running main action method: read_buildbot_config 07:44:17 INFO - Using buildbot properties: 07:44:17 INFO - { 07:44:17 INFO - "project": "", 07:44:17 INFO - "product": "firefox", 07:44:17 INFO - "who": "ryanvm@gmail.com", 07:44:17 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:44:17 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:44:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:44:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:44:17 INFO - "stage_platform": "macosx64", 07:44:17 INFO - "basedir": "/builds/slave/test", 07:44:17 INFO - "buildnumber": 14, 07:44:17 INFO - "platform": "yosemite_r7", 07:44:17 INFO - "parent_task_id": "AdAISLXpQiiMTIT2SknmbA", 07:44:17 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:44:17 INFO - "slavebuilddir": "test", 07:44:17 INFO - "taskId": "EM4nVmzbRp6MMTj-2M7TPQ", 07:44:17 INFO - "branch": "mozilla-central", 07:44:17 INFO - "script_repo_revision": "production", 07:44:17 INFO - "revision": "2029bc93ed006007f6d5939801338f7003d4b8d7", 07:44:17 INFO - "slavename": "t-yosemite-r7-0057", 07:44:17 INFO - "repo_path": "mozilla-central" 07:44:17 INFO - } 07:44:17 INFO - Finding installer, test and symbols from parent task. 07:44:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EM4nVmzbRp6MMTj-2M7TPQ'}, attempt #1 07:44:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EM4nVmzbRp6MMTj-2M7TPQ'}, attempt #1 07:44:19 INFO - Task dependencies: C-4ZACc7RDupCaatrkT37Q AdAISLXpQiiMTIT2SknmbA 07:44:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA'}, attempt #1 07:44:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:44:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/test_packages.json 07:44:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/target.crashreporter-symbols.zip 07:44:19 INFO - Running post-action listener: _resource_record_post_action 07:44:19 INFO - [mozharness: 2017-06-05 14:44:19.769075Z] Finished read-buildbot-config step (success) 07:44:19 INFO - [mozharness: 2017-06-05 14:44:19.769220Z] Running download-and-extract step. 07:44:19 INFO - Running pre-action listener: _resource_record_pre_action 07:44:19 INFO - Running main action method: download_and_extract 07:44:19 INFO - mkdir: /builds/slave/test/build/tests 07:44:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:44:19 INFO - trying https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:44:19 INFO - Downloading https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:44:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 07:44:20 INFO - Downloaded 1328 bytes. 07:44:20 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:44:20 INFO - Using the following test package requirements: 07:44:20 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:44:20 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:44:20 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:44:20 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:44:20 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:44:20 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:44:20 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:44:20 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:44:20 INFO - u'jsshell-mac.zip'], 07:44:20 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:44:20 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:44:20 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:44:20 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:44:20 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:44:20 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:44:20 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:44:20 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:44:20 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 07:44:20 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:44:20 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:44:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:44:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:44:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:44:20 INFO - Fetch https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:44:22 INFO - Content-Length response header: 57631760 07:44:22 INFO - Bytes received: 57631760 07:44:40 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:44:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:44:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:44:40 INFO - Fetch https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:44:42 INFO - Content-Length response header: 57631760 07:44:42 INFO - Bytes received: 57631760 07:44:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:44:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:44:59 INFO - Fetch https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:45:01 INFO - Content-Length response header: 14447425 07:45:01 INFO - Bytes received: 14447425 07:45:01 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')]} 07:45:01 INFO - trying https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:45:01 INFO - Downloading https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:45:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 07:45:03 INFO - Downloaded 67971447 bytes. 07:45:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:45:03 INFO - mkdir: /builds/slave/test/properties 07:45:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:45:03 INFO - Writing to file /builds/slave/test/properties/build_url 07:45:03 INFO - Contents: 07:45:03 INFO - build_url:https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:45:03 INFO - Running post-action listener: _resource_record_post_action 07:45:03 INFO - Running post-action listener: set_extra_try_arguments 07:45:03 INFO - [mozharness: 2017-06-05 14:45:03.489130Z] Finished download-and-extract step (success) 07:45:03 INFO - [mozharness: 2017-06-05 14:45:03.489276Z] Running populate-webroot step. 07:45:03 INFO - Running pre-action listener: _resource_record_pre_action 07:45:03 INFO - Running main action method: populate_webroot 07:45:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:45:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:45:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:45:03 INFO - '--tppagecycles', 07:45:03 INFO - '7'], 07:45:03 INFO - 'tests': ['tsvgm']}}, 07:45:03 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:45:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:45:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:45:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:45:03 INFO - 'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:45:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:45:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:45:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:45:03 INFO - 'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['damp', 'tps']}, 07:45:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:45:03 INFO - 'tests': ['damp', 'tps']}, 07:45:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['dromaeo_dom']}, 07:45:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:45:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:45:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:45:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:45:03 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 07:45:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['a11yr', 07:45:03 INFO - 'ts_paint', 07:45:03 INFO - 'tpaint', 07:45:03 INFO - 'sessionrestore', 07:45:03 INFO - 'sessionrestore_no_auto_restore', 07:45:03 INFO - 'tabpaint']}, 07:45:03 INFO - 'other-e10s': {'tests': ['a11yr', 07:45:03 INFO - 'ts_paint', 07:45:03 INFO - 'tpaint', 07:45:03 INFO - 'sessionrestore', 07:45:03 INFO - 'sessionrestore_no_auto_restore', 07:45:03 INFO - 'tabpaint']}, 07:45:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:45:03 INFO - 'ts_paint', 07:45:03 INFO - 'tpaint', 07:45:03 INFO - 'sessionrestore', 07:45:03 INFO - 'sessionrestore_no_auto_restore', 07:45:03 INFO - 'tabpaint']}, 07:45:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:45:03 INFO - 'ts_paint', 07:45:03 INFO - 'tpaint', 07:45:03 INFO - 'sessionrestore', 07:45:03 INFO - 'sessionrestore_no_auto_restore', 07:45:03 INFO - 'tabpaint']}, 07:45:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['a11yr', 07:45:03 INFO - 'ts_paint', 07:45:03 INFO - 'tpaint', 07:45:03 INFO - 'sessionrestore', 07:45:03 INFO - 'sessionrestore_no_auto_restore', 07:45:03 INFO - 'tabpaint']}, 07:45:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['a11yr', 07:45:03 INFO - 'ts_paint', 07:45:03 INFO - 'tpaint', 07:45:03 INFO - 'sessionrestore', 07:45:03 INFO - 'sessionrestore_no_auto_restore', 07:45:03 INFO - 'tabpaint']}, 07:45:03 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:45:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:45:03 INFO - 'bloom_basic_ref']}, 07:45:03 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 07:45:03 INFO - 'talos_options': ['--mitmproxy', 07:45:03 INFO - 'mitmproxy-recording-1.mp', 07:45:03 INFO - '--firstNonBlankPaint'], 07:45:03 INFO - 'tests': ['Quantum_1']}, 07:45:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['tsvgx', 07:45:03 INFO - 'tsvgr_opacity', 07:45:03 INFO - 'tart', 07:45:03 INFO - 'tscrollx', 07:45:03 INFO - 'cart', 07:45:03 INFO - 'tsvg_static']}, 07:45:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:45:03 INFO - 'tsvgr_opacity', 07:45:03 INFO - 'tart', 07:45:03 INFO - 'tscrollx', 07:45:03 INFO - 'cart', 07:45:03 INFO - 'tsvg_static']}, 07:45:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:45:03 INFO - 'talos_options': ['--disable-e10s'], 07:45:03 INFO - 'tests': ['tp5o']}, 07:45:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:45:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:45:03 INFO - 'talos_options': ['--disable-e10s', 07:45:03 INFO - '--xperf_path', 07:45:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:45:03 INFO - 'tests': ['tp5n']}, 07:45:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:45:03 INFO - 'talos_options': ['--xperf_path', 07:45:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:45:03 INFO - 'tests': ['tp5n']}}, 07:45:03 INFO - 'talos.zip': {'path': '', 07:45:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:45:03 INFO - Running post-action listener: _resource_record_post_action 07:45:03 INFO - [mozharness: 2017-06-05 14:45:03.512096Z] Finished populate-webroot step (success) 07:45:03 INFO - [mozharness: 2017-06-05 14:45:03.512233Z] Running create-virtualenv step. 07:45:03 INFO - Running pre-action listener: _resource_record_pre_action 07:45:03 INFO - Running main action method: create_virtualenv 07:45:03 INFO - Creating virtualenv /builds/slave/test/build/venv 07:45:03 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 07:45:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:45:03 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 07:45:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:45:03 INFO - Using real prefix '/tools/python27' 07:45:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:45:04 INFO - Installing distribute.............................................................................................................................................................................................done. 07:45:07 INFO - Installing pip.................done. 07:45:07 INFO - Return code: 0 07:45:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:45:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:45:07 INFO - Reading from file tmpfile_stdout 07:45:07 INFO - Output received: 07:45:07 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) 07:45:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:45:07 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')]} 07:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:07 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')]} 07:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:07 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 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:07 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 07:45:07 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 07:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:07 INFO - 'HOME': '/Users/cltbld', 07:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:07 INFO - 'LOGNAME': 'cltbld', 07:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:07 INFO - 'MOZ_NO_REMOTE': '1', 07:45:07 INFO - 'NO_EM_RESTART': '1', 07:45:07 INFO - 'PAGER': '/bin/cat', 07:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:07 INFO - 'PWD': '/builds/slave/test', 07:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:07 INFO - 'SHELL': '/bin/bash', 07:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:07 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:07 INFO - 'USER': 'cltbld', 07:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:07 INFO - 'XPC_FLAGS': '0x0', 07:45:07 INFO - 'XPC_SERVICE_NAME': '0', 07:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:07 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 07:45:07 INFO - Cleaning up... 07:45:07 INFO - Return code: 0 07:45:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:45:07 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')]} 07:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:07 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')]} 07:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:07 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 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:07 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 07:45:07 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 07:45:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:07 INFO - 'HOME': '/Users/cltbld', 07:45:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:07 INFO - 'LOGNAME': 'cltbld', 07:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:07 INFO - 'MOZ_NO_REMOTE': '1', 07:45:07 INFO - 'NO_EM_RESTART': '1', 07:45:07 INFO - 'PAGER': '/bin/cat', 07:45:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:07 INFO - 'PWD': '/builds/slave/test', 07:45:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:07 INFO - 'SHELL': '/bin/bash', 07:45:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:07 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:07 INFO - 'USER': 'cltbld', 07:45:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:07 INFO - 'XPC_FLAGS': '0x0', 07:45:07 INFO - 'XPC_SERVICE_NAME': '0', 07:45:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:08 INFO - Downloading/unpacking psutil>=3.1.1 07:45:08 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 07:45:08 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 07:45:08 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 07:45:08 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 07:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:45:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:45:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:45:10 INFO - Installing collected packages: psutil 07:45:10 INFO - Running setup.py install for psutil 07:45:10 INFO - building 'psutil._psutil_osx' extension 07:45:10 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 07:45:10 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 07:45:11 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 07:45:11 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 07:45:11 INFO - building 'psutil._psutil_posix' extension 07:45:11 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 07:45:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:45:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:45:11 INFO - ^ 07:45:11 INFO - 1 warning generated. 07:45:11 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 07:45:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:45:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:45:11 INFO - Successfully installed psutil 07:45:11 INFO - Cleaning up... 07:45:11 INFO - Return code: 0 07:45:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:45:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:45:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:11 INFO - 'HOME': '/Users/cltbld', 07:45:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:11 INFO - 'LOGNAME': 'cltbld', 07:45:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:11 INFO - 'MOZ_NO_REMOTE': '1', 07:45:11 INFO - 'NO_EM_RESTART': '1', 07:45:11 INFO - 'PAGER': '/bin/cat', 07:45:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:11 INFO - 'PWD': '/builds/slave/test', 07:45:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:11 INFO - 'SHELL': '/bin/bash', 07:45:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:11 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:11 INFO - 'USER': 'cltbld', 07:45:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:11 INFO - 'XPC_FLAGS': '0x0', 07:45:11 INFO - 'XPC_SERVICE_NAME': '0', 07:45:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:45:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:45:13 INFO - Installing collected packages: mozsystemmonitor 07:45:13 INFO - Running setup.py install for mozsystemmonitor 07:45:13 INFO - Successfully installed mozsystemmonitor 07:45:13 INFO - Cleaning up... 07:45:13 INFO - Return code: 0 07:45:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:45:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:45:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:45:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:13 INFO - 'HOME': '/Users/cltbld', 07:45:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:13 INFO - 'LOGNAME': 'cltbld', 07:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:13 INFO - 'MOZ_NO_REMOTE': '1', 07:45:13 INFO - 'NO_EM_RESTART': '1', 07:45:13 INFO - 'PAGER': '/bin/cat', 07:45:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:13 INFO - 'PWD': '/builds/slave/test', 07:45:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:13 INFO - 'SHELL': '/bin/bash', 07:45:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:13 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:13 INFO - 'USER': 'cltbld', 07:45:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:13 INFO - 'XPC_FLAGS': '0x0', 07:45:13 INFO - 'XPC_SERVICE_NAME': '0', 07:45:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:13 INFO - Downloading/unpacking jsonschema==2.5.1 07:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:45:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:15 INFO - Downloading functools32-3.2.3-2.tar.gz 07:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:45:15 INFO - warning: no files found matching '*.txt' 07:45:15 INFO - no previously-included directories found matching 'build' 07:45:15 INFO - no previously-included directories found matching 'dist' 07:45:15 INFO - no previously-included directories found matching '.git*' 07:45:15 INFO - Installing collected packages: jsonschema, functools32 07:45:15 INFO - Running setup.py install for functools32 07:45:15 INFO - warning: no files found matching '*.txt' 07:45:15 INFO - no previously-included directories found matching 'build' 07:45:15 INFO - no previously-included directories found matching 'dist' 07:45:15 INFO - no previously-included directories found matching '.git*' 07:45:15 INFO - Successfully installed jsonschema functools32 07:45:15 INFO - Cleaning up... 07:45:15 INFO - Return code: 0 07:45:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:15 INFO - 'HOME': '/Users/cltbld', 07:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:15 INFO - 'LOGNAME': 'cltbld', 07:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:15 INFO - 'MOZ_NO_REMOTE': '1', 07:45:15 INFO - 'NO_EM_RESTART': '1', 07:45:15 INFO - 'PAGER': '/bin/cat', 07:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:15 INFO - 'PWD': '/builds/slave/test', 07:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:15 INFO - 'SHELL': '/bin/bash', 07:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:15 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:15 INFO - 'USER': 'cltbld', 07:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:15 INFO - 'XPC_FLAGS': '0x0', 07:45:15 INFO - 'XPC_SERVICE_NAME': '0', 07:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:45:15 INFO - Cleaning up... 07:45:15 INFO - Return code: 0 07:45:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:15 INFO - 'HOME': '/Users/cltbld', 07:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:15 INFO - 'LOGNAME': 'cltbld', 07:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:15 INFO - 'MOZ_NO_REMOTE': '1', 07:45:15 INFO - 'NO_EM_RESTART': '1', 07:45:15 INFO - 'PAGER': '/bin/cat', 07:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:15 INFO - 'PWD': '/builds/slave/test', 07:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:15 INFO - 'SHELL': '/bin/bash', 07:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:15 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:15 INFO - 'USER': 'cltbld', 07:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:15 INFO - 'XPC_FLAGS': '0x0', 07:45:15 INFO - 'XPC_SERVICE_NAME': '0', 07:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:16 INFO - Downloading/unpacking blobuploader==1.2.4 07:45:16 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 07:45:16 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 07:45:16 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 07:45:16 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 07:45:17 INFO - Downloading blobuploader-1.2.4.tar.gz 07:45:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:45:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:45:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:45:18 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 07:45:18 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 07:45:18 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 07:45:18 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 07:45:18 INFO - Downloading docopt-0.6.1.tar.gz 07:45:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:45:18 INFO - Installing collected packages: blobuploader, requests, docopt 07:45:18 INFO - Running setup.py install for blobuploader 07:45:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:45:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:45:18 INFO - Running setup.py install for requests 07:45:19 INFO - Running setup.py install for docopt 07:45:19 INFO - Successfully installed blobuploader requests docopt 07:45:19 INFO - Cleaning up... 07:45:19 INFO - Return code: 0 07:45:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:19 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 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:19 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 07:45:19 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 07:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:19 INFO - 'HOME': '/Users/cltbld', 07:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:19 INFO - 'LOGNAME': 'cltbld', 07:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:19 INFO - 'MOZ_NO_REMOTE': '1', 07:45:19 INFO - 'NO_EM_RESTART': '1', 07:45:19 INFO - 'PAGER': '/bin/cat', 07:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:19 INFO - 'PWD': '/builds/slave/test', 07:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:19 INFO - 'SHELL': '/bin/bash', 07:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:19 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:19 INFO - 'USER': 'cltbld', 07:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:19 INFO - 'XPC_FLAGS': '0x0', 07:45:19 INFO - 'XPC_SERVICE_NAME': '0', 07:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:45:19 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-j3L0Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:45:19 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-zp3pMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:45:19 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-oK6slF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:45:19 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-gqiXZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:45:20 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-38zG2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:45:20 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-abQU85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:45:20 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-A_G9wv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:45:20 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-so1Pj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:45:20 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-h_bkwo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:45:20 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-3AdZl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:45:20 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-kf5p2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:45:20 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-6o2Spo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:45:20 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-WFEW4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:45:21 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-O8HXjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:21 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-v3X1mR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:45:21 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-qc5Qm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:45:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:45:21 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-MX7O51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:45:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:45:21 INFO - Running setup.py install for manifestparser 07:45:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:45:21 INFO - Running setup.py install for mozcrash 07:45:21 INFO - Running setup.py install for mozdebug 07:45:21 INFO - Running setup.py install for mozdevice 07:45:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:45:21 INFO - Running setup.py install for mozfile 07:45:22 INFO - Running setup.py install for mozhttpd 07:45:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:45:22 INFO - Running setup.py install for mozinfo 07:45:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:45:22 INFO - Running setup.py install for mozInstall 07:45:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:45:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:45:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:45:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:45:22 INFO - Running setup.py install for mozleak 07:45:22 INFO - Running setup.py install for mozlog 07:45:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:45:22 INFO - Running setup.py install for moznetwork 07:45:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:45:23 INFO - Running setup.py install for mozprocess 07:45:23 INFO - Running setup.py install for mozprofile 07:45:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:45:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:45:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:45:23 INFO - Running setup.py install for mozrunner 07:45:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:45:23 INFO - Running setup.py install for mozscreenshot 07:45:23 INFO - Running setup.py install for moztest 07:45:23 INFO - Running setup.py install for mozversion 07:45:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:45:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:45:23 INFO - Cleaning up... 07:45:23 INFO - Return code: 0 07:45:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:23 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 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:23 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 07:45:23 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 07:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:23 INFO - 'HOME': '/Users/cltbld', 07:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:23 INFO - 'LOGNAME': 'cltbld', 07:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:23 INFO - 'MOZ_NO_REMOTE': '1', 07:45:23 INFO - 'NO_EM_RESTART': '1', 07:45:23 INFO - 'PAGER': '/bin/cat', 07:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:23 INFO - 'PWD': '/builds/slave/test', 07:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:23 INFO - 'SHELL': '/bin/bash', 07:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:23 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:23 INFO - 'USER': 'cltbld', 07:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:23 INFO - 'XPC_FLAGS': '0x0', 07:45:23 INFO - 'XPC_SERVICE_NAME': '0', 07:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:45:24 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-BNM2_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:45:24 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)) 07:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:45:24 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-ZPllp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:45:24 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)) 07:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:45:24 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-qOYA8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:45:24 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)) 07:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:45:24 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-4mnm4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:45:24 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)) 07:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:45:24 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-cu11E0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:45:24 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)) 07:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:45:24 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-vi40qB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:45:24 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)) 07:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:45:24 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-IgIpw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:45:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:45:24 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-HWy8Mh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:45:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:45:25 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-o0k1OH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:45:25 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)) 07:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:45:25 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-iZNrx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:45:25 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)) 07:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:45:25 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-4SzTbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:45:25 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)) 07:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:45:25 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-Q4qZ0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:45:25 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)) 07:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:45:25 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-z0GVG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:45:25 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)) 07:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:45:25 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-Xl4CNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:45:25 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)) 07:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:25 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-_36xJv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:45:25 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)) 07:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:45:25 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-bEVlav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:45:25 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)) 07:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:45:25 INFO - Running setup.py (path:/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/pip-k4WPkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:45:26 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)) 07:45:26 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)) 07:45:26 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)) 07:45:26 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)) 07:45:26 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)) 07:45:26 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)) 07:45:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:27 INFO - Downloading blessings-1.6.tar.gz 07:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:45:27 INFO - Installing collected packages: blessings 07:45:27 INFO - Running setup.py install for blessings 07:45:27 INFO - Successfully installed blessings 07:45:27 INFO - Cleaning up... 07:45:27 INFO - Return code: 0 07:45:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:45:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:45:28 INFO - Reading from file tmpfile_stdout 07:45:28 INFO - Current package versions: 07:45:28 INFO - blessings == 1.6 07:45:28 INFO - blobuploader == 1.2.4 07:45:28 INFO - docopt == 0.6.1 07:45:28 INFO - functools32 == 3.2.3-2 07:45:28 INFO - jsonschema == 2.5.1 07:45:28 INFO - manifestparser == 1.1 07:45:28 INFO - mozInstall == 1.12 07:45:28 INFO - mozcrash == 1.0 07:45:28 INFO - mozdebug == 0.1 07:45:28 INFO - mozdevice == 0.51 07:45:28 INFO - mozfile == 1.2 07:45:28 INFO - mozhttpd == 0.7 07:45:28 INFO - mozinfo == 0.9 07:45:28 INFO - mozleak == 0.1 07:45:28 INFO - mozlog == 3.4 07:45:28 INFO - moznetwork == 0.27 07:45:28 INFO - mozprocess == 0.25 07:45:28 INFO - mozprofile == 0.28 07:45:28 INFO - mozrunner == 6.13 07:45:28 INFO - mozscreenshot == 0.1 07:45:28 INFO - mozsystemmonitor == 0.3 07:45:28 INFO - moztest == 0.8 07:45:28 INFO - mozversion == 1.4 07:45:28 INFO - psutil == 3.1.1 07:45:28 INFO - requests == 1.2.3 07:45:28 INFO - wsgiref == 0.1.2 07:45:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:28 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 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:28 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 07:45:28 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 07:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:28 INFO - 'HOME': '/Users/cltbld', 07:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:28 INFO - 'LOGNAME': 'cltbld', 07:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:28 INFO - 'MOZ_NO_REMOTE': '1', 07:45:28 INFO - 'NO_EM_RESTART': '1', 07:45:28 INFO - 'PAGER': '/bin/cat', 07:45:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:28 INFO - 'PWD': '/builds/slave/test', 07:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:28 INFO - 'SHELL': '/bin/bash', 07:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:28 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:28 INFO - 'USER': 'cltbld', 07:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:28 INFO - 'XPC_FLAGS': '0x0', 07:45:28 INFO - 'XPC_SERVICE_NAME': '0', 07:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:28 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)) 07:45:28 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)) 07:45:28 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)) 07:45:28 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)) 07:45:28 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)) 07:45:28 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)) 07:45:28 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)) 07:45:28 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)) 07:45:28 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)) 07:45:28 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 07:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:45:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:45:29 INFO - Downloading marionette_driver-1.4.0.tar.gz 07:45:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 07:45:29 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)) 07:45:29 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)) 07:45:29 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)) 07:45:29 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)) 07:45:29 INFO - Installing collected packages: marionette-driver 07:45:29 INFO - Running setup.py install for marionette-driver 07:45:30 INFO - Successfully installed marionette-driver 07:45:30 INFO - Cleaning up... 07:45:30 INFO - Return code: 0 07:45:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:45:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:45:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:45:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b43ec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b442768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffadb4597f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, '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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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 07:45:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:45:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:30 INFO - 'HOME': '/Users/cltbld', 07:45:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:30 INFO - 'LOGNAME': 'cltbld', 07:45:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:30 INFO - 'MOZ_NO_REMOTE': '1', 07:45:30 INFO - 'NO_EM_RESTART': '1', 07:45:30 INFO - 'PAGER': '/bin/cat', 07:45:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:30 INFO - 'PWD': '/builds/slave/test', 07:45:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:30 INFO - 'SHELL': '/bin/bash', 07:45:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:30 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:30 INFO - 'USER': 'cltbld', 07:45:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:30 INFO - 'XPC_FLAGS': '0x0', 07:45:30 INFO - 'XPC_SERVICE_NAME': '0', 07:45:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:45:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:45:30 INFO - Cleaning up... 07:45:30 INFO - Return code: 0 07:45:30 INFO - Running post-action listener: _resource_record_post_action 07:45:30 INFO - Running post-action listener: _start_resource_monitoring 07:45:30 INFO - Starting resource monitoring. 07:45:30 INFO - [mozharness: 2017-06-05 14:45:30.499801Z] Finished create-virtualenv step (success) 07:45:30 INFO - [mozharness: 2017-06-05 14:45:30.501026Z] Running install step. 07:45:30 INFO - Running pre-action listener: _resource_record_pre_action 07:45:30 INFO - Running main action method: install 07:45:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:45:30 INFO - Reading from file tmpfile_stdout 07:45:30 INFO - Detecting whether we're running mozinstall >=1.0... 07:45:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:45:30 INFO - Reading from file tmpfile_stdout 07:45:30 INFO - Output received: 07:45:30 INFO - Usage: mozinstall [options] installer 07:45:30 INFO - Options: 07:45:30 INFO - -h, --help show this help message and exit 07:45:30 INFO - -d DEST, --destination=DEST 07:45:30 INFO - Directory to install application into. [default: 07:45:30 INFO - "/builds/slave/test"] 07:45:30 INFO - --app=APP Application being installed. [default: firefox] 07:45:30 INFO - mkdir: /builds/slave/test/build/application 07:45:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:45:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:45:52 INFO - Reading from file tmpfile_stdout 07:45:52 INFO - Output received: 07:45:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:45:52 INFO - Running post-action listener: _resource_record_post_action 07:45:52 INFO - [mozharness: 2017-06-05 14:45:52.571430Z] Finished install step (success) 07:45:52 INFO - [mozharness: 2017-06-05 14:45:52.571584Z] Running setup-mitmproxy step. 07:45:52 INFO - Running pre-action listener: _resource_record_pre_action 07:45:52 INFO - Running main action method: setup_mitmproxy 07:45:52 INFO - Skipping: mitmproxy is not required 07:45:52 INFO - Running post-action listener: _resource_record_post_action 07:45:52 INFO - [mozharness: 2017-06-05 14:45:52.572203Z] Finished setup-mitmproxy step (success) 07:45:52 INFO - [mozharness: 2017-06-05 14:45:52.572347Z] Running run-tests step. 07:45:52 INFO - Running pre-action listener: _resource_record_pre_action 07:45:52 INFO - Running main action method: run_tests 07:45:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:45:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:45:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:45:52 INFO - 2017-06-05 07:45:52.586 screenresolution[1628:8175] starting screenresolution argv=screenresolution get 07:45:52 INFO - 2017-06-05 07:45:52.589 screenresolution[1628:8175] Display 0: 1600x1200x32@60 07:45:52 INFO - 2017-06-05 07:45:52.595 screenresolution[1629:8178] starting screenresolution argv=screenresolution list 07:45:52 INFO - Available Modes on Display 0 07:45:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:45:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:45:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:45:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:45:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:45:52 INFO - Intel Iris: 07:45:52 INFO - Chipset Model: Intel Iris 07:45:52 INFO - Type: GPU 07:45:52 INFO - Bus: Built-In 07:45:52 INFO - VRAM (Dynamic, Max): 1536 MB 07:45:52 INFO - Vendor: Intel (0x8086) 07:45:52 INFO - Device ID: 0x0a2e 07:45:52 INFO - Revision ID: 0x0009 07:45:52 INFO - Displays: 07:45:52 INFO - Display: 07:45:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:45:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:45:52 INFO - Display Serial Number: 1600x1200 60 07:45:52 INFO - Main Display: Yes 07:45:52 INFO - Mirror: Off 07:45:52 INFO - Online: Yes 07:45:52 INFO - Rotation: Supported 07:45:52 INFO - Return code: 0 07:45:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:45:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:45:52 INFO - Python 2.7.3 07:45:52 INFO - Return code: 0 07:45:52 INFO - grabbing minidump binary from tooltool 07:45:52 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')]} 07:45:52 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 0x10b3c7c60>, '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 0x10b15ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b185be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:45:52 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 07:45:52 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 07:45:52 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 07:45:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:45:52 INFO - Return code: 0 07:45:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:45:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:45:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:45:52 INFO - ENV: RUST_BACKTRACE is now 1 07:45:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:45:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:45:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--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 07:45:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0057 --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 07:45:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Te6JGnUmlB/Render', 07:45:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:45:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:45:52 INFO - 'HOME': '/Users/cltbld', 07:45:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:45:52 INFO - 'LOGNAME': 'cltbld', 07:45:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:45:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:45:52 INFO - 'MOZ_NO_REMOTE': '1', 07:45:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:45:52 INFO - 'NO_EM_RESTART': '1', 07:45:52 INFO - 'PAGER': '/bin/cat', 07:45:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:45:52 INFO - 'PWD': '/builds/slave/test', 07:45:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:45:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:45:52 INFO - 'RUST_BACKTRACE': '1', 07:45:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 07:45:52 INFO - 'SHELL': '/bin/bash', 07:45:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yOcYFc0xYM/Listeners', 07:45:52 INFO - 'TMPDIR': '/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/', 07:45:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:45:52 INFO - 'USER': 'cltbld', 07:45:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:45:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:45:52 INFO - 'XPC_FLAGS': '0x0', 07:45:52 INFO - 'XPC_SERVICE_NAME': '0', 07:45:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:45:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0057', '--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 07:45:53 INFO - mozversion application_buildid: 20170605061619 07:45:53 INFO - mozversion application_changeset: 2029bc93ed006007f6d5939801338f7003d4b8d7 07:45:53 INFO - mozversion application_display_name: Nightly 07:45:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:45:53 INFO - mozversion application_name: Firefox 07:45:53 INFO - mozversion application_remotingname: firefox 07:45:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:45:53 INFO - mozversion application_vendor: Mozilla 07:45:53 INFO - mozversion application_version: 55.0a1 07:45:53 INFO - mozversion platform_buildid: 20170605061619 07:45:53 INFO - mozversion platform_changeset: 2029bc93ed006007f6d5939801338f7003d4b8d7 07:45:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:45:53 INFO - mozversion platform_version: 55.0a1 07:45:53 INFO - using testdate: 1496673953 07:45:53 INFO - actual date: 1496673953 07:45:53 INFO - starting webserver on 'localhost:49226' 07:45:53 INFO - SUITE-START | Running 2 tests 07:45:53 INFO - TEST-START | dromaeo_css 07:45:53 INFO - operating with platform_type : mac_ 07:45:53 INFO - Initialising browser for dromaeo_css test... 07:45:53 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpJI_kOZ/profile 07:45:53 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 07:46:27 INFO - TEST-INFO | 1639: killed by SIGTERM 07:46:27 INFO - Browser initialized. 07:46:27 INFO - Running cycle 1/1 for dromaeo_css test... 07:46:27 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpJI_kOZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:46:29 INFO - PID 1647 | 2017-06-05 07:46:29.221 plugin-container[1648:8828] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9837, name = 'com.apple.tsm.portname' 07:46:29 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:46:29 INFO - PID 1647 | 2017-06-05 07:46:29.221 plugin-container[1648:8828] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 07:46:29 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:46:29 INFO - PID 1647 | 2017-06-05 07:46:29.221 plugin-container[1648:8828] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:46:29 INFO - PID 1647 | 2017-06-05 07:46:29.631 plugin-container[1649:8873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 07:46:29 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:46:29 INFO - PID 1647 | 2017-06-05 07:46:29.631 plugin-container[1649:8873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 07:46:29 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:46:29 INFO - PID 1647 | 2017-06-05 07:46:29.631 plugin-container[1649:8873] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:46:38 INFO - PID 1647 | RSS: Main: 268247040 07:46:38 INFO - PID 1647 | 07:49:41 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 07:49:42 INFO - PID 1647 | RSS: Main: 270524416 07:49:42 INFO - PID 1647 | 07:52:44 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 07:52:45 INFO - PID 1647 | RSS: Main: 283639808 07:52:45 INFO - PID 1647 | 07:55:47 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 07:55:48 INFO - PID 1647 | RSS: Main: 282714112 07:55:48 INFO - PID 1647 | 07:58:49 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 07:58:50 INFO - PID 1647 | RSS: Main: 286453760 07:58:50 INFO - PID 1647 | 08:01:53 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 08:01:54 INFO - PID 1647 | RSS: Main: 286494720 08:01:54 INFO - PID 1647 | 08:04:55 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 08:04:56 INFO - PID 1647 | RSS: Main: 286384128 08:04:56 INFO - PID 1647 | 08:04:56 INFO - PID 1647 | __start_tp_report 08:04:56 INFO - PID 1647 | _x_x_mozilla_page_load 08:04:56 INFO - PID 1647 | _x_x_mozilla_page_load_details 08:04:56 INFO - PID 1647 | |i|pagename|runs| 08:04:56 INFO - PID 1647 | |0;dojo.html;3591.820408979551;3611.8735844245457;3641.326354624396;3655.798931058791;3658.9634103658955;4571.725696458215;6166.982447896103;6214.471769899564;6229.501639868811;6237.282712488065;6157.692115394235;6173.228346456693;6210.875782484348;6218.471429928451;6261.436470717635;5033.84898453047;5282.3925248596515;5396.811111611094;5437.32033495813;5448.727563621803;4860.441049279325;5564.248826408444;5569.053260945629;5576.888462230753;5585.944140558583;5373.38206106295;5429.972850135763;5441.945580544223;5505.449455054482;5508.173773933902;4720.929186062212;5437.673739575638;5490.505854473076;5500.614956953015;5503.9174412383845;5416.702081385522;5435.211894275348;5435.537979271727;5436.184572314145;5452.100403433429;6145.863015342176;6188.566800323979;6283.654399008053;6285.120083188357;6295.087212354183;6514.283428822826;6615.669216539154;6698.631575263358;6723.798286051427;6734.730610775563;6779.796606101825;6798.898016529756;6823.795286141423;6839.350261725128;6848.212455567574;6322.9051564226575;6350.5554611177695;6352.14246076783;6353;6361.3638636136475;6720.126383570105;6801;6805.251422343538;6883.311668833177;6938.063361446241;6245.875082498324;6365.331640177715;6531.738730450728;6539.444147247537;6583.604983700993;6753.8649227015185;6763.72945082201;6766.966165169143;6775.898361524581;6779.966100169566;6604.471642268607;6618.205815302194;6636.336366363325;6643.2360278568585;6651.900221496681;6475.805725828232;6525.543211975116;6633.2371777246235;6659.300773418819;6711.899321510181;4567.5432456754725;5318.680879147263;5345.1714984177515;5355.437679043722;5360.463953604686;4754.738489383117;4764.166270902579;4783.1868582341085;4805.27920811881;4818.638602104857;6208.348123447064;6261.843453913689;6301;6306.24325080984;6322;6706.798796036127;6765.661716914135;6793.082933803972;6817.318268173242;6820.079289295981;6307.432331090132;6328.145700330489;6349.111124442582;6352.936470635327;6382.585131966407;5818.330891947396;6001.549883758736;6011.3086994995265;6012.248468941382;6039.335673075958;6780.1524809398825;6811.250762416229;6883.3116688330765;6925.861482770417;6973.058637084132;6746.156730408699;6824.317568243136;6824.965875170592;6854.417374523221;6887.380135787803;6683.7660681875905;6875.3124687532845;6968.616726080114;6981.96509017472;6984.895226571707;5561.249231353715;6504.252011018793;6592.505562082959;6653.933460665331;6677.399034086956;6796.694148763218;6818.727250909908;6821.727130914708;6822.590644561342;6828.863422731418;6314.084457753692;6342.841428964312;6501.317361676955;6569.146011018537;6619.3380661933425;5728.598998070095;6513.576617519846;6517.283098859217;6541.672916354259;6560.770373036921;5396.298481197419;6339.9683001586545;6423.132877061724;6451.064595633513;6479.514036447191;6339.080833279057;6602.471802255904;6606.867862642816;6616.106825578485;6622.503312251504;6304.148939893239;6310.936890631035;6390.073439351361;6398.456131228898;6414.839629009312 08:04:56 INFO - PID 1647 | |1;ext.html;676.8240257533041;775.7207405334083;837.6733074101102;1259.7039695671515;1267.4169881854345;34223.48664770024;36076.819615901884;37648;38649.61350386495;38746;34700.13249668753;38758.61241387585;39209.80395098028;39236.80381598095;39285;7813.6483858226375;8291.917080829175;8526.104759000278;8575.571221438902;8586.87119693202;8480.957595212047;8544.871826922601;8553.786155346104;8577.614007369652;8584.785380365478;8524.403291769579;8564.614592343329;8578.4424012439;8583.184597463262;8586.828263434758;8488.320934325238;8546.487210767344;8566.914330856705;8580.442271252348;8585.570721463902;8301.543415112166;8340.37447191457;8342.37432192588;8354.624041918129;8355.874661880016;37516;37587.24825503475;37648;37657.81171094155;37890.43164352537;3503.7911920387423;3541.8760343388117;3544.113971507123;3549.0772399176144;3554.608993010767;30532.69467305343;30783.692163078307;30784.538231926537;30826.53760193599;30844.53733194004;2831.462022215793;2924.809887357315;2942.279141610319;3088.629364476255;3106.8601912913746;255331;258684.70657646778;290748.54625726945;309180;311156;48478.5152148474;48514.5148548517;48572;48607.78480538015;48616.02767944621;51044.74477627588;51061.48938510568;51253.231201532006;52248.47751522436;54537.181942270894;14470.27648617565;14485.420583176765;14621.92689036548;14647.340869660678;14669.193194374411;11521.769564608661;11642.53429862796;11726.824097638542;11728.354940478184;11731.296122232694;11286.774264514665;13977.161370317814;13996.650083747783;14015.789763153562;14151.93860460449;5210.817621383233;6101.572889897753;6148.1392605035335;6173.876522469615;6175.59858609189;919.8114386550741;963.393062370702;996.3025881882711;998.2612866478898;1028.4395004722444;18911.470721134476;22922.937317459164;23027;23262.65106023411;24497.755022449903;964.6623681711485;988.6539711101057;1080.7892460970193;1094.5731164845558;1106.6845948904524;10544.789104218025;11769.823017698161;12112.273263604213;12122.515099396102;12377.752444951051;318685;319358;319852;321165;322149;56035.719821400635;56242.71878640662;56321.15518267229;56350.436495634516;56367.43632563704;1464.2312785787547;1500.6848561801926;1518.4381778741936;1519.0733652471984;1526.9847301527066;22491.550168996855;22577.548449031256;23413.648795267745;23965.334540588054;24107.879460602584;38053.42919856149;38134.809325953196;38171.618283817916;38196.80901595474;38205.61794382021;259590;275275;276322;277117;277175;36144.638553614124;36218.818905905304;36421.08947276339;36478.635213647525;36528.81735591305;43530.56469435265;43878.12243755043;44313;46073;46092.53907461017;24874.87562562248;29530.55704164482;30732.692673072983;31316.06051818449;31513.8424307877;25948.74051259463;28764.70558824816;29119.563206552342;30583.2354191147;30714.232144196572;36377.636223637426;37878.62121378751;38000.61999379971;38042.23915521729;38092.85721428361;22461.663075054214;29474.410511789214;29508.114756557337;30939.0718278452;31412;22144.66782998224;22425.551488969802;24072.638910416706;24428.55714428543;24559.38601534976 08:04:56 INFO - PID 1647 | |2;jquery.html;420.24775651583667;429.7278551923832;430.87719999800044;435.6645383055048;435.9891002724932;21853.344399668014;22698.43253918651;22776.088956441745;23434.88282558589;24441.26676199717;24335.513289734237;24462.87768561159;24675.876620616917;24685.38286542837;24692.259232223016;6078.574499785006;6224.377562243762;6226.782062627809;6229.750809967619;6239.376062393747;6135.815925522219;6173.876522469548;6180.752769889199;6232.501399887998;6496.772612958547;6831.180258369002;6876;6926.619035953021;6939.791806245821;6945.062416573748;6857.451403887702;6884.1050663413935;6902.516823822334;6920.238773734885;6921.930780692179;6726.529142959995;6760.087388202579;6771.424428923548;6784.525083244175;6818.420434263094;30105.397892042038;30869.919552815543;31150.37699246025;31397.8430107848;31712.841435792903;2298.1267118494866;2331.6502524621274;2332.918347857834;2334.031376978552;2334.4280651240383;19224.80775192258;19589;19693.507662308555;19721.506962326057;19724.50688732779;2313.4332088638225;2350.095213342858;2409.2772168349425;2411.385096800305;2412.3245491262473;152350.23824880915;162699;166388;167689;195547.0222648906;21044.78955210459;23590.7025113617;23971.880140599187;24617;24999.62500562493;42593;42647.57352426436;42666.09339925886;42719.57280427156;42752.3587146193;11154.553817847196;11172.776544469065;11261.43692815356;11265.21143519962;11266.155038372155;7527.473076884675;7808.7657370278985;8153.388495862834;8923.910760892439;8956.283497320199;9503.669486271927;11522.539098436137;11810.822837657443;12040.337781422106;12112.81830772539;4022.9540319517137;4070.2673518766314;4671.346011558385;4759.809607615656;4868.14807408702;856.1224744636797;865.5153114256037;935.2704890185666;954.407997380424;964.1130160252444;21317.720936743845;21354.252601159194;22222.333330000125;22490.100395983976;22506.774932250795;954.8710924025306;967.3663750243602;975.1516180922642;1019.5361110694616;1023.7645341571331;10757.462126893624;11116.609928865171;11349.148813838996;11405.486753096131;11473.54105835774;544373.8343924844;552640;557697;560775;562488;139136;146571.2671436636;147118;147233;153200;991.4002028772633;1042.3068659341604;1121.9046381057701;1143.1255089856268;1191.970200744971;22585.661215082117;22618.734980056382;24135.275941721775;24141.034358625457;24273.902480479297;61799.69100154471;66004.33995660175;66830.64862864984;67039.66480167568;67683.66158169374;93271;93796;93911;94255;94629;15782.842171578597;16332.836671633608;16429.91785041067;16484.34062637481;16535.421260255833;14756.778648320069;17976;18126.909365453088;18167.81832181714;18389.90805045966;14870.553883383516;14985.950983431056;15041.646251837386;15068.623284417978;15075.020123691926;12897.355132243538;13242.271675057964;13267;13491.178690876606;13511.864881351454;14480.637984050061;14522.564323070324;14621.976461647582;14750.2624868754;14799.334029968893;15904.761428578811;15965.521034368987;16041.919790400972;16044.192266353575;16124.838751612333;14611.881277214532;14644.633884152981;14833.184174870486;14927.955043147309;14992.100473971597 08:04:56 INFO - PID 1647 | |3;mootools.html;1394.8953828462866;1412;1468.9265536723165;1473.0130812355721;1477.4385733421298;11705.3562054087;11739.706507337321;12246.510139594428;12297.692557686063;12358;12054.09594280428;12067.21563098398;12202;12215.938920305409;12244.775522447773;2492.5264199802027;2522.2433270019014;2552.4639825636636;2555.6805399325085;2560.5775047117204;2112.6091673040537;2199.0544066051593;2470.258922323305;2481.789047930928;2522.092046863128;2549.3499157714755;2566.679165104362;2574.1891304239107;2579;2582.832115912469;2222.1555808792637;2524.8485090894605;2526.2926380613458;2528.1025236041214;2534.556452620784;2489.601663733812;2495;2495.413577809213;2528.380546766036;2531.898724051036;11675.241109327866;11936.045116390667;11943.761124777457;11957;11994.740552242063;5422.620416570841;6619.93380066198;6723.899141512881;6726.495512836508;6738.494612904052;4161.771102589357;4288.442502474658;4362.563743625644;4366.104948485553;4367.410399596046;1084.7450849050467;1224.5836415618644;1265.3736400481728;1286.646172302615;1287.9742405151937;16657.4169904054;16709.498715038568;16800.747988780055;16817.83182168187;16824.74762878558;4489.9775501122285;5029.5221953914315;5609.523190528769;5803.332616749128;5811.447912448395;12182.512699491921;12365.07261955357;12391.318477483825;12428.316442595564;12435.875641243472;6464.644444555501;6545.312742162005;6567.179102612174;6574.671266436659;6579.47364210862;5069.645124841226;6040.697965101727;6176.135341052256;6196.349383314777;6225.190725205694;4406.096750166273;4509.692189265076;4583.106294272651;4628.675992680547;4633.517274472137;1173.9647810565696;1194.2476239968764;1297.6172029251393;1305.7453796509778;1325.8342707161605;795.0459448661513;824.3116997307196;824.5671022713003;838.4005436113208;852.7100785732756;6672.666366681647;7023.473239507058;7341.70633174667;7417.443691723142;7552.093748750185;927.2813569483569;929.5352323838081;956.9282303827101;961.8028304197622;965.6234068713208;3988.860389886398;4657.720536767804;4749.35883655702;4750.809967601326;4871.318015477836;14921.030133041317;15243.390264389522;15347.61630959235;15421.84578154204;15513.069215847085;15072.171030593423;15259.694806103817;15301.08193508393;15366.462173824086;15375.692486150438;8404.663813447394;8451.746447606582;8459.746207613782;8461.153884611489;8467.534285614227;6310.905336419865;6455.838604034937;6984.336488033832;7364.5581265124265;7493.775186744407;11359.772804543698;13072.49666288389;13953;13961;14034.508792192224;13432.865671343161;13850.376733046836;13876.86123138796;13884.583462495737;13904.096233744775;9299.116583924517;9458.479783611692;9463.905360946303;9464.19554337889;9470.95264523673;10980.725481863017;11378.658640240807;11447.427628618369;11471.483783229607;11606.361650109326;9418.764530886783;9438.480883551196;9474.38416502925;9593.616255349707;9627.759306017406;9160.221381182408;9284.72145835626;9299.163075323253;9332.25341972627;9359.110884465965;7302.817429564303;8428.831423371375;8525.360597955054;8568.48589084632;8570.74287771368;6169.56813023084;6251.968740156452;6595.274519802721;6654.866902662016;6842.93157068423;6290.528210384148;6381.904271436025;6689.49828762835;6729.89905151433;6757.831054223684 08:04:56 INFO - PID 1647 | |4;prototype.html;384.9403342481916;401.23164140670616;406.351868769313;408.15308235411527;408.26104750401777;4829.179039563278;5607.299087614048;5994.640321580703;6296.842578935529;6527.41253287204;5186.766595503202;6314.210723659543;6346.524010699204;6374.840628984266;6387.838020180017;4487.282034874421;4591.311303304515;4599.93100103497;4618.307253911907;4670.579647831694;4613.861584152481;4650.372199753041;4663.347131401607;4665.743384113873;4674.555917187861;4577.977110114445;4578.175928332898;4579.358889755437;4588.632909367259;4614.7461889596225;4009.2382447334912;4255.702100852926;4473;4593.448786145667;4603.562661547147;3978.701597380179;3983.581723918976;3984.920301593981;3993.1414745829497;4000.4199391088127;5414.864628384283;6232.3144454110015;6278.937210627881;6300.21247344082;6301.747930082791;4869.756512174377;5013.498650134994;5469.015577196104;5545.667259964374;5560.221568980346;6159.414855588712;6267.9686601567155;6432.388923052349;6766.18805743309;6844.041834143224;3845.8461661533506;4563.406757121586;4567.469897584313;4573.382593349888;4603.723776573416;6383.553151279412;7115.964420177917;7156.320149585781;7263.12842458903;7376.852462950711;3938.03518138058;4414.624756895636;4570.154521413549;4633.768311584475;4641.3734145889875;4721.457032441244;4728.373235849328;4735.573798358096;4740.691855029481;4742.66801323904;5462.235287059815;5484.725763711798;5487.286652735119;5494.038543254915;5522.696251706115;5747.85630359236;6682.164729408824;6854.280300568368;6909.447244220451;6937.583744975217;1598.5683865356289;1603.1984007996002;1611.242715923514;1611.427943080213;1614.8708103351703;930.692871352452;1036.3729943384294;1056.3978532236706;1070.4540684250933;1072.8390741388855;6204.937950620436;6451.9354806452275;6525.934740652628;6577.309382514767;6582.76960306387;6068.969655151696;6303.149074874925;6749.3250674932115;7040.894386584206;7074.858502829915;5728.541716662657;6186.257649082139;6419.614823110628;6474.413768626717;6489.545731798728;3444.9311013780084;3450.46517789743;3749.062734316421;3764.435334699817;3786.2393986796274;7344.559326440431;7369.668364923591;7379.852402952019;7390.7413240536325;7393.963030184815;6905.240423553405;7398.18868829852;7406.222346653632;7411.147718012389;7425.7029718810645;7298.525595836254;7304.744333948286;7334.3032411920785;7345.228750981177;7370.742024029133;6400.807975760735;6595.637239951849;7115.2528984455885;7121.216666166614;7220.855582888418;6552.508561857975;6619.238787539302;6763.951587503945;7018.298170182941;7067.611281379368;5869.06095024794;6456.451201647913;6517.837054073686;6977.1627404709725;7158;4558.5897269245925;4636.953630463787;4657.534246575316;4670.346151538719;4685.109829132454;5565.189579262608;6451.3226111257645;7009.2990700929495;7135.577979817997;7152.821179470555;5790.536757059341;5973.731182096903;6548.428377189535;6798.898016529855;6869.313068693091;6543.083968244355;6547.247066587403;6668.299583606149;6837.453003759588;6885.380315771605;6719.227288861844;6729.865402692017;6744.5616034957575;6755.189377274758;6761.290064543349;6892.551984121016;6924.861502769816;6924.861502770017;6925.238223795278;6927.27263637331;6173.506119510518;6715.93284067153;6716;6727.73089076451;6734.6663401616515 08:04:56 INFO - PID 1647 | |5;yui.html;722.3498851034069;758.6093162021558;798.3294033012269;840.8018573532718;851.9308268123505;14372.784408233858;14872.628184295398;16945.322187112517;17447.38934137307;17448.040357780315;17343.82656173441;18194.81805181948;18321.175547100378;18338.633227335446;18414.44756657299;965.7247684409945;967.0667805567643;994.5574219472302;994.8408254679252;995.9153471954893;999.010979130659;1003.8042581696573;1005.9949700251489;1006.9899301007006;1008.0423597582326;930.3208657679897;1034.3535290443472;1041.182671602792;1042.0829669890486;1042.880068792091;1035.969210635415;1037.7405648587853;1038.9532471038822;1040.4173662748808;1041.3907863899628;748.1583218878761;759.1611269547182;760.4600733479236;762.268222506389;763.1872056260121;17069;17557;17569.56076098095;17656.028918409484;17864;17167.570810729707;17181.82818171815;17707.73438398425;17711;17727.645447090985;13013.414396352187;14691.63270918225;14925.552233433014;14952.70094598102;14984.850151498455;3551.4672799080085;3610.765300255501;3640.0899775056237;3642.562892452923;3647.2340808430263;124632;132069.35861282816;135373;153950;155578;13615.795763063563;13635.727285454434;13828.585142445743;13894.791578126336;13948;26580.468390632464;27039.32401689934;27231.727682722918;27243.86378068097;27252.863735681196;7498.850022999619;7662.118856331594;7689.269519395649;7757.495762775401;7757.689692412353;3878.45701601776;3961.7622942623534;3963.128111815454;3968.3849003404516;3972.880813575597;3948.565657777642;4318.762468064224;4394.802233899418;4480.798364073625;4487.865364039084;846.6697987784769;888.9199972002551;904.9592768325441;908.9500077495669;910.5902343945252;651.6057785040077;669.6450881033061;675.3078094952615;690.3959035843637;691.515938842812;11255.324680519196;11428.485718142703;12092;12210.633680989584;12627.368631568384;627.369493658879;655.3577494055852;661.5865084322298;676.1109141478939;681.437813803614;4553.385292985471;5316.282301889196;5606.635568688022;5767.36558978512;5863.355030946593;135949.35952347718;136775.6322436783;137379.31310343585;139443;140123;115344.42327788308;115781;116530.41734791272;116687.41656291834;116704;93927;94148;95611.91686789744;95662.04337956531;97131.51434242784;8883.911160888309;9920.603175872884;11193.27243729155;11486.081113510732;11542.480588373375;36525;41214.58785412108;42916.570834292506;43210.56789432065;43383.34924976065;58760.4123958772;58936;69554;73080.90378644431;91053.54473227591;17521.299148033937;17595.912020439817;18059.2776288947;18211.45365639033;18259.54351141179;18599.628007439504;19068.713969290748;19445.708314375013;19556.23993840561;19577.412677619694;17537.82462175413;17998.550036248675;18494.982775947454;18564.071796409855;18586.535336616693;17841.46475605734;17951.01269430194;18161.636767264317;18287.177077031298;18458.169382378095;17503.912480437517;17642.294308227527;18069.63860722752;18077.18652660607;18304;11075.778484430428;11725.824112638487;12506.249625022529;12594.87405125937;12642.2414655121;13377.331133443484;14491.710165796836;14555.854441455873;14714.337854796346;14862.108273503625 08:04:56 INFO - PID 1647 | __end_tp_report 08:04:56 INFO - PID 1647 | __start_cc_report 08:04:56 INFO - PID 1647 | _x_x_mozilla_cycle_collect,456 08:04:56 INFO - PID 1647 | __end_cc_report 08:04:56 INFO - PID 1647 | __startTimestamp1496675096052__endTimestamp 08:04:56 INFO - PID 1647 | 08:04:56 INFO - PID 1647 | ------- Summary: start ------- 08:04:56 INFO - PID 1647 | Number of tests: 6 08:04:56 INFO - PID 1647 | 08:04:56 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6157.22 Median:6353.00 stddev:748.65 (11.8%) stddev-sans-first:723.39 08:04:56 INFO - PID 1647 | Values: 3591.8 3611.9 3641.3 3655.8 3659.0 4571.7 6167.0 6214.5 6229.5 6237.3 6157.7 6173.2 6210.9 6218.5 6261.4 5033.8 5282.4 5396.8 5437.3 5448.7 4860.4 5564.2 5569.1 5576.9 5585.9 5373.4 5430.0 5441.9 5505.4 5508.2 4720.9 5437.7 5490.5 5500.6 5503.9 5416.7 5435.2 5435.5 5436.2 5452.1 6145.9 6188.6 6283.7 6285.1 6295.1 6514.3 6615.7 6698.6 6723.8 6734.7 6779.8 6798.9 6823.8 6839.4 6848.2 6322.9 6350.6 6352.1 6353.0 6361.4 6720.1 6801.0 6805.3 6883.3 6938.1 6245.9 6365.3 6531.7 6539.4 6583.6 6753.9 6763.7 6767.0 6775.9 6780.0 6604.5 6618.2 6636.3 6643.2 6651.9 6475.8 6525.5 6633.2 6659.3 6711.9 4567.5 5318.7 5345.2 5355.4 5360.5 4754.7 4764.2 4783.2 4805.3 4818.6 6208.3 6261.8 6301.0 6306.2 6322.0 6706.8 6765.7 6793.1 6817.3 6820.1 6307.4 6328.1 6349.1 6352.9 6382.6 5818.3 6001.5 6011.3 6012.2 6039.3 6780.2 6811.3 6883.3 6925.9 6973.1 6746.2 6824.3 6825.0 6854.4 6887.4 6683.8 6875.3 6968.6 6982.0 6984.9 5561.2 6504.3 6592.5 6653.9 6677.4 6796.7 6818.7 6821.7 6822.6 6828.9 6314.1 6342.8 6501.3 6569.1 6619.3 5728.6 6513.6 6517.3 6541.7 6560.8 5396.3 6340.0 6423.1 6451.1 6479.5 6339.1 6602.5 6606.9 6616.1 6622.5 6304.1 6310.9 6390.1 6398.5 6414.8 08:04:56 INFO - PID 1647 | 08:04:56 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:44541.69 Median:3546.60 stddev:77040.59 (2172.2%) stddev-sans-first:77186.31 08:04:56 INFO - PID 1647 | Values: 676.8 775.7 837.7 1259.7 1267.4 34223.5 36076.8 37648.0 38649.6 38746.0 34700.1 38758.6 39209.8 39236.8 39285.0 7813.6 8291.9 8526.1 8575.6 8586.9 8481.0 8544.9 8553.8 8577.6 8584.8 8524.4 8564.6 8578.4 8583.2 8586.8 8488.3 8546.5 8566.9 8580.4 8585.6 8301.5 8340.4 8342.4 8354.6 8355.9 37516.0 37587.2 37648.0 37657.8 37890.4 3503.8 3541.9 3544.1 3549.1 3554.6 30532.7 30783.7 30784.5 30826.5 30844.5 2831.5 2924.8 2942.3 3088.6 3106.9 255331.0 258684.7 290748.5 309180.0 311156.0 48478.5 48514.5 48572.0 48607.8 48616.0 51044.7 51061.5 51253.2 52248.5 54537.2 14470.3 14485.4 14621.9 14647.3 14669.2 11521.8 11642.5 11726.8 11728.4 11731.3 11286.8 13977.2 13996.7 14015.8 14151.9 5210.8 6101.6 6148.1 6173.9 6175.6 919.8 963.4 996.3 998.3 1028.4 18911.5 22922.9 23027.0 23262.7 24497.8 964.7 988.7 1080.8 1094.6 1106.7 10544.8 11769.8 12112.3 12122.5 12377.8 318685.0 319358.0 319852.0 321165.0 322149.0 56035.7 56242.7 56321.2 56350.4 56367.4 1464.2 1500.7 1518.4 1519.1 1527.0 22491.6 22577.5 23413.6 23965.3 24107.9 38053.4 38134.8 38171.6 38196.8 38205.6 259590.0 275275.0 276322.0 277117.0 277175.0 36144.6 36218.8 36421.1 36478.6 36528.8 43530.6 43878.1 44313.0 46073.0 46092.5 24874.9 29530.6 30732.7 31316.1 31513.8 25948.7 28764.7 29119.6 30583.2 30714.2 36377.6 37878.6 38000.6 38042.2 38092.9 22461.7 29474.4 29508.1 30939.1 31412.0 22144.7 22425.6 24072.6 24428.6 24559.4 08:04:56 INFO - PID 1647 | 08:04:56 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:39900.37 Median:13190.58 stddev:95005.90 (720.3%) stddev-sans-first:95226.17 08:04:56 INFO - PID 1647 | Values: 420.2 429.7 430.9 435.7 436.0 21853.3 22698.4 22776.1 23434.9 24441.3 24335.5 24462.9 24675.9 24685.4 24692.3 6078.6 6224.4 6226.8 6229.8 6239.4 6135.8 6173.9 6180.8 6232.5 6496.8 6831.2 6876.0 6926.6 6939.8 6945.1 6857.5 6884.1 6902.5 6920.2 6921.9 6726.5 6760.1 6771.4 6784.5 6818.4 30105.4 30869.9 31150.4 31397.8 31712.8 2298.1 2331.7 2332.9 2334.0 2334.4 19224.8 19589.0 19693.5 19721.5 19724.5 2313.4 2350.1 2409.3 2411.4 2412.3 152350.2 162699.0 166388.0 167689.0 195547.0 21044.8 23590.7 23971.9 24617.0 24999.6 42593.0 42647.6 42666.1 42719.6 42752.4 11154.6 11172.8 11261.4 11265.2 11266.2 7527.5 7808.8 8153.4 8923.9 8956.3 9503.7 11522.5 11810.8 12040.3 12112.8 4023.0 4070.3 4671.3 4759.8 4868.1 856.1 865.5 935.3 954.4 964.1 21317.7 21354.3 22222.3 22490.1 22506.8 954.9 967.4 975.2 1019.5 1023.8 10757.5 11116.6 11349.1 11405.5 11473.5 544373.8 552640.0 557697.0 560775.0 562488.0 139136.0 146571.3 147118.0 147233.0 153200.0 991.4 1042.3 1121.9 1143.1 1192.0 22585.7 22618.7 24135.3 24141.0 24273.9 61799.7 66004.3 66830.6 67039.7 67683.7 93271.0 93796.0 93911.0 94255.0 94629.0 15782.8 16332.8 16429.9 16484.3 16535.4 14756.8 17976.0 18126.9 18167.8 18389.9 14870.6 14986.0 15041.6 15068.6 15075.0 12897.4 13242.3 13267.0 13491.2 13511.9 14480.6 14522.6 14622.0 14750.3 14799.3 15904.8 15965.5 16041.9 16044.2 16124.8 14611.9 14644.6 14833.2 14928.0 14992.1 08:04:56 INFO - PID 1647 | 08:04:56 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:7286.34 Median:4075.32 stddev:4609.77 (113.1%) stddev-sans-first:4601.44 08:04:56 INFO - PID 1647 | Values: 1394.9 1412.0 1468.9 1473.0 1477.4 11705.4 11739.7 12246.5 12297.7 12358.0 12054.1 12067.2 12202.0 12215.9 12244.8 2492.5 2522.2 2552.5 2555.7 2560.6 2112.6 2199.1 2470.3 2481.8 2522.1 2549.3 2566.7 2574.2 2579.0 2582.8 2222.2 2524.8 2526.3 2528.1 2534.6 2489.6 2495.0 2495.4 2528.4 2531.9 11675.2 11936.0 11943.8 11957.0 11994.7 5422.6 6619.9 6723.9 6726.5 6738.5 4161.8 4288.4 4362.6 4366.1 4367.4 1084.7 1224.6 1265.4 1286.6 1288.0 16657.4 16709.5 16800.7 16817.8 16824.7 4490.0 5029.5 5609.5 5803.3 5811.4 12182.5 12365.1 12391.3 12428.3 12435.9 6464.6 6545.3 6567.2 6574.7 6579.5 5069.6 6040.7 6176.1 6196.3 6225.2 4406.1 4509.7 4583.1 4628.7 4633.5 1174.0 1194.2 1297.6 1305.7 1325.8 795.0 824.3 824.6 838.4 852.7 6672.7 7023.5 7341.7 7417.4 7552.1 927.3 929.5 956.9 961.8 965.6 3988.9 4657.7 4749.4 4750.8 4871.3 14921.0 15243.4 15347.6 15421.8 15513.1 15072.2 15259.7 15301.1 15366.5 15375.7 8404.7 8451.7 8459.7 8461.2 8467.5 6310.9 6455.8 6984.3 7364.6 7493.8 11359.8 13072.5 13953.0 13961.0 14034.5 13432.9 13850.4 13876.9 13884.6 13904.1 9299.1 9458.5 9463.9 9464.2 9471.0 10980.7 11378.7 11447.4 11471.5 11606.4 9418.8 9438.5 9474.4 9593.6 9627.8 9160.2 9284.7 9299.2 9332.3 9359.1 7302.8 8428.8 8525.4 8568.5 8570.7 6169.6 6252.0 6595.3 6654.9 6842.9 6290.5 6381.9 6689.5 6729.9 6757.8 08:04:56 INFO - PID 1647 | 08:04:56 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5496.94 Median:6250.14 stddev:1741.25 (27.9%) stddev-sans-first:1703.34 08:04:56 INFO - PID 1647 | Values: 384.9 401.2 406.4 408.2 408.3 4829.2 5607.3 5994.6 6296.8 6527.4 5186.8 6314.2 6346.5 6374.8 6387.8 4487.3 4591.3 4599.9 4618.3 4670.6 4613.9 4650.4 4663.3 4665.7 4674.6 4578.0 4578.2 4579.4 4588.6 4614.7 4009.2 4255.7 4473.0 4593.4 4603.6 3978.7 3983.6 3984.9 3993.1 4000.4 5414.9 6232.3 6278.9 6300.2 6301.7 4869.8 5013.5 5469.0 5545.7 5560.2 6159.4 6268.0 6432.4 6766.2 6844.0 3845.8 4563.4 4567.5 4573.4 4603.7 6383.6 7116.0 7156.3 7263.1 7376.9 3938.0 4414.6 4570.2 4633.8 4641.4 4721.5 4728.4 4735.6 4740.7 4742.7 5462.2 5484.7 5487.3 5494.0 5522.7 5747.9 6682.2 6854.3 6909.4 6937.6 1598.6 1603.2 1611.2 1611.4 1614.9 930.7 1036.4 1056.4 1070.5 1072.8 6204.9 6451.9 6525.9 6577.3 6582.8 6069.0 6303.1 6749.3 7040.9 7074.9 5728.5 6186.3 6419.6 6474.4 6489.5 3444.9 3450.5 3749.1 3764.4 3786.2 7344.6 7369.7 7379.9 7390.7 7394.0 6905.2 7398.2 7406.2 7411.1 7425.7 7298.5 7304.7 7334.3 7345.2 7370.7 6400.8 6595.6 7115.3 7121.2 7220.9 6552.5 6619.2 6764.0 7018.3 7067.6 5869.1 6456.5 6517.8 6977.2 7158.0 4558.6 4637.0 4657.5 4670.3 4685.1 5565.2 6451.3 7009.3 7135.6 7152.8 5790.5 5973.7 6548.4 6798.9 6869.3 6543.1 6547.2 6668.3 6837.5 6885.4 6719.2 6729.9 6744.6 6755.2 6761.3 6892.6 6924.9 6924.9 6925.2 6927.3 6173.5 6715.9 6716.0 6727.7 6734.7 08:04:56 INFO - PID 1647 | 08:04:56 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:24987.88 Median:18376.54 stddev:37577.98 (204.5%) stddev-sans-first:37639.22 08:04:56 INFO - PID 1647 | Values: 722.3 758.6 798.3 840.8 851.9 14372.8 14872.6 16945.3 17447.4 17448.0 17343.8 18194.8 18321.2 18338.6 18414.4 965.7 967.1 994.6 994.8 995.9 999.0 1003.8 1006.0 1007.0 1008.0 930.3 1034.4 1041.2 1042.1 1042.9 1036.0 1037.7 1039.0 1040.4 1041.4 748.2 759.2 760.5 762.3 763.2 17069.0 17557.0 17569.6 17656.0 17864.0 17167.6 17181.8 17707.7 17711.0 17727.6 13013.4 14691.6 14925.6 14952.7 14984.9 3551.5 3610.8 3640.1 3642.6 3647.2 124632.0 132069.4 135373.0 153950.0 155578.0 13615.8 13635.7 13828.6 13894.8 13948.0 26580.5 27039.3 27231.7 27243.9 27252.9 7498.9 7662.1 7689.3 7757.5 7757.7 3878.5 3961.8 3963.1 3968.4 3972.9 3948.6 4318.8 4394.8 4480.8 4487.9 846.7 888.9 905.0 909.0 910.6 651.6 669.6 675.3 690.4 691.5 11255.3 11428.5 12092.0 12210.6 12627.4 627.4 655.4 661.6 676.1 681.4 4553.4 5316.3 5606.6 5767.4 5863.4 135949.4 136775.6 137379.3 139443.0 140123.0 115344.4 115781.0 116530.4 116687.4 116704.0 93927.0 94148.0 95611.9 95662.0 97131.5 8883.9 9920.6 11193.3 11486.1 11542.5 36525.0 41214.6 42916.6 43210.6 43383.3 58760.4 58936.0 69554.0 73080.9 91053.5 17521.3 17595.9 18059.3 18211.5 18259.5 18599.6 19068.7 19445.7 19556.2 19577.4 17537.8 17998.6 18495.0 18564.1 18586.5 17841.5 17951.0 18161.6 18287.2 18458.2 17503.9 17642.3 18069.6 18077.2 18304.0 11075.8 11725.8 12506.2 12594.9 12642.2 13377.3 14491.7 14555.9 14714.3 14862.1 08:04:56 INFO - PID 1647 | -------- Summary: end -------- 08:04:56 INFO - PID 1647 | 08:04:56 INFO - TEST-INFO | 1647: exit 0 08:04:56 INFO - TEST-OK | dromaeo_css | took 1143517ms 08:04:56 INFO - TEST-START | kraken 08:04:56 INFO - operating with platform_type : mac_ 08:04:56 INFO - Initialising browser for kraken test... 08:04:56 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpWBO81A/profile 08:04:56 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 08:05:30 INFO - TEST-INFO | 1656: killed by SIGTERM 08:05:30 INFO - Browser initialized. 08:05:30 INFO - Running cycle 1/1 for kraken test... 08:05:30 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/tmpWBO81A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:05:32 INFO - PID 1660 | 2017-06-05 08:05:32.472 plugin-container[1661:11363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a47, name = 'com.apple.tsm.portname' 08:05:32 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:32 INFO - PID 1660 | 2017-06-05 08:05:32.473 plugin-container[1661:11363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 08:05:32 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:32 INFO - PID 1660 | 2017-06-05 08:05:32.473 plugin-container[1661:11363] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:05:33 INFO - PID 1660 | 2017-06-05 08:05:33.249 plugin-container[1662:11409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 08:05:33 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:33 INFO - PID 1660 | 2017-06-05 08:05:33.250 plugin-container[1662:11409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 08:05:33 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:05:33 INFO - PID 1660 | 2017-06-05 08:05:33.250 plugin-container[1662:11409] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:05:42 INFO - PID 1660 | RSS: Main: 260042752 08:05:42 INFO - PID 1660 | 08:05:45 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 08:05:46 INFO - PID 1660 | RSS: Main: 270663680 08:05:46 INFO - PID 1660 | 08:05:48 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 08:05:49 INFO - PID 1660 | RSS: Main: 281317376 08:05:49 INFO - PID 1660 | 08:05:52 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 08:05:53 INFO - PID 1660 | RSS: Main: 283217920 08:05:53 INFO - PID 1660 | 08:05:55 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 08:05:56 INFO - PID 1660 | RSS: Main: 284151808 08:05:56 INFO - PID 1660 | 08:05:58 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:05:58 INFO - PID 1660 | RSS: Main: 286617600 08:05:58 INFO - PID 1660 | 08:06:01 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 08:06:02 INFO - PID 1660 | RSS: Main: 291717120 08:06:02 INFO - PID 1660 | 08:06:05 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 08:06:06 INFO - PID 1660 | RSS: Main: 294506496 08:06:06 INFO - PID 1660 | 08:06:08 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 08:06:09 INFO - PID 1660 | RSS: Main: 294301696 08:06:09 INFO - PID 1660 | 08:06:11 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:06:11 INFO - PID 1660 | RSS: Main: 293883904 08:06:11 INFO - PID 1660 | 08:06:13 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:06:14 INFO - PID 1660 | RSS: Main: 293740544 08:06:14 INFO - PID 1660 | 08:06:16 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:06:17 INFO - PID 1660 | RSS: Main: 293388288 08:06:17 INFO - PID 1660 | 08:06:20 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:06:20 INFO - PID 1660 | RSS: Main: 293199872 08:06:20 INFO - PID 1660 | 08:06:23 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:06:24 INFO - PID 1660 | RSS: Main: 293117952 08:06:24 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 08:06:26 INFO - PID 1660 | RSS: Main: 295276544 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | __start_tp_report 08:06:26 INFO - PID 1660 | _x_x_mozilla_page_load 08:06:26 INFO - PID 1660 | _x_x_mozilla_page_load_details 08:06:26 INFO - PID 1660 | |i|pagename|runs| 08:06:26 INFO - PID 1660 | |0;ai-astar;106;114;113;113;113;116;113;117;115;114 08:06:26 INFO - PID 1660 | |1;audio-beat-detection;178;143;110;151;113;118;142;114;143;147 08:06:26 INFO - PID 1660 | |2;audio-dft;201;198;202;203;205;247;299;201;196;197 08:06:26 INFO - PID 1660 | |3;audio-fft;77;61;85;97;50;83;50;60;85;79 08:06:26 INFO - PID 1660 | |4;audio-oscillator;80;68;83;71;68;68;84;69;86;110 08:06:26 INFO - PID 1660 | |5;imaging-gaussian-blur;90;90;91;91;91;91;93;91;90;90 08:06:26 INFO - PID 1660 | |6;imaging-darkroom;98;97;98;98;151;151;100;98;98;96 08:06:26 INFO - PID 1660 | |7;imaging-desaturate;91;94;92;102;135;92;93;100;94;94 08:06:26 INFO - PID 1660 | |8;json-parse-financial;52;51;53;50;51;53;52;51;52;53 08:06:26 INFO - PID 1660 | |9;json-stringify-tinderbox;59;56;62;57;58;60;58;59;62;56 08:06:26 INFO - PID 1660 | |10;stanford-crypto-aes;96;111;90;85;89;88;86;89;108;90 08:06:26 INFO - PID 1660 | |11;stanford-crypto-ccm;156;147;160;148;147;157;135;140;152;145 08:06:26 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;185;183;182;184;197;233;229;184;183;223 08:06:26 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;66;70;69;68;69;70;66;69;68;68 08:06:26 INFO - PID 1660 | __end_tp_report 08:06:26 INFO - PID 1660 | __start_cc_report 08:06:26 INFO - PID 1660 | _x_x_mozilla_cycle_collect,1187 08:06:26 INFO - PID 1660 | __end_cc_report 08:06:26 INFO - PID 1660 | __startTimestamp1496675186502__endTimestamp 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | ------- Summary: start ------- 08:06:26 INFO - PID 1660 | Number of tests: 14 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:2.95 (2.6%) stddev-sans-first:1.48 08:06:26 INFO - PID 1660 | Values: 106.0 114.0 113.0 113.0 113.0 116.0 113.0 117.0 115.0 114.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:135.90 Median:143.00 stddev:21.75 (15.2%) stddev-sans-first:16.91 08:06:26 INFO - PID 1660 | Values: 178.0 143.0 110.0 151.0 113.0 118.0 142.0 114.0 143.0 147.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:214.90 Median:202.50 stddev:33.10 (16.3%) stddev-sans-first:34.72 08:06:26 INFO - PID 1660 | Values: 201.0 198.0 202.0 203.0 205.0 247.0 299.0 201.0 196.0 197.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:72.70 Median:81.00 stddev:16.28 (20.1%) stddev-sans-first:17.20 08:06:26 INFO - PID 1660 | Values: 77.0 61.0 85.0 97.0 50.0 83.0 50.0 60.0 85.0 79.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:75.50 stddev:13.24 (17.5%) stddev-sans-first:14.04 08:06:26 INFO - PID 1660 | Values: 80.0 68.0 83.0 71.0 68.0 68.0 84.0 69.0 86.0 110.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:91.00 stddev:0.92 (1.0%) stddev-sans-first:0.93 08:06:26 INFO - PID 1660 | Values: 90.0 90.0 91.0 91.0 91.0 91.0 93.0 91.0 90.0 90.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:108.50 Median:98.00 stddev:22.42 (22.9%) stddev-sans-first:23.46 08:06:26 INFO - PID 1660 | Values: 98.0 97.0 98.0 98.0 151.0 151.0 100.0 98.0 98.0 96.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:98.70 Median:92.50 stddev:13.24 (14.3%) stddev-sans-first:13.75 08:06:26 INFO - PID 1660 | Values: 91.0 94.0 92.0 102.0 135.0 92.0 93.0 100.0 94.0 94.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:1.09 08:06:26 INFO - PID 1660 | Values: 52.0 51.0 53.0 50.0 51.0 53.0 52.0 51.0 52.0 53.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:2.16 (3.7%) stddev-sans-first:2.29 08:06:26 INFO - PID 1660 | Values: 59.0 56.0 62.0 57.0 58.0 60.0 58.0 59.0 62.0 56.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:93.20 Median:89.00 stddev:9.10 (10.2%) stddev-sans-first:9.60 08:06:26 INFO - PID 1660 | Values: 96.0 111.0 90.0 85.0 89.0 88.0 86.0 89.0 108.0 90.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:150.00 stddev:7.78 (5.2%) stddev-sans-first:7.79 08:06:26 INFO - PID 1660 | Values: 156.0 147.0 160.0 148.0 147.0 157.0 135.0 140.0 152.0 145.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.30 Median:191.00 stddev:21.29 (11.1%) stddev-sans-first:22.03 08:06:26 INFO - PID 1660 | Values: 185.0 183.0 182.0 184.0 197.0 233.0 229.0 184.0 183.0 223.0 08:06:26 INFO - PID 1660 | 08:06:26 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.42 (2.1%) stddev-sans-first:1.24 08:06:26 INFO - PID 1660 | Values: 66.0 70.0 69.0 68.0 69.0 70.0 66.0 69.0 68.0 68.0 08:06:26 INFO - PID 1660 | -------- Summary: end -------- 08:06:26 INFO - PID 1660 | 08:06:27 INFO - TEST-INFO | 1660: exit 0 08:06:27 INFO - TEST-OK | kraken | took 90620ms 08:06:27 INFO - SUITE-END | took 1234s 08:06:27 INFO - Completed test suite (00:20:34) 08:06:27 INFO - javascript benchmark 08:06:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8275.108780888038, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3591.820408979551, 3611.8735844245457, 3641.326354624396, 3655.798931058791, 3658.9634103658955, 4571.725696458215, 6166.982447896103, 6214.471769899564, 6229.501639868811, 6237.282712488065, 6157.692115394235, 6173.228346456693, 6210.875782484348, 6218.471429928451, 6261.436470717635, 5033.84898453047, 5282.3925248596515, 5396.811111611094, 5437.32033495813, 5448.727563621803, 4860.441049279325, 5564.248826408444, 5569.053260945629, 5576.888462230753, 5585.944140558583, 5373.38206106295, 5429.972850135763, 5441.945580544223, 5505.449455054482, 5508.173773933902, 4720.929186062212, 5437.673739575638, 5490.505854473076, 5500.614956953015, 5503.9174412383845, 5416.702081385522, 5435.211894275348, 5435.537979271727, 5436.184572314145, 5452.100403433429, 6145.863015342176, 6188.566800323979, 6283.654399008053, 6285.120083188357, 6295.087212354183, 6514.283428822826, 6615.669216539154, 6698.631575263358, 6723.798286051427, 6734.730610775563, 6779.796606101825, 6798.898016529756, 6823.795286141423, 6839.350261725128, 6848.212455567574, 6322.9051564226575, 6350.5554611177695, 6352.14246076783, 6353.0, 6361.3638636136475, 6720.126383570105, 6801.0, 6805.251422343538, 6883.311668833177, 6938.063361446241, 6245.875082498324, 6365.331640177715, 6531.738730450728, 6539.444147247537, 6583.604983700993, 6753.8649227015185, 6763.72945082201, 6766.966165169143, 6775.898361524581, 6779.966100169566, 6604.471642268607, 6618.205815302194, 6636.336366363325, 6643.2360278568585, 6651.900221496681, 6475.805725828232, 6525.543211975116, 6633.2371777246235, 6659.300773418819, 6711.899321510181, 4567.5432456754725, 5318.680879147263, 5345.1714984177515, 5355.437679043722, 5360.463953604686, 4754.738489383117, 4764.166270902579, 4783.1868582341085, 4805.27920811881, 4818.638602104857, 6208.348123447064, 6261.843453913689, 6301.0, 6306.24325080984, 6322.0, 6706.798796036127, 6765.661716914135, 6793.082933803972, 6817.318268173242, 6820.079289295981, 6307.432331090132, 6328.145700330489, 6349.111124442582, 6352.936470635327, 6382.585131966407, 5818.330891947396, 6001.549883758736, 6011.3086994995265, 6012.248468941382, 6039.335673075958, 6780.1524809398825, 6811.250762416229, 6883.3116688330765, 6925.861482770417, 6973.058637084132, 6746.156730408699, 6824.317568243136, 6824.965875170592, 6854.417374523221, 6887.380135787803, 6683.7660681875905, 6875.3124687532845, 6968.616726080114, 6981.96509017472, 6984.895226571707, 5561.249231353715, 6504.252011018793, 6592.505562082959, 6653.933460665331, 6677.399034086956, 6796.694148763218, 6818.727250909908, 6821.727130914708, 6822.590644561342, 6828.863422731418, 6314.084457753692, 6342.841428964312, 6501.317361676955, 6569.146011018537, 6619.3380661933425, 5728.598998070095, 6513.576617519846, 6517.283098859217, 6541.672916354259, 6560.770373036921, 5396.298481197419, 6339.9683001586545, 6423.132877061724, 6451.064595633513, 6479.514036447191, 6339.080833279057, 6602.471802255904, 6606.867862642816, 6616.106825578485, 6622.503312251504, 6304.148939893239, 6310.936890631035, 6390.073439351361, 6398.456131228898, 6414.839629009312], "value": 6108.027554830475, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.8240257533041, 775.7207405334083, 837.6733074101102, 1259.7039695671515, 1267.4169881854345, 34223.48664770024, 36076.819615901884, 37648.0, 38649.61350386495, 38746.0, 34700.13249668753, 38758.61241387585, 39209.80395098028, 39236.80381598095, 39285.0, 7813.6483858226375, 8291.917080829175, 8526.104759000278, 8575.571221438902, 8586.87119693202, 8480.957595212047, 8544.871826922601, 8553.786155346104, 8577.614007369652, 8584.785380365478, 8524.403291769579, 8564.614592343329, 8578.4424012439, 8583.184597463262, 8586.828263434758, 8488.320934325238, 8546.487210767344, 8566.914330856705, 8580.442271252348, 8585.570721463902, 8301.543415112166, 8340.37447191457, 8342.37432192588, 8354.624041918129, 8355.874661880016, 37516.0, 37587.24825503475, 37648.0, 37657.81171094155, 37890.43164352537, 3503.7911920387423, 3541.8760343388117, 3544.113971507123, 3549.0772399176144, 3554.608993010767, 30532.69467305343, 30783.692163078307, 30784.538231926537, 30826.53760193599, 30844.53733194004, 2831.462022215793, 2924.809887357315, 2942.279141610319, 3088.629364476255, 3106.8601912913746, 255331.0, 258684.70657646778, 290748.54625726945, 309180.0, 311156.0, 48478.5152148474, 48514.5148548517, 48572.0, 48607.78480538015, 48616.02767944621, 51044.74477627588, 51061.48938510568, 51253.231201532006, 52248.47751522436, 54537.181942270894, 14470.27648617565, 14485.420583176765, 14621.92689036548, 14647.340869660678, 14669.193194374411, 11521.769564608661, 11642.53429862796, 11726.824097638542, 11728.354940478184, 11731.296122232694, 11286.774264514665, 13977.161370317814, 13996.650083747783, 14015.789763153562, 14151.93860460449, 5210.817621383233, 6101.572889897753, 6148.1392605035335, 6173.876522469615, 6175.59858609189, 919.8114386550741, 963.393062370702, 996.3025881882711, 998.2612866478898, 1028.4395004722444, 18911.470721134476, 22922.937317459164, 23027.0, 23262.65106023411, 24497.755022449903, 964.6623681711485, 988.6539711101057, 1080.7892460970193, 1094.5731164845558, 1106.6845948904524, 10544.789104218025, 11769.823017698161, 12112.273263604213, 12122.515099396102, 12377.752444951051, 318685.0, 319358.0, 319852.0, 321165.0, 322149.0, 56035.719821400635, 56242.71878640662, 56321.15518267229, 56350.436495634516, 56367.43632563704, 1464.2312785787547, 1500.6848561801926, 1518.4381778741936, 1519.0733652471984, 1526.9847301527066, 22491.550168996855, 22577.548449031256, 23413.648795267745, 23965.334540588054, 24107.879460602584, 38053.42919856149, 38134.809325953196, 38171.618283817916, 38196.80901595474, 38205.61794382021, 259590.0, 275275.0, 276322.0, 277117.0, 277175.0, 36144.638553614124, 36218.818905905304, 36421.08947276339, 36478.635213647525, 36528.81735591305, 43530.56469435265, 43878.12243755043, 44313.0, 46073.0, 46092.53907461017, 24874.87562562248, 29530.55704164482, 30732.692673072983, 31316.06051818449, 31513.8424307877, 25948.74051259463, 28764.70558824816, 29119.563206552342, 30583.2354191147, 30714.232144196572, 36377.636223637426, 37878.62121378751, 38000.61999379971, 38042.23915521729, 38092.85721428361, 22461.663075054214, 29474.410511789214, 29508.114756557337, 30939.0718278452, 31412.0, 22144.66782998224, 22425.551488969802, 24072.638910416706, 24428.55714428543, 24559.38601534976], "value": 17404.67278392056, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.24775651583667, 429.7278551923832, 430.87719999800044, 435.6645383055048, 435.9891002724932, 21853.344399668014, 22698.43253918651, 22776.088956441745, 23434.88282558589, 24441.26676199717, 24335.513289734237, 24462.87768561159, 24675.876620616917, 24685.38286542837, 24692.259232223016, 6078.574499785006, 6224.377562243762, 6226.782062627809, 6229.750809967619, 6239.376062393747, 6135.815925522219, 6173.876522469548, 6180.752769889199, 6232.501399887998, 6496.772612958547, 6831.180258369002, 6876.0, 6926.619035953021, 6939.791806245821, 6945.062416573748, 6857.451403887702, 6884.1050663413935, 6902.516823822334, 6920.238773734885, 6921.930780692179, 6726.529142959995, 6760.087388202579, 6771.424428923548, 6784.525083244175, 6818.420434263094, 30105.397892042038, 30869.919552815543, 31150.37699246025, 31397.8430107848, 31712.841435792903, 2298.1267118494866, 2331.6502524621274, 2332.918347857834, 2334.031376978552, 2334.4280651240383, 19224.80775192258, 19589.0, 19693.507662308555, 19721.506962326057, 19724.50688732779, 2313.4332088638225, 2350.095213342858, 2409.2772168349425, 2411.385096800305, 2412.3245491262473, 152350.23824880915, 162699.0, 166388.0, 167689.0, 195547.0222648906, 21044.78955210459, 23590.7025113617, 23971.880140599187, 24617.0, 24999.62500562493, 42593.0, 42647.57352426436, 42666.09339925886, 42719.57280427156, 42752.3587146193, 11154.553817847196, 11172.776544469065, 11261.43692815356, 11265.21143519962, 11266.155038372155, 7527.473076884675, 7808.7657370278985, 8153.388495862834, 8923.910760892439, 8956.283497320199, 9503.669486271927, 11522.539098436137, 11810.822837657443, 12040.337781422106, 12112.81830772539, 4022.9540319517137, 4070.2673518766314, 4671.346011558385, 4759.809607615656, 4868.14807408702, 856.1224744636797, 865.5153114256037, 935.2704890185666, 954.407997380424, 964.1130160252444, 21317.720936743845, 21354.252601159194, 22222.333330000125, 22490.100395983976, 22506.774932250795, 954.8710924025306, 967.3663750243602, 975.1516180922642, 1019.5361110694616, 1023.7645341571331, 10757.462126893624, 11116.609928865171, 11349.148813838996, 11405.486753096131, 11473.54105835774, 544373.8343924844, 552640.0, 557697.0, 560775.0, 562488.0, 139136.0, 146571.2671436636, 147118.0, 147233.0, 153200.0, 991.4002028772633, 1042.3068659341604, 1121.9046381057701, 1143.1255089856268, 1191.970200744971, 22585.661215082117, 22618.734980056382, 24135.275941721775, 24141.034358625457, 24273.902480479297, 61799.69100154471, 66004.33995660175, 66830.64862864984, 67039.66480167568, 67683.66158169374, 93271.0, 93796.0, 93911.0, 94255.0, 94629.0, 15782.842171578597, 16332.836671633608, 16429.91785041067, 16484.34062637481, 16535.421260255833, 14756.778648320069, 17976.0, 18126.909365453088, 18167.81832181714, 18389.90805045966, 14870.553883383516, 14985.950983431056, 15041.646251837386, 15068.623284417978, 15075.020123691926, 12897.355132243538, 13242.271675057964, 13267.0, 13491.178690876606, 13511.864881351454, 14480.637984050061, 14522.564323070324, 14621.976461647582, 14750.2624868754, 14799.334029968893, 15904.761428578811, 15965.521034368987, 16041.919790400972, 16044.192266353575, 16124.838751612333, 14611.881277214532, 14644.633884152981, 14833.184174870486, 14927.955043147309, 14992.100473971597], "value": 12653.000210578515, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.8953828462866, 1412.0, 1468.9265536723165, 1473.0130812355721, 1477.4385733421298, 11705.3562054087, 11739.706507337321, 12246.510139594428, 12297.692557686063, 12358.0, 12054.09594280428, 12067.21563098398, 12202.0, 12215.938920305409, 12244.775522447773, 2492.5264199802027, 2522.2433270019014, 2552.4639825636636, 2555.6805399325085, 2560.5775047117204, 2112.6091673040537, 2199.0544066051593, 2470.258922323305, 2481.789047930928, 2522.092046863128, 2549.3499157714755, 2566.679165104362, 2574.1891304239107, 2579.0, 2582.832115912469, 2222.1555808792637, 2524.8485090894605, 2526.2926380613458, 2528.1025236041214, 2534.556452620784, 2489.601663733812, 2495.0, 2495.413577809213, 2528.380546766036, 2531.898724051036, 11675.241109327866, 11936.045116390667, 11943.761124777457, 11957.0, 11994.740552242063, 5422.620416570841, 6619.93380066198, 6723.899141512881, 6726.495512836508, 6738.494612904052, 4161.771102589357, 4288.442502474658, 4362.563743625644, 4366.104948485553, 4367.410399596046, 1084.7450849050467, 1224.5836415618644, 1265.3736400481728, 1286.646172302615, 1287.9742405151937, 16657.4169904054, 16709.498715038568, 16800.747988780055, 16817.83182168187, 16824.74762878558, 4489.9775501122285, 5029.5221953914315, 5609.523190528769, 5803.332616749128, 5811.447912448395, 12182.512699491921, 12365.07261955357, 12391.318477483825, 12428.316442595564, 12435.875641243472, 6464.644444555501, 6545.312742162005, 6567.179102612174, 6574.671266436659, 6579.47364210862, 5069.645124841226, 6040.697965101727, 6176.135341052256, 6196.349383314777, 6225.190725205694, 4406.096750166273, 4509.692189265076, 4583.106294272651, 4628.675992680547, 4633.517274472137, 1173.9647810565696, 1194.2476239968764, 1297.6172029251393, 1305.7453796509778, 1325.8342707161605, 795.0459448661513, 824.3116997307196, 824.5671022713003, 838.4005436113208, 852.7100785732756, 6672.666366681647, 7023.473239507058, 7341.70633174667, 7417.443691723142, 7552.093748750185, 927.2813569483569, 929.5352323838081, 956.9282303827101, 961.8028304197622, 965.6234068713208, 3988.860389886398, 4657.720536767804, 4749.35883655702, 4750.809967601326, 4871.318015477836, 14921.030133041317, 15243.390264389522, 15347.61630959235, 15421.84578154204, 15513.069215847085, 15072.171030593423, 15259.694806103817, 15301.08193508393, 15366.462173824086, 15375.692486150438, 8404.663813447394, 8451.746447606582, 8459.746207613782, 8461.153884611489, 8467.534285614227, 6310.905336419865, 6455.838604034937, 6984.336488033832, 7364.5581265124265, 7493.775186744407, 11359.772804543698, 13072.49666288389, 13953.0, 13961.0, 14034.508792192224, 13432.865671343161, 13850.376733046836, 13876.86123138796, 13884.583462495737, 13904.096233744775, 9299.116583924517, 9458.479783611692, 9463.905360946303, 9464.19554337889, 9470.95264523673, 10980.725481863017, 11378.658640240807, 11447.427628618369, 11471.483783229607, 11606.361650109326, 9418.764530886783, 9438.480883551196, 9474.38416502925, 9593.616255349707, 9627.759306017406, 9160.221381182408, 9284.72145835626, 9299.163075323253, 9332.25341972627, 9359.110884465965, 7302.817429564303, 8428.831423371375, 8525.360597955054, 8568.48589084632, 8570.74287771368, 6169.56813023084, 6251.968740156452, 6595.274519802721, 6654.866902662016, 6842.93157068423, 6290.528210384148, 6381.904271436025, 6689.49828762835, 6729.89905151433, 6757.831054223684], "value": 5497.6891687049965, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.9403342481916, 401.23164140670616, 406.351868769313, 408.15308235411527, 408.26104750401777, 4829.179039563278, 5607.299087614048, 5994.640321580703, 6296.842578935529, 6527.41253287204, 5186.766595503202, 6314.210723659543, 6346.524010699204, 6374.840628984266, 6387.838020180017, 4487.282034874421, 4591.311303304515, 4599.93100103497, 4618.307253911907, 4670.579647831694, 4613.861584152481, 4650.372199753041, 4663.347131401607, 4665.743384113873, 4674.555917187861, 4577.977110114445, 4578.175928332898, 4579.358889755437, 4588.632909367259, 4614.7461889596225, 4009.2382447334912, 4255.702100852926, 4473.0, 4593.448786145667, 4603.562661547147, 3978.701597380179, 3983.581723918976, 3984.920301593981, 3993.1414745829497, 4000.4199391088127, 5414.864628384283, 6232.3144454110015, 6278.937210627881, 6300.21247344082, 6301.747930082791, 4869.756512174377, 5013.498650134994, 5469.015577196104, 5545.667259964374, 5560.221568980346, 6159.414855588712, 6267.9686601567155, 6432.388923052349, 6766.18805743309, 6844.041834143224, 3845.8461661533506, 4563.406757121586, 4567.469897584313, 4573.382593349888, 4603.723776573416, 6383.553151279412, 7115.964420177917, 7156.320149585781, 7263.12842458903, 7376.852462950711, 3938.03518138058, 4414.624756895636, 4570.154521413549, 4633.768311584475, 4641.3734145889875, 4721.457032441244, 4728.373235849328, 4735.573798358096, 4740.691855029481, 4742.66801323904, 5462.235287059815, 5484.725763711798, 5487.286652735119, 5494.038543254915, 5522.696251706115, 5747.85630359236, 6682.164729408824, 6854.280300568368, 6909.447244220451, 6937.583744975217, 1598.5683865356289, 1603.1984007996002, 1611.242715923514, 1611.427943080213, 1614.8708103351703, 930.692871352452, 1036.3729943384294, 1056.3978532236706, 1070.4540684250933, 1072.8390741388855, 6204.937950620436, 6451.9354806452275, 6525.934740652628, 6577.309382514767, 6582.76960306387, 6068.969655151696, 6303.149074874925, 6749.3250674932115, 7040.894386584206, 7074.858502829915, 5728.541716662657, 6186.257649082139, 6419.614823110628, 6474.413768626717, 6489.545731798728, 3444.9311013780084, 3450.46517789743, 3749.062734316421, 3764.435334699817, 3786.2393986796274, 7344.559326440431, 7369.668364923591, 7379.852402952019, 7390.7413240536325, 7393.963030184815, 6905.240423553405, 7398.18868829852, 7406.222346653632, 7411.147718012389, 7425.7029718810645, 7298.525595836254, 7304.744333948286, 7334.3032411920785, 7345.228750981177, 7370.742024029133, 6400.807975760735, 6595.637239951849, 7115.2528984455885, 7121.216666166614, 7220.855582888418, 6552.508561857975, 6619.238787539302, 6763.951587503945, 7018.298170182941, 7067.611281379368, 5869.06095024794, 6456.451201647913, 6517.837054073686, 6977.1627404709725, 7158.0, 4558.5897269245925, 4636.953630463787, 4657.534246575316, 4670.346151538719, 4685.109829132454, 5565.189579262608, 6451.3226111257645, 7009.2990700929495, 7135.577979817997, 7152.821179470555, 5790.536757059341, 5973.731182096903, 6548.428377189535, 6798.898016529855, 6869.313068693091, 6543.083968244355, 6547.247066587403, 6668.299583606149, 6837.453003759588, 6885.380315771605, 6719.227288861844, 6729.865402692017, 6744.5616034957575, 6755.189377274758, 6761.290064543349, 6892.551984121016, 6924.861502769816, 6924.861502770017, 6925.238223795278, 6927.27263637331, 6173.506119510518, 6715.93284067153, 6716.0, 6727.73089076451, 6734.6663401616515], "value": 4949.49749639716, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.3498851034069, 758.6093162021558, 798.3294033012269, 840.8018573532718, 851.9308268123505, 14372.784408233858, 14872.628184295398, 16945.322187112517, 17447.38934137307, 17448.040357780315, 17343.82656173441, 18194.81805181948, 18321.175547100378, 18338.633227335446, 18414.44756657299, 965.7247684409945, 967.0667805567643, 994.5574219472302, 994.8408254679252, 995.9153471954893, 999.010979130659, 1003.8042581696573, 1005.9949700251489, 1006.9899301007006, 1008.0423597582326, 930.3208657679897, 1034.3535290443472, 1041.182671602792, 1042.0829669890486, 1042.880068792091, 1035.969210635415, 1037.7405648587853, 1038.9532471038822, 1040.4173662748808, 1041.3907863899628, 748.1583218878761, 759.1611269547182, 760.4600733479236, 762.268222506389, 763.1872056260121, 17069.0, 17557.0, 17569.56076098095, 17656.028918409484, 17864.0, 17167.570810729707, 17181.82818171815, 17707.73438398425, 17711.0, 17727.645447090985, 13013.414396352187, 14691.63270918225, 14925.552233433014, 14952.70094598102, 14984.850151498455, 3551.4672799080085, 3610.765300255501, 3640.0899775056237, 3642.562892452923, 3647.2340808430263, 124632.0, 132069.35861282816, 135373.0, 153950.0, 155578.0, 13615.795763063563, 13635.727285454434, 13828.585142445743, 13894.791578126336, 13948.0, 26580.468390632464, 27039.32401689934, 27231.727682722918, 27243.86378068097, 27252.863735681196, 7498.850022999619, 7662.118856331594, 7689.269519395649, 7757.495762775401, 7757.689692412353, 3878.45701601776, 3961.7622942623534, 3963.128111815454, 3968.3849003404516, 3972.880813575597, 3948.565657777642, 4318.762468064224, 4394.802233899418, 4480.798364073625, 4487.865364039084, 846.6697987784769, 888.9199972002551, 904.9592768325441, 908.9500077495669, 910.5902343945252, 651.6057785040077, 669.6450881033061, 675.3078094952615, 690.3959035843637, 691.515938842812, 11255.324680519196, 11428.485718142703, 12092.0, 12210.633680989584, 12627.368631568384, 627.369493658879, 655.3577494055852, 661.5865084322298, 676.1109141478939, 681.437813803614, 4553.385292985471, 5316.282301889196, 5606.635568688022, 5767.36558978512, 5863.355030946593, 135949.35952347718, 136775.6322436783, 137379.31310343585, 139443.0, 140123.0, 115344.42327788308, 115781.0, 116530.41734791272, 116687.41656291834, 116704.0, 93927.0, 94148.0, 95611.91686789744, 95662.04337956531, 97131.51434242784, 8883.911160888309, 9920.603175872884, 11193.27243729155, 11486.081113510732, 11542.480588373375, 36525.0, 41214.58785412108, 42916.570834292506, 43210.56789432065, 43383.34924976065, 58760.4123958772, 58936.0, 69554.0, 73080.90378644431, 91053.54473227591, 17521.299148033937, 17595.912020439817, 18059.2776288947, 18211.45365639033, 18259.54351141179, 18599.628007439504, 19068.713969290748, 19445.708314375013, 19556.23993840561, 19577.412677619694, 17537.82462175413, 17998.550036248675, 18494.982775947454, 18564.071796409855, 18586.535336616693, 17841.46475605734, 17951.01269430194, 18161.636767264317, 18287.177077031298, 18458.169382378095, 17503.912480437517, 17642.294308227527, 18069.63860722752, 18077.18652660607, 18304.0, 11075.778484430428, 11725.824112638487, 12506.249625022529, 12594.87405125937, 12642.2414655121, 13377.331133443484, 14491.710165796836, 14555.854441455873, 14714.337854796346, 14862.108273503625], "value": 8772.207282441053, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 113.0, 113.0, 113.0, 116.0, 113.0, 117.0, 115.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 143.0, 110.0, 151.0, 113.0, 118.0, 142.0, 114.0, 143.0, 147.0], "value": 135.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 198.0, 202.0, 203.0, 205.0, 247.0, 299.0, 201.0, 196.0, 197.0], "value": 214.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 61.0, 85.0, 97.0, 50.0, 83.0, 50.0, 60.0, 85.0, 79.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 68.0, 83.0, 71.0, 68.0, 68.0, 84.0, 69.0, 86.0, 110.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 91.0, 91.0, 91.0, 91.0, 93.0, 91.0, 90.0, 90.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 98.0, 98.0, 151.0, 151.0, 100.0, 98.0, 98.0, 96.0], "value": 108.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 92.0, 102.0, 135.0, 92.0, 93.0, 100.0, 94.0, 94.0], "value": 98.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 50.0, 51.0, 53.0, 52.0, 51.0, 52.0, 53.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 62.0, 57.0, 58.0, 60.0, 58.0, 59.0, 62.0, 56.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 111.0, 90.0, 85.0, 89.0, 88.0, 86.0, 89.0, 108.0, 90.0], "value": 93.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 147.0, 160.0, 148.0, 147.0, 157.0, 135.0, 140.0, 152.0, 145.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 182.0, 184.0, 197.0, 233.0, 229.0, 184.0, 183.0, 223.0], "value": 198.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 69.0, 68.0, 69.0, 70.0, 66.0, 69.0, 68.0, 68.0], "value": 68.3, "unit": "score"}]}]} 08:06:27 INFO - Return code: 0 08:06:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:06:27 INFO - # TBPL SUCCESS # 08:06:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:06:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:06:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:06:27 INFO - 2017-06-05 08:06:27.885 screenresolution[1668:11789] starting screenresolution argv=screenresolution get 08:06:27 INFO - 2017-06-05 08:06:27.887 screenresolution[1668:11789] Display 0: 1600x1200x32@60 08:06:27 INFO - 2017-06-05 08:06:27.893 screenresolution[1669:11792] starting screenresolution argv=screenresolution list 08:06:27 INFO - Available Modes on Display 0 08:06:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:06:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:06:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:06:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:06:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:06:28 INFO - Intel Iris: 08:06:28 INFO - Chipset Model: Intel Iris 08:06:28 INFO - Type: GPU 08:06:28 INFO - Bus: Built-In 08:06:28 INFO - VRAM (Dynamic, Max): 1536 MB 08:06:28 INFO - Vendor: Intel (0x8086) 08:06:28 INFO - Device ID: 0x0a2e 08:06:28 INFO - Revision ID: 0x0009 08:06:28 INFO - Displays: 08:06:28 INFO - Display: 08:06:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:06:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:06:28 INFO - Display Serial Number: 1600x1200 60 08:06:28 INFO - Main Display: Yes 08:06:28 INFO - Mirror: Off 08:06:28 INFO - Online: Yes 08:06:28 INFO - Rotation: Supported 08:06:28 INFO - Return code: 0 08:06:28 INFO - Running post-action listener: _resource_record_post_action 08:06:28 INFO - [mozharness: 2017-06-05 15:06:28.129945Z] Finished run-tests step (success) 08:06:28 INFO - Running post-run listener: _resource_record_post_run 08:06:28 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 33828352; Write bytes: 572520448; Read time: 346; Write time: 1875 08:06:28 INFO - TinderboxPrint: CPU usage
53.4% 08:06:28 INFO - TinderboxPrint: I/O read bytes / time
33,828,352 / 346 08:06:28 INFO - TinderboxPrint: I/O write bytes / time
572,520,448 / 1,875 08:06:28 INFO - TinderboxPrint: CPU idle
2,343.4 (46.6%) 08:06:28 INFO - TinderboxPrint: CPU system
1,020.2 (20.3%) 08:06:28 INFO - TinderboxPrint: CPU user
1,666.1 (33.1%) 08:06:28 INFO - TinderboxPrint: Swap in / out
277,037,056 / 0 08:06:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247865856; Write bytes: 249349632; Read time: 17932; Write time: 641 08:06:28 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 08:06:28 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 30957056; Write bytes: 321790464; Read time: 318; Write time: 1228 08:06:28 INFO - Running post-run listener: _upload_blobber_files 08:06:28 INFO - Blob upload gear active. 08:06:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:06:28 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 08:06:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:06:29 INFO - (blobuploader) - INFO - Open directory for files ... 08:06:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:06:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:06:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:30 INFO - (blobuploader) - INFO - Done attempting. 08:06:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:06:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:06:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:31 INFO - (blobuploader) - INFO - Done attempting. 08:06:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:06:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:06:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:32 INFO - (blobuploader) - INFO - Done attempting. 08:06:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:06:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:06:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:06:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:06:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:06:34 INFO - (blobuploader) - INFO - Done attempting. 08:06:34 INFO - (blobuploader) - INFO - Iteration through files over. 08:06:34 INFO - Return code: 0 08:06:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:06:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:06:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06d05bb8b262abcb9dce9b521d001371041b1735665e0fa584c3060f25a025ad429087c7d6f1691a306eb2dfe047b9a625e589edab19610e485c648899c26662", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4644ff0bdc4f57a9fa7c51e50bb2f29254aa747afc515682289e61fc6fafd232ecf9c00c5e72c30f82d4cbd4b0f1e012fcd4ad2c87cec7620643192ff360fa32", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32bf98409c6a4cb8f4285b7374a62a7ab37987df3f921c4f99bd3c19815b0cf491b2cc327fc4e3a9c8dd03f96831ed9f79448f8a55cc10e1c144260d19468755", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/113217bb034540923e86987e9389528ac0e362ae9e7fb37c88af52e9a3d54697d847e245490fce4197f8fd83ca6efd84738687fa3a52dc230d75f7878072ceff"} 08:06:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:06:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:06:34 INFO - Contents: 08:06:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06d05bb8b262abcb9dce9b521d001371041b1735665e0fa584c3060f25a025ad429087c7d6f1691a306eb2dfe047b9a625e589edab19610e485c648899c26662", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4644ff0bdc4f57a9fa7c51e50bb2f29254aa747afc515682289e61fc6fafd232ecf9c00c5e72c30f82d4cbd4b0f1e012fcd4ad2c87cec7620643192ff360fa32", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32bf98409c6a4cb8f4285b7374a62a7ab37987df3f921c4f99bd3c19815b0cf491b2cc327fc4e3a9c8dd03f96831ed9f79448f8a55cc10e1c144260d19468755", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/113217bb034540923e86987e9389528ac0e362ae9e7fb37c88af52e9a3d54697d847e245490fce4197f8fd83ca6efd84738687fa3a52dc230d75f7878072ceff"} 08:06:34 INFO - Running post-run listener: copy_logs_to_upload_dir 08:06:34 INFO - Copying logs to upload dir... 08:06:34 INFO - mkdir: /builds/slave/test/build/upload/logs 08:06:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.323020 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-05 08:06:34.399297) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 08:06:34.403236) ========= 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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06d05bb8b262abcb9dce9b521d001371041b1735665e0fa584c3060f25a025ad429087c7d6f1691a306eb2dfe047b9a625e589edab19610e485c648899c26662", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4644ff0bdc4f57a9fa7c51e50bb2f29254aa747afc515682289e61fc6fafd232ecf9c00c5e72c30f82d4cbd4b0f1e012fcd4ad2c87cec7620643192ff360fa32", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32bf98409c6a4cb8f4285b7374a62a7ab37987df3f921c4f99bd3c19815b0cf491b2cc327fc4e3a9c8dd03f96831ed9f79448f8a55cc10e1c144260d19468755", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/113217bb034540923e86987e9389528ac0e362ae9e7fb37c88af52e9a3d54697d847e245490fce4197f8fd83ca6efd84738687fa3a52dc230d75f7878072ceff"} build_url:https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011491 build_url: 'https://queue.taskcluster.net/v1/task/AdAISLXpQiiMTIT2SknmbA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06d05bb8b262abcb9dce9b521d001371041b1735665e0fa584c3060f25a025ad429087c7d6f1691a306eb2dfe047b9a625e589edab19610e485c648899c26662", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4644ff0bdc4f57a9fa7c51e50bb2f29254aa747afc515682289e61fc6fafd232ecf9c00c5e72c30f82d4cbd4b0f1e012fcd4ad2c87cec7620643192ff360fa32", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/32bf98409c6a4cb8f4285b7374a62a7ab37987df3f921c4f99bd3c19815b0cf491b2cc327fc4e3a9c8dd03f96831ed9f79448f8a55cc10e1c144260d19468755", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/113217bb034540923e86987e9389528ac0e362ae9e7fb37c88af52e9a3d54697d847e245490fce4197f8fd83ca6efd84738687fa3a52dc230d75f7878072ceff"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 08:06:34.440506) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:06:34.441069) ========= 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.Te6JGnUmlB/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.yOcYFc0xYM/Listeners TMPDIR=/var/folders/m4/d0n5fftn6ks85y96r7kgw23r00000w/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.005305 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 08:06:34.492202) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 08:06:34.492746) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 08:06:34.493267) ========= ========= Total master_lag: 1.42 =========