builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0181 starttime: 1495056981.67 results: success (0) revision: b133ec74e3d0813c0951603209fa283ef0efd8b2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:21.667930) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:21.668679) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:21.669171) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:21.696939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:21.697513) ========= 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.vURfQURecD/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.jMT5T3145w/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-17 14:36:21-- 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% 13.9M=0.001s 2017-05-17 14:36:22 (13.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.407444 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:22.130796) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:22.131158) ========= 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.vURfQURecD/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.jMT5T3145w/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.028057 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:22.180022) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-17 14:36:22.180414) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b133ec74e3d0813c0951603209fa283ef0efd8b2 --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 b133ec74e3d0813c0951603209fa283ef0efd8b2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vURfQURecD/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.jMT5T3145w/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-17 14:36:22,269 truncating revision to first 12 chars 2017-05-17 14:36:22,270 Setting DEBUG logging. 2017-05-17 14:36:22,270 attempt 1/10 2017-05-17 14:36:22,270 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b133ec74e3d0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-17 14:36:24,135 unpacking tar archive at: mozilla-central-b133ec74e3d0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.141676 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-17 14:36:24.345214) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:24.345862) ========= script_repo_revision: b133ec74e3d0813c0951603209fa283ef0efd8b2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:24.346495) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:24.346990) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 14:36:24.367157) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-17 14:36:24.367614) ========= /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.vURfQURecD/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.jMT5T3145w/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:36:24 INFO - MultiFileLogger online at 20170517 14:36:24 in /builds/slave/test 14:36:24 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 14:36:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:36:24 INFO - {'append_to_log': False, 14:36:24 INFO - 'base_work_dir': '/builds/slave/test', 14:36:24 INFO - 'blob_upload_branch': 'Firefox', 14:36:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:36:24 INFO - 'branch': 'Firefox', 14:36:24 INFO - 'buildbot_json_path': 'buildprops.json', 14:36:24 INFO - 'config_files': ('talos/mac_config.py',), 14:36:24 INFO - 'default_actions': ('clobber', 14:36:24 INFO - 'read-buildbot-config', 14:36:24 INFO - 'download-and-extract', 14:36:24 INFO - 'populate-webroot', 14:36:24 INFO - 'create-virtualenv', 14:36:24 INFO - 'install', 14:36:24 INFO - 'run-tests'), 14:36:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:36:24 INFO - 'download_minidump_stackwalk': True, 14:36:24 INFO - 'download_symbols': 'ondemand', 14:36:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:36:24 INFO - 'tooltool.py': '/tools/tooltool.py', 14:36:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:36:24 INFO - '/tools/misc-python/virtualenv.py')}, 14:36:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:36:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:36:24 INFO - 'gecko_profile': False, 14:36:24 INFO - 'gecko_profile_interval': 0, 14:36:24 INFO - 'installer_path': 'installer.exe', 14:36:24 INFO - 'log_level': 'info', 14:36:24 INFO - 'log_name': 'talos', 14:36:24 INFO - 'log_to_console': True, 14:36:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:36:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:36:24 INFO - 'opt_config_files': (), 14:36:24 INFO - 'pip_index': False, 14:36:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:36:24 INFO - 'cmd': ('bash', 14:36:24 INFO - '-c', 14:36:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:36:24 INFO - 'enabled': True, 14:36:24 INFO - 'halt_on_failure': False, 14:36:24 INFO - 'name': 'check_screen_resolution'},), 14:36:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:36:24 INFO - 'cmd': ('bash', 14:36:24 INFO - '-c', 14:36:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:36:24 INFO - 'enabled': True, 14:36:24 INFO - 'halt_on_failure': False, 14:36:24 INFO - 'name': 'check_screen_resolution'},), 14:36:24 INFO - 'run_cmd_checks_enabled': True, 14:36:24 INFO - 'suite': 'dromaeojs-e10s', 14:36:24 INFO - 'system_bits': '32', 14:36:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:36:24 INFO - 'title': 't-yosemite-r7-0181', 14:36:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:36:24 INFO - 'use_talos_json': True, 14:36:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:36:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:36:24 INFO - 'work_dir': 'build'} 14:36:24 INFO - [mozharness: 2017-05-17 21:36:24.572939Z] Running clobber step. 14:36:24 INFO - Running pre-action listener: _resource_record_pre_action 14:36:24 INFO - Running main action method: clobber 14:36:24 INFO - rmtree: /builds/slave/test/build 14:36:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:36:27 INFO - Running post-action listener: _resource_record_post_action 14:36:27 INFO - [mozharness: 2017-05-17 21:36:27.091899Z] Finished clobber step (success) 14:36:27 INFO - [mozharness: 2017-05-17 21:36:27.092023Z] Running read-buildbot-config step. 14:36:27 INFO - Running pre-action listener: _resource_record_pre_action 14:36:27 INFO - Running main action method: read_buildbot_config 14:36:27 INFO - Using buildbot properties: 14:36:27 INFO - { 14:36:27 INFO - "project": "", 14:36:27 INFO - "product": "firefox", 14:36:27 INFO - "who": "kwierso@gmail.com", 14:36:27 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:36:27 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:36:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:36:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:36:27 INFO - "stage_platform": "macosx64", 14:36:27 INFO - "basedir": "/builds/slave/test", 14:36:27 INFO - "buildnumber": 59, 14:36:27 INFO - "platform": "yosemite_r7", 14:36:27 INFO - "parent_task_id": "QFzh82g4SvWCDaKgp-37tA", 14:36:27 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:36:27 INFO - "slavebuilddir": "test", 14:36:27 INFO - "taskId": "OW6AeT_0TNulbT0yUD2COw", 14:36:27 INFO - "branch": "mozilla-central", 14:36:27 INFO - "script_repo_revision": "production", 14:36:27 INFO - "revision": "b133ec74e3d0813c0951603209fa283ef0efd8b2", 14:36:27 INFO - "slavename": "t-yosemite-r7-0181", 14:36:27 INFO - "repo_path": "mozilla-central" 14:36:27 INFO - } 14:36:27 INFO - Finding installer, test and symbols from parent task. 14:36:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OW6AeT_0TNulbT0yUD2COw'}, attempt #1 14:36:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OW6AeT_0TNulbT0yUD2COw'}, attempt #1 14:36:28 INFO - Task dependencies: UR_J-Io2QUmz_7hIAb4CLQ 14:36:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA'}, attempt #1 14:36:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:36:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/test_packages.json 14:36:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/target.crashreporter-symbols.zip 14:36:28 INFO - Running post-action listener: _resource_record_post_action 14:36:28 INFO - [mozharness: 2017-05-17 21:36:28.911107Z] Finished read-buildbot-config step (success) 14:36:28 INFO - [mozharness: 2017-05-17 21:36:28.911246Z] Running download-and-extract step. 14:36:28 INFO - Running pre-action listener: _resource_record_pre_action 14:36:28 INFO - Running main action method: download_and_extract 14:36:28 INFO - mkdir: /builds/slave/test/build/tests 14:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:36:28 INFO - trying https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:36:28 INFO - Downloading https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/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 14:36:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/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 14:36:29 INFO - Downloaded 1325 bytes. 14:36:29 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:36:29 INFO - Using the following test package requirements: 14:36:29 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:36:29 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:36:29 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:36:29 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:36:29 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:36:29 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:36:29 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:36:29 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:36:29 INFO - u'jsshell-mac.zip'], 14:36:29 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:36:29 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:36:29 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:36:29 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:36:29 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:36:29 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:36:29 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:36:29 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:36:29 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:36:29 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:36:29 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:36:29 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:36:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:36:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:36:29 INFO - Fetch https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:36:31 INFO - Content-Length response header: 56567340 14:36:31 INFO - Bytes received: 56567340 14:36:49 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 14:36:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:36:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:36:49 INFO - Fetch https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:36:51 INFO - Content-Length response header: 56567340 14:36:51 INFO - Bytes received: 56567340 14:37:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:37:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:37:08 INFO - Fetch https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:37:09 INFO - Content-Length response header: 14410069 14:37:09 INFO - Bytes received: 14410069 14:37:09 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')]} 14:37:09 INFO - trying https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:37:09 INFO - Downloading https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:37:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/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 14:37:11 INFO - Downloaded 75913247 bytes. 14:37:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:37:11 INFO - mkdir: /builds/slave/test/properties 14:37:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:37:11 INFO - Writing to file /builds/slave/test/properties/build_url 14:37:11 INFO - Contents: 14:37:11 INFO - build_url:https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:37:11 INFO - Running post-action listener: _resource_record_post_action 14:37:11 INFO - Running post-action listener: set_extra_try_arguments 14:37:11 INFO - [mozharness: 2017-05-17 21:37:11.825669Z] Finished download-and-extract step (success) 14:37:11 INFO - [mozharness: 2017-05-17 21:37:11.825798Z] Running populate-webroot step. 14:37:11 INFO - Running pre-action listener: _resource_record_pre_action 14:37:11 INFO - Running main action method: populate_webroot 14:37:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:37:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:37:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:37:11 INFO - '--tppagecycles', 14:37:11 INFO - '7'], 14:37:11 INFO - 'tests': ['tsvgm']}}, 14:37:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:37:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:37:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:37:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:37:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:37:11 INFO - 'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:37:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:37:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:37:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:37:11 INFO - 'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['damp', 'tps']}, 14:37:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:37:11 INFO - 'tests': ['damp', 'tps']}, 14:37:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['dromaeo_dom']}, 14:37:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:37:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:37:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:37:11 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:37:11 INFO - 'tests': ['ts_paint', 'tp5o']}, 14:37:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['a11yr', 14:37:11 INFO - 'ts_paint', 14:37:11 INFO - 'tpaint', 14:37:11 INFO - 'sessionrestore', 14:37:11 INFO - 'sessionrestore_no_auto_restore', 14:37:11 INFO - 'tabpaint']}, 14:37:11 INFO - 'other-e10s': {'tests': ['a11yr', 14:37:11 INFO - 'ts_paint', 14:37:11 INFO - 'tpaint', 14:37:11 INFO - 'sessionrestore', 14:37:11 INFO - 'sessionrestore_no_auto_restore', 14:37:11 INFO - 'tabpaint']}, 14:37:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:37:11 INFO - 'ts_paint', 14:37:11 INFO - 'tpaint', 14:37:11 INFO - 'sessionrestore', 14:37:11 INFO - 'sessionrestore_no_auto_restore', 14:37:11 INFO - 'tabpaint']}, 14:37:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:37:11 INFO - 'ts_paint', 14:37:11 INFO - 'tpaint', 14:37:11 INFO - 'sessionrestore', 14:37:11 INFO - 'sessionrestore_no_auto_restore', 14:37:11 INFO - 'tabpaint']}, 14:37:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['a11yr', 14:37:11 INFO - 'ts_paint', 14:37:11 INFO - 'tpaint', 14:37:11 INFO - 'sessionrestore', 14:37:11 INFO - 'sessionrestore_no_auto_restore', 14:37:11 INFO - 'tabpaint']}, 14:37:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['a11yr', 14:37:11 INFO - 'ts_paint', 14:37:11 INFO - 'tpaint', 14:37:11 INFO - 'sessionrestore', 14:37:11 INFO - 'sessionrestore_no_auto_restore', 14:37:11 INFO - 'tabpaint']}, 14:37:11 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:37:11 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:37:11 INFO - 'bloom_basic_ref']}, 14:37:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['tsvgx', 14:37:11 INFO - 'tsvgr_opacity', 14:37:11 INFO - 'tart', 14:37:11 INFO - 'tscrollx', 14:37:11 INFO - 'cart', 14:37:11 INFO - 'tsvg_static']}, 14:37:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:37:11 INFO - 'tsvgr_opacity', 14:37:11 INFO - 'tart', 14:37:11 INFO - 'tscrollx', 14:37:11 INFO - 'cart', 14:37:11 INFO - 'tsvg_static']}, 14:37:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:37:11 INFO - 'talos_options': ['--disable-e10s'], 14:37:11 INFO - 'tests': ['tp5o']}, 14:37:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:37:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:37:11 INFO - 'talos_options': ['--disable-e10s', 14:37:11 INFO - '--xperf_path', 14:37:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:37:11 INFO - 'tests': ['tp5n']}, 14:37:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:37:11 INFO - 'talos_options': ['--xperf_path', 14:37:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:37:11 INFO - 'tests': ['tp5n']}}, 14:37:11 INFO - 'talos.zip': {'path': '', 14:37:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:37:11 INFO - Running post-action listener: _resource_record_post_action 14:37:11 INFO - [mozharness: 2017-05-17 21:37:11.847341Z] Finished populate-webroot step (success) 14:37:11 INFO - [mozharness: 2017-05-17 21:37:11.847451Z] Running create-virtualenv step. 14:37:11 INFO - Running pre-action listener: _resource_record_pre_action 14:37:11 INFO - Running main action method: create_virtualenv 14:37:11 INFO - Creating virtualenv /builds/slave/test/build/venv 14:37:11 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 14:37:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:37:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:37:11 INFO - Using real prefix '/tools/python27' 14:37:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:37:12 INFO - Installing distribute.............................................................................................................................................................................................done. 14:37:15 INFO - Installing pip.................done. 14:37:15 INFO - Return code: 0 14:37:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:37:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:37:15 INFO - Reading from file tmpfile_stdout 14:37:15 INFO - Output received: 14:37:15 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) 14:37:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37: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')]} 14:37:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37: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', 'pip>=1.5'] in /builds/slave/test/build 14:37: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 pip>=1.5 14:37:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:15 INFO - 'HOME': '/Users/cltbld', 14:37:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:15 INFO - 'LOGNAME': 'cltbld', 14:37:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:15 INFO - 'MOZ_NO_REMOTE': '1', 14:37:15 INFO - 'NO_EM_RESTART': '1', 14:37:15 INFO - 'PAGER': '/bin/cat', 14:37:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:15 INFO - 'PWD': '/builds/slave/test', 14:37:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:15 INFO - 'SHELL': '/bin/bash', 14:37:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:15 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:15 INFO - 'USER': 'cltbld', 14:37:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:15 INFO - 'XPC_FLAGS': '0x0', 14:37:15 INFO - 'XPC_SERVICE_NAME': '0', 14:37:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:16 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 14:37:16 INFO - Cleaning up... 14:37:16 INFO - Return code: 0 14:37:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:37:16 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')]} 14:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:16 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')]} 14:37:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:16 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 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37:16 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 14:37:16 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 14:37:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:16 INFO - 'HOME': '/Users/cltbld', 14:37:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:16 INFO - 'LOGNAME': 'cltbld', 14:37:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:16 INFO - 'MOZ_NO_REMOTE': '1', 14:37:16 INFO - 'NO_EM_RESTART': '1', 14:37:16 INFO - 'PAGER': '/bin/cat', 14:37:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:16 INFO - 'PWD': '/builds/slave/test', 14:37:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:16 INFO - 'SHELL': '/bin/bash', 14:37:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:16 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:16 INFO - 'USER': 'cltbld', 14:37:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:16 INFO - 'XPC_FLAGS': '0x0', 14:37:16 INFO - 'XPC_SERVICE_NAME': '0', 14:37:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:16 INFO - Downloading/unpacking psutil>=3.1.1 14:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:37:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:37:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:37:18 INFO - Installing collected packages: psutil 14:37:18 INFO - Running setup.py install for psutil 14:37:18 INFO - building 'psutil._psutil_osx' extension 14:37:18 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 14:37:18 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 14:37:19 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 14:37:19 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 14:37:19 INFO - building 'psutil._psutil_posix' extension 14:37:19 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 14:37:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:37:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:37:19 INFO - ^ 14:37:19 INFO - 1 warning generated. 14:37:19 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 14:37:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:37:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:37:19 INFO - Successfully installed psutil 14:37:19 INFO - Cleaning up... 14:37:19 INFO - Return code: 0 14:37:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:37:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:20 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 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37:20 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 14:37:20 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 14:37:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:20 INFO - 'HOME': '/Users/cltbld', 14:37:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:20 INFO - 'LOGNAME': 'cltbld', 14:37:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:20 INFO - 'MOZ_NO_REMOTE': '1', 14:37:20 INFO - 'NO_EM_RESTART': '1', 14:37:20 INFO - 'PAGER': '/bin/cat', 14:37:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:20 INFO - 'PWD': '/builds/slave/test', 14:37:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:20 INFO - 'SHELL': '/bin/bash', 14:37:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:20 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:20 INFO - 'USER': 'cltbld', 14:37:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:20 INFO - 'XPC_FLAGS': '0x0', 14:37:20 INFO - 'XPC_SERVICE_NAME': '0', 14:37:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:37:20 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 14:37:20 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 14:37:20 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 14:37:20 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 14:37:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:37:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:37:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:37:22 INFO - Installing collected packages: mozsystemmonitor 14:37:22 INFO - Running setup.py install for mozsystemmonitor 14:37:22 INFO - Successfully installed mozsystemmonitor 14:37:22 INFO - Cleaning up... 14:37:22 INFO - Return code: 0 14:37:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:22 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 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37:22 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 14:37:22 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 14:37:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:22 INFO - 'HOME': '/Users/cltbld', 14:37:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:22 INFO - 'LOGNAME': 'cltbld', 14:37:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:22 INFO - 'MOZ_NO_REMOTE': '1', 14:37:22 INFO - 'NO_EM_RESTART': '1', 14:37:22 INFO - 'PAGER': '/bin/cat', 14:37:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:22 INFO - 'PWD': '/builds/slave/test', 14:37:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:22 INFO - 'SHELL': '/bin/bash', 14:37:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:22 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:22 INFO - 'USER': 'cltbld', 14:37:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:22 INFO - 'XPC_FLAGS': '0x0', 14:37:22 INFO - 'XPC_SERVICE_NAME': '0', 14:37:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:22 INFO - Downloading/unpacking jsonschema==2.5.1 14:37:22 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 14:37:22 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 14:37:22 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 14:37:22 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 14:37:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:37:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:37:24 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 14:37:24 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 14:37:24 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 14:37:24 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 14:37:24 INFO - Downloading functools32-3.2.3-2.tar.gz 14:37:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:37:24 INFO - warning: no files found matching '*.txt' 14:37:24 INFO - no previously-included directories found matching 'build' 14:37:24 INFO - no previously-included directories found matching 'dist' 14:37:24 INFO - no previously-included directories found matching '.git*' 14:37:24 INFO - Installing collected packages: jsonschema, functools32 14:37:24 INFO - Running setup.py install for functools32 14:37:24 INFO - warning: no files found matching '*.txt' 14:37:24 INFO - no previously-included directories found matching 'build' 14:37:24 INFO - no previously-included directories found matching 'dist' 14:37:24 INFO - no previously-included directories found matching '.git*' 14:37:24 INFO - Successfully installed jsonschema functools32 14:37:24 INFO - Cleaning up... 14:37:24 INFO - Return code: 0 14:37:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:37:24 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')]} 14:37:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:24 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')]} 14:37:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:24 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 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37:24 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 14:37:24 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 14:37:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:24 INFO - 'HOME': '/Users/cltbld', 14:37:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:24 INFO - 'LOGNAME': 'cltbld', 14:37:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:24 INFO - 'MOZ_NO_REMOTE': '1', 14:37:24 INFO - 'NO_EM_RESTART': '1', 14:37:24 INFO - 'PAGER': '/bin/cat', 14:37:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:24 INFO - 'PWD': '/builds/slave/test', 14:37:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:24 INFO - 'SHELL': '/bin/bash', 14:37:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:24 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:24 INFO - 'USER': 'cltbld', 14:37:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:24 INFO - 'XPC_FLAGS': '0x0', 14:37:24 INFO - 'XPC_SERVICE_NAME': '0', 14:37:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:37:24 INFO - Cleaning up... 14:37:25 INFO - Return code: 0 14:37:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:37:25 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')]} 14:37:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:25 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')]} 14:37:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:25 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 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37:25 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 14:37:25 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 14:37:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:25 INFO - 'HOME': '/Users/cltbld', 14:37:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:25 INFO - 'LOGNAME': 'cltbld', 14:37:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:25 INFO - 'MOZ_NO_REMOTE': '1', 14:37:25 INFO - 'NO_EM_RESTART': '1', 14:37:25 INFO - 'PAGER': '/bin/cat', 14:37:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:25 INFO - 'PWD': '/builds/slave/test', 14:37:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:25 INFO - 'SHELL': '/bin/bash', 14:37:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:25 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:25 INFO - 'USER': 'cltbld', 14:37:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:25 INFO - 'XPC_FLAGS': '0x0', 14:37:25 INFO - 'XPC_SERVICE_NAME': '0', 14:37:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:25 INFO - Downloading/unpacking blobuploader==1.2.4 14:37:25 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 14:37:25 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 14:37:25 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 14:37:25 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 14:37:26 INFO - Downloading blobuploader-1.2.4.tar.gz 14:37:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:37:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:37:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:37:27 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 14:37:27 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 14:37:27 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 14:37:27 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 14:37:27 INFO - Downloading docopt-0.6.1.tar.gz 14:37:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:37:27 INFO - Installing collected packages: blobuploader, requests, docopt 14:37:27 INFO - Running setup.py install for blobuploader 14:37:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:37:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:37:27 INFO - Running setup.py install for requests 14:37:28 INFO - Running setup.py install for docopt 14:37:28 INFO - Successfully installed blobuploader requests docopt 14:37:28 INFO - Cleaning up... 14:37:28 INFO - Return code: 0 14:37:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:28 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 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37:28 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 14:37:28 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 14:37:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:28 INFO - 'HOME': '/Users/cltbld', 14:37:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:28 INFO - 'LOGNAME': 'cltbld', 14:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:28 INFO - 'MOZ_NO_REMOTE': '1', 14:37:28 INFO - 'NO_EM_RESTART': '1', 14:37:28 INFO - 'PAGER': '/bin/cat', 14:37:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:28 INFO - 'PWD': '/builds/slave/test', 14:37:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:28 INFO - 'SHELL': '/bin/bash', 14:37:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:28 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:28 INFO - 'USER': 'cltbld', 14:37:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:28 INFO - 'XPC_FLAGS': '0x0', 14:37:28 INFO - 'XPC_SERVICE_NAME': '0', 14:37:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:37:28 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-dO3Hke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:37:28 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5UqhwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:37:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:37:28 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-kLMzOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:37:29 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-itAafE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:37:29 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-iCuy0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:37:29 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-A400xR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:37:29 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-hpR0Eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:37:29 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-6HwH4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:37:29 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Hd9QVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:37:29 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-jDOIxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:37:29 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-CgVd86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:37:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:37:29 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-E3QA0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:37:30 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Yqif5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:37:30 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-81kRRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:30 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-XS1kGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:37:30 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-5Wnmgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:37:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:37:30 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-q2eWxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:37:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:37:30 INFO - Running setup.py install for manifestparser 14:37:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:37:30 INFO - Running setup.py install for mozcrash 14:37:30 INFO - Running setup.py install for mozdebug 14:37:30 INFO - Running setup.py install for mozdevice 14:37:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:37:31 INFO - Running setup.py install for mozfile 14:37:31 INFO - Running setup.py install for mozhttpd 14:37:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:37:31 INFO - Running setup.py install for mozinfo 14:37:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:37:31 INFO - Running setup.py install for mozInstall 14:37:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:37:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:37:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:37:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:37:31 INFO - Running setup.py install for mozleak 14:37:31 INFO - Running setup.py install for mozlog 14:37:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:37:32 INFO - Running setup.py install for moznetwork 14:37:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:37:32 INFO - Running setup.py install for mozprocess 14:37:32 INFO - Running setup.py install for mozprofile 14:37:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:37:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:37:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:37:32 INFO - Running setup.py install for mozrunner 14:37:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:37:32 INFO - Running setup.py install for mozscreenshot 14:37:32 INFO - Running setup.py install for moztest 14:37:32 INFO - Running setup.py install for mozversion 14:37:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:37:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:37:33 INFO - Cleaning up... 14:37:33 INFO - Return code: 0 14:37:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:33 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 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37:33 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 14:37:33 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 14:37:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:33 INFO - 'HOME': '/Users/cltbld', 14:37:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:33 INFO - 'LOGNAME': 'cltbld', 14:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:33 INFO - 'MOZ_NO_REMOTE': '1', 14:37:33 INFO - 'NO_EM_RESTART': '1', 14:37:33 INFO - 'PAGER': '/bin/cat', 14:37:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:33 INFO - 'PWD': '/builds/slave/test', 14:37:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:33 INFO - 'SHELL': '/bin/bash', 14:37:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:33 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:33 INFO - 'USER': 'cltbld', 14:37:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:33 INFO - 'XPC_FLAGS': '0x0', 14:37:33 INFO - 'XPC_SERVICE_NAME': '0', 14:37:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:37:33 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-Vp0SGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:37:33 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)) 14:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:37:33 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-1rqtAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:37:33 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)) 14:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:37:33 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-zUv7Jr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:37:33 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)) 14:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:37:33 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-1TaoEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:37:33 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)) 14:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:37:33 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-b6qLLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:37:33 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)) 14:37:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:37:33 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-BZMfKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-9MbdlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-3XxBfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-CvK9gM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-1Bg5Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-9vCEql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-NfTcyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-SC8_Xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-xxPTzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-w7WkpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:37:34 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)) 14:37:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:37:34 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-7WwMS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:37:35 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)) 14:37:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:37:35 INFO - Running setup.py (path:/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/pip-RvjkX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:37:35 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)) 14:37:35 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)) 14:37:35 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)) 14:37:35 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)) 14:37:35 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)) 14:37:35 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)) 14:37:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:37:35 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 14:37:35 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 14:37:35 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 14:37:35 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 14:37:36 INFO - Downloading blessings-1.6.tar.gz 14:37:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:37:36 INFO - Installing collected packages: blessings 14:37:36 INFO - Running setup.py install for blessings 14:37:36 INFO - Successfully installed blessings 14:37:36 INFO - Cleaning up... 14:37:36 INFO - Return code: 0 14:37:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:37:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:37:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:37:37 INFO - Reading from file tmpfile_stdout 14:37:37 INFO - Current package versions: 14:37:37 INFO - blessings == 1.6 14:37:37 INFO - blobuploader == 1.2.4 14:37:37 INFO - docopt == 0.6.1 14:37:37 INFO - functools32 == 3.2.3-2 14:37:37 INFO - jsonschema == 2.5.1 14:37:37 INFO - manifestparser == 1.1 14:37:37 INFO - mozInstall == 1.12 14:37:37 INFO - mozcrash == 1.0 14:37:37 INFO - mozdebug == 0.1 14:37:37 INFO - mozdevice == 0.50 14:37:37 INFO - mozfile == 1.2 14:37:37 INFO - mozhttpd == 0.7 14:37:37 INFO - mozinfo == 0.9 14:37:37 INFO - mozleak == 0.1 14:37:37 INFO - mozlog == 3.4 14:37:37 INFO - moznetwork == 0.27 14:37:37 INFO - mozprocess == 0.25 14:37:37 INFO - mozprofile == 0.28 14:37:37 INFO - mozrunner == 6.13 14:37:37 INFO - mozscreenshot == 0.1 14:37:37 INFO - mozsystemmonitor == 0.3 14:37:37 INFO - moztest == 0.8 14:37:37 INFO - mozversion == 1.4 14:37:37 INFO - psutil == 3.1.1 14:37:37 INFO - requests == 1.2.3 14:37:37 INFO - wsgiref == 0.1.2 14:37:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37: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')]} 14:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:37 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 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37:37 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 14:37:37 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 14:37:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:37 INFO - 'HOME': '/Users/cltbld', 14:37:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:37 INFO - 'LOGNAME': 'cltbld', 14:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:37 INFO - 'MOZ_NO_REMOTE': '1', 14:37:37 INFO - 'NO_EM_RESTART': '1', 14:37:37 INFO - 'PAGER': '/bin/cat', 14:37:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:37 INFO - 'PWD': '/builds/slave/test', 14:37:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:37 INFO - 'SHELL': '/bin/bash', 14:37:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:37 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:37 INFO - 'USER': 'cltbld', 14:37:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:37 INFO - 'XPC_FLAGS': '0x0', 14:37:37 INFO - 'XPC_SERVICE_NAME': '0', 14:37:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:37 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)) 14:37:37 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)) 14:37:37 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)) 14:37:37 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)) 14:37:37 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)) 14:37:37 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)) 14:37:37 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)) 14:37:37 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)) 14:37:37 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)) 14:37:37 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)) 14:37:37 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)) 14:37:37 INFO - Cleaning up... 14:37:37 INFO - Return code: 0 14:37:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:37: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')]} 14:37:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37: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')]} 14:37:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:37 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 0x102883b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102887768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc158462fb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, '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.vURfQURecD/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.jMT5T3145w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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 14:37:37 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 14:37:37 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 14:37:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:37 INFO - 'HOME': '/Users/cltbld', 14:37:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:37 INFO - 'LOGNAME': 'cltbld', 14:37:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:37 INFO - 'MOZ_NO_REMOTE': '1', 14:37:37 INFO - 'NO_EM_RESTART': '1', 14:37:37 INFO - 'PAGER': '/bin/cat', 14:37:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:37 INFO - 'PWD': '/builds/slave/test', 14:37:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:37 INFO - 'SHELL': '/bin/bash', 14:37:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:37 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:37 INFO - 'USER': 'cltbld', 14:37:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:37 INFO - 'XPC_FLAGS': '0x0', 14:37:37 INFO - 'XPC_SERVICE_NAME': '0', 14:37:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:37:37 INFO - Cleaning up... 14:37:37 INFO - Return code: 0 14:37:37 INFO - Running post-action listener: _resource_record_post_action 14:37:37 INFO - Running post-action listener: _start_resource_monitoring 14:37:37 INFO - Starting resource monitoring. 14:37:37 INFO - [mozharness: 2017-05-17 21:37:37.837452Z] Finished create-virtualenv step (success) 14:37:37 INFO - [mozharness: 2017-05-17 21:37:37.838234Z] Running install step. 14:37:37 INFO - Running pre-action listener: _resource_record_pre_action 14:37:37 INFO - Running main action method: install 14:37:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:37:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:37:38 INFO - Reading from file tmpfile_stdout 14:37:38 INFO - Detecting whether we're running mozinstall >=1.0... 14:37:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:37:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:37:38 INFO - Reading from file tmpfile_stdout 14:37:38 INFO - Output received: 14:37:38 INFO - Usage: mozinstall [options] installer 14:37:38 INFO - Options: 14:37:38 INFO - -h, --help show this help message and exit 14:37:38 INFO - -d DEST, --destination=DEST 14:37:38 INFO - Directory to install application into. [default: 14:37:38 INFO - "/builds/slave/test"] 14:37:38 INFO - --app=APP Application being installed. [default: firefox] 14:37:38 INFO - mkdir: /builds/slave/test/build/application 14:37:38 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'] 14:37:38 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 14:37:59 INFO - Reading from file tmpfile_stdout 14:37:59 INFO - Output received: 14:37:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:37:59 INFO - Running post-action listener: _resource_record_post_action 14:37:59 INFO - [mozharness: 2017-05-17 21:37:59.097158Z] Finished install step (success) 14:37:59 INFO - [mozharness: 2017-05-17 21:37:59.097294Z] Running run-tests step. 14:37:59 INFO - Running pre-action listener: _resource_record_pre_action 14:37:59 INFO - Running main action method: run_tests 14:37:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:37:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:37:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:37:59 INFO - 2017-05-17 14:37:59.112 screenresolution[1644:7631] starting screenresolution argv=screenresolution get 14:37:59 INFO - 2017-05-17 14:37:59.114 screenresolution[1644:7631] Display 0: 1600x1200x32@60 14:37:59 INFO - 2017-05-17 14:37:59.120 screenresolution[1645:7634] starting screenresolution argv=screenresolution list 14:37:59 INFO - Available Modes on Display 0 14:37:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:37:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:37:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:37:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:37:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:37:59 INFO - Intel Iris: 14:37:59 INFO - Chipset Model: Intel Iris 14:37:59 INFO - Type: GPU 14:37:59 INFO - Bus: Built-In 14:37:59 INFO - VRAM (Dynamic, Max): 1536 MB 14:37:59 INFO - Vendor: Intel (0x8086) 14:37:59 INFO - Device ID: 0x0a2e 14:37:59 INFO - Revision ID: 0x0009 14:37:59 INFO - Displays: 14:37:59 INFO - Display: 14:37:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:37:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:37:59 INFO - Display Serial Number: 1600x1200 60 14:37:59 INFO - Main Display: Yes 14:37:59 INFO - Mirror: Off 14:37:59 INFO - Online: Yes 14:37:59 INFO - Rotation: Supported 14:37:59 INFO - Return code: 0 14:37:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:37:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:37:59 INFO - Python 2.7.3 14:37:59 INFO - Return code: 0 14:37:59 INFO - grabbing minidump binary from tooltool 14:37: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')]} 14:37:59 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 0x102593d30>, '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 0x102547db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102588be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:37:59 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 14:37:59 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 14:37:59 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 14:37:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:37:59 INFO - Return code: 0 14:37:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:37:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:37:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:37:59 INFO - ENV: RUST_BACKTRACE is now 1 14:37:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:37:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:37:59 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/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 14:37:59 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/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0181 --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 14:37:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vURfQURecD/Render', 14:37:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:59 INFO - 'HOME': '/Users/cltbld', 14:37:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:59 INFO - 'LOGNAME': 'cltbld', 14:37:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:37:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:37:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:59 INFO - 'MOZ_NO_REMOTE': '1', 14:37:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:37:59 INFO - 'NO_EM_RESTART': '1', 14:37:59 INFO - 'PAGER': '/bin/cat', 14:37:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:59 INFO - 'PWD': '/builds/slave/test', 14:37:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:37:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:59 INFO - 'RUST_BACKTRACE': '1', 14:37:59 INFO - 'SHELL': '/bin/bash', 14:37:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jMT5T3145w/Listeners', 14:37:59 INFO - 'TMPDIR': '/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/', 14:37:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:59 INFO - 'USER': 'cltbld', 14:37:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:59 INFO - 'XPC_FLAGS': '0x0', 14:37:59 INFO - 'XPC_SERVICE_NAME': '0', 14:37:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:59 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/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0181', '--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 14:37:59 INFO - mozversion application_buildid: 20170517133516 14:37:59 INFO - mozversion application_changeset: b133ec74e3d0813c0951603209fa283ef0efd8b2 14:37:59 INFO - mozversion application_display_name: Nightly 14:37:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:37:59 INFO - mozversion application_name: Firefox 14:37:59 INFO - mozversion application_remotingname: firefox 14:37:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:37:59 INFO - mozversion application_vendor: Mozilla 14:37:59 INFO - mozversion application_version: 55.0a1 14:37:59 INFO - mozversion platform_buildid: 20170517133516 14:37:59 INFO - mozversion platform_changeset: b133ec74e3d0813c0951603209fa283ef0efd8b2 14:37:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:37:59 INFO - mozversion platform_version: 55.0a1 14:37:59 INFO - using testdate: 1495057079 14:37:59 INFO - actual date: 1495057079 14:37:59 INFO - starting webserver on 'localhost:49229' 14:37:59 INFO - SUITE-START | Running 2 tests 14:37:59 INFO - TEST-START | dromaeo_css 14:37:59 INFO - operating with platform_type : mac_ 14:37:59 INFO - Initialising browser for dromaeo_css test... 14:37:59 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpIgmx6D/profile http://localhost:49229/getInfo.html) 14:38:00 INFO - PID 1655 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2939: String.localeCompare is deprecated; use String.prototype.localeCompare instead 14:38:02 INFO - PID 1655 | 2017-05-17 14:38:02.326 plugin-container[1657:7819] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 14:38:02 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:02 INFO - PID 1655 | 2017-05-17 14:38:02.329 plugin-container[1657:7819] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa10b, name = 'com.apple.CFPasteboardClient' 14:38:02 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:02 INFO - PID 1655 | 2017-05-17 14:38:02.329 plugin-container[1657:7819] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:38:02 INFO - PID 1655 | __metrics Screen width/height:1600/1200 14:38:02 INFO - PID 1655 | colorDepth:24 14:38:02 INFO - PID 1655 | Browser inner width/height: 1024/691 14:38:02 INFO - PID 1655 | __metrics 14:38:03 INFO - TEST-INFO | 1655: exit 0 14:38:03 INFO - Browser initialized. 14:38:03 INFO - Running cycle 1/1 for dromaeo_css test... 14:38:03 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpIgmx6D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:05 INFO - PID 1659 | 2017-05-17 14:38:05.358 plugin-container[1660:7965] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 14:38:05 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:05 INFO - PID 1659 | 2017-05-17 14:38:05.359 plugin-container[1660:7965] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a13, name = 'com.apple.CFPasteboardClient' 14:38:05 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:05 INFO - PID 1659 | 2017-05-17 14:38:05.359 plugin-container[1660:7965] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:38:06 INFO - PID 1659 | 2017-05-17 14:38:06.096 plugin-container[1661:8017] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 14:38:06 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:06 INFO - PID 1659 | 2017-05-17 14:38:06.097 plugin-container[1661:8017] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9613, name = 'com.apple.CFPasteboardClient' 14:38:06 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:06 INFO - PID 1659 | 2017-05-17 14:38:06.097 plugin-container[1661:8017] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:38:15 INFO - PID 1659 | RSS: Main: 235798528 14:38:15 INFO - PID 1659 | 14:41:18 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 14:41:20 INFO - PID 1659 | RSS: Main: 256405504 14:41:20 INFO - PID 1659 | 14:44:22 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 14:44:22 INFO - PID 1659 | RSS: Main: 268410880 14:44:22 INFO - PID 1659 | 14:47:24 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 14:47:25 INFO - PID 1659 | RSS: Main: 272883712 14:47:25 INFO - PID 1659 | 14:50:26 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 14:50:27 INFO - PID 1659 | RSS: Main: 273879040 14:50:27 INFO - PID 1659 | 14:53:30 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 14:53:30 INFO - PID 1659 | RSS: Main: 274558976 14:53:30 INFO - PID 1659 | 14:56:32 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 14:56:32 INFO - PID 1659 | RSS: Main: 276582400 14:56:32 INFO - PID 1659 | 14:56:32 INFO - PID 1659 | __start_tp_report 14:56:32 INFO - PID 1659 | _x_x_mozilla_page_load 14:56:32 INFO - PID 1659 | _x_x_mozilla_page_load_details 14:56:32 INFO - PID 1659 | |i|pagename|runs| 14:56:32 INFO - PID 1659 | |0;dojo.html;3175.872965081397;3636.054625797292;3666.1384574624976;3678.8896333110015;3687.9489345536526;6151.861905547476;6303.527235457336;6307.306196318401;6351.7459301628;6352.047192921064;6232.096346029823;6251.062340648905;6278.874422511547;6306.590071645352;6350.555461117724;5522.30971128609;5579.358373787026;5590.468905453975;5590.776368945238;5677.630953987978;5653.802116925908;5732.914006289909;5783.074708046714;5795.913061304083;5805.73874175661;5503.036968530531;5658.236138121342;5660.29246344207;5742.310922689283;5776.335721392052;5508.55931525477;5654.717264136777;5658.321001479808;5689.516391106761;5728.341240757283;5578.748956296977;5579.414161513064;5628.549716022709;5635.126555383922;5673.262475878109;5523.28197334348;5861.4724674779045;6351.968240158816;6371.362863713638;6378.107065010902;5500;6662.966685166543;6738.989151627246;6796.08252885859;6892.414144797698;7003.019577259188;7014.123234595676;7019.298070192991;7025.964870175668;7039.577625342496;6486.156799616067;6489.059086432441;6497.545171837926;6497.740090396425;6507.967460162764;6908.930910690829;6998.825029374205;7040.894386584206;7094.219635840053;7185.065941427581;5762.481376676035;6832.829179270558;6837.3162683731225;6840.521163518506;6849.965750171317;6838.21360543534;6988;6992.755253566203;7001.229864714877;7027.402670772939;6543.869122617521;6704;6776.7628133015105;6850.143732033496;6888.035675005503;6727.596344219362;6865;6893.724251030003;6897.068895699016;6904.067950826675;5422.566194704415;5435;5436.293281873332;5439.700816455053;5568.6101972862325;4809.639277054235;4829.589484893823;4895.290182923456;4910.091633047898;4986.077575648516;6390.808275751735;6408.807735767934;6446.129772480749;6453.386928241809;6464.159659244268;6888.931110688929;6987.6855541499735;6993.125859267591;7008.229094799568;7035.613041282779;6300.27546832111;6382.266039405527;6455.096286519891;6457.773977910751;6467.902981455282;6061.181740465069;6111.786087486917;6116.113163591254;6120.660465672121;6129.14485165794;7130.215676275502;7135.643217839087;7152.892706609405;7154.713811447484;7156.570605763671;6786.626735529396;6888.896666549906;7004.579725216503;7005.859882802417;7028.226895041439;5983.521318294439;6981.5112942093565;7064.858702826017;7122.537035092702;7183.497155199086;6838.897416538855;6851.760188393382;6874.9312506876295;6876.346747059021;6897.379235868796;6241.375862413904;6785.7553073016525;6826.590404575742;6941.687624057031;6994.895076573754;6394.488440924808;6501.959686450145;6608.7356505739235;6728.562643428161;6749.73001079971;6690.230623478362;6714.798556043326;6718.260991291052;6727.293634168537;6740.955151951455;5658.377578466448;6384.489240860813;6388.201474815648;6466.353364663496;6630.602163870183;5573.442655734394;6685.63229022389;6778.288279730557;6794.592324460548;6801.965990170018;5542.8914217155925;6161.260648722182;6494.090827284279;6581;6601.768938087144 14:56:32 INFO - PID 1659 | |1;ext.html;721.3003386714885;772.9188435214303;794.5193158139325;1124.9043831274341;1208.9758204835898;37274.254514909684;37580.624193758056;37814;37849.81075094624;37906;32386.838065809698;37445.63424217883;37893.810530947376;37922.672706455276;38064.048398790044;7658.5021973571675;7776.844463110734;8012.198780121999;8238.629261683238;8263.793405164859;8194.959025204866;8269.669213231464;8274.33805295578;8307.335413166962;8316.37627177961;7863.741801311791;8223.876641850376;8242.670293188265;8312.251897329239;8314.958425207866;7644.579548124851;8250.38122140842;8268.421210515236;8316.584170791497;8338.541380224086;8089.514629122272;8131.959340203321;8140.47086939353;8145.470544414653;8152.062512810984;35887.64112358869;35916.64083359159;36023.819880900424;36080.09799755001;36149.45775813365;3543.6810687038032;3556.199855032623;3557.786532808014;3590.030691713249;3594.263176048904;29826.701732982827;29909.551356729666;29980.8500957496;29985.250368740737;30075.496225188872;3085.444619968427;3103.379324135182;3115.4080724662467;3115.4859448191023;3120.984395078033;253023;253377.73311133328;290351.9999999979;313324;315416;46721.76639116851;46855.76572117118;47133.76433117881;47158.76420617875;47200.29199562009;40300;48467.75766121147;48581.5141848577;48625.51374486281;48763;13863.306834658226;13898.027138100237;13982.300884955712;13984.580462586138;13995.930020349833;11184.664460066211;11353.432328383547;11367.147463940237;11382.65852024424;11386.601468948713;13037.478500860048;13119.934400328128;13142.474301027853;13215.471381144647;13286.468541258435;5908.202392676933;5917.201177840936;5929.881402371929;5944.6730429826775;5951.4048595141;959.3620242538634;968.4818622037176;972.5866506734609;987.2891518106951;988.649029594498;19443.838171392086;20554.38336849897;24023;24482.387940301636;24545.859967296255;1000.4997501249376;1120.8206686930052;1122.7417693930443;1145.9255148415327;1156.2311063143084;12061.638150855488;12278.079144064051;12321.336619556425;12357.32034738098;12382.938085309515;321795;323237;323486;323623;324135.3793231066;51464.22803657948;51558.45324640267;51579;51581;51680.74159629178;1502.1963249661596;1545.1115608525142;1549.0241148076639;1553.4485257733568;1557.704036233112;19886.403407897786;21688.457788555414;21833.908304585024;23697.763022369556;24194.637080443455;36436.635633644386;36610.901672949854;36634;36721.816390918946;36727;234699;279673;280075.5996220006;280146;281014;25512.48975020452;28064.579031314956;28308.71691283061;32023.359532808747;32128.19679508031;39737;40934.79532602318;40996;41794;41992.160156797305;23198;27347.179584612495;29754.464063913012;30069.3986120272;30483.475826209044;28488.85755571209;29332.560011599417;29372.853135734185;29558.704412955594;29741.85129074341;33500.32999340048;33637.66362336443;34608.134796630286;34753.65246347504;34960.85352318304;28826.567601486415;28894.42211155723;29763.851180743957;30065.699343007163;30816.383672326876;20147.093380797607;22111.889440552695;22901;23363.761112105403;23505.529889401772 14:56:32 INFO - PID 1659 | |2;jquery.html;385.360301898848;429.31738535728186;434.3029437752409;439.5582439648153;458.97475638839853;19034.61930761384;20582.48543786406;21105.788942110594;21130.471738206554;21389.786102139013;19489.902550487226;20906.372808815722;22071.44821379466;22528.620706893973;22702;5939.970300148493;6180.567360284782;6201.348858369874;6204.968975155118;6217.129601855721;5279.4984476474865;6067.666278354689;6081.148639190517;6088.9391106088815;6159.630422174684;6077.969610151943;6084.117802918574;6117.204763380754;6120.96939515304;6124.387561243862;5853;6034.06471996839;6092.268927728701;6097.176881121059;6107.541934354941;5294.602904782157;5314.256004159421;5402.6758394496455;5429.9411614735145;5448.237246785453;27893.58159627607;27901.581476277872;27977.30056748577;28522;28726.712732872613;2224.1881713174575;2289.862608243494;2308.549832782608;2309.549637820626;2311.9653205201935;17558.858674186264;18146.00196989165;18480.168392422373;18498.07509624527;18598.349057782914;2110.504031552584;2131.82945364372;2170.869747815136;2185.1041073159927;2186.5080356919725;122146;125256;152487.9999999989;154258;155333;12457.377131143407;12860.742785144244;12886.226826390446;12953.028522860823;13011.21932684042;16510.587235318973;16527.91736041336;16545.924514906492;16564.337426503047;16581.91709041471;8598.613062412207;8813.074627164055;8865.68970086044;8920.687775927941;9012.41419307743;8223.794405139919;8227.794305142299;8282.8343433131;8378;8393.328533717287;7167.390771784352;7204.387627051655;7557.5465472071855;7663.118741344705;7961.482503637245;2177.335912546657;2193.5503221839485;2193.6270833958265;2198.5383069555573;2379.060271192869;915.1763412928418;920.6501529418912;941.5951141009432;958.0802429667597;986.5412583148727;21126;21525.06053208587;21616.783832161793;21635.89182054111;21885.67171492429;954.5275088830934;1016.2733645443543;1016.6086056868158;1051.8842927277992;1052.4001319247955;10739.194560408001;10928.836067458995;10985.39580323074;11014.669559913216;11081.778364432666;522662;525474;528390;532054;538794;103671;108797;125209;131046;136330;1217.945192466341;1258.3267951646008;1268.5623459906317;1289.2586762611536;1293.3662505372488;16267.918660406622;22034.669479957494;22921.88539057294;23034.308970730905;23049.539009219385;43146.92132696708;45101.87245318893;46541.76729116333;54790.726046371106;55417.1687424697;57864.421355787585;64901.675491624126;66890.99327020197;75745;76357.61821190859;9003.623641134574;9020.594073266588;9068.047854975397;9075.682351117679;9323.813523729623;11664.94167529157;13633.931830340785;14502.637434064232;14668.119912805267;14826.925865371035;12005.279683219034;12133;12197.451114700038;12214.877851221374;12344.567940122053;11270.774584508099;11397.202195846212;11521.423928803693;11725.848055919834;11863.288202707865;12084.45619947122;12191.451384687532;12515.249085054926;12528.498860045496;12584.811227831407;7910.762677119696;8718.997315308821;8990.370674052754;9050.592723327334;9060.501672407818;11857.288562686266;12017.278963262232;12052.939735301617;12089.75820483603;12240.632781016584 14:56:32 INFO - PID 1659 | |3;mootools.html;1431.9069260498068;1484.213366915535;1503.068097779377;1504.8194216693992;1508.894377393582;11520.135989800767;12039.277643341395;12077.335746533938;12129.636110916665;12442.19125756825;12004.939975300133;12216.328101954388;12235.755284894296;12261.509539618426;12261.938690306559;2590.67616547932;2647.7087520372743;2650.6554147960833;2652.1247988163955;2658.986705066472;2573.6525569048126;2601.778848797845;2605.3226161198036;2611.542979978496;2620.2925210193234;2527.35552434129;2530.165045534978;2559.782418494421;2574.1466144376113;2574.5108429398447;2376.857388556692;2394.030417680842;2410.6745589345387;2414.215380001489;2422.6002709552904;2505.8747062646794;2508.1221572449494;2518.156417600106;2518.609615509599;2526.2926380613458;11807.704807379798;11818.054555635528;11864.406779660982;11919.165658403916;11947.462364193632;6639.73441062357;6893.034975103489;6895.931040689579;6902.033715279815;6951.16586009677;3488.5116083748294;3965.504311961005;3969.702272329587;4400.889977750582;4486.685931984763;1236.4930378544755;1254.956076537326;1300.5123078845434;1302.4594793160827;1304.30871638032;16363.754543681855;16453.42413015539;16503.752443713354;16641.583960400967;17022.063786491737;5593.664380137124;5707.1153971134545;5743.511801496919;5921.200637913913;5948.791792287249;11710.531578736754;11808.822867656992;11825.113116516295;11825.940870295766;11837;6564.376384243489;6571.605703657796;6579;6581.934180658228;6659.101021362151;6053.909191362133;6195.473384762346;6255.093011513305;6314.652694101776;6314.810555683337;3921.431392448079;4244.342126970325;4391.187630288407;4418.513963464016;4438.334249862482;1199.5861427807392;1227.1226073357413;1305.9673508162182;1310.2138716769862;1310.4692599497218;697.3235961117708;809.4536188073145;835.3651225068992;840.1892174052069;851.8637018077078;6279.529035322369;6301.842453938688;6519.771807986698;6552.3447655234095;6589.505787065893;591.2343515147894;595.2499850188793;598.7724664627501;600.6396162302583;606.333033662976;3390.830458477116;3896.201278737852;3933.3116704576582;4496.955030449719;4522.186006518858;14169.504067357593;14895.77656335156;14913.850861491463;15141.924290378478;15356.846431535765;14456.855431445761;15150.621234469227;15236.923815380853;15272.160031198391;15288.847111528965;8540.316774658013;8643.481391116427;8695.73912782605;8769.956150219336;8784.868226976601;6230.470410015199;6565.441937435371;6761.154855643044;6874.621895795779;6893.620850853251;12716.427760750603;13198.340082995619;13435.664108397368;13605.251711155981;13693.041487095808;13003.869961300645;13946;14022.22877741734;14081.507147249797;14261.358238879482;8318.625661845352;8333.791655208668;8364.205400486944;8390.706325278587;8429.915700842914;10829.458527073772;10874.891251087387;10946.726331841768;10950.39772812503;10971.945140274247;8423.70517031901;8977.281817454457;9189;9229.400088994194;9305.906940930505;9063.90936090657;9152.725418237464;9160.084136477184;9255.398399104037;9278.860817087614;6628.76799312041;7140.7143714252925;8124.025116985763;8241.423100382915;8382.790430239293;6036.849078773066;6052.061930400795;6077.969610151921;6258.843528911813;6271.78048768291;5876.471117599265;6005.549583781147;6124.693765311627;6225.190725205694;6285.59143655661 14:56:32 INFO - PID 1659 | |4;prototype.html;385.30837147320557;399.8960270329714;410.98561550345727;411.14276733011667;414.91286829765755;6090.23872015998;6274.811755647326;6388.904166437502;6428.2928877823515;6437.420632143107;6113.480354169889;6269.811905642827;6271.498280137589;6277.905831412532;6305.833420817141;3616.7287453440968;3678.411454167327;4536.183486972343;4538.478075021365;4559.475660299058;4045.2111838191563;4400.889977750566;4520.163769702599;4559.566841150086;4566.1780879441685;3594.388953877847;3853.5761066282685;4268.765217913014;4293.184294983943;4485.573870482299;3574.303010912873;4481.215787237253;4498.975461042512;4499.380223119674;4528.9094218115615;3492.720582353431;3781.2626537825136;3864.439656249828;3870.7096967727534;3877.9224415511812;4941.728204948727;5896.292444906639;5991.850203744898;6113.877722445526;6232.719527621268;4732.479427262998;4934.309196712463;5392.352917649907;5434.130539113723;5437.864053398657;5480.6437581557075;5564.304461942257;6480.287168411471;6715.093462382565;6723.226828914689;3805.847766089353;4277.422547956048;4376.824927002916;4387.9561204387865;4392.846250381253;5796.217510636052;6962.7911162665205;7101.07060604396;7190.209077001578;7203.495755297184;4324.632406245504;5007.198848184273;5046.066477701637;5071.2139618359215;5094.77073531692;4355.956440435555;4369.497507786646;4880.902381952341;4940.6788558743565;5180.585553155738;4352.347147927836;5559.471850174227;5586.190002449622;5713.600047996601;5791.710414479259;6691.799246022627;6827.761028363983;6827.965860170767;6828.146481689789;6835.906254999176;1716.742488626691;1753.1059159828324;1754.8683848711398;1776.1740790532465;1779.661864245789;1045.5190612318268;1185.903039688285;1204.6325870609548;1224.2776761710634;1244.172625204229;6244.875102497925;6333.651649159248;6346.01636746305;6350.904736428957;6354.4281014708895;6072.635641861414;6366.586171898812;6842.349976752201;6976.267491913276;6999.930000700029;5423.674579525241;5693.117566777156;6456.289808121197;6479.5788273762055;6483.481321494269;3321.103302108417;3589.892303230907;3624.7281453891064;3653.94519082214;3656.2870240302886;6262.903991801417;6386.393292637192;6944.479164062715;7029.578225306498;7239.819004524824;6629.403353698191;7189.352958233784;7221.566705997657;7250.3112204340505;7264.604914027043;5703.828885133453;6646.567973081735;7038.964805175941;7114.359707626339;7228.241034691387;6208.658523781235;6422;7087.03126249302;7118.52214484837;7124.821879453055;5849.415058494116;6795.7088153250725;6904.896426553706;6940.41006514452;6954.79135625932;6839.487038472035;7221.028434361186;7240.565566066053;7273.81785464348;7283.490155689051;3816.3512202926127;4432.938683512182;4439.089986552684;4462.709923854939;4498.865034048984;7063.223045464892;7066.222715501393;7089.503734738519;7104.786856394317;7116.181639111362;5074.441811400817;6834.419074378733;6853.623050732258;6919.584824910521;6928.549644273106;6701.631410272482;6732.821756192745;6787.457003439812;6788.558743681645;6803.829904252433;5341.732913354239;5382.650127741685;6672.132622759011;6679.098321726505;6730.038782935493;6740.494462915203;6855.189372357309;6871.278515755774;6873.243943166148;6873.635241621506;6544.9435402693225;6637.946544589543;6656.73373065072;6692.732290708317;6733.764318249034 14:56:32 INFO - PID 1659 | |5;yui.html;824.3364091906013;836.8033512124649;860.410618726173;862.284304027657;863.2705363967445;16609.501714948557;16963.067031313272;17074.317027318906;18497.90751046246;19134.23463061476;17993.010384428882;18358.173882175302;18480.353187638437;18586.070696465158;18765.343212987547;994.890562038177;997.5660587644362;1014.7919676466307;1017.6590842067916;1018.3228153278098;1010.0000999010999;1011.9797604047915;1012.0385633648027;1013.8986101389839;1016.369850692568;1053.420618659738;1074.9247552671316;1075.67192006438;1078.0190027075364;1080.983785243222;1058.2856571814034;1063.9308444951052;1067;1075.9623413180502;1079.2768844873954;763.1300317637897;772.4824367673672;772.6059709548115;781.0861292288037;781.1368437876122;17046.318147274094;17235.827641723547;17564.473065808048;17566.91216543909;17568.736468952975;15753.448629298035;16712.830101892872;17059.744103838453;17134.228959696844;17555.73666395005;14091;14235.359408826627;14308.427662893471;14473.565793026226;14485.493007744784;3553.6979356754705;3611.133328001261;3617.3127105850067;3618.222082252328;3619.49327094207;119889.80110198873;135365.32317338447;136019;136355;137317;13507.459701612022;13712.3829427676;13726.45094196241;13815.309234538434;13888.513902013381;21481.67777483339;24591.508169836503;25295.988160473375;25786.87106564455;26302.47395052088;7664.463487555818;7732.381409487228;7766.611669416507;7776.7278145264645;7838.372930165573;3704.2776658551325;3763.5107436033686;3774.094217387807;3776.0371105367954;3778.6221377861993;3885.9896426929204;3929.7838618876235;3990.1620659661794;4003.259397011562;4013.9197216055513;890.2521881619341;895.5746020640274;905.3979103895967;906.1753804038293;922.4880191493651;609.938706650425;615.3692465223182;620.500497099839;627.1345543150424;642.1042645509488;10078.395296282246;12184.451699673536;12208.145429820004;12220.816687749691;12317.199382040139;624.9375062493714;629.789020678078;633.7465013994439;645.3353046362255;667.5160508631203;4137;4577.718238893115;4883.4628190898975;4915.90168196634;5053.519915608012;99211;99452;99793;100252;102386.48806755918;59427.70286148541;91008.54495727479;92037;92906;93299.06700933039;73699;78696;83257.58371208105;83517.58241208877;85535.45271388408;8069.394795390252;8580.828383432172;8684.78288042804;9547.379420337656;9627.229821614394;39637.405438917864;39691.40462893001;40117.59882401255;40252.79873600613;40273.798631006655;33643.663563364054;51262.48737512577;51689;51845.74077129741;52656.736716316176;13903;13933.23367214813;14048.438062477795;14092.436302548193;14298.285085745463;15882.2058897057;15896;15926.522204333889;15935.681286373976;16074.919625401799;14523.491677791226;14584.124952502883;14867.95695486616;14911.85088149148;15019;14413.42346306178;14688.853111468748;14813.481528146463;14885.181315027345;14966.02720823143;14162.504312349018;14479;14704.852951470777;14716.19060951658;14734.410623574937;9064.410813297114;9597.952010239904;9802.950985245028;9953.203743700342;10014.39913605186;10509.842352364567;10624.468776560987;10681.465926703479;10821.9458902705;10878.728031799268 14:56:32 INFO - PID 1659 | __end_tp_report 14:56:32 INFO - PID 1659 | __start_cc_report 14:56:32 INFO - PID 1659 | _x_x_mozilla_cycle_collect,347 14:56:32 INFO - PID 1659 | __end_cc_report 14:56:32 INFO - PID 1659 | __startTimestamp1495058192556__endTimestamp 14:56:32 INFO - PID 1659 | 14:56:32 INFO - PID 1659 | ------- Summary: start ------- 14:56:32 INFO - PID 1659 | Number of tests: 6 14:56:32 INFO - PID 1659 | 14:56:32 INFO - PID 1659 | [#0] dojo.html Cycles:165 Average:6305.96 Median:6486.16 stddev:757.85 (11.7%) stddev-sans-first:719.29 14:56:32 INFO - PID 1659 | Values: 3175.9 3636.1 3666.1 3678.9 3687.9 6151.9 6303.5 6307.3 6351.7 6352.0 6232.1 6251.1 6278.9 6306.6 6350.6 5522.3 5579.4 5590.5 5590.8 5677.6 5653.8 5732.9 5783.1 5795.9 5805.7 5503.0 5658.2 5660.3 5742.3 5776.3 5508.6 5654.7 5658.3 5689.5 5728.3 5578.7 5579.4 5628.5 5635.1 5673.3 5523.3 5861.5 6352.0 6371.4 6378.1 5500.0 6663.0 6739.0 6796.1 6892.4 7003.0 7014.1 7019.3 7026.0 7039.6 6486.2 6489.1 6497.5 6497.7 6508.0 6908.9 6998.8 7040.9 7094.2 7185.1 5762.5 6832.8 6837.3 6840.5 6850.0 6838.2 6988.0 6992.8 7001.2 7027.4 6543.9 6704.0 6776.8 6850.1 6888.0 6727.6 6865.0 6893.7 6897.1 6904.1 5422.6 5435.0 5436.3 5439.7 5568.6 4809.6 4829.6 4895.3 4910.1 4986.1 6390.8 6408.8 6446.1 6453.4 6464.2 6888.9 6987.7 6993.1 7008.2 7035.6 6300.3 6382.3 6455.1 6457.8 6467.9 6061.2 6111.8 6116.1 6120.7 6129.1 7130.2 7135.6 7152.9 7154.7 7156.6 6786.6 6888.9 7004.6 7005.9 7028.2 5983.5 6981.5 7064.9 7122.5 7183.5 6838.9 6851.8 6874.9 6876.3 6897.4 6241.4 6785.8 6826.6 6941.7 6994.9 6394.5 6502.0 6608.7 6728.6 6749.7 6690.2 6714.8 6718.3 6727.3 6741.0 5658.4 6384.5 6388.2 6466.4 6630.6 5573.4 6685.6 6778.3 6794.6 6802.0 5542.9 6161.3 6494.1 6581.0 6601.8 14:56:32 INFO - PID 1659 | 14:56:32 INFO - PID 1659 | [#1] ext.html Cycles:180 Average:43601.86 Median:178004.92 stddev:77342.54 (43.4%) stddev-sans-first:77492.50 14:56:32 INFO - PID 1659 | Values: 721.3 772.9 794.5 1124.9 1209.0 37274.3 37580.6 37814.0 37849.8 37906.0 32386.8 37445.6 37893.8 37922.7 38064.0 7658.5 7776.8 8012.2 8238.6 8263.8 8195.0 8269.7 8274.3 8307.3 8316.4 7863.7 8223.9 8242.7 8312.3 8315.0 7644.6 8250.4 8268.4 8316.6 8338.5 8089.5 8132.0 8140.5 8145.5 8152.1 35887.6 35916.6 36023.8 36080.1 36149.5 3543.7 3556.2 3557.8 3590.0 3594.3 29826.7 29909.6 29980.9 29985.3 30075.5 3085.4 3103.4 3115.4 3115.5 3121.0 253023.0 253377.7 290352.0 313324.0 315416.0 46721.8 46855.8 47133.8 47158.8 47200.3 40300.0 48467.8 48581.5 48625.5 48763.0 13863.3 13898.0 13982.3 13984.6 13995.9 11184.7 11353.4 11367.1 11382.7 11386.6 13037.5 13119.9 13142.5 13215.5 13286.5 5908.2 5917.2 5929.9 5944.7 5951.4 959.4 968.5 972.6 987.3 988.6 19443.8 20554.4 24023.0 24482.4 24545.9 1000.5 1120.8 1122.7 1145.9 1156.2 12061.6 12278.1 12321.3 12357.3 12382.9 321795.0 323237.0 323486.0 323623.0 324135.4 51464.2 51558.5 51579.0 51581.0 51680.7 1502.2 1545.1 1549.0 1553.4 1557.7 19886.4 21688.5 21833.9 23697.8 24194.6 36436.6 36610.9 36634.0 36721.8 36727.0 234699.0 279673.0 280075.6 280146.0 281014.0 25512.5 28064.6 28308.7 32023.4 32128.2 39737.0 40934.8 40996.0 41794.0 41992.2 23198.0 27347.2 29754.5 30069.4 30483.5 28488.9 29332.6 29372.9 29558.7 29741.9 33500.3 33637.7 34608.1 34753.7 34960.9 28826.6 28894.4 29763.9 30065.7 30816.4 20147.1 22111.9 22901.0 23363.8 23505.5 14:56:32 INFO - PID 1659 | 14:56:32 INFO - PID 1659 | [#2] jquery.html Cycles:180 Average:34060.90 Median:12495.93 stddev:89343.07 (715.0%) stddev-sans-first:89557.92 14:56:32 INFO - PID 1659 | Values: 385.4 429.3 434.3 439.6 459.0 19034.6 20582.5 21105.8 21130.5 21389.8 19489.9 20906.4 22071.4 22528.6 22702.0 5940.0 6180.6 6201.3 6205.0 6217.1 5279.5 6067.7 6081.1 6088.9 6159.6 6078.0 6084.1 6117.2 6121.0 6124.4 5853.0 6034.1 6092.3 6097.2 6107.5 5294.6 5314.3 5402.7 5429.9 5448.2 27893.6 27901.6 27977.3 28522.0 28726.7 2224.2 2289.9 2308.5 2309.5 2312.0 17558.9 18146.0 18480.2 18498.1 18598.3 2110.5 2131.8 2170.9 2185.1 2186.5 122146.0 125256.0 152488.0 154258.0 155333.0 12457.4 12860.7 12886.2 12953.0 13011.2 16510.6 16527.9 16545.9 16564.3 16581.9 8598.6 8813.1 8865.7 8920.7 9012.4 8223.8 8227.8 8282.8 8378.0 8393.3 7167.4 7204.4 7557.5 7663.1 7961.5 2177.3 2193.6 2193.6 2198.5 2379.1 915.2 920.7 941.6 958.1 986.5 21126.0 21525.1 21616.8 21635.9 21885.7 954.5 1016.3 1016.6 1051.9 1052.4 10739.2 10928.8 10985.4 11014.7 11081.8 522662.0 525474.0 528390.0 532054.0 538794.0 103671.0 108797.0 125209.0 131046.0 136330.0 1217.9 1258.3 1268.6 1289.3 1293.4 16267.9 22034.7 22921.9 23034.3 23049.5 43146.9 45101.9 46541.8 54790.7 55417.2 57864.4 64901.7 66891.0 75745.0 76357.6 9003.6 9020.6 9068.0 9075.7 9323.8 11664.9 13633.9 14502.6 14668.1 14826.9 12005.3 12133.0 12197.5 12214.9 12344.6 11270.8 11397.2 11521.4 11725.8 11863.3 12084.5 12191.5 12515.2 12528.5 12584.8 7910.8 8719.0 8990.4 9050.6 9060.5 11857.3 12017.3 12052.9 12089.8 12240.6 14:56:32 INFO - PID 1659 | 14:56:32 INFO - PID 1659 | [#3] mootools.html Cycles:180 Average:7136.69 Median:3439.67 stddev:4547.31 (132.2%) stddev-sans-first:4539.86 14:56:32 INFO - PID 1659 | Values: 1431.9 1484.2 1503.1 1504.8 1508.9 11520.1 12039.3 12077.3 12129.6 12442.2 12004.9 12216.3 12235.8 12261.5 12261.9 2590.7 2647.7 2650.7 2652.1 2659.0 2573.7 2601.8 2605.3 2611.5 2620.3 2527.4 2530.2 2559.8 2574.1 2574.5 2376.9 2394.0 2410.7 2414.2 2422.6 2505.9 2508.1 2518.2 2518.6 2526.3 11807.7 11818.1 11864.4 11919.2 11947.5 6639.7 6893.0 6895.9 6902.0 6951.2 3488.5 3965.5 3969.7 4400.9 4486.7 1236.5 1255.0 1300.5 1302.5 1304.3 16363.8 16453.4 16503.8 16641.6 17022.1 5593.7 5707.1 5743.5 5921.2 5948.8 11710.5 11808.8 11825.1 11825.9 11837.0 6564.4 6571.6 6579.0 6581.9 6659.1 6053.9 6195.5 6255.1 6314.7 6314.8 3921.4 4244.3 4391.2 4418.5 4438.3 1199.6 1227.1 1306.0 1310.2 1310.5 697.3 809.5 835.4 840.2 851.9 6279.5 6301.8 6519.8 6552.3 6589.5 591.2 595.2 598.8 600.6 606.3 3390.8 3896.2 3933.3 4497.0 4522.2 14169.5 14895.8 14913.9 15141.9 15356.8 14456.9 15150.6 15236.9 15272.2 15288.8 8540.3 8643.5 8695.7 8770.0 8784.9 6230.5 6565.4 6761.2 6874.6 6893.6 12716.4 13198.3 13435.7 13605.3 13693.0 13003.9 13946.0 14022.2 14081.5 14261.4 8318.6 8333.8 8364.2 8390.7 8429.9 10829.5 10874.9 10946.7 10950.4 10971.9 8423.7 8977.3 9189.0 9229.4 9305.9 9063.9 9152.7 9160.1 9255.4 9278.9 6628.8 7140.7 8124.0 8241.4 8382.8 6036.8 6052.1 6078.0 6258.8 6271.8 5876.5 6005.5 6124.7 6225.2 6285.6 14:56:32 INFO - PID 1659 | 14:56:32 INFO - PID 1659 | [#4] prototype.html Cycles:180 Average:5421.61 Median:6113.68 stddev:1734.08 (28.4%) stddev-sans-first:1697.24 14:56:32 INFO - PID 1659 | Values: 385.3 399.9 411.0 411.1 414.9 6090.2 6274.8 6388.9 6428.3 6437.4 6113.5 6269.8 6271.5 6277.9 6305.8 3616.7 3678.4 4536.2 4538.5 4559.5 4045.2 4400.9 4520.2 4559.6 4566.2 3594.4 3853.6 4268.8 4293.2 4485.6 3574.3 4481.2 4499.0 4499.4 4528.9 3492.7 3781.3 3864.4 3870.7 3877.9 4941.7 5896.3 5991.9 6113.9 6232.7 4732.5 4934.3 5392.4 5434.1 5437.9 5480.6 5564.3 6480.3 6715.1 6723.2 3805.8 4277.4 4376.8 4388.0 4392.8 5796.2 6962.8 7101.1 7190.2 7203.5 4324.6 5007.2 5046.1 5071.2 5094.8 4356.0 4369.5 4880.9 4940.7 5180.6 4352.3 5559.5 5586.2 5713.6 5791.7 6691.8 6827.8 6828.0 6828.1 6835.9 1716.7 1753.1 1754.9 1776.2 1779.7 1045.5 1185.9 1204.6 1224.3 1244.2 6244.9 6333.7 6346.0 6350.9 6354.4 6072.6 6366.6 6842.3 6976.3 6999.9 5423.7 5693.1 6456.3 6479.6 6483.5 3321.1 3589.9 3624.7 3653.9 3656.3 6262.9 6386.4 6944.5 7029.6 7239.8 6629.4 7189.4 7221.6 7250.3 7264.6 5703.8 6646.6 7039.0 7114.4 7228.2 6208.7 6422.0 7087.0 7118.5 7124.8 5849.4 6795.7 6904.9 6940.4 6954.8 6839.5 7221.0 7240.6 7273.8 7283.5 3816.4 4432.9 4439.1 4462.7 4498.9 7063.2 7066.2 7089.5 7104.8 7116.2 5074.4 6834.4 6853.6 6919.6 6928.5 6701.6 6732.8 6787.5 6788.6 6803.8 5341.7 5382.7 6672.1 6679.1 6730.0 6740.5 6855.2 6871.3 6873.2 6873.6 6544.9 6637.9 6656.7 6692.7 6733.8 14:56:32 INFO - PID 1659 | 14:56:32 INFO - PID 1659 | [#5] yui.html Cycles:180 Average:21058.56 Median:20307.96 stddev:30721.36 (151.3%) stddev-sans-first:30769.98 14:56:32 INFO - PID 1659 | Values: 824.3 836.8 860.4 862.3 863.3 16609.5 16963.1 17074.3 18497.9 19134.2 17993.0 18358.2 18480.4 18586.1 18765.3 994.9 997.6 1014.8 1017.7 1018.3 1010.0 1012.0 1012.0 1013.9 1016.4 1053.4 1074.9 1075.7 1078.0 1081.0 1058.3 1063.9 1067.0 1076.0 1079.3 763.1 772.5 772.6 781.1 781.1 17046.3 17235.8 17564.5 17566.9 17568.7 15753.4 16712.8 17059.7 17134.2 17555.7 14091.0 14235.4 14308.4 14473.6 14485.5 3553.7 3611.1 3617.3 3618.2 3619.5 119889.8 135365.3 136019.0 136355.0 137317.0 13507.5 13712.4 13726.5 13815.3 13888.5 21481.7 24591.5 25296.0 25786.9 26302.5 7664.5 7732.4 7766.6 7776.7 7838.4 3704.3 3763.5 3774.1 3776.0 3778.6 3886.0 3929.8 3990.2 4003.3 4013.9 890.3 895.6 905.4 906.2 922.5 609.9 615.4 620.5 627.1 642.1 10078.4 12184.5 12208.1 12220.8 12317.2 624.9 629.8 633.7 645.3 667.5 4137.0 4577.7 4883.5 4915.9 5053.5 99211.0 99452.0 99793.0 100252.0 102386.5 59427.7 91008.5 92037.0 92906.0 93299.1 73699.0 78696.0 83257.6 83517.6 85535.5 8069.4 8580.8 8684.8 9547.4 9627.2 39637.4 39691.4 40117.6 40252.8 40273.8 33643.7 51262.5 51689.0 51845.7 52656.7 13903.0 13933.2 14048.4 14092.4 14298.3 15882.2 15896.0 15926.5 15935.7 16074.9 14523.5 14584.1 14868.0 14911.9 15019.0 14413.4 14688.9 14813.5 14885.2 14966.0 14162.5 14479.0 14704.9 14716.2 14734.4 9064.4 9598.0 9803.0 9953.2 10014.4 10509.8 10624.5 10681.5 10821.9 10878.7 14:56:32 INFO - PID 1659 | -------- Summary: end -------- 14:56:32 INFO - PID 1659 | 14:56:33 INFO - TEST-INFO | 1659: exit 0 14:56:33 INFO - TEST-OK | dromaeo_css | took 1113512ms 14:56:33 INFO - TEST-START | kraken 14:56:33 INFO - operating with platform_type : mac_ 14:56:33 INFO - Initialising browser for kraken test... 14:56:33 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpDGTRyB/profile http://localhost:49229/getInfo.html) 14:56:33 INFO - PID 1673 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2939: String.localeCompare is deprecated; use String.prototype.localeCompare instead 14:56:35 INFO - PID 1673 | 2017-05-17 14:56:35.721 plugin-container[1674:10555] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 14:56:35 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:35 INFO - PID 1673 | 2017-05-17 14:56:35.724 plugin-container[1674:10555] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 14:56:35 INFO - PID 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:35 INFO - PID 1673 | 2017-05-17 14:56:35.724 plugin-container[1674:10555] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:56:35 INFO - PID 1673 | __metrics Screen width/height:1600/1200 14:56:35 INFO - PID 1673 | colorDepth:24 14:56:35 INFO - PID 1673 | Browser inner width/height: 1024/691 14:56:35 INFO - PID 1673 | __metrics 14:56:36 INFO - TEST-INFO | 1673: exit 0 14:56:36 INFO - Browser initialized. 14:56:36 INFO - Running cycle 1/1 for kraken test... 14:56:36 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/T/tmpDGTRyB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:56:38 INFO - PID 1676 | 2017-05-17 14:56:38.540 plugin-container[1677:10704] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 14:56:38 INFO - PID 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:38 INFO - PID 1676 | 2017-05-17 14:56:38.541 plugin-container[1677:10704] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 14:56:38 INFO - PID 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:38 INFO - PID 1676 | 2017-05-17 14:56:38.542 plugin-container[1677:10704] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:56:39 INFO - PID 1676 | 2017-05-17 14:56:39.283 plugin-container[1678:10752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 14:56:39 INFO - PID 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:39 INFO - PID 1676 | 2017-05-17 14:56:39.283 plugin-container[1678:10752] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 14:56:39 INFO - PID 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:56:39 INFO - PID 1676 | 2017-05-17 14:56:39.283 plugin-container[1678:10752] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:56:48 INFO - PID 1676 | RSS: Main: 238628864 14:56:48 INFO - PID 1676 | 14:56:51 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 14:56:52 INFO - PID 1676 | RSS: Main: 250933248 14:56:52 INFO - PID 1676 | 14:56:54 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 14:56:55 INFO - PID 1676 | RSS: Main: 259473408 14:56:55 INFO - PID 1676 | 14:56:58 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 14:56:59 INFO - PID 1676 | RSS: Main: 261173248 14:56:59 INFO - PID 1676 | 14:57:01 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 14:57:01 INFO - PID 1676 | RSS: Main: 263380992 14:57:01 INFO - PID 1676 | 14:57:03 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:57:04 INFO - PID 1676 | RSS: Main: 263852032 14:57:04 INFO - PID 1676 | 14:57:07 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 14:57:07 INFO - PID 1676 | RSS: Main: 269762560 14:57:07 INFO - PID 1676 | 14:57:10 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 14:57:11 INFO - PID 1676 | RSS: Main: 269189120 14:57:11 INFO - PID 1676 | 14:57:14 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 14:57:14 INFO - PID 1676 | RSS: Main: 268562432 14:57:14 INFO - PID 1676 | 14:57:16 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:57:17 INFO - PID 1676 | RSS: Main: 268505088 14:57:17 INFO - PID 1676 | 14:57:18 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:57:19 INFO - PID 1676 | RSS: Main: 268271616 14:57:19 INFO - PID 1676 | 14:57:21 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:57:22 INFO - PID 1676 | RSS: Main: 268369920 14:57:22 INFO - PID 1676 | 14:57:25 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:57:26 INFO - PID 1676 | RSS: Main: 268148736 14:57:26 INFO - PID 1676 | 14:57:28 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:57:29 INFO - PID 1676 | RSS: Main: 268349440 14:57:29 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 14:57:31 INFO - PID 1676 | RSS: Main: 270737408 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | __start_tp_report 14:57:31 INFO - PID 1676 | _x_x_mozilla_page_load 14:57:31 INFO - PID 1676 | _x_x_mozilla_page_load_details 14:57:31 INFO - PID 1676 | |i|pagename|runs| 14:57:31 INFO - PID 1676 | |0;ai-astar;105;111;110;114;114;117;114;115;115;114 14:57:31 INFO - PID 1676 | |1;audio-beat-detection;135;109;115;175;111;110;108;110;133;124 14:57:31 INFO - PID 1676 | |2;audio-dft;200;203;199;202;198;204;217;206;199;201 14:57:31 INFO - PID 1676 | |3;audio-fft;84;56;62;79;68;85;89;84;78;72 14:57:31 INFO - PID 1676 | |4;audio-oscillator;88;74;85;72;70;71;88;80;104;116 14:57:31 INFO - PID 1676 | |5;imaging-gaussian-blur;94;89;89;88;90;97;89;89;89;89 14:57:31 INFO - PID 1676 | |6;imaging-darkroom;96;97;97;97;96;96;97;121;103;98 14:57:31 INFO - PID 1676 | |7;imaging-desaturate;92;96;93;92;88;91;92;93;92;90 14:57:31 INFO - PID 1676 | |8;json-parse-financial;48;49;49;49;50;50;51;51;52;52 14:57:31 INFO - PID 1676 | |9;json-stringify-tinderbox;59;68;77;81;71;60;59;66;56;58 14:57:31 INFO - PID 1676 | |10;stanford-crypto-aes;84;82;95;90;84;82;83;85;84;83 14:57:31 INFO - PID 1676 | |11;stanford-crypto-ccm;138;142;146;146;140;153;145;138;213;149 14:57:31 INFO - PID 1676 | |12;stanford-crypto-pbkdf2;186;215;193;187;190;186;181;181;184;177 14:57:31 INFO - PID 1676 | |13;stanford-crypto-sha256-iterative;70;70;77;87;93;88;78;72;69;71 14:57:31 INFO - PID 1676 | __end_tp_report 14:57:31 INFO - PID 1676 | __start_cc_report 14:57:31 INFO - PID 1676 | _x_x_mozilla_cycle_collect,951 14:57:31 INFO - PID 1676 | __end_cc_report 14:57:31 INFO - PID 1676 | __startTimestamp1495058251673__endTimestamp 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | ------- Summary: start ------- 14:57:31 INFO - PID 1676 | Number of tests: 14 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:3.41 (3.0%) stddev-sans-first:2.11 14:57:31 INFO - PID 1676 | Values: 105.0 111.0 110.0 114.0 114.0 117.0 114.0 115.0 115.0 114.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:119.50 stddev:20.86 (17.5%) stddev-sans-first:21.67 14:57:31 INFO - PID 1676 | Values: 135.0 109.0 115.0 175.0 111.0 110.0 108.0 110.0 133.0 124.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#2] audio-dft Cycles:10 Average:202.90 Median:202.50 stddev:5.55 (2.7%) stddev-sans-first:5.78 14:57:31 INFO - PID 1676 | Values: 200.0 203.0 199.0 202.0 198.0 204.0 217.0 206.0 199.0 201.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#3] audio-fft Cycles:10 Average:75.70 Median:81.50 stddev:10.88 (13.4%) stddev-sans-first:11.12 14:57:31 INFO - PID 1676 | Values: 84.0 56.0 62.0 79.0 68.0 85.0 89.0 84.0 78.0 72.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:77.00 stddev:15.19 (19.7%) stddev-sans-first:16.06 14:57:31 INFO - PID 1676 | Values: 88.0 74.0 85.0 72.0 70.0 71.0 88.0 80.0 104.0 116.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:89.00 stddev:2.87 (3.2%) stddev-sans-first:2.71 14:57:31 INFO - PID 1676 | Values: 94.0 89.0 89.0 88.0 90.0 97.0 89.0 89.0 89.0 89.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#6] imaging-darkroom Cycles:10 Average:99.80 Median:97.00 stddev:7.73 (8.0%) stddev-sans-first:8.07 14:57:31 INFO - PID 1676 | Values: 96.0 97.0 97.0 97.0 96.0 96.0 97.0 121.0 103.0 98.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:92.00 stddev:2.08 (2.3%) stddev-sans-first:2.20 14:57:31 INFO - PID 1676 | Values: 92.0 96.0 93.0 92.0 88.0 91.0 92.0 93.0 92.0 90.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.50 stddev:1.37 (2.7%) stddev-sans-first:1.22 14:57:31 INFO - PID 1676 | Values: 48.0 49.0 49.0 49.0 50.0 50.0 51.0 51.0 52.0 52.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:67.00 stddev:8.63 (12.9%) stddev-sans-first:8.83 14:57:31 INFO - PID 1676 | Values: 59.0 68.0 77.0 81.0 71.0 60.0 59.0 66.0 56.0 58.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:84.00 stddev:4.13 (4.9%) stddev-sans-first:4.36 14:57:31 INFO - PID 1676 | Values: 84.0 82.0 95.0 90.0 84.0 82.0 83.0 85.0 84.0 83.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:151.00 Median:146.00 stddev:22.31 (15.3%) stddev-sans-first:23.16 14:57:31 INFO - PID 1676 | Values: 138.0 142.0 146.0 146.0 140.0 153.0 145.0 138.0 213.0 149.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.00 Median:186.50 stddev:10.55 (5.7%) stddev-sans-first:11.17 14:57:31 INFO - PID 1676 | Values: 186.0 215.0 193.0 187.0 190.0 186.0 181.0 181.0 184.0 177.0 14:57:31 INFO - PID 1676 | 14:57:31 INFO - PID 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.50 Median:77.50 stddev:8.81 (11.4%) stddev-sans-first:8.92 14:57:31 INFO - PID 1676 | Values: 70.0 70.0 77.0 87.0 93.0 88.0 78.0 72.0 69.0 71.0 14:57:31 INFO - PID 1676 | -------- Summary: end -------- 14:57:31 INFO - PID 1676 | 14:57:32 INFO - TEST-INFO | 1676: exit 0 14:57:32 INFO - TEST-OK | kraken | took 59255ms 14:57:32 INFO - SUITE-END | took 1172s 14:57:32 INFO - Completed test suite (00:19:33) 14:57:32 INFO - javascript benchmark 14:57:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7834.03656101186, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3175.872965081397, 3636.054625797292, 3666.1384574624976, 3678.8896333110015, 3687.9489345536526, 6151.861905547476, 6303.527235457336, 6307.306196318401, 6351.7459301628, 6352.047192921064, 6232.096346029823, 6251.062340648905, 6278.874422511547, 6306.590071645352, 6350.555461117724, 5522.30971128609, 5579.358373787026, 5590.468905453975, 5590.776368945238, 5677.630953987978, 5653.802116925908, 5732.914006289909, 5783.074708046714, 5795.913061304083, 5805.73874175661, 5503.036968530531, 5658.236138121342, 5660.29246344207, 5742.310922689283, 5776.335721392052, 5508.55931525477, 5654.717264136777, 5658.321001479808, 5689.516391106761, 5728.341240757283, 5578.748956296977, 5579.414161513064, 5628.549716022709, 5635.126555383922, 5673.262475878109, 5523.28197334348, 5861.4724674779045, 6351.968240158816, 6371.362863713638, 6378.107065010902, 5500.0, 6662.966685166543, 6738.989151627246, 6796.08252885859, 6892.414144797698, 7003.019577259188, 7014.123234595676, 7019.298070192991, 7025.964870175668, 7039.577625342496, 6486.156799616067, 6489.059086432441, 6497.545171837926, 6497.740090396425, 6507.967460162764, 6908.930910690829, 6998.825029374205, 7040.894386584206, 7094.219635840053, 7185.065941427581, 5762.481376676035, 6832.829179270558, 6837.3162683731225, 6840.521163518506, 6849.965750171317, 6838.21360543534, 6988.0, 6992.755253566203, 7001.229864714877, 7027.402670772939, 6543.869122617521, 6704.0, 6776.7628133015105, 6850.143732033496, 6888.035675005503, 6727.596344219362, 6865.0, 6893.724251030003, 6897.068895699016, 6904.067950826675, 5422.566194704415, 5435.0, 5436.293281873332, 5439.700816455053, 5568.6101972862325, 4809.639277054235, 4829.589484893823, 4895.290182923456, 4910.091633047898, 4986.077575648516, 6390.808275751735, 6408.807735767934, 6446.129772480749, 6453.386928241809, 6464.159659244268, 6888.931110688929, 6987.6855541499735, 6993.125859267591, 7008.229094799568, 7035.613041282779, 6300.27546832111, 6382.266039405527, 6455.096286519891, 6457.773977910751, 6467.902981455282, 6061.181740465069, 6111.786087486917, 6116.113163591254, 6120.660465672121, 6129.14485165794, 7130.215676275502, 7135.643217839087, 7152.892706609405, 7154.713811447484, 7156.570605763671, 6786.626735529396, 6888.896666549906, 7004.579725216503, 7005.859882802417, 7028.226895041439, 5983.521318294439, 6981.5112942093565, 7064.858702826017, 7122.537035092702, 7183.497155199086, 6838.897416538855, 6851.760188393382, 6874.9312506876295, 6876.346747059021, 6897.379235868796, 6241.375862413904, 6785.7553073016525, 6826.590404575742, 6941.687624057031, 6994.895076573754, 6394.488440924808, 6501.959686450145, 6608.7356505739235, 6728.562643428161, 6749.73001079971, 6690.230623478362, 6714.798556043326, 6718.260991291052, 6727.293634168537, 6740.955151951455, 5658.377578466448, 6384.489240860813, 6388.201474815648, 6466.353364663496, 6630.602163870183, 5573.442655734394, 6685.63229022389, 6778.288279730557, 6794.592324460548, 6801.965990170018, 5542.8914217155925, 6161.260648722182, 6494.090827284279, 6581.0, 6601.768938087144], "value": 6255.931182217387, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.3003386714885, 772.9188435214303, 794.5193158139325, 1124.9043831274341, 1208.9758204835898, 37274.254514909684, 37580.624193758056, 37814.0, 37849.81075094624, 37906.0, 32386.838065809698, 37445.63424217883, 37893.810530947376, 37922.672706455276, 38064.048398790044, 7658.5021973571675, 7776.844463110734, 8012.198780121999, 8238.629261683238, 8263.793405164859, 8194.959025204866, 8269.669213231464, 8274.33805295578, 8307.335413166962, 8316.37627177961, 7863.741801311791, 8223.876641850376, 8242.670293188265, 8312.251897329239, 8314.958425207866, 7644.579548124851, 8250.38122140842, 8268.421210515236, 8316.584170791497, 8338.541380224086, 8089.514629122272, 8131.959340203321, 8140.47086939353, 8145.470544414653, 8152.062512810984, 35887.64112358869, 35916.64083359159, 36023.819880900424, 36080.09799755001, 36149.45775813365, 3543.6810687038032, 3556.199855032623, 3557.786532808014, 3590.030691713249, 3594.263176048904, 29826.701732982827, 29909.551356729666, 29980.8500957496, 29985.250368740737, 30075.496225188872, 3085.444619968427, 3103.379324135182, 3115.4080724662467, 3115.4859448191023, 3120.984395078033, 253023.0, 253377.73311133328, 290351.9999999979, 313324.0, 315416.0, 46721.76639116851, 46855.76572117118, 47133.76433117881, 47158.76420617875, 47200.29199562009, 40300.0, 48467.75766121147, 48581.5141848577, 48625.51374486281, 48763.0, 13863.306834658226, 13898.027138100237, 13982.300884955712, 13984.580462586138, 13995.930020349833, 11184.664460066211, 11353.432328383547, 11367.147463940237, 11382.65852024424, 11386.601468948713, 13037.478500860048, 13119.934400328128, 13142.474301027853, 13215.471381144647, 13286.468541258435, 5908.202392676933, 5917.201177840936, 5929.881402371929, 5944.6730429826775, 5951.4048595141, 959.3620242538634, 968.4818622037176, 972.5866506734609, 987.2891518106951, 988.649029594498, 19443.838171392086, 20554.38336849897, 24023.0, 24482.387940301636, 24545.859967296255, 1000.4997501249376, 1120.8206686930052, 1122.7417693930443, 1145.9255148415327, 1156.2311063143084, 12061.638150855488, 12278.079144064051, 12321.336619556425, 12357.32034738098, 12382.938085309515, 321795.0, 323237.0, 323486.0, 323623.0, 324135.3793231066, 51464.22803657948, 51558.45324640267, 51579.0, 51581.0, 51680.74159629178, 1502.1963249661596, 1545.1115608525142, 1549.0241148076639, 1553.4485257733568, 1557.704036233112, 19886.403407897786, 21688.457788555414, 21833.908304585024, 23697.763022369556, 24194.637080443455, 36436.635633644386, 36610.901672949854, 36634.0, 36721.816390918946, 36727.0, 234699.0, 279673.0, 280075.5996220006, 280146.0, 281014.0, 25512.48975020452, 28064.579031314956, 28308.71691283061, 32023.359532808747, 32128.19679508031, 39737.0, 40934.79532602318, 40996.0, 41794.0, 41992.160156797305, 23198.0, 27347.179584612495, 29754.464063913012, 30069.3986120272, 30483.475826209044, 28488.85755571209, 29332.560011599417, 29372.853135734185, 29558.704412955594, 29741.85129074341, 33500.32999340048, 33637.66362336443, 34608.134796630286, 34753.65246347504, 34960.85352318304, 28826.567601486415, 28894.42211155723, 29763.851180743957, 30065.699343007163, 30816.383672326876, 20147.093380797607, 22111.889440552695, 22901.0, 23363.761112105403, 23505.529889401772], "value": 16915.52951182983, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.360301898848, 429.31738535728186, 434.3029437752409, 439.5582439648153, 458.97475638839853, 19034.61930761384, 20582.48543786406, 21105.788942110594, 21130.471738206554, 21389.786102139013, 19489.902550487226, 20906.372808815722, 22071.44821379466, 22528.620706893973, 22702.0, 5939.970300148493, 6180.567360284782, 6201.348858369874, 6204.968975155118, 6217.129601855721, 5279.4984476474865, 6067.666278354689, 6081.148639190517, 6088.9391106088815, 6159.630422174684, 6077.969610151943, 6084.117802918574, 6117.204763380754, 6120.96939515304, 6124.387561243862, 5853.0, 6034.06471996839, 6092.268927728701, 6097.176881121059, 6107.541934354941, 5294.602904782157, 5314.256004159421, 5402.6758394496455, 5429.9411614735145, 5448.237246785453, 27893.58159627607, 27901.581476277872, 27977.30056748577, 28522.0, 28726.712732872613, 2224.1881713174575, 2289.862608243494, 2308.549832782608, 2309.549637820626, 2311.9653205201935, 17558.858674186264, 18146.00196989165, 18480.168392422373, 18498.07509624527, 18598.349057782914, 2110.504031552584, 2131.82945364372, 2170.869747815136, 2185.1041073159927, 2186.5080356919725, 122146.0, 125256.0, 152487.9999999989, 154258.0, 155333.0, 12457.377131143407, 12860.742785144244, 12886.226826390446, 12953.028522860823, 13011.21932684042, 16510.587235318973, 16527.91736041336, 16545.924514906492, 16564.337426503047, 16581.91709041471, 8598.613062412207, 8813.074627164055, 8865.68970086044, 8920.687775927941, 9012.41419307743, 8223.794405139919, 8227.794305142299, 8282.8343433131, 8378.0, 8393.328533717287, 7167.390771784352, 7204.387627051655, 7557.5465472071855, 7663.118741344705, 7961.482503637245, 2177.335912546657, 2193.5503221839485, 2193.6270833958265, 2198.5383069555573, 2379.060271192869, 915.1763412928418, 920.6501529418912, 941.5951141009432, 958.0802429667597, 986.5412583148727, 21126.0, 21525.06053208587, 21616.783832161793, 21635.89182054111, 21885.67171492429, 954.5275088830934, 1016.2733645443543, 1016.6086056868158, 1051.8842927277992, 1052.4001319247955, 10739.194560408001, 10928.836067458995, 10985.39580323074, 11014.669559913216, 11081.778364432666, 522662.0, 525474.0, 528390.0, 532054.0, 538794.0, 103671.0, 108797.0, 125209.0, 131046.0, 136330.0, 1217.945192466341, 1258.3267951646008, 1268.5623459906317, 1289.2586762611536, 1293.3662505372488, 16267.918660406622, 22034.669479957494, 22921.88539057294, 23034.308970730905, 23049.539009219385, 43146.92132696708, 45101.87245318893, 46541.76729116333, 54790.726046371106, 55417.1687424697, 57864.421355787585, 64901.675491624126, 66890.99327020197, 75745.0, 76357.61821190859, 9003.623641134574, 9020.594073266588, 9068.047854975397, 9075.682351117679, 9323.813523729623, 11664.94167529157, 13633.931830340785, 14502.637434064232, 14668.119912805267, 14826.925865371035, 12005.279683219034, 12133.0, 12197.451114700038, 12214.877851221374, 12344.567940122053, 11270.774584508099, 11397.202195846212, 11521.423928803693, 11725.848055919834, 11863.288202707865, 12084.45619947122, 12191.451384687532, 12515.249085054926, 12528.498860045496, 12584.811227831407, 7910.762677119696, 8718.997315308821, 8990.370674052754, 9050.592723327334, 9060.501672407818, 11857.288562686266, 12017.278963262232, 12052.939735301617, 12089.75820483603, 12240.632781016584], "value": 10393.03045293888, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.9069260498068, 1484.213366915535, 1503.068097779377, 1504.8194216693992, 1508.894377393582, 11520.135989800767, 12039.277643341395, 12077.335746533938, 12129.636110916665, 12442.19125756825, 12004.939975300133, 12216.328101954388, 12235.755284894296, 12261.509539618426, 12261.938690306559, 2590.67616547932, 2647.7087520372743, 2650.6554147960833, 2652.1247988163955, 2658.986705066472, 2573.6525569048126, 2601.778848797845, 2605.3226161198036, 2611.542979978496, 2620.2925210193234, 2527.35552434129, 2530.165045534978, 2559.782418494421, 2574.1466144376113, 2574.5108429398447, 2376.857388556692, 2394.030417680842, 2410.6745589345387, 2414.215380001489, 2422.6002709552904, 2505.8747062646794, 2508.1221572449494, 2518.156417600106, 2518.609615509599, 2526.2926380613458, 11807.704807379798, 11818.054555635528, 11864.406779660982, 11919.165658403916, 11947.462364193632, 6639.73441062357, 6893.034975103489, 6895.931040689579, 6902.033715279815, 6951.16586009677, 3488.5116083748294, 3965.504311961005, 3969.702272329587, 4400.889977750582, 4486.685931984763, 1236.4930378544755, 1254.956076537326, 1300.5123078845434, 1302.4594793160827, 1304.30871638032, 16363.754543681855, 16453.42413015539, 16503.752443713354, 16641.583960400967, 17022.063786491737, 5593.664380137124, 5707.1153971134545, 5743.511801496919, 5921.200637913913, 5948.791792287249, 11710.531578736754, 11808.822867656992, 11825.113116516295, 11825.940870295766, 11837.0, 6564.376384243489, 6571.605703657796, 6579.0, 6581.934180658228, 6659.101021362151, 6053.909191362133, 6195.473384762346, 6255.093011513305, 6314.652694101776, 6314.810555683337, 3921.431392448079, 4244.342126970325, 4391.187630288407, 4418.513963464016, 4438.334249862482, 1199.5861427807392, 1227.1226073357413, 1305.9673508162182, 1310.2138716769862, 1310.4692599497218, 697.3235961117708, 809.4536188073145, 835.3651225068992, 840.1892174052069, 851.8637018077078, 6279.529035322369, 6301.842453938688, 6519.771807986698, 6552.3447655234095, 6589.505787065893, 591.2343515147894, 595.2499850188793, 598.7724664627501, 600.6396162302583, 606.333033662976, 3390.830458477116, 3896.201278737852, 3933.3116704576582, 4496.955030449719, 4522.186006518858, 14169.504067357593, 14895.77656335156, 14913.850861491463, 15141.924290378478, 15356.846431535765, 14456.855431445761, 15150.621234469227, 15236.923815380853, 15272.160031198391, 15288.847111528965, 8540.316774658013, 8643.481391116427, 8695.73912782605, 8769.956150219336, 8784.868226976601, 6230.470410015199, 6565.441937435371, 6761.154855643044, 6874.621895795779, 6893.620850853251, 12716.427760750603, 13198.340082995619, 13435.664108397368, 13605.251711155981, 13693.041487095808, 13003.869961300645, 13946.0, 14022.22877741734, 14081.507147249797, 14261.358238879482, 8318.625661845352, 8333.791655208668, 8364.205400486944, 8390.706325278587, 8429.915700842914, 10829.458527073772, 10874.891251087387, 10946.726331841768, 10950.39772812503, 10971.945140274247, 8423.70517031901, 8977.281817454457, 9189.0, 9229.400088994194, 9305.906940930505, 9063.90936090657, 9152.725418237464, 9160.084136477184, 9255.398399104037, 9278.860817087614, 6628.76799312041, 7140.7143714252925, 8124.025116985763, 8241.423100382915, 8382.790430239293, 6036.849078773066, 6052.061930400795, 6077.969610151921, 6258.843528911813, 6271.78048768291, 5876.471117599265, 6005.549583781147, 6124.693765311627, 6225.190725205694, 6285.59143655661], "value": 5348.589040069723, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.30837147320557, 399.8960270329714, 410.98561550345727, 411.14276733011667, 414.91286829765755, 6090.23872015998, 6274.811755647326, 6388.904166437502, 6428.2928877823515, 6437.420632143107, 6113.480354169889, 6269.811905642827, 6271.498280137589, 6277.905831412532, 6305.833420817141, 3616.7287453440968, 3678.411454167327, 4536.183486972343, 4538.478075021365, 4559.475660299058, 4045.2111838191563, 4400.889977750566, 4520.163769702599, 4559.566841150086, 4566.1780879441685, 3594.388953877847, 3853.5761066282685, 4268.765217913014, 4293.184294983943, 4485.573870482299, 3574.303010912873, 4481.215787237253, 4498.975461042512, 4499.380223119674, 4528.9094218115615, 3492.720582353431, 3781.2626537825136, 3864.439656249828, 3870.7096967727534, 3877.9224415511812, 4941.728204948727, 5896.292444906639, 5991.850203744898, 6113.877722445526, 6232.719527621268, 4732.479427262998, 4934.309196712463, 5392.352917649907, 5434.130539113723, 5437.864053398657, 5480.6437581557075, 5564.304461942257, 6480.287168411471, 6715.093462382565, 6723.226828914689, 3805.847766089353, 4277.422547956048, 4376.824927002916, 4387.9561204387865, 4392.846250381253, 5796.217510636052, 6962.7911162665205, 7101.07060604396, 7190.209077001578, 7203.495755297184, 4324.632406245504, 5007.198848184273, 5046.066477701637, 5071.2139618359215, 5094.77073531692, 4355.956440435555, 4369.497507786646, 4880.902381952341, 4940.6788558743565, 5180.585553155738, 4352.347147927836, 5559.471850174227, 5586.190002449622, 5713.600047996601, 5791.710414479259, 6691.799246022627, 6827.761028363983, 6827.965860170767, 6828.146481689789, 6835.906254999176, 1716.742488626691, 1753.1059159828324, 1754.8683848711398, 1776.1740790532465, 1779.661864245789, 1045.5190612318268, 1185.903039688285, 1204.6325870609548, 1224.2776761710634, 1244.172625204229, 6244.875102497925, 6333.651649159248, 6346.01636746305, 6350.904736428957, 6354.4281014708895, 6072.635641861414, 6366.586171898812, 6842.349976752201, 6976.267491913276, 6999.930000700029, 5423.674579525241, 5693.117566777156, 6456.289808121197, 6479.5788273762055, 6483.481321494269, 3321.103302108417, 3589.892303230907, 3624.7281453891064, 3653.94519082214, 3656.2870240302886, 6262.903991801417, 6386.393292637192, 6944.479164062715, 7029.578225306498, 7239.819004524824, 6629.403353698191, 7189.352958233784, 7221.566705997657, 7250.3112204340505, 7264.604914027043, 5703.828885133453, 6646.567973081735, 7038.964805175941, 7114.359707626339, 7228.241034691387, 6208.658523781235, 6422.0, 7087.03126249302, 7118.52214484837, 7124.821879453055, 5849.415058494116, 6795.7088153250725, 6904.896426553706, 6940.41006514452, 6954.79135625932, 6839.487038472035, 7221.028434361186, 7240.565566066053, 7273.81785464348, 7283.490155689051, 3816.3512202926127, 4432.938683512182, 4439.089986552684, 4462.709923854939, 4498.865034048984, 7063.223045464892, 7066.222715501393, 7089.503734738519, 7104.786856394317, 7116.181639111362, 5074.441811400817, 6834.419074378733, 6853.623050732258, 6919.584824910521, 6928.549644273106, 6701.631410272482, 6732.821756192745, 6787.457003439812, 6788.558743681645, 6803.829904252433, 5341.732913354239, 5382.650127741685, 6672.132622759011, 6679.098321726505, 6730.038782935493, 6740.494462915203, 6855.189372357309, 6871.278515755774, 6873.243943166148, 6873.635241621506, 6544.9435402693225, 6637.946544589543, 6656.73373065072, 6692.732290708317, 6733.764318249034], "value": 4900.688480241726, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.3364091906013, 836.8033512124649, 860.410618726173, 862.284304027657, 863.2705363967445, 16609.501714948557, 16963.067031313272, 17074.317027318906, 18497.90751046246, 19134.23463061476, 17993.010384428882, 18358.173882175302, 18480.353187638437, 18586.070696465158, 18765.343212987547, 994.890562038177, 997.5660587644362, 1014.7919676466307, 1017.6590842067916, 1018.3228153278098, 1010.0000999010999, 1011.9797604047915, 1012.0385633648027, 1013.8986101389839, 1016.369850692568, 1053.420618659738, 1074.9247552671316, 1075.67192006438, 1078.0190027075364, 1080.983785243222, 1058.2856571814034, 1063.9308444951052, 1067.0, 1075.9623413180502, 1079.2768844873954, 763.1300317637897, 772.4824367673672, 772.6059709548115, 781.0861292288037, 781.1368437876122, 17046.318147274094, 17235.827641723547, 17564.473065808048, 17566.91216543909, 17568.736468952975, 15753.448629298035, 16712.830101892872, 17059.744103838453, 17134.228959696844, 17555.73666395005, 14091.0, 14235.359408826627, 14308.427662893471, 14473.565793026226, 14485.493007744784, 3553.6979356754705, 3611.133328001261, 3617.3127105850067, 3618.222082252328, 3619.49327094207, 119889.80110198873, 135365.32317338447, 136019.0, 136355.0, 137317.0, 13507.459701612022, 13712.3829427676, 13726.45094196241, 13815.309234538434, 13888.513902013381, 21481.67777483339, 24591.508169836503, 25295.988160473375, 25786.87106564455, 26302.47395052088, 7664.463487555818, 7732.381409487228, 7766.611669416507, 7776.7278145264645, 7838.372930165573, 3704.2776658551325, 3763.5107436033686, 3774.094217387807, 3776.0371105367954, 3778.6221377861993, 3885.9896426929204, 3929.7838618876235, 3990.1620659661794, 4003.259397011562, 4013.9197216055513, 890.2521881619341, 895.5746020640274, 905.3979103895967, 906.1753804038293, 922.4880191493651, 609.938706650425, 615.3692465223182, 620.500497099839, 627.1345543150424, 642.1042645509488, 10078.395296282246, 12184.451699673536, 12208.145429820004, 12220.816687749691, 12317.199382040139, 624.9375062493714, 629.789020678078, 633.7465013994439, 645.3353046362255, 667.5160508631203, 4137.0, 4577.718238893115, 4883.4628190898975, 4915.90168196634, 5053.519915608012, 99211.0, 99452.0, 99793.0, 100252.0, 102386.48806755918, 59427.70286148541, 91008.54495727479, 92037.0, 92906.0, 93299.06700933039, 73699.0, 78696.0, 83257.58371208105, 83517.58241208877, 85535.45271388408, 8069.394795390252, 8580.828383432172, 8684.78288042804, 9547.379420337656, 9627.229821614394, 39637.405438917864, 39691.40462893001, 40117.59882401255, 40252.79873600613, 40273.798631006655, 33643.663563364054, 51262.48737512577, 51689.0, 51845.74077129741, 52656.736716316176, 13903.0, 13933.23367214813, 14048.438062477795, 14092.436302548193, 14298.285085745463, 15882.2058897057, 15896.0, 15926.522204333889, 15935.681286373976, 16074.919625401799, 14523.491677791226, 14584.124952502883, 14867.95695486616, 14911.85088149148, 15019.0, 14413.42346306178, 14688.853111468748, 14813.481528146463, 14885.181315027345, 14966.02720823143, 14162.504312349018, 14479.0, 14704.852951470777, 14716.19060951658, 14734.410623574937, 9064.410813297114, 9597.952010239904, 9802.950985245028, 9953.203743700342, 10014.39913605186, 10509.842352364567, 10624.468776560987, 10681.465926703479, 10821.9458902705, 10878.728031799268], "value": 8018.0588932295295, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1498.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 111.0, 110.0, 114.0, 114.0, 117.0, 114.0, 115.0, 115.0, 114.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 109.0, 115.0, 175.0, 111.0, 110.0, 108.0, 110.0, 133.0, 124.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 203.0, 199.0, 202.0, 198.0, 204.0, 217.0, 206.0, 199.0, 201.0], "value": 202.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 56.0, 62.0, 79.0, 68.0, 85.0, 89.0, 84.0, 78.0, 72.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 74.0, 85.0, 72.0, 70.0, 71.0, 88.0, 80.0, 104.0, 116.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 89.0, 89.0, 88.0, 90.0, 97.0, 89.0, 89.0, 89.0, 89.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 97.0, 96.0, 96.0, 97.0, 121.0, 103.0, 98.0], "value": 99.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 93.0, 92.0, 88.0, 91.0, 92.0, 93.0, 92.0, 90.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 49.0, 49.0, 49.0, 50.0, 50.0, 51.0, 51.0, 52.0, 52.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 68.0, 77.0, 81.0, 71.0, 60.0, 59.0, 66.0, 56.0, 58.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 95.0, 90.0, 84.0, 82.0, 83.0, 85.0, 84.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 146.0, 146.0, 140.0, 153.0, 145.0, 138.0, 213.0, 149.0], "value": 151.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 215.0, 193.0, 187.0, 190.0, 186.0, 181.0, 181.0, 184.0, 177.0], "value": 188.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 77.0, 87.0, 93.0, 88.0, 78.0, 72.0, 69.0, 71.0], "value": 77.5, "unit": "score"}]}]} 14:57:32 INFO - Return code: 0 14:57:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:57:32 INFO - # TBPL SUCCESS # 14:57:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:57:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:57:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:57:33 INFO - 2017-05-17 14:57:33.007 screenresolution[1683:11147] starting screenresolution argv=screenresolution get 14:57:33 INFO - 2017-05-17 14:57:33.009 screenresolution[1683:11147] Display 0: 1600x1200x32@60 14:57:33 INFO - 2017-05-17 14:57:33.015 screenresolution[1684:11150] starting screenresolution argv=screenresolution list 14:57:33 INFO - Available Modes on Display 0 14:57:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:57:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:57:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:57:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:57:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:57:33 INFO - Intel Iris: 14:57:33 INFO - Chipset Model: Intel Iris 14:57:33 INFO - Type: GPU 14:57:33 INFO - Bus: Built-In 14:57:33 INFO - VRAM (Dynamic, Max): 1536 MB 14:57:33 INFO - Vendor: Intel (0x8086) 14:57:33 INFO - Device ID: 0x0a2e 14:57:33 INFO - Revision ID: 0x0009 14:57:33 INFO - Displays: 14:57:33 INFO - Display: 14:57:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:57:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:57:33 INFO - Display Serial Number: 1600x1200 60 14:57:33 INFO - Main Display: Yes 14:57:33 INFO - Mirror: Off 14:57:33 INFO - Online: Yes 14:57:33 INFO - Rotation: Supported 14:57:33 INFO - Return code: 0 14:57:33 INFO - Running post-action listener: _resource_record_post_action 14:57:33 INFO - [mozharness: 2017-05-17 21:57:33.250455Z] Finished run-tests step (success) 14:57:33 INFO - Running post-run listener: _resource_record_post_run 14:57:33 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 53776384; Write bytes: 541884416; Read time: 586; Write time: 1862 14:57:33 INFO - TinderboxPrint: CPU usage
54.3% 14:57:33 INFO - TinderboxPrint: I/O read bytes / time
53,776,384 / 586 14:57:33 INFO - TinderboxPrint: I/O write bytes / time
541,884,416 / 1,862 14:57:33 INFO - TinderboxPrint: CPU idle
2,185.2 (45.7%) 14:57:33 INFO - TinderboxPrint: CPU system
958.4 (20.1%) 14:57:33 INFO - TinderboxPrint: CPU user
1,633.9 (34.2%) 14:57:33 INFO - TinderboxPrint: Swap in / out
259,313,664 / 0 14:57:33 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 212617728; Write bytes: 209704448; Read time: 17020; Write time: 434 14:57:33 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 48754688; Write bytes: 321096192; Read time: 553; Write time: 1391 14:57:33 INFO - Running post-run listener: _upload_blobber_files 14:57:33 INFO - Blob upload gear active. 14:57:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:57:33 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 14:57:33 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'] 14:57:33 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 14:57:34 INFO - (blobuploader) - INFO - Open directory for files ... 14:57:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:57:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:57:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:35 INFO - (blobuploader) - INFO - Done attempting. 14:57:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:57:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:57:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:36 INFO - (blobuploader) - INFO - Done attempting. 14:57:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:57:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:57:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:37 INFO - (blobuploader) - INFO - Done attempting. 14:57:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:57:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:57:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:57:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:57:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:57:39 INFO - (blobuploader) - INFO - Done attempting. 14:57:39 INFO - (blobuploader) - INFO - Iteration through files over. 14:57:39 INFO - Return code: 0 14:57:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:57:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:57:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0462f9418f673de5fc8a5a27cfb2736ca3a4fede11a2d1911ea24c88e54670ad1eb8d504fc9c78d9942db4e9490dabf6760325e7f5d0b905cee9013fe7bd794", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52528477a37ed8d2182a3c4c97446221b508deafcb5caa8368b32cf0a16f54d48cc5af46cffe646bcb9aeec3ac8aa12a58638fc8c41415d7ad90bb7e0ceaf405", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c556fff870814d33c250c3e02d38e5665036b9d22ca238e3cc4be0543542de4601f97088f0cdbb13bb960462e2415e55281d7797ecf0e57b013fae16ed9393e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb07bf0a3aaa85e8012a9592d1c7c186d1ab5957f6cccc144ec19b681bfd98dc0ef1aa3c93fd049f1270b7762600756cbd1ac92d03ad5530bb11603d9ea5a18f"} 14:57:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:57:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:57:39 INFO - Contents: 14:57:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0462f9418f673de5fc8a5a27cfb2736ca3a4fede11a2d1911ea24c88e54670ad1eb8d504fc9c78d9942db4e9490dabf6760325e7f5d0b905cee9013fe7bd794", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52528477a37ed8d2182a3c4c97446221b508deafcb5caa8368b32cf0a16f54d48cc5af46cffe646bcb9aeec3ac8aa12a58638fc8c41415d7ad90bb7e0ceaf405", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c556fff870814d33c250c3e02d38e5665036b9d22ca238e3cc4be0543542de4601f97088f0cdbb13bb960462e2415e55281d7797ecf0e57b013fae16ed9393e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb07bf0a3aaa85e8012a9592d1c7c186d1ab5957f6cccc144ec19b681bfd98dc0ef1aa3c93fd049f1270b7762600756cbd1ac92d03ad5530bb11603d9ea5a18f"} 14:57:39 INFO - Running post-run listener: copy_logs_to_upload_dir 14:57:39 INFO - Copying logs to upload dir... 14:57:39 INFO - mkdir: /builds/slave/test/build/upload/logs 14:57:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.614108 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-17 14:57:40.018932) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 14:57:40.021969) ========= 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.vURfQURecD/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.jMT5T3145w/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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/f0462f9418f673de5fc8a5a27cfb2736ca3a4fede11a2d1911ea24c88e54670ad1eb8d504fc9c78d9942db4e9490dabf6760325e7f5d0b905cee9013fe7bd794", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52528477a37ed8d2182a3c4c97446221b508deafcb5caa8368b32cf0a16f54d48cc5af46cffe646bcb9aeec3ac8aa12a58638fc8c41415d7ad90bb7e0ceaf405", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c556fff870814d33c250c3e02d38e5665036b9d22ca238e3cc4be0543542de4601f97088f0cdbb13bb960462e2415e55281d7797ecf0e57b013fae16ed9393e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb07bf0a3aaa85e8012a9592d1c7c186d1ab5957f6cccc144ec19b681bfd98dc0ef1aa3c93fd049f1270b7762600756cbd1ac92d03ad5530bb11603d9ea5a18f"} build_url:https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/QFzh82g4SvWCDaKgp-37tA/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/f0462f9418f673de5fc8a5a27cfb2736ca3a4fede11a2d1911ea24c88e54670ad1eb8d504fc9c78d9942db4e9490dabf6760325e7f5d0b905cee9013fe7bd794", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52528477a37ed8d2182a3c4c97446221b508deafcb5caa8368b32cf0a16f54d48cc5af46cffe646bcb9aeec3ac8aa12a58638fc8c41415d7ad90bb7e0ceaf405", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c556fff870814d33c250c3e02d38e5665036b9d22ca238e3cc4be0543542de4601f97088f0cdbb13bb960462e2415e55281d7797ecf0e57b013fae16ed9393e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb07bf0a3aaa85e8012a9592d1c7c186d1ab5957f6cccc144ec19b681bfd98dc0ef1aa3c93fd049f1270b7762600756cbd1ac92d03ad5530bb11603d9ea5a18f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 14:57:40.059506) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 14:57:40.059880) ========= 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.vURfQURecD/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.jMT5T3145w/Listeners TMPDIR=/var/folders/xg/002r27vj2cl62wmqwq6r5fgr00000w/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.005068 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 14:57:40.111425) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 14:57:40.111897) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 14:57:40.112448) ========= ========= Total master_lag: 0.18 =========