builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0314 starttime: 1494984302.48 results: success (0) revision: 985b3ee939338022ef44028b5251f77af19c3638 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:02.475566) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:02.476040) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:02.476360) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:02.501844) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:02.502252) ========= 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.Fqz84EzdjL/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.4khZ427Zvo/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-16 18:25:02-- 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% 19.4M=0.001s 2017-05-16 18:25:02 (19.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123104 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:02.649255) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:02.649580) ========= 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.Fqz84EzdjL/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.4khZ427Zvo/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.026706 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:02.698449) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 18:25:02.698932) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 985b3ee939338022ef44028b5251f77af19c3638 --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 985b3ee939338022ef44028b5251f77af19c3638 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Fqz84EzdjL/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.4khZ427Zvo/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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-16 18:25:02,796 truncating revision to first 12 chars 2017-05-16 18:25:02,796 Setting DEBUG logging. 2017-05-16 18:25:02,796 attempt 1/10 2017-05-16 18:25:02,796 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/985b3ee93933?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 18:25:04,051 unpacking tar archive at: mozilla-central-985b3ee93933/testing/mozharness/ program finished with exit code 0 elapsedTime=1.521662 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 18:25:04.244269) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:04.244602) ========= script_repo_revision: 985b3ee939338022ef44028b5251f77af19c3638 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:04.245187) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:04.245481) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 18:25:04.264302) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-16 18:25:04.264692) ========= /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.Fqz84EzdjL/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.4khZ427Zvo/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:25:04 INFO - MultiFileLogger online at 20170516 18:25:04 in /builds/slave/test 18:25:04 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 18:25:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:25:04 INFO - {'append_to_log': False, 18:25:04 INFO - 'base_work_dir': '/builds/slave/test', 18:25:04 INFO - 'blob_upload_branch': 'Firefox', 18:25:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:25:04 INFO - 'branch': 'Firefox', 18:25:04 INFO - 'buildbot_json_path': 'buildprops.json', 18:25:04 INFO - 'config_files': ('talos/mac_config.py',), 18:25:04 INFO - 'default_actions': ('clobber', 18:25:04 INFO - 'read-buildbot-config', 18:25:04 INFO - 'download-and-extract', 18:25:04 INFO - 'populate-webroot', 18:25:04 INFO - 'create-virtualenv', 18:25:04 INFO - 'install', 18:25:04 INFO - 'run-tests'), 18:25:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:25:04 INFO - 'download_minidump_stackwalk': True, 18:25:04 INFO - 'download_symbols': 'ondemand', 18:25:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:25:04 INFO - 'tooltool.py': '/tools/tooltool.py', 18:25:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:25:04 INFO - '/tools/misc-python/virtualenv.py')}, 18:25:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:25:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:25:04 INFO - 'gecko_profile': False, 18:25:04 INFO - 'gecko_profile_interval': 0, 18:25:04 INFO - 'installer_path': 'installer.exe', 18:25:04 INFO - 'log_level': 'info', 18:25:04 INFO - 'log_name': 'talos', 18:25:04 INFO - 'log_to_console': True, 18:25:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:25:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:25:04 INFO - 'opt_config_files': (), 18:25:04 INFO - 'pip_index': False, 18:25:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:25:04 INFO - 'cmd': ('bash', 18:25:04 INFO - '-c', 18:25:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:25:04 INFO - 'enabled': True, 18:25:04 INFO - 'halt_on_failure': False, 18:25:04 INFO - 'name': 'check_screen_resolution'},), 18:25:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:25:04 INFO - 'cmd': ('bash', 18:25:04 INFO - '-c', 18:25:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:25:04 INFO - 'enabled': True, 18:25:04 INFO - 'halt_on_failure': False, 18:25:04 INFO - 'name': 'check_screen_resolution'},), 18:25:04 INFO - 'run_cmd_checks_enabled': True, 18:25:04 INFO - 'suite': 'dromaeojs-e10s', 18:25:04 INFO - 'system_bits': '32', 18:25:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:25:04 INFO - 'title': 't-yosemite-r7-0314', 18:25:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:25:04 INFO - 'use_talos_json': True, 18:25:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:25:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:25:04 INFO - 'work_dir': 'build'} 18:25:04 INFO - [mozharness: 2017-05-17 01:25:04.471356Z] Running clobber step. 18:25:04 INFO - Running pre-action listener: _resource_record_pre_action 18:25:04 INFO - Running main action method: clobber 18:25:04 INFO - rmtree: /builds/slave/test/build 18:25:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:25:07 INFO - Running post-action listener: _resource_record_post_action 18:25:07 INFO - [mozharness: 2017-05-17 01:25:07.017584Z] Finished clobber step (success) 18:25:07 INFO - [mozharness: 2017-05-17 01:25:07.017714Z] Running read-buildbot-config step. 18:25:07 INFO - Running pre-action listener: _resource_record_pre_action 18:25:07 INFO - Running main action method: read_buildbot_config 18:25:07 INFO - Using buildbot properties: 18:25:07 INFO - { 18:25:07 INFO - "project": "", 18:25:07 INFO - "product": "firefox", 18:25:07 INFO - "who": "ryanvm@gmail.com", 18:25:07 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:25:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:25:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:25:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:25:07 INFO - "stage_platform": "macosx64", 18:25:07 INFO - "basedir": "/builds/slave/test", 18:25:07 INFO - "buildnumber": 65, 18:25:07 INFO - "platform": "yosemite_r7", 18:25:07 INFO - "parent_task_id": "UNqw_RLvTtaon9Bho4zygg", 18:25:07 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:25:07 INFO - "slavebuilddir": "test", 18:25:07 INFO - "taskId": "XGX9qnizRE6fBRYaiL-Vug", 18:25:07 INFO - "branch": "mozilla-central", 18:25:07 INFO - "script_repo_revision": "production", 18:25:07 INFO - "revision": "985b3ee939338022ef44028b5251f77af19c3638", 18:25:07 INFO - "slavename": "t-yosemite-r7-0314", 18:25:07 INFO - "repo_path": "mozilla-central" 18:25:07 INFO - } 18:25:07 INFO - Finding installer, test and symbols from parent task. 18:25:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XGX9qnizRE6fBRYaiL-Vug'}, attempt #1 18:25:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XGX9qnizRE6fBRYaiL-Vug'}, attempt #1 18:25:07 INFO - Task dependencies: aBkuBIakTaKHRduh5Iw5Tg 18:25:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg'}, attempt #1 18:25:08 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:25:08 INFO - Set test_url: https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/test_packages.json 18:25:08 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/target.crashreporter-symbols.zip 18:25:08 INFO - Running post-action listener: _resource_record_post_action 18:25:08 INFO - [mozharness: 2017-05-17 01:25:08.393048Z] Finished read-buildbot-config step (success) 18:25:08 INFO - [mozharness: 2017-05-17 01:25:08.393187Z] Running download-and-extract step. 18:25:08 INFO - Running pre-action listener: _resource_record_pre_action 18:25:08 INFO - Running main action method: download_and_extract 18:25:08 INFO - mkdir: /builds/slave/test/build/tests 18:25:08 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')]} 18:25:08 INFO - trying https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:25:08 INFO - Downloading https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/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 18:25:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/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 18:25:09 INFO - Downloaded 1325 bytes. 18:25:09 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:25:09 INFO - Using the following test package requirements: 18:25:09 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:09 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:25:09 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:25:09 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:09 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:25:09 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:09 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:25:09 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:09 INFO - u'jsshell-mac.zip'], 18:25:09 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:09 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:25:09 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:25:09 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:09 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:25:09 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:09 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:25:09 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:09 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:25:09 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:25:09 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:25:09 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:25:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:25:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:25:09 INFO - Fetch https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:25:11 INFO - Content-Length response header: 56566471 18:25:11 INFO - Bytes received: 56566471 18:25:28 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 18:25:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:25:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:25:28 INFO - Fetch https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:25:30 INFO - Content-Length response header: 56566471 18:25:30 INFO - Bytes received: 56566471 18:25:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:25:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:25:46 INFO - Fetch https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:25:47 INFO - Content-Length response header: 14410069 18:25:47 INFO - Bytes received: 14410069 18:25:48 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')]} 18:25:48 INFO - trying https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:25:48 INFO - Downloading https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:25:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/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 18:25:50 INFO - Downloaded 75951926 bytes. 18:25:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:25:50 INFO - mkdir: /builds/slave/test/properties 18:25:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:25:50 INFO - Writing to file /builds/slave/test/properties/build_url 18:25:50 INFO - Contents: 18:25:50 INFO - build_url:https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:25:50 INFO - Running post-action listener: _resource_record_post_action 18:25:50 INFO - Running post-action listener: set_extra_try_arguments 18:25:50 INFO - [mozharness: 2017-05-17 01:25:50.309646Z] Finished download-and-extract step (success) 18:25:50 INFO - [mozharness: 2017-05-17 01:25:50.309809Z] Running populate-webroot step. 18:25:50 INFO - Running pre-action listener: _resource_record_pre_action 18:25:50 INFO - Running main action method: populate_webroot 18:25:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:25:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:25:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:25:50 INFO - '--tppagecycles', 18:25:50 INFO - '7'], 18:25:50 INFO - 'tests': ['tsvgm']}}, 18:25:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:25:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:25:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:25:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:25:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:25:50 INFO - 'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:25:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:25:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:25:50 INFO - 'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['damp', 'tps']}, 18:25:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:25:50 INFO - 'tests': ['damp', 'tps']}, 18:25:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['dromaeo_dom']}, 18:25:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:25:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:25:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:25:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:25:50 INFO - 'tests': ['ts_paint', 'tp5o']}, 18:25:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['a11yr', 18:25:50 INFO - 'ts_paint', 18:25:50 INFO - 'tpaint', 18:25:50 INFO - 'sessionrestore', 18:25:50 INFO - 'sessionrestore_no_auto_restore', 18:25:50 INFO - 'tabpaint']}, 18:25:50 INFO - 'other-e10s': {'tests': ['a11yr', 18:25:50 INFO - 'ts_paint', 18:25:50 INFO - 'tpaint', 18:25:50 INFO - 'sessionrestore', 18:25:50 INFO - 'sessionrestore_no_auto_restore', 18:25:50 INFO - 'tabpaint']}, 18:25:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:25:50 INFO - 'ts_paint', 18:25:50 INFO - 'tpaint', 18:25:50 INFO - 'sessionrestore', 18:25:50 INFO - 'sessionrestore_no_auto_restore', 18:25:50 INFO - 'tabpaint']}, 18:25:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:25:50 INFO - 'ts_paint', 18:25:50 INFO - 'tpaint', 18:25:50 INFO - 'sessionrestore', 18:25:50 INFO - 'sessionrestore_no_auto_restore', 18:25:50 INFO - 'tabpaint']}, 18:25:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['a11yr', 18:25:50 INFO - 'ts_paint', 18:25:50 INFO - 'tpaint', 18:25:50 INFO - 'sessionrestore', 18:25:50 INFO - 'sessionrestore_no_auto_restore', 18:25:50 INFO - 'tabpaint']}, 18:25:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['a11yr', 18:25:50 INFO - 'ts_paint', 18:25:50 INFO - 'tpaint', 18:25:50 INFO - 'sessionrestore', 18:25:50 INFO - 'sessionrestore_no_auto_restore', 18:25:50 INFO - 'tabpaint']}, 18:25:50 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:25:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:25:50 INFO - 'bloom_basic_ref']}, 18:25:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['tsvgx', 18:25:50 INFO - 'tsvgr_opacity', 18:25:50 INFO - 'tart', 18:25:50 INFO - 'tscrollx', 18:25:50 INFO - 'cart', 18:25:50 INFO - 'tsvg_static']}, 18:25:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:25:50 INFO - 'tsvgr_opacity', 18:25:50 INFO - 'tart', 18:25:50 INFO - 'tscrollx', 18:25:50 INFO - 'cart', 18:25:50 INFO - 'tsvg_static']}, 18:25:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:25:50 INFO - 'talos_options': ['--disable-e10s'], 18:25:50 INFO - 'tests': ['tp5o']}, 18:25:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:25:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:25:50 INFO - 'talos_options': ['--disable-e10s', 18:25:50 INFO - '--xperf_path', 18:25:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:50 INFO - 'tests': ['tp5n']}, 18:25:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:25:50 INFO - 'talos_options': ['--xperf_path', 18:25:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:25:50 INFO - 'tests': ['tp5n']}}, 18:25:50 INFO - 'talos.zip': {'path': '', 18:25:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:25:50 INFO - Running post-action listener: _resource_record_post_action 18:25:50 INFO - [mozharness: 2017-05-17 01:25:50.331152Z] Finished populate-webroot step (success) 18:25:50 INFO - [mozharness: 2017-05-17 01:25:50.331301Z] Running create-virtualenv step. 18:25:50 INFO - Running pre-action listener: _resource_record_pre_action 18:25:50 INFO - Running main action method: create_virtualenv 18:25:50 INFO - Creating virtualenv /builds/slave/test/build/venv 18:25:50 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 18:25:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:25:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:25:50 INFO - Using real prefix '/tools/python27' 18:25:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:25:51 INFO - Installing distribute.............................................................................................................................................................................................done. 18:25:54 INFO - Installing pip.................done. 18:25:54 INFO - Return code: 0 18:25:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:25:54 INFO - Reading from file tmpfile_stdout 18:25:54 INFO - Output received: 18:25:54 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) 18:25:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:25:54 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')]} 18:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:54 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')]} 18:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:54 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 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:25:54 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 18:25:54 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 18:25:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:25:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:54 INFO - 'HOME': '/Users/cltbld', 18:25:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:54 INFO - 'LOGNAME': 'cltbld', 18:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:54 INFO - 'MOZ_NO_REMOTE': '1', 18:25:54 INFO - 'NO_EM_RESTART': '1', 18:25:54 INFO - 'PAGER': '/bin/cat', 18:25:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:54 INFO - 'PWD': '/builds/slave/test', 18:25:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:54 INFO - 'SHELL': '/bin/bash', 18:25:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:25:54 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:25:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:54 INFO - 'USER': 'cltbld', 18:25:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:54 INFO - 'XPC_FLAGS': '0x0', 18:25:54 INFO - 'XPC_SERVICE_NAME': '0', 18:25:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:54 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 18:25:54 INFO - Cleaning up... 18:25:54 INFO - Return code: 0 18:25:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:25:54 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')]} 18:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:54 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')]} 18:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:54 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 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:25:54 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 18:25:54 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 18:25:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:25:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:54 INFO - 'HOME': '/Users/cltbld', 18:25:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:54 INFO - 'LOGNAME': 'cltbld', 18:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:54 INFO - 'MOZ_NO_REMOTE': '1', 18:25:54 INFO - 'NO_EM_RESTART': '1', 18:25:54 INFO - 'PAGER': '/bin/cat', 18:25:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:54 INFO - 'PWD': '/builds/slave/test', 18:25:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:54 INFO - 'SHELL': '/bin/bash', 18:25:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:25:54 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:25:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:54 INFO - 'USER': 'cltbld', 18:25:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:54 INFO - 'XPC_FLAGS': '0x0', 18:25:54 INFO - 'XPC_SERVICE_NAME': '0', 18:25:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:54 INFO - Downloading/unpacking psutil>=3.1.1 18:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:25:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:56 INFO - Installing collected packages: psutil 18:25:56 INFO - Running setup.py install for psutil 18:25:56 INFO - building 'psutil._psutil_osx' extension 18:25:56 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 18:25:57 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 18:25:57 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 18:25:57 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 18:25:57 INFO - building 'psutil._psutil_posix' extension 18:25:57 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 18:25:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:25:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:25:57 INFO - ^ 18:25:57 INFO - 1 warning generated. 18:25:57 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 18:25:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:25:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:25:57 INFO - Successfully installed psutil 18:25:57 INFO - Cleaning up... 18:25:57 INFO - Return code: 0 18:25:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:25:57 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 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:25:57 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 18:25:57 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 18:25:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:25:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:25:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:25:57 INFO - 'HOME': '/Users/cltbld', 18:25:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:25:57 INFO - 'LOGNAME': 'cltbld', 18:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:25:57 INFO - 'MOZ_NO_REMOTE': '1', 18:25:57 INFO - 'NO_EM_RESTART': '1', 18:25:57 INFO - 'PAGER': '/bin/cat', 18:25:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:25:57 INFO - 'PWD': '/builds/slave/test', 18:25:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:25:57 INFO - 'SHELL': '/bin/bash', 18:25:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:25:57 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:25:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:25:57 INFO - 'USER': 'cltbld', 18:25:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:25:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:25:57 INFO - 'XPC_FLAGS': '0x0', 18:25:57 INFO - 'XPC_SERVICE_NAME': '0', 18:25:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:25:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:25:58 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 18:25:58 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 18:25:58 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 18:25:58 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 18:25:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:25:59 INFO - Installing collected packages: mozsystemmonitor 18:25:59 INFO - Running setup.py install for mozsystemmonitor 18:25:59 INFO - Successfully installed mozsystemmonitor 18:25:59 INFO - Cleaning up... 18:25:59 INFO - Return code: 0 18:25:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:00 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')]} 18:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:00 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 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:26:00 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 18:26:00 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 18:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:00 INFO - 'HOME': '/Users/cltbld', 18:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:00 INFO - 'LOGNAME': 'cltbld', 18:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:00 INFO - 'MOZ_NO_REMOTE': '1', 18:26:00 INFO - 'NO_EM_RESTART': '1', 18:26:00 INFO - 'PAGER': '/bin/cat', 18:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:00 INFO - 'PWD': '/builds/slave/test', 18:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:00 INFO - 'SHELL': '/bin/bash', 18:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:26:00 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:00 INFO - 'USER': 'cltbld', 18:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:00 INFO - 'XPC_FLAGS': '0x0', 18:26:00 INFO - 'XPC_SERVICE_NAME': '0', 18:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:00 INFO - Downloading/unpacking jsonschema==2.5.1 18:26:00 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 18:26:00 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 18:26:00 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 18:26:00 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 18:26:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:26:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:02 INFO - Downloading functools32-3.2.3-2.tar.gz 18:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:26:02 INFO - warning: no files found matching '*.txt' 18:26:02 INFO - no previously-included directories found matching 'build' 18:26:02 INFO - no previously-included directories found matching 'dist' 18:26:02 INFO - no previously-included directories found matching '.git*' 18:26:02 INFO - Installing collected packages: jsonschema, functools32 18:26:02 INFO - Running setup.py install for functools32 18:26:02 INFO - warning: no files found matching '*.txt' 18:26:02 INFO - no previously-included directories found matching 'build' 18:26:02 INFO - no previously-included directories found matching 'dist' 18:26:02 INFO - no previously-included directories found matching '.git*' 18:26:03 INFO - Successfully installed jsonschema functools32 18:26:03 INFO - Cleaning up... 18:26:03 INFO - Return code: 0 18:26:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:26:03 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')]} 18:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:03 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')]} 18:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:03 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 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:26:03 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 18:26:03 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 18:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:03 INFO - 'HOME': '/Users/cltbld', 18:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:03 INFO - 'LOGNAME': 'cltbld', 18:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:03 INFO - 'MOZ_NO_REMOTE': '1', 18:26:03 INFO - 'NO_EM_RESTART': '1', 18:26:03 INFO - 'PAGER': '/bin/cat', 18:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:03 INFO - 'PWD': '/builds/slave/test', 18:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:03 INFO - 'SHELL': '/bin/bash', 18:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:26:03 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:03 INFO - 'USER': 'cltbld', 18:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:03 INFO - 'XPC_FLAGS': '0x0', 18:26:03 INFO - 'XPC_SERVICE_NAME': '0', 18:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:26:03 INFO - Cleaning up... 18:26:03 INFO - Return code: 0 18:26:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:26:03 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')]} 18:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:03 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')]} 18:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:03 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 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:26:03 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 18:26:03 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 18:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:03 INFO - 'HOME': '/Users/cltbld', 18:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:03 INFO - 'LOGNAME': 'cltbld', 18:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:03 INFO - 'MOZ_NO_REMOTE': '1', 18:26:03 INFO - 'NO_EM_RESTART': '1', 18:26:03 INFO - 'PAGER': '/bin/cat', 18:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:03 INFO - 'PWD': '/builds/slave/test', 18:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:03 INFO - 'SHELL': '/bin/bash', 18:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:26:03 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:03 INFO - 'USER': 'cltbld', 18:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:03 INFO - 'XPC_FLAGS': '0x0', 18:26:03 INFO - 'XPC_SERVICE_NAME': '0', 18:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:03 INFO - Downloading/unpacking blobuploader==1.2.4 18:26:03 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 18:26:03 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 18:26:03 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 18:26:03 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 18:26:04 INFO - Downloading blobuploader-1.2.4.tar.gz 18:26:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:26:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:26:05 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 18:26:05 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 18:26:05 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 18:26:05 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 18:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:26:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:26:05 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 18:26:05 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 18:26:05 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 18:26:05 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 18:26:05 INFO - Downloading docopt-0.6.1.tar.gz 18:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:26:05 INFO - Installing collected packages: blobuploader, requests, docopt 18:26:05 INFO - Running setup.py install for blobuploader 18:26:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:26:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:26:06 INFO - Running setup.py install for requests 18:26:06 INFO - Running setup.py install for docopt 18:26:06 INFO - Successfully installed blobuploader requests docopt 18:26:06 INFO - Cleaning up... 18:26:06 INFO - Return code: 0 18:26:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:06 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 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:26:06 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 18:26:06 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 18:26:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:26:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:06 INFO - 'HOME': '/Users/cltbld', 18:26:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:06 INFO - 'LOGNAME': 'cltbld', 18:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:06 INFO - 'MOZ_NO_REMOTE': '1', 18:26:06 INFO - 'NO_EM_RESTART': '1', 18:26:06 INFO - 'PAGER': '/bin/cat', 18:26:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:06 INFO - 'PWD': '/builds/slave/test', 18:26:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:06 INFO - 'SHELL': '/bin/bash', 18:26:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:26:06 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:26:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:06 INFO - 'USER': 'cltbld', 18:26:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:06 INFO - 'XPC_FLAGS': '0x0', 18:26:06 INFO - 'XPC_SERVICE_NAME': '0', 18:26:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:07 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Yas3DB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:07 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-g7tqG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:07 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-r2GJk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:07 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-tjL8tn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:07 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-B5cFyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:07 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-osMk9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:07 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Qhcdyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:07 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-1xrFAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:07 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-o65rHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:08 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ZN5kV9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:08 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-nNwum4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:08 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-2EzYAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:08 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-cRpyLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:08 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-oMzl4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:08 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-48fejl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:08 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-BFgGlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:08 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-lp6R_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:26:08 INFO - Running setup.py install for manifestparser 18:26:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:26:08 INFO - Running setup.py install for mozcrash 18:26:09 INFO - Running setup.py install for mozdebug 18:26:09 INFO - Running setup.py install for mozdevice 18:26:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:26:09 INFO - Running setup.py install for mozfile 18:26:09 INFO - Running setup.py install for mozhttpd 18:26:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:26:09 INFO - Running setup.py install for mozinfo 18:26:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:26:09 INFO - Running setup.py install for mozInstall 18:26:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:26:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:26:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:26:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:26:09 INFO - Running setup.py install for mozleak 18:26:10 INFO - Running setup.py install for mozlog 18:26:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:26:10 INFO - Running setup.py install for moznetwork 18:26:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:26:10 INFO - Running setup.py install for mozprocess 18:26:10 INFO - Running setup.py install for mozprofile 18:26:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:26:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:26:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:26:10 INFO - Running setup.py install for mozrunner 18:26:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:26:10 INFO - Running setup.py install for mozscreenshot 18:26:11 INFO - Running setup.py install for moztest 18:26:11 INFO - Running setup.py install for mozversion 18:26:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:26:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:26:11 INFO - Cleaning up... 18:26:11 INFO - Return code: 0 18:26:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:11 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 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:26:11 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 18:26:11 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 18:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:11 INFO - 'HOME': '/Users/cltbld', 18:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:11 INFO - 'LOGNAME': 'cltbld', 18:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:11 INFO - 'MOZ_NO_REMOTE': '1', 18:26:11 INFO - 'NO_EM_RESTART': '1', 18:26:11 INFO - 'PAGER': '/bin/cat', 18:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:11 INFO - 'PWD': '/builds/slave/test', 18:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:11 INFO - 'SHELL': '/bin/bash', 18:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:26:11 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:11 INFO - 'USER': 'cltbld', 18:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:11 INFO - 'XPC_FLAGS': '0x0', 18:26:11 INFO - 'XPC_SERVICE_NAME': '0', 18:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:26:11 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-VuGfnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:26:11 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)) 18:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:26:11 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-irfFaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:26:11 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)) 18:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:26:11 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-4guFGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:26:11 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)) 18:26:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:26:11 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-7Twwrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:26:12 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)) 18:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:26:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-WbTDaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:26:12 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)) 18:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:26:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-yR88r9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:26:12 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)) 18:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:26:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-wiBrDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:26:12 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)) 18:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:26:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ZD3rWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:26:12 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)) 18:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:26:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-ncdmWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:26:12 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)) 18:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:26:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-SAFl6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:26:12 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)) 18:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:26:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-PwiJDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:26:12 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)) 18:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:26:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-xRsodA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:26:12 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)) 18:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:26:12 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-lrFbXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:26:13 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)) 18:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:26:13 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-BESjU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:26:13 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)) 18:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:13 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-08e79i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:26:13 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)) 18:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:26:13 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-hmcg52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:26:13 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)) 18:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:26:13 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-MQEfeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:26:13 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)) 18:26:13 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)) 18:26:13 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)) 18:26:13 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)) 18:26:13 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)) 18:26:13 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)) 18:26:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:26:14 INFO - Downloading blessings-1.6.tar.gz 18:26:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:26:14 INFO - Installing collected packages: blessings 18:26:14 INFO - Running setup.py install for blessings 18:26:15 INFO - Successfully installed blessings 18:26:15 INFO - Cleaning up... 18:26:15 INFO - Return code: 0 18:26:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:26:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:26:15 INFO - Reading from file tmpfile_stdout 18:26:15 INFO - Current package versions: 18:26:15 INFO - blessings == 1.6 18:26:15 INFO - blobuploader == 1.2.4 18:26:15 INFO - docopt == 0.6.1 18:26:15 INFO - functools32 == 3.2.3-2 18:26:15 INFO - jsonschema == 2.5.1 18:26:15 INFO - manifestparser == 1.1 18:26:15 INFO - mozInstall == 1.12 18:26:15 INFO - mozcrash == 1.0 18:26:15 INFO - mozdebug == 0.1 18:26:15 INFO - mozdevice == 0.50 18:26:15 INFO - mozfile == 1.2 18:26:15 INFO - mozhttpd == 0.7 18:26:15 INFO - mozinfo == 0.9 18:26:15 INFO - mozleak == 0.1 18:26:15 INFO - mozlog == 3.4 18:26:15 INFO - moznetwork == 0.27 18:26:15 INFO - mozprocess == 0.25 18:26:15 INFO - mozprofile == 0.28 18:26:15 INFO - mozrunner == 6.13 18:26:15 INFO - mozscreenshot == 0.1 18:26:15 INFO - mozsystemmonitor == 0.3 18:26:15 INFO - moztest == 0.8 18:26:15 INFO - mozversion == 1.4 18:26:15 INFO - psutil == 3.1.1 18:26:15 INFO - requests == 1.2.3 18:26:15 INFO - wsgiref == 0.1.2 18:26:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:15 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 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:26:15 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 18:26:15 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 18:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:15 INFO - 'HOME': '/Users/cltbld', 18:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:15 INFO - 'LOGNAME': 'cltbld', 18:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:15 INFO - 'MOZ_NO_REMOTE': '1', 18:26:15 INFO - 'NO_EM_RESTART': '1', 18:26:15 INFO - 'PAGER': '/bin/cat', 18:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:15 INFO - 'PWD': '/builds/slave/test', 18:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:15 INFO - 'SHELL': '/bin/bash', 18:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:26:15 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:15 INFO - 'USER': 'cltbld', 18:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:15 INFO - 'XPC_FLAGS': '0x0', 18:26:15 INFO - 'XPC_SERVICE_NAME': '0', 18:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:15 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)) 18:26:15 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)) 18:26:15 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)) 18:26:15 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)) 18:26:15 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)) 18:26:15 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)) 18:26:15 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)) 18:26:15 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)) 18:26:15 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)) 18:26:15 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)) 18:26:15 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)) 18:26:15 INFO - Cleaning up... 18:26:15 INFO - Return code: 0 18:26:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:26:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109873b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80b1c6e140>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, '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.Fqz84EzdjL/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.4khZ427Zvo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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 18:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:15 INFO - 'HOME': '/Users/cltbld', 18:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:15 INFO - 'LOGNAME': 'cltbld', 18:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:15 INFO - 'MOZ_NO_REMOTE': '1', 18:26:15 INFO - 'NO_EM_RESTART': '1', 18:26:15 INFO - 'PAGER': '/bin/cat', 18:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:15 INFO - 'PWD': '/builds/slave/test', 18:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:15 INFO - 'SHELL': '/bin/bash', 18:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:26:15 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:15 INFO - 'USER': 'cltbld', 18:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:15 INFO - 'XPC_FLAGS': '0x0', 18:26:15 INFO - 'XPC_SERVICE_NAME': '0', 18:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:26:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:26:16 INFO - Cleaning up... 18:26:16 INFO - Return code: 0 18:26:16 INFO - Running post-action listener: _resource_record_post_action 18:26:16 INFO - Running post-action listener: _start_resource_monitoring 18:26:16 INFO - Starting resource monitoring. 18:26:16 INFO - [mozharness: 2017-05-17 01:26:16.117477Z] Finished create-virtualenv step (success) 18:26:16 INFO - [mozharness: 2017-05-17 01:26:16.118414Z] Running install step. 18:26:16 INFO - Running pre-action listener: _resource_record_pre_action 18:26:16 INFO - Running main action method: install 18:26:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:26:16 INFO - Reading from file tmpfile_stdout 18:26:16 INFO - Detecting whether we're running mozinstall >=1.0... 18:26:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:26:16 INFO - Reading from file tmpfile_stdout 18:26:16 INFO - Output received: 18:26:16 INFO - Usage: mozinstall [options] installer 18:26:16 INFO - Options: 18:26:16 INFO - -h, --help show this help message and exit 18:26:16 INFO - -d DEST, --destination=DEST 18:26:16 INFO - Directory to install application into. [default: 18:26:16 INFO - "/builds/slave/test"] 18:26:16 INFO - --app=APP Application being installed. [default: firefox] 18:26:16 INFO - mkdir: /builds/slave/test/build/application 18:26:16 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'] 18:26:16 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 18:26:36 INFO - Reading from file tmpfile_stdout 18:26:36 INFO - Output received: 18:26:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:26:36 INFO - Running post-action listener: _resource_record_post_action 18:26:36 INFO - [mozharness: 2017-05-17 01:26:36.866490Z] Finished install step (success) 18:26:36 INFO - [mozharness: 2017-05-17 01:26:36.866628Z] Running run-tests step. 18:26:36 INFO - Running pre-action listener: _resource_record_pre_action 18:26:36 INFO - Running main action method: run_tests 18:26:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:26:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:26:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:26:36 INFO - 2017-05-16 18:26:36.880 screenresolution[1640:7373] starting screenresolution argv=screenresolution get 18:26:36 INFO - 2017-05-16 18:26:36.883 screenresolution[1640:7373] Display 0: 1600x1200x32@60 18:26:36 INFO - 2017-05-16 18:26:36.889 screenresolution[1641:7375] starting screenresolution argv=screenresolution list 18:26:36 INFO - Available Modes on Display 0 18:26:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:26:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:26:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:26:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:26:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:26:37 INFO - Intel Iris: 18:26:37 INFO - Chipset Model: Intel Iris 18:26:37 INFO - Type: GPU 18:26:37 INFO - Bus: Built-In 18:26:37 INFO - VRAM (Dynamic, Max): 1536 MB 18:26:37 INFO - Vendor: Intel (0x8086) 18:26:37 INFO - Device ID: 0x0a2e 18:26:37 INFO - Revision ID: 0x0009 18:26:37 INFO - Displays: 18:26:37 INFO - Display: 18:26:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:26:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:26:37 INFO - Display Serial Number: 1600x1200 60 18:26:37 INFO - Main Display: Yes 18:26:37 INFO - Mirror: Off 18:26:37 INFO - Online: Yes 18:26:37 INFO - Rotation: Supported 18:26:37 INFO - Return code: 0 18:26:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:26:37 INFO - Python 2.7.3 18:26:37 INFO - Return code: 0 18:26:37 INFO - grabbing minidump binary from tooltool 18:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:26:37 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 0x109583d30>, '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 0x109537db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109578be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:26:37 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 18:26:37 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 18:26:37 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 18:26:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:26:37 INFO - Return code: 0 18:26:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:26:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:26:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:26:37 INFO - ENV: RUST_BACKTRACE is now 1 18:26:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:26:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:26:37 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/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 18:26:37 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/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0314 --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 18:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Fqz84EzdjL/Render', 18:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:26:37 INFO - 'HOME': '/Users/cltbld', 18:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:26:37 INFO - 'LOGNAME': 'cltbld', 18:26:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:26:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:26:37 INFO - 'MOZ_NO_REMOTE': '1', 18:26:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:26:37 INFO - 'NO_EM_RESTART': '1', 18:26:37 INFO - 'PAGER': '/bin/cat', 18:26:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:26:37 INFO - 'PWD': '/builds/slave/test', 18:26:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:26:37 INFO - 'RUST_BACKTRACE': '1', 18:26:37 INFO - 'SHELL': '/bin/bash', 18:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4khZ427Zvo/Listeners', 18:26:37 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 18:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:26:37 INFO - 'USER': 'cltbld', 18:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:26:37 INFO - 'XPC_FLAGS': '0x0', 18:26:37 INFO - 'XPC_SERVICE_NAME': '0', 18:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:26:37 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/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 18:26:37 INFO - mozversion application_buildid: 20170516171253 18:26:37 INFO - mozversion application_changeset: 985b3ee939338022ef44028b5251f77af19c3638 18:26:37 INFO - mozversion application_display_name: Nightly 18:26:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:26:37 INFO - mozversion application_name: Firefox 18:26:37 INFO - mozversion application_remotingname: firefox 18:26:37 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:26:37 INFO - mozversion application_vendor: Mozilla 18:26:37 INFO - mozversion application_version: 55.0a1 18:26:37 INFO - mozversion platform_buildid: 20170516171253 18:26:37 INFO - mozversion platform_changeset: 985b3ee939338022ef44028b5251f77af19c3638 18:26:37 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:26:37 INFO - mozversion platform_version: 55.0a1 18:26:37 INFO - using testdate: 1494984397 18:26:37 INFO - actual date: 1494984397 18:26:37 INFO - starting webserver on 'localhost:49225' 18:26:37 INFO - SUITE-START | Running 2 tests 18:26:37 INFO - TEST-START | dromaeo_css 18:26:37 INFO - operating with platform_type : mac_ 18:26:37 INFO - Initialising browser for dromaeo_css test... 18:26:37 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpwPGhj3/profile http://localhost:49225/getInfo.html) 18:26:38 INFO - PID 1651 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 18:26:40 INFO - PID 1651 | 2017-05-16 18:26:40.227 plugin-container[1653:7557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 18:26:40 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:40 INFO - PID 1651 | 2017-05-16 18:26:40.231 plugin-container[1653:7557] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 18:26:40 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:40 INFO - PID 1651 | 2017-05-16 18:26:40.232 plugin-container[1653:7557] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:26:40 INFO - PID 1651 | __metrics Screen width/height:1600/1200 18:26:40 INFO - PID 1651 | colorDepth:24 18:26:40 INFO - PID 1651 | Browser inner width/height: 1024/691 18:26:40 INFO - PID 1651 | __metrics 18:26:40 INFO - PID 1651 | 2017-05-16 18:26:40.739 plugin-container[1654:7610] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 18:26:40 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:40 INFO - PID 1651 | 2017-05-16 18:26:40.739 plugin-container[1654:7610] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9313, name = 'com.apple.CFPasteboardClient' 18:26:40 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:40 INFO - PID 1651 | 2017-05-16 18:26:40.739 plugin-container[1654:7610] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:26:41 INFO - TEST-INFO | 1651: exit 0 18:26:41 INFO - Browser initialized. 18:26:41 INFO - Running cycle 1/1 for dromaeo_css test... 18:26:41 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpwPGhj3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:26:43 INFO - PID 1656 | 2017-05-16 18:26:43.250 plugin-container[1657:7706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.tsm.portname' 18:26:43 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:43 INFO - PID 1656 | 2017-05-16 18:26:43.251 plugin-container[1657:7706] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 18:26:43 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:43 INFO - PID 1656 | 2017-05-16 18:26:43.251 plugin-container[1657:7706] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:26:43 INFO - PID 1656 | 2017-05-16 18:26:43.932 plugin-container[1658:7750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9137, name = 'com.apple.tsm.portname' 18:26:43 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:43 INFO - PID 1656 | 2017-05-16 18:26:43.932 plugin-container[1658:7750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x951b, name = 'com.apple.CFPasteboardClient' 18:26:43 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:26:43 INFO - PID 1656 | 2017-05-16 18:26:43.932 plugin-container[1658:7750] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:26:53 INFO - PID 1656 | RSS: Main: 235864064 18:26:53 INFO - PID 1656 | 18:29:56 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 18:29:58 INFO - PID 1656 | RSS: Main: 256204800 18:29:58 INFO - PID 1656 | 18:33:00 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 18:33:00 INFO - PID 1656 | RSS: Main: 265900032 18:33:00 INFO - PID 1656 | 18:36:02 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 18:36:02 INFO - PID 1656 | RSS: Main: 270516224 18:36:02 INFO - PID 1656 | 18:39:04 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 18:39:05 INFO - PID 1656 | RSS: Main: 268247040 18:39:05 INFO - PID 1656 | 18:42:08 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 18:42:08 INFO - PID 1656 | RSS: Main: 269680640 18:42:08 INFO - PID 1656 | 18:45:10 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 18:45:10 INFO - PID 1656 | RSS: Main: 270868480 18:45:10 INFO - PID 1656 | 18:45:10 INFO - PID 1656 | __start_tp_report 18:45:10 INFO - PID 1656 | _x_x_mozilla_page_load 18:45:10 INFO - PID 1656 | _x_x_mozilla_page_load_details 18:45:10 INFO - PID 1656 | |i|pagename|runs| 18:45:10 INFO - PID 1656 | |0;dojo.html;2966.377060817228;3440.449528075507;3584.4264917613177;3585.946210806837;3623.184783423729;5320.574354051676;6146.2317210348765;6154.630722156674;6262;6263.37366263374;6198.44214020738;6201.255849298091;6213.751449941997;6255.624662520252;6296.653684047387;5457.399686034533;5476.890462190754;5492.642978206414;5540.473655002768;5548.250986116883;4738.289256611519;5585.776568937237;5670.404607516213;5678.205051292823;5685.658860468385;5419.376771671269;5502.0921547944545;5506.724663766796;5572.219889215473;5640;5506.394296627368;5555.666660000413;5587.664740115606;5608.046632072557;5612.326520817487;5483.067878460632;5498.532624726903;5537.695426751527;5538.529225015878;5546.944530554723;6064.211652485149;6272.968635156795;6288.151099601541;6311.936880631227;6317.273513545909;6078.179445774808;6124.540659450559;6446.419822216023;6534.24856141545;6751.831204219934;6732.93267067328;6837.282085381063;6849.452043836481;6857.828554286133;6864.107666003438;6240.781572644935;6324.873502529969;6347.682615869188;6351.269603995554;6365.299817020078;6742.662866856637;7019.157701075904;7026.227115017344;7039.54242974205;7055.682494287769;6506.25178104524;6536.934630653633;6631.767888123893;6658.966705166443;6661.467082633378;5717.828465146053;6624.768133115318;6720.395164435225;6799.082123913307;6806.897896531556;6509.056186852879;6692.899606505906;6695.765648202289;6697;6699.698513566901;6759.594424334556;6766.898496522556;6785.762498312535;6786.5928044316515;6803.251642319341;5059.886824898516;5319.228711836762;5319.5744340452675;5322.520973112361;5332.146856502941;4769.73766442842;4801;4834.734089625104;4848.539388758062;4900.068986892479;6387.648679322589;6388.584741991755;6406.5515413920575;6407.807765767035;6414.807555773335;6628.270890202074;6821.658917054127;6867.484938629623;6888.586684798928;6889.207741109836;6266.122742816009;6280.842978925563;6288.6855657217875;6356;6392.520560957853;6017.16335154899;6021.277446706424;6025.698715064229;6047.183630209954;6051.606645568024;6913.308669133046;6955.965220173866;6997.755078572225;7011.263817299314;7012.474064445187;6636.535442518977;6731.764388246584;6763.560368576027;6836.145481814773;6843.144606924135;5906.468417842415;7016.438684905297;7027.4729395294535;7066.964665176642;7078.2921707830865;5597.776088956396;6106.7862624809695;6347.238331400262;6709.832254193684;6761.86476270462;5253.842384728464;6645.435138013129;6718.529702920944;6869.210040845167;6875.656217189221;6078.6930809876085;6162.506999439945;6547.279799222177;6586.308437613981;6603.471722262304;6342.841428964312;6577.177852768404;6579.111819904251;6595.868082638416;6604.405603495708;5020.824271150492;6018.789342373171;6304.18045654062;6469.838254043687;6485.837854053498;5718.199452076796;6496.740130394731;6611.173603299588;6647.933520664731;6672.599644021374;6465.838354041187;6505.056766768697;6516.120323756232;6522.315156908457;6534.379233972765 18:45:10 INFO - PID 1656 | |1;ext.html;802.2859654907132;1210.1347536511396;1276.2661469654952;1278.769821432142;1284.5118854835155;37154.814225928865;37897;37938.620613793886;37973;38026.619733802654;37805.05487362817;38165.61834381655;38312.23375532488;38367.80816095915;38915.61084389162;8006.519608823474;8241.546714930677;8248.711295104673;8265.256126948574;8290.253877151055;8152.918470815275;8220.985858464655;8229.177082291782;8236.876446853303;8294.70968516102;8101.351891848667;8179.713710020151;8235.13531079237;8252.257296843283;8265.95867020667;6949.756758513453;8221.630026648816;8229;8232.300254478376;8239.464434811747;8027.07688615805;8042.075161356461;8063.838723225503;8071.19288071194;8074.3136833369235;28369.716302836914;35353.64646353529;35381.82309088464;35386.29227415437;35407;3602.8018458984743;3646.2160635463238;3649.81750912456;3651.050726811022;3656.4698118772894;29161;29285.70714292851;29312;29324;29388.412231755246;3010.3979204159255;3088.3051313454516;3109.9067027989195;3110.7666924980717;3111.377724455095;244169.77915110489;292222;293191;293681.0631893696;294377;37236;44305.55694443014;44866;45196.54803451923;45273;47061;47109.293360599615;47142.82142946385;47265.76367118143;47299.52700472951;13583.932080339535;13711.245881476416;13724.862751372559;13759.10565813219;13769.586912392644;9625.759356016015;10882.727931801768;11106.55573777056;11115.944420277847;11157.442127893573;12796.552120675731;12802.42389092493;12833.294168820805;12871.356432178354;12979.675508112372;5910.940890591039;5946;5956.55325850563;5965.373635768268;5965.582409231312;885.2873436883242;972.3096601412934;992.3153024413276;1001.8447140693204;1047.696168111239;23340.533189336118;23405.180818671266;23498.412539686302;23529.058837646302;23789.64315535268;896.1531352871605;980;1009.7526106104051;1036.0106098675747;1065.8454524094118;11362.602308919148;11857.762844743056;12021.579244726567;12086.69783255408;12103.818442723366;285002.5749871279;286053;286389.5680521584;286509;286682;41085.58914410877;50326.993460130594;50748;50754.49245507571;50811.745941270055;1366.3373263966969;1487.970240595182;1501.8948673592963;1539.8306186319496;1554.2539581001183;23049.539009220058;23109.88445057764;23118.306450806504;23222.419439514146;23252.069917203124;36342.818285908405;36375.272494549434;36433.089172770895;36463.45304820483;36496;265905;266412;266515;267283;267369;31646.525302120946;32362.83818580892;32435;33034.66965330316;33136.84021059251;41429.79285103555;43275;43450.782746086064;43462;43489;29009.7099029007;29243.707562924097;29697.5545366824;30483.08550743481;30637.927672531354;29533;30146.54780178343;30451.086467406014;30499.54250686197;30608.387832242784;34874.47688284627;35290;36082.27835443329;36186.27627447489;36320;29515.40969180647;29767.10698679043;30407.239819004702;30485.54271685882;30807.69192308048;22658.546829062998;23670.52658946846;23697.05211791509;24063;24063 18:45:10 INFO - PID 1656 | |2;jquery.html;426.47117574207994;436.46968932746705;440.4824331410593;451.0370359282931;457.1976181800937;18657.4402767917;20398.490037749063;21033.579328413423;21141.942902854873;21211.57576848462;18280.16116615509;21763.782362176375;21841.890790546066;22464.213752518666;22665.433364165863;5920.674362910038;6194.163787888645;6198.752049917998;6199.163112979735;6200.968995155018;5190.558802501791;5880.529557635379;6084.665343406111;6108.938910610881;6111.908321375183;6062.060380641008;6099.054646529773;6101.816945491642;6114.052321890114;6124.540659450537;5762.193292938992;6003.879922401549;6045.637261764308;6070.848228794293;6071.483923866476;5012.323336349583;5057.873553161164;5336.573074154058;5374.220737992969;5398.811041613525;22209.44476388087;24487.510249795083;27351;27358.863205684043;27580.586291205647;2153.3432303147533;2200.6698995150696;2223.5775202711434;2226.0428015953134;2234.2180236916947;17538.912305438393;17613.471595852017;18224;18227;18513.90743046276;1857.8978156201404;1895.4882181810833;2152.720146380976;2183.170395249811;2185.912563497458;148191;167334;169041;169454;171018;12460.12779105472;12539.623811285675;12604.054695897661;12757.55348562796;12937.676558086123;13852.93073534626;16359.918200408922;16491.752623710654;16495.91752041256;16646.16769161537;7512.323890849741;8589.398742088119;8878.28973682104;8930.374873758905;9015.729528114167;8342.374321925758;8374.790630234293;8377.706780262783;8557.187067228604;8598.312135029182;6588.8023359299295;7860.528368297921;7910;7952.801179970547;8008.439409241414;1942.8834269943848;2129.4037669452578;2132.424245453719;2147.484603695133;2203.1848216160124;928.6007016982627;934.9485778282124;951.695457453622;967.4375882949128;993.0714781679153;20166.899165504077;20494.897525512577;20637.896810515853;20714.58570828575;21017;966.7969726357403;969.3941286695815;998.8601595776597;1002.9699109026741;1003.9749006274756;9111.772205694779;10118.139958103644;10256.948715256376;10394.896051039545;10851.891481085246;540591;542748;543307;547191;547348;123985;126401;127325;127433.08850367252;130988;1166.4925757295434;1199.772043311768;1203.6449247472017;1246.962591122268;1252.7745005898844;22433.43916402103;22800.771992279864;22890.54218915646;22973.540529189657;23068.4232894179;52560;53318.73340633427;53747.92504149973;56781.148282774964;57547.7122614401;70602.58794824178;71296;71846;72245.55508889898;72282.27717722756;7679.308862202429;8605.741827744927;8663.566821658766;8687.782805429915;8871.379003469954;13052.738945221232;14164.645883852987;14174.858251417354;14423.92788036053;14511.492097776527;11470;11896.464659090188;12029.639110826689;12120;12135.635930922086;10811.513481893491;10910.78178436411;11138.832917506083;11147.386893720923;11275.943620281847;10885.619003334845;11226.70892847333;11905.285682859056;12221.69445763863;12242.204256723284;8664.393492455467;8897.777555561162;9035.412698174598;9046.909530904606;9152.542372881462;11018.834717479405;11395.943020284845;11853.644390668294;12117.030637549151;12128 18:45:10 INFO - PID 1656 | |3;mootools.html;1122.5117074072778;1309.8362704661918;1319.3732976836004;1465.5969608357707;1466.208247546325;11667.41662916855;11838.99368553674;12101.455434505435;12120.69698257544;12146;11770;11862;11948.163628546004;11983.340916249603;12021.338826364547;2546.7707906288383;2589.339718371822;2598.922032339023;2601.8829152688177;2601.9219423417326;2511.660925775024;2561.5773397389407;2573.4209802794317;2576.2786419802487;2577.7422257774165;2188.9343319700356;2540.7840333571667;2548.566743653574;2552.145031414469;2554.463562651845;2101.8843963582;2512.836665616738;2529.7723204911463;2538.3019669590826;2540.9237722868343;2485.6768620079456;2509.6862892138483;2517.093846215361;2518.5970244760933;2594.169865642995;11698.707532311675;11749.647510574696;11756.823647645293;11758.17692761507;11783;6244.5316601254635;6403.327650596667;6589.242237142742;6819.318068193141;6829.658517074176;3875.2636998970106;4191.308434108369;4224.281872081723;4255.510616279136;4271.61555460007;1247.7878760610718;1264.8237139460298;1284.3578210894552;1284.871512848717;1303.4720938020116;16169.191540422931;16323.83676163235;16341.42805001831;16342.918285408616;16756.83243167577;4879.780409881564;5464.453554644504;5593.216949627055;5620.3255609326325;5733.71331433435;11436.485358158903;11465.197436179387;11524.88475115255;11567.652970410902;11578;6480.416762491304;6515.967420162869;6518.543701940818;6544.574602650812;6572.737090516422;5979.701014949235;6069.484093851984;6112.572119951561;6241.282252540925;6249.031400132987;4183.184279065614;4295.892602684896;4299.613034826881;4371.0602220522105;4389.056352884145;1082.7401423658266;1134.9262297950606;1210.140800031987;1263.7409331087106;1283.0633637444719;668.4552090046597;795.5425630262506;817.8568750468644;825.4964471672275;826.5040975414823;6281.748730050838;6312.171552389766;6948.305169483011;6948.79153625382;7002.334778196063;904.6381447421085;923.6166990698919;932.4545141092401;940.4216406909701;942.8403064231035;3310.751693622988;4063.39049142631;4171.582841715865;4194.6853985951175;4244.978775106167;14579.343929523196;15164.393424263126;15178.089314640936;15186.544403667907;15239;14328.785068223984;15000.099994000177;15092.320845561977;15127.697446051017;15135.940484166224;7441.962790185906;7987.8402431951035;8571.271441927505;8614.741557753277;8646.048934617187;6162.020183861999;6838.93161068383;6936.965315173392;7157.141143062866;7204.9826048706445;12140.575079872515;12536.310502922426;12760.808587871375;12774.489020439078;12811.743765124831;12795.232286062866;12808.103432759617;12897.871021289668;13082.672933176365;13290.734185316433;10428.426436546062;10583.31208471447;10619.681409557727;10730.248882578144;10741.677749667522;10596.20528460384;10602.89397106019;10639.521221544894;10718.410487422954;10738.892611073788;8430.915690843012;8557.743267701979;8876.600552975002;9037.367384283038;9217.539123043955;8831.161039701217;8937.776555586162;8967.641294348154;9032.141946515334;9056.54717264121;7021.297870212938;8062.39532035088;8192.139825318256;8275.710350137717;8290.046644635944;5556;5941.732622031933;5991.2510936132985;6119.3268740437625;6171.59884607499;4636.675432719677;6007.969960150172;6087.452129308206;6183.3816618337805;6223.37766223374 18:45:10 INFO - PID 1656 | |4;prototype.html;353.3145697347146;400.42538956597286;400.6074047433513;409.02447662325346;412.6595558664101;5419.945800541994;6250.874982500347;6304.873902521947;6355;6464.3212462691445;4814.494478079804;5179.352580927384;5800.593958422912;6274.937250627492;6287.937120628792;4425.8450954216605;4489.393931819195;4530;4542;4578.908421831561;3672.8347224374834;3758.755680880734;3989.9202015959663;4141.81361838718;4500.279955207151;3469.583649962008;4514.435695538058;4530.22986092365;4534.523874993111;4548.044910568768;3927.7643341399607;4039.798010099513;4298.441202643652;4491.640668746492;4546.727196368212;3232.498962660791;3774.5093137892172;3800.448934904451;3829.5151412215027;3843.7501562398356;6031.366706495799;6055.848603784896;6066;6074.51403887688;6087.634741915499;5272.736363181825;5317.308749862493;5330.1205301125265;5368.221607866876;5397.487238712316;5860.648361098348;6473.723915009253;6610.900836487456;6624.73501059957;6629.701663425158;4196.971741923248;4375.453068366454;4381.518033016366;4385.429894113745;4394.362817391492;6250.0624906263965;6936.514443988923;7035.226125126232;7199.280071992759;7226.891596626055;4068.898277543085;4405.493368262627;4449.594901047939;4454.28731402974;4456.598906098467;3689.815509224528;3834.5206849143856;4390.968122491212;4538.546145385435;4651.837185698484;4539.547344849682;5307.575393968474;5380.112281473513;5398.2982212312145;5427.810026649048;5637.7181140942785;5726.856828579235;6726.327367263235;6731.730730770812;6734.292899245606;1528.9847101529065;1531.9199964025336;1546.7989161408946;1550.7053659804826;1553.324303927795;870.8737233101292;997.6258902911409;1001.9849702254472;1009.7576581620505;1022.6625213679467;5782.703858800443;5829.591928565044;5970.671613061237;6030.5778595497895;6455.7740479083;6060.969695151496;6131.448169664752;6981.0924579804305;7028.789136325919;7071.646417679095;4932.161532539477;5256.710880901511;5325.068113080196;5511.724413779295;6389.488840892717;3630.4735813306925;3653.214558869856;3655.415133578668;3668.8899333020054;3689.3728066228805;5356.384015838228;6759.019942108367;6933.479989000845;7179.09246755065;7222.277772222736;6694.698738556776;7044.0415025689335;7195.460340474486;7197;7232.168300645493;5970.910436343458;7077.5753454792875;7111.359977601937;7156.856862862818;7215.927840721631;6955.756548520778;6963.7562685305775;6969.989351544099;6983.476239281973;7059.541129826544;5235.973820130875;5625.521830644441;6172.598781079395;6287.056941458818;6660.56706314088;7036.612986285803;7187.9640601796655;7202.71189152428;7242.334263119406;7250.456215783943;3868.5937976513187;4033.8165457387117;4164.104717485755;4441.800118994588;4457.016627600687;6587.176602924635;6992.405645520188;7028.297170282931;7062.364387205176;7073.893891591733;5460.563154947516;5957.255343082114;6233.781817636361;6775;6810.523263371517;6631.148606099424;6711.865762684622;6784.660766961731;6788.25329213796;6816.693248803716;6332.778352757816;6497.18785151856;6641.933580664325;6661.570819781862;6676.465882729273;5546.112621980464;5698.572607054404;6751.696173672099;6803.829904252433;6805.693743781443;6541.574797638138;6553.639549824615;6586.242582103119;6605.240397354173;6624.403803657694 18:45:10 INFO - PID 1656 | |5;yui.html;737.6090671943865;784.9372050235982;841.9831603367933;864.4424346296639;867.986980195297;14330.28348582572;14951.401943922243;15018.924905375485;16684.082375469345;16898.74651880221;16841.32634694611;17161.227744751486;17490.38783642573;17540.035298058603;17897.015664138467;943.6744323208482;958.5015791788265;966.5602151021268;966.6375109334;970.1220395542045;973.5667627905585;977.0766625538869;977.301229620821;980.691082309074;981.813455443467;1006.0894890124449;1018.0989824005771;1024.5901639344247;1025.2361990317224;1027.8406846938738;995.21378111292;1016.938983660979;1021.6883850425618;1022.110763635638;1023.1967905194387;737.6791095873259;751.3838652305112;755.0938873351945;757.3373298363931;757.7234309477001;16525.58686032847;16766.413175538797;16934.83065169357;16979.57551061221;16994;16013.95909265894;16275.023498590004;17148.142592870307;17171.914140429217;17365.131743412778;12798.744025119446;14891.478798242119;14911.552653420415;15019.324130414157;15036.849631503654;3546.131197856542;3639.308531379056;3690.686291665185;3693.409054551286;3712.480252764615;107473;108106;108699.91300086977;108799;110223.44888275507;13123.278219697817;13164.275964821834;13332.800007999887;13383;13422.39599218018;24604.015839366584;24614.138505152234;24626;24662.5067498649;25202.873985629954;7549.50928189666;8006.559639219782;8016.519008859487;8044.678212871537;8068.435209535393;3885.378339465672;3896.5713771485116;3925.7644541327613;3939.3893946438347;3940.7832569208394;4262.657262962157;4338.674599405057;4470.731756094579;4473.709208901411;4483.417155769795;906.1391677906016;922.8661844032578;927.8329900617954;938.15565990609;940.5438362394233;636.3699937062275;648.1217949678212;651.9087327774115;653.1965682210907;679.9762008329685;11147.386893720923;11841;12065.215760975509;12085.939570302093;12215.083868709882;636.7166610858217;641.6150309814075;648.9448396886223;650.7201903181677;677.3734295776387;4902.313676085351;4926.926096108561;5436.374816896028;5602.38373778884;5715.828525144252;66017;104712.47643761733;105256;105373.41939870754;107322;87495.58512622808;91905.89035206122;93421.06578934124;93576;94446.52776736072;81934;83284.16715832886;83398;84189.57905210435;84962;10266.844430294237;11077.501512431798;11114.499847507042;11204.103671706082;11409.372484513431;42007.78996105;42032;42268.36597451102;42341;42483;54181;54831.725841370535;54886.902261955336;55111.173332400846;55224.61938451571;13610.251436170709;13760.380782864595;14147.717045658823;14173.362198700877;14176.078554893898;15159.317830697866;15704.136272505122;15800.92099539495;15853;15889.920550397173;13108.475660973454;13150.079494435298;14400.567982960529;14736.852631473548;14846.480373186887;14021.719565608426;14086.647833804236;14315.498957536436;14423.062500937405;14534.85465145335;13962.511312104027;13998.230097344744;14274.857251427353;14463.49377771773;14590.562283131523;9339.673111441067;10388.896111038794;10469.162467002774;10625.096866766411;10952.507137178687;11653.359065251492;11749.588764393206;11810;11840.934315911265;11953.342566158944 18:45:10 INFO - PID 1656 | __end_tp_report 18:45:10 INFO - PID 1656 | __start_cc_report 18:45:10 INFO - PID 1656 | _x_x_mozilla_cycle_collect,340 18:45:10 INFO - PID 1656 | __end_cc_report 18:45:10 INFO - PID 1656 | __startTimestamp1494985510618__endTimestamp 18:45:10 INFO - PID 1656 | 18:45:10 INFO - PID 1656 | ------- Summary: start ------- 18:45:10 INFO - PID 1656 | Number of tests: 6 18:45:10 INFO - PID 1656 | 18:45:10 INFO - PID 1656 | [#0] dojo.html Cycles:165 Average:6169.18 Median:6356.00 stddev:753.48 (11.9%) stddev-sans-first:712.67 18:45:10 INFO - PID 1656 | Values: 2966.4 3440.4 3584.4 3585.9 3623.2 5320.6 6146.2 6154.6 6262.0 6263.4 6198.4 6201.3 6213.8 6255.6 6296.7 5457.4 5476.9 5492.6 5540.5 5548.3 4738.3 5585.8 5670.4 5678.2 5685.7 5419.4 5502.1 5506.7 5572.2 5640.0 5506.4 5555.7 5587.7 5608.0 5612.3 5483.1 5498.5 5537.7 5538.5 5546.9 6064.2 6273.0 6288.2 6311.9 6317.3 6078.2 6124.5 6446.4 6534.2 6751.8 6732.9 6837.3 6849.5 6857.8 6864.1 6240.8 6324.9 6347.7 6351.3 6365.3 6742.7 7019.2 7026.2 7039.5 7055.7 6506.3 6536.9 6631.8 6659.0 6661.5 5717.8 6624.8 6720.4 6799.1 6806.9 6509.1 6692.9 6695.8 6697.0 6699.7 6759.6 6766.9 6785.8 6786.6 6803.3 5059.9 5319.2 5319.6 5322.5 5332.1 4769.7 4801.0 4834.7 4848.5 4900.1 6387.6 6388.6 6406.6 6407.8 6414.8 6628.3 6821.7 6867.5 6888.6 6889.2 6266.1 6280.8 6288.7 6356.0 6392.5 6017.2 6021.3 6025.7 6047.2 6051.6 6913.3 6956.0 6997.8 7011.3 7012.5 6636.5 6731.8 6763.6 6836.1 6843.1 5906.5 7016.4 7027.5 7067.0 7078.3 5597.8 6106.8 6347.2 6709.8 6761.9 5253.8 6645.4 6718.5 6869.2 6875.7 6078.7 6162.5 6547.3 6586.3 6603.5 6342.8 6577.2 6579.1 6595.9 6604.4 5020.8 6018.8 6304.2 6469.8 6485.8 5718.2 6496.7 6611.2 6647.9 6672.6 6465.8 6505.1 6516.1 6522.3 6534.4 18:45:10 INFO - PID 1656 | 18:45:10 INFO - PID 1656 | [#1] ext.html Cycles:180 Average:42514.51 Median:32398.92 stddev:73044.83 (225.5%) stddev-sans-first:73182.60 18:45:10 INFO - PID 1656 | Values: 802.3 1210.1 1276.3 1278.8 1284.5 37154.8 37897.0 37938.6 37973.0 38026.6 37805.1 38165.6 38312.2 38367.8 38915.6 8006.5 8241.5 8248.7 8265.3 8290.3 8152.9 8221.0 8229.2 8236.9 8294.7 8101.4 8179.7 8235.1 8252.3 8266.0 6949.8 8221.6 8229.0 8232.3 8239.5 8027.1 8042.1 8063.8 8071.2 8074.3 28369.7 35353.6 35381.8 35386.3 35407.0 3602.8 3646.2 3649.8 3651.1 3656.5 29161.0 29285.7 29312.0 29324.0 29388.4 3010.4 3088.3 3109.9 3110.8 3111.4 244169.8 292222.0 293191.0 293681.1 294377.0 37236.0 44305.6 44866.0 45196.5 45273.0 47061.0 47109.3 47142.8 47265.8 47299.5 13583.9 13711.2 13724.9 13759.1 13769.6 9625.8 10882.7 11106.6 11115.9 11157.4 12796.6 12802.4 12833.3 12871.4 12979.7 5910.9 5946.0 5956.6 5965.4 5965.6 885.3 972.3 992.3 1001.8 1047.7 23340.5 23405.2 23498.4 23529.1 23789.6 896.2 980.0 1009.8 1036.0 1065.8 11362.6 11857.8 12021.6 12086.7 12103.8 285002.6 286053.0 286389.6 286509.0 286682.0 41085.6 50327.0 50748.0 50754.5 50811.7 1366.3 1488.0 1501.9 1539.8 1554.3 23049.5 23109.9 23118.3 23222.4 23252.1 36342.8 36375.3 36433.1 36463.5 36496.0 265905.0 266412.0 266515.0 267283.0 267369.0 31646.5 32362.8 32435.0 33034.7 33136.8 41429.8 43275.0 43450.8 43462.0 43489.0 29009.7 29243.7 29697.6 30483.1 30637.9 29533.0 30146.5 30451.1 30499.5 30608.4 34874.5 35290.0 36082.3 36186.3 36320.0 29515.4 29767.1 30407.2 30485.5 30807.7 22658.5 23670.5 23697.1 24063.0 24063.0 18:45:10 INFO - PID 1656 | 18:45:10 INFO - PID 1656 | [#2] jquery.html Cycles:180 Average:35354.87 Median:22448.83 stddev:92673.00 (412.8%) stddev-sans-first:92895.87 18:45:10 INFO - PID 1656 | Values: 426.5 436.5 440.5 451.0 457.2 18657.4 20398.5 21033.6 21141.9 21211.6 18280.2 21763.8 21841.9 22464.2 22665.4 5920.7 6194.2 6198.8 6199.2 6201.0 5190.6 5880.5 6084.7 6108.9 6111.9 6062.1 6099.1 6101.8 6114.1 6124.5 5762.2 6003.9 6045.6 6070.8 6071.5 5012.3 5057.9 5336.6 5374.2 5398.8 22209.4 24487.5 27351.0 27358.9 27580.6 2153.3 2200.7 2223.6 2226.0 2234.2 17538.9 17613.5 18224.0 18227.0 18513.9 1857.9 1895.5 2152.7 2183.2 2185.9 148191.0 167334.0 169041.0 169454.0 171018.0 12460.1 12539.6 12604.1 12757.6 12937.7 13852.9 16359.9 16491.8 16495.9 16646.2 7512.3 8589.4 8878.3 8930.4 9015.7 8342.4 8374.8 8377.7 8557.2 8598.3 6588.8 7860.5 7910.0 7952.8 8008.4 1942.9 2129.4 2132.4 2147.5 2203.2 928.6 934.9 951.7 967.4 993.1 20166.9 20494.9 20637.9 20714.6 21017.0 966.8 969.4 998.9 1003.0 1004.0 9111.8 10118.1 10256.9 10394.9 10851.9 540591.0 542748.0 543307.0 547191.0 547348.0 123985.0 126401.0 127325.0 127433.1 130988.0 1166.5 1199.8 1203.6 1247.0 1252.8 22433.4 22800.8 22890.5 22973.5 23068.4 52560.0 53318.7 53747.9 56781.1 57547.7 70602.6 71296.0 71846.0 72245.6 72282.3 7679.3 8605.7 8663.6 8687.8 8871.4 13052.7 14164.6 14174.9 14423.9 14511.5 11470.0 11896.5 12029.6 12120.0 12135.6 10811.5 10910.8 11138.8 11147.4 11275.9 10885.6 11226.7 11905.3 12221.7 12242.2 8664.4 8897.8 9035.4 9046.9 9152.5 11018.8 11395.9 11853.6 12117.0 12128.0 18:45:10 INFO - PID 1656 | 18:45:10 INFO - PID 1656 | [#3] mootools.html Cycles:180 Average:7084.46 Median:2591.75 stddev:4478.06 (172.8%) stddev-sans-first:4468.21 18:45:10 INFO - PID 1656 | Values: 1122.5 1309.8 1319.4 1465.6 1466.2 11667.4 11839.0 12101.5 12120.7 12146.0 11770.0 11862.0 11948.2 11983.3 12021.3 2546.8 2589.3 2598.9 2601.9 2601.9 2511.7 2561.6 2573.4 2576.3 2577.7 2188.9 2540.8 2548.6 2552.1 2554.5 2101.9 2512.8 2529.8 2538.3 2540.9 2485.7 2509.7 2517.1 2518.6 2594.2 11698.7 11749.6 11756.8 11758.2 11783.0 6244.5 6403.3 6589.2 6819.3 6829.7 3875.3 4191.3 4224.3 4255.5 4271.6 1247.8 1264.8 1284.4 1284.9 1303.5 16169.2 16323.8 16341.4 16342.9 16756.8 4879.8 5464.5 5593.2 5620.3 5733.7 11436.5 11465.2 11524.9 11567.7 11578.0 6480.4 6516.0 6518.5 6544.6 6572.7 5979.7 6069.5 6112.6 6241.3 6249.0 4183.2 4295.9 4299.6 4371.1 4389.1 1082.7 1134.9 1210.1 1263.7 1283.1 668.5 795.5 817.9 825.5 826.5 6281.7 6312.2 6948.3 6948.8 7002.3 904.6 923.6 932.5 940.4 942.8 3310.8 4063.4 4171.6 4194.7 4245.0 14579.3 15164.4 15178.1 15186.5 15239.0 14328.8 15000.1 15092.3 15127.7 15135.9 7442.0 7987.8 8571.3 8614.7 8646.0 6162.0 6838.9 6937.0 7157.1 7205.0 12140.6 12536.3 12760.8 12774.5 12811.7 12795.2 12808.1 12897.9 13082.7 13290.7 10428.4 10583.3 10619.7 10730.2 10741.7 10596.2 10602.9 10639.5 10718.4 10738.9 8430.9 8557.7 8876.6 9037.4 9217.5 8831.2 8937.8 8967.6 9032.1 9056.5 7021.3 8062.4 8192.1 8275.7 8290.0 5556.0 5941.7 5991.3 6119.3 6171.6 4636.7 6008.0 6087.5 6183.4 6223.4 18:45:10 INFO - PID 1656 | 18:45:10 INFO - PID 1656 | [#4] prototype.html Cycles:180 Average:5306.28 Median:5845.12 stddev:1735.10 (29.7%) stddev-sans-first:1699.67 18:45:10 INFO - PID 1656 | Values: 353.3 400.4 400.6 409.0 412.7 5419.9 6250.9 6304.9 6355.0 6464.3 4814.5 5179.4 5800.6 6274.9 6287.9 4425.8 4489.4 4530.0 4542.0 4578.9 3672.8 3758.8 3989.9 4141.8 4500.3 3469.6 4514.4 4530.2 4534.5 4548.0 3927.8 4039.8 4298.4 4491.6 4546.7 3232.5 3774.5 3800.4 3829.5 3843.8 6031.4 6055.8 6066.0 6074.5 6087.6 5272.7 5317.3 5330.1 5368.2 5397.5 5860.6 6473.7 6610.9 6624.7 6629.7 4197.0 4375.5 4381.5 4385.4 4394.4 6250.1 6936.5 7035.2 7199.3 7226.9 4068.9 4405.5 4449.6 4454.3 4456.6 3689.8 3834.5 4391.0 4538.5 4651.8 4539.5 5307.6 5380.1 5398.3 5427.8 5637.7 5726.9 6726.3 6731.7 6734.3 1529.0 1531.9 1546.8 1550.7 1553.3 870.9 997.6 1002.0 1009.8 1022.7 5782.7 5829.6 5970.7 6030.6 6455.8 6061.0 6131.4 6981.1 7028.8 7071.6 4932.2 5256.7 5325.1 5511.7 6389.5 3630.5 3653.2 3655.4 3668.9 3689.4 5356.4 6759.0 6933.5 7179.1 7222.3 6694.7 7044.0 7195.5 7197.0 7232.2 5970.9 7077.6 7111.4 7156.9 7215.9 6955.8 6963.8 6970.0 6983.5 7059.5 5236.0 5625.5 6172.6 6287.1 6660.6 7036.6 7188.0 7202.7 7242.3 7250.5 3868.6 4033.8 4164.1 4441.8 4457.0 6587.2 6992.4 7028.3 7062.4 7073.9 5460.6 5957.3 6233.8 6775.0 6810.5 6631.1 6711.9 6784.7 6788.3 6816.7 6332.8 6497.2 6641.9 6661.6 6676.5 5546.1 5698.6 6751.7 6803.8 6805.7 6541.6 6553.6 6586.2 6605.2 6624.4 18:45:10 INFO - PID 1656 | 18:45:10 INFO - PID 1656 | [#5] yui.html Cycles:180 Average:20795.58 Median:17718.53 stddev:29012.62 (163.7%) stddev-sans-first:29054.91 18:45:10 INFO - PID 1656 | Values: 737.6 784.9 842.0 864.4 868.0 14330.3 14951.4 15018.9 16684.1 16898.7 16841.3 17161.2 17490.4 17540.0 17897.0 943.7 958.5 966.6 966.6 970.1 973.6 977.1 977.3 980.7 981.8 1006.1 1018.1 1024.6 1025.2 1027.8 995.2 1016.9 1021.7 1022.1 1023.2 737.7 751.4 755.1 757.3 757.7 16525.6 16766.4 16934.8 16979.6 16994.0 16014.0 16275.0 17148.1 17171.9 17365.1 12798.7 14891.5 14911.6 15019.3 15036.8 3546.1 3639.3 3690.7 3693.4 3712.5 107473.0 108106.0 108699.9 108799.0 110223.4 13123.3 13164.3 13332.8 13383.0 13422.4 24604.0 24614.1 24626.0 24662.5 25202.9 7549.5 8006.6 8016.5 8044.7 8068.4 3885.4 3896.6 3925.8 3939.4 3940.8 4262.7 4338.7 4470.7 4473.7 4483.4 906.1 922.9 927.8 938.2 940.5 636.4 648.1 651.9 653.2 680.0 11147.4 11841.0 12065.2 12085.9 12215.1 636.7 641.6 648.9 650.7 677.4 4902.3 4926.9 5436.4 5602.4 5715.8 66017.0 104712.5 105256.0 105373.4 107322.0 87495.6 91905.9 93421.1 93576.0 94446.5 81934.0 83284.2 83398.0 84189.6 84962.0 10266.8 11077.5 11114.5 11204.1 11409.4 42007.8 42032.0 42268.4 42341.0 42483.0 54181.0 54831.7 54886.9 55111.2 55224.6 13610.3 13760.4 14147.7 14173.4 14176.1 15159.3 15704.1 15800.9 15853.0 15889.9 13108.5 13150.1 14400.6 14736.9 14846.5 14021.7 14086.6 14315.5 14423.1 14534.9 13962.5 13998.2 14274.9 14463.5 14590.6 9339.7 10388.9 10469.2 10625.1 10952.5 11653.4 11749.6 11810.0 11840.9 11953.3 18:45:10 INFO - PID 1656 | -------- Summary: end -------- 18:45:10 INFO - PID 1656 | 18:45:11 INFO - TEST-INFO | 1656: exit 0 18:45:11 INFO - TEST-OK | dromaeo_css | took 1113867ms 18:45:11 INFO - TEST-START | kraken 18:45:11 INFO - operating with platform_type : mac_ 18:45:11 INFO - Initialising browser for kraken test... 18:45:11 INFO - TEST-INFO | started process 1781 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpHaht5J/profile http://localhost:49225/getInfo.html) 18:45:11 INFO - PID 1781 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 18:45:13 INFO - PID 1781 | 2017-05-16 18:45:13.820 plugin-container[1783:10356] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 18:45:13 INFO - PID 1781 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:13 INFO - PID 1781 | 2017-05-16 18:45:13.824 plugin-container[1783:10356] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.CFPasteboardClient' 18:45:13 INFO - PID 1781 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:13 INFO - PID 1781 | 2017-05-16 18:45:13.825 plugin-container[1783:10356] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:45:13 INFO - PID 1781 | __metrics Screen width/height:1600/1200 18:45:13 INFO - PID 1781 | colorDepth:24 18:45:13 INFO - PID 1781 | Browser inner width/height: 1024/691 18:45:13 INFO - PID 1781 | __metrics 18:45:15 INFO - TEST-INFO | 1781: exit 0 18:45:15 INFO - Browser initialized. 18:45:15 INFO - Running cycle 1/1 for kraken test... 18:45:15 INFO - TEST-INFO | started process 1785 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpHaht5J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:45:16 INFO - PID 1785 | 2017-05-16 18:45:16.838 plugin-container[1786:10503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x474b, name = 'com.apple.tsm.portname' 18:45:16 INFO - PID 1785 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:16 INFO - PID 1785 | 2017-05-16 18:45:16.839 plugin-container[1786:10503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 18:45:16 INFO - PID 1785 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:16 INFO - PID 1785 | 2017-05-16 18:45:16.839 plugin-container[1786:10503] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:45:17 INFO - PID 1785 | 2017-05-16 18:45:17.571 plugin-container[1787:10549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 18:45:17 INFO - PID 1785 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:17 INFO - PID 1785 | 2017-05-16 18:45:17.572 plugin-container[1787:10549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 18:45:17 INFO - PID 1785 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:45:17 INFO - PID 1785 | 2017-05-16 18:45:17.572 plugin-container[1787:10549] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:45:26 INFO - PID 1785 | RSS: Main: 236064768 18:45:26 INFO - PID 1785 | 18:45:29 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 18:45:30 INFO - PID 1785 | RSS: Main: 247881728 18:45:30 INFO - PID 1785 | 18:45:33 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 18:45:33 INFO - PID 1785 | RSS: Main: 256360448 18:45:33 INFO - PID 1785 | 18:45:37 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 18:45:37 INFO - PID 1785 | RSS: Main: 258244608 18:45:37 INFO - PID 1785 | 18:45:39 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 18:45:40 INFO - PID 1785 | RSS: Main: 259186688 18:45:40 INFO - PID 1785 | 18:45:42 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:45:42 INFO - PID 1785 | RSS: Main: 261033984 18:45:42 INFO - PID 1785 | 18:45:45 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 18:45:46 INFO - PID 1785 | RSS: Main: 266805248 18:45:46 INFO - PID 1785 | 18:45:49 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 18:45:50 INFO - PID 1785 | RSS: Main: 266555392 18:45:50 INFO - PID 1785 | 18:45:53 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 18:45:53 INFO - PID 1785 | RSS: Main: 265654272 18:45:53 INFO - PID 1785 | 18:45:55 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:45:55 INFO - PID 1785 | RSS: Main: 265498624 18:45:55 INFO - PID 1785 | 18:45:57 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:45:58 INFO - PID 1785 | RSS: Main: 265211904 18:45:58 INFO - PID 1785 | 18:46:00 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:46:01 INFO - PID 1785 | RSS: Main: 264761344 18:46:01 INFO - PID 1785 | 18:46:03 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:46:04 INFO - PID 1785 | RSS: Main: 264896512 18:46:04 INFO - PID 1785 | 18:46:07 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:46:08 INFO - PID 1785 | RSS: Main: 265068544 18:46:08 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 18:46:10 INFO - PID 1785 | RSS: Main: 267137024 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | __start_tp_report 18:46:10 INFO - PID 1785 | _x_x_mozilla_page_load 18:46:10 INFO - PID 1785 | _x_x_mozilla_page_load_details 18:46:10 INFO - PID 1785 | |i|pagename|runs| 18:46:10 INFO - PID 1785 | |0;ai-astar;103;115;114;115;115;115;139;167;156;115 18:46:10 INFO - PID 1785 | |1;audio-beat-detection;130;113;111;155;110;112;112;109;147;112 18:46:10 INFO - PID 1785 | |2;audio-dft;201;202;198;235;195;220;276;268;202;198 18:46:10 INFO - PID 1785 | |3;audio-fft;84;59;57;85;59;58;62;59;80;58 18:46:10 INFO - PID 1785 | |4;audio-oscillator;90;70;85;71;70;78;111;73;77;107 18:46:10 INFO - PID 1785 | |5;imaging-gaussian-blur;88;90;89;90;90;90;89;83;134;90 18:46:10 INFO - PID 1785 | |6;imaging-darkroom;95;105;96;96;109;150;96;95;95;95 18:46:10 INFO - PID 1785 | |7;imaging-desaturate;89;93;92;90;79;147;140;92;143;87 18:46:10 INFO - PID 1785 | |8;json-parse-financial;50;50;48;53;50;49;48;50;50;49 18:46:10 INFO - PID 1785 | |9;json-stringify-tinderbox;58;54;64;60;63;64;55;59;56;56 18:46:10 INFO - PID 1785 | |10;stanford-crypto-aes;85;81;84;81;85;82;84;94;86;107 18:46:10 INFO - PID 1785 | |11;stanford-crypto-ccm;136;140;136;139;168;156;159;136;192;138 18:46:10 INFO - PID 1785 | |12;stanford-crypto-pbkdf2;189;200;230;189;183;185;187;185;177;184 18:46:10 INFO - PID 1785 | |13;stanford-crypto-sha256-iterative;71;66;73;75;70;67;67;70;70;66 18:46:10 INFO - PID 1785 | __end_tp_report 18:46:10 INFO - PID 1785 | __start_cc_report 18:46:10 INFO - PID 1785 | _x_x_mozilla_cycle_collect,922 18:46:10 INFO - PID 1785 | __end_cc_report 18:46:10 INFO - PID 1785 | __startTimestamp1494985570302__endTimestamp 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | ------- Summary: start ------- 18:46:10 INFO - PID 1785 | Number of tests: 14 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#0] ai-astar Cycles:10 Average:125.40 Median:115.00 stddev:21.15 (18.4%) stddev-sans-first:20.82 18:46:10 INFO - PID 1785 | Values: 103.0 115.0 114.0 115.0 115.0 115.0 139.0 167.0 156.0 115.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#1] audio-beat-detection Cycles:10 Average:121.10 Median:112.50 stddev:16.95 (15.1%) stddev-sans-first:17.67 18:46:10 INFO - PID 1785 | Values: 130.0 113.0 111.0 155.0 110.0 112.0 112.0 109.0 147.0 112.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#2] audio-dft Cycles:10 Average:219.50 Median:211.00 stddev:30.30 (14.4%) stddev-sans-first:31.39 18:46:10 INFO - PID 1785 | Values: 201.0 202.0 198.0 235.0 195.0 220.0 276.0 268.0 202.0 198.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#3] audio-fft Cycles:10 Average:66.10 Median:60.50 stddev:11.80 (19.5%) stddev-sans-first:10.59 18:46:10 INFO - PID 1785 | Values: 84.0 59.0 57.0 85.0 59.0 58.0 62.0 59.0 80.0 58.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#4] audio-oscillator Cycles:10 Average:83.20 Median:75.00 stddev:15.11 (20.2%) stddev-sans-first:15.83 18:46:10 INFO - PID 1785 | Values: 90.0 70.0 85.0 71.0 70.0 78.0 111.0 73.0 77.0 107.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:90.00 stddev:14.46 (16.1%) stddev-sans-first:15.21 18:46:10 INFO - PID 1785 | Values: 88.0 90.0 89.0 90.0 90.0 90.0 89.0 83.0 134.0 90.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#6] imaging-darkroom Cycles:10 Average:103.20 Median:95.00 stddev:17.16 (18.1%) stddev-sans-first:17.95 18:46:10 INFO - PID 1785 | Values: 95.0 105.0 96.0 96.0 109.0 150.0 96.0 95.0 95.0 95.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#7] imaging-desaturate Cycles:10 Average:105.20 Median:89.50 stddev:26.66 (29.8%) stddev-sans-first:27.62 18:46:10 INFO - PID 1785 | Values: 89.0 93.0 92.0 90.0 79.0 147.0 140.0 92.0 143.0 87.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:1.42 (2.8%) stddev-sans-first:1.50 18:46:10 INFO - PID 1785 | Values: 50.0 50.0 48.0 53.0 50.0 49.0 48.0 50.0 50.0 49.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.50 stddev:3.75 (6.3%) stddev-sans-first:3.97 18:46:10 INFO - PID 1785 | Values: 58.0 54.0 64.0 60.0 63.0 64.0 55.0 59.0 56.0 56.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:84.50 stddev:7.98 (9.4%) stddev-sans-first:8.43 18:46:10 INFO - PID 1785 | Values: 85.0 81.0 84.0 81.0 85.0 82.0 84.0 94.0 86.0 107.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:148.00 stddev:18.73 (12.7%) stddev-sans-first:19.17 18:46:10 INFO - PID 1785 | Values: 136.0 140.0 136.0 139.0 168.0 156.0 159.0 136.0 192.0 138.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.90 Median:188.00 stddev:14.93 (7.9%) stddev-sans-first:15.82 18:46:10 INFO - PID 1785 | Values: 189.0 200.0 230.0 189.0 183.0 185.0 187.0 185.0 177.0 184.0 18:46:10 INFO - PID 1785 | 18:46:10 INFO - PID 1785 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:3.03 (4.3%) stddev-sans-first:3.16 18:46:10 INFO - PID 1785 | Values: 71.0 66.0 73.0 75.0 70.0 67.0 67.0 70.0 70.0 66.0 18:46:10 INFO - PID 1785 | -------- Summary: end -------- 18:46:10 INFO - PID 1785 | 18:46:11 INFO - TEST-INFO | 1785: exit 0 18:46:11 INFO - TEST-OK | kraken | took 59919ms 18:46:11 INFO - SUITE-END | took 1173s 18:46:11 INFO - Completed test suite (00:19:33) 18:46:11 INFO - javascript benchmark 18:46:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7774.1743431329805, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2966.377060817228, 3440.449528075507, 3584.4264917613177, 3585.946210806837, 3623.184783423729, 5320.574354051676, 6146.2317210348765, 6154.630722156674, 6262.0, 6263.37366263374, 6198.44214020738, 6201.255849298091, 6213.751449941997, 6255.624662520252, 6296.653684047387, 5457.399686034533, 5476.890462190754, 5492.642978206414, 5540.473655002768, 5548.250986116883, 4738.289256611519, 5585.776568937237, 5670.404607516213, 5678.205051292823, 5685.658860468385, 5419.376771671269, 5502.0921547944545, 5506.724663766796, 5572.219889215473, 5640.0, 5506.394296627368, 5555.666660000413, 5587.664740115606, 5608.046632072557, 5612.326520817487, 5483.067878460632, 5498.532624726903, 5537.695426751527, 5538.529225015878, 5546.944530554723, 6064.211652485149, 6272.968635156795, 6288.151099601541, 6311.936880631227, 6317.273513545909, 6078.179445774808, 6124.540659450559, 6446.419822216023, 6534.24856141545, 6751.831204219934, 6732.93267067328, 6837.282085381063, 6849.452043836481, 6857.828554286133, 6864.107666003438, 6240.781572644935, 6324.873502529969, 6347.682615869188, 6351.269603995554, 6365.299817020078, 6742.662866856637, 7019.157701075904, 7026.227115017344, 7039.54242974205, 7055.682494287769, 6506.25178104524, 6536.934630653633, 6631.767888123893, 6658.966705166443, 6661.467082633378, 5717.828465146053, 6624.768133115318, 6720.395164435225, 6799.082123913307, 6806.897896531556, 6509.056186852879, 6692.899606505906, 6695.765648202289, 6697.0, 6699.698513566901, 6759.594424334556, 6766.898496522556, 6785.762498312535, 6786.5928044316515, 6803.251642319341, 5059.886824898516, 5319.228711836762, 5319.5744340452675, 5322.520973112361, 5332.146856502941, 4769.73766442842, 4801.0, 4834.734089625104, 4848.539388758062, 4900.068986892479, 6387.648679322589, 6388.584741991755, 6406.5515413920575, 6407.807765767035, 6414.807555773335, 6628.270890202074, 6821.658917054127, 6867.484938629623, 6888.586684798928, 6889.207741109836, 6266.122742816009, 6280.842978925563, 6288.6855657217875, 6356.0, 6392.520560957853, 6017.16335154899, 6021.277446706424, 6025.698715064229, 6047.183630209954, 6051.606645568024, 6913.308669133046, 6955.965220173866, 6997.755078572225, 7011.263817299314, 7012.474064445187, 6636.535442518977, 6731.764388246584, 6763.560368576027, 6836.145481814773, 6843.144606924135, 5906.468417842415, 7016.438684905297, 7027.4729395294535, 7066.964665176642, 7078.2921707830865, 5597.776088956396, 6106.7862624809695, 6347.238331400262, 6709.832254193684, 6761.86476270462, 5253.842384728464, 6645.435138013129, 6718.529702920944, 6869.210040845167, 6875.656217189221, 6078.6930809876085, 6162.506999439945, 6547.279799222177, 6586.308437613981, 6603.471722262304, 6342.841428964312, 6577.177852768404, 6579.111819904251, 6595.868082638416, 6604.405603495708, 5020.824271150492, 6018.789342373171, 6304.18045654062, 6469.838254043687, 6485.837854053498, 5718.199452076796, 6496.740130394731, 6611.173603299588, 6647.933520664731, 6672.599644021374, 6465.838354041187, 6505.056766768697, 6516.120323756232, 6522.315156908457, 6534.379233972765], "value": 6119.42172639904, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.2859654907132, 1210.1347536511396, 1276.2661469654952, 1278.769821432142, 1284.5118854835155, 37154.814225928865, 37897.0, 37938.620613793886, 37973.0, 38026.619733802654, 37805.05487362817, 38165.61834381655, 38312.23375532488, 38367.80816095915, 38915.61084389162, 8006.519608823474, 8241.546714930677, 8248.711295104673, 8265.256126948574, 8290.253877151055, 8152.918470815275, 8220.985858464655, 8229.177082291782, 8236.876446853303, 8294.70968516102, 8101.351891848667, 8179.713710020151, 8235.13531079237, 8252.257296843283, 8265.95867020667, 6949.756758513453, 8221.630026648816, 8229.0, 8232.300254478376, 8239.464434811747, 8027.07688615805, 8042.075161356461, 8063.838723225503, 8071.19288071194, 8074.3136833369235, 28369.716302836914, 35353.64646353529, 35381.82309088464, 35386.29227415437, 35407.0, 3602.8018458984743, 3646.2160635463238, 3649.81750912456, 3651.050726811022, 3656.4698118772894, 29161.0, 29285.70714292851, 29312.0, 29324.0, 29388.412231755246, 3010.3979204159255, 3088.3051313454516, 3109.9067027989195, 3110.7666924980717, 3111.377724455095, 244169.77915110489, 292222.0, 293191.0, 293681.0631893696, 294377.0, 37236.0, 44305.55694443014, 44866.0, 45196.54803451923, 45273.0, 47061.0, 47109.293360599615, 47142.82142946385, 47265.76367118143, 47299.52700472951, 13583.932080339535, 13711.245881476416, 13724.862751372559, 13759.10565813219, 13769.586912392644, 9625.759356016015, 10882.727931801768, 11106.55573777056, 11115.944420277847, 11157.442127893573, 12796.552120675731, 12802.42389092493, 12833.294168820805, 12871.356432178354, 12979.675508112372, 5910.940890591039, 5946.0, 5956.55325850563, 5965.373635768268, 5965.582409231312, 885.2873436883242, 972.3096601412934, 992.3153024413276, 1001.8447140693204, 1047.696168111239, 23340.533189336118, 23405.180818671266, 23498.412539686302, 23529.058837646302, 23789.64315535268, 896.1531352871605, 980.0, 1009.7526106104051, 1036.0106098675747, 1065.8454524094118, 11362.602308919148, 11857.762844743056, 12021.579244726567, 12086.69783255408, 12103.818442723366, 285002.5749871279, 286053.0, 286389.5680521584, 286509.0, 286682.0, 41085.58914410877, 50326.993460130594, 50748.0, 50754.49245507571, 50811.745941270055, 1366.3373263966969, 1487.970240595182, 1501.8948673592963, 1539.8306186319496, 1554.2539581001183, 23049.539009220058, 23109.88445057764, 23118.306450806504, 23222.419439514146, 23252.069917203124, 36342.818285908405, 36375.272494549434, 36433.089172770895, 36463.45304820483, 36496.0, 265905.0, 266412.0, 266515.0, 267283.0, 267369.0, 31646.525302120946, 32362.83818580892, 32435.0, 33034.66965330316, 33136.84021059251, 41429.79285103555, 43275.0, 43450.782746086064, 43462.0, 43489.0, 29009.7099029007, 29243.707562924097, 29697.5545366824, 30483.08550743481, 30637.927672531354, 29533.0, 30146.54780178343, 30451.086467406014, 30499.54250686197, 30608.387832242784, 34874.47688284627, 35290.0, 36082.27835443329, 36186.27627447489, 36320.0, 29515.40969180647, 29767.10698679043, 30407.239819004702, 30485.54271685882, 30807.69192308048, 22658.546829062998, 23670.52658946846, 23697.05211791509, 24063.0, 24063.0], "value": 16962.341756553218, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.47117574207994, 436.46968932746705, 440.4824331410593, 451.0370359282931, 457.1976181800937, 18657.4402767917, 20398.490037749063, 21033.579328413423, 21141.942902854873, 21211.57576848462, 18280.16116615509, 21763.782362176375, 21841.890790546066, 22464.213752518666, 22665.433364165863, 5920.674362910038, 6194.163787888645, 6198.752049917998, 6199.163112979735, 6200.968995155018, 5190.558802501791, 5880.529557635379, 6084.665343406111, 6108.938910610881, 6111.908321375183, 6062.060380641008, 6099.054646529773, 6101.816945491642, 6114.052321890114, 6124.540659450537, 5762.193292938992, 6003.879922401549, 6045.637261764308, 6070.848228794293, 6071.483923866476, 5012.323336349583, 5057.873553161164, 5336.573074154058, 5374.220737992969, 5398.811041613525, 22209.44476388087, 24487.510249795083, 27351.0, 27358.863205684043, 27580.586291205647, 2153.3432303147533, 2200.6698995150696, 2223.5775202711434, 2226.0428015953134, 2234.2180236916947, 17538.912305438393, 17613.471595852017, 18224.0, 18227.0, 18513.90743046276, 1857.8978156201404, 1895.4882181810833, 2152.720146380976, 2183.170395249811, 2185.912563497458, 148191.0, 167334.0, 169041.0, 169454.0, 171018.0, 12460.12779105472, 12539.623811285675, 12604.054695897661, 12757.55348562796, 12937.676558086123, 13852.93073534626, 16359.918200408922, 16491.752623710654, 16495.91752041256, 16646.16769161537, 7512.323890849741, 8589.398742088119, 8878.28973682104, 8930.374873758905, 9015.729528114167, 8342.374321925758, 8374.790630234293, 8377.706780262783, 8557.187067228604, 8598.312135029182, 6588.8023359299295, 7860.528368297921, 7910.0, 7952.801179970547, 8008.439409241414, 1942.8834269943848, 2129.4037669452578, 2132.424245453719, 2147.484603695133, 2203.1848216160124, 928.6007016982627, 934.9485778282124, 951.695457453622, 967.4375882949128, 993.0714781679153, 20166.899165504077, 20494.897525512577, 20637.896810515853, 20714.58570828575, 21017.0, 966.7969726357403, 969.3941286695815, 998.8601595776597, 1002.9699109026741, 1003.9749006274756, 9111.772205694779, 10118.139958103644, 10256.948715256376, 10394.896051039545, 10851.891481085246, 540591.0, 542748.0, 543307.0, 547191.0, 547348.0, 123985.0, 126401.0, 127325.0, 127433.08850367252, 130988.0, 1166.4925757295434, 1199.772043311768, 1203.6449247472017, 1246.962591122268, 1252.7745005898844, 22433.43916402103, 22800.771992279864, 22890.54218915646, 22973.540529189657, 23068.4232894179, 52560.0, 53318.73340633427, 53747.92504149973, 56781.148282774964, 57547.7122614401, 70602.58794824178, 71296.0, 71846.0, 72245.55508889898, 72282.27717722756, 7679.308862202429, 8605.741827744927, 8663.566821658766, 8687.782805429915, 8871.379003469954, 13052.738945221232, 14164.645883852987, 14174.858251417354, 14423.92788036053, 14511.492097776527, 11470.0, 11896.464659090188, 12029.639110826689, 12120.0, 12135.635930922086, 10811.513481893491, 10910.78178436411, 11138.832917506083, 11147.386893720923, 11275.943620281847, 10885.619003334845, 11226.70892847333, 11905.285682859056, 12221.69445763863, 12242.204256723284, 8664.393492455467, 8897.777555561162, 9035.412698174598, 9046.909530904606, 9152.542372881462, 11018.834717479405, 11395.943020284845, 11853.644390668294, 12117.030637549151, 12128.0], "value": 10328.449907598757, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1122.5117074072778, 1309.8362704661918, 1319.3732976836004, 1465.5969608357707, 1466.208247546325, 11667.41662916855, 11838.99368553674, 12101.455434505435, 12120.69698257544, 12146.0, 11770.0, 11862.0, 11948.163628546004, 11983.340916249603, 12021.338826364547, 2546.7707906288383, 2589.339718371822, 2598.922032339023, 2601.8829152688177, 2601.9219423417326, 2511.660925775024, 2561.5773397389407, 2573.4209802794317, 2576.2786419802487, 2577.7422257774165, 2188.9343319700356, 2540.7840333571667, 2548.566743653574, 2552.145031414469, 2554.463562651845, 2101.8843963582, 2512.836665616738, 2529.7723204911463, 2538.3019669590826, 2540.9237722868343, 2485.6768620079456, 2509.6862892138483, 2517.093846215361, 2518.5970244760933, 2594.169865642995, 11698.707532311675, 11749.647510574696, 11756.823647645293, 11758.17692761507, 11783.0, 6244.5316601254635, 6403.327650596667, 6589.242237142742, 6819.318068193141, 6829.658517074176, 3875.2636998970106, 4191.308434108369, 4224.281872081723, 4255.510616279136, 4271.61555460007, 1247.7878760610718, 1264.8237139460298, 1284.3578210894552, 1284.871512848717, 1303.4720938020116, 16169.191540422931, 16323.83676163235, 16341.42805001831, 16342.918285408616, 16756.83243167577, 4879.780409881564, 5464.453554644504, 5593.216949627055, 5620.3255609326325, 5733.71331433435, 11436.485358158903, 11465.197436179387, 11524.88475115255, 11567.652970410902, 11578.0, 6480.416762491304, 6515.967420162869, 6518.543701940818, 6544.574602650812, 6572.737090516422, 5979.701014949235, 6069.484093851984, 6112.572119951561, 6241.282252540925, 6249.031400132987, 4183.184279065614, 4295.892602684896, 4299.613034826881, 4371.0602220522105, 4389.056352884145, 1082.7401423658266, 1134.9262297950606, 1210.140800031987, 1263.7409331087106, 1283.0633637444719, 668.4552090046597, 795.5425630262506, 817.8568750468644, 825.4964471672275, 826.5040975414823, 6281.748730050838, 6312.171552389766, 6948.305169483011, 6948.79153625382, 7002.334778196063, 904.6381447421085, 923.6166990698919, 932.4545141092401, 940.4216406909701, 942.8403064231035, 3310.751693622988, 4063.39049142631, 4171.582841715865, 4194.6853985951175, 4244.978775106167, 14579.343929523196, 15164.393424263126, 15178.089314640936, 15186.544403667907, 15239.0, 14328.785068223984, 15000.099994000177, 15092.320845561977, 15127.697446051017, 15135.940484166224, 7441.962790185906, 7987.8402431951035, 8571.271441927505, 8614.741557753277, 8646.048934617187, 6162.020183861999, 6838.93161068383, 6936.965315173392, 7157.141143062866, 7204.9826048706445, 12140.575079872515, 12536.310502922426, 12760.808587871375, 12774.489020439078, 12811.743765124831, 12795.232286062866, 12808.103432759617, 12897.871021289668, 13082.672933176365, 13290.734185316433, 10428.426436546062, 10583.31208471447, 10619.681409557727, 10730.248882578144, 10741.677749667522, 10596.20528460384, 10602.89397106019, 10639.521221544894, 10718.410487422954, 10738.892611073788, 8430.915690843012, 8557.743267701979, 8876.600552975002, 9037.367384283038, 9217.539123043955, 8831.161039701217, 8937.776555586162, 8967.641294348154, 9032.141946515334, 9056.54717264121, 7021.297870212938, 8062.39532035088, 8192.139825318256, 8275.710350137717, 8290.046644635944, 5556.0, 5941.732622031933, 5991.2510936132985, 6119.3268740437625, 6171.59884607499, 4636.675432719677, 6007.969960150172, 6087.452129308206, 6183.3816618337805, 6223.37766223374], "value": 5353.904923773055, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.3145697347146, 400.42538956597286, 400.6074047433513, 409.02447662325346, 412.6595558664101, 5419.945800541994, 6250.874982500347, 6304.873902521947, 6355.0, 6464.3212462691445, 4814.494478079804, 5179.352580927384, 5800.593958422912, 6274.937250627492, 6287.937120628792, 4425.8450954216605, 4489.393931819195, 4530.0, 4542.0, 4578.908421831561, 3672.8347224374834, 3758.755680880734, 3989.9202015959663, 4141.81361838718, 4500.279955207151, 3469.583649962008, 4514.435695538058, 4530.22986092365, 4534.523874993111, 4548.044910568768, 3927.7643341399607, 4039.798010099513, 4298.441202643652, 4491.640668746492, 4546.727196368212, 3232.498962660791, 3774.5093137892172, 3800.448934904451, 3829.5151412215027, 3843.7501562398356, 6031.366706495799, 6055.848603784896, 6066.0, 6074.51403887688, 6087.634741915499, 5272.736363181825, 5317.308749862493, 5330.1205301125265, 5368.221607866876, 5397.487238712316, 5860.648361098348, 6473.723915009253, 6610.900836487456, 6624.73501059957, 6629.701663425158, 4196.971741923248, 4375.453068366454, 4381.518033016366, 4385.429894113745, 4394.362817391492, 6250.0624906263965, 6936.514443988923, 7035.226125126232, 7199.280071992759, 7226.891596626055, 4068.898277543085, 4405.493368262627, 4449.594901047939, 4454.28731402974, 4456.598906098467, 3689.815509224528, 3834.5206849143856, 4390.968122491212, 4538.546145385435, 4651.837185698484, 4539.547344849682, 5307.575393968474, 5380.112281473513, 5398.2982212312145, 5427.810026649048, 5637.7181140942785, 5726.856828579235, 6726.327367263235, 6731.730730770812, 6734.292899245606, 1528.9847101529065, 1531.9199964025336, 1546.7989161408946, 1550.7053659804826, 1553.324303927795, 870.8737233101292, 997.6258902911409, 1001.9849702254472, 1009.7576581620505, 1022.6625213679467, 5782.703858800443, 5829.591928565044, 5970.671613061237, 6030.5778595497895, 6455.7740479083, 6060.969695151496, 6131.448169664752, 6981.0924579804305, 7028.789136325919, 7071.646417679095, 4932.161532539477, 5256.710880901511, 5325.068113080196, 5511.724413779295, 6389.488840892717, 3630.4735813306925, 3653.214558869856, 3655.415133578668, 3668.8899333020054, 3689.3728066228805, 5356.384015838228, 6759.019942108367, 6933.479989000845, 7179.09246755065, 7222.277772222736, 6694.698738556776, 7044.0415025689335, 7195.460340474486, 7197.0, 7232.168300645493, 5970.910436343458, 7077.5753454792875, 7111.359977601937, 7156.856862862818, 7215.927840721631, 6955.756548520778, 6963.7562685305775, 6969.989351544099, 6983.476239281973, 7059.541129826544, 5235.973820130875, 5625.521830644441, 6172.598781079395, 6287.056941458818, 6660.56706314088, 7036.612986285803, 7187.9640601796655, 7202.71189152428, 7242.334263119406, 7250.456215783943, 3868.5937976513187, 4033.8165457387117, 4164.104717485755, 4441.800118994588, 4457.016627600687, 6587.176602924635, 6992.405645520188, 7028.297170282931, 7062.364387205176, 7073.893891591733, 5460.563154947516, 5957.255343082114, 6233.781817636361, 6775.0, 6810.523263371517, 6631.148606099424, 6711.865762684622, 6784.660766961731, 6788.25329213796, 6816.693248803716, 6332.778352757816, 6497.18785151856, 6641.933580664325, 6661.570819781862, 6676.465882729273, 5546.112621980464, 5698.572607054404, 6751.696173672099, 6803.829904252433, 6805.693743781443, 6541.574797638138, 6553.639549824615, 6586.242582103119, 6605.240397354173, 6624.403803657694], "value": 4766.379527449043, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.6090671943865, 784.9372050235982, 841.9831603367933, 864.4424346296639, 867.986980195297, 14330.28348582572, 14951.401943922243, 15018.924905375485, 16684.082375469345, 16898.74651880221, 16841.32634694611, 17161.227744751486, 17490.38783642573, 17540.035298058603, 17897.015664138467, 943.6744323208482, 958.5015791788265, 966.5602151021268, 966.6375109334, 970.1220395542045, 973.5667627905585, 977.0766625538869, 977.301229620821, 980.691082309074, 981.813455443467, 1006.0894890124449, 1018.0989824005771, 1024.5901639344247, 1025.2361990317224, 1027.8406846938738, 995.21378111292, 1016.938983660979, 1021.6883850425618, 1022.110763635638, 1023.1967905194387, 737.6791095873259, 751.3838652305112, 755.0938873351945, 757.3373298363931, 757.7234309477001, 16525.58686032847, 16766.413175538797, 16934.83065169357, 16979.57551061221, 16994.0, 16013.95909265894, 16275.023498590004, 17148.142592870307, 17171.914140429217, 17365.131743412778, 12798.744025119446, 14891.478798242119, 14911.552653420415, 15019.324130414157, 15036.849631503654, 3546.131197856542, 3639.308531379056, 3690.686291665185, 3693.409054551286, 3712.480252764615, 107473.0, 108106.0, 108699.91300086977, 108799.0, 110223.44888275507, 13123.278219697817, 13164.275964821834, 13332.800007999887, 13383.0, 13422.39599218018, 24604.015839366584, 24614.138505152234, 24626.0, 24662.5067498649, 25202.873985629954, 7549.50928189666, 8006.559639219782, 8016.519008859487, 8044.678212871537, 8068.435209535393, 3885.378339465672, 3896.5713771485116, 3925.7644541327613, 3939.3893946438347, 3940.7832569208394, 4262.657262962157, 4338.674599405057, 4470.731756094579, 4473.709208901411, 4483.417155769795, 906.1391677906016, 922.8661844032578, 927.8329900617954, 938.15565990609, 940.5438362394233, 636.3699937062275, 648.1217949678212, 651.9087327774115, 653.1965682210907, 679.9762008329685, 11147.386893720923, 11841.0, 12065.215760975509, 12085.939570302093, 12215.083868709882, 636.7166610858217, 641.6150309814075, 648.9448396886223, 650.7201903181677, 677.3734295776387, 4902.313676085351, 4926.926096108561, 5436.374816896028, 5602.38373778884, 5715.828525144252, 66017.0, 104712.47643761733, 105256.0, 105373.41939870754, 107322.0, 87495.58512622808, 91905.89035206122, 93421.06578934124, 93576.0, 94446.52776736072, 81934.0, 83284.16715832886, 83398.0, 84189.57905210435, 84962.0, 10266.844430294237, 11077.501512431798, 11114.499847507042, 11204.103671706082, 11409.372484513431, 42007.78996105, 42032.0, 42268.36597451102, 42341.0, 42483.0, 54181.0, 54831.725841370535, 54886.902261955336, 55111.173332400846, 55224.61938451571, 13610.251436170709, 13760.380782864595, 14147.717045658823, 14173.362198700877, 14176.078554893898, 15159.317830697866, 15704.136272505122, 15800.92099539495, 15853.0, 15889.920550397173, 13108.475660973454, 13150.079494435298, 14400.567982960529, 14736.852631473548, 14846.480373186887, 14021.719565608426, 14086.647833804236, 14315.498957536436, 14423.062500937405, 14534.85465145335, 13962.511312104027, 13998.230097344744, 14274.857251427353, 14463.49377771773, 14590.562283131523, 9339.673111441067, 10388.896111038794, 10469.162467002774, 10625.096866766411, 10952.507137178687, 11653.359065251492, 11749.588764393206, 11810.0, 11840.934315911265, 11953.342566158944], "value": 8068.736811349092, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 114.0, 115.0, 115.0, 115.0, 139.0, 167.0, 156.0, 115.0], "value": 125.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 113.0, 111.0, 155.0, 110.0, 112.0, 112.0, 109.0, 147.0, 112.0], "value": 121.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 202.0, 198.0, 235.0, 195.0, 220.0, 276.0, 268.0, 202.0, 198.0], "value": 219.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 57.0, 85.0, 59.0, 58.0, 62.0, 59.0, 80.0, 58.0], "value": 66.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 85.0, 71.0, 70.0, 78.0, 111.0, 73.0, 77.0, 107.0], "value": 83.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 89.0, 90.0, 90.0, 90.0, 89.0, 83.0, 134.0, 90.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 105.0, 96.0, 96.0, 109.0, 150.0, 96.0, 95.0, 95.0, 95.0], "value": 103.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 92.0, 90.0, 79.0, 147.0, 140.0, 92.0, 143.0, 87.0], "value": 105.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 48.0, 53.0, 50.0, 49.0, 48.0, 50.0, 50.0, 49.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 64.0, 60.0, 63.0, 64.0, 55.0, 59.0, 56.0, 56.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 84.0, 81.0, 85.0, 82.0, 84.0, 94.0, 86.0, 107.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 140.0, 136.0, 139.0, 168.0, 156.0, 159.0, 136.0, 192.0, 138.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 200.0, 230.0, 189.0, 183.0, 185.0, 187.0, 185.0, 177.0, 184.0], "value": 190.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 73.0, 75.0, 70.0, 67.0, 67.0, 70.0, 70.0, 66.0], "value": 69.5, "unit": "score"}]}]} 18:46:11 INFO - Return code: 0 18:46:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:46:11 INFO - # TBPL SUCCESS # 18:46:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:46:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:46:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:46:11 INFO - 2017-05-16 18:46:11.630 screenresolution[1797:10936] starting screenresolution argv=screenresolution get 18:46:11 INFO - 2017-05-16 18:46:11.633 screenresolution[1797:10936] Display 0: 1600x1200x32@60 18:46:11 INFO - 2017-05-16 18:46:11.639 screenresolution[1798:10939] starting screenresolution argv=screenresolution list 18:46:11 INFO - Available Modes on Display 0 18:46:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:46:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:46:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:46:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:46:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:46:11 INFO - Intel Iris: 18:46:11 INFO - Chipset Model: Intel Iris 18:46:11 INFO - Type: GPU 18:46:11 INFO - Bus: Built-In 18:46:11 INFO - VRAM (Dynamic, Max): 1536 MB 18:46:11 INFO - Vendor: Intel (0x8086) 18:46:11 INFO - Device ID: 0x0a2e 18:46:11 INFO - Revision ID: 0x0009 18:46:11 INFO - Displays: 18:46:11 INFO - Display: 18:46:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:46:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:46:11 INFO - Display Serial Number: 1600x1200 60 18:46:11 INFO - Main Display: Yes 18:46:11 INFO - Mirror: Off 18:46:11 INFO - Online: Yes 18:46:11 INFO - Rotation: Supported 18:46:11 INFO - Return code: 0 18:46:11 INFO - Running post-action listener: _resource_record_post_action 18:46:11 INFO - [mozharness: 2017-05-17 01:46:11.879109Z] Finished run-tests step (success) 18:46:11 INFO - Running post-run listener: _resource_record_post_run 18:46:11 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 40270848; Write bytes: 535298560; Read time: 269; Write time: 1675 18:46:11 INFO - TinderboxPrint: CPU usage
54.4% 18:46:11 INFO - TinderboxPrint: I/O read bytes / time
40,270,848 / 269 18:46:11 INFO - TinderboxPrint: I/O write bytes / time
535,298,560 / 1,675 18:46:11 INFO - TinderboxPrint: CPU idle
2,182.2 (45.6%) 18:46:11 INFO - TinderboxPrint: CPU system
965.6 (20.2%) 18:46:11 INFO - TinderboxPrint: CPU user
1,633.7 (34.2%) 18:46:11 INFO - TinderboxPrint: Swap in / out
254,713,856 / 0 18:46:11 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 207239680; Write bytes: 208635904; Read time: 15804; Write time: 426 18:46:11 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 39291904; Write bytes: 316819968; Read time: 256; Write time: 1215 18:46:12 INFO - Running post-run listener: _upload_blobber_files 18:46:12 INFO - Blob upload gear active. 18:46:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:46:12 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 18:46:12 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'] 18:46:12 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 18:46:12 INFO - (blobuploader) - INFO - Open directory for files ... 18:46:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:46:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:46:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:13 INFO - (blobuploader) - INFO - Done attempting. 18:46:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:46:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:46:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:14 INFO - (blobuploader) - INFO - Done attempting. 18:46:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:46:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:46:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:15 INFO - (blobuploader) - INFO - Done attempting. 18:46:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:46:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:46:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:46:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:46:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:46:17 INFO - (blobuploader) - INFO - Done attempting. 18:46:17 INFO - (blobuploader) - INFO - Iteration through files over. 18:46:17 INFO - Return code: 0 18:46:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:46:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:46:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f377cdd90fdd349ed7ae96595fcd790c12b86adfb78b89b17dcddf7b7affc7fb4a63b178f6e61458c0a0d8056e8cea636aeb558d7f83b8b05d65db6928ae9cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e871d14ad83ae6409e5a65d3bfab5f62c3c975c7272c68f8e810c9eac8778a0eb2632ce32057ad5feaaa3f376ccd1a70ca5e7dfc77504de799daef2185b6d42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a90f3f475b886c627f5975aad99f0f2c4fa290b96b7686d59f6a3c474457f6d095ee65a4a901d7e7cfb29ba002c2bde651fa72283201973ba8792d259362960", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d0bffd073a9d8832f6863c2c652833982814a298a65a67a2f4c069b630b5ebdd633da66d180bb728e534c666d5ba8c214fed56748405a60b023997226270390"} 18:46:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:46:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:46:17 INFO - Contents: 18:46:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f377cdd90fdd349ed7ae96595fcd790c12b86adfb78b89b17dcddf7b7affc7fb4a63b178f6e61458c0a0d8056e8cea636aeb558d7f83b8b05d65db6928ae9cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e871d14ad83ae6409e5a65d3bfab5f62c3c975c7272c68f8e810c9eac8778a0eb2632ce32057ad5feaaa3f376ccd1a70ca5e7dfc77504de799daef2185b6d42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a90f3f475b886c627f5975aad99f0f2c4fa290b96b7686d59f6a3c474457f6d095ee65a4a901d7e7cfb29ba002c2bde651fa72283201973ba8792d259362960", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d0bffd073a9d8832f6863c2c652833982814a298a65a67a2f4c069b630b5ebdd633da66d180bb728e534c666d5ba8c214fed56748405a60b023997226270390"} 18:46:17 INFO - Running post-run listener: copy_logs_to_upload_dir 18:46:17 INFO - Copying logs to upload dir... 18:46:17 INFO - mkdir: /builds/slave/test/build/upload/logs 18:46:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.178851 ========= master_lag: 0.63 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-16 18:46:18.072230) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 18:46:18.077582) ========= 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.Fqz84EzdjL/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.4khZ427Zvo/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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/3f377cdd90fdd349ed7ae96595fcd790c12b86adfb78b89b17dcddf7b7affc7fb4a63b178f6e61458c0a0d8056e8cea636aeb558d7f83b8b05d65db6928ae9cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e871d14ad83ae6409e5a65d3bfab5f62c3c975c7272c68f8e810c9eac8778a0eb2632ce32057ad5feaaa3f376ccd1a70ca5e7dfc77504de799daef2185b6d42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a90f3f475b886c627f5975aad99f0f2c4fa290b96b7686d59f6a3c474457f6d095ee65a4a901d7e7cfb29ba002c2bde651fa72283201973ba8792d259362960", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d0bffd073a9d8832f6863c2c652833982814a298a65a67a2f4c069b630b5ebdd633da66d180bb728e534c666d5ba8c214fed56748405a60b023997226270390"} build_url:https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010966 build_url: 'https://queue.taskcluster.net/v1/task/UNqw_RLvTtaon9Bho4zygg/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/3f377cdd90fdd349ed7ae96595fcd790c12b86adfb78b89b17dcddf7b7affc7fb4a63b178f6e61458c0a0d8056e8cea636aeb558d7f83b8b05d65db6928ae9cf", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0e871d14ad83ae6409e5a65d3bfab5f62c3c975c7272c68f8e810c9eac8778a0eb2632ce32057ad5feaaa3f376ccd1a70ca5e7dfc77504de799daef2185b6d42", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5a90f3f475b886c627f5975aad99f0f2c4fa290b96b7686d59f6a3c474457f6d095ee65a4a901d7e7cfb29ba002c2bde651fa72283201973ba8792d259362960", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9d0bffd073a9d8832f6863c2c652833982814a298a65a67a2f4c069b630b5ebdd633da66d180bb728e534c666d5ba8c214fed56748405a60b023997226270390"}' ========= master_lag: 0.84 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 18:46:18.925495) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2017-05-16 18:46:18.925876) ========= 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.Fqz84EzdjL/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.4khZ427Zvo/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.004843 ========= master_lag: 3.62 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2017-05-16 18:46:22.552510) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 18:46:22.552862) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 18:46:22.646790) ========= ========= Total master_lag: 5.16 =========