builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0056 starttime: 1495017287.13 results: success (0) revision: 95990be385ca8331c466ba5f174794771955751e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:47.129477) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:47.129951) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:47.130238) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:47.155694) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:47.156083) ========= 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.rgusU3YAH1/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.eXoWAiRN4j/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-17 03:34:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.95M=0.001s 2017-05-17 03:34:47 (8.95 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.092435 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:47.271417) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:47.271753) ========= 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.rgusU3YAH1/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.eXoWAiRN4j/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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.030726 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:47.323431) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 03:34:47.323786) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 95990be385ca8331c466ba5f174794771955751e --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 95990be385ca8331c466ba5f174794771955751e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rgusU3YAH1/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.eXoWAiRN4j/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-17 03:34:47,420 truncating revision to first 12 chars 2017-05-17 03:34:47,420 Setting DEBUG logging. 2017-05-17 03:34:47,420 attempt 1/10 2017-05-17 03:34:47,420 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/95990be385ca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-17 03:34:49,074 unpacking tar archive at: mozilla-central-95990be385ca/testing/mozharness/ program finished with exit code 0 elapsedTime=1.936129 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 03:34:49.281517) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:49.281862) ========= script_repo_revision: 95990be385ca8331c466ba5f174794771955751e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:49.282233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:49.282502) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 03:34:49.301315) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-17 03:34:49.301686) ========= /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.rgusU3YAH1/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.eXoWAiRN4j/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:34:49 INFO - MultiFileLogger online at 20170517 03:34:49 in /builds/slave/test 03:34:49 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 03:34:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:34:49 INFO - {'append_to_log': False, 03:34:49 INFO - 'base_work_dir': '/builds/slave/test', 03:34:49 INFO - 'blob_upload_branch': 'Firefox', 03:34:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:34:49 INFO - 'branch': 'Firefox', 03:34:49 INFO - 'buildbot_json_path': 'buildprops.json', 03:34:49 INFO - 'config_files': ('talos/mac_config.py',), 03:34:49 INFO - 'default_actions': ('clobber', 03:34:49 INFO - 'read-buildbot-config', 03:34:49 INFO - 'download-and-extract', 03:34:49 INFO - 'populate-webroot', 03:34:49 INFO - 'create-virtualenv', 03:34:49 INFO - 'install', 03:34:49 INFO - 'run-tests'), 03:34:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:34:49 INFO - 'download_minidump_stackwalk': True, 03:34:49 INFO - 'download_symbols': 'ondemand', 03:34:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:34:49 INFO - 'tooltool.py': '/tools/tooltool.py', 03:34:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:34:49 INFO - '/tools/misc-python/virtualenv.py')}, 03:34:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:34:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:34:49 INFO - 'gecko_profile': False, 03:34:49 INFO - 'gecko_profile_interval': 0, 03:34:49 INFO - 'installer_path': 'installer.exe', 03:34:49 INFO - 'log_level': 'info', 03:34:49 INFO - 'log_name': 'talos', 03:34:49 INFO - 'log_to_console': True, 03:34:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:34:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:34:49 INFO - 'opt_config_files': (), 03:34:49 INFO - 'pip_index': False, 03:34:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:34:49 INFO - 'cmd': ('bash', 03:34:49 INFO - '-c', 03:34:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:34:49 INFO - 'enabled': True, 03:34:49 INFO - 'halt_on_failure': False, 03:34:49 INFO - 'name': 'check_screen_resolution'},), 03:34:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:34:49 INFO - 'cmd': ('bash', 03:34:49 INFO - '-c', 03:34:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:34:49 INFO - 'enabled': True, 03:34:49 INFO - 'halt_on_failure': False, 03:34:49 INFO - 'name': 'check_screen_resolution'},), 03:34:49 INFO - 'run_cmd_checks_enabled': True, 03:34:49 INFO - 'suite': 'dromaeojs-e10s', 03:34:49 INFO - 'system_bits': '32', 03:34:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:34:49 INFO - 'title': 't-yosemite-r7-0056', 03:34:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:34:49 INFO - 'use_talos_json': True, 03:34:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:34:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:34:49 INFO - 'work_dir': 'build'} 03:34:49 INFO - [mozharness: 2017-05-17 10:34:49.505724Z] Running clobber step. 03:34:49 INFO - Running pre-action listener: _resource_record_pre_action 03:34:49 INFO - Running main action method: clobber 03:34:49 INFO - rmtree: /builds/slave/test/build 03:34:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:34:52 INFO - Running post-action listener: _resource_record_post_action 03:34:52 INFO - [mozharness: 2017-05-17 10:34:52.153675Z] Finished clobber step (success) 03:34:52 INFO - [mozharness: 2017-05-17 10:34:52.153828Z] Running read-buildbot-config step. 03:34:52 INFO - Running pre-action listener: _resource_record_pre_action 03:34:52 INFO - Running main action method: read_buildbot_config 03:34:52 INFO - Using buildbot properties: 03:34:52 INFO - { 03:34:52 INFO - "project": "", 03:34:52 INFO - "product": "firefox", 03:34:52 INFO - "who": "cbook@mozilla.com", 03:34:52 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 03:34:52 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 03:34:52 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 03:34:52 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 03:34:52 INFO - "stage_platform": "macosx64", 03:34:52 INFO - "basedir": "/builds/slave/test", 03:34:52 INFO - "buildnumber": 75, 03:34:52 INFO - "platform": "yosemite_r7", 03:34:52 INFO - "parent_task_id": "ECRfMiMBSUiIZsdcLoVbZA", 03:34:52 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:34:52 INFO - "slavebuilddir": "test", 03:34:52 INFO - "taskId": "UOlfUA4_TTCjbRohNUCnvw", 03:34:52 INFO - "branch": "mozilla-central", 03:34:52 INFO - "script_repo_revision": "production", 03:34:52 INFO - "revision": "95990be385ca8331c466ba5f174794771955751e", 03:34:52 INFO - "slavename": "t-yosemite-r7-0056", 03:34:52 INFO - "repo_path": "mozilla-central" 03:34:52 INFO - } 03:34:52 INFO - Finding installer, test and symbols from parent task. 03:34:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UOlfUA4_TTCjbRohNUCnvw'}, attempt #1 03:34:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UOlfUA4_TTCjbRohNUCnvw'}, attempt #1 03:34:53 INFO - Task dependencies: B1aED6_SSECY3DrwpeubRA 03:34:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA'}, attempt #1 03:34:53 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:34:53 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/test_packages.json 03:34:53 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/target.crashreporter-symbols.zip 03:34:53 INFO - Running post-action listener: _resource_record_post_action 03:34:53 INFO - [mozharness: 2017-05-17 10:34:53.526051Z] Finished read-buildbot-config step (success) 03:34:53 INFO - [mozharness: 2017-05-17 10:34:53.526192Z] Running download-and-extract step. 03:34:53 INFO - Running pre-action listener: _resource_record_pre_action 03:34:53 INFO - Running main action method: download_and_extract 03:34:53 INFO - mkdir: /builds/slave/test/build/tests 03:34:53 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')]} 03:34:53 INFO - trying https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 03:34:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/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 03:34:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/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 03:34:54 INFO - Downloaded 1325 bytes. 03:34:54 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:34:54 INFO - Using the following test package requirements: 03:34:54 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:34:54 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:34:54 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:34:54 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:34:54 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:34:54 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:34:54 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:34:54 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:34:54 INFO - u'jsshell-mac.zip'], 03:34:54 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:34:54 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:34:54 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:34:54 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:34:54 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:34:54 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:34:54 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:34:54 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:34:54 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:34:54 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:34:54 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:34:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:34:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:34:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:34:54 INFO - Fetch https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:34:56 INFO - Content-Length response header: 56567923 03:34:56 INFO - Bytes received: 56567923 03:35:14 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 03:35:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 03:35:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:35:14 INFO - Fetch https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:35:15 INFO - Content-Length response header: 56567923 03:35:15 INFO - Bytes received: 56567923 03:35:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 03:35:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:35:32 INFO - Fetch https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:35:33 INFO - Content-Length response header: 14410069 03:35:33 INFO - Bytes received: 14410069 03:35:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:34 INFO - trying https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:35:34 INFO - Downloading https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:35:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/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 03:35:36 INFO - Downloaded 75945470 bytes. 03:35:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:35:36 INFO - mkdir: /builds/slave/test/properties 03:35:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:35:36 INFO - Writing to file /builds/slave/test/properties/build_url 03:35:36 INFO - Contents: 03:35:36 INFO - build_url:https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 03:35:36 INFO - Running post-action listener: _resource_record_post_action 03:35:36 INFO - Running post-action listener: set_extra_try_arguments 03:35:36 INFO - [mozharness: 2017-05-17 10:35:36.141586Z] Finished download-and-extract step (success) 03:35:36 INFO - [mozharness: 2017-05-17 10:35:36.141691Z] Running populate-webroot step. 03:35:36 INFO - Running pre-action listener: _resource_record_pre_action 03:35:36 INFO - Running main action method: populate_webroot 03:35:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:35:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:35:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:35:36 INFO - '--tppagecycles', 03:35:36 INFO - '7'], 03:35:36 INFO - 'tests': ['tsvgm']}}, 03:35:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:35:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:35:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:35:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:35:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:35:36 INFO - 'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:35:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:35:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:35:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:35:36 INFO - 'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['damp', 'tps']}, 03:35:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:35:36 INFO - 'tests': ['damp', 'tps']}, 03:35:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['dromaeo_dom']}, 03:35:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:35:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:35:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:35:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:35:36 INFO - 'tests': ['ts_paint', 'tp5o']}, 03:35:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['a11yr', 03:35:36 INFO - 'ts_paint', 03:35:36 INFO - 'tpaint', 03:35:36 INFO - 'sessionrestore', 03:35:36 INFO - 'sessionrestore_no_auto_restore', 03:35:36 INFO - 'tabpaint']}, 03:35:36 INFO - 'other-e10s': {'tests': ['a11yr', 03:35:36 INFO - 'ts_paint', 03:35:36 INFO - 'tpaint', 03:35:36 INFO - 'sessionrestore', 03:35:36 INFO - 'sessionrestore_no_auto_restore', 03:35:36 INFO - 'tabpaint']}, 03:35:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:35:36 INFO - 'ts_paint', 03:35:36 INFO - 'tpaint', 03:35:36 INFO - 'sessionrestore', 03:35:36 INFO - 'sessionrestore_no_auto_restore', 03:35:36 INFO - 'tabpaint']}, 03:35:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:35:36 INFO - 'ts_paint', 03:35:36 INFO - 'tpaint', 03:35:36 INFO - 'sessionrestore', 03:35:36 INFO - 'sessionrestore_no_auto_restore', 03:35:36 INFO - 'tabpaint']}, 03:35:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['a11yr', 03:35:36 INFO - 'ts_paint', 03:35:36 INFO - 'tpaint', 03:35:36 INFO - 'sessionrestore', 03:35:36 INFO - 'sessionrestore_no_auto_restore', 03:35:36 INFO - 'tabpaint']}, 03:35:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['a11yr', 03:35:36 INFO - 'ts_paint', 03:35:36 INFO - 'tpaint', 03:35:36 INFO - 'sessionrestore', 03:35:36 INFO - 'sessionrestore_no_auto_restore', 03:35:36 INFO - 'tabpaint']}, 03:35:36 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 03:35:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 03:35:36 INFO - 'bloom_basic_ref']}, 03:35:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['tsvgx', 03:35:36 INFO - 'tsvgr_opacity', 03:35:36 INFO - 'tart', 03:35:36 INFO - 'tscrollx', 03:35:36 INFO - 'cart', 03:35:36 INFO - 'tsvg_static']}, 03:35:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:35:36 INFO - 'tsvgr_opacity', 03:35:36 INFO - 'tart', 03:35:36 INFO - 'tscrollx', 03:35:36 INFO - 'cart', 03:35:36 INFO - 'tsvg_static']}, 03:35:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:35:36 INFO - 'talos_options': ['--disable-e10s'], 03:35:36 INFO - 'tests': ['tp5o']}, 03:35:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:35:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:35:36 INFO - 'talos_options': ['--disable-e10s', 03:35:36 INFO - '--xperf_path', 03:35:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:35:36 INFO - 'tests': ['tp5n']}, 03:35:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:35:36 INFO - 'talos_options': ['--xperf_path', 03:35:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:35:36 INFO - 'tests': ['tp5n']}}, 03:35:36 INFO - 'talos.zip': {'path': '', 03:35:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:35:36 INFO - Running post-action listener: _resource_record_post_action 03:35:36 INFO - [mozharness: 2017-05-17 10:35:36.164098Z] Finished populate-webroot step (success) 03:35:36 INFO - [mozharness: 2017-05-17 10:35:36.164237Z] Running create-virtualenv step. 03:35:36 INFO - Running pre-action listener: _resource_record_pre_action 03:35:36 INFO - Running main action method: create_virtualenv 03:35:36 INFO - Creating virtualenv /builds/slave/test/build/venv 03:35:36 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 03:35:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:35:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:35:36 INFO - Using real prefix '/tools/python27' 03:35:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:35:37 INFO - Installing distribute.............................................................................................................................................................................................done. 03:35:39 INFO - Installing pip.................done. 03:35:39 INFO - Return code: 0 03:35:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:35:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:35:40 INFO - Reading from file tmpfile_stdout 03:35:40 INFO - Output received: 03:35:40 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) 03:35:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:35:40 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')]} 03:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:40 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')]} 03:35:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:40 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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:35:40 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 03:35:40 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 03:35:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:35:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:35:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:35:40 INFO - 'HOME': '/Users/cltbld', 03:35:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:35:40 INFO - 'LOGNAME': 'cltbld', 03:35:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:40 INFO - 'MOZ_NO_REMOTE': '1', 03:35:40 INFO - 'NO_EM_RESTART': '1', 03:35:40 INFO - 'PAGER': '/bin/cat', 03:35:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:35:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:40 INFO - 'PWD': '/builds/slave/test', 03:35:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:35:40 INFO - 'SHELL': '/bin/bash', 03:35:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:35:40 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:35:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:35:40 INFO - 'USER': 'cltbld', 03:35:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:35:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:35:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:40 INFO - 'XPC_FLAGS': '0x0', 03:35:40 INFO - 'XPC_SERVICE_NAME': '0', 03:35:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:35:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:40 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 03:35:40 INFO - Cleaning up... 03:35:40 INFO - Return code: 0 03:35:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:35:40 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')]} 03:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:40 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')]} 03:35:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:40 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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:35:40 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 03:35:40 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 03:35:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:35:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:35:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:35:40 INFO - 'HOME': '/Users/cltbld', 03:35:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:35:40 INFO - 'LOGNAME': 'cltbld', 03:35:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:40 INFO - 'MOZ_NO_REMOTE': '1', 03:35:40 INFO - 'NO_EM_RESTART': '1', 03:35:40 INFO - 'PAGER': '/bin/cat', 03:35:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:35:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:40 INFO - 'PWD': '/builds/slave/test', 03:35:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:35:40 INFO - 'SHELL': '/bin/bash', 03:35:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:35:40 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:35:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:35:40 INFO - 'USER': 'cltbld', 03:35:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:35:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:35:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:40 INFO - 'XPC_FLAGS': '0x0', 03:35:40 INFO - 'XPC_SERVICE_NAME': '0', 03:35:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:35:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:40 INFO - Downloading/unpacking psutil>=3.1.1 03:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:35:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:35:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:35:42 INFO - Installing collected packages: psutil 03:35:42 INFO - Running setup.py install for psutil 03:35:42 INFO - building 'psutil._psutil_osx' extension 03:35:42 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 03:35:43 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 03:35:43 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 03:35:43 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 03:35:43 INFO - building 'psutil._psutil_posix' extension 03:35:43 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 03:35:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:35:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:35:43 INFO - ^ 03:35:43 INFO - 1 warning generated. 03:35:43 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 03:35:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:35:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:35:43 INFO - Successfully installed psutil 03:35:43 INFO - Cleaning up... 03:35:43 INFO - Return code: 0 03:35:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:35:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:44 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')]} 03:35:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:44 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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:35:44 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 03:35:44 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 03:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:35:44 INFO - 'HOME': '/Users/cltbld', 03:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:35:44 INFO - 'LOGNAME': 'cltbld', 03:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:44 INFO - 'MOZ_NO_REMOTE': '1', 03:35:44 INFO - 'NO_EM_RESTART': '1', 03:35:44 INFO - 'PAGER': '/bin/cat', 03:35:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:44 INFO - 'PWD': '/builds/slave/test', 03:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:35:44 INFO - 'SHELL': '/bin/bash', 03:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:35:44 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:35:44 INFO - 'USER': 'cltbld', 03:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:44 INFO - 'XPC_FLAGS': '0x0', 03:35:44 INFO - 'XPC_SERVICE_NAME': '0', 03:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:35:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:35:44 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 03:35:44 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 03:35:44 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 03:35:44 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 03:35:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:35:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:35:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:35:46 INFO - Installing collected packages: mozsystemmonitor 03:35:46 INFO - Running setup.py install for mozsystemmonitor 03:35:46 INFO - Successfully installed mozsystemmonitor 03:35:46 INFO - Cleaning up... 03:35:46 INFO - Return code: 0 03:35:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:35:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:35:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:35:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:35:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:35:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:35:46 INFO - 'HOME': '/Users/cltbld', 03:35:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:35:46 INFO - 'LOGNAME': 'cltbld', 03:35:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:46 INFO - 'MOZ_NO_REMOTE': '1', 03:35:46 INFO - 'NO_EM_RESTART': '1', 03:35:46 INFO - 'PAGER': '/bin/cat', 03:35:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:35:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:46 INFO - 'PWD': '/builds/slave/test', 03:35:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:35:46 INFO - 'SHELL': '/bin/bash', 03:35:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:35:46 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:35:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:35:46 INFO - 'USER': 'cltbld', 03:35:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:35:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:35:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:46 INFO - 'XPC_FLAGS': '0x0', 03:35:46 INFO - 'XPC_SERVICE_NAME': '0', 03:35:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:35:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:47 INFO - Downloading/unpacking jsonschema==2.5.1 03:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:35:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:35:48 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 03:35:48 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 03:35:48 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 03:35:48 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 03:35:48 INFO - Downloading functools32-3.2.3-2.tar.gz 03:35:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:35:49 INFO - warning: no files found matching '*.txt' 03:35:49 INFO - no previously-included directories found matching 'build' 03:35:49 INFO - no previously-included directories found matching 'dist' 03:35:49 INFO - no previously-included directories found matching '.git*' 03:35:49 INFO - Installing collected packages: jsonschema, functools32 03:35:49 INFO - Running setup.py install for functools32 03:35:49 INFO - warning: no files found matching '*.txt' 03:35:49 INFO - no previously-included directories found matching 'build' 03:35:49 INFO - no previously-included directories found matching 'dist' 03:35:49 INFO - no previously-included directories found matching '.git*' 03:35:49 INFO - Successfully installed jsonschema functools32 03:35:49 INFO - Cleaning up... 03:35:49 INFO - Return code: 0 03:35:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:35:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:35:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:35:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:35:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:35:49 INFO - 'HOME': '/Users/cltbld', 03:35:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:35:49 INFO - 'LOGNAME': 'cltbld', 03:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:49 INFO - 'MOZ_NO_REMOTE': '1', 03:35:49 INFO - 'NO_EM_RESTART': '1', 03:35:49 INFO - 'PAGER': '/bin/cat', 03:35:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:49 INFO - 'PWD': '/builds/slave/test', 03:35:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:35:49 INFO - 'SHELL': '/bin/bash', 03:35:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:35:49 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:35:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:35:49 INFO - 'USER': 'cltbld', 03:35:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:35:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:49 INFO - 'XPC_FLAGS': '0x0', 03:35:49 INFO - 'XPC_SERVICE_NAME': '0', 03:35:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:35:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:35:49 INFO - Cleaning up... 03:35:49 INFO - Return code: 0 03:35:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:35:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:35:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:35:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:35:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:35:49 INFO - 'HOME': '/Users/cltbld', 03:35:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:35:49 INFO - 'LOGNAME': 'cltbld', 03:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:49 INFO - 'MOZ_NO_REMOTE': '1', 03:35:49 INFO - 'NO_EM_RESTART': '1', 03:35:49 INFO - 'PAGER': '/bin/cat', 03:35:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:49 INFO - 'PWD': '/builds/slave/test', 03:35:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:35:49 INFO - 'SHELL': '/bin/bash', 03:35:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:35:49 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:35:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:35:49 INFO - 'USER': 'cltbld', 03:35:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:35:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:49 INFO - 'XPC_FLAGS': '0x0', 03:35:49 INFO - 'XPC_SERVICE_NAME': '0', 03:35:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:35:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:51 INFO - Downloading blobuploader-1.2.4.tar.gz 03:35:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:35:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:35:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:35:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:35:52 INFO - Downloading docopt-0.6.1.tar.gz 03:35:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:35:52 INFO - Installing collected packages: blobuploader, requests, docopt 03:35:52 INFO - Running setup.py install for blobuploader 03:35:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:35:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:35:52 INFO - Running setup.py install for requests 03:35:52 INFO - Running setup.py install for docopt 03:35:52 INFO - Successfully installed blobuploader requests docopt 03:35:52 INFO - Cleaning up... 03:35:52 INFO - Return code: 0 03:35:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:35: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')]} 03:35:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35: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')]} 03:35:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:52 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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:35:52 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 03:35:52 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 03:35:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:35:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:35:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:35:52 INFO - 'HOME': '/Users/cltbld', 03:35:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:35:52 INFO - 'LOGNAME': 'cltbld', 03:35:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:52 INFO - 'MOZ_NO_REMOTE': '1', 03:35:52 INFO - 'NO_EM_RESTART': '1', 03:35:52 INFO - 'PAGER': '/bin/cat', 03:35:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:35:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:52 INFO - 'PWD': '/builds/slave/test', 03:35:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:35:52 INFO - 'SHELL': '/bin/bash', 03:35:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:35:52 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:35:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:35:52 INFO - 'USER': 'cltbld', 03:35:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:35:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:35:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:52 INFO - 'XPC_FLAGS': '0x0', 03:35:52 INFO - 'XPC_SERVICE_NAME': '0', 03:35:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:35:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:35:53 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-lwNdOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:35:53 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-sr1Hxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:35:53 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-RleZ7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:35:53 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-wSGfei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:35:53 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-C9Ja_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:35:53 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-X28VE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:35:53 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-gO0itI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:35:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:35:53 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-FZX38Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:35:54 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-YCTNhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:35:54 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-DlvRdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:35:54 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-gRTgEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:35:54 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-9XT_Jg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:35:54 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-TvNohG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:35:54 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-yRL_dP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:54 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-BXWe2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:35:54 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-IL8iZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:35:54 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-yxNUVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:35:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:35:55 INFO - Running setup.py install for manifestparser 03:35:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:35:55 INFO - Running setup.py install for mozcrash 03:35:55 INFO - Running setup.py install for mozdebug 03:35:55 INFO - Running setup.py install for mozdevice 03:35:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:35:55 INFO - Running setup.py install for mozfile 03:35:55 INFO - Running setup.py install for mozhttpd 03:35:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:35:55 INFO - Running setup.py install for mozinfo 03:35:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Running setup.py install for mozInstall 03:35:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Running setup.py install for mozleak 03:35:56 INFO - Running setup.py install for mozlog 03:35:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Running setup.py install for moznetwork 03:35:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Running setup.py install for mozprocess 03:35:56 INFO - Running setup.py install for mozprofile 03:35:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:35:56 INFO - Running setup.py install for mozrunner 03:35:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:35:57 INFO - Running setup.py install for mozscreenshot 03:35:57 INFO - Running setup.py install for moztest 03:35:57 INFO - Running setup.py install for mozversion 03:35:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:35:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:35:57 INFO - Cleaning up... 03:35:57 INFO - Return code: 0 03:35:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:35:57 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')]} 03:35:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:35:57 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')]} 03:35:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:35:57 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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:35:57 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 03:35:57 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 03:35:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:35:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:35:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:35:57 INFO - 'HOME': '/Users/cltbld', 03:35:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:35:57 INFO - 'LOGNAME': 'cltbld', 03:35:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:35:57 INFO - 'MOZ_NO_REMOTE': '1', 03:35:57 INFO - 'NO_EM_RESTART': '1', 03:35:57 INFO - 'PAGER': '/bin/cat', 03:35:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:35:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:35:57 INFO - 'PWD': '/builds/slave/test', 03:35:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:35:57 INFO - 'SHELL': '/bin/bash', 03:35:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:35:57 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:35:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:35:57 INFO - 'USER': 'cltbld', 03:35:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:35:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:35:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:35:57 INFO - 'XPC_FLAGS': '0x0', 03:35:57 INFO - 'XPC_SERVICE_NAME': '0', 03:35:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:35:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:35:57 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-bR8q1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:35:57 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)) 03:35:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:35:57 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-ri3CP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:35:58 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)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-uuaSzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:35:58 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)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-6sQY2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:35:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-rhRA4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:35:58 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)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-EtgDQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:35:58 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)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-Dj2P0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:35:58 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)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-yKvT3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:35:58 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)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-MIOUej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:35:58 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)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-x8VuZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:35:58 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)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-OAjflR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:35:58 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)) 03:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:35:58 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-UQEBBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:35:59 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)) 03:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:35:59 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-c9olvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:35:59 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)) 03:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:35:59 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-seBy1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:35:59 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)) 03:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:59 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-q5zn3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:35:59 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)) 03:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:35:59 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-KOOax9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:35:59 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)) 03:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:35:59 INFO - Running setup.py (path:/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/pip-skYmx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:35:59 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)) 03:35:59 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)) 03:35:59 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)) 03:35:59 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)) 03:35:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:35:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:35:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:35:59 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 03:35:59 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 03:35:59 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 03:35:59 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 03:36:00 INFO - Downloading blessings-1.6.tar.gz 03:36:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:36:01 INFO - Installing collected packages: blessings 03:36:01 INFO - Running setup.py install for blessings 03:36:01 INFO - Successfully installed blessings 03:36:01 INFO - Cleaning up... 03:36:01 INFO - Return code: 0 03:36:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:36:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:36:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:36:01 INFO - Reading from file tmpfile_stdout 03:36:01 INFO - Current package versions: 03:36:01 INFO - blessings == 1.6 03:36:01 INFO - blobuploader == 1.2.4 03:36:01 INFO - docopt == 0.6.1 03:36:01 INFO - functools32 == 3.2.3-2 03:36:01 INFO - jsonschema == 2.5.1 03:36:01 INFO - manifestparser == 1.1 03:36:01 INFO - mozInstall == 1.12 03:36:01 INFO - mozcrash == 1.0 03:36:01 INFO - mozdebug == 0.1 03:36:01 INFO - mozdevice == 0.50 03:36:01 INFO - mozfile == 1.2 03:36:01 INFO - mozhttpd == 0.7 03:36:01 INFO - mozinfo == 0.9 03:36:01 INFO - mozleak == 0.1 03:36:01 INFO - mozlog == 3.4 03:36:01 INFO - moznetwork == 0.27 03:36:01 INFO - mozprocess == 0.25 03:36:01 INFO - mozprofile == 0.28 03:36:01 INFO - mozrunner == 6.13 03:36:01 INFO - mozscreenshot == 0.1 03:36:01 INFO - mozsystemmonitor == 0.3 03:36:01 INFO - moztest == 0.8 03:36:01 INFO - mozversion == 1.4 03:36:01 INFO - psutil == 3.1.1 03:36:01 INFO - requests == 1.2.3 03:36:01 INFO - wsgiref == 0.1.2 03:36:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:36: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')]} 03:36:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36: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')]} 03:36:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:01 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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:36:01 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 03:36:01 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 03:36:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:36:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:01 INFO - 'HOME': '/Users/cltbld', 03:36:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:01 INFO - 'LOGNAME': 'cltbld', 03:36:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:01 INFO - 'MOZ_NO_REMOTE': '1', 03:36:01 INFO - 'NO_EM_RESTART': '1', 03:36:01 INFO - 'PAGER': '/bin/cat', 03:36:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:01 INFO - 'PWD': '/builds/slave/test', 03:36:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:01 INFO - 'SHELL': '/bin/bash', 03:36:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:36:01 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:36:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:01 INFO - 'USER': 'cltbld', 03:36:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:01 INFO - 'XPC_FLAGS': '0x0', 03:36:01 INFO - 'XPC_SERVICE_NAME': '0', 03:36:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:01 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)) 03:36:01 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)) 03:36:01 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)) 03:36:01 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)) 03:36:01 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)) 03:36:01 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)) 03:36:01 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)) 03:36:01 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)) 03:36:01 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)) 03:36:01 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)) 03:36:01 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)) 03:36:01 INFO - Cleaning up... 03:36:01 INFO - Return code: 0 03:36:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:36: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')]} 03:36:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:36: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')]} 03:36:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:36:01 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 0x106767b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10676b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a8d920f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, '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.rgusU3YAH1/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.eXoWAiRN4j/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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 03:36:01 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 03:36:01 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 03:36:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:36:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:01 INFO - 'HOME': '/Users/cltbld', 03:36:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:01 INFO - 'LOGNAME': 'cltbld', 03:36:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:01 INFO - 'MOZ_NO_REMOTE': '1', 03:36:01 INFO - 'NO_EM_RESTART': '1', 03:36:01 INFO - 'PAGER': '/bin/cat', 03:36:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:01 INFO - 'PWD': '/builds/slave/test', 03:36:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:01 INFO - 'SHELL': '/bin/bash', 03:36:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:36:01 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:36:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:01 INFO - 'USER': 'cltbld', 03:36:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:01 INFO - 'XPC_FLAGS': '0x0', 03:36:01 INFO - 'XPC_SERVICE_NAME': '0', 03:36:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:36:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:36:02 INFO - Cleaning up... 03:36:02 INFO - Return code: 0 03:36:02 INFO - Running post-action listener: _resource_record_post_action 03:36:02 INFO - Running post-action listener: _start_resource_monitoring 03:36:02 INFO - Starting resource monitoring. 03:36:02 INFO - [mozharness: 2017-05-17 10:36:02.142904Z] Finished create-virtualenv step (success) 03:36:02 INFO - [mozharness: 2017-05-17 10:36:02.143828Z] Running install step. 03:36:02 INFO - Running pre-action listener: _resource_record_pre_action 03:36:02 INFO - Running main action method: install 03:36:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:36:02 INFO - Reading from file tmpfile_stdout 03:36:02 INFO - Detecting whether we're running mozinstall >=1.0... 03:36:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:36:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:36:02 INFO - Reading from file tmpfile_stdout 03:36:02 INFO - Output received: 03:36:02 INFO - Usage: mozinstall [options] installer 03:36:02 INFO - Options: 03:36:02 INFO - -h, --help show this help message and exit 03:36:02 INFO - -d DEST, --destination=DEST 03:36:02 INFO - Directory to install application into. [default: 03:36:02 INFO - "/builds/slave/test"] 03:36:02 INFO - --app=APP Application being installed. [default: firefox] 03:36:02 INFO - mkdir: /builds/slave/test/build/application 03:36:02 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'] 03:36:02 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 03:36:23 INFO - Reading from file tmpfile_stdout 03:36:23 INFO - Output received: 03:36:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:36:23 INFO - Running post-action listener: _resource_record_post_action 03:36:23 INFO - [mozharness: 2017-05-17 10:36:23.046661Z] Finished install step (success) 03:36:23 INFO - [mozharness: 2017-05-17 10:36:23.046763Z] Running run-tests step. 03:36:23 INFO - Running pre-action listener: _resource_record_pre_action 03:36:23 INFO - Running main action method: run_tests 03:36:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:36:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:36:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:36:23 INFO - 2017-05-17 03:36:23.061 screenresolution[1644:7412] starting screenresolution argv=screenresolution get 03:36:23 INFO - 2017-05-17 03:36:23.063 screenresolution[1644:7412] Display 0: 1600x1200x32@60 03:36:23 INFO - 2017-05-17 03:36:23.069 screenresolution[1645:7414] starting screenresolution argv=screenresolution list 03:36:23 INFO - Available Modes on Display 0 03:36:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:36:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:36:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:36:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:36:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:36:23 INFO - Intel Iris: 03:36:23 INFO - Chipset Model: Intel Iris 03:36:23 INFO - Type: GPU 03:36:23 INFO - Bus: Built-In 03:36:23 INFO - VRAM (Dynamic, Max): 1536 MB 03:36:23 INFO - Vendor: Intel (0x8086) 03:36:23 INFO - Device ID: 0x0a2e 03:36:23 INFO - Revision ID: 0x0009 03:36:23 INFO - Displays: 03:36:23 INFO - Display: 03:36:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:36:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:36:23 INFO - Display Serial Number: 1600x1200 60 03:36:23 INFO - Main Display: Yes 03:36:23 INFO - Mirror: Off 03:36:23 INFO - Online: Yes 03:36:23 INFO - Rotation: Supported 03:36:23 INFO - Return code: 0 03:36:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:36:23 INFO - Python 2.7.3 03:36:23 INFO - Return code: 0 03:36:23 INFO - grabbing minidump binary from tooltool 03:36: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')]} 03:36:23 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 0x106477d30>, '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 0x10642bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10646cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:36:23 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 03:36:23 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 03:36:23 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 03:36:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:36:23 INFO - Return code: 0 03:36:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:36:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:36:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:36:23 INFO - ENV: RUST_BACKTRACE is now 1 03:36:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:36:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:36:23 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/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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 03:36:23 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/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0056 --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 03:36:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rgusU3YAH1/Render', 03:36:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:36:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:36:23 INFO - 'HOME': '/Users/cltbld', 03:36:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:36:23 INFO - 'LOGNAME': 'cltbld', 03:36:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:36:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:36:23 INFO - 'MOZ_NO_REMOTE': '1', 03:36:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:36:23 INFO - 'NO_EM_RESTART': '1', 03:36:23 INFO - 'PAGER': '/bin/cat', 03:36:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:36:23 INFO - 'PWD': '/builds/slave/test', 03:36:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:36:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:36:23 INFO - 'RUST_BACKTRACE': '1', 03:36:23 INFO - 'SHELL': '/bin/bash', 03:36:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eXoWAiRN4j/Listeners', 03:36:23 INFO - 'TMPDIR': '/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/', 03:36:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:36:23 INFO - 'USER': 'cltbld', 03:36:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:36:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:36:23 INFO - 'XPC_FLAGS': '0x0', 03:36:23 INFO - 'XPC_SERVICE_NAME': '0', 03:36:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:36:23 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/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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 03:36:23 INFO - mozversion application_buildid: 20170517021745 03:36:23 INFO - mozversion application_changeset: 95990be385ca8331c466ba5f174794771955751e 03:36:23 INFO - mozversion application_display_name: Nightly 03:36:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:36:23 INFO - mozversion application_name: Firefox 03:36:23 INFO - mozversion application_remotingname: firefox 03:36:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:36:23 INFO - mozversion application_vendor: Mozilla 03:36:23 INFO - mozversion application_version: 55.0a1 03:36:23 INFO - mozversion platform_buildid: 20170517021745 03:36:23 INFO - mozversion platform_changeset: 95990be385ca8331c466ba5f174794771955751e 03:36:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:36:23 INFO - mozversion platform_version: 55.0a1 03:36:23 INFO - using testdate: 1495017383 03:36:23 INFO - actual date: 1495017383 03:36:23 INFO - starting webserver on 'localhost:49224' 03:36:23 INFO - SUITE-START | Running 2 tests 03:36:23 INFO - TEST-START | dromaeo_css 03:36:23 INFO - operating with platform_type : mac_ 03:36:23 INFO - Initialising browser for dromaeo_css test... 03:36:23 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/tmpjuRdrm/profile http://localhost:49224/getInfo.html) 03:36:24 INFO - PID 1655 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 03:36:26 INFO - PID 1655 | 2017-05-17 03:36:26.079 plugin-container[1657:7596] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494b, name = 'com.apple.tsm.portname' 03:36:26 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:26 INFO - PID 1655 | 2017-05-17 03:36:26.082 plugin-container[1657:7596] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 03:36:26 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:26 INFO - PID 1655 | 2017-05-17 03:36:26.082 plugin-container[1657:7596] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:36:26 INFO - PID 1655 | __metrics Screen width/height:1600/1200 03:36:26 INFO - PID 1655 | colorDepth:24 03:36:26 INFO - PID 1655 | Browser inner width/height: 1024/691 03:36:26 INFO - PID 1655 | __metrics 03:36:26 INFO - PID 1655 | 2017-05-17 03:36:26.768 plugin-container[1658:7656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 03:36:26 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:26 INFO - PID 1655 | 2017-05-17 03:36:26.769 plugin-container[1658:7656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960f, name = 'com.apple.CFPasteboardClient' 03:36:26 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:26 INFO - PID 1655 | 2017-05-17 03:36:26.769 plugin-container[1658:7656] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:36:27 INFO - TEST-INFO | 1655: exit 0 03:36:27 INFO - Browser initialized. 03:36:27 INFO - Running cycle 1/1 for dromaeo_css test... 03:36:27 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/tmpjuRdrm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:36:29 INFO - PID 1659 | 2017-05-17 03:36:29.104 plugin-container[1660:7752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 03:36:29 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:29 INFO - PID 1659 | 2017-05-17 03:36:29.104 plugin-container[1660:7752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 03:36:29 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:29 INFO - PID 1659 | 2017-05-17 03:36:29.104 plugin-container[1660:7752] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:36:29 INFO - PID 1659 | 2017-05-17 03:36:29.806 plugin-container[1661:7799] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 03:36:29 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:29 INFO - PID 1659 | 2017-05-17 03:36:29.806 plugin-container[1661:7799] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9513, name = 'com.apple.CFPasteboardClient' 03:36:29 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:29 INFO - PID 1659 | 2017-05-17 03:36:29.806 plugin-container[1661:7799] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:36:39 INFO - PID 1659 | RSS: Main: 234872832 03:36:39 INFO - PID 1659 | 03:39:42 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 03:39:44 INFO - PID 1659 | RSS: Main: 256651264 03:39:44 INFO - PID 1659 | 03:42:46 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 03:42:46 INFO - PID 1659 | RSS: Main: 264957952 03:42:46 INFO - PID 1659 | 03:45:48 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 03:45:49 INFO - PID 1659 | RSS: Main: 268537856 03:45:49 INFO - PID 1659 | 03:48:50 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 03:48:51 INFO - PID 1659 | RSS: Main: 266858496 03:48:51 INFO - PID 1659 | 03:51:54 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 03:51:54 INFO - PID 1659 | RSS: Main: 268390400 03:51:54 INFO - PID 1659 | 03:54:56 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 03:54:56 INFO - PID 1659 | RSS: Main: 269594624 03:54:56 INFO - PID 1659 | 03:54:56 INFO - PID 1659 | __start_tp_report 03:54:56 INFO - PID 1659 | _x_x_mozilla_page_load 03:54:56 INFO - PID 1659 | _x_x_mozilla_page_load_details 03:54:56 INFO - PID 1659 | |i|pagename|runs| 03:54:56 INFO - PID 1659 | |0;dojo.html;3538.7169026477877;3553.3603951288756;3610.783352998819;3623.239119784844;3626.184108575569;6126.395534290266;6168.876622467548;6246.09431632413;6250.156228909096;6259.812205633827;6228.750849966007;6241.407066328691;6244.344343843899;6256.124142620037;6280.246370435542;4466.9329960050545;5463;5489.4236105208975;5575.637583557076;5643.633163844337;4908.582770464514;5540.445955404468;5585.162225666142;5617.157426386034;5664.886702265952;5554.166874968747;5559.416261292547;5604.3835178130375;5617.129344951539;5670.8015219467325;5401.540869026137;5495.313085864267;5495.587830912657;5545.611807173499;5635.6618602883955;4193.685473589493;5454.072807622713;5484.61607687462;5523.7790488380415;5524.088525393306;4613.746243956581;6234.906476402857;6252.781152659635;6307.905381419282;6312.863684536782;6421.871562568769;6715.02632118345;6762.120924279812;6764.3573860483175;6779.15260592426;6682.064510968468;6764.763233286861;6854;6868.038474613558;6882.483813713991;6311.179546658905;6313.810585682437;6315;6343.55595108338;6363.140975968277;6823.283555226729;6978.685959131852;6993.965030174817;7005.1944026436595;7006.75476358325;6387.584806987624;6511.772087976898;6537.869242615121;6558.934410655927;6617.669116544153;6806.8638627228165;6829.624370659562;6841.521093523505;6857.177138743283;6869.072675188786;6420.775272865428;6502.154719886385;6603.76886808969;6753.324667533306;6771.661416929134;6757.391834734897;6764.72941082361;6774.322567743285;6787.321267873173;6800.557963732441;5258.579313654898;5331.4401987790725;5336.359636843604;5356.705381204071;5358.517733404013;4767.141914455362;4769.499202583748;4788.401449818773;4821.686590371684;4853.514648535118;5799.39106393831;6327.3988971047675;6343.809685709436;6348;6377.075324077982;6608.173978252718;6698.961660942562;6728.024436456686;6804.65976701163;6815.897761533581;6280.963640999184;6339.0808332791485;6350.714217860207;6360.236771587439;6365.681715914186;5928.229330187134;5956.255468066492;5971.044632858721;5978;6034.275886893601;6763.357481039294;6817.147856517935;6937.098177237028;6944.097267355413;6997.195322537973;5821.505172060253;6074.331823499323;6456.806295811133;6649.168853893263;6728.529002969941;6946.16646002483;6969.1985421677155;6984.685689144102;6985.1617805863625;6987.091678081817;6451.774187903401;6637.668116594247;6667.166604174478;6712.630805305755;6714.227863795726;5648.604597678287;6768.4585233182215;6773.89839152423;6821.3519715626935;6862.588244705334;6610.636414997029;6611.471082313499;6623.735050597922;6623.966880165568;6627.966860165861;5675.205471234113;6316.336784637547;6449.097126402214;6528.706208220547;6636.402723754885;6320.968395158179;6347.873042539216;6401.679916004275;6442.162518872517;6476.70854811544;6572.441342485942;6579.046038324512;6585.012248162814;6589.967050164911;6604.207495100619;6225.968870155621;6409;6426.260979987174;6440;6443.6455994920725 03:54:56 INFO - PID 1659 | |1;ext.html;784.5488843914746;814.9999999999999;840.1262686805725;1225.5710501324531;1295.9805602915965;37254.813725931366;38354.04114897129;38379.80810095949;38459.23081538368;38640.80679596605;31164.68835311646;34096.31807363851;38248.85253442394;38617.03457413559;38779.41830872532;7613;7789.026371703537;7932.524048557077;7955.602219889011;8183.836323273531;8322.708705195319;8343.415960882741;8417.494950302971;8419.410641255115;8450.02824675164;8371.790705232388;8389.07720150786;8410.201030902055;8437.620307086165;8443.15568443157;8346.374021948379;8377.45546539473;8380.8742868857;8382.203690649409;8391.62237699305;8205.71280005203;8266.793330166794;8276.544790036545;8276.875846862302;8283.58582070894;36257;36299.274014519826;36442.45336319957;36467.63532364669;36554.63445365539;3595.388783906742;3613.313470440608;3615.4215325547784;3636.072801435617;3639.7088232941564;22164.335069947927;29716.405671886656;29806.25484362908;29904;30010.399792004257;2861.370498490329;2876.036527763202;2896.319364949235;2899.1012786036395;2914;298266;304166.4791676027;304262;304528;304875.9999999978;43224.351634725506;46319.76840115778;46731.76634116808;46776.2983555247;46917.82705432391;48671;48701;48726;48738;48821.26768098481;11787.646370608896;13648.044636875324;13746.312684365941;13746.862531374558;13761.96785241111;9546.570404331822;11104;11290.491927863102;11293.209475336647;11296.03983661398;13226.669333266553;13228.552717747056;13263.270520121292;13264.336783160803;13267.535636252685;5930.614510056833;5940.257467816523;5969.582129250997;5993.400659933972;5995.760169593167;996.9052939970691;1012.4229189361993;1015.4770293298957;1046.3198920701604;1046.6075221791828;19895.80625162495;21601.783982159977;22948.082076716746;24178.758212418;24297;1085.913126949858;1090.1932569898217;1097.012688580284;1111.7220694826294;1127.9436028198722;9356.812863742824;11653.941730291464;12068.939655301667;12199.451024703909;12207.877921220852;317534;318766.62466751;319124;319253;320152;51988.48011519912;52124;52289.95420091577;52323;52386;1482.495951376537;1533.5016119761121;1536.1167328785994;1538.6461113943856;1565.0766048031717;23382.64926026145;23447.94484248179;23483.647745284175;23569.882150589136;23590.17434389857;37156.628433716396;37181;37236.81381593075;37237.6276237245;37245;260708.6964565165;263945;264426;264505;264748;33416;33625.49561756527;34099;35331.29337413289;35660.821695891354;41929.37105943474;43544.91137721582;43913.560864392224;44086.11827763363;44160.33759493675;29103.85448072746;29803.85098074496;30101.849490752407;30864.845675771478;31057.534136988415;22907.31278061661;30543.847280763453;30777.537257098043;31120.688793112684;31387.529187061755;36658.8167059163;37034;37064;37066.8146659265;37112.81443592765;28551.857240713663;28918.7108128916;30945.690543094282;31114.444277785566;31274.90537831256;23492.6476102862;24142.39644008914;24232.394190145387;24358.51282974366;24596.385090372885 03:54:56 INFO - PID 1659 | |2;jquery.html;426.6330955378376;434.26175501647185;435.6188335206694;447.3200734882978;454.65446260841753;18349.357772477983;20099.59800803983;20628.381148565528;20803.791962080373;20837.895810520946;20993.265235716754;22784.31647050591;22956;23063.423414414647;23426.06295748168;6056.51547876171;6141.938580614204;6221.751129954796;6229.252489701242;6232.0028795392745;5302.92045619316;6006.219191505098;6226.470749986257;6233.594816336947;6238.719257633395;5985;6119;6186.7418392286945;6199.814005579839;6201.689915504229;6059.66671833049;6130.233720784902;6159.661218632996;6166.969165154168;6175.382461753834;4821.22860342347;5261.605379596507;5432.180219626391;5484.369297530795;5500.779968801243;23408.297751067494;27686.446271074466;27744.780417566548;28306.716932830615;28844.855775721197;2232.4195709115584;2274.7384050834034;2291.7593652666396;2293.736220334666;2296.0241897193628;14220.786688199685;17677.823221767747;18148.818511814843;18195;18632.975186364743;1992.0039980009994;2109.799569040939;2153.6015837070036;2167.729033870766;2197.1540956731615;122843;132810.33594831964;150182.9999999989;153092;153579;10564.471776411148;12528.937355313165;13089.41097650608;13090.672733181747;13123.868761312455;16833.74749378736;16848.494545163663;16857.409990650514;16903.83096169047;16920.830791692173;8880.200781929658;9028.548572571477;9099;9134.543272836332;9259.583318750672;8578.399512034097;8635.525046122524;8637.69768058115;8708.738737837748;8738.606762695694;8033.754768010851;8098.287073830215;8172.713955011546;8179.3825975687205;8190.5904704764525;1801.4775715042754;1836.2930271845435;2149.881756503376;2165.4261620670536;2341.6955795746444;896.4580920816186;897.8518544440094;931.3154831199063;936.6581197862731;975.8389865672226;20493.48766280825;21176;21192.94035298229;21240.150393984066;21253.574928501344;863.5941107679381;986.753311672082;1009.7273736091214;1022.754538910656;1023.0255457547333;10599.735006624742;10830.187735919837;10919.454027298603;10940.288881222696;10941.890581094247;479522;479993;511656;541220;546899.26550367;105241.47379263055;126536.10195846886;128582;131265;133726;1166.690826930864;1185.8814118588245;1203.205884116479;1208.3293771956648;1214.7084699672016;17838.821611783715;18451.630967380846;18457;18522.722159167355;18687.906560467112;58109.70945145247;58751.70624146852;61694.38305616887;61722;61819.690901545204;70408;75427.8685819724;75645.24354756602;75737.86393204216;75939.1654751053;8892.288616910502;9034.728958131267;9072.228860546928;9117.817643647222;9220.769480762767;13820.097779466023;13853.16880987144;13915.930420347833;14228.573142805732;14494.85505144935;11858.52565897389;11996;12009.039276858006;12210.389480526115;12283.877161228273;9715.931247562903;10144.239182061227;10674.946625266824;11007.394593297446;11267.887321126684;10330.741731456474;11817.940910295394;12145.878541214828;12281.631551053126;12285.385730713608;7184.712611495691;8439.409241353047;9089.681861134828;9145.63417463294;9235.49204793716;10031.498425078571;11734.295942243492;11841;11949.940250298692;12037.398130093285 03:54:56 INFO - PID 1659 | |3;mootools.html;1309.9672508187296;1322.3784821134063;1535.915524646144;1537.200655659058;1543.5446543269734;11691.240069395493;12157.27056376618;12162;12257.816132758016;12501.874981250185;12049.698757531067;12165.026797856173;12337.629871103882;12343.567975120872;12357.19678220915;2341.812654987597;2509.498100379922;2588.352911772057;2608.1523504860997;2626.159628918747;2524.1417917907906;2578.729233430491;2588.805839562031;2590.883410246534;2596.9740302597015;2207.293666026872;2559.308986573624;2583.354161459635;2609.0346571768473;2615.8430494170316;2535.8605276709773;2565.6279839423273;2590.4948535035674;2595.3511622094475;2595.662563866685;2055.9383218503467;2562.320984938974;2581.664383630135;2598.311447466423;2599.6620439342955;11959.222650527774;12040.578579749754;12041.277523348626;12056.939715301367;12057.698557536043;6672.532922695414;6839.452843772487;6887.001384799226;6921.757738479179;6930.237673855871;3996.620321069523;4270.185171302184;4285.5071666758095;4350.369196466526;4396.076823866992;1194.4147367789808;1220.2922305063041;1266.3098611256887;1269.2891980490954;1270.28863836252;16479.505614831574;16569.171541423002;16602.003879767253;16624.750628740578;16932.23804928781;5614.831555053355;5750.424957504216;5801.361850196475;5937.357875745338;5939.851503712442;11823.822642660367;11937.224080434744;11992.04063674904;12005.339706316061;12010.519579216909;6700.932990670129;6700.966495167493;6704.7988560343265;6705.195376554844;6706.094677218513;6131.724072416752;6215.751369945242;6313.305536390993;6319.557630965788;6326.873462530723;3696.9075773105887;4294;4432.623227025674;4470.859930717646;4476.865694029184;1083.6423980086552;1288.1047671868148;1294.2816736711216;1307.4181989014796;1314.2311747627555;695.8838470447248;808.975730728079;835.8662613981734;841.7853447370882;846.4202021615213;6312.368763123651;6329.2721337045905;6501.772437964743;6532.6733663316645;6543.247526534415;593.7921727395463;600.2107228993859;608.14251904814;610.4749915073033;615.7475435071599;3455.6889879910427;3719.9442008369897;4218.620324170839;4494.9325760113625;4599.517050709627;11956.880431195577;14764.48324308644;14993.400263989537;15526.9223653881;15553.22233888301;12461.813072803734;15150.621234469227;15214;15223.847761522244;15339.92330038365;8557.614907329185;8565.229129378386;8707.825843483095;8804.735857924401;8805.075467075865;6823.38589526945;7054.894176587458;7125.18060423058;7256.383207427428;7266.636668166676;11405.771884562428;13364.665883352995;13663.658408539866;13842.9617778665;13883.375248113656;11269.605563805228;13965.511207107702;14167.504137355143;14181.858181418054;14467.059641123293;9340.579673914826;9567.282453815853;9592.520373981413;9604.903950960681;9628.75928101803;10626.628068017582;10861.34831910088;10951.233413660968;10957.726056848642;10982.341059536453;9201.631934722804;9285.72142835716;9345.252379809735;9503.524823758646;9514.096160864707;9000.729978100668;9071.546422678972;9085.182333590008;9168.220701240469;9192.080791920755;8292.087870334379;8358.038825534899;8485.236328730443;8485.533295668798;8588.325276571259;6100.298465676504;6215.564910456224;6333.398327158912;6341.619502829845;6353.68231588428;6211.316755156839;6258.030005349178;6423.775167869102;6424.871502570016;6453.128827608401 03:54:56 INFO - PID 1659 | |4;prototype.html;395.9742616729913;401.91683413201434;405.66735277072803;407.9877603671891;416.1302876987098;4825.010872771082;5366.168243922189;6329.620222786631;6342.207224096988;6453.967730161355;5578.9163162552495;5933;6075.756969721206;6155.569110162291;6163.41447562482;4024.5975402459812;4085.570050482322;4309.504406993189;4713.811447542094;4738.881527961811;3862.5708487859392;4734.834280800155;4764.547368000034;4764.857054288377;4770.284457331393;4541.455025396957;4650.883727906796;4673.411040246316;4713.693609915362;4725.480197178307;4658.90682186356;4666.859994200179;4681.812727490897;4688.953110468903;4704.835330763407;4168.458100446923;4173.833046678129;4187.958120418787;4189.287821070426;4195.349720793282;4820.445648750404;5025.422076461181;5661.575381846378;6017.939820601826;6030.276366835964;4225.091605304874;4414.823407063714;4621.514740952185;5189.584833213372;5592.3009623797025;5501.982133305351;6096.847578810521;6463;6594.802155935329;6623.503237257177;3973.920521589581;4159.875203743893;4519.322101684741;4714.976425117887;4765.428148622153;5832.550328861888;6675.332466753334;6769.7292108315605;6896.241413444517;6930.3762661360715;3468.3410152071024;4009.178118485762;4289.991851914798;4394.868488782938;4398.934015989763;4452.48796388413;4457.24226881431;4461.620762235181;4473.150101480708;4483.596476317082;5214.817481388133;5284.313039304942;5288.894222115613;5325.174597937326;5381.255559664521;5380.677159370451;6663.633500157441;6670.299618540081;6678.899816502756;6690.632015239208;1384.951526696581;1402.4740722229164;1403.4491886465512;1513.5761986643756;1567.5610828967908;869.799946012408;937.7561833923229;965.2953344058876;993.2103977338033;1004.3277942226111;6123.8162855114415;6153.7230824613;6178.104174894615;6200.255969283715;6208.192934918432;5697.863301262027;6513.73945042193;6610.735570577219;6867.459040155175;6887.793366199022;6080.300765411946;6107.908381374283;6124.755009799558;6132.41742034506;6146.569740118149;3176.9198472519456;3249.3501299740146;3489.842957066938;3569.232614987738;3572.821358932095;6716.294789047178;6941.68762405693;6962.860742785116;6970.581765094111;6970.605878824255;5786.913196302059;6996.475264355194;7010.298970102949;7032.859342813115;7043.436525078084;6082.300535438481;6698.129243198353;6747.797566073025;6990.965045174741;7128.503014366834;6890.345417185359;6895.55178913369;6898;6903.827404314932;6907.447404207551;5577.633479797476;6229.2524897010835;6527.869442611216;6789.524733268624;6835.179778426621;6302.180716506825;6393.776217832354;6772.93227067723;7049.929500704928;7082.752103676346;3655.5430571178604;4282.250606143974;4378.905273681579;4401.36180253855;4413.34499562664;5978.9402105980125;6649.301823308483;6857.588544687135;6936.924776659625;6945.20130185015;5560.944390556043;5878.853623543368;6639.402453779183;6657.367550082734;6659.300773418721;6362.745490180341;6526.641034742945;6633.2371777246235;6639.402453779183;6704.329567043256;6430.421262086435;6448.903266450913;6463.482921366185;6470.191226096738;6471.935280647133;5214.374275087014;5734.741936612779;6606.933930660824;6622.768203112868;6638.236602790741;6401.7119229633845;6489.961606142994;6490.707918143789;6514.1531600891585;6537.5096867734155 03:54:56 INFO - PID 1659 | |5;yui.html;831.2352635575272;832.671094917508;861.4615865084322;868.5136323658747;880.5289170293892;15323;15779;15820.288087036082;17923.910380448113;18733.437996860117;16245.431409900655;16982.83017169828;17049;17167.91416042918;17239.74140387895;841.3143288220097;1020.505055048301;1027.4554486122368;1031.9587216511331;1035.1563475767264;1028.4806172882684;1029.5521448170032;1032.4785983078534;1035.3839465518004;1036.678629624815;1054.4464156317956;1066.3388699006628;1067.8147256545228;1071.4160782373585;1072.9302595331317;1030.3766221436035;1035.4977835749655;1059.9417032063234;1063.3995836266447;1064.3135177810268;793.1671744668075;794.0193860582234;798.59271771397;798.636620337745;798.9360851131954;14295.57113286603;16021.198940053067;16146.03123812575;16184.109873956928;16311.347546098143;16659.500214993448;16824.32702691891;17141.742873856903;17340.91329543357;17453.56366090845;14076.366563504564;14197.148171109766;14250.073745206528;14474.42102315906;14479.058861174095;3529.4529347951107;3563.803990780532;3607.9278414431565;3608.981955090234;3617.439296908983;132793.33603332017;133315;134192;134625.6537434623;136366;13286.933565332305;13344.53210146883;13362.599122026355;13462.596122116352;13491.123076999964;25580.616290755654;25587.488250235263;25712.88593824979;25783.484330313288;25928;6260.655663938436;7537.2085930977555;7679.1936846631415;7752.418568607264;7867.9606601966625;3797.468354430382;3857.633524815138;3871.05159235989;3877.903052423712;3895.1041260509674;4197.769122698281;4284.764337961442;4288.571142885687;4651.465081515601;4812.590929770939;956.7703751099685;963.1524258652341;968.2012339819677;969.7187815533557;975.6536429567543;657.3098246840798;669.2404121322335;676.45545336004;683.2006552333781;693.7606525748608;11422.857714228678;11898.702532436759;12126.211796233216;12285.94294462986;12348.876511234952;590.7282649981057;651.485326591987;661.9139511863523;677.881370760115;685.807973767346;5274.525292723673;5546.27898373217;5554.611177217556;5644.209810626516;5654.6324488908085;96151.51924240474;96507.51746241224;98446;98455;98526;82584.59394904434;84617.57691211627;87963;88743;89200.5539972296;61061;79991.60004199942;80310;80814.59592701998;81241.68824805795;11302.152338574804;11521.88478115208;11602.70993225176;11636.476358563716;11701.531938722695;37693.62306376901;38419.6158038416;38538.843834685;38645.806770965966;38957.80521097376;51133;51604.74197628988;51653;51747.74126129345;52598;12261.773822618024;14464.566063018126;14539.491117810825;14635.487757928848;14813.851861481247;15900.443484477988;15932.68134637324;16076.035437873765;16088.195590220208;16145.838541614434;14712.264386780833;14721.116732996055;14926.701465970402;15024.474143404928;15126.621834454227;14242.501512447014;14690.706185876435;14760.33578488949;14819.40722371093;14879.702405952037;14070.296485175495;14267;14419.711605767616;14549.418023279373;14631.122132672073;10546.525406356579;10563.419011954416;10701.250912436366;10847.783044339227;10871.565137394417;10921.672349829518;11007.944960275147;11020.677518697486;11194.83207751868;11196.328220306808 03:54:56 INFO - PID 1659 | __end_tp_report 03:54:56 INFO - PID 1659 | __start_cc_report 03:54:56 INFO - PID 1659 | _x_x_mozilla_cycle_collect,336 03:54:56 INFO - PID 1659 | __end_cc_report 03:54:56 INFO - PID 1659 | __startTimestamp1495018496863__endTimestamp 03:54:56 INFO - PID 1659 | 03:54:56 INFO - PID 1659 | ------- Summary: start ------- 03:54:56 INFO - PID 1659 | Number of tests: 6 03:54:56 INFO - PID 1659 | 03:54:56 INFO - PID 1659 | [#0] dojo.html Cycles:165 Average:6176.61 Median:6377.08 stddev:754.09 (11.8%) stddev-sans-first:727.46 03:54:56 INFO - PID 1659 | Values: 3538.7 3553.4 3610.8 3623.2 3626.2 6126.4 6168.9 6246.1 6250.2 6259.8 6228.8 6241.4 6244.3 6256.1 6280.2 4466.9 5463.0 5489.4 5575.6 5643.6 4908.6 5540.4 5585.2 5617.2 5664.9 5554.2 5559.4 5604.4 5617.1 5670.8 5401.5 5495.3 5495.6 5545.6 5635.7 4193.7 5454.1 5484.6 5523.8 5524.1 4613.7 6234.9 6252.8 6307.9 6312.9 6421.9 6715.0 6762.1 6764.4 6779.2 6682.1 6764.8 6854.0 6868.0 6882.5 6311.2 6313.8 6315.0 6343.6 6363.1 6823.3 6978.7 6994.0 7005.2 7006.8 6387.6 6511.8 6537.9 6558.9 6617.7 6806.9 6829.6 6841.5 6857.2 6869.1 6420.8 6502.2 6603.8 6753.3 6771.7 6757.4 6764.7 6774.3 6787.3 6800.6 5258.6 5331.4 5336.4 5356.7 5358.5 4767.1 4769.5 4788.4 4821.7 4853.5 5799.4 6327.4 6343.8 6348.0 6377.1 6608.2 6699.0 6728.0 6804.7 6815.9 6281.0 6339.1 6350.7 6360.2 6365.7 5928.2 5956.3 5971.0 5978.0 6034.3 6763.4 6817.1 6937.1 6944.1 6997.2 5821.5 6074.3 6456.8 6649.2 6728.5 6946.2 6969.2 6984.7 6985.2 6987.1 6451.8 6637.7 6667.2 6712.6 6714.2 5648.6 6768.5 6773.9 6821.4 6862.6 6610.6 6611.5 6623.7 6624.0 6628.0 5675.2 6316.3 6449.1 6528.7 6636.4 6321.0 6347.9 6401.7 6442.2 6476.7 6572.4 6579.0 6585.0 6590.0 6604.2 6226.0 6409.0 6426.3 6440.0 6443.6 03:54:56 INFO - PID 1659 | 03:54:56 INFO - PID 1659 | [#1] ext.html Cycles:180 Average:44207.33 Median:33520.75 stddev:77708.20 (231.8%) stddev-sans-first:77857.79 03:54:56 INFO - PID 1659 | Values: 784.5 815.0 840.1 1225.6 1296.0 37254.8 38354.0 38379.8 38459.2 38640.8 31164.7 34096.3 38248.9 38617.0 38779.4 7613.0 7789.0 7932.5 7955.6 8183.8 8322.7 8343.4 8417.5 8419.4 8450.0 8371.8 8389.1 8410.2 8437.6 8443.2 8346.4 8377.5 8380.9 8382.2 8391.6 8205.7 8266.8 8276.5 8276.9 8283.6 36257.0 36299.3 36442.5 36467.6 36554.6 3595.4 3613.3 3615.4 3636.1 3639.7 22164.3 29716.4 29806.3 29904.0 30010.4 2861.4 2876.0 2896.3 2899.1 2914.0 298266.0 304166.5 304262.0 304528.0 304876.0 43224.4 46319.8 46731.8 46776.3 46917.8 48671.0 48701.0 48726.0 48738.0 48821.3 11787.6 13648.0 13746.3 13746.9 13762.0 9546.6 11104.0 11290.5 11293.2 11296.0 13226.7 13228.6 13263.3 13264.3 13267.5 5930.6 5940.3 5969.6 5993.4 5995.8 996.9 1012.4 1015.5 1046.3 1046.6 19895.8 21601.8 22948.1 24178.8 24297.0 1085.9 1090.2 1097.0 1111.7 1127.9 9356.8 11653.9 12068.9 12199.5 12207.9 317534.0 318766.6 319124.0 319253.0 320152.0 51988.5 52124.0 52290.0 52323.0 52386.0 1482.5 1533.5 1536.1 1538.6 1565.1 23382.6 23447.9 23483.6 23569.9 23590.2 37156.6 37181.0 37236.8 37237.6 37245.0 260708.7 263945.0 264426.0 264505.0 264748.0 33416.0 33625.5 34099.0 35331.3 35660.8 41929.4 43544.9 43913.6 44086.1 44160.3 29103.9 29803.9 30101.8 30864.8 31057.5 22907.3 30543.8 30777.5 31120.7 31387.5 36658.8 37034.0 37064.0 37066.8 37112.8 28551.9 28918.7 30945.7 31114.4 31274.9 23492.6 24142.4 24232.4 24358.5 24596.4 03:54:56 INFO - PID 1659 | 03:54:56 INFO - PID 1659 | [#2] jquery.html Cycles:180 Average:34022.58 Median:2292.75 stddev:87037.98 (3796.2%) stddev-sans-first:87245.60 03:54:56 INFO - PID 1659 | Values: 426.6 434.3 435.6 447.3 454.7 18349.4 20099.6 20628.4 20803.8 20837.9 20993.3 22784.3 22956.0 23063.4 23426.1 6056.5 6141.9 6221.8 6229.3 6232.0 5302.9 6006.2 6226.5 6233.6 6238.7 5985.0 6119.0 6186.7 6199.8 6201.7 6059.7 6130.2 6159.7 6167.0 6175.4 4821.2 5261.6 5432.2 5484.4 5500.8 23408.3 27686.4 27744.8 28306.7 28844.9 2232.4 2274.7 2291.8 2293.7 2296.0 14220.8 17677.8 18148.8 18195.0 18633.0 1992.0 2109.8 2153.6 2167.7 2197.2 122843.0 132810.3 150183.0 153092.0 153579.0 10564.5 12528.9 13089.4 13090.7 13123.9 16833.7 16848.5 16857.4 16903.8 16920.8 8880.2 9028.5 9099.0 9134.5 9259.6 8578.4 8635.5 8637.7 8708.7 8738.6 8033.8 8098.3 8172.7 8179.4 8190.6 1801.5 1836.3 2149.9 2165.4 2341.7 896.5 897.9 931.3 936.7 975.8 20493.5 21176.0 21192.9 21240.2 21253.6 863.6 986.8 1009.7 1022.8 1023.0 10599.7 10830.2 10919.5 10940.3 10941.9 479522.0 479993.0 511656.0 541220.0 546899.3 105241.5 126536.1 128582.0 131265.0 133726.0 1166.7 1185.9 1203.2 1208.3 1214.7 17838.8 18451.6 18457.0 18522.7 18687.9 58109.7 58751.7 61694.4 61722.0 61819.7 70408.0 75427.9 75645.2 75737.9 75939.2 8892.3 9034.7 9072.2 9117.8 9220.8 13820.1 13853.2 13915.9 14228.6 14494.9 11858.5 11996.0 12009.0 12210.4 12283.9 9715.9 10144.2 10674.9 11007.4 11267.9 10330.7 11817.9 12145.9 12281.6 12285.4 7184.7 8439.4 9089.7 9145.6 9235.5 10031.5 11734.3 11841.0 11949.9 12037.4 03:54:56 INFO - PID 1659 | 03:54:56 INFO - PID 1659 | [#3] mootools.html Cycles:180 Average:7211.38 Median:3576.30 stddev:4543.09 (127.0%) stddev-sans-first:4534.19 03:54:56 INFO - PID 1659 | Values: 1310.0 1322.4 1535.9 1537.2 1543.5 11691.2 12157.3 12162.0 12257.8 12501.9 12049.7 12165.0 12337.6 12343.6 12357.2 2341.8 2509.5 2588.4 2608.2 2626.2 2524.1 2578.7 2588.8 2590.9 2597.0 2207.3 2559.3 2583.4 2609.0 2615.8 2535.9 2565.6 2590.5 2595.4 2595.7 2055.9 2562.3 2581.7 2598.3 2599.7 11959.2 12040.6 12041.3 12056.9 12057.7 6672.5 6839.5 6887.0 6921.8 6930.2 3996.6 4270.2 4285.5 4350.4 4396.1 1194.4 1220.3 1266.3 1269.3 1270.3 16479.5 16569.2 16602.0 16624.8 16932.2 5614.8 5750.4 5801.4 5937.4 5939.9 11823.8 11937.2 11992.0 12005.3 12010.5 6700.9 6701.0 6704.8 6705.2 6706.1 6131.7 6215.8 6313.3 6319.6 6326.9 3696.9 4294.0 4432.6 4470.9 4476.9 1083.6 1288.1 1294.3 1307.4 1314.2 695.9 809.0 835.9 841.8 846.4 6312.4 6329.3 6501.8 6532.7 6543.2 593.8 600.2 608.1 610.5 615.7 3455.7 3719.9 4218.6 4494.9 4599.5 11956.9 14764.5 14993.4 15526.9 15553.2 12461.8 15150.6 15214.0 15223.8 15339.9 8557.6 8565.2 8707.8 8804.7 8805.1 6823.4 7054.9 7125.2 7256.4 7266.6 11405.8 13364.7 13663.7 13843.0 13883.4 11269.6 13965.5 14167.5 14181.9 14467.1 9340.6 9567.3 9592.5 9604.9 9628.8 10626.6 10861.3 10951.2 10957.7 10982.3 9201.6 9285.7 9345.3 9503.5 9514.1 9000.7 9071.5 9085.2 9168.2 9192.1 8292.1 8358.0 8485.2 8485.5 8588.3 6100.3 6215.6 6333.4 6341.6 6353.7 6211.3 6258.0 6423.8 6424.9 6453.1 03:54:56 INFO - PID 1659 | 03:54:56 INFO - PID 1659 | [#4] prototype.html Cycles:180 Average:5293.30 Median:6053.02 stddev:1678.14 (27.7%) stddev-sans-first:1642.09 03:54:56 INFO - PID 1659 | Values: 396.0 401.9 405.7 408.0 416.1 4825.0 5366.2 6329.6 6342.2 6454.0 5578.9 5933.0 6075.8 6155.6 6163.4 4024.6 4085.6 4309.5 4713.8 4738.9 3862.6 4734.8 4764.5 4764.9 4770.3 4541.5 4650.9 4673.4 4713.7 4725.5 4658.9 4666.9 4681.8 4689.0 4704.8 4168.5 4173.8 4188.0 4189.3 4195.3 4820.4 5025.4 5661.6 6017.9 6030.3 4225.1 4414.8 4621.5 5189.6 5592.3 5502.0 6096.8 6463.0 6594.8 6623.5 3973.9 4159.9 4519.3 4715.0 4765.4 5832.6 6675.3 6769.7 6896.2 6930.4 3468.3 4009.2 4290.0 4394.9 4398.9 4452.5 4457.2 4461.6 4473.2 4483.6 5214.8 5284.3 5288.9 5325.2 5381.3 5380.7 6663.6 6670.3 6678.9 6690.6 1385.0 1402.5 1403.4 1513.6 1567.6 869.8 937.8 965.3 993.2 1004.3 6123.8 6153.7 6178.1 6200.3 6208.2 5697.9 6513.7 6610.7 6867.5 6887.8 6080.3 6107.9 6124.8 6132.4 6146.6 3176.9 3249.4 3489.8 3569.2 3572.8 6716.3 6941.7 6962.9 6970.6 6970.6 5786.9 6996.5 7010.3 7032.9 7043.4 6082.3 6698.1 6747.8 6991.0 7128.5 6890.3 6895.6 6898.0 6903.8 6907.4 5577.6 6229.3 6527.9 6789.5 6835.2 6302.2 6393.8 6772.9 7049.9 7082.8 3655.5 4282.3 4378.9 4401.4 4413.3 5978.9 6649.3 6857.6 6936.9 6945.2 5560.9 5878.9 6639.4 6657.4 6659.3 6362.7 6526.6 6633.2 6639.4 6704.3 6430.4 6448.9 6463.5 6470.2 6471.9 5214.4 5734.7 6606.9 6622.8 6638.2 6401.7 6490.0 6490.7 6514.2 6537.5 03:54:56 INFO - PID 1659 | 03:54:56 INFO - PID 1659 | [#5] yui.html Cycles:180 Average:21048.08 Median:17015.92 stddev:30420.98 (178.8%) stddev-sans-first:30468.44 03:54:56 INFO - PID 1659 | Values: 831.2 832.7 861.5 868.5 880.5 15323.0 15779.0 15820.3 17923.9 18733.4 16245.4 16982.8 17049.0 17167.9 17239.7 841.3 1020.5 1027.5 1032.0 1035.2 1028.5 1029.6 1032.5 1035.4 1036.7 1054.4 1066.3 1067.8 1071.4 1072.9 1030.4 1035.5 1059.9 1063.4 1064.3 793.2 794.0 798.6 798.6 798.9 14295.6 16021.2 16146.0 16184.1 16311.3 16659.5 16824.3 17141.7 17340.9 17453.6 14076.4 14197.1 14250.1 14474.4 14479.1 3529.5 3563.8 3607.9 3609.0 3617.4 132793.3 133315.0 134192.0 134625.7 136366.0 13286.9 13344.5 13362.6 13462.6 13491.1 25580.6 25587.5 25712.9 25783.5 25928.0 6260.7 7537.2 7679.2 7752.4 7868.0 3797.5 3857.6 3871.1 3877.9 3895.1 4197.8 4284.8 4288.6 4651.5 4812.6 956.8 963.2 968.2 969.7 975.7 657.3 669.2 676.5 683.2 693.8 11422.9 11898.7 12126.2 12285.9 12348.9 590.7 651.5 661.9 677.9 685.8 5274.5 5546.3 5554.6 5644.2 5654.6 96151.5 96507.5 98446.0 98455.0 98526.0 82584.6 84617.6 87963.0 88743.0 89200.6 61061.0 79991.6 80310.0 80814.6 81241.7 11302.2 11521.9 11602.7 11636.5 11701.5 37693.6 38419.6 38538.8 38645.8 38957.8 51133.0 51604.7 51653.0 51747.7 52598.0 12261.8 14464.6 14539.5 14635.5 14813.9 15900.4 15932.7 16076.0 16088.2 16145.8 14712.3 14721.1 14926.7 15024.5 15126.6 14242.5 14690.7 14760.3 14819.4 14879.7 14070.3 14267.0 14419.7 14549.4 14631.1 10546.5 10563.4 10701.3 10847.8 10871.6 10921.7 11007.9 11020.7 11194.8 11196.3 03:54:56 INFO - PID 1659 | -------- Summary: end -------- 03:54:56 INFO - PID 1659 | 03:54:57 INFO - TEST-INFO | 1659: exit 0 03:54:57 INFO - TEST-OK | dromaeo_css | took 1113821ms 03:54:57 INFO - TEST-START | kraken 03:54:57 INFO - operating with platform_type : mac_ 03:54:57 INFO - Initialising browser for kraken test... 03:54:57 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/tmpytTvD2/profile http://localhost:49224/getInfo.html) 03:54:57 INFO - PID 1677 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 03:54:59 INFO - PID 1677 | 2017-05-17 03:54:59.889 plugin-container[1678:10176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname' 03:54:59 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:54:59 INFO - PID 1677 | 2017-05-17 03:54:59.892 plugin-container[1678:10176] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 03:54:59 INFO - PID 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:54:59 INFO - PID 1677 | 2017-05-17 03:54:59.892 plugin-container[1678:10176] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:54:59 INFO - PID 1677 | __metrics Screen width/height:1600/1200 03:54:59 INFO - PID 1677 | colorDepth:24 03:54:59 INFO - PID 1677 | Browser inner width/height: 1024/691 03:54:59 INFO - PID 1677 | __metrics 03:55:01 INFO - TEST-INFO | 1677: exit 0 03:55:01 INFO - Browser initialized. 03:55:01 INFO - Running cycle 1/1 for kraken test... 03:55:01 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/T/tmpytTvD2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:55:02 INFO - PID 1680 | 2017-05-17 03:55:02.827 plugin-container[1681:10325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 03:55:02 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:55:02 INFO - PID 1680 | 2017-05-17 03:55:02.828 plugin-container[1681:10325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 03:55:02 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:55:02 INFO - PID 1680 | 2017-05-17 03:55:02.828 plugin-container[1681:10325] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:55:03 INFO - PID 1680 | 2017-05-17 03:55:03.628 plugin-container[1682:10370] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 03:55:03 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:55:03 INFO - PID 1680 | 2017-05-17 03:55:03.628 plugin-container[1682:10370] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903b, name = 'com.apple.CFPasteboardClient' 03:55:03 INFO - PID 1680 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:55:03 INFO - PID 1680 | 2017-05-17 03:55:03.628 plugin-container[1682:10370] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:55:12 INFO - PID 1680 | RSS: Main: 235286528 03:55:12 INFO - PID 1680 | 03:55:15 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 03:55:16 INFO - PID 1680 | RSS: Main: 247803904 03:55:16 INFO - PID 1680 | 03:55:19 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 03:55:20 INFO - PID 1680 | RSS: Main: 256466944 03:55:20 INFO - PID 1680 | 03:55:23 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 03:55:24 INFO - PID 1680 | RSS: Main: 258224128 03:55:24 INFO - PID 1680 | 03:55:26 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 03:55:26 INFO - PID 1680 | RSS: Main: 259072000 03:55:26 INFO - PID 1680 | 03:55:28 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:55:29 INFO - PID 1680 | RSS: Main: 259416064 03:55:29 INFO - PID 1680 | 03:55:32 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 03:55:32 INFO - PID 1680 | RSS: Main: 265433088 03:55:32 INFO - PID 1680 | 03:55:35 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 03:55:36 INFO - PID 1680 | RSS: Main: 265515008 03:55:36 INFO - PID 1680 | 03:55:39 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 03:55:39 INFO - PID 1680 | RSS: Main: 266407936 03:55:39 INFO - PID 1680 | 03:55:41 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:55:41 INFO - PID 1680 | RSS: Main: 265318400 03:55:41 INFO - PID 1680 | 03:55:43 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:55:44 INFO - PID 1680 | RSS: Main: 264990720 03:55:44 INFO - PID 1680 | 03:55:46 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:55:47 INFO - PID 1680 | RSS: Main: 265056256 03:55:47 INFO - PID 1680 | 03:55:50 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:55:50 INFO - PID 1680 | RSS: Main: 264904704 03:55:50 INFO - PID 1680 | 03:55:54 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:55:54 INFO - PID 1680 | RSS: Main: 262569984 03:55:54 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 03:55:56 INFO - PID 1680 | RSS: Main: 264581120 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | __start_tp_report 03:55:56 INFO - PID 1680 | _x_x_mozilla_page_load 03:55:56 INFO - PID 1680 | _x_x_mozilla_page_load_details 03:55:56 INFO - PID 1680 | |i|pagename|runs| 03:55:56 INFO - PID 1680 | |0;ai-astar;102;120;114;113;120;95;158;167;168;96 03:55:56 INFO - PID 1680 | |1;audio-beat-detection;176;97;98;148;141;149;133;109;145;136 03:55:56 INFO - PID 1680 | |2;audio-dft;240;166;275;264;270;254;200;197;193;195 03:55:56 INFO - PID 1680 | |3;audio-fft;83;57;56;89;59;59;57;62;79;71 03:55:56 INFO - PID 1680 | |4;audio-oscillator;84;69;86;73;69;55;96;101;102;78 03:55:56 INFO - PID 1680 | |5;imaging-gaussian-blur;89;89;89;91;89;89;89;95;89;89 03:55:56 INFO - PID 1680 | |6;imaging-darkroom;96;97;119;95;94;95;94;95;96;97 03:55:56 INFO - PID 1680 | |7;imaging-desaturate;90;95;95;89;90;90;90;92;89;87 03:55:56 INFO - PID 1680 | |8;json-parse-financial;50;49;47;48;49;49;49;50;50;49 03:55:56 INFO - PID 1680 | |9;json-stringify-tinderbox;55;61;56;66;65;65;74;81;51;62 03:55:56 INFO - PID 1680 | |10;stanford-crypto-aes;112;108;110;85;82;85;84;86;85;82 03:55:56 INFO - PID 1680 | |11;stanford-crypto-ccm;162;140;148;157;169;153;148;140;187;149 03:55:56 INFO - PID 1680 | |12;stanford-crypto-pbkdf2;197;174;232;210;186;200;239;239;184;180 03:55:56 INFO - PID 1680 | |13;stanford-crypto-sha256-iterative;63;82;96;98;78;70;71;67;67;70 03:55:56 INFO - PID 1680 | __end_tp_report 03:55:56 INFO - PID 1680 | __start_cc_report 03:55:56 INFO - PID 1680 | _x_x_mozilla_cycle_collect,931 03:55:56 INFO - PID 1680 | __end_cc_report 03:55:56 INFO - PID 1680 | __startTimestamp1495018556863__endTimestamp 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | ------- Summary: start ------- 03:55:56 INFO - PID 1680 | Number of tests: 14 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#0] ai-astar Cycles:10 Average:125.30 Median:162.50 stddev:28.45 (17.5%) stddev-sans-first:28.90 03:55:56 INFO - PID 1680 | Values: 102.0 120.0 114.0 113.0 120.0 95.0 158.0 167.0 168.0 96.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:148.50 stddev:25.04 (16.9%) stddev-sans-first:21.24 03:55:56 INFO - PID 1680 | Values: 176.0 97.0 98.0 148.0 141.0 149.0 133.0 109.0 145.0 136.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#2] audio-dft Cycles:10 Average:225.40 Median:247.00 stddev:39.33 (15.9%) stddev-sans-first:41.36 03:55:56 INFO - PID 1680 | Values: 240.0 166.0 275.0 264.0 270.0 254.0 200.0 197.0 193.0 195.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#3] audio-fft Cycles:10 Average:67.20 Median:66.50 stddev:12.35 (18.6%) stddev-sans-first:11.71 03:55:56 INFO - PID 1680 | Values: 83.0 57.0 56.0 89.0 59.0 59.0 57.0 62.0 79.0 71.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:75.50 stddev:15.41 (20.4%) stddev-sans-first:16.31 03:55:56 INFO - PID 1680 | Values: 84.0 69.0 86.0 73.0 69.0 55.0 96.0 101.0 102.0 78.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:89.00 stddev:1.93 (2.2%) stddev-sans-first:2.03 03:55:56 INFO - PID 1680 | Values: 89.0 89.0 89.0 91.0 89.0 89.0 89.0 95.0 89.0 89.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#6] imaging-darkroom Cycles:10 Average:97.80 Median:95.50 stddev:7.52 (7.9%) stddev-sans-first:7.95 03:55:56 INFO - PID 1680 | Values: 96.0 97.0 119.0 95.0 94.0 95.0 94.0 95.0 96.0 97.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#7] imaging-desaturate Cycles:10 Average:90.70 Median:90.00 stddev:2.58 (2.9%) stddev-sans-first:2.73 03:55:56 INFO - PID 1680 | Values: 90.0 95.0 95.0 89.0 90.0 90.0 90.0 92.0 89.0 87.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#8] json-parse-financial Cycles:10 Average:49.00 Median:49.00 stddev:0.94 (1.9%) stddev-sans-first:0.93 03:55:56 INFO - PID 1680 | Values: 50.0 49.0 47.0 48.0 49.0 49.0 49.0 50.0 50.0 49.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:65.00 stddev:8.95 (13.8%) stddev-sans-first:8.93 03:55:56 INFO - PID 1680 | Values: 55.0 61.0 56.0 66.0 65.0 65.0 74.0 81.0 51.0 62.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:84.50 stddev:12.59 (14.9%) stddev-sans-first:11.06 03:55:56 INFO - PID 1680 | Values: 112.0 108.0 110.0 85.0 82.0 85.0 84.0 86.0 85.0 82.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:155.00 stddev:14.38 (9.3%) stddev-sans-first:15.04 03:55:56 INFO - PID 1680 | Values: 162.0 140.0 148.0 157.0 169.0 153.0 148.0 140.0 187.0 149.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:204.10 Median:205.00 stddev:24.80 (12.1%) stddev-sans-first:26.17 03:55:56 INFO - PID 1680 | Values: 197.0 174.0 232.0 210.0 186.0 200.0 239.0 239.0 184.0 180.0 03:55:56 INFO - PID 1680 | 03:55:56 INFO - PID 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.20 Median:74.50 stddev:12.25 (16.4%) stddev-sans-first:12.03 03:55:56 INFO - PID 1680 | Values: 63.0 82.0 96.0 98.0 78.0 70.0 71.0 67.0 67.0 70.0 03:55:56 INFO - PID 1680 | -------- Summary: end -------- 03:55:56 INFO - PID 1680 | 03:55:57 INFO - TEST-INFO | 1680: exit 0 03:55:57 INFO - TEST-OK | kraken | took 60205ms 03:55:57 INFO - SUITE-END | took 1174s 03:55:58 INFO - Completed test suite (00:19:34) 03:55:58 INFO - javascript benchmark 03:55:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7818.206092417435, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3538.7169026477877, 3553.3603951288756, 3610.783352998819, 3623.239119784844, 3626.184108575569, 6126.395534290266, 6168.876622467548, 6246.09431632413, 6250.156228909096, 6259.812205633827, 6228.750849966007, 6241.407066328691, 6244.344343843899, 6256.124142620037, 6280.246370435542, 4466.9329960050545, 5463.0, 5489.4236105208975, 5575.637583557076, 5643.633163844337, 4908.582770464514, 5540.445955404468, 5585.162225666142, 5617.157426386034, 5664.886702265952, 5554.166874968747, 5559.416261292547, 5604.3835178130375, 5617.129344951539, 5670.8015219467325, 5401.540869026137, 5495.313085864267, 5495.587830912657, 5545.611807173499, 5635.6618602883955, 4193.685473589493, 5454.072807622713, 5484.61607687462, 5523.7790488380415, 5524.088525393306, 4613.746243956581, 6234.906476402857, 6252.781152659635, 6307.905381419282, 6312.863684536782, 6421.871562568769, 6715.02632118345, 6762.120924279812, 6764.3573860483175, 6779.15260592426, 6682.064510968468, 6764.763233286861, 6854.0, 6868.038474613558, 6882.483813713991, 6311.179546658905, 6313.810585682437, 6315.0, 6343.55595108338, 6363.140975968277, 6823.283555226729, 6978.685959131852, 6993.965030174817, 7005.1944026436595, 7006.75476358325, 6387.584806987624, 6511.772087976898, 6537.869242615121, 6558.934410655927, 6617.669116544153, 6806.8638627228165, 6829.624370659562, 6841.521093523505, 6857.177138743283, 6869.072675188786, 6420.775272865428, 6502.154719886385, 6603.76886808969, 6753.324667533306, 6771.661416929134, 6757.391834734897, 6764.72941082361, 6774.322567743285, 6787.321267873173, 6800.557963732441, 5258.579313654898, 5331.4401987790725, 5336.359636843604, 5356.705381204071, 5358.517733404013, 4767.141914455362, 4769.499202583748, 4788.401449818773, 4821.686590371684, 4853.514648535118, 5799.39106393831, 6327.3988971047675, 6343.809685709436, 6348.0, 6377.075324077982, 6608.173978252718, 6698.961660942562, 6728.024436456686, 6804.65976701163, 6815.897761533581, 6280.963640999184, 6339.0808332791485, 6350.714217860207, 6360.236771587439, 6365.681715914186, 5928.229330187134, 5956.255468066492, 5971.044632858721, 5978.0, 6034.275886893601, 6763.357481039294, 6817.147856517935, 6937.098177237028, 6944.097267355413, 6997.195322537973, 5821.505172060253, 6074.331823499323, 6456.806295811133, 6649.168853893263, 6728.529002969941, 6946.16646002483, 6969.1985421677155, 6984.685689144102, 6985.1617805863625, 6987.091678081817, 6451.774187903401, 6637.668116594247, 6667.166604174478, 6712.630805305755, 6714.227863795726, 5648.604597678287, 6768.4585233182215, 6773.89839152423, 6821.3519715626935, 6862.588244705334, 6610.636414997029, 6611.471082313499, 6623.735050597922, 6623.966880165568, 6627.966860165861, 5675.205471234113, 6316.336784637547, 6449.097126402214, 6528.706208220547, 6636.402723754885, 6320.968395158179, 6347.873042539216, 6401.679916004275, 6442.162518872517, 6476.70854811544, 6572.441342485942, 6579.046038324512, 6585.012248162814, 6589.967050164911, 6604.207495100619, 6225.968870155621, 6409.0, 6426.260979987174, 6440.0, 6443.6455994920725], "value": 6127.214865301964, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.5488843914746, 814.9999999999999, 840.1262686805725, 1225.5710501324531, 1295.9805602915965, 37254.813725931366, 38354.04114897129, 38379.80810095949, 38459.23081538368, 38640.80679596605, 31164.68835311646, 34096.31807363851, 38248.85253442394, 38617.03457413559, 38779.41830872532, 7613.0, 7789.026371703537, 7932.524048557077, 7955.602219889011, 8183.836323273531, 8322.708705195319, 8343.415960882741, 8417.494950302971, 8419.410641255115, 8450.02824675164, 8371.790705232388, 8389.07720150786, 8410.201030902055, 8437.620307086165, 8443.15568443157, 8346.374021948379, 8377.45546539473, 8380.8742868857, 8382.203690649409, 8391.62237699305, 8205.71280005203, 8266.793330166794, 8276.544790036545, 8276.875846862302, 8283.58582070894, 36257.0, 36299.274014519826, 36442.45336319957, 36467.63532364669, 36554.63445365539, 3595.388783906742, 3613.313470440608, 3615.4215325547784, 3636.072801435617, 3639.7088232941564, 22164.335069947927, 29716.405671886656, 29806.25484362908, 29904.0, 30010.399792004257, 2861.370498490329, 2876.036527763202, 2896.319364949235, 2899.1012786036395, 2914.0, 298266.0, 304166.4791676027, 304262.0, 304528.0, 304875.9999999978, 43224.351634725506, 46319.76840115778, 46731.76634116808, 46776.2983555247, 46917.82705432391, 48671.0, 48701.0, 48726.0, 48738.0, 48821.26768098481, 11787.646370608896, 13648.044636875324, 13746.312684365941, 13746.862531374558, 13761.96785241111, 9546.570404331822, 11104.0, 11290.491927863102, 11293.209475336647, 11296.03983661398, 13226.669333266553, 13228.552717747056, 13263.270520121292, 13264.336783160803, 13267.535636252685, 5930.614510056833, 5940.257467816523, 5969.582129250997, 5993.400659933972, 5995.760169593167, 996.9052939970691, 1012.4229189361993, 1015.4770293298957, 1046.3198920701604, 1046.6075221791828, 19895.80625162495, 21601.783982159977, 22948.082076716746, 24178.758212418, 24297.0, 1085.913126949858, 1090.1932569898217, 1097.012688580284, 1111.7220694826294, 1127.9436028198722, 9356.812863742824, 11653.941730291464, 12068.939655301667, 12199.451024703909, 12207.877921220852, 317534.0, 318766.62466751, 319124.0, 319253.0, 320152.0, 51988.48011519912, 52124.0, 52289.95420091577, 52323.0, 52386.0, 1482.495951376537, 1533.5016119761121, 1536.1167328785994, 1538.6461113943856, 1565.0766048031717, 23382.64926026145, 23447.94484248179, 23483.647745284175, 23569.882150589136, 23590.17434389857, 37156.628433716396, 37181.0, 37236.81381593075, 37237.6276237245, 37245.0, 260708.6964565165, 263945.0, 264426.0, 264505.0, 264748.0, 33416.0, 33625.49561756527, 34099.0, 35331.29337413289, 35660.821695891354, 41929.37105943474, 43544.91137721582, 43913.560864392224, 44086.11827763363, 44160.33759493675, 29103.85448072746, 29803.85098074496, 30101.849490752407, 30864.845675771478, 31057.534136988415, 22907.31278061661, 30543.847280763453, 30777.537257098043, 31120.688793112684, 31387.529187061755, 36658.8167059163, 37034.0, 37064.0, 37066.8146659265, 37112.81443592765, 28551.857240713663, 28918.7108128916, 30945.690543094282, 31114.444277785566, 31274.90537831256, 23492.6476102862, 24142.39644008914, 24232.394190145387, 24358.51282974366, 24596.385090372885], "value": 17157.844411717677, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.6330955378376, 434.26175501647185, 435.6188335206694, 447.3200734882978, 454.65446260841753, 18349.357772477983, 20099.59800803983, 20628.381148565528, 20803.791962080373, 20837.895810520946, 20993.265235716754, 22784.31647050591, 22956.0, 23063.423414414647, 23426.06295748168, 6056.51547876171, 6141.938580614204, 6221.751129954796, 6229.252489701242, 6232.0028795392745, 5302.92045619316, 6006.219191505098, 6226.470749986257, 6233.594816336947, 6238.719257633395, 5985.0, 6119.0, 6186.7418392286945, 6199.814005579839, 6201.689915504229, 6059.66671833049, 6130.233720784902, 6159.661218632996, 6166.969165154168, 6175.382461753834, 4821.22860342347, 5261.605379596507, 5432.180219626391, 5484.369297530795, 5500.779968801243, 23408.297751067494, 27686.446271074466, 27744.780417566548, 28306.716932830615, 28844.855775721197, 2232.4195709115584, 2274.7384050834034, 2291.7593652666396, 2293.736220334666, 2296.0241897193628, 14220.786688199685, 17677.823221767747, 18148.818511814843, 18195.0, 18632.975186364743, 1992.0039980009994, 2109.799569040939, 2153.6015837070036, 2167.729033870766, 2197.1540956731615, 122843.0, 132810.33594831964, 150182.9999999989, 153092.0, 153579.0, 10564.471776411148, 12528.937355313165, 13089.41097650608, 13090.672733181747, 13123.868761312455, 16833.74749378736, 16848.494545163663, 16857.409990650514, 16903.83096169047, 16920.830791692173, 8880.200781929658, 9028.548572571477, 9099.0, 9134.543272836332, 9259.583318750672, 8578.399512034097, 8635.525046122524, 8637.69768058115, 8708.738737837748, 8738.606762695694, 8033.754768010851, 8098.287073830215, 8172.713955011546, 8179.3825975687205, 8190.5904704764525, 1801.4775715042754, 1836.2930271845435, 2149.881756503376, 2165.4261620670536, 2341.6955795746444, 896.4580920816186, 897.8518544440094, 931.3154831199063, 936.6581197862731, 975.8389865672226, 20493.48766280825, 21176.0, 21192.94035298229, 21240.150393984066, 21253.574928501344, 863.5941107679381, 986.753311672082, 1009.7273736091214, 1022.754538910656, 1023.0255457547333, 10599.735006624742, 10830.187735919837, 10919.454027298603, 10940.288881222696, 10941.890581094247, 479522.0, 479993.0, 511656.0, 541220.0, 546899.26550367, 105241.47379263055, 126536.10195846886, 128582.0, 131265.0, 133726.0, 1166.690826930864, 1185.8814118588245, 1203.205884116479, 1208.3293771956648, 1214.7084699672016, 17838.821611783715, 18451.630967380846, 18457.0, 18522.722159167355, 18687.906560467112, 58109.70945145247, 58751.70624146852, 61694.38305616887, 61722.0, 61819.690901545204, 70408.0, 75427.8685819724, 75645.24354756602, 75737.86393204216, 75939.1654751053, 8892.288616910502, 9034.728958131267, 9072.228860546928, 9117.817643647222, 9220.769480762767, 13820.097779466023, 13853.16880987144, 13915.930420347833, 14228.573142805732, 14494.85505144935, 11858.52565897389, 11996.0, 12009.039276858006, 12210.389480526115, 12283.877161228273, 9715.931247562903, 10144.239182061227, 10674.946625266824, 11007.394593297446, 11267.887321126684, 10330.741731456474, 11817.940910295394, 12145.878541214828, 12281.631551053126, 12285.385730713608, 7184.712611495691, 8439.409241353047, 9089.681861134828, 9145.63417463294, 9235.49204793716, 10031.498425078571, 11734.295942243492, 11841.0, 11949.940250298692, 12037.398130093285], "value": 10344.5050737034, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.9672508187296, 1322.3784821134063, 1535.915524646144, 1537.200655659058, 1543.5446543269734, 11691.240069395493, 12157.27056376618, 12162.0, 12257.816132758016, 12501.874981250185, 12049.698757531067, 12165.026797856173, 12337.629871103882, 12343.567975120872, 12357.19678220915, 2341.812654987597, 2509.498100379922, 2588.352911772057, 2608.1523504860997, 2626.159628918747, 2524.1417917907906, 2578.729233430491, 2588.805839562031, 2590.883410246534, 2596.9740302597015, 2207.293666026872, 2559.308986573624, 2583.354161459635, 2609.0346571768473, 2615.8430494170316, 2535.8605276709773, 2565.6279839423273, 2590.4948535035674, 2595.3511622094475, 2595.662563866685, 2055.9383218503467, 2562.320984938974, 2581.664383630135, 2598.311447466423, 2599.6620439342955, 11959.222650527774, 12040.578579749754, 12041.277523348626, 12056.939715301367, 12057.698557536043, 6672.532922695414, 6839.452843772487, 6887.001384799226, 6921.757738479179, 6930.237673855871, 3996.620321069523, 4270.185171302184, 4285.5071666758095, 4350.369196466526, 4396.076823866992, 1194.4147367789808, 1220.2922305063041, 1266.3098611256887, 1269.2891980490954, 1270.28863836252, 16479.505614831574, 16569.171541423002, 16602.003879767253, 16624.750628740578, 16932.23804928781, 5614.831555053355, 5750.424957504216, 5801.361850196475, 5937.357875745338, 5939.851503712442, 11823.822642660367, 11937.224080434744, 11992.04063674904, 12005.339706316061, 12010.519579216909, 6700.932990670129, 6700.966495167493, 6704.7988560343265, 6705.195376554844, 6706.094677218513, 6131.724072416752, 6215.751369945242, 6313.305536390993, 6319.557630965788, 6326.873462530723, 3696.9075773105887, 4294.0, 4432.623227025674, 4470.859930717646, 4476.865694029184, 1083.6423980086552, 1288.1047671868148, 1294.2816736711216, 1307.4181989014796, 1314.2311747627555, 695.8838470447248, 808.975730728079, 835.8662613981734, 841.7853447370882, 846.4202021615213, 6312.368763123651, 6329.2721337045905, 6501.772437964743, 6532.6733663316645, 6543.247526534415, 593.7921727395463, 600.2107228993859, 608.14251904814, 610.4749915073033, 615.7475435071599, 3455.6889879910427, 3719.9442008369897, 4218.620324170839, 4494.9325760113625, 4599.517050709627, 11956.880431195577, 14764.48324308644, 14993.400263989537, 15526.9223653881, 15553.22233888301, 12461.813072803734, 15150.621234469227, 15214.0, 15223.847761522244, 15339.92330038365, 8557.614907329185, 8565.229129378386, 8707.825843483095, 8804.735857924401, 8805.075467075865, 6823.38589526945, 7054.894176587458, 7125.18060423058, 7256.383207427428, 7266.636668166676, 11405.771884562428, 13364.665883352995, 13663.658408539866, 13842.9617778665, 13883.375248113656, 11269.605563805228, 13965.511207107702, 14167.504137355143, 14181.858181418054, 14467.059641123293, 9340.579673914826, 9567.282453815853, 9592.520373981413, 9604.903950960681, 9628.75928101803, 10626.628068017582, 10861.34831910088, 10951.233413660968, 10957.726056848642, 10982.341059536453, 9201.631934722804, 9285.72142835716, 9345.252379809735, 9503.524823758646, 9514.096160864707, 9000.729978100668, 9071.546422678972, 9085.182333590008, 9168.220701240469, 9192.080791920755, 8292.087870334379, 8358.038825534899, 8485.236328730443, 8485.533295668798, 8588.325276571259, 6100.298465676504, 6215.564910456224, 6333.398327158912, 6341.619502829845, 6353.68231588428, 6211.316755156839, 6258.030005349178, 6423.775167869102, 6424.871502570016, 6453.128827608401], "value": 5402.347644442452, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.9742616729913, 401.91683413201434, 405.66735277072803, 407.9877603671891, 416.1302876987098, 4825.010872771082, 5366.168243922189, 6329.620222786631, 6342.207224096988, 6453.967730161355, 5578.9163162552495, 5933.0, 6075.756969721206, 6155.569110162291, 6163.41447562482, 4024.5975402459812, 4085.570050482322, 4309.504406993189, 4713.811447542094, 4738.881527961811, 3862.5708487859392, 4734.834280800155, 4764.547368000034, 4764.857054288377, 4770.284457331393, 4541.455025396957, 4650.883727906796, 4673.411040246316, 4713.693609915362, 4725.480197178307, 4658.90682186356, 4666.859994200179, 4681.812727490897, 4688.953110468903, 4704.835330763407, 4168.458100446923, 4173.833046678129, 4187.958120418787, 4189.287821070426, 4195.349720793282, 4820.445648750404, 5025.422076461181, 5661.575381846378, 6017.939820601826, 6030.276366835964, 4225.091605304874, 4414.823407063714, 4621.514740952185, 5189.584833213372, 5592.3009623797025, 5501.982133305351, 6096.847578810521, 6463.0, 6594.802155935329, 6623.503237257177, 3973.920521589581, 4159.875203743893, 4519.322101684741, 4714.976425117887, 4765.428148622153, 5832.550328861888, 6675.332466753334, 6769.7292108315605, 6896.241413444517, 6930.3762661360715, 3468.3410152071024, 4009.178118485762, 4289.991851914798, 4394.868488782938, 4398.934015989763, 4452.48796388413, 4457.24226881431, 4461.620762235181, 4473.150101480708, 4483.596476317082, 5214.817481388133, 5284.313039304942, 5288.894222115613, 5325.174597937326, 5381.255559664521, 5380.677159370451, 6663.633500157441, 6670.299618540081, 6678.899816502756, 6690.632015239208, 1384.951526696581, 1402.4740722229164, 1403.4491886465512, 1513.5761986643756, 1567.5610828967908, 869.799946012408, 937.7561833923229, 965.2953344058876, 993.2103977338033, 1004.3277942226111, 6123.8162855114415, 6153.7230824613, 6178.104174894615, 6200.255969283715, 6208.192934918432, 5697.863301262027, 6513.73945042193, 6610.735570577219, 6867.459040155175, 6887.793366199022, 6080.300765411946, 6107.908381374283, 6124.755009799558, 6132.41742034506, 6146.569740118149, 3176.9198472519456, 3249.3501299740146, 3489.842957066938, 3569.232614987738, 3572.821358932095, 6716.294789047178, 6941.68762405693, 6962.860742785116, 6970.581765094111, 6970.605878824255, 5786.913196302059, 6996.475264355194, 7010.298970102949, 7032.859342813115, 7043.436525078084, 6082.300535438481, 6698.129243198353, 6747.797566073025, 6990.965045174741, 7128.503014366834, 6890.345417185359, 6895.55178913369, 6898.0, 6903.827404314932, 6907.447404207551, 5577.633479797476, 6229.2524897010835, 6527.869442611216, 6789.524733268624, 6835.179778426621, 6302.180716506825, 6393.776217832354, 6772.93227067723, 7049.929500704928, 7082.752103676346, 3655.5430571178604, 4282.250606143974, 4378.905273681579, 4401.36180253855, 4413.34499562664, 5978.9402105980125, 6649.301823308483, 6857.588544687135, 6936.924776659625, 6945.20130185015, 5560.944390556043, 5878.853623543368, 6639.402453779183, 6657.367550082734, 6659.300773418721, 6362.745490180341, 6526.641034742945, 6633.2371777246235, 6639.402453779183, 6704.329567043256, 6430.421262086435, 6448.903266450913, 6463.482921366185, 6470.191226096738, 6471.935280647133, 5214.374275087014, 5734.741936612779, 6606.933930660824, 6622.768203112868, 6638.236602790741, 6401.7119229633845, 6489.961606142994, 6490.707918143789, 6514.1531600891585, 6537.5096867734155], "value": 4765.706125161259, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.2352635575272, 832.671094917508, 861.4615865084322, 868.5136323658747, 880.5289170293892, 15323.0, 15779.0, 15820.288087036082, 17923.910380448113, 18733.437996860117, 16245.431409900655, 16982.83017169828, 17049.0, 17167.91416042918, 17239.74140387895, 841.3143288220097, 1020.505055048301, 1027.4554486122368, 1031.9587216511331, 1035.1563475767264, 1028.4806172882684, 1029.5521448170032, 1032.4785983078534, 1035.3839465518004, 1036.678629624815, 1054.4464156317956, 1066.3388699006628, 1067.8147256545228, 1071.4160782373585, 1072.9302595331317, 1030.3766221436035, 1035.4977835749655, 1059.9417032063234, 1063.3995836266447, 1064.3135177810268, 793.1671744668075, 794.0193860582234, 798.59271771397, 798.636620337745, 798.9360851131954, 14295.57113286603, 16021.198940053067, 16146.03123812575, 16184.109873956928, 16311.347546098143, 16659.500214993448, 16824.32702691891, 17141.742873856903, 17340.91329543357, 17453.56366090845, 14076.366563504564, 14197.148171109766, 14250.073745206528, 14474.42102315906, 14479.058861174095, 3529.4529347951107, 3563.803990780532, 3607.9278414431565, 3608.981955090234, 3617.439296908983, 132793.33603332017, 133315.0, 134192.0, 134625.6537434623, 136366.0, 13286.933565332305, 13344.53210146883, 13362.599122026355, 13462.596122116352, 13491.123076999964, 25580.616290755654, 25587.488250235263, 25712.88593824979, 25783.484330313288, 25928.0, 6260.655663938436, 7537.2085930977555, 7679.1936846631415, 7752.418568607264, 7867.9606601966625, 3797.468354430382, 3857.633524815138, 3871.05159235989, 3877.903052423712, 3895.1041260509674, 4197.769122698281, 4284.764337961442, 4288.571142885687, 4651.465081515601, 4812.590929770939, 956.7703751099685, 963.1524258652341, 968.2012339819677, 969.7187815533557, 975.6536429567543, 657.3098246840798, 669.2404121322335, 676.45545336004, 683.2006552333781, 693.7606525748608, 11422.857714228678, 11898.702532436759, 12126.211796233216, 12285.94294462986, 12348.876511234952, 590.7282649981057, 651.485326591987, 661.9139511863523, 677.881370760115, 685.807973767346, 5274.525292723673, 5546.27898373217, 5554.611177217556, 5644.209810626516, 5654.6324488908085, 96151.51924240474, 96507.51746241224, 98446.0, 98455.0, 98526.0, 82584.59394904434, 84617.57691211627, 87963.0, 88743.0, 89200.5539972296, 61061.0, 79991.60004199942, 80310.0, 80814.59592701998, 81241.68824805795, 11302.152338574804, 11521.88478115208, 11602.70993225176, 11636.476358563716, 11701.531938722695, 37693.62306376901, 38419.6158038416, 38538.843834685, 38645.806770965966, 38957.80521097376, 51133.0, 51604.74197628988, 51653.0, 51747.74126129345, 52598.0, 12261.773822618024, 14464.566063018126, 14539.491117810825, 14635.487757928848, 14813.851861481247, 15900.443484477988, 15932.68134637324, 16076.035437873765, 16088.195590220208, 16145.838541614434, 14712.264386780833, 14721.116732996055, 14926.701465970402, 15024.474143404928, 15126.621834454227, 14242.501512447014, 14690.706185876435, 14760.33578488949, 14819.40722371093, 14879.702405952037, 14070.296485175495, 14267.0, 14419.711605767616, 14549.418023279373, 14631.122132672073, 10546.525406356579, 10563.419011954416, 10701.250912436366, 10847.783044339227, 10871.565137394417, 10921.672349829518, 11007.944960275147, 11020.677518697486, 11194.83207751868, 11196.328220306808], "value": 8155.821413685853, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 120.0, 114.0, 113.0, 120.0, 95.0, 158.0, 167.0, 168.0, 96.0], "value": 125.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 97.0, 98.0, 148.0, 141.0, 149.0, 133.0, 109.0, 145.0, 136.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [240.0, 166.0, 275.0, 264.0, 270.0, 254.0, 200.0, 197.0, 193.0, 195.0], "value": 225.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 56.0, 89.0, 59.0, 59.0, 57.0, 62.0, 79.0, 71.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 86.0, 73.0, 69.0, 55.0, 96.0, 101.0, 102.0, 78.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 91.0, 89.0, 89.0, 89.0, 95.0, 89.0, 89.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 119.0, 95.0, 94.0, 95.0, 94.0, 95.0, 96.0, 97.0], "value": 97.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 95.0, 89.0, 90.0, 90.0, 90.0, 92.0, 89.0, 87.0], "value": 90.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 47.0, 48.0, 49.0, 49.0, 49.0, 50.0, 50.0, 49.0], "value": 49.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 61.0, 56.0, 66.0, 65.0, 65.0, 74.0, 81.0, 51.0, 62.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 110.0, 85.0, 82.0, 85.0, 84.0, 86.0, 85.0, 82.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 140.0, 148.0, 157.0, 169.0, 153.0, 148.0, 140.0, 187.0, 149.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 174.0, 232.0, 210.0, 186.0, 200.0, 239.0, 239.0, 184.0, 180.0], "value": 204.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 82.0, 96.0, 98.0, 78.0, 70.0, 71.0, 67.0, 67.0, 70.0], "value": 76.2, "unit": "score"}]}]} 03:55:58 INFO - Return code: 0 03:55:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:55:58 INFO - # TBPL SUCCESS # 03:55:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:55:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:55:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:55:58 INFO - 2017-05-17 03:55:58.197 screenresolution[1687:10759] starting screenresolution argv=screenresolution get 03:55:58 INFO - 2017-05-17 03:55:58.200 screenresolution[1687:10759] Display 0: 1600x1200x32@60 03:55:58 INFO - 2017-05-17 03:55:58.205 screenresolution[1688:10762] starting screenresolution argv=screenresolution list 03:55:58 INFO - Available Modes on Display 0 03:55:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:55:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:55:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:55:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:55:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:55:58 INFO - Intel Iris: 03:55:58 INFO - Chipset Model: Intel Iris 03:55:58 INFO - Type: GPU 03:55:58 INFO - Bus: Built-In 03:55:58 INFO - VRAM (Dynamic, Max): 1536 MB 03:55:58 INFO - Vendor: Intel (0x8086) 03:55:58 INFO - Device ID: 0x0a2e 03:55:58 INFO - Revision ID: 0x0009 03:55:58 INFO - Displays: 03:55:58 INFO - Display: 03:55:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:55:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:55:58 INFO - Display Serial Number: 1600x1200 60 03:55:58 INFO - Main Display: Yes 03:55:58 INFO - Mirror: Off 03:55:58 INFO - Online: Yes 03:55:58 INFO - Rotation: Supported 03:55:58 INFO - Return code: 0 03:55:58 INFO - Running post-action listener: _resource_record_post_action 03:55:58 INFO - [mozharness: 2017-05-17 10:55:58.450775Z] Finished run-tests step (success) 03:55:58 INFO - Running post-run listener: _resource_record_post_run 03:55:58 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 38235136; Write bytes: 532526592; Read time: 401; Write time: 1839 03:55:58 INFO - TinderboxPrint: CPU usage
54.0% 03:55:58 INFO - TinderboxPrint: I/O read bytes / time
38,235,136 / 401 03:55:58 INFO - TinderboxPrint: I/O write bytes / time
532,526,592 / 1,839 03:55:58 INFO - TinderboxPrint: CPU idle
2,197.9 (46.0%) 03:55:58 INFO - TinderboxPrint: CPU system
957.9 (20.0%) 03:55:58 INFO - TinderboxPrint: CPU user
1,625.9 (34.0%) 03:55:58 INFO - TinderboxPrint: Swap in / out
253,177,856 / 0 03:55:58 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206197248; Write bytes: 201140224; Read time: 16018; Write time: 459 03:55:58 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 37280768; Write bytes: 313777664; Read time: 387; Write time: 1322 03:55:58 INFO - Running post-run listener: _upload_blobber_files 03:55:58 INFO - Blob upload gear active. 03:55:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:55:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:55:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:55:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:55:59 INFO - (blobuploader) - INFO - Open directory for files ... 03:55:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:55:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:55:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:56:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:56:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:56:00 INFO - (blobuploader) - INFO - Done attempting. 03:56:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:56:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:56:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:56:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:56:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:56:01 INFO - (blobuploader) - INFO - Done attempting. 03:56:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:56:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:56:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:56:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:56:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:56:02 INFO - (blobuploader) - INFO - Done attempting. 03:56:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:56:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:56:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:56:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:56:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:56:04 INFO - (blobuploader) - INFO - Done attempting. 03:56:04 INFO - (blobuploader) - INFO - Iteration through files over. 03:56:04 INFO - Return code: 0 03:56:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:56:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:56:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7523f5ec3ae4b57afe111c167bf1a63fa053b9dd38f014c3fe10aae8abd8342a51b5e4a1cd037fae2f7b758f7fb9993c8b802036e6d898148597200693239d81", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1acb9e27afe0f2fc51d887b7ad0551028399ced20ee360b137cb1aac62e8ec3fbe13ae8aeabaaaed63dffacac709ec54c209dbbe2e204d5e7ee50f0962e11e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc947b544a7f96982e1d4e281a27fcd2bad8810c36b8c761581aad24a1e3cc635339e76a67f76ca2c3309d5a66845622e40aa4ef831966ed1c3704577768ef16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fadbcb8edd80ed2129b1d3d5f916625169c3bd8ecae770a1e1d0fc09d4e3896a437672fa6b66622cebf6d4b3bb8fd75ddfa9ff7ca1bf0752f5b39e39c1c0153"} 03:56:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:56:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:56:04 INFO - Contents: 03:56:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7523f5ec3ae4b57afe111c167bf1a63fa053b9dd38f014c3fe10aae8abd8342a51b5e4a1cd037fae2f7b758f7fb9993c8b802036e6d898148597200693239d81", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1acb9e27afe0f2fc51d887b7ad0551028399ced20ee360b137cb1aac62e8ec3fbe13ae8aeabaaaed63dffacac709ec54c209dbbe2e204d5e7ee50f0962e11e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc947b544a7f96982e1d4e281a27fcd2bad8810c36b8c761581aad24a1e3cc635339e76a67f76ca2c3309d5a66845622e40aa4ef831966ed1c3704577768ef16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fadbcb8edd80ed2129b1d3d5f916625169c3bd8ecae770a1e1d0fc09d4e3896a437672fa6b66622cebf6d4b3bb8fd75ddfa9ff7ca1bf0752f5b39e39c1c0153"} 03:56:04 INFO - Running post-run listener: copy_logs_to_upload_dir 03:56:04 INFO - Copying logs to upload dir... 03:56:04 INFO - mkdir: /builds/slave/test/build/upload/logs 03:56:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.373969 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-17 03:56:04.716109) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 03:56:04.720011) ========= 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.rgusU3YAH1/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.eXoWAiRN4j/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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/7523f5ec3ae4b57afe111c167bf1a63fa053b9dd38f014c3fe10aae8abd8342a51b5e4a1cd037fae2f7b758f7fb9993c8b802036e6d898148597200693239d81", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1acb9e27afe0f2fc51d887b7ad0551028399ced20ee360b137cb1aac62e8ec3fbe13ae8aeabaaaed63dffacac709ec54c209dbbe2e204d5e7ee50f0962e11e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc947b544a7f96982e1d4e281a27fcd2bad8810c36b8c761581aad24a1e3cc635339e76a67f76ca2c3309d5a66845622e40aa4ef831966ed1c3704577768ef16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fadbcb8edd80ed2129b1d3d5f916625169c3bd8ecae770a1e1d0fc09d4e3896a437672fa6b66622cebf6d4b3bb8fd75ddfa9ff7ca1bf0752f5b39e39c1c0153"} build_url:https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010503 build_url: 'https://queue.taskcluster.net/v1/task/ECRfMiMBSUiIZsdcLoVbZA/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/7523f5ec3ae4b57afe111c167bf1a63fa053b9dd38f014c3fe10aae8abd8342a51b5e4a1cd037fae2f7b758f7fb9993c8b802036e6d898148597200693239d81", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5c1acb9e27afe0f2fc51d887b7ad0551028399ced20ee360b137cb1aac62e8ec3fbe13ae8aeabaaaed63dffacac709ec54c209dbbe2e204d5e7ee50f0962e11e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc947b544a7f96982e1d4e281a27fcd2bad8810c36b8c761581aad24a1e3cc635339e76a67f76ca2c3309d5a66845622e40aa4ef831966ed1c3704577768ef16", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3fadbcb8edd80ed2129b1d3d5f916625169c3bd8ecae770a1e1d0fc09d4e3896a437672fa6b66622cebf6d4b3bb8fd75ddfa9ff7ca1bf0752f5b39e39c1c0153"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 03:56:04.752189) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 03:56:04.752510) ========= 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.rgusU3YAH1/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.eXoWAiRN4j/Listeners TMPDIR=/var/folders/7v/lyfkk85x63v4yvnywsb9kls800000w/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.005283 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 03:56:04.801607) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 03:56:04.801926) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 03:56:04.802337) ========= ========= Total master_lag: 0.17 =========