builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0368 starttime: 1494364719.6 results: success (0) revision: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:39.598120) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:39.598758) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:39.599434) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:40.191434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:40.191849) ========= 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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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-09 14:18:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.80M=0.002s 2017-05-09 14:18:40 (6.80 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.161760 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:40.380270) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:40.380982) ========= 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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.021200 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:40.433536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-09 14:18:40.435347) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 --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 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0LsBIK3uTc/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.FxfvZcfF7m/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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-09 14:18:40,528 truncating revision to first 12 chars 2017-05-09 14:18:40,528 Setting DEBUG logging. 2017-05-09 14:18:40,529 attempt 1/10 2017-05-09 14:18:40,529 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4b8939ed1281?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-09 14:18:41,730 unpacking tar archive at: mozilla-central-4b8939ed1281/testing/mozharness/ program finished with exit code 0 elapsedTime=1.467233 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-09 14:18:41.926525) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:41.926902) ========= script_repo_revision: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:41.927320) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:41.927622) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-09 14:18:41.947491) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-05-09 14:18:41.947810) ========= /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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:18:42 INFO - MultiFileLogger online at 20170509 14:18:42 in /builds/slave/test 14:18:42 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:18:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:18:42 INFO - {'append_to_log': False, 14:18:42 INFO - 'base_work_dir': '/builds/slave/test', 14:18:42 INFO - 'blob_upload_branch': 'Firefox', 14:18:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:18:42 INFO - 'branch': 'Firefox', 14:18:42 INFO - 'buildbot_json_path': 'buildprops.json', 14:18:42 INFO - 'config_files': ('talos/mac_config.py',), 14:18:42 INFO - 'default_actions': ('clobber', 14:18:42 INFO - 'read-buildbot-config', 14:18:42 INFO - 'download-and-extract', 14:18:42 INFO - 'populate-webroot', 14:18:42 INFO - 'create-virtualenv', 14:18:42 INFO - 'install', 14:18:42 INFO - 'run-tests'), 14:18:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:18:42 INFO - 'download_minidump_stackwalk': True, 14:18:42 INFO - 'download_symbols': 'ondemand', 14:18:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:18:42 INFO - 'tooltool.py': '/tools/tooltool.py', 14:18:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:18:42 INFO - '/tools/misc-python/virtualenv.py')}, 14:18:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:18:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:18:42 INFO - 'gecko_profile': False, 14:18:42 INFO - 'gecko_profile_interval': 0, 14:18:42 INFO - 'installer_path': 'installer.exe', 14:18:42 INFO - 'log_level': 'info', 14:18:42 INFO - 'log_name': 'talos', 14:18:42 INFO - 'log_to_console': True, 14:18:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:18:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:18:42 INFO - 'opt_config_files': (), 14:18:42 INFO - 'pip_index': False, 14:18:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:18:42 INFO - 'cmd': ('bash', 14:18:42 INFO - '-c', 14:18:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:18:42 INFO - 'enabled': True, 14:18:42 INFO - 'halt_on_failure': False, 14:18:42 INFO - 'name': 'check_screen_resolution'},), 14:18:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:18:42 INFO - 'cmd': ('bash', 14:18:42 INFO - '-c', 14:18:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:18:42 INFO - 'enabled': True, 14:18:42 INFO - 'halt_on_failure': False, 14:18:42 INFO - 'name': 'check_screen_resolution'},), 14:18:42 INFO - 'run_cmd_checks_enabled': True, 14:18:42 INFO - 'suite': 'dromaeojs-e10s', 14:18:42 INFO - 'system_bits': '32', 14:18:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:18:42 INFO - 'title': 't-yosemite-r7-0368', 14:18:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:18:42 INFO - 'use_talos_json': True, 14:18:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:18:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:18:42 INFO - 'work_dir': 'build'} 14:18:42 INFO - [mozharness: 2017-05-09 21:18:42.161678Z] Running clobber step. 14:18:42 INFO - Running pre-action listener: _resource_record_pre_action 14:18:42 INFO - Running main action method: clobber 14:18:42 INFO - rmtree: /builds/slave/test/build 14:18:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:18:44 INFO - Running post-action listener: _resource_record_post_action 14:18:44 INFO - [mozharness: 2017-05-09 21:18:44.264458Z] Finished clobber step (success) 14:18:44 INFO - [mozharness: 2017-05-09 21:18:44.264632Z] Running read-buildbot-config step. 14:18:44 INFO - Running pre-action listener: _resource_record_pre_action 14:18:44 INFO - Running main action method: read_buildbot_config 14:18:44 INFO - Using buildbot properties: 14:18:44 INFO - { 14:18:44 INFO - "project": "", 14:18:44 INFO - "product": "firefox", 14:18:44 INFO - "who": "asasaki@mozilla.com", 14:18:44 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:18:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:18:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:18:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:18:44 INFO - "stage_platform": "macosx64", 14:18:44 INFO - "basedir": "/builds/slave/test", 14:18:44 INFO - "buildnumber": 150, 14:18:44 INFO - "platform": "yosemite_r7", 14:18:44 INFO - "parent_task_id": "Et-fjChoQyqz62h1UrGjaQ", 14:18:44 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:18:44 INFO - "slavebuilddir": "test", 14:18:44 INFO - "taskId": "eG0TuuwjSLu52arK2KZ8Ng", 14:18:44 INFO - "branch": "mozilla-central", 14:18:44 INFO - "script_repo_revision": "production", 14:18:44 INFO - "revision": "4b8939ed128172fcd3433ee25f336a8c1ba77ab0", 14:18:44 INFO - "slavename": "t-yosemite-r7-0368", 14:18:44 INFO - "repo_path": "mozilla-central" 14:18:44 INFO - } 14:18:44 INFO - Finding installer, test and symbols from parent task. 14:18:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eG0TuuwjSLu52arK2KZ8Ng'}, attempt #1 14:18:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eG0TuuwjSLu52arK2KZ8Ng'}, attempt #1 14:18:45 INFO - Task dependencies: Fy0EN9ZVSMiDBv6vuN45cQ 14:18:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ'}, attempt #1 14:18:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:18:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/test_packages.json 14:18:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/target.crashreporter-symbols.zip 14:18:46 INFO - Running post-action listener: _resource_record_post_action 14:18:46 INFO - [mozharness: 2017-05-09 21:18:46.095492Z] Finished read-buildbot-config step (success) 14:18:46 INFO - [mozharness: 2017-05-09 21:18:46.095604Z] Running download-and-extract step. 14:18:46 INFO - Running pre-action listener: _resource_record_pre_action 14:18:46 INFO - Running main action method: download_and_extract 14:18:46 INFO - mkdir: /builds/slave/test/build/tests 14:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:18:46 INFO - trying https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:18:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/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:18:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/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:18:47 INFO - Downloaded 1325 bytes. 14:18:47 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:18:47 INFO - Using the following test package requirements: 14:18:47 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:18:47 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:18:47 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:18:47 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:18:47 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:18:47 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:18:47 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:18:47 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:18:47 INFO - u'jsshell-mac.zip'], 14:18:47 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:18:47 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:18:47 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:18:47 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:18:47 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:18:47 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:18:47 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:18:47 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:18:47 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:18:47 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:18:47 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:18:47 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:18:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:18:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:18:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:18:48 INFO - Content-Length response header: 52706476 14:18:48 INFO - Bytes received: 52706476 14:19:04 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:19:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:19:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:19:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:19:05 INFO - Content-Length response header: 52706476 14:19:05 INFO - Bytes received: 52706476 14:19:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:19:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:19:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:19:22 INFO - Content-Length response header: 14409362 14:19:22 INFO - Bytes received: 14409362 14:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:22 INFO - trying https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:19:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:19:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/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:19:24 INFO - Downloaded 75533056 bytes. 14:19:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:19:24 INFO - mkdir: /builds/slave/test/properties 14:19:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:19:24 INFO - Writing to file /builds/slave/test/properties/build_url 14:19:24 INFO - Contents: 14:19:24 INFO - build_url:https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:19:24 INFO - Running post-action listener: _resource_record_post_action 14:19:24 INFO - Running post-action listener: set_extra_try_arguments 14:19:24 INFO - [mozharness: 2017-05-09 21:19:24.769053Z] Finished download-and-extract step (success) 14:19:24 INFO - [mozharness: 2017-05-09 21:19:24.769211Z] Running populate-webroot step. 14:19:24 INFO - Running pre-action listener: _resource_record_pre_action 14:19:24 INFO - Running main action method: populate_webroot 14:19:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:19:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:19:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:19:24 INFO - '--tppagecycles', 14:19:24 INFO - '7'], 14:19:24 INFO - 'tests': ['tsvgm']}}, 14:19:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:19:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:19:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:19:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:19:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:19:24 INFO - 'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:19:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:19:24 INFO - 'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['damp', 'tps']}, 14:19:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:19:24 INFO - 'tests': ['damp', 'tps']}, 14:19:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['dromaeo_dom']}, 14:19:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:19:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:19:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:19:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['a11yr', 14:19:24 INFO - 'ts_paint', 14:19:24 INFO - 'tpaint', 14:19:24 INFO - 'sessionrestore', 14:19:24 INFO - 'sessionrestore_no_auto_restore', 14:19:24 INFO - 'tabpaint']}, 14:19:24 INFO - 'other-e10s': {'tests': ['a11yr', 14:19:24 INFO - 'ts_paint', 14:19:24 INFO - 'tpaint', 14:19:24 INFO - 'sessionrestore', 14:19:24 INFO - 'sessionrestore_no_auto_restore', 14:19:24 INFO - 'tabpaint']}, 14:19:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:19:24 INFO - 'ts_paint', 14:19:24 INFO - 'tpaint', 14:19:24 INFO - 'sessionrestore', 14:19:24 INFO - 'sessionrestore_no_auto_restore', 14:19:24 INFO - 'tabpaint']}, 14:19:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:19:24 INFO - 'ts_paint', 14:19:24 INFO - 'tpaint', 14:19:24 INFO - 'sessionrestore', 14:19:24 INFO - 'sessionrestore_no_auto_restore', 14:19:24 INFO - 'tabpaint']}, 14:19:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['a11yr', 14:19:24 INFO - 'ts_paint', 14:19:24 INFO - 'tpaint', 14:19:24 INFO - 'sessionrestore', 14:19:24 INFO - 'sessionrestore_no_auto_restore', 14:19:24 INFO - 'tabpaint']}, 14:19:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['a11yr', 14:19:24 INFO - 'ts_paint', 14:19:24 INFO - 'tpaint', 14:19:24 INFO - 'sessionrestore', 14:19:24 INFO - 'sessionrestore_no_auto_restore', 14:19:24 INFO - 'tabpaint']}, 14:19:24 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:19:24 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:19:24 INFO - 'bloom_basic_ref']}, 14:19:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['tsvgx', 14:19:24 INFO - 'tsvgr_opacity', 14:19:24 INFO - 'tart', 14:19:24 INFO - 'tscrollx', 14:19:24 INFO - 'cart', 14:19:24 INFO - 'tsvg_static']}, 14:19:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:19:24 INFO - 'tsvgr_opacity', 14:19:24 INFO - 'tart', 14:19:24 INFO - 'tscrollx', 14:19:24 INFO - 'cart', 14:19:24 INFO - 'tsvg_static']}, 14:19:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:19:24 INFO - 'talos_options': ['--disable-e10s'], 14:19:24 INFO - 'tests': ['tp5o']}, 14:19:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:19:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:19:24 INFO - 'talos_options': ['--disable-e10s', 14:19:24 INFO - '--xperf_path', 14:19:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:24 INFO - 'tests': ['tp5n']}, 14:19:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:19:24 INFO - 'talos_options': ['--xperf_path', 14:19:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:24 INFO - 'tests': ['tp5n']}}, 14:19:24 INFO - 'talos.zip': {'path': '', 14:19:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:19:24 INFO - Running post-action listener: _resource_record_post_action 14:19:24 INFO - [mozharness: 2017-05-09 21:19:24.789848Z] Finished populate-webroot step (success) 14:19:24 INFO - [mozharness: 2017-05-09 21:19:24.789974Z] Running create-virtualenv step. 14:19:24 INFO - Running pre-action listener: _resource_record_pre_action 14:19:24 INFO - Running main action method: create_virtualenv 14:19:24 INFO - Creating virtualenv /builds/slave/test/build/venv 14:19:24 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:19:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:19:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:19:24 INFO - Using real prefix '/tools/python27' 14:19:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:19:25 INFO - Installing distribute.............................................................................................................................................................................................done. 14:19:28 INFO - Installing pip.................done. 14:19:28 INFO - Return code: 0 14:19:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:19:28 INFO - Reading from file tmpfile_stdout 14:19:28 INFO - Output received: 14:19:28 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:19:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:28 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:28 INFO - 'HOME': '/Users/cltbld', 14:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:28 INFO - 'LOGNAME': 'cltbld', 14:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:28 INFO - 'MOZ_NO_REMOTE': '1', 14:19:28 INFO - 'NO_EM_RESTART': '1', 14:19:28 INFO - 'PAGER': '/bin/cat', 14:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:28 INFO - 'PWD': '/builds/slave/test', 14:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:28 INFO - 'SHELL': '/bin/bash', 14:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:28 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:28 INFO - 'USER': 'cltbld', 14:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:28 INFO - 'XPC_FLAGS': '0x0', 14:19:28 INFO - 'XPC_SERVICE_NAME': '0', 14:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:19:29 INFO - Cleaning up... 14:19:29 INFO - Return code: 0 14:19:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:19:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:29 INFO - 'HOME': '/Users/cltbld', 14:19:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:29 INFO - 'LOGNAME': 'cltbld', 14:19:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:29 INFO - 'MOZ_NO_REMOTE': '1', 14:19:29 INFO - 'NO_EM_RESTART': '1', 14:19:29 INFO - 'PAGER': '/bin/cat', 14:19:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:29 INFO - 'PWD': '/builds/slave/test', 14:19:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:29 INFO - 'SHELL': '/bin/bash', 14:19:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:29 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:29 INFO - 'USER': 'cltbld', 14:19:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:29 INFO - 'XPC_FLAGS': '0x0', 14:19:29 INFO - 'XPC_SERVICE_NAME': '0', 14:19:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:29 INFO - Downloading/unpacking psutil>=3.1.1 14:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:19:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:31 INFO - Installing collected packages: psutil 14:19:31 INFO - Running setup.py install for psutil 14:19:31 INFO - building 'psutil._psutil_osx' extension 14:19:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:19:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:19:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:19:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:19:32 INFO - building 'psutil._psutil_posix' extension 14:19:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:19:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:19:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:19:32 INFO - ^ 14:19:32 INFO - 1 warning generated. 14:19:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:19:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:32 INFO - Successfully installed psutil 14:19:32 INFO - Cleaning up... 14:19:32 INFO - Return code: 0 14:19:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:32 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:32 INFO - 'HOME': '/Users/cltbld', 14:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:32 INFO - 'LOGNAME': 'cltbld', 14:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:32 INFO - 'MOZ_NO_REMOTE': '1', 14:19:32 INFO - 'NO_EM_RESTART': '1', 14:19:32 INFO - 'PAGER': '/bin/cat', 14:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:32 INFO - 'PWD': '/builds/slave/test', 14:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:32 INFO - 'SHELL': '/bin/bash', 14:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:32 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:32 INFO - 'USER': 'cltbld', 14:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:32 INFO - 'XPC_FLAGS': '0x0', 14:19:32 INFO - 'XPC_SERVICE_NAME': '0', 14:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:19:32 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:19:32 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:19:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:32 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:19:32 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:19:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:19:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:19:34 INFO - Installing collected packages: mozsystemmonitor 14:19:34 INFO - Running setup.py install for mozsystemmonitor 14:19:34 INFO - Successfully installed mozsystemmonitor 14:19:34 INFO - Cleaning up... 14:19:34 INFO - Return code: 0 14:19:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:19:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:35 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:35 INFO - 'HOME': '/Users/cltbld', 14:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:35 INFO - 'LOGNAME': 'cltbld', 14:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:35 INFO - 'MOZ_NO_REMOTE': '1', 14:19:35 INFO - 'NO_EM_RESTART': '1', 14:19:35 INFO - 'PAGER': '/bin/cat', 14:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:35 INFO - 'PWD': '/builds/slave/test', 14:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:35 INFO - 'SHELL': '/bin/bash', 14:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:35 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:35 INFO - 'USER': 'cltbld', 14:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:35 INFO - 'XPC_FLAGS': '0x0', 14:19:35 INFO - 'XPC_SERVICE_NAME': '0', 14:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:35 INFO - Downloading/unpacking jsonschema==2.5.1 14:19: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:19: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:19:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19: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:19: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:19:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:19:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:37 INFO - Downloading functools32-3.2.3-2.tar.gz 14:19:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:19:37 INFO - warning: no files found matching '*.txt' 14:19:37 INFO - no previously-included directories found matching 'build' 14:19:37 INFO - no previously-included directories found matching 'dist' 14:19:37 INFO - no previously-included directories found matching '.git*' 14:19:37 INFO - Installing collected packages: jsonschema, functools32 14:19:37 INFO - Running setup.py install for functools32 14:19:37 INFO - warning: no files found matching '*.txt' 14:19:37 INFO - no previously-included directories found matching 'build' 14:19:37 INFO - no previously-included directories found matching 'dist' 14:19:37 INFO - no previously-included directories found matching '.git*' 14:19:37 INFO - Successfully installed jsonschema functools32 14:19:37 INFO - Cleaning up... 14:19:37 INFO - Return code: 0 14:19:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:37 INFO - 'HOME': '/Users/cltbld', 14:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:37 INFO - 'LOGNAME': 'cltbld', 14:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:37 INFO - 'MOZ_NO_REMOTE': '1', 14:19:37 INFO - 'NO_EM_RESTART': '1', 14:19:37 INFO - 'PAGER': '/bin/cat', 14:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:37 INFO - 'PWD': '/builds/slave/test', 14:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:37 INFO - 'SHELL': '/bin/bash', 14:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:37 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:37 INFO - 'USER': 'cltbld', 14:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:37 INFO - 'XPC_FLAGS': '0x0', 14:19:37 INFO - 'XPC_SERVICE_NAME': '0', 14:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:19:37 INFO - Cleaning up... 14:19:37 INFO - Return code: 0 14:19:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:37 INFO - 'HOME': '/Users/cltbld', 14:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:37 INFO - 'LOGNAME': 'cltbld', 14:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:37 INFO - 'MOZ_NO_REMOTE': '1', 14:19:37 INFO - 'NO_EM_RESTART': '1', 14:19:37 INFO - 'PAGER': '/bin/cat', 14:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:37 INFO - 'PWD': '/builds/slave/test', 14:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:37 INFO - 'SHELL': '/bin/bash', 14:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:37 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:37 INFO - 'USER': 'cltbld', 14:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:37 INFO - 'XPC_FLAGS': '0x0', 14:19:37 INFO - 'XPC_SERVICE_NAME': '0', 14:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:38 INFO - Downloading/unpacking blobuploader==1.2.4 14:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:39 INFO - Downloading blobuploader-1.2.4.tar.gz 14:19:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:19:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:19:39 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:19:39 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:19:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:39 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:19:39 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:19:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:19:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:40 INFO - Downloading docopt-0.6.1.tar.gz 14:19:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:19:40 INFO - Installing collected packages: blobuploader, requests, docopt 14:19:40 INFO - Running setup.py install for blobuploader 14:19:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:19:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:19:40 INFO - Running setup.py install for requests 14:19:41 INFO - Running setup.py install for docopt 14:19:41 INFO - Successfully installed blobuploader requests docopt 14:19:41 INFO - Cleaning up... 14:19:41 INFO - Return code: 0 14:19:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:41 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:19:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:41 INFO - 'HOME': '/Users/cltbld', 14:19:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:41 INFO - 'LOGNAME': 'cltbld', 14:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:41 INFO - 'MOZ_NO_REMOTE': '1', 14:19:41 INFO - 'NO_EM_RESTART': '1', 14:19:41 INFO - 'PAGER': '/bin/cat', 14:19:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:41 INFO - 'PWD': '/builds/slave/test', 14:19:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:41 INFO - 'SHELL': '/bin/bash', 14:19:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:41 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:41 INFO - 'USER': 'cltbld', 14:19:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:41 INFO - 'XPC_FLAGS': '0x0', 14:19:41 INFO - 'XPC_SERVICE_NAME': '0', 14:19:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:41 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Db98j2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:41 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-YU8P6R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:41 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-RJk_JE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:41 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-_n0Wg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:42 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-AyDILZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:42 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-wBrhDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:42 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-jxbvxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:42 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-IbIMIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:42 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-2Zw31I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:42 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-N6XkdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:42 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-PqLLC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:42 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-o0vju8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:42 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-wWPAnE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:43 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-0kGJts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:43 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-00Ee6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:43 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-cJWVfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:43 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-9IazgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:19:43 INFO - Running setup.py install for manifestparser 14:19:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:19:43 INFO - Running setup.py install for mozcrash 14:19:43 INFO - Running setup.py install for mozdebug 14:19:43 INFO - Running setup.py install for mozdevice 14:19:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:19:44 INFO - Running setup.py install for mozfile 14:19:44 INFO - Running setup.py install for mozhttpd 14:19:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:19:44 INFO - Running setup.py install for mozinfo 14:19:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:19:44 INFO - Running setup.py install for mozInstall 14:19:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:19:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:19:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:19:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:19:44 INFO - Running setup.py install for mozleak 14:19:44 INFO - Running setup.py install for mozlog 14:19:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:19:44 INFO - Running setup.py install for moznetwork 14:19:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:19:45 INFO - Running setup.py install for mozprocess 14:19:45 INFO - Running setup.py install for mozprofile 14:19:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:19:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:19:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:19:45 INFO - Running setup.py install for mozrunner 14:19:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:19:45 INFO - Running setup.py install for mozscreenshot 14:19:45 INFO - Running setup.py install for moztest 14:19:45 INFO - Running setup.py install for mozversion 14:19:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:19:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:19:46 INFO - Cleaning up... 14:19:46 INFO - Return code: 0 14:19:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:19:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:46 INFO - 'HOME': '/Users/cltbld', 14:19:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:46 INFO - 'LOGNAME': 'cltbld', 14:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:46 INFO - 'MOZ_NO_REMOTE': '1', 14:19:46 INFO - 'NO_EM_RESTART': '1', 14:19:46 INFO - 'PAGER': '/bin/cat', 14:19:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:46 INFO - 'PWD': '/builds/slave/test', 14:19:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:46 INFO - 'SHELL': '/bin/bash', 14:19:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:46 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:46 INFO - 'USER': 'cltbld', 14:19:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:46 INFO - 'XPC_FLAGS': '0x0', 14:19:46 INFO - 'XPC_SERVICE_NAME': '0', 14:19:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:46 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-o6GKwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:46 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:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:46 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-k9zdwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:46 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:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:46 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-_3xI_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:46 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:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:46 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Li8JQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:46 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:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:46 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-NFN8wQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:46 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:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:46 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-ga7In0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:46 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:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:46 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-NF2HfX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:47 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-Ekcc6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:47 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-XQUYrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:47 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-he0Tih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:47 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-hQsKb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:47 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:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:47 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-MBFTjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:47 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:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:47 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-t0gkAE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:47 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:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:47 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-77Ghy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:47 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:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:47 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-SvxXNE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:47 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:19:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:47 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-qlgptU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:19:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:48 INFO - Running setup.py (path:/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/pip-dOcjYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:49 INFO - Downloading blessings-1.6.tar.gz 14:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:19:49 INFO - Installing collected packages: blessings 14:19:49 INFO - Running setup.py install for blessings 14:19:49 INFO - Successfully installed blessings 14:19:49 INFO - Cleaning up... 14:19:49 INFO - Return code: 0 14:19:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:19:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:50 INFO - Reading from file tmpfile_stdout 14:19:50 INFO - Current package versions: 14:19:50 INFO - blessings == 1.6 14:19:50 INFO - blobuploader == 1.2.4 14:19:50 INFO - docopt == 0.6.1 14:19:50 INFO - functools32 == 3.2.3-2 14:19:50 INFO - jsonschema == 2.5.1 14:19:50 INFO - manifestparser == 1.1 14:19:50 INFO - mozInstall == 1.12 14:19:50 INFO - mozcrash == 1.0 14:19:50 INFO - mozdebug == 0.1 14:19:50 INFO - mozdevice == 0.50 14:19:50 INFO - mozfile == 1.2 14:19:50 INFO - mozhttpd == 0.7 14:19:50 INFO - mozinfo == 0.9 14:19:50 INFO - mozleak == 0.1 14:19:50 INFO - mozlog == 3.4 14:19:50 INFO - moznetwork == 0.27 14:19:50 INFO - mozprocess == 0.25 14:19:50 INFO - mozprofile == 0.28 14:19:50 INFO - mozrunner == 6.13 14:19:50 INFO - mozscreenshot == 0.1 14:19:50 INFO - mozsystemmonitor == 0.3 14:19:50 INFO - moztest == 0.8 14:19:50 INFO - mozversion == 1.4 14:19:50 INFO - psutil == 3.1.1 14:19:50 INFO - requests == 1.2.3 14:19:50 INFO - wsgiref == 0.1.2 14:19:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:19:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:50 INFO - 'HOME': '/Users/cltbld', 14:19:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:50 INFO - 'LOGNAME': 'cltbld', 14:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:50 INFO - 'MOZ_NO_REMOTE': '1', 14:19:50 INFO - 'NO_EM_RESTART': '1', 14:19:50 INFO - 'PAGER': '/bin/cat', 14:19:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:50 INFO - 'PWD': '/builds/slave/test', 14:19:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:50 INFO - 'SHELL': '/bin/bash', 14:19:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:50 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:50 INFO - 'USER': 'cltbld', 14:19:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:50 INFO - 'XPC_FLAGS': '0x0', 14:19:50 INFO - 'XPC_SERVICE_NAME': '0', 14:19:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:19:50 INFO - Cleaning up... 14:19:50 INFO - Return code: 0 14:19:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:50 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x1037c2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1037c5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feac3cb5300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, '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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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:19:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:19:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:19:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:50 INFO - 'HOME': '/Users/cltbld', 14:19:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:50 INFO - 'LOGNAME': 'cltbld', 14:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:50 INFO - 'MOZ_NO_REMOTE': '1', 14:19:50 INFO - 'NO_EM_RESTART': '1', 14:19:50 INFO - 'PAGER': '/bin/cat', 14:19:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:50 INFO - 'PWD': '/builds/slave/test', 14:19:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:50 INFO - 'SHELL': '/bin/bash', 14:19:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:19:50 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:19:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:50 INFO - 'USER': 'cltbld', 14:19:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:50 INFO - 'XPC_FLAGS': '0x0', 14:19:50 INFO - 'XPC_SERVICE_NAME': '0', 14:19:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:19:50 INFO - Cleaning up... 14:19:50 INFO - Return code: 0 14:19:50 INFO - Running post-action listener: _resource_record_post_action 14:19:50 INFO - Running post-action listener: _start_resource_monitoring 14:19:50 INFO - Starting resource monitoring. 14:19:50 INFO - [mozharness: 2017-05-09 21:19:50.740724Z] Finished create-virtualenv step (success) 14:19:50 INFO - [mozharness: 2017-05-09 21:19:50.741665Z] Running install step. 14:19:50 INFO - Running pre-action listener: _resource_record_pre_action 14:19:50 INFO - Running main action method: install 14:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:50 INFO - Reading from file tmpfile_stdout 14:19:50 INFO - Detecting whether we're running mozinstall >=1.0... 14:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:19:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:19:51 INFO - Reading from file tmpfile_stdout 14:19:51 INFO - Output received: 14:19:51 INFO - Usage: mozinstall [options] installer 14:19:51 INFO - Options: 14:19:51 INFO - -h, --help show this help message and exit 14:19:51 INFO - -d DEST, --destination=DEST 14:19:51 INFO - Directory to install application into. [default: 14:19:51 INFO - "/builds/slave/test"] 14:19:51 INFO - --app=APP Application being installed. [default: firefox] 14:19:51 INFO - mkdir: /builds/slave/test/build/application 14:19:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:19:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:20:11 INFO - Reading from file tmpfile_stdout 14:20:11 INFO - Output received: 14:20:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:20:11 INFO - Running post-action listener: _resource_record_post_action 14:20:11 INFO - [mozharness: 2017-05-09 21:20:11.212506Z] Finished install step (success) 14:20:11 INFO - [mozharness: 2017-05-09 21:20:11.212638Z] Running run-tests step. 14:20:11 INFO - Running pre-action listener: _resource_record_pre_action 14:20:11 INFO - Running main action method: run_tests 14:20:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:20:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:20:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:20:11 INFO - 2017-05-09 14:20:11.227 screenresolution[1942:12866] starting screenresolution argv=screenresolution get 14:20:11 INFO - 2017-05-09 14:20:11.230 screenresolution[1942:12866] Display 0: 1600x1200x32@60 14:20:11 INFO - 2017-05-09 14:20:11.235 screenresolution[1943:12869] starting screenresolution argv=screenresolution list 14:20:11 INFO - Available Modes on Display 0 14:20:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:20:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:20:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:20:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:20:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:20:11 INFO - Intel Iris: 14:20:11 INFO - Chipset Model: Intel Iris 14:20:11 INFO - Type: GPU 14:20:11 INFO - Bus: Built-In 14:20:11 INFO - VRAM (Dynamic, Max): 1536 MB 14:20:11 INFO - Vendor: Intel (0x8086) 14:20:11 INFO - Device ID: 0x0a2e 14:20:11 INFO - Revision ID: 0x0009 14:20:11 INFO - Displays: 14:20:11 INFO - Display: 14:20:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:20:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:20:11 INFO - Display Serial Number: 1600x1200 60 14:20:11 INFO - Main Display: Yes 14:20:11 INFO - Mirror: Off 14:20:11 INFO - Online: Yes 14:20:11 INFO - Rotation: Supported 14:20:11 INFO - Return code: 0 14:20:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:20:11 INFO - Python 2.7.3 14:20:11 INFO - Return code: 0 14:20:11 INFO - grabbing minidump binary from tooltool 14:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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:20:11 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 0x1034d3d30>, '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 0x103486db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034c7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:20:11 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:20:11 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:20:11 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:20:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:20:11 INFO - Return code: 0 14:20:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:20:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:20:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:20:11 INFO - ENV: RUST_BACKTRACE is now 1 14:20:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:20:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:20:11 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/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--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:20:11 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/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0368 --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:20:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0LsBIK3uTc/Render', 14:20:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:11 INFO - 'HOME': '/Users/cltbld', 14:20:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:11 INFO - 'LOGNAME': 'cltbld', 14:20:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:20:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:11 INFO - 'MOZ_NO_REMOTE': '1', 14:20:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:20:11 INFO - 'NO_EM_RESTART': '1', 14:20:11 INFO - 'PAGER': '/bin/cat', 14:20:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:11 INFO - 'PWD': '/builds/slave/test', 14:20:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:20:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:11 INFO - 'RUST_BACKTRACE': '1', 14:20:11 INFO - 'SHELL': '/bin/bash', 14:20:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FxfvZcfF7m/Listeners', 14:20:11 INFO - 'TMPDIR': '/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/', 14:20:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:11 INFO - 'USER': 'cltbld', 14:20:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:11 INFO - 'XPC_FLAGS': '0x0', 14:20:11 INFO - 'XPC_SERVICE_NAME': '0', 14:20:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:11 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/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0368', '--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:20:11 INFO - mozversion application_buildid: 20170509133150 14:20:11 INFO - mozversion application_changeset: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 14:20:11 INFO - mozversion application_display_name: Nightly 14:20:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:20:11 INFO - mozversion application_name: Firefox 14:20:11 INFO - mozversion application_remotingname: firefox 14:20:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:20:11 INFO - mozversion application_vendor: Mozilla 14:20:11 INFO - mozversion application_version: 55.0a1 14:20:11 INFO - mozversion platform_buildid: 20170509133150 14:20:11 INFO - mozversion platform_changeset: 4b8939ed128172fcd3433ee25f336a8c1ba77ab0 14:20:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:20:11 INFO - mozversion platform_version: 55.0a1 14:20:11 INFO - using testdate: 1494364811 14:20:11 INFO - actual date: 1494364811 14:20:11 INFO - starting webserver on 'localhost:49518' 14:20:11 INFO - SUITE-START | Running 2 tests 14:20:11 INFO - TEST-START | dromaeo_css 14:20:11 INFO - operating with platform_type : mac_ 14:20:11 INFO - Initialising browser for dromaeo_css test... 14:20:11 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmp6oqiKJ/profile http://localhost:49518/getInfo.html) 14:20:14 INFO - PID 1953 | 2017-05-09 14:20:14.367 plugin-container[1955:13052] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9837, name = 'com.apple.tsm.portname' 14:20:14 INFO - PID 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:20:14 INFO - PID 1953 | 2017-05-09 14:20:14.369 plugin-container[1955:13052] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f17, name = 'com.apple.CFPasteboardClient' 14:20:14 INFO - PID 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:20:14 INFO - PID 1953 | 2017-05-09 14:20:14.369 plugin-container[1955:13052] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:20:14 INFO - PID 1953 | __metrics Screen width/height:1600/1200 14:20:14 INFO - PID 1953 | colorDepth:24 14:20:14 INFO - PID 1953 | Browser inner width/height: 1024/691 14:20:14 INFO - PID 1953 | __metrics 14:20:14 INFO - PID 1953 | 2017-05-09 14:20:14.882 plugin-container[1956:13105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 14:20:14 INFO - PID 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:20:14 INFO - PID 1953 | 2017-05-09 14:20:14.883 plugin-container[1956:13105] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 14:20:14 INFO - PID 1953 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:20:14 INFO - PID 1953 | 2017-05-09 14:20:14.883 plugin-container[1956:13105] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:20:15 INFO - TEST-INFO | 1953: exit 0 14:20:15 INFO - Browser initialized. 14:20:15 INFO - Running cycle 1/1 for dromaeo_css test... 14:20:15 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmp6oqiKJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:17 INFO - PID 1957 | 2017-05-09 14:20:17.816 plugin-container[1958:13219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x964b, name = 'com.apple.tsm.portname' 14:20:17 INFO - PID 1957 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:20:17 INFO - PID 1957 | 2017-05-09 14:20:17.816 plugin-container[1958:13219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 14:20:17 INFO - PID 1957 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:20:17 INFO - PID 1957 | 2017-05-09 14:20:17.816 plugin-container[1958:13219] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:20:18 INFO - PID 1957 | 2017-05-09 14:20:18.229 plugin-container[1959:13264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 14:20:18 INFO - PID 1957 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:20:18 INFO - PID 1957 | 2017-05-09 14:20:18.230 plugin-container[1959:13264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 14:20:18 INFO - PID 1957 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:20:18 INFO - PID 1957 | 2017-05-09 14:20:18.230 plugin-container[1959:13264] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:20:27 INFO - PID 1957 | RSS: Main: 229634048 14:20:27 INFO - PID 1957 | 14:23:30 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:49518/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49518/tests/dromaeo/cssquery-ext.html) 14:23:32 INFO - PID 1957 | RSS: Main: 251457536 14:23:32 INFO - PID 1957 | 14:26:34 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:49518/tests/dromaeo/cssquery-ext.html (next: http://localhost:49518/tests/dromaeo/cssquery-jquery.html) 14:26:35 INFO - PID 1957 | RSS: Main: 258088960 14:26:35 INFO - PID 1957 | 14:29:37 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:49518/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49518/tests/dromaeo/cssquery-mootools.html) 14:29:37 INFO - PID 1957 | RSS: Main: 269160448 14:29:37 INFO - PID 1957 | 14:32:39 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:49518/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49518/tests/dromaeo/cssquery-prototype.html) 14:32:40 INFO - PID 1957 | RSS: Main: 261963776 14:32:40 INFO - PID 1957 | 14:35:42 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:49518/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49518/tests/dromaeo/cssquery-yui.html) 14:35:43 INFO - PID 1957 | RSS: Main: 265666560 14:35:43 INFO - PID 1957 | 14:38:45 INFO - PID 1957 | Cycle 1(1): loaded http://localhost:49518/tests/dromaeo/cssquery-yui.html (next: http://localhost:49518/tests/dromaeo/cssquery-dojo.html) 14:38:45 INFO - PID 1957 | RSS: Main: 264507392 14:38:45 INFO - PID 1957 | 14:38:45 INFO - PID 1957 | __start_tp_report 14:38:45 INFO - PID 1957 | _x_x_mozilla_page_load 14:38:45 INFO - PID 1957 | _x_x_mozilla_page_load_details 14:38:45 INFO - PID 1957 | |i|pagename|runs| 14:38:45 INFO - PID 1957 | |0;dojo.html;3558.0037589474955;3571.3214489247043;3599.4780756790265;3601.153728873716;3611.5124458198143;5420;5578.609497335188;5717.085266357383;5848.23972883525;6144.170536977506;6156.168917196188;6174.9382506174925;6201.503879689625;6229.127922090911;6285.120083188357;4619.653525985547;4981.601471882241;5231.529162375386;5418.376886658026;5425.457454254562;5234.136367499358;5454.4545545445535;5542.251796007527;5544;5548.55611551077;5280.1815718563685;5365;5443.591730620219;5448.591355648323;5467.343918729738;5441.809536666237;5443.047466693313;5463.508284254396;5465.480779325958;5481.698506582136;5354.5180933715765;5383.973080134574;5404.729763511848;5415.48552887475;5428.972855135699;5891.911621325684;6057.969710151421;6136.641621527162;6151.907721384138;6179.721912513947;6534.54258201926;6628.072069910217;6633.402993730588;6633.834154146137;6653.4344580710695;6775.2208496023095;6792.898106528406;6815.045893574904;6815.216250131249;6820.04519367289;6179.536534759911;6287.339829317856;6323.494120470351;6337.461315788163;6349;6764.255931847493;6825.69284382204;6947.617881016593;6963.303669632996;6985.511014229057;6295.087212354183;6381.042843573408;6397.072424498515;6560.278369379364;6619.834504137339;5352.357717073898;6644.8671026579195;6683.866322673519;6776.593404395653;6785.796426107225;6665.533412661164;6669.299723529057;6675.732970681119;6690.866182676319;6692.19693636757;6409.67951602418;6472.417482426604;6497.4477169441125;6548.476121910236;6694.665266736643;4501.549845015472;4714.38712967312;5345.518903298722;5369.140937449989;5389.380221274525;3952.66402355803;4683.99294151759;4687.929681054787;4720.362751028567;4760.238361862085;6176.752929882844;6395.072714456378;6400.391962763623;6402;6404.647744374104;6681.632510211888;6722.630255336004;6729.528932974743;6754.189497260262;6786.423154031962;5355.491228333302;6324.873502529924;6355.428011478896;6379.138816259839;6440.162778838815;5013.473585273567;5904.822855314347;5914.290285165807;5925.644461332334;5972.820815375459;6699.933000670028;6785.864282714417;6836.692348844214;6913.619750913748;6951.339622735832;6775.762848300285;6786.083878676315;6812.114425124702;6833.45332373399;6834.248232694499;6797.708435397259;6966.268541803241;6974.093367862146;6987.965060174666;6992.895106573507;5431.4296998816135;5936.3470018297085;6423.9036414452885;6640.900386494303;6658.267590565131;5575.721213939368;6678.432333251728;6766.391024807791;6794.72821087151;6801.319868013159;6264.749410023548;6381.1704478417505;6542.182227221598;6566.244881838458;6581;6375.6174629522375;6458.870822583428;6557.639329836905;6583.144191255106;6593.406593406617;6210.503159747119;6249.750009999549;6327.810165695037;6339.619622822645;6358.459530939922;6435.549511534147;6506.41442270198;6515.609063456208;6621.27166011748;6625.933740662725;6455.160829092188;6476.5466417350335;6487.416132548094;6501.609903405811;6501.9674901625185 14:38:45 INFO - PID 1957 | |1;ext.html;675.8242856857217;743.5798773692867;825.5129473610571;1206.607852447954;1258.6790368456043;34392.484112738326;35111.59553617859;37057.62942370575;38235.23529529408;38351.23297534048;38208.61791382085;38420.039499012535;38469.422958655574;38488.61511384886;38541.229175416476;7791.727289544853;7944.563049032301;8345.123762004992;8510.106438823927;8550.61522231498;8549.871751923725;8623.870641940375;8632.956835215846;8634.568271586428;8675.045744968049;8608;8619.741407757778;8664.61009254582;8680.739577812645;8691.695790647329;8614.698485553006;8640.697575584887;8650.567471626457;8650.956745216297;8652.8702069469;8358.498490090553;8405.159484051606;8413.915860841436;8436.578171091482;8451.23938845507;37869.43195852037;37872.81063594692;37874;37882;37957.81021094904;3606.5131207286945;3619.855205791765;3648.3068217038676;3650.1787097903025;3657.487951686766;30692;31024.534631980314;31056.37887242265;31096.911608093833;31112.533312000338;3063.356695094033;3131.5928929239053;3131.7181453669277;3143.151349135743;3170.7621928355234;297500;297683;297740.0225997756;309116;310944;38295.80852095722;43030.13939721188;43675.34486982698;47795.044099117826;47899;44528.3320750189;45701.857453563265;50387.496125038284;50538.747306263234;55208;14725.337359818834;14890.404383824742;14908.478203262834;14920;14962.401503939938;11878.88121118795;11976.700582485333;11982.940085299517;11984.700382490333;11992.40037998114;13998.440062397594;14010.299485025707;14050.437982480586;14074.71850562983;14085.577432677037;5206.453322401169;6316.526260530479;6321.462675672527;6385.201849768779;6396.552241343062;947.9052094790466;985.6747273399761;988.3032462114193;998.7353351361895;1077.4558847781977;23296.76703232946;23773.454725442793;24671.863912225966;24700.173310225302;24958.750412496007;1113.2207454781685;1114.8717897441898;1118.8433619293305;1125.0661950580998;1153.135148638521;11288.830667539993;12823.974082073411;13544.322783860767;15930.36278548845;16797.664046718997;296625.5168724143;307029.92970070016;330290;331071;332383;56621;56684.716576417675;57129.42870571324;57193.14210286849;57219.14171287434;1472.8968972171845;1492.2165862922052;1524.6493306539558;1524.8703860171788;1549.6435819761293;26829.329266768487;29584.112476625734;30197.245068873453;30510;31297.8435107823;44415.55584444114;53835.461645383046;54490;54577.72711136419;54737.452625473234;287537;288217;290776;291119;291166.5441672863;30190.9433169838;36190.4571431434;36925.26149477049;37281.81359093187;37356.62643373531;44280.33579496374;45519.77240113778;46655.766721166176;47010;47071.293930590386;30203.848980754956;31075.37849243048;31491.37017259688;31728.20679482939;31949.520757189126;25593.104241351462;31037.67217458745;31582.052538423883;31642;31743.50821409689;37318.25363492769;38441;38560.42159367556;38586.03534911649;38600.0699964995;25105.24684259475;30541.014834035715;32275;32320.030399088064;32397.676023239466;22318.553628927657;25090.37274068163;25249.74750252474;25485.872570637028;25722.35694107662 14:38:45 INFO - PID 1957 | |2;jquery.html;411.69534544437107;426.5734265734264;430.6059955141046;441.22344673374863;445.61454341994164;20691.689624655624;21537.56924861502;21566.02952867121;21976;22202.444938876535;21644;22628;22695.659565106496;22887.542249155005;23041.193558225466;5890.823275301748;6048.697565121748;6122.387761223864;6143.44708976192;6148.647297594522;5841.591088623798;5974.312954010279;5975.641461512323;6042.456178943894;6110.725017374207;6030.457258846703;6033.27600687918;6075.270967483908;6078.726457309432;6084.574079814415;5815.796447124351;6014.488768454687;6018.037114061773;6020.217371741689;6041.214642096521;5312.707801070939;5319.946800532022;5340.305760251182;5370.865728356784;5380.381256155553;26526.734732652618;26723;27027.324316892234;27052;27079.916803327844;2212.1814928476565;2286.474110954473;2289.0614847912275;2289.5420915816903;2294.8737819419925;16998;17262.30950761968;17553;18020.279188832432;18070.277188912427;2115.8095771380495;2142.153849229546;2152.1499007891794;2167.425632207466;2169.3275084723787;139496.30251848776;148754.99999999892;162422.1878890598;165855;166878;9720.368176068532;12500.812487812691;12504.687382815502;12530.749385012248;12538.247705137721;13634.454621815215;16212.837871621134;16330.591735206715;16363.50909472694;16398.360163983743;7028.859422811515;8701.608427620646;9048.276137908952;9050.954745226232;9112.453252804853;6994;8505.489670619783;8643.56782160902;8798.86801697975;8864.689735859214;7877.251661092187;7955.7613271600785;8010.519368837889;8030.277275045274;8072.273495385443;2140.3044010696585;2157.2341818654468;2158.093600687715;2175.7389113306504;2372.406898275431;995.6714284286313;999.8550210219478;1000.3747657713928;1028.5371582787775;1035.761774791787;15514.767278490832;20440.488987775425;21296.680549791767;21398.358049258546;22507.662385064235;890.3233542507741;1031.9690409287734;1035.2753072849036;1046.2788712567935;1063.8297872340388;10492.895071049192;10559.366438013743;11197;11253.493592788343;11263.324200547993;459480;543407;546795;548931;549494;133355;136145.31927340498;137141.3142934279;141687;141928;1209.2865209526421;1230.9815352769715;1263.684078980255;1264.7786637338613;1268.7081971146688;21942.561148776615;22930.656040159076;23158;23373.181908747032;23387.76612233856;50863.72840679013;52133.69665758387;59124;61297;61821.76356472755;76239;77727;79008.60495697484;81002;81188.59405702933;8923.598438070172;8974.596143173703;9005.459672419676;9077.45535267886;9113.954430227806;12341.568045118378;14029.508967186099;14428.350724217646;14727.337269822669;14783.556493305217;9998.950005249928;11755.764884702086;11912.761744764883;11999.820002699791;12287.1399002069;11087.889121108685;11469;11477.36874471912;11767.23512971654;11795.233309834834;12025.037996960047;12032.458539365574;12099.395030248628;12344.691382715144;12358.382080896099;8920.821583568422;9013.324000700104;9081.364304498886;9098.454092754457;9107.316951228817;11624.476898539417;12010.399480026139;12072.45673944657;12129;12323.075769317516 14:38:45 INFO - PID 1957 | |3;mootools.html;1264.6901509130264;1301.7852054411023;1440.2726623055362;1455;1481.7629179331302;11160.888391116086;11549.6535103947;11610.941945290273;11676.532938682443;11701.122415818805;11460.48427820748;11632.767344653123;11656.825147622792;11740.29558226507;11750.177487575851;2515.9622605660884;2560.1807421624994;2565.4735432417683;2566.653501777255;2574.5108429398356;2480.2063339731294;2530.4053547416343;2535.3408113890337;2535.7083935347487;2542.6313184588316;2187.8687278763246;2492.1277552856536;2514.6479492871013;2524.066095544651;2545.9490810183784;2496.463260399014;2515.5723527000455;2522.697276326834;2535.8985640574538;2547.961780573293;2035.9287424940205;2358.846674966139;2485.428351479152;2497.088562674629;2497.1634502441702;11560.075193984461;11631.534738610446;11633.243839150427;11640.650780476599;11643.53425862973;6576.572522786004;6780.355866192704;6799.864002719918;6836.829079273009;6846.589204647687;4049.8785036448953;4367.279398899178;4427.977860110679;4469.329600559942;4480.148771733393;1038.9324693894873;1067.4929408530963;1184.2716729211559;1200.2798321007413;1209.1475509765683;14321.35553900077;15507.689846203013;16182.514524564282;16297.022178669318;16320.591985200346;5513.448655134454;5518.227448157261;5703.515201207861;5704.11586204139;5808.912866307009;9863.506824658738;11745.882541174478;11796.764064718658;11873.940630296793;11966.222195557435;6563.982582699594;6586.4072233498255;6589.703463344161;6603.966980165068;6611.669416529155;6050;6078.544109191828;6168.290646575701;6202.100695399232;6241.407066328691;4292.914141717149;4382.364557139261;4488.932666010012;4521.253993091103;4535.569120933506;1270.2568997136593;1284.9100562960502;1287.8712128787047;1301.128244076471;1303.3418123847396;795.1650766694947;800.7628214408636;804.3725893802844;806.3992325717378;835.849547081519;6069.241344831896;6093.964026115571;6306.558540902093;6323.008252400479;6333.20834895638;591.8727473593112;598.2073752278249;612.4946918791925;615.5411674331862;620.8789286089169;3785.9432108518395;3893.435451859521;4028.798560071985;4301.376300436482;4335.349697545394;14231.78652320216;14791.556253312418;14884.70230595382;14918;14929.40282388714;14391.920605954596;14548.563543093724;14765.557033288804;14789.704205915821;14825.925870370795;8591.226789588967;8606.526641034803;8640.697575584825;8643.178898004806;8658.874346334936;5170.37955445349;6008.729607167776;6938.791836244921;7038.190608079932;7135.571865688075;12503.624891253277;12509.186902851285;12519.93740031294;12560.434780435085;12765.680857978625;12753.48986040585;13422.664433389244;13562.38969246406;13677;13828.239446830521;8386.832263354576;8436.662533498591;8442.451240669337;8471.745847624581;8482.703105391281;10589.894101058891;10698.946505267424;10814.026737593655;10840.837387439036;10867.836982445111;9024.344140792251;9136.543172841199;9155.359124861196;9229.953850230706;9271.351005429826;7659.770206893802;8882.200601945857;9029.916410030839;9073.00196978345;9094.090590940854;6877.449804015767;8158.021037475541;8175.018997720312;8180.100188979327;8307.584620768817;6012.428819262163;6080.148779171008;6139.877202455837;6144.600600960924;6177.1969643945995;5375.784968601212;6062.575619706578;6066.908996364969;6182.629042257479;6237.500999919905 14:38:45 INFO - PID 1957 | |4;prototype.html;368.1716138687952;401.6385253272057;407.1571846278202;412.70697804558773;413.51825123730856;4581.198290299201;5306.416294207639;5902.645841249528;6181.938180618193;6458.870822583545;4592.4718657354415;6206.906896396557;6228.377162283769;6246.063720246727;6257.96743537317;3655.872044478444;4560.566746159117;4588.820310352573;4595.471520775103;4611.169989401907;3801.2967600993766;4556.70381425208;4575.382323386333;4590.908181836361;4628.033085938479;3654.3604869147925;4129.905575022622;4152.107296931159;4262.742490965157;4585.541445855421;3548.787072775616;3963.7712309184103;4144.813483393239;4329.631981281595;4401.031773009932;3227.9031629051165;3352.1116904020455;3867;3883.1262965832743;3901.882943511699;5661.5187709044785;6109.63342199465;6138.048602466625;6150.2927163376335;6170.81487555374;4923.458419573844;5128;5358.839234822962;5391.78432862685;5481.287432633732;6114.38856114385;6157.107219453154;6709.322669332667;6727.428168605675;6754.5947243165565;3673;4167.749935003879;4413.624841888443;4434.84478043267;4438.977805110986;6262.868565717142;6659.901116315802;6662.267150613428;7105.89341159883;7237.42100631943;4048.10941592855;4387.166438376698;4506.887327816831;4520.773961301922;4538.364628951949;4590.793414296365;4603.332516785047;4604.861854144381;4609.1703493371515;4612.930806037912;4491.865244042684;5325.147976323769;5490.945090549043;5497.835064948058;5504.2294078829;5905.911411328833;6680.966595166993;6778.983751816846;6817.352351526597;6831.438210670998;1536.4391996921042;1560.9843901561067;1562.2813505787465;1566.0994927916493;1576.3615735627088;938.9483578403117;990.0891180114312;1009.8030883977553;1019.230480986865;1026.3021145621049;5697.116946873241;6138.785142519991;6193.628382297076;6465.094886715863;6497.5126865484335;6735.393814556714;6885.415321017945;6904.275051119661;6987.860242795217;7037.929620703728;5501.284832971688;6313.116163737081;6474.287828338879;6487.448566871869;6526.575772574767;3348.313595712873;3502.4571191465366;3658.9817050914576;3662.7985460799914;3678.700811992505;5721.942780572141;6625.867482650417;7243.456740744466;7303.567323358013;7335.7432489862595;7077.681504332318;7122.643867806589;7198.712051517985;7209.873087439218;7230.66160303824;5981.790637327673;6230.719617617218;7204.459665525002;7238.420926325986;7263.4915555910575;6108.816735497942;6758.155230596176;6967.965160174167;7002.70872689835;7060.894086588808;6590.275069742421;6759.053732477359;6762.594244345355;6875.140607424072;6908.585484870924;6686.966565167143;6753.5710718209575;7026.837922934597;7327.413806895543;7379.372753316028;3852.3643598805884;4077.653399461079;4123.505179378494;4529.822246215942;4559.77201139935;5629.324481062299;6781.728730850711;7051.294870512907;7059.223485416497;7119.715211391694;6487.318831522811;6841.486888483483;6843.178818541807;6859.897101543381;6865.176178858569;5571.832845014494;5835.095560188178;5901.232839730979;6670.83322916931;6712.49656275791;6526.9020964686515;6703.229128650268;6735.562188457735;6739.831504212434;6751;5243.397009343822;5944.989351810289;6859.965700171467;6862.632391824813;6884.965575172092;5336.67979921206;6628.337166283526;6671.299513551007;6698;6706.966465167643 14:38:45 INFO - PID 1957 | |5;yui.html;712.6614857942477;809.2150613904518;829.8962629671291;837.3468694418352;851.6337974670892;14252.287385630716;15835.920820395897;16854.662906741873;18220.72668909964;19303.517412064706;17727.113644317797;18131.637367252646;18301.450956471326;18567.535811604717;19162.71255931162;975.4683697384925;1000.4897502273857;1008.6419321141;1015.0610685116275;1015.8222311095566;974.1086905481476;998.8351921932872;1022.8874823769379;1029.1663752360575;1031.242037102729;1060.1995493402471;1071.6892101290616;1072.8498010278568;1074.049466222391;1074.962376316829;1053.857729206555;1063.2079101069676;1064.281609913313;1064.6539874540806;1072.2654981337769;770.7071312901118;781.6677911887482;793.5556088590408;794.4240425691386;796.6534557467462;16705.331786728515;16858.915705421517;16903.40838070674;17318.220680069302;17474.301027958867;17048.659026819394;17090.658186836194;17394.739078913826;17426.47720568385;17606.2077206526;13915;14022.92988535061;14236.28818559068;14269.643258918506;14356.353964071643;3508.4035713928693;3510.877119300838;3533.6819686228364;3534.169470174507;3544.6632569906074;122585;123256.38371808083;123288;124822.75177248292;125809;11614.477348519336;13448.175883064667;13472.663183420493;13630.591082267549;13779;24649.507009860063;24676.876615616806;25254.621180682305;25802.612960805604;26412.339691507867;6535.967320163464;7984.361251099898;7987.68049278034;8001;8038.236367545074;3887.6695480883877;3935.9212815743526;3976.6818654508147;3988.6809055275507;4005.0187704012706;3270.7898077710925;3739.476473293741;4256.489221293403;4436.844710435119;4502.8198872045405;806.2058872011064;917.8756023870704;973.6981535723806;981.4503877828439;981.749653838281;636.3795299582962;668.7893313606198;672.9427998620074;681.1927865479423;699.1295836683261;9309.627614895464;11329.564143709142;11811.822822657667;12188.085893557842;12274.631761047183;655.6492276631978;658.3613894522306;658.7463826426861;664.7341063574609;678.5521555773166;5366.651167674088;5540.060978657518;5570.582206334541;5584.46947539983;5593.692346920959;102690;106516.9348306507;107619.8476030475;108196.45901770599;109687;94929;95425.045749543;95762.08475830444;96763;98258;73043;86566;87456.37630871084;87773.78375270632;88132;11884.049276058067;12236.388180590757;12380.442880070239;12682.429290682127;12802.295873727035;38954.4156837642;39439.80280098581;39685.60314396819;40842.387364188966;41403.7929810349;52506;52626;52767.73616131895;69535.65232173807;70287.59424811373;16419.01485910849;17145.314187432363;17215.31138754436;17234;17268.309267629153;17958.92246465216;18297;18684.439466816017;18768.87386756799;18899;13886.652833679238;14482.927585362006;14711.852881471477;15645.452409165624;17293.65412691714;15997;17013.48959531216;17101.57246068858;17217.74173387375;17239.482815515556;15964.201789910227;16126;16713.247903844112;16740.832591673927;16946;9460.621575137193;9923.950380248052;10542.578296868347;11026.228164028762;11928;11375.601853935405;12669.619911402673;13151.408186631816;13429.261390623218;13610.319484025957 14:38:45 INFO - PID 1957 | __end_tp_report 14:38:45 INFO - PID 1957 | __start_cc_report 14:38:45 INFO - PID 1957 | _x_x_mozilla_cycle_collect,409 14:38:45 INFO - PID 1957 | __end_cc_report 14:38:45 INFO - PID 1957 | __startTimestamp1494365925562__endTimestamp 14:38:45 INFO - PID 1957 | 14:38:45 INFO - PID 1957 | ------- Summary: start ------- 14:38:45 INFO - PID 1957 | Number of tests: 6 14:38:45 INFO - PID 1957 | 14:38:45 INFO - PID 1957 | [#0] dojo.html Cycles:165 Average:6104.96 Median:6381.04 stddev:773.41 (12.1%) stddev-sans-first:749.53 14:38:45 INFO - PID 1957 | Values: 3558.0 3571.3 3599.5 3601.2 3611.5 5420.0 5578.6 5717.1 5848.2 6144.2 6156.2 6174.9 6201.5 6229.1 6285.1 4619.7 4981.6 5231.5 5418.4 5425.5 5234.1 5454.5 5542.3 5544.0 5548.6 5280.2 5365.0 5443.6 5448.6 5467.3 5441.8 5443.0 5463.5 5465.5 5481.7 5354.5 5384.0 5404.7 5415.5 5429.0 5891.9 6058.0 6136.6 6151.9 6179.7 6534.5 6628.1 6633.4 6633.8 6653.4 6775.2 6792.9 6815.0 6815.2 6820.0 6179.5 6287.3 6323.5 6337.5 6349.0 6764.3 6825.7 6947.6 6963.3 6985.5 6295.1 6381.0 6397.1 6560.3 6619.8 5352.4 6644.9 6683.9 6776.6 6785.8 6665.5 6669.3 6675.7 6690.9 6692.2 6409.7 6472.4 6497.4 6548.5 6694.7 4501.5 4714.4 5345.5 5369.1 5389.4 3952.7 4684.0 4687.9 4720.4 4760.2 6176.8 6395.1 6400.4 6402.0 6404.6 6681.6 6722.6 6729.5 6754.2 6786.4 5355.5 6324.9 6355.4 6379.1 6440.2 5013.5 5904.8 5914.3 5925.6 5972.8 6699.9 6785.9 6836.7 6913.6 6951.3 6775.8 6786.1 6812.1 6833.5 6834.2 6797.7 6966.3 6974.1 6988.0 6992.9 5431.4 5936.3 6423.9 6640.9 6658.3 5575.7 6678.4 6766.4 6794.7 6801.3 6264.7 6381.2 6542.2 6566.2 6581.0 6375.6 6458.9 6557.6 6583.1 6593.4 6210.5 6249.8 6327.8 6339.6 6358.5 6435.5 6506.4 6515.6 6621.3 6625.9 6455.2 6476.5 6487.4 6501.6 6502.0 14:38:45 INFO - PID 1957 | 14:38:45 INFO - PID 1957 | [#1] ext.html Cycles:180 Average:46234.49 Median:19359.05 stddev:79720.54 (411.8%) stddev-sans-first:79870.79 14:38:45 INFO - PID 1957 | Values: 675.8 743.6 825.5 1206.6 1258.7 34392.5 35111.6 37057.6 38235.2 38351.2 38208.6 38420.0 38469.4 38488.6 38541.2 7791.7 7944.6 8345.1 8510.1 8550.6 8549.9 8623.9 8633.0 8634.6 8675.0 8608.0 8619.7 8664.6 8680.7 8691.7 8614.7 8640.7 8650.6 8651.0 8652.9 8358.5 8405.2 8413.9 8436.6 8451.2 37869.4 37872.8 37874.0 37882.0 37957.8 3606.5 3619.9 3648.3 3650.2 3657.5 30692.0 31024.5 31056.4 31096.9 31112.5 3063.4 3131.6 3131.7 3143.2 3170.8 297500.0 297683.0 297740.0 309116.0 310944.0 38295.8 43030.1 43675.3 47795.0 47899.0 44528.3 45701.9 50387.5 50538.7 55208.0 14725.3 14890.4 14908.5 14920.0 14962.4 11878.9 11976.7 11982.9 11984.7 11992.4 13998.4 14010.3 14050.4 14074.7 14085.6 5206.5 6316.5 6321.5 6385.2 6396.6 947.9 985.7 988.3 998.7 1077.5 23296.8 23773.5 24671.9 24700.2 24958.8 1113.2 1114.9 1118.8 1125.1 1153.1 11288.8 12824.0 13544.3 15930.4 16797.7 296625.5 307029.9 330290.0 331071.0 332383.0 56621.0 56684.7 57129.4 57193.1 57219.1 1472.9 1492.2 1524.6 1524.9 1549.6 26829.3 29584.1 30197.2 30510.0 31297.8 44415.6 53835.5 54490.0 54577.7 54737.5 287537.0 288217.0 290776.0 291119.0 291166.5 30190.9 36190.5 36925.3 37281.8 37356.6 44280.3 45519.8 46655.8 47010.0 47071.3 30203.8 31075.4 31491.4 31728.2 31949.5 25593.1 31037.7 31582.1 31642.0 31743.5 37318.3 38441.0 38560.4 38586.0 38600.1 25105.2 30541.0 32275.0 32320.0 32397.7 22318.6 25090.4 25249.7 25485.9 25722.4 14:38:45 INFO - PID 1957 | 14:38:45 INFO - PID 1957 | [#2] jquery.html Cycles:180 Average:35419.89 Median:12588.30 stddev:90716.90 (720.6%) stddev-sans-first:90933.31 14:38:45 INFO - PID 1957 | Values: 411.7 426.6 430.6 441.2 445.6 20691.7 21537.6 21566.0 21976.0 22202.4 21644.0 22628.0 22695.7 22887.5 23041.2 5890.8 6048.7 6122.4 6143.4 6148.6 5841.6 5974.3 5975.6 6042.5 6110.7 6030.5 6033.3 6075.3 6078.7 6084.6 5815.8 6014.5 6018.0 6020.2 6041.2 5312.7 5319.9 5340.3 5370.9 5380.4 26526.7 26723.0 27027.3 27052.0 27079.9 2212.2 2286.5 2289.1 2289.5 2294.9 16998.0 17262.3 17553.0 18020.3 18070.3 2115.8 2142.2 2152.1 2167.4 2169.3 139496.3 148755.0 162422.2 165855.0 166878.0 9720.4 12500.8 12504.7 12530.7 12538.2 13634.5 16212.8 16330.6 16363.5 16398.4 7028.9 8701.6 9048.3 9051.0 9112.5 6994.0 8505.5 8643.6 8798.9 8864.7 7877.3 7955.8 8010.5 8030.3 8072.3 2140.3 2157.2 2158.1 2175.7 2372.4 995.7 999.9 1000.4 1028.5 1035.8 15514.8 20440.5 21296.7 21398.4 22507.7 890.3 1032.0 1035.3 1046.3 1063.8 10492.9 10559.4 11197.0 11253.5 11263.3 459480.0 543407.0 546795.0 548931.0 549494.0 133355.0 136145.3 137141.3 141687.0 141928.0 1209.3 1231.0 1263.7 1264.8 1268.7 21942.6 22930.7 23158.0 23373.2 23387.8 50863.7 52133.7 59124.0 61297.0 61821.8 76239.0 77727.0 79008.6 81002.0 81188.6 8923.6 8974.6 9005.5 9077.5 9114.0 12341.6 14029.5 14428.4 14727.3 14783.6 9999.0 11755.8 11912.8 11999.8 12287.1 11087.9 11469.0 11477.4 11767.2 11795.2 12025.0 12032.5 12099.4 12344.7 12358.4 8920.8 9013.3 9081.4 9098.5 9107.3 11624.5 12010.4 12072.5 12129.0 12323.1 14:38:45 INFO - PID 1957 | 14:38:45 INFO - PID 1957 | [#3] mootools.html Cycles:180 Average:6988.39 Median:3961.12 stddev:4385.55 (110.7%) stddev-sans-first:4376.76 14:38:45 INFO - PID 1957 | Values: 1264.7 1301.8 1440.3 1455.0 1481.8 11160.9 11549.7 11610.9 11676.5 11701.1 11460.5 11632.8 11656.8 11740.3 11750.2 2516.0 2560.2 2565.5 2566.7 2574.5 2480.2 2530.4 2535.3 2535.7 2542.6 2187.9 2492.1 2514.6 2524.1 2545.9 2496.5 2515.6 2522.7 2535.9 2548.0 2035.9 2358.8 2485.4 2497.1 2497.2 11560.1 11631.5 11633.2 11640.7 11643.5 6576.6 6780.4 6799.9 6836.8 6846.6 4049.9 4367.3 4428.0 4469.3 4480.1 1038.9 1067.5 1184.3 1200.3 1209.1 14321.4 15507.7 16182.5 16297.0 16320.6 5513.4 5518.2 5703.5 5704.1 5808.9 9863.5 11745.9 11796.8 11873.9 11966.2 6564.0 6586.4 6589.7 6604.0 6611.7 6050.0 6078.5 6168.3 6202.1 6241.4 4292.9 4382.4 4488.9 4521.3 4535.6 1270.3 1284.9 1287.9 1301.1 1303.3 795.2 800.8 804.4 806.4 835.8 6069.2 6094.0 6306.6 6323.0 6333.2 591.9 598.2 612.5 615.5 620.9 3785.9 3893.4 4028.8 4301.4 4335.3 14231.8 14791.6 14884.7 14918.0 14929.4 14391.9 14548.6 14765.6 14789.7 14825.9 8591.2 8606.5 8640.7 8643.2 8658.9 5170.4 6008.7 6938.8 7038.2 7135.6 12503.6 12509.2 12519.9 12560.4 12765.7 12753.5 13422.7 13562.4 13677.0 13828.2 8386.8 8436.7 8442.5 8471.7 8482.7 10589.9 10698.9 10814.0 10840.8 10867.8 9024.3 9136.5 9155.4 9230.0 9271.4 7659.8 8882.2 9029.9 9073.0 9094.1 6877.4 8158.0 8175.0 8180.1 8307.6 6012.4 6080.1 6139.9 6144.6 6177.2 5375.8 6062.6 6066.9 6182.6 6237.5 14:38:45 INFO - PID 1957 | 14:38:45 INFO - PID 1957 | [#4] prototype.html Cycles:180 Average:5350.13 Median:6109.23 stddev:1753.89 (28.7%) stddev-sans-first:1718.49 14:38:45 INFO - PID 1957 | Values: 368.2 401.6 407.2 412.7 413.5 4581.2 5306.4 5902.6 6181.9 6458.9 4592.5 6206.9 6228.4 6246.1 6258.0 3655.9 4560.6 4588.8 4595.5 4611.2 3801.3 4556.7 4575.4 4590.9 4628.0 3654.4 4129.9 4152.1 4262.7 4585.5 3548.8 3963.8 4144.8 4329.6 4401.0 3227.9 3352.1 3867.0 3883.1 3901.9 5661.5 6109.6 6138.0 6150.3 6170.8 4923.5 5128.0 5358.8 5391.8 5481.3 6114.4 6157.1 6709.3 6727.4 6754.6 3673.0 4167.7 4413.6 4434.8 4439.0 6262.9 6659.9 6662.3 7105.9 7237.4 4048.1 4387.2 4506.9 4520.8 4538.4 4590.8 4603.3 4604.9 4609.2 4612.9 4491.9 5325.1 5490.9 5497.8 5504.2 5905.9 6681.0 6779.0 6817.4 6831.4 1536.4 1561.0 1562.3 1566.1 1576.4 938.9 990.1 1009.8 1019.2 1026.3 5697.1 6138.8 6193.6 6465.1 6497.5 6735.4 6885.4 6904.3 6987.9 7037.9 5501.3 6313.1 6474.3 6487.4 6526.6 3348.3 3502.5 3659.0 3662.8 3678.7 5721.9 6625.9 7243.5 7303.6 7335.7 7077.7 7122.6 7198.7 7209.9 7230.7 5981.8 6230.7 7204.5 7238.4 7263.5 6108.8 6758.2 6968.0 7002.7 7060.9 6590.3 6759.1 6762.6 6875.1 6908.6 6687.0 6753.6 7026.8 7327.4 7379.4 3852.4 4077.7 4123.5 4529.8 4559.8 5629.3 6781.7 7051.3 7059.2 7119.7 6487.3 6841.5 6843.2 6859.9 6865.2 5571.8 5835.1 5901.2 6670.8 6712.5 6526.9 6703.2 6735.6 6739.8 6751.0 5243.4 5945.0 6860.0 6862.6 6885.0 5336.7 6628.3 6671.3 6698.0 6707.0 14:38:45 INFO - PID 1957 | 14:38:45 INFO - PID 1957 | [#5] yui.html Cycles:180 Average:22162.41 Median:18258.86 stddev:31341.61 (171.7%) stddev-sans-first:31388.15 14:38:45 INFO - PID 1957 | Values: 712.7 809.2 829.9 837.3 851.6 14252.3 15835.9 16854.7 18220.7 19303.5 17727.1 18131.6 18301.5 18567.5 19162.7 975.5 1000.5 1008.6 1015.1 1015.8 974.1 998.8 1022.9 1029.2 1031.2 1060.2 1071.7 1072.8 1074.0 1075.0 1053.9 1063.2 1064.3 1064.7 1072.3 770.7 781.7 793.6 794.4 796.7 16705.3 16858.9 16903.4 17318.2 17474.3 17048.7 17090.7 17394.7 17426.5 17606.2 13915.0 14022.9 14236.3 14269.6 14356.4 3508.4 3510.9 3533.7 3534.2 3544.7 122585.0 123256.4 123288.0 124822.8 125809.0 11614.5 13448.2 13472.7 13630.6 13779.0 24649.5 24676.9 25254.6 25802.6 26412.3 6536.0 7984.4 7987.7 8001.0 8038.2 3887.7 3935.9 3976.7 3988.7 4005.0 3270.8 3739.5 4256.5 4436.8 4502.8 806.2 917.9 973.7 981.5 981.7 636.4 668.8 672.9 681.2 699.1 9309.6 11329.6 11811.8 12188.1 12274.6 655.6 658.4 658.7 664.7 678.6 5366.7 5540.1 5570.6 5584.5 5593.7 102690.0 106516.9 107619.8 108196.5 109687.0 94929.0 95425.0 95762.1 96763.0 98258.0 73043.0 86566.0 87456.4 87773.8 88132.0 11884.0 12236.4 12380.4 12682.4 12802.3 38954.4 39439.8 39685.6 40842.4 41403.8 52506.0 52626.0 52767.7 69535.7 70287.6 16419.0 17145.3 17215.3 17234.0 17268.3 17958.9 18297.0 18684.4 18768.9 18899.0 13886.7 14482.9 14711.9 15645.5 17293.7 15997.0 17013.5 17101.6 17217.7 17239.5 15964.2 16126.0 16713.2 16740.8 16946.0 9460.6 9924.0 10542.6 11026.2 11928.0 11375.6 12669.6 13151.4 13429.3 13610.3 14:38:45 INFO - PID 1957 | -------- Summary: end -------- 14:38:45 INFO - PID 1957 | 14:38:46 INFO - TEST-INFO | 1957: exit 0 14:38:46 INFO - TEST-OK | dromaeo_css | took 1114425ms 14:38:46 INFO - TEST-START | kraken 14:38:46 INFO - operating with platform_type : mac_ 14:38:46 INFO - Initialising browser for kraken test... 14:38:46 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmprOHnWZ/profile http://localhost:49518/getInfo.html) 14:38:48 INFO - PID 1966 | 2017-05-09 14:38:48.629 plugin-container[1967:15388] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 14:38:48 INFO - PID 1966 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:48 INFO - PID 1966 | 2017-05-09 14:38:48.632 plugin-container[1967:15388] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 14:38:48 INFO - PID 1966 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:48 INFO - PID 1966 | 2017-05-09 14:38:48.632 plugin-container[1967:15388] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:38:48 INFO - PID 1966 | __metrics Screen width/height:1600/1200 14:38:48 INFO - PID 1966 | colorDepth:24 14:38:48 INFO - PID 1966 | Browser inner width/height: 1024/691 14:38:48 INFO - PID 1966 | __metrics 14:38:49 INFO - TEST-INFO | 1966: exit 0 14:38:49 INFO - Browser initialized. 14:38:49 INFO - Running cycle 1/1 for kraken test... 14:38:49 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/T/tmprOHnWZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:51 INFO - PID 1969 | 2017-05-09 14:38:51.908 plugin-container[1970:15552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 14:38:51 INFO - PID 1969 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:51 INFO - PID 1969 | 2017-05-09 14:38:51.909 plugin-container[1970:15552] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 14:38:51 INFO - PID 1969 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:51 INFO - PID 1969 | 2017-05-09 14:38:51.909 plugin-container[1970:15552] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:38:52 INFO - PID 1969 | 2017-05-09 14:38:52.450 plugin-container[1971:15595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x913f, name = 'com.apple.tsm.portname' 14:38:52 INFO - PID 1969 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:52 INFO - PID 1969 | 2017-05-09 14:38:52.451 plugin-container[1971:15595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 14:38:52 INFO - PID 1969 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:52 INFO - PID 1969 | 2017-05-09 14:38:52.451 plugin-container[1971:15595] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:39:01 INFO - PID 1969 | RSS: Main: 229134336 14:39:01 INFO - PID 1969 | 14:39:04 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49518/tests/kraken/driver.html?testName=audio-beat-detection) 14:39:05 INFO - PID 1969 | RSS: Main: 242040832 14:39:05 INFO - PID 1969 | 14:39:07 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49518/tests/kraken/driver.html?testName=audio-dft) 14:39:08 INFO - PID 1969 | RSS: Main: 250179584 14:39:08 INFO - PID 1969 | 14:39:12 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49518/tests/kraken/driver.html?testName=audio-fft) 14:39:12 INFO - PID 1969 | RSS: Main: 252997632 14:39:12 INFO - PID 1969 | 14:39:14 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49518/tests/kraken/driver.html?testName=audio-oscillator) 14:39:15 INFO - PID 1969 | RSS: Main: 253845504 14:39:15 INFO - PID 1969 | 14:39:17 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49518/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:39:17 INFO - PID 1969 | RSS: Main: 255537152 14:39:17 INFO - PID 1969 | 14:39:20 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49518/tests/kraken/driver.html?testName=imaging-darkroom) 14:39:21 INFO - PID 1969 | RSS: Main: 261820416 14:39:21 INFO - PID 1969 | 14:39:24 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49518/tests/kraken/driver.html?testName=imaging-desaturate) 14:39:25 INFO - PID 1969 | RSS: Main: 263192576 14:39:25 INFO - PID 1969 | 14:39:28 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49518/tests/kraken/driver.html?testName=json-parse-financial) 14:39:28 INFO - PID 1969 | RSS: Main: 262311936 14:39:28 INFO - PID 1969 | 14:39:30 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49518/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:39:30 INFO - PID 1969 | RSS: Main: 262115328 14:39:30 INFO - PID 1969 | 14:39:32 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49518/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:39:33 INFO - PID 1969 | RSS: Main: 262291456 14:39:33 INFO - PID 1969 | 14:39:35 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49518/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:39:35 INFO - PID 1969 | RSS: Main: 261804032 14:39:35 INFO - PID 1969 | 14:39:38 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49518/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:39:39 INFO - PID 1969 | RSS: Main: 261660672 14:39:39 INFO - PID 1969 | 14:39:42 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49518/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:39:43 INFO - PID 1969 | RSS: Main: 261988352 14:39:43 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | Cycle 1(1): loaded http://localhost:49518/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49518/tests/kraken/driver.html?testName=ai-astar) 14:39:45 INFO - PID 1969 | RSS: Main: 263569408 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | __start_tp_report 14:39:45 INFO - PID 1969 | _x_x_mozilla_page_load 14:39:45 INFO - PID 1969 | _x_x_mozilla_page_load_details 14:39:45 INFO - PID 1969 | |i|pagename|runs| 14:39:45 INFO - PID 1969 | |0;ai-astar;102;114;114;114;121;113;114;115;115;114 14:39:45 INFO - PID 1969 | |1;audio-beat-detection;132;108;113;135;135;145;145;137;135;107 14:39:45 INFO - PID 1969 | |2;audio-dft;207;194;260;276;223;218;196;204;196;197 14:39:45 INFO - PID 1969 | |3;audio-fft;80;65;83;73;58;79;59;47;72;69 14:39:45 INFO - PID 1969 | |4;audio-oscillator;95;78;91;63;70;98;113;109;103;90 14:39:45 INFO - PID 1969 | |5;imaging-gaussian-blur;87;89;90;90;111;88;88;103;88;88 14:39:45 INFO - PID 1969 | |6;imaging-darkroom;95;107;96;96;96;95;122;135;100;146 14:39:45 INFO - PID 1969 | |7;imaging-desaturate;91;94;91;92;94;92;87;93;91;92 14:39:45 INFO - PID 1969 | |8;json-parse-financial;50;51;49;51;49;52;51;49;49;51 14:39:45 INFO - PID 1969 | |9;json-stringify-tinderbox;58;57;61;58;66;62;58;57;58;58 14:39:45 INFO - PID 1969 | |10;stanford-crypto-aes;81;83;83;82;84;83;82;85;84;86 14:39:45 INFO - PID 1969 | |11;stanford-crypto-ccm;147;142;138;141;138;134;148;140;221;160 14:39:45 INFO - PID 1969 | |12;stanford-crypto-pbkdf2;186;223;235;238;196;234;190;185;174;181 14:39:45 INFO - PID 1969 | |13;stanford-crypto-sha256-iterative;73;88;95;103;101;93;70;69;68;71 14:39:45 INFO - PID 1969 | __end_tp_report 14:39:45 INFO - PID 1969 | __start_cc_report 14:39:45 INFO - PID 1969 | _x_x_mozilla_cycle_collect,1020 14:39:45 INFO - PID 1969 | __end_cc_report 14:39:45 INFO - PID 1969 | __startTimestamp1494365985595__endTimestamp 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | ------- Summary: start ------- 14:39:45 INFO - PID 1969 | Number of tests: 14 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:4.65 (4.1%) stddev-sans-first:2.37 14:39:45 INFO - PID 1969 | Values: 102.0 114.0 114.0 114.0 121.0 113.0 114.0 115.0 115.0 114.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:135.00 stddev:14.43 (10.7%) stddev-sans-first:15.27 14:39:45 INFO - PID 1969 | Values: 132.0 108.0 113.0 135.0 135.0 145.0 145.0 137.0 135.0 107.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#2] audio-dft Cycles:10 Average:217.10 Median:212.50 stddev:28.77 (13.5%) stddev-sans-first:30.28 14:39:45 INFO - PID 1969 | Values: 207.0 194.0 260.0 276.0 223.0 218.0 196.0 204.0 196.0 197.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#3] audio-fft Cycles:10 Average:68.50 Median:72.50 stddev:11.36 (15.7%) stddev-sans-first:11.26 14:39:45 INFO - PID 1969 | Values: 80.0 65.0 83.0 73.0 58.0 79.0 59.0 47.0 72.0 69.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:84.00 stddev:16.37 (19.5%) stddev-sans-first:17.30 14:39:45 INFO - PID 1969 | Values: 95.0 78.0 91.0 63.0 70.0 98.0 113.0 109.0 103.0 90.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:88.00 stddev:8.08 (9.2%) stddev-sans-first:8.35 14:39:45 INFO - PID 1969 | Values: 87.0 89.0 90.0 90.0 111.0 88.0 88.0 103.0 88.0 88.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#6] imaging-darkroom Cycles:10 Average:108.80 Median:95.00 stddev:18.85 (19.8%) stddev-sans-first:19.32 14:39:45 INFO - PID 1969 | Values: 95.0 107.0 96.0 96.0 96.0 95.0 122.0 135.0 100.0 146.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:92.00 stddev:2.00 (2.2%) stddev-sans-first:2.11 14:39:45 INFO - PID 1969 | Values: 91.0 94.0 91.0 92.0 94.0 92.0 87.0 93.0 91.0 92.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#8] json-parse-financial Cycles:10 Average:50.20 Median:51.00 stddev:1.14 (2.2%) stddev-sans-first:1.20 14:39:45 INFO - PID 1969 | Values: 50.0 51.0 49.0 51.0 49.0 52.0 51.0 49.0 49.0 51.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:58.00 stddev:2.87 (4.9%) stddev-sans-first:3.00 14:39:45 INFO - PID 1969 | Values: 58.0 57.0 61.0 58.0 66.0 62.0 58.0 57.0 58.0 58.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:83.50 stddev:1.49 (1.8%) stddev-sans-first:1.33 14:39:45 INFO - PID 1969 | Values: 81.0 83.0 83.0 82.0 84.0 83.0 82.0 85.0 84.0 86.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#11] stanford-crypto-ccm Cycles:10 Average:150.90 Median:144.50 stddev:25.68 (17.8%) stddev-sans-first:27.20 14:39:45 INFO - PID 1969 | Values: 147.0 142.0 138.0 141.0 138.0 134.0 148.0 140.0 221.0 160.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:204.20 Median:209.50 stddev:25.28 (12.1%) stddev-sans-first:25.94 14:39:45 INFO - PID 1969 | Values: 186.0 223.0 235.0 238.0 196.0 234.0 190.0 185.0 174.0 181.0 14:39:45 INFO - PID 1969 | 14:39:45 INFO - PID 1969 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.10 Median:72.00 stddev:14.25 (19.8%) stddev-sans-first:14.64 14:39:45 INFO - PID 1969 | Values: 73.0 88.0 95.0 103.0 101.0 93.0 70.0 69.0 68.0 71.0 14:39:45 INFO - PID 1969 | -------- Summary: end -------- 14:39:45 INFO - PID 1969 | 14:39:46 INFO - TEST-INFO | 1969: exit 0 14:39:46 INFO - TEST-OK | kraken | took 60387ms 14:39:46 INFO - SUITE-END | took 1174s 14:39:46 INFO - Completed test suite (00:19:34) 14:39:46 INFO - javascript benchmark 14:39:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7909.1288949140135, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3558.0037589474955, 3571.3214489247043, 3599.4780756790265, 3601.153728873716, 3611.5124458198143, 5420.0, 5578.609497335188, 5717.085266357383, 5848.23972883525, 6144.170536977506, 6156.168917196188, 6174.9382506174925, 6201.503879689625, 6229.127922090911, 6285.120083188357, 4619.653525985547, 4981.601471882241, 5231.529162375386, 5418.376886658026, 5425.457454254562, 5234.136367499358, 5454.4545545445535, 5542.251796007527, 5544.0, 5548.55611551077, 5280.1815718563685, 5365.0, 5443.591730620219, 5448.591355648323, 5467.343918729738, 5441.809536666237, 5443.047466693313, 5463.508284254396, 5465.480779325958, 5481.698506582136, 5354.5180933715765, 5383.973080134574, 5404.729763511848, 5415.48552887475, 5428.972855135699, 5891.911621325684, 6057.969710151421, 6136.641621527162, 6151.907721384138, 6179.721912513947, 6534.54258201926, 6628.072069910217, 6633.402993730588, 6633.834154146137, 6653.4344580710695, 6775.2208496023095, 6792.898106528406, 6815.045893574904, 6815.216250131249, 6820.04519367289, 6179.536534759911, 6287.339829317856, 6323.494120470351, 6337.461315788163, 6349.0, 6764.255931847493, 6825.69284382204, 6947.617881016593, 6963.303669632996, 6985.511014229057, 6295.087212354183, 6381.042843573408, 6397.072424498515, 6560.278369379364, 6619.834504137339, 5352.357717073898, 6644.8671026579195, 6683.866322673519, 6776.593404395653, 6785.796426107225, 6665.533412661164, 6669.299723529057, 6675.732970681119, 6690.866182676319, 6692.19693636757, 6409.67951602418, 6472.417482426604, 6497.4477169441125, 6548.476121910236, 6694.665266736643, 4501.549845015472, 4714.38712967312, 5345.518903298722, 5369.140937449989, 5389.380221274525, 3952.66402355803, 4683.99294151759, 4687.929681054787, 4720.362751028567, 4760.238361862085, 6176.752929882844, 6395.072714456378, 6400.391962763623, 6402.0, 6404.647744374104, 6681.632510211888, 6722.630255336004, 6729.528932974743, 6754.189497260262, 6786.423154031962, 5355.491228333302, 6324.873502529924, 6355.428011478896, 6379.138816259839, 6440.162778838815, 5013.473585273567, 5904.822855314347, 5914.290285165807, 5925.644461332334, 5972.820815375459, 6699.933000670028, 6785.864282714417, 6836.692348844214, 6913.619750913748, 6951.339622735832, 6775.762848300285, 6786.083878676315, 6812.114425124702, 6833.45332373399, 6834.248232694499, 6797.708435397259, 6966.268541803241, 6974.093367862146, 6987.965060174666, 6992.895106573507, 5431.4296998816135, 5936.3470018297085, 6423.9036414452885, 6640.900386494303, 6658.267590565131, 5575.721213939368, 6678.432333251728, 6766.391024807791, 6794.72821087151, 6801.319868013159, 6264.749410023548, 6381.1704478417505, 6542.182227221598, 6566.244881838458, 6581.0, 6375.6174629522375, 6458.870822583428, 6557.639329836905, 6583.144191255106, 6593.406593406617, 6210.503159747119, 6249.750009999549, 6327.810165695037, 6339.619622822645, 6358.459530939922, 6435.549511534147, 6506.41442270198, 6515.609063456208, 6621.27166011748, 6625.933740662725, 6455.160829092188, 6476.5466417350335, 6487.416132548094, 6501.609903405811, 6501.9674901625185], "value": 6052.6248644826455, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.8242856857217, 743.5798773692867, 825.5129473610571, 1206.607852447954, 1258.6790368456043, 34392.484112738326, 35111.59553617859, 37057.62942370575, 38235.23529529408, 38351.23297534048, 38208.61791382085, 38420.039499012535, 38469.422958655574, 38488.61511384886, 38541.229175416476, 7791.727289544853, 7944.563049032301, 8345.123762004992, 8510.106438823927, 8550.61522231498, 8549.871751923725, 8623.870641940375, 8632.956835215846, 8634.568271586428, 8675.045744968049, 8608.0, 8619.741407757778, 8664.61009254582, 8680.739577812645, 8691.695790647329, 8614.698485553006, 8640.697575584887, 8650.567471626457, 8650.956745216297, 8652.8702069469, 8358.498490090553, 8405.159484051606, 8413.915860841436, 8436.578171091482, 8451.23938845507, 37869.43195852037, 37872.81063594692, 37874.0, 37882.0, 37957.81021094904, 3606.5131207286945, 3619.855205791765, 3648.3068217038676, 3650.1787097903025, 3657.487951686766, 30692.0, 31024.534631980314, 31056.37887242265, 31096.911608093833, 31112.533312000338, 3063.356695094033, 3131.5928929239053, 3131.7181453669277, 3143.151349135743, 3170.7621928355234, 297500.0, 297683.0, 297740.0225997756, 309116.0, 310944.0, 38295.80852095722, 43030.13939721188, 43675.34486982698, 47795.044099117826, 47899.0, 44528.3320750189, 45701.857453563265, 50387.496125038284, 50538.747306263234, 55208.0, 14725.337359818834, 14890.404383824742, 14908.478203262834, 14920.0, 14962.401503939938, 11878.88121118795, 11976.700582485333, 11982.940085299517, 11984.700382490333, 11992.40037998114, 13998.440062397594, 14010.299485025707, 14050.437982480586, 14074.71850562983, 14085.577432677037, 5206.453322401169, 6316.526260530479, 6321.462675672527, 6385.201849768779, 6396.552241343062, 947.9052094790466, 985.6747273399761, 988.3032462114193, 998.7353351361895, 1077.4558847781977, 23296.76703232946, 23773.454725442793, 24671.863912225966, 24700.173310225302, 24958.750412496007, 1113.2207454781685, 1114.8717897441898, 1118.8433619293305, 1125.0661950580998, 1153.135148638521, 11288.830667539993, 12823.974082073411, 13544.322783860767, 15930.36278548845, 16797.664046718997, 296625.5168724143, 307029.92970070016, 330290.0, 331071.0, 332383.0, 56621.0, 56684.716576417675, 57129.42870571324, 57193.14210286849, 57219.14171287434, 1472.8968972171845, 1492.2165862922052, 1524.6493306539558, 1524.8703860171788, 1549.6435819761293, 26829.329266768487, 29584.112476625734, 30197.245068873453, 30510.0, 31297.8435107823, 44415.55584444114, 53835.461645383046, 54490.0, 54577.72711136419, 54737.452625473234, 287537.0, 288217.0, 290776.0, 291119.0, 291166.5441672863, 30190.9433169838, 36190.4571431434, 36925.26149477049, 37281.81359093187, 37356.62643373531, 44280.33579496374, 45519.77240113778, 46655.766721166176, 47010.0, 47071.293930590386, 30203.848980754956, 31075.37849243048, 31491.37017259688, 31728.20679482939, 31949.520757189126, 25593.104241351462, 31037.67217458745, 31582.052538423883, 31642.0, 31743.50821409689, 37318.25363492769, 38441.0, 38560.42159367556, 38586.03534911649, 38600.0699964995, 25105.24684259475, 30541.014834035715, 32275.0, 32320.030399088064, 32397.676023239466, 22318.553628927657, 25090.37274068163, 25249.74750252474, 25485.872570637028, 25722.35694107662], "value": 18053.034538986714, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.69534544437107, 426.5734265734264, 430.6059955141046, 441.22344673374863, 445.61454341994164, 20691.689624655624, 21537.56924861502, 21566.02952867121, 21976.0, 22202.444938876535, 21644.0, 22628.0, 22695.659565106496, 22887.542249155005, 23041.193558225466, 5890.823275301748, 6048.697565121748, 6122.387761223864, 6143.44708976192, 6148.647297594522, 5841.591088623798, 5974.312954010279, 5975.641461512323, 6042.456178943894, 6110.725017374207, 6030.457258846703, 6033.27600687918, 6075.270967483908, 6078.726457309432, 6084.574079814415, 5815.796447124351, 6014.488768454687, 6018.037114061773, 6020.217371741689, 6041.214642096521, 5312.707801070939, 5319.946800532022, 5340.305760251182, 5370.865728356784, 5380.381256155553, 26526.734732652618, 26723.0, 27027.324316892234, 27052.0, 27079.916803327844, 2212.1814928476565, 2286.474110954473, 2289.0614847912275, 2289.5420915816903, 2294.8737819419925, 16998.0, 17262.30950761968, 17553.0, 18020.279188832432, 18070.277188912427, 2115.8095771380495, 2142.153849229546, 2152.1499007891794, 2167.425632207466, 2169.3275084723787, 139496.30251848776, 148754.99999999892, 162422.1878890598, 165855.0, 166878.0, 9720.368176068532, 12500.812487812691, 12504.687382815502, 12530.749385012248, 12538.247705137721, 13634.454621815215, 16212.837871621134, 16330.591735206715, 16363.50909472694, 16398.360163983743, 7028.859422811515, 8701.608427620646, 9048.276137908952, 9050.954745226232, 9112.453252804853, 6994.0, 8505.489670619783, 8643.56782160902, 8798.86801697975, 8864.689735859214, 7877.251661092187, 7955.7613271600785, 8010.519368837889, 8030.277275045274, 8072.273495385443, 2140.3044010696585, 2157.2341818654468, 2158.093600687715, 2175.7389113306504, 2372.406898275431, 995.6714284286313, 999.8550210219478, 1000.3747657713928, 1028.5371582787775, 1035.761774791787, 15514.767278490832, 20440.488987775425, 21296.680549791767, 21398.358049258546, 22507.662385064235, 890.3233542507741, 1031.9690409287734, 1035.2753072849036, 1046.2788712567935, 1063.8297872340388, 10492.895071049192, 10559.366438013743, 11197.0, 11253.493592788343, 11263.324200547993, 459480.0, 543407.0, 546795.0, 548931.0, 549494.0, 133355.0, 136145.31927340498, 137141.3142934279, 141687.0, 141928.0, 1209.2865209526421, 1230.9815352769715, 1263.684078980255, 1264.7786637338613, 1268.7081971146688, 21942.561148776615, 22930.656040159076, 23158.0, 23373.181908747032, 23387.76612233856, 50863.72840679013, 52133.69665758387, 59124.0, 61297.0, 61821.76356472755, 76239.0, 77727.0, 79008.60495697484, 81002.0, 81188.59405702933, 8923.598438070172, 8974.596143173703, 9005.459672419676, 9077.45535267886, 9113.954430227806, 12341.568045118378, 14029.508967186099, 14428.350724217646, 14727.337269822669, 14783.556493305217, 9998.950005249928, 11755.764884702086, 11912.761744764883, 11999.820002699791, 12287.1399002069, 11087.889121108685, 11469.0, 11477.36874471912, 11767.23512971654, 11795.233309834834, 12025.037996960047, 12032.458539365574, 12099.395030248628, 12344.691382715144, 12358.382080896099, 8920.821583568422, 9013.324000700104, 9081.364304498886, 9098.454092754457, 9107.316951228817, 11624.476898539417, 12010.399480026139, 12072.45673944657, 12129.0, 12323.075769317516], "value": 10519.190105199557, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1264.6901509130264, 1301.7852054411023, 1440.2726623055362, 1455.0, 1481.7629179331302, 11160.888391116086, 11549.6535103947, 11610.941945290273, 11676.532938682443, 11701.122415818805, 11460.48427820748, 11632.767344653123, 11656.825147622792, 11740.29558226507, 11750.177487575851, 2515.9622605660884, 2560.1807421624994, 2565.4735432417683, 2566.653501777255, 2574.5108429398356, 2480.2063339731294, 2530.4053547416343, 2535.3408113890337, 2535.7083935347487, 2542.6313184588316, 2187.8687278763246, 2492.1277552856536, 2514.6479492871013, 2524.066095544651, 2545.9490810183784, 2496.463260399014, 2515.5723527000455, 2522.697276326834, 2535.8985640574538, 2547.961780573293, 2035.9287424940205, 2358.846674966139, 2485.428351479152, 2497.088562674629, 2497.1634502441702, 11560.075193984461, 11631.534738610446, 11633.243839150427, 11640.650780476599, 11643.53425862973, 6576.572522786004, 6780.355866192704, 6799.864002719918, 6836.829079273009, 6846.589204647687, 4049.8785036448953, 4367.279398899178, 4427.977860110679, 4469.329600559942, 4480.148771733393, 1038.9324693894873, 1067.4929408530963, 1184.2716729211559, 1200.2798321007413, 1209.1475509765683, 14321.35553900077, 15507.689846203013, 16182.514524564282, 16297.022178669318, 16320.591985200346, 5513.448655134454, 5518.227448157261, 5703.515201207861, 5704.11586204139, 5808.912866307009, 9863.506824658738, 11745.882541174478, 11796.764064718658, 11873.940630296793, 11966.222195557435, 6563.982582699594, 6586.4072233498255, 6589.703463344161, 6603.966980165068, 6611.669416529155, 6050.0, 6078.544109191828, 6168.290646575701, 6202.100695399232, 6241.407066328691, 4292.914141717149, 4382.364557139261, 4488.932666010012, 4521.253993091103, 4535.569120933506, 1270.2568997136593, 1284.9100562960502, 1287.8712128787047, 1301.128244076471, 1303.3418123847396, 795.1650766694947, 800.7628214408636, 804.3725893802844, 806.3992325717378, 835.849547081519, 6069.241344831896, 6093.964026115571, 6306.558540902093, 6323.008252400479, 6333.20834895638, 591.8727473593112, 598.2073752278249, 612.4946918791925, 615.5411674331862, 620.8789286089169, 3785.9432108518395, 3893.435451859521, 4028.798560071985, 4301.376300436482, 4335.349697545394, 14231.78652320216, 14791.556253312418, 14884.70230595382, 14918.0, 14929.40282388714, 14391.920605954596, 14548.563543093724, 14765.557033288804, 14789.704205915821, 14825.925870370795, 8591.226789588967, 8606.526641034803, 8640.697575584825, 8643.178898004806, 8658.874346334936, 5170.37955445349, 6008.729607167776, 6938.791836244921, 7038.190608079932, 7135.571865688075, 12503.624891253277, 12509.186902851285, 12519.93740031294, 12560.434780435085, 12765.680857978625, 12753.48986040585, 13422.664433389244, 13562.38969246406, 13677.0, 13828.239446830521, 8386.832263354576, 8436.662533498591, 8442.451240669337, 8471.745847624581, 8482.703105391281, 10589.894101058891, 10698.946505267424, 10814.026737593655, 10840.837387439036, 10867.836982445111, 9024.344140792251, 9136.543172841199, 9155.359124861196, 9229.953850230706, 9271.351005429826, 7659.770206893802, 8882.200601945857, 9029.916410030839, 9073.00196978345, 9094.090590940854, 6877.449804015767, 8158.021037475541, 8175.018997720312, 8180.100188979327, 8307.584620768817, 6012.428819262163, 6080.148779171008, 6139.877202455837, 6144.600600960924, 6177.1969643945995, 5375.784968601212, 6062.575619706578, 6066.908996364969, 6182.629042257479, 6237.500999919905], "value": 5256.473126550175, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.1716138687952, 401.6385253272057, 407.1571846278202, 412.70697804558773, 413.51825123730856, 4581.198290299201, 5306.416294207639, 5902.645841249528, 6181.938180618193, 6458.870822583545, 4592.4718657354415, 6206.906896396557, 6228.377162283769, 6246.063720246727, 6257.96743537317, 3655.872044478444, 4560.566746159117, 4588.820310352573, 4595.471520775103, 4611.169989401907, 3801.2967600993766, 4556.70381425208, 4575.382323386333, 4590.908181836361, 4628.033085938479, 3654.3604869147925, 4129.905575022622, 4152.107296931159, 4262.742490965157, 4585.541445855421, 3548.787072775616, 3963.7712309184103, 4144.813483393239, 4329.631981281595, 4401.031773009932, 3227.9031629051165, 3352.1116904020455, 3867.0, 3883.1262965832743, 3901.882943511699, 5661.5187709044785, 6109.63342199465, 6138.048602466625, 6150.2927163376335, 6170.81487555374, 4923.458419573844, 5128.0, 5358.839234822962, 5391.78432862685, 5481.287432633732, 6114.38856114385, 6157.107219453154, 6709.322669332667, 6727.428168605675, 6754.5947243165565, 3673.0, 4167.749935003879, 4413.624841888443, 4434.84478043267, 4438.977805110986, 6262.868565717142, 6659.901116315802, 6662.267150613428, 7105.89341159883, 7237.42100631943, 4048.10941592855, 4387.166438376698, 4506.887327816831, 4520.773961301922, 4538.364628951949, 4590.793414296365, 4603.332516785047, 4604.861854144381, 4609.1703493371515, 4612.930806037912, 4491.865244042684, 5325.147976323769, 5490.945090549043, 5497.835064948058, 5504.2294078829, 5905.911411328833, 6680.966595166993, 6778.983751816846, 6817.352351526597, 6831.438210670998, 1536.4391996921042, 1560.9843901561067, 1562.2813505787465, 1566.0994927916493, 1576.3615735627088, 938.9483578403117, 990.0891180114312, 1009.8030883977553, 1019.230480986865, 1026.3021145621049, 5697.116946873241, 6138.785142519991, 6193.628382297076, 6465.094886715863, 6497.5126865484335, 6735.393814556714, 6885.415321017945, 6904.275051119661, 6987.860242795217, 7037.929620703728, 5501.284832971688, 6313.116163737081, 6474.287828338879, 6487.448566871869, 6526.575772574767, 3348.313595712873, 3502.4571191465366, 3658.9817050914576, 3662.7985460799914, 3678.700811992505, 5721.942780572141, 6625.867482650417, 7243.456740744466, 7303.567323358013, 7335.7432489862595, 7077.681504332318, 7122.643867806589, 7198.712051517985, 7209.873087439218, 7230.66160303824, 5981.790637327673, 6230.719617617218, 7204.459665525002, 7238.420926325986, 7263.4915555910575, 6108.816735497942, 6758.155230596176, 6967.965160174167, 7002.70872689835, 7060.894086588808, 6590.275069742421, 6759.053732477359, 6762.594244345355, 6875.140607424072, 6908.585484870924, 6686.966565167143, 6753.5710718209575, 7026.837922934597, 7327.413806895543, 7379.372753316028, 3852.3643598805884, 4077.653399461079, 4123.505179378494, 4529.822246215942, 4559.77201139935, 5629.324481062299, 6781.728730850711, 7051.294870512907, 7059.223485416497, 7119.715211391694, 6487.318831522811, 6841.486888483483, 6843.178818541807, 6859.897101543381, 6865.176178858569, 5571.832845014494, 5835.095560188178, 5901.232839730979, 6670.83322916931, 6712.49656275791, 6526.9020964686515, 6703.229128650268, 6735.562188457735, 6739.831504212434, 6751.0, 5243.397009343822, 5944.989351810289, 6859.965700171467, 6862.632391824813, 6884.965575172092, 5336.67979921206, 6628.337166283526, 6671.299513551007, 6698.0, 6706.966465167643], "value": 4806.162963666082, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.6614857942477, 809.2150613904518, 829.8962629671291, 837.3468694418352, 851.6337974670892, 14252.287385630716, 15835.920820395897, 16854.662906741873, 18220.72668909964, 19303.517412064706, 17727.113644317797, 18131.637367252646, 18301.450956471326, 18567.535811604717, 19162.71255931162, 975.4683697384925, 1000.4897502273857, 1008.6419321141, 1015.0610685116275, 1015.8222311095566, 974.1086905481476, 998.8351921932872, 1022.8874823769379, 1029.1663752360575, 1031.242037102729, 1060.1995493402471, 1071.6892101290616, 1072.8498010278568, 1074.049466222391, 1074.962376316829, 1053.857729206555, 1063.2079101069676, 1064.281609913313, 1064.6539874540806, 1072.2654981337769, 770.7071312901118, 781.6677911887482, 793.5556088590408, 794.4240425691386, 796.6534557467462, 16705.331786728515, 16858.915705421517, 16903.40838070674, 17318.220680069302, 17474.301027958867, 17048.659026819394, 17090.658186836194, 17394.739078913826, 17426.47720568385, 17606.2077206526, 13915.0, 14022.92988535061, 14236.28818559068, 14269.643258918506, 14356.353964071643, 3508.4035713928693, 3510.877119300838, 3533.6819686228364, 3534.169470174507, 3544.6632569906074, 122585.0, 123256.38371808083, 123288.0, 124822.75177248292, 125809.0, 11614.477348519336, 13448.175883064667, 13472.663183420493, 13630.591082267549, 13779.0, 24649.507009860063, 24676.876615616806, 25254.621180682305, 25802.612960805604, 26412.339691507867, 6535.967320163464, 7984.361251099898, 7987.68049278034, 8001.0, 8038.236367545074, 3887.6695480883877, 3935.9212815743526, 3976.6818654508147, 3988.6809055275507, 4005.0187704012706, 3270.7898077710925, 3739.476473293741, 4256.489221293403, 4436.844710435119, 4502.8198872045405, 806.2058872011064, 917.8756023870704, 973.6981535723806, 981.4503877828439, 981.749653838281, 636.3795299582962, 668.7893313606198, 672.9427998620074, 681.1927865479423, 699.1295836683261, 9309.627614895464, 11329.564143709142, 11811.822822657667, 12188.085893557842, 12274.631761047183, 655.6492276631978, 658.3613894522306, 658.7463826426861, 664.7341063574609, 678.5521555773166, 5366.651167674088, 5540.060978657518, 5570.582206334541, 5584.46947539983, 5593.692346920959, 102690.0, 106516.9348306507, 107619.8476030475, 108196.45901770599, 109687.0, 94929.0, 95425.045749543, 95762.08475830444, 96763.0, 98258.0, 73043.0, 86566.0, 87456.37630871084, 87773.78375270632, 88132.0, 11884.049276058067, 12236.388180590757, 12380.442880070239, 12682.429290682127, 12802.295873727035, 38954.4156837642, 39439.80280098581, 39685.60314396819, 40842.387364188966, 41403.7929810349, 52506.0, 52626.0, 52767.73616131895, 69535.65232173807, 70287.59424811373, 16419.01485910849, 17145.314187432363, 17215.31138754436, 17234.0, 17268.309267629153, 17958.92246465216, 18297.0, 18684.439466816017, 18768.87386756799, 18899.0, 13886.652833679238, 14482.927585362006, 14711.852881471477, 15645.452409165624, 17293.65412691714, 15997.0, 17013.48959531216, 17101.57246068858, 17217.74173387375, 17239.482815515556, 15964.201789910227, 16126.0, 16713.247903844112, 16740.832591673927, 16946.0, 9460.621575137193, 9923.950380248052, 10542.578296868347, 11026.228164028762, 11928.0, 11375.601853935405, 12669.619911402673, 13151.408186631816, 13429.261390623218, 13610.319484025957], "value": 8428.893617923042, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1543.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 114.0, 114.0, 121.0, 113.0, 114.0, 115.0, 115.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 108.0, 113.0, 135.0, 135.0, 145.0, 145.0, 137.0, 135.0, 107.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 194.0, 260.0, 276.0, 223.0, 218.0, 196.0, 204.0, 196.0, 197.0], "value": 217.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 65.0, 83.0, 73.0, 58.0, 79.0, 59.0, 47.0, 72.0, 69.0], "value": 68.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 78.0, 91.0, 63.0, 70.0, 98.0, 113.0, 109.0, 103.0, 90.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 90.0, 90.0, 111.0, 88.0, 88.0, 103.0, 88.0, 88.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 107.0, 96.0, 96.0, 96.0, 95.0, 122.0, 135.0, 100.0, 146.0], "value": 108.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 91.0, 92.0, 94.0, 92.0, 87.0, 93.0, 91.0, 92.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 49.0, 51.0, 49.0, 52.0, 51.0, 49.0, 49.0, 51.0], "value": 50.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 61.0, 58.0, 66.0, 62.0, 58.0, 57.0, 58.0, 58.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 83.0, 82.0, 84.0, 83.0, 82.0, 85.0, 84.0, 86.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 142.0, 138.0, 141.0, 138.0, 134.0, 148.0, 140.0, 221.0, 160.0], "value": 150.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 223.0, 235.0, 238.0, 196.0, 234.0, 190.0, 185.0, 174.0, 181.0], "value": 204.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 88.0, 95.0, 103.0, 101.0, 93.0, 70.0, 69.0, 68.0, 71.0], "value": 83.1, "unit": "score"}]}]} 14:39:46 INFO - Return code: 0 14:39:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:39:46 INFO - # TBPL SUCCESS # 14:39:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:39:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:39:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:39:46 INFO - 2017-05-09 14:39:46.949 screenresolution[1976:15984] starting screenresolution argv=screenresolution get 14:39:46 INFO - 2017-05-09 14:39:46.954 screenresolution[1976:15984] Display 0: 1600x1200x32@60 14:39:46 INFO - 2017-05-09 14:39:46.960 screenresolution[1977:15987] starting screenresolution argv=screenresolution list 14:39:46 INFO - Available Modes on Display 0 14:39:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:39:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:39:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:39:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:39:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:39:47 INFO - Intel Iris: 14:39:47 INFO - Chipset Model: Intel Iris 14:39:47 INFO - Type: GPU 14:39:47 INFO - Bus: Built-In 14:39:47 INFO - VRAM (Dynamic, Max): 1536 MB 14:39:47 INFO - Vendor: Intel (0x8086) 14:39:47 INFO - Device ID: 0x0a2e 14:39:47 INFO - Revision ID: 0x0009 14:39:47 INFO - Displays: 14:39:47 INFO - Display: 14:39:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:39:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:39:47 INFO - Display Serial Number: 1600x1200 60 14:39:47 INFO - Main Display: Yes 14:39:47 INFO - Mirror: Off 14:39:47 INFO - Online: Yes 14:39:47 INFO - Rotation: Supported 14:39:47 INFO - Return code: 0 14:39:47 INFO - Running post-action listener: _resource_record_post_action 14:39:47 INFO - [mozharness: 2017-05-09 21:39:47.199506Z] Finished run-tests step (success) 14:39:47 INFO - Running post-run listener: _resource_record_post_run 14:39:47 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 2008576; Write bytes: 526769152; Read time: 20; Write time: 1622 14:39:47 INFO - TinderboxPrint: CPU usage
54.1% 14:39:47 INFO - TinderboxPrint: I/O read bytes / time
2,008,576 / 20 14:39:47 INFO - TinderboxPrint: I/O write bytes / time
526,769,152 / 1,622 14:39:47 INFO - TinderboxPrint: CPU idle
2,196.7 (45.9%) 14:39:47 INFO - TinderboxPrint: CPU system
964.0 (20.1%) 14:39:47 INFO - TinderboxPrint: CPU user
1,624.9 (34.0%) 14:39:47 INFO - TinderboxPrint: Swap in / out
215,658,496 / 0 14:39:47 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208431616; Write bytes: 206497792; Read time: 15915; Write time: 426 14:39:47 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1992192; Write bytes: 309474304; Read time: 18; Write time: 1163 14:39:47 INFO - Running post-run listener: _upload_blobber_files 14:39:47 INFO - Blob upload gear active. 14:39:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:39:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:39:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:39:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:39:48 INFO - (blobuploader) - INFO - Open directory for files ... 14:39:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:39:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:39:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:49 INFO - (blobuploader) - INFO - Done attempting. 14:39:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:39:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:39:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:50 INFO - (blobuploader) - INFO - Done attempting. 14:39:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:39:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:39:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:51 INFO - (blobuploader) - INFO - Done attempting. 14:39:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:39:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:39:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:53 INFO - (blobuploader) - INFO - Done attempting. 14:39:53 INFO - (blobuploader) - INFO - Iteration through files over. 14:39:53 INFO - Return code: 0 14:39:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:39:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:39:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c9ea927319d632ff9bc09a985a44d3a7cc48a744f2ea00b0badb61d14125711ad650228b76149353b1dd2c5a71c950113cb8a7bef9fe82bb28e2b31e5dba974", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/776b81a6c26f4aabe54cf86eb9aa881cddc828c83ecad6a70db18045c28869820122dd2ffa43982247ae6539bf21a7c5872c9c7ddc855962a8f1d9978c41c797", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cf89fd63c1e37721798f7a0caeaedf70fc3b783bc8893f1bb616e58f7d5ae07e7ab69c9bc7942229d31d4aa64867deab8e47ab7a16b9bea08c595987787fcb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/246fc362d32f5180fd55cdea1e4c22b4aee4980278d2cb859b911233be6238501d5aa72783130c1db918daa348637bb84473ce27ada9aa554230a98616788b25"} 14:39:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:39:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:39:53 INFO - Contents: 14:39:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3c9ea927319d632ff9bc09a985a44d3a7cc48a744f2ea00b0badb61d14125711ad650228b76149353b1dd2c5a71c950113cb8a7bef9fe82bb28e2b31e5dba974", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/776b81a6c26f4aabe54cf86eb9aa881cddc828c83ecad6a70db18045c28869820122dd2ffa43982247ae6539bf21a7c5872c9c7ddc855962a8f1d9978c41c797", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cf89fd63c1e37721798f7a0caeaedf70fc3b783bc8893f1bb616e58f7d5ae07e7ab69c9bc7942229d31d4aa64867deab8e47ab7a16b9bea08c595987787fcb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/246fc362d32f5180fd55cdea1e4c22b4aee4980278d2cb859b911233be6238501d5aa72783130c1db918daa348637bb84473ce27ada9aa554230a98616788b25"} 14:39:53 INFO - Running post-run listener: copy_logs_to_upload_dir 14:39:53 INFO - Copying logs to upload dir... 14:39:53 INFO - mkdir: /builds/slave/test/build/upload/logs 14:39:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.691214 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-05-09 14:39:53.683814) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-09 14:39:53.687226) ========= 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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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/3c9ea927319d632ff9bc09a985a44d3a7cc48a744f2ea00b0badb61d14125711ad650228b76149353b1dd2c5a71c950113cb8a7bef9fe82bb28e2b31e5dba974", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/776b81a6c26f4aabe54cf86eb9aa881cddc828c83ecad6a70db18045c28869820122dd2ffa43982247ae6539bf21a7c5872c9c7ddc855962a8f1d9978c41c797", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cf89fd63c1e37721798f7a0caeaedf70fc3b783bc8893f1bb616e58f7d5ae07e7ab69c9bc7942229d31d4aa64867deab8e47ab7a16b9bea08c595987787fcb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/246fc362d32f5180fd55cdea1e4c22b4aee4980278d2cb859b911233be6238501d5aa72783130c1db918daa348637bb84473ce27ada9aa554230a98616788b25"} build_url:https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011151 build_url: 'https://queue.taskcluster.net/v1/task/Et-fjChoQyqz62h1UrGjaQ/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/3c9ea927319d632ff9bc09a985a44d3a7cc48a744f2ea00b0badb61d14125711ad650228b76149353b1dd2c5a71c950113cb8a7bef9fe82bb28e2b31e5dba974", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/776b81a6c26f4aabe54cf86eb9aa881cddc828c83ecad6a70db18045c28869820122dd2ffa43982247ae6539bf21a7c5872c9c7ddc855962a8f1d9978c41c797", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cf89fd63c1e37721798f7a0caeaedf70fc3b783bc8893f1bb616e58f7d5ae07e7ab69c9bc7942229d31d4aa64867deab8e47ab7a16b9bea08c595987787fcb8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/246fc362d32f5180fd55cdea1e4c22b4aee4980278d2cb859b911233be6238501d5aa72783130c1db918daa348637bb84473ce27ada9aa554230a98616788b25"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-09 14:39:53.721551) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 14:39:53.721924) ========= 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.0LsBIK3uTc/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.FxfvZcfF7m/Listeners TMPDIR=/var/folders/ls/yjsxg9nn7856g1hdz9l0735w00000w/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.005064 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-09 14:39:53.770573) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-09 14:39:53.770918) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-09 14:39:53.771260) ========= ========= Total master_lag: 0.19 =========