builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0340 starttime: 1495386875.0 results: success (0) revision: b31a663614e8df7876563e03c4735aa5c8a38f5a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:35.003522) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:35.004372) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:35.004866) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:35.032895) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:35.033290) ========= 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.2WbBOicuJM/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.7nXu8RyLkz/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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-21 10:14:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-05-21 10:14:35 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130896 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:35.187361) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:35.187767) ========= 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.2WbBOicuJM/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.7nXu8RyLkz/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.027090 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:35.236349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 10:14:35.236721) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b31a663614e8df7876563e03c4735aa5c8a38f5a --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 b31a663614e8df7876563e03c4735aa5c8a38f5a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2WbBOicuJM/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.7nXu8RyLkz/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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-21 10:14:35,357 truncating revision to first 12 chars 2017-05-21 10:14:35,357 Setting DEBUG logging. 2017-05-21 10:14:35,357 attempt 1/10 2017-05-21 10:14:35,357 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b31a663614e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-21 10:14:36,388 unpacking tar archive at: mozilla-central-b31a663614e8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.756961 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-21 10:14:37.016057) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:37.016391) ========= script_repo_revision: b31a663614e8df7876563e03c4735aa5c8a38f5a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:37.016766) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:37.017028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 10:14:37.034983) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-21 10:14:37.035437) ========= /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.2WbBOicuJM/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.7nXu8RyLkz/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:14:37 INFO - MultiFileLogger online at 20170521 10:14:37 in /builds/slave/test 10:14:37 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 10:14:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:14:37 INFO - {'append_to_log': False, 10:14:37 INFO - 'base_work_dir': '/builds/slave/test', 10:14:37 INFO - 'blob_upload_branch': 'Firefox', 10:14:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:14:37 INFO - 'branch': 'Firefox', 10:14:37 INFO - 'buildbot_json_path': 'buildprops.json', 10:14:37 INFO - 'config_files': ('talos/mac_config.py',), 10:14:37 INFO - 'default_actions': ('clobber', 10:14:37 INFO - 'read-buildbot-config', 10:14:37 INFO - 'download-and-extract', 10:14:37 INFO - 'populate-webroot', 10:14:37 INFO - 'create-virtualenv', 10:14:37 INFO - 'install', 10:14:37 INFO - 'run-tests'), 10:14:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:14:37 INFO - 'download_minidump_stackwalk': True, 10:14:37 INFO - 'download_symbols': 'ondemand', 10:14:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:14:37 INFO - 'tooltool.py': '/tools/tooltool.py', 10:14:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:14:37 INFO - '/tools/misc-python/virtualenv.py')}, 10:14:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:14:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:14:37 INFO - 'gecko_profile': False, 10:14:37 INFO - 'gecko_profile_interval': 0, 10:14:37 INFO - 'installer_path': 'installer.exe', 10:14:37 INFO - 'log_level': 'info', 10:14:37 INFO - 'log_name': 'talos', 10:14:37 INFO - 'log_to_console': True, 10:14:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:14:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:14:37 INFO - 'opt_config_files': (), 10:14:37 INFO - 'pip_index': False, 10:14:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:14:37 INFO - 'cmd': ('bash', 10:14:37 INFO - '-c', 10:14:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:14:37 INFO - 'enabled': True, 10:14:37 INFO - 'halt_on_failure': False, 10:14:37 INFO - 'name': 'check_screen_resolution'},), 10:14:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:14:37 INFO - 'cmd': ('bash', 10:14:37 INFO - '-c', 10:14:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:14:37 INFO - 'enabled': True, 10:14:37 INFO - 'halt_on_failure': False, 10:14:37 INFO - 'name': 'check_screen_resolution'},), 10:14:37 INFO - 'run_cmd_checks_enabled': True, 10:14:37 INFO - 'suite': 'dromaeojs-e10s', 10:14:37 INFO - 'system_bits': '32', 10:14:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:14:37 INFO - 'title': 't-yosemite-r7-0340', 10:14:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:14:37 INFO - 'use_talos_json': True, 10:14:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:14:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:14:37 INFO - 'work_dir': 'build'} 10:14:37 INFO - [mozharness: 2017-05-21 17:14:37.265160Z] Running clobber step. 10:14:37 INFO - Running pre-action listener: _resource_record_pre_action 10:14:37 INFO - Running main action method: clobber 10:14:37 INFO - rmtree: /builds/slave/test/build 10:14:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:14:39 INFO - Running post-action listener: _resource_record_post_action 10:14:39 INFO - [mozharness: 2017-05-21 17:14:39.904526Z] Finished clobber step (success) 10:14:39 INFO - [mozharness: 2017-05-21 17:14:39.904646Z] Running read-buildbot-config step. 10:14:39 INFO - Running pre-action listener: _resource_record_pre_action 10:14:39 INFO - Running main action method: read_buildbot_config 10:14:39 INFO - Using buildbot properties: 10:14:39 INFO - { 10:14:39 INFO - "project": "", 10:14:39 INFO - "product": "firefox", 10:14:39 INFO - "who": "philringnalda@gmail.com", 10:14:39 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:14:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:14:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:14:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:14:39 INFO - "stage_platform": "macosx64", 10:14:39 INFO - "basedir": "/builds/slave/test", 10:14:39 INFO - "buildnumber": 160, 10:14:39 INFO - "platform": "yosemite_r7", 10:14:39 INFO - "parent_task_id": "Vf4tUfziRTmHMR2y3JYZag", 10:14:39 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:14:39 INFO - "slavebuilddir": "test", 10:14:39 INFO - "taskId": "ftb2Q0KQTDi0b_NuCyAQHA", 10:14:39 INFO - "branch": "mozilla-central", 10:14:39 INFO - "script_repo_revision": "production", 10:14:39 INFO - "revision": "b31a663614e8df7876563e03c4735aa5c8a38f5a", 10:14:39 INFO - "slavename": "t-yosemite-r7-0340", 10:14:39 INFO - "repo_path": "mozilla-central" 10:14:39 INFO - } 10:14:39 INFO - Finding installer, test and symbols from parent task. 10:14:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ftb2Q0KQTDi0b_NuCyAQHA'}, attempt #1 10:14:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ftb2Q0KQTDi0b_NuCyAQHA'}, attempt #1 10:14:41 INFO - Task dependencies: V6VzMiYXQxGc0N5oDL0JgA Vf4tUfziRTmHMR2y3JYZag 10:14:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag'}, attempt #1 10:14:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:14:41 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/test_packages.json 10:14:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/target.crashreporter-symbols.zip 10:14:41 INFO - Running post-action listener: _resource_record_post_action 10:14:41 INFO - [mozharness: 2017-05-21 17:14:41.662267Z] Finished read-buildbot-config step (success) 10:14:41 INFO - [mozharness: 2017-05-21 17:14:41.662405Z] Running download-and-extract step. 10:14:41 INFO - Running pre-action listener: _resource_record_pre_action 10:14:41 INFO - Running main action method: download_and_extract 10:14:41 INFO - mkdir: /builds/slave/test/build/tests 10:14:41 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')]} 10:14:41 INFO - trying https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:14:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/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 10:14:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/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 10:14:42 INFO - Downloaded 1325 bytes. 10:14:42 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:14:42 INFO - Using the following test package requirements: 10:14:42 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:14:42 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:14:42 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:14:42 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:14:42 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:14:42 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:14:42 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:14:42 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:14:42 INFO - u'jsshell-mac.zip'], 10:14:42 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:14:42 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:14:42 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:14:42 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:14:42 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:14:42 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:14:42 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:14:42 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:14:42 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:14:42 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:14:42 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:14:42 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:14:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:14:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:14:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:14:43 INFO - Content-Length response header: 57622769 10:14:43 INFO - Bytes received: 57622769 10:15:02 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 10:15:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:15:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:15:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:15:04 INFO - Content-Length response header: 57622769 10:15:04 INFO - Bytes received: 57622769 10:15:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:15:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:15:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:15:22 INFO - Content-Length response header: 14410000 10:15:22 INFO - Bytes received: 14410000 10:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:22 INFO - trying https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:15:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:15:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/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 10:15:25 INFO - Downloaded 68068787 bytes. 10:15:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:15:25 INFO - mkdir: /builds/slave/test/properties 10:15:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:15:25 INFO - Writing to file /builds/slave/test/properties/build_url 10:15:25 INFO - Contents: 10:15:25 INFO - build_url:https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:15:25 INFO - Running post-action listener: _resource_record_post_action 10:15:25 INFO - Running post-action listener: set_extra_try_arguments 10:15:25 INFO - [mozharness: 2017-05-21 17:15:25.247973Z] Finished download-and-extract step (success) 10:15:25 INFO - [mozharness: 2017-05-21 17:15:25.248138Z] Running populate-webroot step. 10:15:25 INFO - Running pre-action listener: _resource_record_pre_action 10:15:25 INFO - Running main action method: populate_webroot 10:15:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:15:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:15:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:15:25 INFO - '--tppagecycles', 10:15:25 INFO - '7'], 10:15:25 INFO - 'tests': ['tsvgm']}}, 10:15:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:15:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:15:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:15:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:15:25 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:15:25 INFO - 'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:15:25 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:15:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:15:25 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:15:25 INFO - 'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['damp', 'tps']}, 10:15:25 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:15:25 INFO - 'tests': ['damp', 'tps']}, 10:15:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['dromaeo_dom']}, 10:15:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:15:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:15:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:15:25 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:15:25 INFO - 'tests': ['ts_paint', 'tp5o']}, 10:15:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['a11yr', 10:15:25 INFO - 'ts_paint', 10:15:25 INFO - 'tpaint', 10:15:25 INFO - 'sessionrestore', 10:15:25 INFO - 'sessionrestore_no_auto_restore', 10:15:25 INFO - 'tabpaint']}, 10:15:25 INFO - 'other-e10s': {'tests': ['a11yr', 10:15:25 INFO - 'ts_paint', 10:15:25 INFO - 'tpaint', 10:15:25 INFO - 'sessionrestore', 10:15:25 INFO - 'sessionrestore_no_auto_restore', 10:15:25 INFO - 'tabpaint']}, 10:15:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:15:25 INFO - 'ts_paint', 10:15:25 INFO - 'tpaint', 10:15:25 INFO - 'sessionrestore', 10:15:25 INFO - 'sessionrestore_no_auto_restore', 10:15:25 INFO - 'tabpaint']}, 10:15:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:15:25 INFO - 'ts_paint', 10:15:25 INFO - 'tpaint', 10:15:25 INFO - 'sessionrestore', 10:15:25 INFO - 'sessionrestore_no_auto_restore', 10:15:25 INFO - 'tabpaint']}, 10:15:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['a11yr', 10:15:25 INFO - 'ts_paint', 10:15:25 INFO - 'tpaint', 10:15:25 INFO - 'sessionrestore', 10:15:25 INFO - 'sessionrestore_no_auto_restore', 10:15:25 INFO - 'tabpaint']}, 10:15:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['a11yr', 10:15:25 INFO - 'ts_paint', 10:15:25 INFO - 'tpaint', 10:15:25 INFO - 'sessionrestore', 10:15:25 INFO - 'sessionrestore_no_auto_restore', 10:15:25 INFO - 'tabpaint']}, 10:15:25 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:15:25 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:15:25 INFO - 'bloom_basic_ref']}, 10:15:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['tsvgx', 10:15:25 INFO - 'tsvgr_opacity', 10:15:25 INFO - 'tart', 10:15:25 INFO - 'tscrollx', 10:15:25 INFO - 'cart', 10:15:25 INFO - 'tsvg_static']}, 10:15:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:15:25 INFO - 'tsvgr_opacity', 10:15:25 INFO - 'tart', 10:15:25 INFO - 'tscrollx', 10:15:25 INFO - 'cart', 10:15:25 INFO - 'tsvg_static']}, 10:15:25 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:15:25 INFO - 'talos_options': ['--disable-e10s'], 10:15:25 INFO - 'tests': ['tp5o']}, 10:15:25 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:15:25 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:15:25 INFO - 'talos_options': ['--disable-e10s', 10:15:25 INFO - '--xperf_path', 10:15:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:15:25 INFO - 'tests': ['tp5n']}, 10:15:25 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:15:25 INFO - 'talos_options': ['--xperf_path', 10:15:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:15:25 INFO - 'tests': ['tp5n']}}, 10:15:25 INFO - 'talos.zip': {'path': '', 10:15:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:15:25 INFO - Running post-action listener: _resource_record_post_action 10:15:25 INFO - [mozharness: 2017-05-21 17:15:25.269587Z] Finished populate-webroot step (success) 10:15:25 INFO - [mozharness: 2017-05-21 17:15:25.269710Z] Running create-virtualenv step. 10:15:25 INFO - Running pre-action listener: _resource_record_pre_action 10:15:25 INFO - Running main action method: create_virtualenv 10:15:25 INFO - Creating virtualenv /builds/slave/test/build/venv 10:15:25 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 10:15:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:15:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:15:25 INFO - Using real prefix '/tools/python27' 10:15:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:15:26 INFO - Installing distribute.............................................................................................................................................................................................done. 10:15:29 INFO - Installing pip.................done. 10:15:29 INFO - Return code: 0 10:15:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:15:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:15:29 INFO - Reading from file tmpfile_stdout 10:15:29 INFO - Output received: 10:15:29 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) 10:15:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:15:29 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')]} 10:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:29 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')]} 10:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:29 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 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:29 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 10:15:29 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 10:15:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:29 INFO - 'HOME': '/Users/cltbld', 10:15:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:29 INFO - 'LOGNAME': 'cltbld', 10:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:29 INFO - 'MOZ_NO_REMOTE': '1', 10:15:29 INFO - 'NO_EM_RESTART': '1', 10:15:29 INFO - 'PAGER': '/bin/cat', 10:15:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:29 INFO - 'PWD': '/builds/slave/test', 10:15:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:29 INFO - 'SHELL': '/bin/bash', 10:15:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:29 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:29 INFO - 'USER': 'cltbld', 10:15:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:29 INFO - 'XPC_FLAGS': '0x0', 10:15:29 INFO - 'XPC_SERVICE_NAME': '0', 10:15:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:29 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 10:15:29 INFO - Cleaning up... 10:15:29 INFO - Return code: 0 10:15:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:15:29 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')]} 10:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:29 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')]} 10:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:29 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 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:29 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 10:15:29 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 10:15:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:29 INFO - 'HOME': '/Users/cltbld', 10:15:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:29 INFO - 'LOGNAME': 'cltbld', 10:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:29 INFO - 'MOZ_NO_REMOTE': '1', 10:15:29 INFO - 'NO_EM_RESTART': '1', 10:15:29 INFO - 'PAGER': '/bin/cat', 10:15:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:29 INFO - 'PWD': '/builds/slave/test', 10:15:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:29 INFO - 'SHELL': '/bin/bash', 10:15:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:29 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:29 INFO - 'USER': 'cltbld', 10:15:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:29 INFO - 'XPC_FLAGS': '0x0', 10:15:29 INFO - 'XPC_SERVICE_NAME': '0', 10:15:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:29 INFO - Downloading/unpacking psutil>=3.1.1 10:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:15:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:15:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:15:31 INFO - Installing collected packages: psutil 10:15:31 INFO - Running setup.py install for psutil 10:15:31 INFO - building 'psutil._psutil_osx' extension 10:15:31 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 10:15:32 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 10:15:32 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 10:15:32 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 10:15:32 INFO - building 'psutil._psutil_posix' extension 10:15:32 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 10:15:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:15:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:15:32 INFO - ^ 10:15:32 INFO - 1 warning generated. 10:15:32 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 10:15:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:15:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:15:32 INFO - Successfully installed psutil 10:15:32 INFO - Cleaning up... 10:15:32 INFO - Return code: 0 10:15:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:15:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:33 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')]} 10:15:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:33 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 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:33 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 10:15:33 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 10:15:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:33 INFO - 'HOME': '/Users/cltbld', 10:15:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:33 INFO - 'LOGNAME': 'cltbld', 10:15:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:33 INFO - 'MOZ_NO_REMOTE': '1', 10:15:33 INFO - 'NO_EM_RESTART': '1', 10:15:33 INFO - 'PAGER': '/bin/cat', 10:15:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:33 INFO - 'PWD': '/builds/slave/test', 10:15:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:33 INFO - 'SHELL': '/bin/bash', 10:15:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:33 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:33 INFO - 'USER': 'cltbld', 10:15:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:33 INFO - 'XPC_FLAGS': '0x0', 10:15:33 INFO - 'XPC_SERVICE_NAME': '0', 10:15:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:15:33 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 10:15:33 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 10:15:33 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 10:15:33 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 10:15:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:15:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:15:35 INFO - Installing collected packages: mozsystemmonitor 10:15:35 INFO - Running setup.py install for mozsystemmonitor 10:15:35 INFO - Successfully installed mozsystemmonitor 10:15:35 INFO - Cleaning up... 10:15:36 INFO - Return code: 0 10:15:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:15:36 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')]} 10:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:36 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')]} 10:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:36 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 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:36 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 10:15:36 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 10:15:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:36 INFO - 'HOME': '/Users/cltbld', 10:15:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:36 INFO - 'LOGNAME': 'cltbld', 10:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:36 INFO - 'MOZ_NO_REMOTE': '1', 10:15:36 INFO - 'NO_EM_RESTART': '1', 10:15:36 INFO - 'PAGER': '/bin/cat', 10:15:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:36 INFO - 'PWD': '/builds/slave/test', 10:15:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:36 INFO - 'SHELL': '/bin/bash', 10:15:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:36 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:36 INFO - 'USER': 'cltbld', 10:15:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:36 INFO - 'XPC_FLAGS': '0x0', 10:15:36 INFO - 'XPC_SERVICE_NAME': '0', 10:15:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:36 INFO - Downloading/unpacking jsonschema==2.5.1 10:15:36 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 10:15:36 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 10:15:36 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 10:15:36 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 10:15:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:15:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:15:37 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 10:15:37 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 10:15:37 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 10:15:37 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 10:15:37 INFO - Downloading functools32-3.2.3-2.tar.gz 10:15:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:15:37 INFO - warning: no files found matching '*.txt' 10:15:37 INFO - no previously-included directories found matching 'build' 10:15:37 INFO - no previously-included directories found matching 'dist' 10:15:37 INFO - no previously-included directories found matching '.git*' 10:15:37 INFO - Installing collected packages: jsonschema, functools32 10:15:38 INFO - Running setup.py install for functools32 10:15:38 INFO - warning: no files found matching '*.txt' 10:15:38 INFO - no previously-included directories found matching 'build' 10:15:38 INFO - no previously-included directories found matching 'dist' 10:15:38 INFO - no previously-included directories found matching '.git*' 10:15:38 INFO - Successfully installed jsonschema functools32 10:15:38 INFO - Cleaning up... 10:15:38 INFO - Return code: 0 10:15:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:38 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 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:38 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 10:15:38 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 10:15:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:38 INFO - 'HOME': '/Users/cltbld', 10:15:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:38 INFO - 'LOGNAME': 'cltbld', 10:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:38 INFO - 'MOZ_NO_REMOTE': '1', 10:15:38 INFO - 'NO_EM_RESTART': '1', 10:15:38 INFO - 'PAGER': '/bin/cat', 10:15:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:38 INFO - 'PWD': '/builds/slave/test', 10:15:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:38 INFO - 'SHELL': '/bin/bash', 10:15:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:38 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:38 INFO - 'USER': 'cltbld', 10:15:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:38 INFO - 'XPC_FLAGS': '0x0', 10:15:38 INFO - 'XPC_SERVICE_NAME': '0', 10:15:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:15:38 INFO - Cleaning up... 10:15:38 INFO - Return code: 0 10:15:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:38 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 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:38 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 10:15:38 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 10:15:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:38 INFO - 'HOME': '/Users/cltbld', 10:15:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:38 INFO - 'LOGNAME': 'cltbld', 10:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:38 INFO - 'MOZ_NO_REMOTE': '1', 10:15:38 INFO - 'NO_EM_RESTART': '1', 10:15:38 INFO - 'PAGER': '/bin/cat', 10:15:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:38 INFO - 'PWD': '/builds/slave/test', 10:15:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:38 INFO - 'SHELL': '/bin/bash', 10:15:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:38 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:38 INFO - 'USER': 'cltbld', 10:15:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:38 INFO - 'XPC_FLAGS': '0x0', 10:15:38 INFO - 'XPC_SERVICE_NAME': '0', 10:15:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:38 INFO - Downloading/unpacking blobuploader==1.2.4 10:15:38 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 10:15:38 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 10:15:38 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 10:15:38 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 10:15:40 INFO - Downloading blobuploader-1.2.4.tar.gz 10:15:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:15:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:15: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 10:15: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 10:15: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 10:15: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 10:15:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:15:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:15: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 10:15: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 10:15: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 10:15: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 10:15:40 INFO - Downloading docopt-0.6.1.tar.gz 10:15:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:15:41 INFO - Installing collected packages: blobuploader, requests, docopt 10:15:41 INFO - Running setup.py install for blobuploader 10:15:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:15:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:15:41 INFO - Running setup.py install for requests 10:15:41 INFO - Running setup.py install for docopt 10:15:41 INFO - Successfully installed blobuploader requests docopt 10:15:41 INFO - Cleaning up... 10:15:42 INFO - Return code: 0 10:15:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:42 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 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:42 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 10:15:42 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 10:15:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:42 INFO - 'HOME': '/Users/cltbld', 10:15:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:42 INFO - 'LOGNAME': 'cltbld', 10:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:42 INFO - 'MOZ_NO_REMOTE': '1', 10:15:42 INFO - 'NO_EM_RESTART': '1', 10:15:42 INFO - 'PAGER': '/bin/cat', 10:15:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:42 INFO - 'PWD': '/builds/slave/test', 10:15:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:42 INFO - 'SHELL': '/bin/bash', 10:15:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:42 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:42 INFO - 'USER': 'cltbld', 10:15:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:42 INFO - 'XPC_FLAGS': '0x0', 10:15:42 INFO - 'XPC_SERVICE_NAME': '0', 10:15:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:15:42 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-I1QnRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:15:42 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-tMAtDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:15:42 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-rZ_8lR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:15:42 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-2pgIjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:15:42 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-2F6XXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:15:42 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-fw8VKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:15:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:15:42 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-wRnqcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-mqIold-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-UK44Hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-pfwm8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-ZMF5hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-uxPVzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-j4fkuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-g4Pk8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-SycA0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-iEr4Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:15:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:15:43 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-e5AJUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:15:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:15:44 INFO - Running setup.py install for manifestparser 10:15:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:15:44 INFO - Running setup.py install for mozcrash 10:15:44 INFO - Running setup.py install for mozdebug 10:15:44 INFO - Running setup.py install for mozdevice 10:15:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:15:44 INFO - Running setup.py install for mozfile 10:15:44 INFO - Running setup.py install for mozhttpd 10:15:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:15:44 INFO - Running setup.py install for mozinfo 10:15:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:15:45 INFO - Running setup.py install for mozInstall 10:15:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:15:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:15:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:15:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:15:45 INFO - Running setup.py install for mozleak 10:15:45 INFO - Running setup.py install for mozlog 10:15:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:15:45 INFO - Running setup.py install for moznetwork 10:15:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:15:45 INFO - Running setup.py install for mozprocess 10:15:45 INFO - Running setup.py install for mozprofile 10:15:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:15:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:15:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:15:46 INFO - Running setup.py install for mozrunner 10:15:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:15:46 INFO - Running setup.py install for mozscreenshot 10:15:46 INFO - Running setup.py install for moztest 10:15:46 INFO - Running setup.py install for mozversion 10:15:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:15:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:15:46 INFO - Cleaning up... 10:15:46 INFO - Return code: 0 10:15:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15: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')]} 10:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15: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')]} 10:15:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:46 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 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:46 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 10:15:46 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 10:15:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:46 INFO - 'HOME': '/Users/cltbld', 10:15:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:46 INFO - 'LOGNAME': 'cltbld', 10:15:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:46 INFO - 'MOZ_NO_REMOTE': '1', 10:15:46 INFO - 'NO_EM_RESTART': '1', 10:15:46 INFO - 'PAGER': '/bin/cat', 10:15:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:46 INFO - 'PWD': '/builds/slave/test', 10:15:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:46 INFO - 'SHELL': '/bin/bash', 10:15:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:46 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:46 INFO - 'USER': 'cltbld', 10:15:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:46 INFO - 'XPC_FLAGS': '0x0', 10:15:46 INFO - 'XPC_SERVICE_NAME': '0', 10:15:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:15:46 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-34vZRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Kyi2I8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-uOzHv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-8aFz4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-TAyUaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-7oQ7Uk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-648vUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-1vHRwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-fgSIp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-cRbFce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:15:47 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)) 10:15:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:15:47 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-ZEnWv3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:15:48 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)) 10:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:15:48 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-HVqg1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:15:48 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)) 10:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:15:48 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-hd2s4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:15:48 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)) 10:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:15:48 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-u5DChN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:15:48 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)) 10:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:48 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-DqKmpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:48 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)) 10:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:15:48 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-qdlkmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:15:48 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)) 10:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:15:48 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-RbSOnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:15:48 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)) 10:15:48 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)) 10:15:48 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)) 10:15:48 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)) 10:15:48 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)) 10:15:48 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)) 10:15:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:15: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 10:15: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 10:15: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 10:15: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 10:15:50 INFO - Downloading blessings-1.6.tar.gz 10:15:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:15:50 INFO - Installing collected packages: blessings 10:15:50 INFO - Running setup.py install for blessings 10:15:50 INFO - Successfully installed blessings 10:15:50 INFO - Cleaning up... 10:15:50 INFO - Return code: 0 10:15:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:15:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:15:50 INFO - Reading from file tmpfile_stdout 10:15:50 INFO - Current package versions: 10:15:50 INFO - blessings == 1.6 10:15:50 INFO - blobuploader == 1.2.4 10:15:50 INFO - docopt == 0.6.1 10:15:50 INFO - functools32 == 3.2.3-2 10:15:50 INFO - jsonschema == 2.5.1 10:15:50 INFO - manifestparser == 1.1 10:15:50 INFO - mozInstall == 1.12 10:15:50 INFO - mozcrash == 1.0 10:15:50 INFO - mozdebug == 0.1 10:15:50 INFO - mozdevice == 0.50 10:15:50 INFO - mozfile == 1.2 10:15:50 INFO - mozhttpd == 0.7 10:15:50 INFO - mozinfo == 0.9 10:15:50 INFO - mozleak == 0.1 10:15:50 INFO - mozlog == 3.4 10:15:50 INFO - moznetwork == 0.27 10:15:50 INFO - mozprocess == 0.25 10:15:50 INFO - mozprofile == 0.28 10:15:50 INFO - mozrunner == 6.13 10:15:50 INFO - mozscreenshot == 0.1 10:15:50 INFO - mozsystemmonitor == 0.3 10:15:50 INFO - moztest == 0.8 10:15:50 INFO - mozversion == 1.4 10:15:50 INFO - psutil == 3.1.1 10:15:50 INFO - requests == 1.2.3 10:15:50 INFO - wsgiref == 0.1.2 10:15:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15:50 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')]} 10:15:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:50 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')]} 10:15:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:50 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 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:50 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 10:15:50 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 10:15:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:50 INFO - 'HOME': '/Users/cltbld', 10:15:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:50 INFO - 'LOGNAME': 'cltbld', 10:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:50 INFO - 'MOZ_NO_REMOTE': '1', 10:15:50 INFO - 'NO_EM_RESTART': '1', 10:15:50 INFO - 'PAGER': '/bin/cat', 10:15:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:50 INFO - 'PWD': '/builds/slave/test', 10:15:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:50 INFO - 'SHELL': '/bin/bash', 10:15:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:50 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:50 INFO - 'USER': 'cltbld', 10:15:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:50 INFO - 'XPC_FLAGS': '0x0', 10:15:50 INFO - 'XPC_SERVICE_NAME': '0', 10:15:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:50 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)) 10:15:50 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)) 10:15:50 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)) 10:15:50 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)) 10:15:50 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)) 10:15:50 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)) 10:15:50 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)) 10:15:50 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)) 10:15:50 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)) 10:15:50 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)) 10:15:50 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)) 10:15:50 INFO - Cleaning up... 10:15:51 INFO - Return code: 0 10:15:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f447b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f44b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e495d0d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, '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.2WbBOicuJM/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.7nXu8RyLkz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 10:15:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:15:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:15:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:51 INFO - 'HOME': '/Users/cltbld', 10:15:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:51 INFO - 'LOGNAME': 'cltbld', 10:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:51 INFO - 'MOZ_NO_REMOTE': '1', 10:15:51 INFO - 'NO_EM_RESTART': '1', 10:15:51 INFO - 'PAGER': '/bin/cat', 10:15:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:51 INFO - 'PWD': '/builds/slave/test', 10:15:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:51 INFO - 'SHELL': '/bin/bash', 10:15:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:15:51 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:15:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:51 INFO - 'USER': 'cltbld', 10:15:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:51 INFO - 'XPC_FLAGS': '0x0', 10:15:51 INFO - 'XPC_SERVICE_NAME': '0', 10:15:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:15:51 INFO - Cleaning up... 10:15:51 INFO - Return code: 0 10:15:51 INFO - Running post-action listener: _resource_record_post_action 10:15:51 INFO - Running post-action listener: _start_resource_monitoring 10:15:51 INFO - Starting resource monitoring. 10:15:51 INFO - [mozharness: 2017-05-21 17:15:51.305847Z] Finished create-virtualenv step (success) 10:15:51 INFO - [mozharness: 2017-05-21 17:15:51.306789Z] Running install step. 10:15:51 INFO - Running pre-action listener: _resource_record_pre_action 10:15:51 INFO - Running main action method: install 10:15:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:15:51 INFO - Reading from file tmpfile_stdout 10:15:51 INFO - Detecting whether we're running mozinstall >=1.0... 10:15:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:15:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:15:51 INFO - Reading from file tmpfile_stdout 10:15:51 INFO - Output received: 10:15:51 INFO - Usage: mozinstall [options] installer 10:15:51 INFO - Options: 10:15:51 INFO - -h, --help show this help message and exit 10:15:51 INFO - -d DEST, --destination=DEST 10:15:51 INFO - Directory to install application into. [default: 10:15:51 INFO - "/builds/slave/test"] 10:15:51 INFO - --app=APP Application being installed. [default: firefox] 10:15:51 INFO - mkdir: /builds/slave/test/build/application 10:15:51 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'] 10:15:51 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 10:16:12 INFO - Reading from file tmpfile_stdout 10:16:12 INFO - Output received: 10:16:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:16:12 INFO - Running post-action listener: _resource_record_post_action 10:16:12 INFO - [mozharness: 2017-05-21 17:16:12.882795Z] Finished install step (success) 10:16:12 INFO - [mozharness: 2017-05-21 17:16:12.882905Z] Running run-tests step. 10:16:12 INFO - Running pre-action listener: _resource_record_pre_action 10:16:12 INFO - Running main action method: run_tests 10:16:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:16:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:16:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:16:12 INFO - 2017-05-21 10:16:12.897 screenresolution[1626:7452] starting screenresolution argv=screenresolution get 10:16:12 INFO - 2017-05-21 10:16:12.900 screenresolution[1626:7452] Display 0: 1600x1200x32@60 10:16:12 INFO - 2017-05-21 10:16:12.905 screenresolution[1627:7454] starting screenresolution argv=screenresolution list 10:16:12 INFO - Available Modes on Display 0 10:16:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:16:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:16:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:16:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:16:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:16:13 INFO - Intel Iris: 10:16:13 INFO - Chipset Model: Intel Iris 10:16:13 INFO - Type: GPU 10:16:13 INFO - Bus: Built-In 10:16:13 INFO - VRAM (Dynamic, Max): 1536 MB 10:16:13 INFO - Vendor: Intel (0x8086) 10:16:13 INFO - Device ID: 0x0a2e 10:16:13 INFO - Revision ID: 0x0009 10:16:13 INFO - Displays: 10:16:13 INFO - Display: 10:16:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:16:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:16:13 INFO - Display Serial Number: 1600x1200 60 10:16:13 INFO - Main Display: Yes 10:16:13 INFO - Mirror: Off 10:16:13 INFO - Online: Yes 10:16:13 INFO - Rotation: Supported 10:16:13 INFO - Return code: 0 10:16:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:16:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:16:13 INFO - Python 2.7.3 10:16:13 INFO - Return code: 0 10:16:13 INFO - grabbing minidump binary from tooltool 10:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:16:13 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 0x10f157d30>, '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 0x10f10bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f14cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:16:13 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 10:16:13 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 10:16:13 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 10:16:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:16:13 INFO - Return code: 0 10:16:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:16:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:16:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:16:13 INFO - ENV: RUST_BACKTRACE is now 1 10:16:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:16:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:16:13 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/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--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 10:16:13 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/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0340 --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 10:16:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2WbBOicuJM/Render', 10:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:16:13 INFO - 'HOME': '/Users/cltbld', 10:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:16:13 INFO - 'LOGNAME': 'cltbld', 10:16:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:16:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:16:13 INFO - 'MOZ_NO_REMOTE': '1', 10:16:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:16:13 INFO - 'NO_EM_RESTART': '1', 10:16:13 INFO - 'PAGER': '/bin/cat', 10:16:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:16:13 INFO - 'PWD': '/builds/slave/test', 10:16:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:16:13 INFO - 'RUST_BACKTRACE': '1', 10:16:13 INFO - 'SHELL': '/bin/bash', 10:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7nXu8RyLkz/Listeners', 10:16:13 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 10:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:16:13 INFO - 'USER': 'cltbld', 10:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:16:13 INFO - 'XPC_FLAGS': '0x0', 10:16:13 INFO - 'XPC_SERVICE_NAME': '0', 10:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:16:13 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/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--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 10:16:13 INFO - mozversion application_buildid: 20170521094149 10:16:13 INFO - mozversion application_changeset: b31a663614e8df7876563e03c4735aa5c8a38f5a 10:16:13 INFO - mozversion application_display_name: Nightly 10:16:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:16:13 INFO - mozversion application_name: Firefox 10:16:13 INFO - mozversion application_remotingname: firefox 10:16:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:16:13 INFO - mozversion application_vendor: Mozilla 10:16:13 INFO - mozversion application_version: 55.0a1 10:16:13 INFO - mozversion platform_buildid: 20170521094149 10:16:13 INFO - mozversion platform_changeset: b31a663614e8df7876563e03c4735aa5c8a38f5a 10:16:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:16:13 INFO - mozversion platform_version: 55.0a1 10:16:13 INFO - using testdate: 1495386973 10:16:13 INFO - actual date: 1495386973 10:16:13 INFO - starting webserver on 'localhost:49227' 10:16:13 INFO - SUITE-START | Running 2 tests 10:16:13 INFO - TEST-START | dromaeo_css 10:16:13 INFO - operating with platform_type : mac_ 10:16:13 INFO - Initialising browser for dromaeo_css test... 10:16:13 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpkufJZc/profile http://localhost:49227/getInfo.html) 10:16:14 INFO - PID 1637 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 10:16:16 INFO - PID 1637 | 2017-05-21 10:16:16.057 plugin-container[1639:7623] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f43, name = 'com.apple.tsm.portname' 10:16:16 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:16 INFO - PID 1637 | 2017-05-21 10:16:16.060 plugin-container[1639:7623] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa30b, name = 'com.apple.CFPasteboardClient' 10:16:16 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:16 INFO - PID 1637 | 2017-05-21 10:16:16.060 plugin-container[1639:7623] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:16:16 INFO - PID 1637 | __metrics Screen width/height:1600/1200 10:16:16 INFO - PID 1637 | colorDepth:24 10:16:16 INFO - PID 1637 | Browser inner width/height: 1024/691 10:16:16 INFO - PID 1637 | __metrics 10:16:16 INFO - PID 1637 | 10:16:16 INFO - PID 1637 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 10:16:16 INFO - PID 1637 | 10:16:17 INFO - TEST-INFO | 1637: exit 0 10:16:17 INFO - Browser initialized. 10:16:17 INFO - Running cycle 1/1 for dromaeo_css test... 10:16:17 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpkufJZc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:16:19 INFO - PID 1641 | 2017-05-21 10:16:19.031 plugin-container[1642:7769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9737, name = 'com.apple.tsm.portname' 10:16:19 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:19 INFO - PID 1641 | 2017-05-21 10:16:19.031 plugin-container[1642:7769] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 10:16:19 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:19 INFO - PID 1641 | 2017-05-21 10:16:19.032 plugin-container[1642:7769] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:16:19 INFO - PID 1641 | 2017-05-21 10:16:19.751 plugin-container[1643:7815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 10:16:19 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:19 INFO - PID 1641 | 2017-05-21 10:16:19.752 plugin-container[1643:7815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 10:16:19 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:16:19 INFO - PID 1641 | 2017-05-21 10:16:19.752 plugin-container[1643:7815] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:16:29 INFO - PID 1641 | RSS: Main: 242442240 10:16:29 INFO - PID 1641 | 10:19:32 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 10:19:33 INFO - PID 1641 | RSS: Main: 260571136 10:19:33 INFO - PID 1641 | 10:22:35 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 10:22:36 INFO - PID 1641 | RSS: Main: 269160448 10:22:36 INFO - PID 1641 | 10:25:38 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 10:25:38 INFO - PID 1641 | RSS: Main: 272732160 10:25:38 INFO - PID 1641 | 10:28:40 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 10:28:41 INFO - PID 1641 | RSS: Main: 271671296 10:28:41 INFO - PID 1641 | 10:31:43 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 10:31:44 INFO - PID 1641 | RSS: Main: 273219584 10:31:44 INFO - PID 1641 | 10:34:46 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 10:34:46 INFO - PID 1641 | RSS: Main: 274382848 10:34:46 INFO - PID 1641 | 10:34:46 INFO - PID 1641 | __start_tp_report 10:34:46 INFO - PID 1641 | _x_x_mozilla_page_load 10:34:46 INFO - PID 1641 | _x_x_mozilla_page_load_details 10:34:46 INFO - PID 1641 | |i|pagename|runs| 10:34:46 INFO - PID 1641 | |0;dojo.html;3306.421376259154;3503.2993401319736;3532.5407696999387;3574.571051473823;3597.982010089949;4492.460904691442;6003.21958145441;6111.969440152793;6169.16716243307;6184.907226391608;6192.225971753531;6203.91431499488;6218.160548325975;6269.655168965705;6271.372862713726;5076.568491678212;5301.1783173608155;5446.373667028283;5472.91790623141;5481.397046324901;5579.832605021856;5584.1902924075985;5590.832275031756;5594.216809646653;5643.661380317194;4588.49526552079;5118.976204759026;5489.39616642169;5507.586930980193;5513.972430137824;5512.614117011811;5541.584381171388;5542.362628297757;5562.110062389998;5638.830835074954;4354.586314300136;4537.251353526664;5487.835364939058;5500.009998200322;5514.724263786795;5265.578753699695;6176.69116544171;6242.781502647385;6305.022721478178;6314.989601663758;6573.671316434159;6630.867382652368;6648.401643852077;6651.833704157387;6656.434203092743;6756.0879281297375;6782.559133656346;6797.048413222153;6816.284290149512;6839.110915580993;5908.881822363614;6254.9358711625055;6320.336364681734;6339.809805705836;6361.4592759615025;5791.536677065825;6784.660766961632;6829.658517074126;6912.170539535288;6951.548149370275;6413;6555.147830781968;6584;6584.9012264816065;6614.801555953329;5952.672603006789;6493.8701225976165;6559.47524198063;6617.106690596708;6795.830104247433;6594.7032383542855;6607.405333520006;6631.436327912085;6637.236717777518;6651.16860392451;6664;6664.333566643297;6676.532642714963;6681.5656982296;6686.598804071771;4937.753112344369;4975.253711943237;5232.686038837682;5271.262023316739;5317.707526086025;4742.928856067162;4772.0694464579765;4817.686850354716;4865.9026819463415;4867.294242334841;6241.313455519889;6295.5907865988565;6322.9051564226575;6342.460890824239;6381.712822922979;6766.627835469097;6774.762883299061;6783.864322713518;6790.5925644461495;6796.626185559841;6273.12176295319;6284.183056202664;6351.301356850743;6353.110564520971;6365.713542890581;5958.195643588147;5994.1608174855555;6011.729472173763;6022.488088512526;6027.48766354856;6920.100386949664;6928.16861976566;6966.616836073862;6968.163820341591;6981.720731170798;6526.053722210348;6621.073049773132;6742.69657865407;6800.217974932748;6822;6111.07520520229;6583.045458408408;6878.724851006104;6902;6926.376626103473;6034.185384972972;6651.900221496779;6654.334566543307;6678.365555272241;6686.899696504654;6286;6725.663716814041;6765;6797.72809087631;6818.863622727418;5695;5833.300003999547;6361.363863613602;6431.099646049651;6534.248561415498;6508.8047358579315;6511.088447617242;6562.573432726857;6563.803085907431;6569.770058047945;4937.7531123444405;5983.521318294613;6357.523185760994;6367.363263673781;6404.487640988803;5377.435369286169;6041.637501749733;6071.392860713893;6553.606783593;6562.048502966947;6341.524385671001;6414.262359828679;6437.871242575216;6457.741690332334;6463.547551671338 10:34:46 INFO - PID 1641 | |1;ext.html;749.142232144195;784.9285724985397;833.5582141465022;1220.1337050694008;1265.8734126587349;38130.80934595327;38606.61393386065;38700.645477408296;38741;38777.61222387775;38761.41857872134;39151.80424097875;39157;39187.608123918755;39300;7770.922290777076;7877.842443151134;8290.544020078893;8475.576221188916;8481.279091277249;8350;8481.575921203947;8494.915050849475;8498.405111642187;8499.277561407287;7164.355208031277;7176.282371762808;8441.704540341088;8516.531590762506;8544.273736732353;7327;8374.036985746656;8473.364497662638;8485.95757021214;8488.363372747008;8230.794230144234;8295.460795048302;8307.460015098999;8344.958275208646;8356.832863342759;27182.86408567964;37061.81469092664;37205.81397093024;37297.627023729685;39044.41433378502;3567.9643203568153;3614.5662520497444;3622.945655815165;3624.691901188402;3638.235970446183;29598.408031839244;29643.70356296431;29731.702682973326;29739.851300743358;29785;3097.7212050915527;3122.5160100184507;3132.6710695376887;3134.5141503066834;3139.4035133324824;266745;267749;316742;320276.3986180101;321785.3910730431;48008.759956199996;48392.75803620959;48660.5133948656;48773.26840097401;48836.53490395294;51183.74408127936;51219.48780512222;51281.23078153831;51290;51318.74340628348;14075.577732668036;14088.647783805282;14190.290485475685;14298.928505357406;14302.284885755671;11549;11669;11748.412579370997;11761.000314973324;11772.587959421378;13671.726565468634;13723.313834308245;13731.176129432266;13736.65658358529;13829.30853457323;6201.224846894138;6214.316425193225;6217.191765070603;6240.469560087353;6243.656598887105;910.649399981012;965.9712406287282;1009.2733232072895;1041.2086814021247;1089.2266490791626;23710.644340334908;24844.50310993806;24858;25114.748852511242;25159.87420062888;995.9651412200539;1044.639599338227;1048.7729356652735;1170.1750266062406;1174.629991552658;12555.686107847194;12728.314769130984;12761.489540418279;12827.486900523874;12887.968962482979;331467;333460;333736;334082;334433;49644.0071198574;50814;54753.726231368586;54937.450625494035;55475;1435.2106341512335;1560.617648676059;1588.7775711400413;1593.9123348215728;1600.6718622682556;22564.661530076733;23168.07327706873;24340.26979190627;24490.387740306636;24561.771911404;37834.43248351222;37854;37885.81057094697;37935;37959;239238;245496;271454;271736;272604;34645.65354346424;35028.12429689278;35907;35963.46054809128;36037.81981090183;43620;44588;45962.31056534222;45978.08043839171;46128.769356153;23896.88051559731;29033.838646453903;30225.848870755504;31234.531482027334;31687.841560792047;30256.243593910327;31277.530837037008;31377.52933706042;31470.527942081346;31800;37700;37708.811455942545;37755;37817.81091094527;37887.05282367963;29399.85300073486;30048.849755751082;31722;31906;32045.03864884057;25028.749712502642;25186.041183092475;25229.24312270635;25237.990480381315;25352.366190845376 10:34:46 INFO - PID 1641 | |2;jquery.html;429.8194758201555;438.64469779478617;440.0934075803842;453.82754553269774;456.2403598009314;19508;20991.790082099174;21799.346019619396;21947.341579752592;22040.44898877529;21902.45243868904;22759.772402275972;22821.543569128607;22943;23145.884270578666;5994.97002514989;6008.75964961401;6193.69031548423;6240.188775459184;6269.467095296905;6026.698665066751;6203.100550420187;6206.069089636546;6214.166820787856;6217.502599792006;6104.542159338045;6114.327423983358;6131.540134489909;6142;6176.814695559118;5976.820695379146;6145.569810113294;6157.568970172089;6165.876682466325;6169.784057557987;4689.812407503696;5417.837464876021;5473;5473.987312347229;5478.287822583039;23743.525129497488;26552.468950620878;26772.464550708875;26921.865390673116;27665.308367290778;2062.9174833006814;2106.926257580977;2233.419310979141;2251.2008237075765;2252.054137262354;17566.736498952527;17693.734593981102;17935.641287174312;18024.459266222035;18030.819691803044;2100.453881990678;2112;2158.816500597451;2167.2414654870827;2167.794059564339;149661;152121;152635.47364526324;154246.22876885656;155699.44300556963;10177.236707246986;11896.821547676791;12416.130870839135;12419;12572.245665260114;13006.154599950973;13123.409446574928;16233.918830405772;16247.431339903047;16251.837481625269;7360.96319518399;8761.080086590944;8932.169907779577;8980.281577473786;9148.954255228815;6390.808275751735;8247.95876020628;8442.873356899529;8676.696315628922;8739.956300218586;6512.6092434454085;6525.706343214567;7455.4408419368765;7952.045754509496;8017.879731804028;2209.933701988943;2220.5891909996703;2232.359215976256;2234.608943434925;2237.172246268891;900.486722568143;933.1554942776797;964.9144712198777;985.482621623639;997.5660587644398;15695.529134125994;20799.480012999797;21576.460588485414;21595.784042159692;21720.456988575097;845.7251393297203;929.7907970706536;1043.5251960358019;1068.7541865370852;1082.2802836113897;9387.90612093884;10845;11123.777524449466;11136.498857551429;11215.66353009411;529327;537273;547976;549684;554444;89957.55021224852;119284.80715192911;120493;126613;129573;1216.5377156680404;1228.2446295528364;1277.2208952538945;1285.2352850054203;1290.1871820753054;22587.548249035255;23001.931936394576;23064.769352306263;23127.88436057809;23307.41731456659;49313.52059438223;52221;58174.709126454094;58490.707546463695;58852.70573647104;49224.50775492199;74976;77947;81027.3794524118;82724.58637706772;8898.332625053017;9177.715119883354;9347.439153650803;13064.150830196008;13302.268375239455;12378.195417297848;13096.934515327363;14483.420663173356;14824.184669843262;14918.701625967637;11902.880971190523;11982.400879956142;12228.633141005785;12229.571964981184;12261.26432414058;11253.606123785628;11516.827247591125;11564.94217528907;11850.830247700018;12003.63989080329;10058.798824023625;10885.836712449478;11596.88403115958;12313.753724925631;12337.136400452242;13213.207207567577;13257.469701211843;13403.865961340261;13476.393562289917;13601.319934003062;10137.239707021856;11797.710817557943;11989.34058626784;12168;12186 10:34:46 INFO - PID 1641 | |3;mootools.html;1394.8953828462866;1453.8328092269385;1462.663587374903;1474.0713350589128;1474.8525147485252;9093.681721139754;11126.554937802479;11490.023348015418;11516.481758320875;11804.704882377944;10901.727456813564;11070.39112848793;11100.722481937955;11108.16688748343;11119.666410007712;2462.8768561571896;2505.5991041433376;2507.4734305795805;2519.987400062997;2522.4828910073484;2091.4875855415444;2152.332776839177;2412.1798588479915;2419.8790060496995;2479.1942618648923;2516.4086439686657;2535.492901419714;2544.1095616534158;2551;2551.5024570208816;2491.5639763041486;2526.4189236475713;2528.1404322530243;2528.6080657497932;2533.5439620868237;2469.1111199967995;2481.04479775286;2483.5653760592004;2485.254423672909;2486.154707399493;10754.946225268903;10779.622713205;10787.622433214878;10794.892051079545;10799.082078023377;6405.871882562369;6651.135352404156;6656.600603963778;6659.2008958925235;6677;3534.452159915217;3783.4892289541112;3813.027677942107;4196.034911970233;4259.829606815723;1168.976620467586;1208.3474923541278;1234.086775785921;1260.1619922751358;1264.4436447962867;15910;15925.601859953478;16081.195940202942;16087.597810054842;16155.515334539983;4760.904781904412;4775.594074503706;5206.193040078794;5549.833504994856;5551.666899986014;11473.196876218584;11485;11491.77016459666;11508.769824603462;11536.96167344943;6402.519811014193;6427.453666438405;6432.292447830735;6435.549511534241;6451.096846441502;5802.187693722883;5870.236869207061;5982.790602328898;6022.006368949074;6040.939590604125;3845.7115716321387;4041.2523683118716;4199.769012704331;4269.146170765859;4301.763403012802;1243.3286025546104;1384.1833318342226;1426.3953052815582;1434.0678558936565;1438.4030627289767;773.3619763694974;804.6298702596754;811.9323090136452;816.4692949582819;839.5886015852188;6445.742170313229;6774.864502709918;6791.830204244835;6859.759908403182;6897.241303456616;766.4519868294085;797.9825722204235;805.7381351060928;917.655879045358;940.4451373689419;3681.0245284999496;3779.962200377961;3791.2038471920655;4195.601417865298;4381.277089280233;13255;13819.654508637163;13821.447142114404;13891.652708682364;13903.582892513241;13319.467221311234;13648.317584120754;13849.515266965607;13942.651433714238;13966.790498142536;8033.076196237391;8050.879236811453;8206.917930820735;8263.793405164919;8307.293880020145;5964.970175149097;6365.96817015912;6495.642739649365;6800;6805.863882722417;9859.507024648596;12087.879121209027;12552.497900083894;12615.558455454015;13003.219806811621;12512.43694033789;12780.61658150257;12830.422630981442;12839.743205135657;12857.421416036113;8936.46381217129;9018.909810901807;9146.31402644791;9150.359474836698;9155.404898681565;10103.09072183507;10134;10219.540120694735;10292.639757608447;10400.791984160425;8309.83380332378;8490.448120872123;8528.019277783134;8586.914130858611;8742.300615950582;8506.74479765608;8518.403711740119;8527.786805329917;8557.400981931454;8635.093315201813;7877.133515313197;7930.246626570467;7948.602569871367;8036.919630803851;8050.597470126353;5939.613925094855;5960.493358064613;6011.969940150271;6038.215032045806;6087.391260873877;5928.229330187048;6015.488683461955;6038.365971573097;6083.087536869328;6103.420175083537 10:34:46 INFO - PID 1641 | |4;prototype.html;343.34935297611025;385.10463173122497;385.2045525988835;387.6937219596519;401.71478250442186;5514.338279406466;6215.689215539216;6294.937050629493;6306.653134077624;6350.634163893133;5296;6104.5116390688745;6165.691715414234;6222.599915019118;6257.843553911143;3650.251698401828;4449.799759010836;4531.456225252974;4563.338315944203;4600.654950878681;3835.347990841564;3948.7433316834313;3952.7628342299554;4383.75889326087;4573.679842411033;3734.8692795752154;3929.469521614574;4296.785160741966;4534.9319760203625;4553;3670.284294562535;3917.275304068742;4371.191329604034;4479.2609219478745;4541.682082254243;3451.430513965193;3652.926941461156;3873.612638736132;3877.3020856245867;3882.786446745428;5702.030654788696;6112.358202388729;6140.416660417298;6156.445919867234;6183.2889217740085;4316.546762589915;4798.760061996886;4946.689127381247;5389.688249400503;5434.510894019557;5614.494695477386;6701.765438209688;6728.629925354153;6732.192136943598;6735.676191713851;3865.5168103987003;3986.8637438330225;4293.690424420551;4354.477462704464;4406.405135306757;6224.813255602339;6494.707738151747;6912.758053468105;6983.196932352794;7128.287171282882;4308.91382172361;4424.6681498887065;4431.224535706238;4454.4877339106415;4463.486699029589;3673.7612055216327;4501.41481607389;4523.411956445641;4526.79629416677;4528.298113792367;4484.551544845554;4821.614270858323;5209.645492172062;5357.1696387060065;5427.6200665953;6668.299828518033;6703.966480167568;6706.966465167643;6740.595564266159;6764.222114456802;1370.4586688258282;1512.8638422541803;1553.485351782026;1555.2845690982049;1560.7346751052348;936.6628013914985;939.0281059103795;962.3071388600197;1002.871769259583;1006.8288390973552;6303.92833218354;6328.462080723098;6357.396047375584;6357.904631430532;6375.585586936834;6733.3939945405145;6915.481338899603;6936.514443988873;7010.509264351548;7081.752138675121;5154.5103215194495;6308.810735677937;6334.651594162366;6398.880195965688;6412.006139048455;3023.486007378751;3088.822380221938;3491.319192757439;3673.834677439522;3693.298273328113;6035.973884439656;6447.548671592944;7243.239459856744;7263.0194923685685;7285.854282914312;5939.4951429129005;6698.995150727333;7233.348998590152;7272.945422913648;7277.543166995635;7028.2620324866175;7035.994741498751;7186.233300179469;7265.782026539212;7272.527285726411;5884.205632239764;6163.414475624809;7023.277662838704;7039.683214255473;7073.681684324115;5985.191999080243;6809.557378770364;6860.142482189726;6883.965580172067;6938;6991.580505169706;7125.67934442941;7272.23641517633;7274.454415918722;7282.798338274126;3475.7740746851373;3759.93565244325;4239.872803815767;4396.2851390574915;4408.647308215399;6947.92307192386;7003.719851205895;7091.503594748318;7115.964420178073;7124.857502850017;5975.7749661320195;6802.6258555779905;6826.795196144124;6835.965820171066;6853.109095817512;5792.681402522901;6167.6916154193;6734.898976515454;6856.41720453767;6857.211420686485;6662.300458451793;6673.799786006428;6685.298043705341;6693.966530167318;6707.4969377297875;6550.377714117151;6755.256921738703;6781.660916954232;6827.453803695792;6842.828929276808;6642.4686025118845;6681.732730690718;6683.398494135551;6691.230508491586;6700.564463309869 10:34:46 INFO - PID 1641 | |5;yui.html;684.8356394465328;823.4647479138563;844.5017439710572;846.9195426434486;858.9263420724095;14390.208538530376;14944.626384340398;16291.51125466235;16742.665146697058;16747.162641867904;16763.24565394557;16953;17443.476695699148;17490.300387984465;17557.736633950502;971.5045326883287;991.5984026469291;995.3629677006721;999.3054826895311;1003.2225025605142;1004.5128112865291;1012.2661070723703;1016.6289304403876;1017.6489111256604;1018.6282007067441;1033.5813995331864;1060.2366296266714;1061.7345663584103;1062.1768129699506;1069.7218723132003;1033.7725700345911;1039.5062345385957;1060.0671409159968;1067.146282973622;1071.5499490214129;770.433731207562;786.4769927997875;786.5909726942016;788.9447738658296;790.075611534506;16422.425215117415;16955.91522042382;17133.742993855103;17183;17183.312667493286;16312.75530867038;16673.166341682867;16962.49112526626;17120.143992800437;17170.313187472486;14058.226797526131;14407.711845763131;14473.27633618315;14513.056651317736;14516.927415362856;3459.9827000865084;3593.6406359364114;3605.4952306677087;3630.146915474861;3632.4369722693023;114420;116348;116493.83506164915;117219;117274.41362793217;11208.327500350006;13319.600411987656;13361;13407.597772066854;13539.729205415837;21606.91965401755;24535.877320613283;25059.87470062638;25580.488390232462;25980.350491237492;5903.468687818117;6674.299198584175;6942.132233470817;7633.541987480658;7735.839624056436;3295.423300922329;3979.840806367713;3983.42240375144;3984.5019372578427;3986.2824691555797;4009.2182024505523;4137.834486620502;4224.366345048267;4396.3625274335045;4397.648188144941;919.8252332057021;948.8481842905102;949.6059135458845;949.6913503111516;959.7840485890758;630.8643641616983;636.5925807483214;639.9328070552618;652.3802387731579;654.0842820051965;11080.889191108146;11430.714232144262;11856.822147667792;11924.523019079314;11960.342181179945;630.7477009196266;641.974321027163;644.7453255963868;651.3584119642148;662.9701663425158;5254.42201357858;5310.230016647642;5322.0952438085615;5566.997940370772;5920.37836027211;95468;95847;96188.51905740426;96189;98293.50853245688;63544;82299;87974.56012719896;88328.5583572078;91144.54427727818;78326;79139;79226.6038669803;79583.20416795874;81294.59352703199;10775.73060673458;12218;12298.262104273772;12338.876611233773;12369;29134;39026.02434939149;39263.803680981415;39482;39525.01187470336;67215.02351171573;69844.6507767458;71441.57116857737;71725.64137179281;74520.62739686266;15934.760978585098;16497;16645.731148064177;17063.829361706223;17231.913840430716;17994.280228790703;18090.27638894482;18115.315621423844;18161.545961351072;18537.81462185361;16021.519354419386;16486.917565412095;16774.49676509707;17012.234449449556;17026.978381297162;16763.580910477336;16854.57863553421;16945.32218711238;16982.830171698126;17257.65484690274;16109.113998729714;16443.9177804115;16683.332666693197;16812.57968550747;17100.828991709925;9838.36050656705;11341.334786325371;11838.526458941546;12071.155019148575;12270.693232669255;12053.63839084829;12143.757124857277;12416;13089.607311780663;13149.342532873126 10:34:46 INFO - PID 1641 | __end_tp_report 10:34:46 INFO - PID 1641 | __start_cc_report 10:34:46 INFO - PID 1641 | _x_x_mozilla_cycle_collect,345 10:34:46 INFO - PID 1641 | __end_cc_report 10:34:46 INFO - PID 1641 | __startTimestamp1495388086487__endTimestamp 10:34:46 INFO - PID 1641 | 10:34:46 INFO - PID 1641 | ------- Summary: start ------- 10:34:46 INFO - PID 1641 | Number of tests: 6 10:34:46 INFO - PID 1641 | 10:34:46 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:6099.70 Median:6342.46 stddev:768.77 (12.1%) stddev-sans-first:739.24 10:34:46 INFO - PID 1641 | Values: 3306.4 3503.3 3532.5 3574.6 3598.0 4492.5 6003.2 6112.0 6169.2 6184.9 6192.2 6203.9 6218.2 6269.7 6271.4 5076.6 5301.2 5446.4 5472.9 5481.4 5579.8 5584.2 5590.8 5594.2 5643.7 4588.5 5119.0 5489.4 5507.6 5514.0 5512.6 5541.6 5542.4 5562.1 5638.8 4354.6 4537.3 5487.8 5500.0 5514.7 5265.6 6176.7 6242.8 6305.0 6315.0 6573.7 6630.9 6648.4 6651.8 6656.4 6756.1 6782.6 6797.0 6816.3 6839.1 5908.9 6254.9 6320.3 6339.8 6361.5 5791.5 6784.7 6829.7 6912.2 6951.5 6413.0 6555.1 6584.0 6584.9 6614.8 5952.7 6493.9 6559.5 6617.1 6795.8 6594.7 6607.4 6631.4 6637.2 6651.2 6664.0 6664.3 6676.5 6681.6 6686.6 4937.8 4975.3 5232.7 5271.3 5317.7 4742.9 4772.1 4817.7 4865.9 4867.3 6241.3 6295.6 6322.9 6342.5 6381.7 6766.6 6774.8 6783.9 6790.6 6796.6 6273.1 6284.2 6351.3 6353.1 6365.7 5958.2 5994.2 6011.7 6022.5 6027.5 6920.1 6928.2 6966.6 6968.2 6981.7 6526.1 6621.1 6742.7 6800.2 6822.0 6111.1 6583.0 6878.7 6902.0 6926.4 6034.2 6651.9 6654.3 6678.4 6686.9 6286.0 6725.7 6765.0 6797.7 6818.9 5695.0 5833.3 6361.4 6431.1 6534.2 6508.8 6511.1 6562.6 6563.8 6569.8 4937.8 5983.5 6357.5 6367.4 6404.5 5377.4 6041.6 6071.4 6553.6 6562.0 6341.5 6414.3 6437.9 6457.7 6463.5 10:34:46 INFO - PID 1641 | 10:34:46 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:45024.32 Median:34836.89 stddev:78535.88 (225.4%) stddev-sans-first:78685.84 10:34:46 INFO - PID 1641 | Values: 749.1 784.9 833.6 1220.1 1265.9 38130.8 38606.6 38700.6 38741.0 38777.6 38761.4 39151.8 39157.0 39187.6 39300.0 7770.9 7877.8 8290.5 8475.6 8481.3 8350.0 8481.6 8494.9 8498.4 8499.3 7164.4 7176.3 8441.7 8516.5 8544.3 7327.0 8374.0 8473.4 8486.0 8488.4 8230.8 8295.5 8307.5 8345.0 8356.8 27182.9 37061.8 37205.8 37297.6 39044.4 3568.0 3614.6 3622.9 3624.7 3638.2 29598.4 29643.7 29731.7 29739.9 29785.0 3097.7 3122.5 3132.7 3134.5 3139.4 266745.0 267749.0 316742.0 320276.4 321785.4 48008.8 48392.8 48660.5 48773.3 48836.5 51183.7 51219.5 51281.2 51290.0 51318.7 14075.6 14088.6 14190.3 14298.9 14302.3 11549.0 11669.0 11748.4 11761.0 11772.6 13671.7 13723.3 13731.2 13736.7 13829.3 6201.2 6214.3 6217.2 6240.5 6243.7 910.6 966.0 1009.3 1041.2 1089.2 23710.6 24844.5 24858.0 25114.7 25159.9 996.0 1044.6 1048.8 1170.2 1174.6 12555.7 12728.3 12761.5 12827.5 12888.0 331467.0 333460.0 333736.0 334082.0 334433.0 49644.0 50814.0 54753.7 54937.5 55475.0 1435.2 1560.6 1588.8 1593.9 1600.7 22564.7 23168.1 24340.3 24490.4 24561.8 37834.4 37854.0 37885.8 37935.0 37959.0 239238.0 245496.0 271454.0 271736.0 272604.0 34645.7 35028.1 35907.0 35963.5 36037.8 43620.0 44588.0 45962.3 45978.1 46128.8 23896.9 29033.8 30225.8 31234.5 31687.8 30256.2 31277.5 31377.5 31470.5 31800.0 37700.0 37708.8 37755.0 37817.8 37887.1 29399.9 30048.8 31722.0 31906.0 32045.0 25028.7 25186.0 25229.2 25238.0 25352.4 10:34:46 INFO - PID 1641 | 10:34:46 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:35013.89 Median:2235.89 stddev:91899.74 (4110.2%) stddev-sans-first:92120.85 10:34:46 INFO - PID 1641 | Values: 429.8 438.6 440.1 453.8 456.2 19508.0 20991.8 21799.3 21947.3 22040.4 21902.5 22759.8 22821.5 22943.0 23145.9 5995.0 6008.8 6193.7 6240.2 6269.5 6026.7 6203.1 6206.1 6214.2 6217.5 6104.5 6114.3 6131.5 6142.0 6176.8 5976.8 6145.6 6157.6 6165.9 6169.8 4689.8 5417.8 5473.0 5474.0 5478.3 23743.5 26552.5 26772.5 26921.9 27665.3 2062.9 2106.9 2233.4 2251.2 2252.1 17566.7 17693.7 17935.6 18024.5 18030.8 2100.5 2112.0 2158.8 2167.2 2167.8 149661.0 152121.0 152635.5 154246.2 155699.4 10177.2 11896.8 12416.1 12419.0 12572.2 13006.2 13123.4 16233.9 16247.4 16251.8 7361.0 8761.1 8932.2 8980.3 9149.0 6390.8 8248.0 8442.9 8676.7 8740.0 6512.6 6525.7 7455.4 7952.0 8017.9 2209.9 2220.6 2232.4 2234.6 2237.2 900.5 933.2 964.9 985.5 997.6 15695.5 20799.5 21576.5 21595.8 21720.5 845.7 929.8 1043.5 1068.8 1082.3 9387.9 10845.0 11123.8 11136.5 11215.7 529327.0 537273.0 547976.0 549684.0 554444.0 89957.6 119284.8 120493.0 126613.0 129573.0 1216.5 1228.2 1277.2 1285.2 1290.2 22587.5 23001.9 23064.8 23127.9 23307.4 49313.5 52221.0 58174.7 58490.7 58852.7 49224.5 74976.0 77947.0 81027.4 82724.6 8898.3 9177.7 9347.4 13064.2 13302.3 12378.2 13096.9 14483.4 14824.2 14918.7 11902.9 11982.4 12228.6 12229.6 12261.3 11253.6 11516.8 11564.9 11850.8 12003.6 10058.8 10885.8 11596.9 12313.8 12337.1 13213.2 13257.5 13403.9 13476.4 13601.3 10137.2 11797.7 11989.3 12168.0 12186.0 10:34:46 INFO - PID 1641 | 10:34:46 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:6797.51 Median:3781.73 stddev:4194.39 (110.9%) stddev-sans-first:4186.51 10:34:46 INFO - PID 1641 | Values: 1394.9 1453.8 1462.7 1474.1 1474.9 9093.7 11126.6 11490.0 11516.5 11804.7 10901.7 11070.4 11100.7 11108.2 11119.7 2462.9 2505.6 2507.5 2520.0 2522.5 2091.5 2152.3 2412.2 2419.9 2479.2 2516.4 2535.5 2544.1 2551.0 2551.5 2491.6 2526.4 2528.1 2528.6 2533.5 2469.1 2481.0 2483.6 2485.3 2486.2 10754.9 10779.6 10787.6 10794.9 10799.1 6405.9 6651.1 6656.6 6659.2 6677.0 3534.5 3783.5 3813.0 4196.0 4259.8 1169.0 1208.3 1234.1 1260.2 1264.4 15910.0 15925.6 16081.2 16087.6 16155.5 4760.9 4775.6 5206.2 5549.8 5551.7 11473.2 11485.0 11491.8 11508.8 11537.0 6402.5 6427.5 6432.3 6435.5 6451.1 5802.2 5870.2 5982.8 6022.0 6040.9 3845.7 4041.3 4199.8 4269.1 4301.8 1243.3 1384.2 1426.4 1434.1 1438.4 773.4 804.6 811.9 816.5 839.6 6445.7 6774.9 6791.8 6859.8 6897.2 766.5 798.0 805.7 917.7 940.4 3681.0 3780.0 3791.2 4195.6 4381.3 13255.0 13819.7 13821.4 13891.7 13903.6 13319.5 13648.3 13849.5 13942.7 13966.8 8033.1 8050.9 8206.9 8263.8 8307.3 5965.0 6366.0 6495.6 6800.0 6805.9 9859.5 12087.9 12552.5 12615.6 13003.2 12512.4 12780.6 12830.4 12839.7 12857.4 8936.5 9018.9 9146.3 9150.4 9155.4 10103.1 10134.0 10219.5 10292.6 10400.8 8309.8 8490.4 8528.0 8586.9 8742.3 8506.7 8518.4 8527.8 8557.4 8635.1 7877.1 7930.2 7948.6 8036.9 8050.6 5939.6 5960.5 6012.0 6038.2 6087.4 5928.2 6015.5 6038.4 6083.1 6103.4 10:34:46 INFO - PID 1641 | 10:34:46 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5368.21 Median:6199.49 stddev:1795.60 (29.0%) stddev-sans-first:1760.59 10:34:46 INFO - PID 1641 | Values: 343.3 385.1 385.2 387.7 401.7 5514.3 6215.7 6294.9 6306.7 6350.6 5296.0 6104.5 6165.7 6222.6 6257.8 3650.3 4449.8 4531.5 4563.3 4600.7 3835.3 3948.7 3952.8 4383.8 4573.7 3734.9 3929.5 4296.8 4534.9 4553.0 3670.3 3917.3 4371.2 4479.3 4541.7 3451.4 3652.9 3873.6 3877.3 3882.8 5702.0 6112.4 6140.4 6156.4 6183.3 4316.5 4798.8 4946.7 5389.7 5434.5 5614.5 6701.8 6728.6 6732.2 6735.7 3865.5 3986.9 4293.7 4354.5 4406.4 6224.8 6494.7 6912.8 6983.2 7128.3 4308.9 4424.7 4431.2 4454.5 4463.5 3673.8 4501.4 4523.4 4526.8 4528.3 4484.6 4821.6 5209.6 5357.2 5427.6 6668.3 6704.0 6707.0 6740.6 6764.2 1370.5 1512.9 1553.5 1555.3 1560.7 936.7 939.0 962.3 1002.9 1006.8 6303.9 6328.5 6357.4 6357.9 6375.6 6733.4 6915.5 6936.5 7010.5 7081.8 5154.5 6308.8 6334.7 6398.9 6412.0 3023.5 3088.8 3491.3 3673.8 3693.3 6036.0 6447.5 7243.2 7263.0 7285.9 5939.5 6699.0 7233.3 7272.9 7277.5 7028.3 7036.0 7186.2 7265.8 7272.5 5884.2 6163.4 7023.3 7039.7 7073.7 5985.2 6809.6 6860.1 6884.0 6938.0 6991.6 7125.7 7272.2 7274.5 7282.8 3475.8 3759.9 4239.9 4396.3 4408.6 6947.9 7003.7 7091.5 7116.0 7124.9 5975.8 6802.6 6826.8 6836.0 6853.1 5792.7 6167.7 6734.9 6856.4 6857.2 6662.3 6673.8 6685.3 6694.0 6707.5 6550.4 6755.3 6781.7 6827.5 6842.8 6642.5 6681.7 6683.4 6691.2 6700.6 10:34:46 INFO - PID 1641 | 10:34:46 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:21300.71 Median:18042.28 stddev:29167.29 (161.7%) stddev-sans-first:29208.03 10:34:46 INFO - PID 1641 | Values: 684.8 823.5 844.5 846.9 858.9 14390.2 14944.6 16291.5 16742.7 16747.2 16763.2 16953.0 17443.5 17490.3 17557.7 971.5 991.6 995.4 999.3 1003.2 1004.5 1012.3 1016.6 1017.6 1018.6 1033.6 1060.2 1061.7 1062.2 1069.7 1033.8 1039.5 1060.1 1067.1 1071.5 770.4 786.5 786.6 788.9 790.1 16422.4 16955.9 17133.7 17183.0 17183.3 16312.8 16673.2 16962.5 17120.1 17170.3 14058.2 14407.7 14473.3 14513.1 14516.9 3460.0 3593.6 3605.5 3630.1 3632.4 114420.0 116348.0 116493.8 117219.0 117274.4 11208.3 13319.6 13361.0 13407.6 13539.7 21606.9 24535.9 25059.9 25580.5 25980.4 5903.5 6674.3 6942.1 7633.5 7735.8 3295.4 3979.8 3983.4 3984.5 3986.3 4009.2 4137.8 4224.4 4396.4 4397.6 919.8 948.8 949.6 949.7 959.8 630.9 636.6 639.9 652.4 654.1 11080.9 11430.7 11856.8 11924.5 11960.3 630.7 642.0 644.7 651.4 663.0 5254.4 5310.2 5322.1 5567.0 5920.4 95468.0 95847.0 96188.5 96189.0 98293.5 63544.0 82299.0 87974.6 88328.6 91144.5 78326.0 79139.0 79226.6 79583.2 81294.6 10775.7 12218.0 12298.3 12338.9 12369.0 29134.0 39026.0 39263.8 39482.0 39525.0 67215.0 69844.7 71441.6 71725.6 74520.6 15934.8 16497.0 16645.7 17063.8 17231.9 17994.3 18090.3 18115.3 18161.5 18537.8 16021.5 16486.9 16774.5 17012.2 17027.0 16763.6 16854.6 16945.3 16982.8 17257.7 16109.1 16443.9 16683.3 16812.6 17100.8 9838.4 11341.3 11838.5 12071.2 12270.7 12053.6 12143.8 12416.0 13089.6 13149.3 10:34:46 INFO - PID 1641 | -------- Summary: end -------- 10:34:46 INFO - PID 1641 | 10:34:47 INFO - TEST-INFO | 1641: exit 0 10:34:47 INFO - TEST-OK | dromaeo_css | took 1113595ms 10:34:47 INFO - TEST-START | kraken 10:34:47 INFO - operating with platform_type : mac_ 10:34:47 INFO - Initialising browser for kraken test... 10:34:47 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpsfwdZe/profile http://localhost:49227/getInfo.html) 10:34:47 INFO - PID 1657 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 10:34:49 INFO - PID 1657 | 2017-05-21 10:34:49.609 plugin-container[1658:10225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 10:34:49 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:49 INFO - PID 1657 | 2017-05-21 10:34:49.614 plugin-container[1658:10225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 10:34:49 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:49 INFO - PID 1657 | 2017-05-21 10:34:49.614 plugin-container[1658:10225] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:34:49 INFO - PID 1657 | __metrics Screen width/height:1600/1200 10:34:49 INFO - PID 1657 | colorDepth:24 10:34:49 INFO - PID 1657 | Browser inner width/height: 1024/691 10:34:49 INFO - PID 1657 | __metrics 10:34:50 INFO - TEST-INFO | 1657: exit 0 10:34:50 INFO - Browser initialized. 10:34:50 INFO - Running cycle 1/1 for kraken test... 10:34:50 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpsfwdZe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:34:52 INFO - PID 1660 | 2017-05-21 10:34:52.934 plugin-container[1661:10363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 10:34:52 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:52 INFO - PID 1660 | 2017-05-21 10:34:52.934 plugin-container[1661:10363] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490f, name = 'com.apple.CFPasteboardClient' 10:34:52 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:52 INFO - PID 1660 | 2017-05-21 10:34:52.934 plugin-container[1661:10363] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:34:53 INFO - PID 1660 | 2017-05-21 10:34:53.433 plugin-container[1662:10411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 10:34:53 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:53 INFO - PID 1660 | 2017-05-21 10:34:53.433 plugin-container[1662:10411] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 10:34:53 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:53 INFO - PID 1660 | 2017-05-21 10:34:53.433 plugin-container[1662:10411] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:35:02 INFO - PID 1660 | RSS: Main: 243318784 10:35:02 INFO - PID 1660 | 10:35:05 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 10:35:06 INFO - PID 1660 | RSS: Main: 254529536 10:35:06 INFO - PID 1660 | 10:35:09 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 10:35:09 INFO - PID 1660 | RSS: Main: 263270400 10:35:09 INFO - PID 1660 | 10:35:12 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 10:35:13 INFO - PID 1660 | RSS: Main: 265187328 10:35:13 INFO - PID 1660 | 10:35:15 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 10:35:16 INFO - PID 1660 | RSS: Main: 267481088 10:35:16 INFO - PID 1660 | 10:35:18 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:35:18 INFO - PID 1660 | RSS: Main: 267960320 10:35:18 INFO - PID 1660 | 10:35:21 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 10:35:22 INFO - PID 1660 | RSS: Main: 277147648 10:35:22 INFO - PID 1660 | 10:35:25 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 10:35:25 INFO - PID 1660 | RSS: Main: 277225472 10:35:25 INFO - PID 1660 | 10:35:28 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 10:35:29 INFO - PID 1660 | RSS: Main: 276586496 10:35:29 INFO - PID 1660 | 10:35:30 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:35:31 INFO - PID 1660 | RSS: Main: 276131840 10:35:31 INFO - PID 1660 | 10:35:33 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:35:33 INFO - PID 1660 | RSS: Main: 275935232 10:35:33 INFO - PID 1660 | 10:35:35 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:35:36 INFO - PID 1660 | RSS: Main: 275902464 10:35:36 INFO - PID 1660 | 10:35:39 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:35:39 INFO - PID 1660 | RSS: Main: 275636224 10:35:39 INFO - PID 1660 | 10:35:43 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:35:43 INFO - PID 1660 | RSS: Main: 275955712 10:35:43 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 10:35:45 INFO - PID 1660 | RSS: Main: 278044672 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | __start_tp_report 10:35:45 INFO - PID 1660 | _x_x_mozilla_page_load 10:35:45 INFO - PID 1660 | _x_x_mozilla_page_load_details 10:35:45 INFO - PID 1660 | |i|pagename|runs| 10:35:45 INFO - PID 1660 | |0;ai-astar;104;116;119;116;115;114;114;114;116;114 10:35:45 INFO - PID 1660 | |1;audio-beat-detection;136;110;124;190;113;120;148;96;130;128 10:35:45 INFO - PID 1660 | |2;audio-dft;197;196;198;204;203;240;257;197;200;198 10:35:45 INFO - PID 1660 | |3;audio-fft;73;58;57;80;58;66;70;70;92;91 10:35:45 INFO - PID 1660 | |4;audio-oscillator;77;69;85;75;68;70;86;72;77;91 10:35:45 INFO - PID 1660 | |5;imaging-gaussian-blur;90;90;89;89;91;90;90;90;89;90 10:35:45 INFO - PID 1660 | |6;imaging-darkroom;96;96;96;96;96;97;95;97;106;98 10:35:45 INFO - PID 1660 | |7;imaging-desaturate;95;89;89;93;95;89;91;93;91;90 10:35:45 INFO - PID 1660 | |8;json-parse-financial;50;48;51;52;49;52;52;54;51;49 10:35:45 INFO - PID 1660 | |9;json-stringify-tinderbox;57;63;55;64;64;65;59;56;56;56 10:35:45 INFO - PID 1660 | |10;stanford-crypto-aes;83;83;82;83;83;82;84;83;83;81 10:35:45 INFO - PID 1660 | |11;stanford-crypto-ccm;146;137;135;133;133;140;137;138;187;137 10:35:45 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;178;186;185;185;225;237;188;188;190;183 10:35:45 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;67;70;73;71;70;67;69;67;70;67 10:35:45 INFO - PID 1660 | __end_tp_report 10:35:45 INFO - PID 1660 | __start_cc_report 10:35:45 INFO - PID 1660 | _x_x_mozilla_cycle_collect,910 10:35:45 INFO - PID 1660 | __end_cc_report 10:35:45 INFO - PID 1660 | __startTimestamp1495388145626__endTimestamp 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | ------- Summary: start ------- 10:35:45 INFO - PID 1660 | Number of tests: 14 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.50 stddev:3.91 (3.4%) stddev-sans-first:1.66 10:35:45 INFO - PID 1660 | Values: 104.0 116.0 119.0 116.0 115.0 114.0 114.0 114.0 116.0 114.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:129.50 Median:133.00 stddev:25.70 (19.3%) stddev-sans-first:27.15 10:35:45 INFO - PID 1660 | Values: 136.0 110.0 124.0 190.0 113.0 120.0 148.0 96.0 130.0 128.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:209.00 Median:201.50 stddev:21.36 (10.6%) stddev-sans-first:22.21 10:35:45 INFO - PID 1660 | Values: 197.0 196.0 198.0 204.0 203.0 240.0 257.0 197.0 200.0 198.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:71.50 Median:71.50 stddev:12.84 (18.0%) stddev-sans-first:13.61 10:35:45 INFO - PID 1660 | Values: 73.0 58.0 57.0 80.0 58.0 66.0 70.0 70.0 92.0 91.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:77.00 stddev:7.92 (10.3%) stddev-sans-first:8.40 10:35:45 INFO - PID 1660 | Values: 77.0 69.0 85.0 75.0 68.0 70.0 86.0 72.0 77.0 91.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:90.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 10:35:45 INFO - PID 1660 | Values: 90.0 90.0 89.0 89.0 91.0 90.0 90.0 90.0 89.0 90.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:97.30 Median:96.00 stddev:3.16 (3.3%) stddev-sans-first:3.32 10:35:45 INFO - PID 1660 | Values: 96.0 96.0 96.0 96.0 96.0 97.0 95.0 97.0 106.0 98.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:92.00 stddev:2.37 (2.6%) stddev-sans-first:2.15 10:35:45 INFO - PID 1660 | Values: 95.0 89.0 89.0 93.0 95.0 89.0 91.0 93.0 91.0 90.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.50 stddev:1.81 (3.5%) stddev-sans-first:1.90 10:35:45 INFO - PID 1660 | Values: 50.0 48.0 51.0 52.0 49.0 52.0 52.0 54.0 51.0 49.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:61.00 stddev:4.03 (6.6%) stddev-sans-first:4.18 10:35:45 INFO - PID 1660 | Values: 57.0 63.0 55.0 64.0 64.0 65.0 59.0 56.0 56.0 56.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 10:35:45 INFO - PID 1660 | Values: 83.0 83.0 82.0 83.0 83.0 82.0 84.0 83.0 83.0 81.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:142.30 Median:137.50 stddev:16.15 (11.7%) stddev-sans-first:17.07 10:35:45 INFO - PID 1660 | Values: 146.0 137.0 135.0 133.0 133.0 140.0 137.0 138.0 187.0 137.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.50 Median:188.00 stddev:19.72 (10.5%) stddev-sans-first:19.99 10:35:45 INFO - PID 1660 | Values: 178.0 186.0 185.0 185.0 225.0 237.0 188.0 188.0 190.0 183.0 10:35:45 INFO - PID 1660 | 10:35:45 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:2.08 (3.0%) stddev-sans-first:2.06 10:35:45 INFO - PID 1660 | Values: 67.0 70.0 73.0 71.0 70.0 67.0 69.0 67.0 70.0 67.0 10:35:45 INFO - PID 1660 | -------- Summary: end -------- 10:35:45 INFO - PID 1660 | 10:35:46 INFO - TEST-INFO | 1660: exit 0 10:35:46 INFO - TEST-OK | kraken | took 59420ms 10:35:46 INFO - SUITE-END | took 1173s 10:35:46 INFO - Completed test suite (00:19:33) 10:35:46 INFO - javascript benchmark 10:35:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7842.742723088426, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3306.421376259154, 3503.2993401319736, 3532.5407696999387, 3574.571051473823, 3597.982010089949, 4492.460904691442, 6003.21958145441, 6111.969440152793, 6169.16716243307, 6184.907226391608, 6192.225971753531, 6203.91431499488, 6218.160548325975, 6269.655168965705, 6271.372862713726, 5076.568491678212, 5301.1783173608155, 5446.373667028283, 5472.91790623141, 5481.397046324901, 5579.832605021856, 5584.1902924075985, 5590.832275031756, 5594.216809646653, 5643.661380317194, 4588.49526552079, 5118.976204759026, 5489.39616642169, 5507.586930980193, 5513.972430137824, 5512.614117011811, 5541.584381171388, 5542.362628297757, 5562.110062389998, 5638.830835074954, 4354.586314300136, 4537.251353526664, 5487.835364939058, 5500.009998200322, 5514.724263786795, 5265.578753699695, 6176.69116544171, 6242.781502647385, 6305.022721478178, 6314.989601663758, 6573.671316434159, 6630.867382652368, 6648.401643852077, 6651.833704157387, 6656.434203092743, 6756.0879281297375, 6782.559133656346, 6797.048413222153, 6816.284290149512, 6839.110915580993, 5908.881822363614, 6254.9358711625055, 6320.336364681734, 6339.809805705836, 6361.4592759615025, 5791.536677065825, 6784.660766961632, 6829.658517074126, 6912.170539535288, 6951.548149370275, 6413.0, 6555.147830781968, 6584.0, 6584.9012264816065, 6614.801555953329, 5952.672603006789, 6493.8701225976165, 6559.47524198063, 6617.106690596708, 6795.830104247433, 6594.7032383542855, 6607.405333520006, 6631.436327912085, 6637.236717777518, 6651.16860392451, 6664.0, 6664.333566643297, 6676.532642714963, 6681.5656982296, 6686.598804071771, 4937.753112344369, 4975.253711943237, 5232.686038837682, 5271.262023316739, 5317.707526086025, 4742.928856067162, 4772.0694464579765, 4817.686850354716, 4865.9026819463415, 4867.294242334841, 6241.313455519889, 6295.5907865988565, 6322.9051564226575, 6342.460890824239, 6381.712822922979, 6766.627835469097, 6774.762883299061, 6783.864322713518, 6790.5925644461495, 6796.626185559841, 6273.12176295319, 6284.183056202664, 6351.301356850743, 6353.110564520971, 6365.713542890581, 5958.195643588147, 5994.1608174855555, 6011.729472173763, 6022.488088512526, 6027.48766354856, 6920.100386949664, 6928.16861976566, 6966.616836073862, 6968.163820341591, 6981.720731170798, 6526.053722210348, 6621.073049773132, 6742.69657865407, 6800.217974932748, 6822.0, 6111.07520520229, 6583.045458408408, 6878.724851006104, 6902.0, 6926.376626103473, 6034.185384972972, 6651.900221496779, 6654.334566543307, 6678.365555272241, 6686.899696504654, 6286.0, 6725.663716814041, 6765.0, 6797.72809087631, 6818.863622727418, 5695.0, 5833.300003999547, 6361.363863613602, 6431.099646049651, 6534.248561415498, 6508.8047358579315, 6511.088447617242, 6562.573432726857, 6563.803085907431, 6569.770058047945, 4937.7531123444405, 5983.521318294613, 6357.523185760994, 6367.363263673781, 6404.487640988803, 5377.435369286169, 6041.637501749733, 6071.392860713893, 6553.606783593, 6562.048502966947, 6341.524385671001, 6414.262359828679, 6437.871242575216, 6457.741690332334, 6463.547551671338], "value": 6049.181737700109, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.142232144195, 784.9285724985397, 833.5582141465022, 1220.1337050694008, 1265.8734126587349, 38130.80934595327, 38606.61393386065, 38700.645477408296, 38741.0, 38777.61222387775, 38761.41857872134, 39151.80424097875, 39157.0, 39187.608123918755, 39300.0, 7770.922290777076, 7877.842443151134, 8290.544020078893, 8475.576221188916, 8481.279091277249, 8350.0, 8481.575921203947, 8494.915050849475, 8498.405111642187, 8499.277561407287, 7164.355208031277, 7176.282371762808, 8441.704540341088, 8516.531590762506, 8544.273736732353, 7327.0, 8374.036985746656, 8473.364497662638, 8485.95757021214, 8488.363372747008, 8230.794230144234, 8295.460795048302, 8307.460015098999, 8344.958275208646, 8356.832863342759, 27182.86408567964, 37061.81469092664, 37205.81397093024, 37297.627023729685, 39044.41433378502, 3567.9643203568153, 3614.5662520497444, 3622.945655815165, 3624.691901188402, 3638.235970446183, 29598.408031839244, 29643.70356296431, 29731.702682973326, 29739.851300743358, 29785.0, 3097.7212050915527, 3122.5160100184507, 3132.6710695376887, 3134.5141503066834, 3139.4035133324824, 266745.0, 267749.0, 316742.0, 320276.3986180101, 321785.3910730431, 48008.759956199996, 48392.75803620959, 48660.5133948656, 48773.26840097401, 48836.53490395294, 51183.74408127936, 51219.48780512222, 51281.23078153831, 51290.0, 51318.74340628348, 14075.577732668036, 14088.647783805282, 14190.290485475685, 14298.928505357406, 14302.284885755671, 11549.0, 11669.0, 11748.412579370997, 11761.000314973324, 11772.587959421378, 13671.726565468634, 13723.313834308245, 13731.176129432266, 13736.65658358529, 13829.30853457323, 6201.224846894138, 6214.316425193225, 6217.191765070603, 6240.469560087353, 6243.656598887105, 910.649399981012, 965.9712406287282, 1009.2733232072895, 1041.2086814021247, 1089.2266490791626, 23710.644340334908, 24844.50310993806, 24858.0, 25114.748852511242, 25159.87420062888, 995.9651412200539, 1044.639599338227, 1048.7729356652735, 1170.1750266062406, 1174.629991552658, 12555.686107847194, 12728.314769130984, 12761.489540418279, 12827.486900523874, 12887.968962482979, 331467.0, 333460.0, 333736.0, 334082.0, 334433.0, 49644.0071198574, 50814.0, 54753.726231368586, 54937.450625494035, 55475.0, 1435.2106341512335, 1560.617648676059, 1588.7775711400413, 1593.9123348215728, 1600.6718622682556, 22564.661530076733, 23168.07327706873, 24340.26979190627, 24490.387740306636, 24561.771911404, 37834.43248351222, 37854.0, 37885.81057094697, 37935.0, 37959.0, 239238.0, 245496.0, 271454.0, 271736.0, 272604.0, 34645.65354346424, 35028.12429689278, 35907.0, 35963.46054809128, 36037.81981090183, 43620.0, 44588.0, 45962.31056534222, 45978.08043839171, 46128.769356153, 23896.88051559731, 29033.838646453903, 30225.848870755504, 31234.531482027334, 31687.841560792047, 30256.243593910327, 31277.530837037008, 31377.52933706042, 31470.527942081346, 31800.0, 37700.0, 37708.811455942545, 37755.0, 37817.81091094527, 37887.05282367963, 29399.85300073486, 30048.849755751082, 31722.0, 31906.0, 32045.03864884057, 25028.749712502642, 25186.041183092475, 25229.24312270635, 25237.990480381315, 25352.366190845376], "value": 17588.344814522894, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.8194758201555, 438.64469779478617, 440.0934075803842, 453.82754553269774, 456.2403598009314, 19508.0, 20991.790082099174, 21799.346019619396, 21947.341579752592, 22040.44898877529, 21902.45243868904, 22759.772402275972, 22821.543569128607, 22943.0, 23145.884270578666, 5994.97002514989, 6008.75964961401, 6193.69031548423, 6240.188775459184, 6269.467095296905, 6026.698665066751, 6203.100550420187, 6206.069089636546, 6214.166820787856, 6217.502599792006, 6104.542159338045, 6114.327423983358, 6131.540134489909, 6142.0, 6176.814695559118, 5976.820695379146, 6145.569810113294, 6157.568970172089, 6165.876682466325, 6169.784057557987, 4689.812407503696, 5417.837464876021, 5473.0, 5473.987312347229, 5478.287822583039, 23743.525129497488, 26552.468950620878, 26772.464550708875, 26921.865390673116, 27665.308367290778, 2062.9174833006814, 2106.926257580977, 2233.419310979141, 2251.2008237075765, 2252.054137262354, 17566.736498952527, 17693.734593981102, 17935.641287174312, 18024.459266222035, 18030.819691803044, 2100.453881990678, 2112.0, 2158.816500597451, 2167.2414654870827, 2167.794059564339, 149661.0, 152121.0, 152635.47364526324, 154246.22876885656, 155699.44300556963, 10177.236707246986, 11896.821547676791, 12416.130870839135, 12419.0, 12572.245665260114, 13006.154599950973, 13123.409446574928, 16233.918830405772, 16247.431339903047, 16251.837481625269, 7360.96319518399, 8761.080086590944, 8932.169907779577, 8980.281577473786, 9148.954255228815, 6390.808275751735, 8247.95876020628, 8442.873356899529, 8676.696315628922, 8739.956300218586, 6512.6092434454085, 6525.706343214567, 7455.4408419368765, 7952.045754509496, 8017.879731804028, 2209.933701988943, 2220.5891909996703, 2232.359215976256, 2234.608943434925, 2237.172246268891, 900.486722568143, 933.1554942776797, 964.9144712198777, 985.482621623639, 997.5660587644398, 15695.529134125994, 20799.480012999797, 21576.460588485414, 21595.784042159692, 21720.456988575097, 845.7251393297203, 929.7907970706536, 1043.5251960358019, 1068.7541865370852, 1082.2802836113897, 9387.90612093884, 10845.0, 11123.777524449466, 11136.498857551429, 11215.66353009411, 529327.0, 537273.0, 547976.0, 549684.0, 554444.0, 89957.55021224852, 119284.80715192911, 120493.0, 126613.0, 129573.0, 1216.5377156680404, 1228.2446295528364, 1277.2208952538945, 1285.2352850054203, 1290.1871820753054, 22587.548249035255, 23001.931936394576, 23064.769352306263, 23127.88436057809, 23307.41731456659, 49313.52059438223, 52221.0, 58174.709126454094, 58490.707546463695, 58852.70573647104, 49224.50775492199, 74976.0, 77947.0, 81027.3794524118, 82724.58637706772, 8898.332625053017, 9177.715119883354, 9347.439153650803, 13064.150830196008, 13302.268375239455, 12378.195417297848, 13096.934515327363, 14483.420663173356, 14824.184669843262, 14918.701625967637, 11902.880971190523, 11982.400879956142, 12228.633141005785, 12229.571964981184, 12261.26432414058, 11253.606123785628, 11516.827247591125, 11564.94217528907, 11850.830247700018, 12003.63989080329, 10058.798824023625, 10885.836712449478, 11596.88403115958, 12313.753724925631, 12337.136400452242, 13213.207207567577, 13257.469701211843, 13403.865961340261, 13476.393562289917, 13601.319934003062, 10137.239707021856, 11797.710817557943, 11989.34058626784, 12168.0, 12186.0], "value": 10532.585762640443, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1394.8953828462866, 1453.8328092269385, 1462.663587374903, 1474.0713350589128, 1474.8525147485252, 9093.681721139754, 11126.554937802479, 11490.023348015418, 11516.481758320875, 11804.704882377944, 10901.727456813564, 11070.39112848793, 11100.722481937955, 11108.16688748343, 11119.666410007712, 2462.8768561571896, 2505.5991041433376, 2507.4734305795805, 2519.987400062997, 2522.4828910073484, 2091.4875855415444, 2152.332776839177, 2412.1798588479915, 2419.8790060496995, 2479.1942618648923, 2516.4086439686657, 2535.492901419714, 2544.1095616534158, 2551.0, 2551.5024570208816, 2491.5639763041486, 2526.4189236475713, 2528.1404322530243, 2528.6080657497932, 2533.5439620868237, 2469.1111199967995, 2481.04479775286, 2483.5653760592004, 2485.254423672909, 2486.154707399493, 10754.946225268903, 10779.622713205, 10787.622433214878, 10794.892051079545, 10799.082078023377, 6405.871882562369, 6651.135352404156, 6656.600603963778, 6659.2008958925235, 6677.0, 3534.452159915217, 3783.4892289541112, 3813.027677942107, 4196.034911970233, 4259.829606815723, 1168.976620467586, 1208.3474923541278, 1234.086775785921, 1260.1619922751358, 1264.4436447962867, 15910.0, 15925.601859953478, 16081.195940202942, 16087.597810054842, 16155.515334539983, 4760.904781904412, 4775.594074503706, 5206.193040078794, 5549.833504994856, 5551.666899986014, 11473.196876218584, 11485.0, 11491.77016459666, 11508.769824603462, 11536.96167344943, 6402.519811014193, 6427.453666438405, 6432.292447830735, 6435.549511534241, 6451.096846441502, 5802.187693722883, 5870.236869207061, 5982.790602328898, 6022.006368949074, 6040.939590604125, 3845.7115716321387, 4041.2523683118716, 4199.769012704331, 4269.146170765859, 4301.763403012802, 1243.3286025546104, 1384.1833318342226, 1426.3953052815582, 1434.0678558936565, 1438.4030627289767, 773.3619763694974, 804.6298702596754, 811.9323090136452, 816.4692949582819, 839.5886015852188, 6445.742170313229, 6774.864502709918, 6791.830204244835, 6859.759908403182, 6897.241303456616, 766.4519868294085, 797.9825722204235, 805.7381351060928, 917.655879045358, 940.4451373689419, 3681.0245284999496, 3779.962200377961, 3791.2038471920655, 4195.601417865298, 4381.277089280233, 13255.0, 13819.654508637163, 13821.447142114404, 13891.652708682364, 13903.582892513241, 13319.467221311234, 13648.317584120754, 13849.515266965607, 13942.651433714238, 13966.790498142536, 8033.076196237391, 8050.879236811453, 8206.917930820735, 8263.793405164919, 8307.293880020145, 5964.970175149097, 6365.96817015912, 6495.642739649365, 6800.0, 6805.863882722417, 9859.507024648596, 12087.879121209027, 12552.497900083894, 12615.558455454015, 13003.219806811621, 12512.43694033789, 12780.61658150257, 12830.422630981442, 12839.743205135657, 12857.421416036113, 8936.46381217129, 9018.909810901807, 9146.31402644791, 9150.359474836698, 9155.404898681565, 10103.09072183507, 10134.0, 10219.540120694735, 10292.639757608447, 10400.791984160425, 8309.83380332378, 8490.448120872123, 8528.019277783134, 8586.914130858611, 8742.300615950582, 8506.74479765608, 8518.403711740119, 8527.786805329917, 8557.400981931454, 8635.093315201813, 7877.133515313197, 7930.246626570467, 7948.602569871367, 8036.919630803851, 8050.597470126353, 5939.613925094855, 5960.493358064613, 6011.969940150271, 6038.215032045806, 6087.391260873877, 5928.229330187048, 6015.488683461955, 6038.365971573097, 6083.087536869328, 6103.420175083537], "value": 5210.390211311342, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.34935297611025, 385.10463173122497, 385.2045525988835, 387.6937219596519, 401.71478250442186, 5514.338279406466, 6215.689215539216, 6294.937050629493, 6306.653134077624, 6350.634163893133, 5296.0, 6104.5116390688745, 6165.691715414234, 6222.599915019118, 6257.843553911143, 3650.251698401828, 4449.799759010836, 4531.456225252974, 4563.338315944203, 4600.654950878681, 3835.347990841564, 3948.7433316834313, 3952.7628342299554, 4383.75889326087, 4573.679842411033, 3734.8692795752154, 3929.469521614574, 4296.785160741966, 4534.9319760203625, 4553.0, 3670.284294562535, 3917.275304068742, 4371.191329604034, 4479.2609219478745, 4541.682082254243, 3451.430513965193, 3652.926941461156, 3873.612638736132, 3877.3020856245867, 3882.786446745428, 5702.030654788696, 6112.358202388729, 6140.416660417298, 6156.445919867234, 6183.2889217740085, 4316.546762589915, 4798.760061996886, 4946.689127381247, 5389.688249400503, 5434.510894019557, 5614.494695477386, 6701.765438209688, 6728.629925354153, 6732.192136943598, 6735.676191713851, 3865.5168103987003, 3986.8637438330225, 4293.690424420551, 4354.477462704464, 4406.405135306757, 6224.813255602339, 6494.707738151747, 6912.758053468105, 6983.196932352794, 7128.287171282882, 4308.91382172361, 4424.6681498887065, 4431.224535706238, 4454.4877339106415, 4463.486699029589, 3673.7612055216327, 4501.41481607389, 4523.411956445641, 4526.79629416677, 4528.298113792367, 4484.551544845554, 4821.614270858323, 5209.645492172062, 5357.1696387060065, 5427.6200665953, 6668.299828518033, 6703.966480167568, 6706.966465167643, 6740.595564266159, 6764.222114456802, 1370.4586688258282, 1512.8638422541803, 1553.485351782026, 1555.2845690982049, 1560.7346751052348, 936.6628013914985, 939.0281059103795, 962.3071388600197, 1002.871769259583, 1006.8288390973552, 6303.92833218354, 6328.462080723098, 6357.396047375584, 6357.904631430532, 6375.585586936834, 6733.3939945405145, 6915.481338899603, 6936.514443988873, 7010.509264351548, 7081.752138675121, 5154.5103215194495, 6308.810735677937, 6334.651594162366, 6398.880195965688, 6412.006139048455, 3023.486007378751, 3088.822380221938, 3491.319192757439, 3673.834677439522, 3693.298273328113, 6035.973884439656, 6447.548671592944, 7243.239459856744, 7263.0194923685685, 7285.854282914312, 5939.4951429129005, 6698.995150727333, 7233.348998590152, 7272.945422913648, 7277.543166995635, 7028.2620324866175, 7035.994741498751, 7186.233300179469, 7265.782026539212, 7272.527285726411, 5884.205632239764, 6163.414475624809, 7023.277662838704, 7039.683214255473, 7073.681684324115, 5985.191999080243, 6809.557378770364, 6860.142482189726, 6883.965580172067, 6938.0, 6991.580505169706, 7125.67934442941, 7272.23641517633, 7274.454415918722, 7282.798338274126, 3475.7740746851373, 3759.93565244325, 4239.872803815767, 4396.2851390574915, 4408.647308215399, 6947.92307192386, 7003.719851205895, 7091.503594748318, 7115.964420178073, 7124.857502850017, 5975.7749661320195, 6802.6258555779905, 6826.795196144124, 6835.965820171066, 6853.109095817512, 5792.681402522901, 6167.6916154193, 6734.898976515454, 6856.41720453767, 6857.211420686485, 6662.300458451793, 6673.799786006428, 6685.298043705341, 6693.966530167318, 6707.4969377297875, 6550.377714117151, 6755.256921738703, 6781.660916954232, 6827.453803695792, 6842.828929276808, 6642.4686025118845, 6681.732730690718, 6683.398494135551, 6691.230508491586, 6700.564463309869], "value": 4796.876666620401, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.8356394465328, 823.4647479138563, 844.5017439710572, 846.9195426434486, 858.9263420724095, 14390.208538530376, 14944.626384340398, 16291.51125466235, 16742.665146697058, 16747.162641867904, 16763.24565394557, 16953.0, 17443.476695699148, 17490.300387984465, 17557.736633950502, 971.5045326883287, 991.5984026469291, 995.3629677006721, 999.3054826895311, 1003.2225025605142, 1004.5128112865291, 1012.2661070723703, 1016.6289304403876, 1017.6489111256604, 1018.6282007067441, 1033.5813995331864, 1060.2366296266714, 1061.7345663584103, 1062.1768129699506, 1069.7218723132003, 1033.7725700345911, 1039.5062345385957, 1060.0671409159968, 1067.146282973622, 1071.5499490214129, 770.433731207562, 786.4769927997875, 786.5909726942016, 788.9447738658296, 790.075611534506, 16422.425215117415, 16955.91522042382, 17133.742993855103, 17183.0, 17183.312667493286, 16312.75530867038, 16673.166341682867, 16962.49112526626, 17120.143992800437, 17170.313187472486, 14058.226797526131, 14407.711845763131, 14473.27633618315, 14513.056651317736, 14516.927415362856, 3459.9827000865084, 3593.6406359364114, 3605.4952306677087, 3630.146915474861, 3632.4369722693023, 114420.0, 116348.0, 116493.83506164915, 117219.0, 117274.41362793217, 11208.327500350006, 13319.600411987656, 13361.0, 13407.597772066854, 13539.729205415837, 21606.91965401755, 24535.877320613283, 25059.87470062638, 25580.488390232462, 25980.350491237492, 5903.468687818117, 6674.299198584175, 6942.132233470817, 7633.541987480658, 7735.839624056436, 3295.423300922329, 3979.840806367713, 3983.42240375144, 3984.5019372578427, 3986.2824691555797, 4009.2182024505523, 4137.834486620502, 4224.366345048267, 4396.3625274335045, 4397.648188144941, 919.8252332057021, 948.8481842905102, 949.6059135458845, 949.6913503111516, 959.7840485890758, 630.8643641616983, 636.5925807483214, 639.9328070552618, 652.3802387731579, 654.0842820051965, 11080.889191108146, 11430.714232144262, 11856.822147667792, 11924.523019079314, 11960.342181179945, 630.7477009196266, 641.974321027163, 644.7453255963868, 651.3584119642148, 662.9701663425158, 5254.42201357858, 5310.230016647642, 5322.0952438085615, 5566.997940370772, 5920.37836027211, 95468.0, 95847.0, 96188.51905740426, 96189.0, 98293.50853245688, 63544.0, 82299.0, 87974.56012719896, 88328.5583572078, 91144.54427727818, 78326.0, 79139.0, 79226.6038669803, 79583.20416795874, 81294.59352703199, 10775.73060673458, 12218.0, 12298.262104273772, 12338.876611233773, 12369.0, 29134.0, 39026.02434939149, 39263.803680981415, 39482.0, 39525.01187470336, 67215.02351171573, 69844.6507767458, 71441.57116857737, 71725.64137179281, 74520.62739686266, 15934.760978585098, 16497.0, 16645.731148064177, 17063.829361706223, 17231.913840430716, 17994.280228790703, 18090.27638894482, 18115.315621423844, 18161.545961351072, 18537.81462185361, 16021.519354419386, 16486.917565412095, 16774.49676509707, 17012.234449449556, 17026.978381297162, 16763.580910477336, 16854.57863553421, 16945.32218711238, 16982.830171698126, 17257.65484690274, 16109.113998729714, 16443.9177804115, 16683.332666693197, 16812.57968550747, 17100.828991709925, 9838.36050656705, 11341.334786325371, 11838.526458941546, 12071.155019148575, 12270.693232669255, 12053.63839084829, 12143.757124857277, 12416.0, 13089.607311780663, 13149.342532873126], "value": 8307.890777711376, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 119.0, 116.0, 115.0, 114.0, 114.0, 114.0, 116.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 110.0, 124.0, 190.0, 113.0, 120.0, 148.0, 96.0, 130.0, 128.0], "value": 129.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 196.0, 198.0, 204.0, 203.0, 240.0, 257.0, 197.0, 200.0, 198.0], "value": 209.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 58.0, 57.0, 80.0, 58.0, 66.0, 70.0, 70.0, 92.0, 91.0], "value": 71.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 69.0, 85.0, 75.0, 68.0, 70.0, 86.0, 72.0, 77.0, 91.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 89.0, 91.0, 90.0, 90.0, 90.0, 89.0, 90.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 95.0, 97.0, 106.0, 98.0], "value": 97.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 89.0, 89.0, 93.0, 95.0, 89.0, 91.0, 93.0, 91.0, 90.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 51.0, 52.0, 49.0, 52.0, 52.0, 54.0, 51.0, 49.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 55.0, 64.0, 64.0, 65.0, 59.0, 56.0, 56.0, 56.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 83.0, 83.0, 82.0, 84.0, 83.0, 83.0, 81.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 137.0, 135.0, 133.0, 133.0, 140.0, 137.0, 138.0, 187.0, 137.0], "value": 142.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 186.0, 185.0, 185.0, 225.0, 237.0, 188.0, 188.0, 190.0, 183.0], "value": 194.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 73.0, 71.0, 70.0, 67.0, 69.0, 67.0, 70.0, 67.0], "value": 69.1, "unit": "score"}]}]} 10:35:46 INFO - Return code: 0 10:35:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:35:46 INFO - # TBPL SUCCESS # 10:35:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:35:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:35:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:35:46 INFO - 2017-05-21 10:35:46.977 screenresolution[1667:10792] starting screenresolution argv=screenresolution get 10:35:46 INFO - 2017-05-21 10:35:46.980 screenresolution[1667:10792] Display 0: 1600x1200x32@60 10:35:46 INFO - 2017-05-21 10:35:46.986 screenresolution[1668:10795] starting screenresolution argv=screenresolution list 10:35:46 INFO - Available Modes on Display 0 10:35:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:35:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:35:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:35:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:35:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:35:47 INFO - Intel Iris: 10:35:47 INFO - Chipset Model: Intel Iris 10:35:47 INFO - Type: GPU 10:35:47 INFO - Bus: Built-In 10:35:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:35:47 INFO - Vendor: Intel (0x8086) 10:35:47 INFO - Device ID: 0x0a2e 10:35:47 INFO - Revision ID: 0x0009 10:35:47 INFO - Displays: 10:35:47 INFO - Display: 10:35:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:35:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:35:47 INFO - Display Serial Number: 1600x1200 60 10:35:47 INFO - Main Display: Yes 10:35:47 INFO - Mirror: Off 10:35:47 INFO - Online: Yes 10:35:47 INFO - Rotation: Supported 10:35:47 INFO - Return code: 0 10:35:47 INFO - Running post-action listener: _resource_record_post_action 10:35:47 INFO - [mozharness: 2017-05-21 17:35:47.222612Z] Finished run-tests step (success) 10:35:47 INFO - Running post-run listener: _resource_record_post_run 10:35:47 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 40303616; Write bytes: 565551104; Read time: 263; Write time: 1683 10:35:47 INFO - TinderboxPrint: CPU usage
54.2% 10:35:47 INFO - TinderboxPrint: I/O read bytes / time
40,303,616 / 263 10:35:47 INFO - TinderboxPrint: I/O write bytes / time
565,551,104 / 1,683 10:35:47 INFO - TinderboxPrint: CPU idle
2,191.3 (45.8%) 10:35:47 INFO - TinderboxPrint: CPU system
961.6 (20.1%) 10:35:47 INFO - TinderboxPrint: CPU user
1,628.8 (34.1%) 10:35:47 INFO - TinderboxPrint: Swap in / out
284,692,480 / 0 10:35:47 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 234287616; Write bytes: 229849088; Read time: 16804; Write time: 453 10:35:47 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 38304768; Write bytes: 316405760; Read time: 245; Write time: 1182 10:35:47 INFO - Running post-run listener: _upload_blobber_files 10:35:47 INFO - Blob upload gear active. 10:35:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:35:47 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 10:35:47 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'] 10:35:47 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 10:35:48 INFO - (blobuploader) - INFO - Open directory for files ... 10:35:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:35:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:35:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:49 INFO - (blobuploader) - INFO - Done attempting. 10:35:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:35:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:35:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:50 INFO - (blobuploader) - INFO - Done attempting. 10:35:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:35:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:35:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:51 INFO - (blobuploader) - INFO - Done attempting. 10:35:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:35:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:35:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:53 INFO - (blobuploader) - INFO - Done attempting. 10:35:53 INFO - (blobuploader) - INFO - Iteration through files over. 10:35:53 INFO - Return code: 0 10:35:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:35:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:35:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c7d76539b7796300f566392c15ffa5dc9db149bd23b9d08fc8555435fa821e457fd7fd25a235ca19131fa2d6fa3565cecd4a8642ac4916e4e49f39b8a4138ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed7174ebd9ef32049153c8f038948061f9c1273e699ae0cc4a1a11694d96cf57940f337a70f4f8a29b5cf9b23c423e30aa3744677400b019c5860546eddf9ebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b590f39153f7c04ac6082b249fd702dd9a3fd9cd0f57a9924c7df36a98e07aa6a503f0f374ea97114f2ae9ee4aa862c5693571904dc2e99c12715c9bb56a2e3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7745495646f9eda74c5593f1b71804f80d7f4a5c886b636b8d2a99c4ea13866e2a3d3c3afbfb547ff19e39bf161c67cff506084d73d62145dfdbd96b28f1539"} 10:35:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:35:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:35:53 INFO - Contents: 10:35:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c7d76539b7796300f566392c15ffa5dc9db149bd23b9d08fc8555435fa821e457fd7fd25a235ca19131fa2d6fa3565cecd4a8642ac4916e4e49f39b8a4138ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed7174ebd9ef32049153c8f038948061f9c1273e699ae0cc4a1a11694d96cf57940f337a70f4f8a29b5cf9b23c423e30aa3744677400b019c5860546eddf9ebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b590f39153f7c04ac6082b249fd702dd9a3fd9cd0f57a9924c7df36a98e07aa6a503f0f374ea97114f2ae9ee4aa862c5693571904dc2e99c12715c9bb56a2e3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7745495646f9eda74c5593f1b71804f80d7f4a5c886b636b8d2a99c4ea13866e2a3d3c3afbfb547ff19e39bf161c67cff506084d73d62145dfdbd96b28f1539"} 10:35:53 INFO - Running post-run listener: copy_logs_to_upload_dir 10:35:53 INFO - Copying logs to upload dir... 10:35:53 INFO - mkdir: /builds/slave/test/build/upload/logs 10:35:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.542346 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-21 10:35:53.617669) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 10:35:53.620789) ========= 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.2WbBOicuJM/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.7nXu8RyLkz/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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/7c7d76539b7796300f566392c15ffa5dc9db149bd23b9d08fc8555435fa821e457fd7fd25a235ca19131fa2d6fa3565cecd4a8642ac4916e4e49f39b8a4138ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed7174ebd9ef32049153c8f038948061f9c1273e699ae0cc4a1a11694d96cf57940f337a70f4f8a29b5cf9b23c423e30aa3744677400b019c5860546eddf9ebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b590f39153f7c04ac6082b249fd702dd9a3fd9cd0f57a9924c7df36a98e07aa6a503f0f374ea97114f2ae9ee4aa862c5693571904dc2e99c12715c9bb56a2e3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7745495646f9eda74c5593f1b71804f80d7f4a5c886b636b8d2a99c4ea13866e2a3d3c3afbfb547ff19e39bf161c67cff506084d73d62145dfdbd96b28f1539"} build_url:https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011099 build_url: 'https://queue.taskcluster.net/v1/task/Vf4tUfziRTmHMR2y3JYZag/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/7c7d76539b7796300f566392c15ffa5dc9db149bd23b9d08fc8555435fa821e457fd7fd25a235ca19131fa2d6fa3565cecd4a8642ac4916e4e49f39b8a4138ea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed7174ebd9ef32049153c8f038948061f9c1273e699ae0cc4a1a11694d96cf57940f337a70f4f8a29b5cf9b23c423e30aa3744677400b019c5860546eddf9ebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b590f39153f7c04ac6082b249fd702dd9a3fd9cd0f57a9924c7df36a98e07aa6a503f0f374ea97114f2ae9ee4aa862c5693571904dc2e99c12715c9bb56a2e3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c7745495646f9eda74c5593f1b71804f80d7f4a5c886b636b8d2a99c4ea13866e2a3d3c3afbfb547ff19e39bf161c67cff506084d73d62145dfdbd96b28f1539"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 10:35:53.653739) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 10:35:53.654078) ========= 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.2WbBOicuJM/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.7nXu8RyLkz/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.005148 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 10:35:53.702377) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 10:35:53.702699) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 10:35:53.703037) ========= ========= Total master_lag: 0.17 =========