builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0104 starttime: 1494430417.23 results: success (0) revision: 8ad5338081026c48029d72f1e6c1668a58489bec ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:37.231462) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:37.231951) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:37.232236) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:37.256985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:37.257298) ========= 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.S39FmXctf6/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.KJUZINrufX/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-10 08:33:37-- 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% 145M=0s 2017-05-10 08:33:37 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.182507 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:37.462831) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:37.463148) ========= 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.S39FmXctf6/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.KJUZINrufX/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.022606 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:37.511651) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 08:33:37.511969) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8ad5338081026c48029d72f1e6c1668a58489bec --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 8ad5338081026c48029d72f1e6c1668a58489bec --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.S39FmXctf6/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.KJUZINrufX/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-10 08:33:37,596 truncating revision to first 12 chars 2017-05-10 08:33:37,596 Setting DEBUG logging. 2017-05-10 08:33:37,596 attempt 1/10 2017-05-10 08:33:37,596 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8ad533808102?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-10 08:33:39,237 unpacking tar archive at: mozilla-central-8ad533808102/testing/mozharness/ program finished with exit code 0 elapsedTime=1.905206 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-10 08:33:39.439881) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:39.440176) ========= script_repo_revision: 8ad5338081026c48029d72f1e6c1668a58489bec ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:39.440517) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:39.440776) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-10 08:33:39.460065) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-10 08:33:39.460348) ========= /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.S39FmXctf6/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.KJUZINrufX/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:33:39 INFO - MultiFileLogger online at 20170510 08:33:39 in /builds/slave/test 08:33:39 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 08:33:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:33:39 INFO - {'append_to_log': False, 08:33:39 INFO - 'base_work_dir': '/builds/slave/test', 08:33:39 INFO - 'blob_upload_branch': 'Firefox', 08:33:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:33:39 INFO - 'branch': 'Firefox', 08:33:39 INFO - 'buildbot_json_path': 'buildprops.json', 08:33:39 INFO - 'config_files': ('talos/mac_config.py',), 08:33:39 INFO - 'default_actions': ('clobber', 08:33:39 INFO - 'read-buildbot-config', 08:33:39 INFO - 'download-and-extract', 08:33:39 INFO - 'populate-webroot', 08:33:39 INFO - 'create-virtualenv', 08:33:39 INFO - 'install', 08:33:39 INFO - 'run-tests'), 08:33:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:33:39 INFO - 'download_minidump_stackwalk': True, 08:33:39 INFO - 'download_symbols': 'ondemand', 08:33:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:33:39 INFO - 'tooltool.py': '/tools/tooltool.py', 08:33:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:33:39 INFO - '/tools/misc-python/virtualenv.py')}, 08:33:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:33:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:33:39 INFO - 'gecko_profile': False, 08:33:39 INFO - 'gecko_profile_interval': 0, 08:33:39 INFO - 'installer_path': 'installer.exe', 08:33:39 INFO - 'log_level': 'info', 08:33:39 INFO - 'log_name': 'talos', 08:33:39 INFO - 'log_to_console': True, 08:33:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:33:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:33:39 INFO - 'opt_config_files': (), 08:33:39 INFO - 'pip_index': False, 08:33:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:33:39 INFO - 'cmd': ('bash', 08:33:39 INFO - '-c', 08:33:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:33:39 INFO - 'enabled': True, 08:33:39 INFO - 'halt_on_failure': False, 08:33:39 INFO - 'name': 'check_screen_resolution'},), 08:33:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:33:39 INFO - 'cmd': ('bash', 08:33:39 INFO - '-c', 08:33:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:33:39 INFO - 'enabled': True, 08:33:39 INFO - 'halt_on_failure': False, 08:33:39 INFO - 'name': 'check_screen_resolution'},), 08:33:39 INFO - 'run_cmd_checks_enabled': True, 08:33:39 INFO - 'suite': 'dromaeojs-e10s', 08:33:39 INFO - 'system_bits': '32', 08:33:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:33:39 INFO - 'title': 't-yosemite-r7-0104', 08:33:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:33:39 INFO - 'use_talos_json': True, 08:33:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:33:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:33:39 INFO - 'work_dir': 'build'} 08:33:39 INFO - [mozharness: 2017-05-10 15:33:39.672758Z] Running clobber step. 08:33:39 INFO - Running pre-action listener: _resource_record_pre_action 08:33:39 INFO - Running main action method: clobber 08:33:39 INFO - rmtree: /builds/slave/test/build 08:33:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:33:43 INFO - Running post-action listener: _resource_record_post_action 08:33:43 INFO - [mozharness: 2017-05-10 15:33:43.108862Z] Finished clobber step (success) 08:33:43 INFO - [mozharness: 2017-05-10 15:33:43.109126Z] Running read-buildbot-config step. 08:33:43 INFO - Running pre-action listener: _resource_record_pre_action 08:33:43 INFO - Running main action method: read_buildbot_config 08:33:43 INFO - Using buildbot properties: 08:33:43 INFO - { 08:33:43 INFO - "project": "", 08:33:43 INFO - "product": "firefox", 08:33:43 INFO - "who": "cbook@mozilla.com", 08:33:43 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 08:33:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:33:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:33:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:33:43 INFO - "stage_platform": "macosx64", 08:33:43 INFO - "basedir": "/builds/slave/test", 08:33:43 INFO - "buildnumber": 55, 08:33:43 INFO - "platform": "yosemite_r7", 08:33:43 INFO - "parent_task_id": "chHUlsfiS9-sgeQPSfD6vQ", 08:33:43 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:33:43 INFO - "slavebuilddir": "test", 08:33:43 INFO - "taskId": "ByeRwu75QMe6XSDDgciSQg", 08:33:43 INFO - "branch": "mozilla-central", 08:33:43 INFO - "script_repo_revision": "production", 08:33:43 INFO - "revision": "8ad5338081026c48029d72f1e6c1668a58489bec", 08:33:43 INFO - "slavename": "t-yosemite-r7-0104", 08:33:43 INFO - "repo_path": "mozilla-central" 08:33:43 INFO - } 08:33:43 INFO - Finding installer, test and symbols from parent task. 08:33:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ByeRwu75QMe6XSDDgciSQg'}, attempt #1 08:33:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ByeRwu75QMe6XSDDgciSQg'}, attempt #1 08:33:44 INFO - Task dependencies: ApNyQ-6_S76a-7Edxe6uPg 08:33:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ'}, attempt #1 08:33:44 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:33:44 INFO - Set test_url: https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/test_packages.json 08:33:44 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/target.crashreporter-symbols.zip 08:33:44 INFO - Running post-action listener: _resource_record_post_action 08:33:44 INFO - [mozharness: 2017-05-10 15:33:44.645938Z] Finished read-buildbot-config step (success) 08:33:44 INFO - [mozharness: 2017-05-10 15:33:44.646056Z] Running download-and-extract step. 08:33:44 INFO - Running pre-action listener: _resource_record_pre_action 08:33:44 INFO - Running main action method: download_and_extract 08:33:44 INFO - mkdir: /builds/slave/test/build/tests 08:33:44 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')]} 08:33:44 INFO - trying https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:33:44 INFO - Downloading https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/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 08:33:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/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 08:33:45 INFO - Downloaded 1325 bytes. 08:33:45 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:33:45 INFO - Using the following test package requirements: 08:33:45 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:33:45 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:33:45 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:33:45 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:33:45 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:33:45 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:33:45 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:33:45 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:33:45 INFO - u'jsshell-mac.zip'], 08:33:45 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:33:45 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:33:45 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:33:45 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:33:45 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:33:45 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:33:45 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:33:45 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:33:45 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:33:45 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:33:45 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:33:45 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:33:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:33:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:33:45 INFO - Fetch https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:33:46 INFO - Content-Length response header: 52709434 08:33:46 INFO - Bytes received: 52709434 08:34:03 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 08:34:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:34:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:34:03 INFO - Fetch https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:34:05 INFO - Content-Length response header: 52709434 08:34:05 INFO - Bytes received: 52709434 08:34:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:34:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:34:20 INFO - Fetch https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:34:22 INFO - Content-Length response header: 14409362 08:34:22 INFO - Bytes received: 14409362 08:34: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')]} 08:34:22 INFO - trying https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:34:22 INFO - Downloading https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:34:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/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 08:34:24 INFO - Downloaded 75480910 bytes. 08:34:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:34:24 INFO - mkdir: /builds/slave/test/properties 08:34:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:34:24 INFO - Writing to file /builds/slave/test/properties/build_url 08:34:24 INFO - Contents: 08:34:24 INFO - build_url:https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:34:24 INFO - Running post-action listener: _resource_record_post_action 08:34:24 INFO - Running post-action listener: set_extra_try_arguments 08:34:24 INFO - [mozharness: 2017-05-10 15:34:24.705564Z] Finished download-and-extract step (success) 08:34:24 INFO - [mozharness: 2017-05-10 15:34:24.705716Z] Running populate-webroot step. 08:34:24 INFO - Running pre-action listener: _resource_record_pre_action 08:34:24 INFO - Running main action method: populate_webroot 08:34:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:34:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:34:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:34:24 INFO - '--tppagecycles', 08:34:24 INFO - '7'], 08:34:24 INFO - 'tests': ['tsvgm']}}, 08:34:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:34:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:34:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:34:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:34:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:34:24 INFO - 'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:34:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:34:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:34:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:34:24 INFO - 'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['damp', 'tps']}, 08:34:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:34:24 INFO - 'tests': ['damp', 'tps']}, 08:34:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['dromaeo_dom']}, 08:34:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:34:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:34:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:34:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other-e10s': {'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['a11yr', 08:34:24 INFO - 'ts_paint', 08:34:24 INFO - 'tpaint', 08:34:24 INFO - 'sessionrestore', 08:34:24 INFO - 'sessionrestore_no_auto_restore', 08:34:24 INFO - 'tabpaint']}, 08:34:24 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:34:24 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:34:24 INFO - 'bloom_basic_ref']}, 08:34:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['tsvgx', 08:34:24 INFO - 'tsvgr_opacity', 08:34:24 INFO - 'tart', 08:34:24 INFO - 'tscrollx', 08:34:24 INFO - 'cart', 08:34:24 INFO - 'tsvg_static']}, 08:34:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:34:24 INFO - 'tsvgr_opacity', 08:34:24 INFO - 'tart', 08:34:24 INFO - 'tscrollx', 08:34:24 INFO - 'cart', 08:34:24 INFO - 'tsvg_static']}, 08:34:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:34:24 INFO - 'talos_options': ['--disable-e10s'], 08:34:24 INFO - 'tests': ['tp5o']}, 08:34:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:34:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:34:24 INFO - 'talos_options': ['--disable-e10s', 08:34:24 INFO - '--xperf_path', 08:34:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:34:24 INFO - 'tests': ['tp5n']}, 08:34:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:34:24 INFO - 'talos_options': ['--xperf_path', 08:34:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:34:24 INFO - 'tests': ['tp5n']}}, 08:34:24 INFO - 'talos.zip': {'path': '', 08:34:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:34:24 INFO - Running post-action listener: _resource_record_post_action 08:34:24 INFO - [mozharness: 2017-05-10 15:34:24.726508Z] Finished populate-webroot step (success) 08:34:24 INFO - [mozharness: 2017-05-10 15:34:24.726640Z] Running create-virtualenv step. 08:34:24 INFO - Running pre-action listener: _resource_record_pre_action 08:34:24 INFO - Running main action method: create_virtualenv 08:34:24 INFO - Creating virtualenv /builds/slave/test/build/venv 08:34: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 08:34:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:34:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:34:24 INFO - Using real prefix '/tools/python27' 08:34:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:34:25 INFO - Installing distribute.............................................................................................................................................................................................done. 08:34:28 INFO - Installing pip.................done. 08:34:28 INFO - Return code: 0 08:34:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:34:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:34:28 INFO - Reading from file tmpfile_stdout 08:34:28 INFO - Output received: 08:34: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) 08:34:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:34: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')]} 08:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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')]} 08:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34: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 08:34: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 08:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:28 INFO - 'HOME': '/Users/cltbld', 08:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:28 INFO - 'LOGNAME': 'cltbld', 08:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:28 INFO - 'MOZ_NO_REMOTE': '1', 08:34:28 INFO - 'NO_EM_RESTART': '1', 08:34:28 INFO - 'PAGER': '/bin/cat', 08:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:28 INFO - 'PWD': '/builds/slave/test', 08:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:28 INFO - 'SHELL': '/bin/bash', 08:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:28 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:28 INFO - 'USER': 'cltbld', 08:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:28 INFO - 'XPC_FLAGS': '0x0', 08:34:28 INFO - 'XPC_SERVICE_NAME': '0', 08:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:28 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 08:34:28 INFO - Cleaning up... 08:34:28 INFO - Return code: 0 08:34:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:34: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')]} 08:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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')]} 08:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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', '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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:34: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 psutil>=3.1.1 08:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:28 INFO - 'HOME': '/Users/cltbld', 08:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:28 INFO - 'LOGNAME': 'cltbld', 08:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:28 INFO - 'MOZ_NO_REMOTE': '1', 08:34:28 INFO - 'NO_EM_RESTART': '1', 08:34:28 INFO - 'PAGER': '/bin/cat', 08:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:28 INFO - 'PWD': '/builds/slave/test', 08:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:28 INFO - 'SHELL': '/bin/bash', 08:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:28 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:28 INFO - 'USER': 'cltbld', 08:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:28 INFO - 'XPC_FLAGS': '0x0', 08:34:28 INFO - 'XPC_SERVICE_NAME': '0', 08:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:29 INFO - Downloading/unpacking psutil>=3.1.1 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:34:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:34:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:34:31 INFO - Installing collected packages: psutil 08:34:31 INFO - Running setup.py install for psutil 08:34:31 INFO - building 'psutil._psutil_osx' extension 08:34: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 08:34: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 08:34: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 08:34: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 08:34:32 INFO - building 'psutil._psutil_posix' extension 08:34: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 08:34:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:34:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:34:32 INFO - ^ 08:34:32 INFO - 1 warning generated. 08:34: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 08:34:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:34:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:34:32 INFO - Successfully installed psutil 08:34:32 INFO - Cleaning up... 08:34:32 INFO - Return code: 0 08:34:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:34: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')]} 08:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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')]} 08:34:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34: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 08:34: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 08:34:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:32 INFO - 'HOME': '/Users/cltbld', 08:34:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:32 INFO - 'LOGNAME': 'cltbld', 08:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:32 INFO - 'MOZ_NO_REMOTE': '1', 08:34:32 INFO - 'NO_EM_RESTART': '1', 08:34:32 INFO - 'PAGER': '/bin/cat', 08:34:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:32 INFO - 'PWD': '/builds/slave/test', 08:34:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:32 INFO - 'SHELL': '/bin/bash', 08:34:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:32 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:32 INFO - 'USER': 'cltbld', 08:34:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:32 INFO - 'XPC_FLAGS': '0x0', 08:34:32 INFO - 'XPC_SERVICE_NAME': '0', 08:34:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:33 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 08:34:33 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 08:34:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:34:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:34:34 INFO - Installing collected packages: mozsystemmonitor 08:34:34 INFO - Running setup.py install for mozsystemmonitor 08:34:34 INFO - Successfully installed mozsystemmonitor 08:34:34 INFO - Cleaning up... 08:34:34 INFO - Return code: 0 08:34:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:34: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')]} 08:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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')]} 08:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:34 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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34:34 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 08:34:34 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 08:34:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:34 INFO - 'HOME': '/Users/cltbld', 08:34:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:34 INFO - 'LOGNAME': 'cltbld', 08:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:34 INFO - 'MOZ_NO_REMOTE': '1', 08:34:34 INFO - 'NO_EM_RESTART': '1', 08:34:34 INFO - 'PAGER': '/bin/cat', 08:34:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:34 INFO - 'PWD': '/builds/slave/test', 08:34:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:34 INFO - 'SHELL': '/bin/bash', 08:34:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:34 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:34 INFO - 'USER': 'cltbld', 08:34:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:34 INFO - 'XPC_FLAGS': '0x0', 08:34:34 INFO - 'XPC_SERVICE_NAME': '0', 08:34:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:34 INFO - Downloading/unpacking jsonschema==2.5.1 08:34:34 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 08:34:34 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 08:34:34 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 08:34:34 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 08:34:34 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 08:34:34 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 08:34:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:34:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:36 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 08:34:36 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 08:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:36 INFO - Downloading functools32-3.2.3-2.tar.gz 08:34:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:34:36 INFO - warning: no files found matching '*.txt' 08:34:36 INFO - no previously-included directories found matching 'build' 08:34:36 INFO - no previously-included directories found matching 'dist' 08:34:36 INFO - no previously-included directories found matching '.git*' 08:34:36 INFO - Installing collected packages: jsonschema, functools32 08:34:36 INFO - Running setup.py install for functools32 08:34:36 INFO - warning: no files found matching '*.txt' 08:34:36 INFO - no previously-included directories found matching 'build' 08:34:36 INFO - no previously-included directories found matching 'dist' 08:34:36 INFO - no previously-included directories found matching '.git*' 08:34:36 INFO - Successfully installed jsonschema functools32 08:34:36 INFO - Cleaning up... 08:34:36 INFO - Return code: 0 08:34:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:34:36 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')]} 08:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:36 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')]} 08:34:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34:36 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 08:34:36 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 08:34:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:36 INFO - 'HOME': '/Users/cltbld', 08:34:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:36 INFO - 'LOGNAME': 'cltbld', 08:34:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:36 INFO - 'MOZ_NO_REMOTE': '1', 08:34:36 INFO - 'NO_EM_RESTART': '1', 08:34:36 INFO - 'PAGER': '/bin/cat', 08:34:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:36 INFO - 'PWD': '/builds/slave/test', 08:34:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:36 INFO - 'SHELL': '/bin/bash', 08:34:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:36 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:36 INFO - 'USER': 'cltbld', 08:34:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:36 INFO - 'XPC_FLAGS': '0x0', 08:34:36 INFO - 'XPC_SERVICE_NAME': '0', 08:34:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:34:37 INFO - Cleaning up... 08:34:37 INFO - Return code: 0 08:34:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:34: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')]} 08:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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')]} 08:34:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34: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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34: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 08:34: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 08:34:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:37 INFO - 'HOME': '/Users/cltbld', 08:34:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:37 INFO - 'LOGNAME': 'cltbld', 08:34:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:37 INFO - 'MOZ_NO_REMOTE': '1', 08:34:37 INFO - 'NO_EM_RESTART': '1', 08:34:37 INFO - 'PAGER': '/bin/cat', 08:34:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:37 INFO - 'PWD': '/builds/slave/test', 08:34:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:37 INFO - 'SHELL': '/bin/bash', 08:34:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:37 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:37 INFO - 'USER': 'cltbld', 08:34:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:37 INFO - 'XPC_FLAGS': '0x0', 08:34:37 INFO - 'XPC_SERVICE_NAME': '0', 08:34:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:37 INFO - Downloading/unpacking blobuploader==1.2.4 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34:39 INFO - Downloading blobuploader-1.2.4.tar.gz 08:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:34:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:34:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34: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 08:34:39 INFO - Downloading docopt-0.6.1.tar.gz 08:34:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:34:40 INFO - Installing collected packages: blobuploader, requests, docopt 08:34:40 INFO - Running setup.py install for blobuploader 08:34:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:34:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:34:40 INFO - Running setup.py install for requests 08:34:40 INFO - Running setup.py install for docopt 08:34:40 INFO - Successfully installed blobuploader requests docopt 08:34:40 INFO - Cleaning up... 08:34:40 INFO - Return code: 0 08:34:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:40 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')]} 08:34:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:40 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')]} 08:34:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:40 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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34:40 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 08:34:40 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 08:34:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:40 INFO - 'HOME': '/Users/cltbld', 08:34:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:40 INFO - 'LOGNAME': 'cltbld', 08:34:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:40 INFO - 'MOZ_NO_REMOTE': '1', 08:34:40 INFO - 'NO_EM_RESTART': '1', 08:34:40 INFO - 'PAGER': '/bin/cat', 08:34:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:40 INFO - 'PWD': '/builds/slave/test', 08:34:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:40 INFO - 'SHELL': '/bin/bash', 08:34:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:40 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:40 INFO - 'USER': 'cltbld', 08:34:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:40 INFO - 'XPC_FLAGS': '0x0', 08:34:40 INFO - 'XPC_SERVICE_NAME': '0', 08:34:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:34:41 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Ghr3E0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:34:41 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-V_R05z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:34:41 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-OB4DdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:34:41 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-A9Jvpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:34:41 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-DQVTeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:34:41 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-kAxIEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:34:41 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-G4s7uG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:34:41 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Bsba5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:34:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:34:41 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-GnVkfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:34:42 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-edPRkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:34:42 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-F7qoW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:34:42 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-osOCeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:34:42 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-KIYP3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:34:42 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-DXm1nB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:42 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-zmFjxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:34:42 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-oqcfH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:34:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:34:42 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-FJMXu8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:34:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:34:42 INFO - Running setup.py install for manifestparser 08:34:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:34:43 INFO - Running setup.py install for mozcrash 08:34:43 INFO - Running setup.py install for mozdebug 08:34:43 INFO - Running setup.py install for mozdevice 08:34:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:34:43 INFO - Running setup.py install for mozfile 08:34:43 INFO - Running setup.py install for mozhttpd 08:34:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:34:43 INFO - Running setup.py install for mozinfo 08:34:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:34:43 INFO - Running setup.py install for mozInstall 08:34:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:34:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:34:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:34:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:34:44 INFO - Running setup.py install for mozleak 08:34:44 INFO - Running setup.py install for mozlog 08:34:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:34:44 INFO - Running setup.py install for moznetwork 08:34:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:34:44 INFO - Running setup.py install for mozprocess 08:34:44 INFO - Running setup.py install for mozprofile 08:34:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:34:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:34:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:34:44 INFO - Running setup.py install for mozrunner 08:34:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:34:44 INFO - Running setup.py install for mozscreenshot 08:34:45 INFO - Running setup.py install for moztest 08:34:45 INFO - Running setup.py install for mozversion 08:34:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:34:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:34:45 INFO - Cleaning up... 08:34:45 INFO - Return code: 0 08:34:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:45 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')]} 08:34:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:45 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')]} 08:34:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:45 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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34:45 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 08:34:45 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 08:34:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:45 INFO - 'HOME': '/Users/cltbld', 08:34:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:45 INFO - 'LOGNAME': 'cltbld', 08:34:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:45 INFO - 'MOZ_NO_REMOTE': '1', 08:34:45 INFO - 'NO_EM_RESTART': '1', 08:34:45 INFO - 'PAGER': '/bin/cat', 08:34:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:45 INFO - 'PWD': '/builds/slave/test', 08:34:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:45 INFO - 'SHELL': '/bin/bash', 08:34:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:45 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:45 INFO - 'USER': 'cltbld', 08:34:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:45 INFO - 'XPC_FLAGS': '0x0', 08:34:45 INFO - 'XPC_SERVICE_NAME': '0', 08:34:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:34:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-3VfJpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:34:45 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)) 08:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:34:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-u57Fm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:34:45 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)) 08:34:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:34:45 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-K8cbcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:34: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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-41Nfhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:34: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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-almV3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:34: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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-I2ZZbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:34: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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-y8_icT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:34:46 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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-h7hsMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:34:46 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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-75o2dq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:34:46 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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-xR3SKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:34:46 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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-5h_vIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:34:46 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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-bfRnPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:34:46 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)) 08:34:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:34:46 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-KDapyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:34: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)) 08:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:34:47 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-35jeWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:34: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)) 08:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:34:47 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-rp_Dhb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:34: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)) 08:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:34:47 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-KyBRUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:34:47 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)) 08:34:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:34:47 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Qx1WEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:34:47 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)) 08:34:47 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)) 08:34:47 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)) 08:34:47 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)) 08:34:47 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)) 08:34:47 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)) 08:34:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:47 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 08:34:47 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 08:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:34:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:34:48 INFO - Downloading blessings-1.6.tar.gz 08:34:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:34:49 INFO - Installing collected packages: blessings 08:34:49 INFO - Running setup.py install for blessings 08:34:49 INFO - Successfully installed blessings 08:34:49 INFO - Cleaning up... 08:34:49 INFO - Return code: 0 08:34:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:34:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:34:49 INFO - Reading from file tmpfile_stdout 08:34:49 INFO - Current package versions: 08:34:49 INFO - blessings == 1.6 08:34:49 INFO - blobuploader == 1.2.4 08:34:49 INFO - docopt == 0.6.1 08:34:49 INFO - functools32 == 3.2.3-2 08:34:49 INFO - jsonschema == 2.5.1 08:34:49 INFO - manifestparser == 1.1 08:34:49 INFO - mozInstall == 1.12 08:34:49 INFO - mozcrash == 1.0 08:34:49 INFO - mozdebug == 0.1 08:34:49 INFO - mozdevice == 0.50 08:34:49 INFO - mozfile == 1.2 08:34:49 INFO - mozhttpd == 0.7 08:34:49 INFO - mozinfo == 0.9 08:34:49 INFO - mozleak == 0.1 08:34:49 INFO - mozlog == 3.4 08:34:49 INFO - moznetwork == 0.27 08:34:49 INFO - mozprocess == 0.25 08:34:49 INFO - mozprofile == 0.28 08:34:49 INFO - mozrunner == 6.13 08:34:49 INFO - mozscreenshot == 0.1 08:34:49 INFO - mozsystemmonitor == 0.3 08:34:49 INFO - moztest == 0.8 08:34:49 INFO - mozversion == 1.4 08:34:49 INFO - psutil == 3.1.1 08:34:49 INFO - requests == 1.2.3 08:34:49 INFO - wsgiref == 0.1.2 08:34:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:34:49 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')]} 08:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:49 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')]} 08:34:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:49 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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34:49 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 08:34:49 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 08:34:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:49 INFO - 'HOME': '/Users/cltbld', 08:34:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:49 INFO - 'LOGNAME': 'cltbld', 08:34:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:49 INFO - 'MOZ_NO_REMOTE': '1', 08:34:49 INFO - 'NO_EM_RESTART': '1', 08:34:49 INFO - 'PAGER': '/bin/cat', 08:34:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:49 INFO - 'PWD': '/builds/slave/test', 08:34:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:49 INFO - 'SHELL': '/bin/bash', 08:34:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:49 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:49 INFO - 'USER': 'cltbld', 08:34:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:49 INFO - 'XPC_FLAGS': '0x0', 08:34:49 INFO - 'XPC_SERVICE_NAME': '0', 08:34:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:49 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)) 08:34:49 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)) 08:34:49 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)) 08:34:49 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)) 08:34:49 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)) 08:34:49 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)) 08:34:49 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)) 08:34:49 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)) 08:34:49 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)) 08:34:49 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)) 08:34:49 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)) 08:34:49 INFO - Cleaning up... 08:34:49 INFO - Return code: 0 08:34:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:34:49 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')]} 08:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:34:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:49 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')]} 08:34:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:34:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:34:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10fb83b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb86768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9feadb0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, '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.S39FmXctf6/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.KJUZINrufX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 08:34:49 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 08:34:49 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 08:34:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:34:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:34:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:34:49 INFO - 'HOME': '/Users/cltbld', 08:34:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:34:49 INFO - 'LOGNAME': 'cltbld', 08:34:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:34:49 INFO - 'MOZ_NO_REMOTE': '1', 08:34:49 INFO - 'NO_EM_RESTART': '1', 08:34:49 INFO - 'PAGER': '/bin/cat', 08:34:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:34:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:34:49 INFO - 'PWD': '/builds/slave/test', 08:34:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:34:49 INFO - 'SHELL': '/bin/bash', 08:34:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:34:49 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:34:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:34:49 INFO - 'USER': 'cltbld', 08:34:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:34:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:34:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:34:49 INFO - 'XPC_FLAGS': '0x0', 08:34:49 INFO - 'XPC_SERVICE_NAME': '0', 08:34:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:34:50 INFO - Cleaning up... 08:34:50 INFO - Return code: 0 08:34:50 INFO - Running post-action listener: _resource_record_post_action 08:34:50 INFO - Running post-action listener: _start_resource_monitoring 08:34:50 INFO - Starting resource monitoring. 08:34:50 INFO - [mozharness: 2017-05-10 15:34:50.115421Z] Finished create-virtualenv step (success) 08:34:50 INFO - [mozharness: 2017-05-10 15:34:50.116394Z] Running install step. 08:34:50 INFO - Running pre-action listener: _resource_record_pre_action 08:34:50 INFO - Running main action method: install 08:34:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:34:50 INFO - Reading from file tmpfile_stdout 08:34:50 INFO - Detecting whether we're running mozinstall >=1.0... 08:34:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:34:50 INFO - Reading from file tmpfile_stdout 08:34:50 INFO - Output received: 08:34:50 INFO - Usage: mozinstall [options] installer 08:34:50 INFO - Options: 08:34:50 INFO - -h, --help show this help message and exit 08:34:50 INFO - -d DEST, --destination=DEST 08:34:50 INFO - Directory to install application into. [default: 08:34:50 INFO - "/builds/slave/test"] 08:34:50 INFO - --app=APP Application being installed. [default: firefox] 08:34:50 INFO - mkdir: /builds/slave/test/build/application 08:34:50 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'] 08:34:50 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 08:35:10 INFO - Reading from file tmpfile_stdout 08:35:10 INFO - Output received: 08:35:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:35:10 INFO - Running post-action listener: _resource_record_post_action 08:35:10 INFO - [mozharness: 2017-05-10 15:35:10.941567Z] Finished install step (success) 08:35:10 INFO - [mozharness: 2017-05-10 15:35:10.941705Z] Running run-tests step. 08:35:10 INFO - Running pre-action listener: _resource_record_pre_action 08:35:10 INFO - Running main action method: run_tests 08:35:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:35:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:35:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:35:10 INFO - 2017-05-10 08:35:10.956 screenresolution[1913:19148] starting screenresolution argv=screenresolution get 08:35:10 INFO - 2017-05-10 08:35:10.959 screenresolution[1913:19148] Display 0: 1600x1200x32@60 08:35:10 INFO - 2017-05-10 08:35:10.965 screenresolution[1914:19150] starting screenresolution argv=screenresolution list 08:35:10 INFO - Available Modes on Display 0 08:35:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:35:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:35:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:35:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:35:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:35:11 INFO - Intel Iris: 08:35:11 INFO - Chipset Model: Intel Iris 08:35:11 INFO - Type: GPU 08:35:11 INFO - Bus: Built-In 08:35:11 INFO - VRAM (Dynamic, Max): 1536 MB 08:35:11 INFO - Vendor: Intel (0x8086) 08:35:11 INFO - Device ID: 0x0a2e 08:35:11 INFO - Revision ID: 0x0009 08:35:11 INFO - Displays: 08:35:11 INFO - Display: 08:35:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:35:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:35:11 INFO - Display Serial Number: 1600x1200 60 08:35:11 INFO - Main Display: Yes 08:35:11 INFO - Mirror: Off 08:35:11 INFO - Online: Yes 08:35:11 INFO - Rotation: Supported 08:35:11 INFO - Return code: 0 08:35:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:35:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:35:11 INFO - Python 2.7.3 08:35:11 INFO - Return code: 0 08:35:11 INFO - grabbing minidump binary from tooltool 08:35: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')]} 08:35: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 0x10f894d30>, '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 0x10f847db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f888be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:35: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 08:35: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 08:35: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 08:35:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:35:11 INFO - Return code: 0 08:35:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:35:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:35:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:35:11 INFO - ENV: RUST_BACKTRACE is now 1 08:35:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:35:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:35: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/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 08:35: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/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 08:35:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.S39FmXctf6/Render', 08:35:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:35:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:35:11 INFO - 'HOME': '/Users/cltbld', 08:35:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:35:11 INFO - 'LOGNAME': 'cltbld', 08:35:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:35:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:35:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:35:11 INFO - 'MOZ_NO_REMOTE': '1', 08:35:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:35:11 INFO - 'NO_EM_RESTART': '1', 08:35:11 INFO - 'PAGER': '/bin/cat', 08:35:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:35:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:35:11 INFO - 'PWD': '/builds/slave/test', 08:35:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:35:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:35:11 INFO - 'RUST_BACKTRACE': '1', 08:35:11 INFO - 'SHELL': '/bin/bash', 08:35:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KJUZINrufX/Listeners', 08:35:11 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 08:35:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:35:11 INFO - 'USER': 'cltbld', 08:35:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:35:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:35:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:35:11 INFO - 'XPC_FLAGS': '0x0', 08:35:11 INFO - 'XPC_SERVICE_NAME': '0', 08:35:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:35: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/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 08:35:11 INFO - mozversion application_buildid: 20170510060943 08:35:11 INFO - mozversion application_changeset: 8ad5338081026c48029d72f1e6c1668a58489bec 08:35:11 INFO - mozversion application_display_name: Nightly 08:35:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:35:11 INFO - mozversion application_name: Firefox 08:35:11 INFO - mozversion application_remotingname: firefox 08:35:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:35:11 INFO - mozversion application_vendor: Mozilla 08:35:11 INFO - mozversion application_version: 55.0a1 08:35:11 INFO - mozversion platform_buildid: 20170510060943 08:35:11 INFO - mozversion platform_changeset: 8ad5338081026c48029d72f1e6c1668a58489bec 08:35:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:35:11 INFO - mozversion platform_version: 55.0a1 08:35:11 INFO - using testdate: 1494430511 08:35:11 INFO - actual date: 1494430511 08:35:11 INFO - starting webserver on 'localhost:55859' 08:35:11 INFO - SUITE-START | Running 2 tests 08:35:11 INFO - TEST-START | dromaeo_css 08:35:11 INFO - operating with platform_type : mac_ 08:35:11 INFO - Initialising browser for dromaeo_css test... 08:35:11 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpCH05OV/profile http://localhost:55859/getInfo.html) 08:35:14 INFO - PID 1924 | 2017-05-10 08:35:14.221 plugin-container[1926:19320] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 08:35:14 INFO - PID 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:14 INFO - PID 1924 | 2017-05-10 08:35:14.224 plugin-container[1926:19320] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 08:35:14 INFO - PID 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:14 INFO - PID 1924 | 2017-05-10 08:35:14.224 plugin-container[1926:19320] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:14 INFO - PID 1924 | __metrics Screen width/height:1600/1200 08:35:14 INFO - PID 1924 | colorDepth:24 08:35:14 INFO - PID 1924 | Browser inner width/height: 1024/691 08:35:14 INFO - PID 1924 | __metrics 08:35:14 INFO - PID 1924 | 2017-05-10 08:35:14.727 plugin-container[1927:19373] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 08:35:14 INFO - PID 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:14 INFO - PID 1924 | 2017-05-10 08:35:14.728 plugin-container[1927:19373] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9803, name = 'com.apple.CFPasteboardClient' 08:35:14 INFO - PID 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:14 INFO - PID 1924 | 2017-05-10 08:35:14.728 plugin-container[1927:19373] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:15 INFO - TEST-INFO | 1924: exit 0 08:35:15 INFO - Browser initialized. 08:35:15 INFO - Running cycle 1/1 for dromaeo_css test... 08:35:15 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpCH05OV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:35:17 INFO - PID 1928 | 2017-05-10 08:35:17.492 plugin-container[1929:19486] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 08:35:17 INFO - PID 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:17 INFO - PID 1928 | 2017-05-10 08:35:17.492 plugin-container[1929:19486] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 08:35:17 INFO - PID 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:17 INFO - PID 1928 | 2017-05-10 08:35:17.492 plugin-container[1929:19486] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:18 INFO - PID 1928 | 2017-05-10 08:35:18.026 plugin-container[1930:19527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 08:35:18 INFO - PID 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:18 INFO - PID 1928 | 2017-05-10 08:35:18.026 plugin-container[1930:19527] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 08:35:18 INFO - PID 1928 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:18 INFO - PID 1928 | 2017-05-10 08:35:18.026 plugin-container[1930:19527] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:27 INFO - PID 1928 | RSS: Main: 229277696 08:35:27 INFO - PID 1928 | 08:38:30 INFO - PID 1928 | Cycle 1(1): loaded http://localhost:55859/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55859/tests/dromaeo/cssquery-ext.html) 08:38:32 INFO - PID 1928 | RSS: Main: 251826176 08:38:32 INFO - PID 1928 | 08:41:34 INFO - PID 1928 | Cycle 1(1): loaded http://localhost:55859/tests/dromaeo/cssquery-ext.html (next: http://localhost:55859/tests/dromaeo/cssquery-jquery.html) 08:41:35 INFO - PID 1928 | RSS: Main: 255352832 08:41:35 INFO - PID 1928 | 08:44:37 INFO - PID 1928 | Cycle 1(1): loaded http://localhost:55859/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55859/tests/dromaeo/cssquery-mootools.html) 08:44:37 INFO - PID 1928 | RSS: Main: 267014144 08:44:37 INFO - PID 1928 | 08:47:39 INFO - PID 1928 | Cycle 1(1): loaded http://localhost:55859/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55859/tests/dromaeo/cssquery-prototype.html) 08:47:40 INFO - PID 1928 | RSS: Main: 260501504 08:47:40 INFO - PID 1928 | 08:50:42 INFO - PID 1928 | Cycle 1(1): loaded http://localhost:55859/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55859/tests/dromaeo/cssquery-yui.html) 08:50:43 INFO - PID 1928 | RSS: Main: 264695808 08:50:43 INFO - PID 1928 | 08:53:45 INFO - PID 1928 | Cycle 1(1): loaded http://localhost:55859/tests/dromaeo/cssquery-yui.html (next: http://localhost:55859/tests/dromaeo/cssquery-dojo.html) 08:53:45 INFO - PID 1928 | RSS: Main: 266493952 08:53:45 INFO - PID 1928 | 08:53:45 INFO - PID 1928 | __start_tp_report 08:53:45 INFO - PID 1928 | _x_x_mozilla_page_load 08:53:45 INFO - PID 1928 | _x_x_mozilla_page_load_details 08:53:45 INFO - PID 1928 | |i|pagename|runs| 08:53:45 INFO - PID 1928 | |0;dojo.html;3002.609660744103;3571.874984375546;3638.126849556104;3640.5995340512554;3646.671799538042;6150.200473938393;6205.596636218648;6246.625202487854;6293.842653933648;6301.369863013696;5182.766775495103;6218.1916350874435;6247.219097612799;6266.091416744581;6339.239291285052;4518.502964673899;5531.502164805167;5566.972165139179;5567.4432556744405;5717.685527295997;4523.341066376937;5624.578156638248;5680.801171958982;5732.22614946981;5734.4265573442735;5447.4824891635235;5559.499645031967;5587.217789509472;5620.606557540974;5665.716714164296;5595.860103497404;5610.523105536035;5616.438356164392;5661.264035675377;5673.6879471629045;5149.84550463483;5561.916571251434;5564;5577.330720313547;5578.525825304854;5055.721935293557;5794.043982742843;6282.874342513124;6302.527310451688;6382.68086595673;6408.903866442007;6499;6627.93372066278;6747.493937954673;6768.221654509745;6846.144231971004;6867.862642747117;6868.519203655746;6874.312568743136;6874.65626718667;6294.65379404128;6317.210348706411;6349.873002539924;6355.745770169187;6357.205349331333;6754.898676519758;6843.315668433117;7001.36987671112;7004.334588214111;7025.613591252428;6567.211934567882;6637.1703537057865;6662.56693314933;6666.500012499082;6687.097241872285;6792;6815.829604259834;6830.146231721035;6831.4534837212905;6855.143107111611;6662.410597611912;6678.899816502756;6758.729650814011;6759.898601520981;6769.627670478171;6512.511561632897;6616.96691516549;6618.8345291368105;6664.666766661648;6702.430293425016;5401.378841433207;5411.323584551931;5414.675119492843;5422.186671999231;5425.945740542623;4745.976270118627;4763.237881938873;4828.420589529209;4828.903421931542;4846.29728689338;6232.127502149703;6319.273283572448;6323;6351.682415879188;6377.617342959437;6861.759838405632;6899.75850845218;6904.585724856424;6905.309469053054;6913.1358580177475;6358.1734374531015;6370.713317900706;6378.840528986812;6393.904091438632;6399.616023038632;5821.388754180834;5971.343152253248;5973.641581505123;6076.179715738407;6081.300650425169;6850.349216824394;6880.415164710856;6892.758753443605;7022.683979221049;7060.964695176492;6630.370114839083;6752.122224110834;6897.827554311182;6908.412784913338;6917;5938.020226662552;6793.286704895915;6925.238223795479;7033.472489563201;7048.471364647569;6756.121704178426;6769.356911093439;6793.728250870107;6794.3545363190415;6811.284815094542;5562.666240025612;6706.698198580978;6748.966255168692;6792.864142717019;6935.791926242221;6568.507361947777;6632.7678531253105;6660.766873159416;6663.533552651268;6671.532992690465;6642.302558231316;6643.368879956396;6645.1361323027695;6647.169103862017;6660.600363978177;6287.245530536182;6342.23893132827;6368.840778980562;6457.09600655918;6526.967365163144;6597.50518711089;6645.867082658417;6655.600663960178;6702.262751097278;6713.985804258605;6471.805845824444;6486.05952136947;6486.254080780582;6492.805215843532;6532.804015879531 08:53:45 INFO - PID 1928 | |1;ext.html;737.7086051009851;837.5812093953024;1052.8578641883344;1180.6340034589277;1243.471524602044;28905.843766249352;29952.850235748792;33580;37362.62637373625;37473.25053498928;37576.624233757655;37728.81135594318;37730.8113459433;37748.24503509928;37752.24495510095;7640.9235907640905;7772.145064042951;7788.376929845599;7803.141654418037;8143.104258531586;8379.916200837975;8484.575771211446;8520.275776559;8520.8721869172;8536.80484732138;8486.915130848705;8526.147385261485;8528.275096616795;8543.871841922377;8551.786205344853;8515.872261916045;8540.017897941692;8560.357973152039;8572.14278572141;8589.44168629037;7694.269044440825;7846.862204980303;8214.4660597061;8260.380471464665;8311.625976830997;37243.813780931196;37296.81351593225;37321.77495150681;37355.06612334686;37467.68863089806;3598.280343931198;3635.291118231946;3635.509206257148;3638.745287829853;3640.1263696712863;30208.8489557553;30260.697393026006;30262.394752105054;30474;30601.08196754101;2997.2956355256715;3045;3045.482268014443;3054.3585846972164;3059.4034163338156;302413;302738.48630756926;303044;303125;303656;38824.41763373552;39529.80235098806;47190.52809471861;47312.5268747315;47499.81250468697;48484;50444.49555504471;50667;50675.23987140196;50757;14148.929255353658;14220.00459967813;14248.643783905278;14252.358643860844;14292;11405.657830265105;11456.713582160513;11463.484143213409;11467.311961282183;11469.827952580717;13461.25963072021;13480.797788033187;13508.054436189372;13508.32458377097;13627.795583066261;5850.707464626752;5934.732937017845;5941.732622032019;5978.491828194652;5980.5514586405325;888.9155530224618;1028.3829702178782;1032.879325931358;1055.176961969665;1074.6453670288786;24100.87949560241;24241.63637545438;24274;24279.63580546293;24290.635640465407;1117.7481221032497;1120.6357933671588;1148.8047032004579;1158.3339579741682;1159.8202278646654;11872.88127118735;12450.377481125908;12454.81317780216;12455.564055258023;12464.937675311565;321894.3905280459;323546;323707;323714;324714;53038.204426933626;53391.73304133455;53447;53453.465465345624;53457.7327113362;1352.317079874657;1459.9562013139623;1487.5165571189407;1549.1866769945643;1572.5439622509573;18786.71819922675;22314.330570082922;22750;23987;24242.030318787052;32359.352812944082;32485.512717308786;38747.61252387553;38923;38927.61072389353;274274;274491.62754186103;274851.62574187;275332;276366;33381.49927751037;34140;34239.801606943634;34674.653253468154;34922.12694682653;37612.62387376091;42279;43402.56597433985;43642;44445.77777111223;28876;29962.550561742028;30157.64290606972;30378.39243215079;30687.539686904267;21394.57210855743;29633.851830741572;30267.394652106395;30546;30768.230794230323;36575.71984980513;36599.45100823543;36607.45088823616;36646.816765916;36724.81637591795;29083.418331632827;29989.55015674723;30034;30714.539281910344;30947.381052379278;23997.52004959926;24080.75919240785;24105.51788964246;24109.071274298363;24373.63439548373 08:53:45 INFO - PID 1928 | |2;jquery.html;429.8581468115522;436.8536540259012;437.3330670727141;458.9954100458995;459.03602434886744;18653.626927461442;20195.495112622193;20912.68630970533;21080.472988175305;21130.89434552829;21152.894235528838;22159.556808863854;23001.76998230017;23238.65142022871;23348.949297281622;6203.286622038467;6356.841078973016;6381.170447841774;6385.457236134911;6420.58266212697;5978.940210597903;6226.750929962796;6264;6276.089966954789;6287.748490060415;6048.213732214828;6182.443580077792;6189.102580125879;6233.625982441045;6258.374162583751;5448.645838020516;6143.863385273717;6209.9068513972325;6239.095331176999;6274.937250627481;4628.467726211495;5501.642393244466;5581.776728930838;5582.720863956786;5605.327360716699;27628;27702.3074423139;27762.722372776214;27828.30429239285;28019.85990070057;2175;2251.3808702606752;2256.3231030690727;2259.728832540089;2265.694131292271;17659.293628254854;17715.822841771547;18089.09554522282;18195.63608727818;18371.98954057526;2138.4653836540865;2168.111074459464;2213.734351877769;2215.2578886073184;2219.5117074243803;132284.99999999904;158602;162940;165906;167723.32276677198;12574.497020119094;13008.414621342063;13083.542076027294;13083.542076027294;13133.277669728257;16986.235619397157;17036.403725869535;17058;17060.658786824195;17060.91469542669;8166.6528342600395;8445.957770211233;8485;8584.540628093238;8621.224089831945;6483.610983341015;6499.447546958467;7737.651805668758;7787.909692643035;7900.960495197487;7168.996340512333;7600.391968642495;7927.563983980936;8026.237507436784;8075.192480751878;2174.717286752744;2186.2238905188747;2194.923177688805;2197.516546359798;2354.505553833714;880;889.1863944490848;935.3826474526779;950.7480517662825;954.4511905654276;20596.27913023037;21228.363149105553;21230.681539776917;21296.361109166748;21468.570628587342;835.418576419;979.6962941488162;1001.0840081325506;1016.1261315268863;1019.5564929255797;8789.252913502423;9006.729798106067;10097.343672661253;10990.560377584967;11016.834747478793;537075;540158;549927;557205;559689;127673;129055.35472322766;134139;134897.99999999805;135150;1203.7111093337535;1229.8524177098627;1251.6495381293253;1253.3921048291572;1256.5099611151659;21361.78638213598;22546.61800729838;22747;22791.430214244778;22850.771492284865;56501.869962599696;57439.13841292467;57993;58240.70879645574;59173.70413147907;75725.6992010554;78897;79332;79352;79499.60250198712;7721.382289416721;8981.7754556134;9084.40951338161;9124.95437522808;9193.494357810385;13896.37466313998;13961.930190349389;14103.858961410666;14260.71478570402;14316.785248221493;11345.829812552984;11823.645290641294;11928;12114.394280286127;12205;9217.354785164975;9302.813943720952;9441.339106262496;9530.046995300414;11320.26418282809;11528.481218345363;11980.58067967617;12116.333601651993;12133.453994570089;12231.571894983634;7715.344195743424;9051.04963978799;9056.13966673165;9179;9302.534873256445;9565.426074435556;12009.879901200877;12035.33290439072;12175.939120304341;12333.876661233273 08:53:45 INFO - PID 1928 | |3;mootools.html;1239.4918083585728;1447.442734547199;1449.5216578529087;1461.7136919510835;1470.8970372073957;11060.944695276512;11108.277961932468;11610.651680449579;11649.184557080996;11677.591284305052;9496.715098547054;11579.65261042168;11664.00855927245;11667.824982625267;11675;2482.788962938148;2521.6343630173615;2537.5305568469894;2539.9111031113916;2542.2627438042946;2490.638857365681;2518.7355327690602;2531.0255551612577;2533.3160046787357;2533.733957934418;2442.926712198628;2489.987550062256;2517.0812653381477;2521.684789401325;2521.9117330893423;2442.157455677788;2474.18351943859;2479.838810477313;2480.3179125740476;2482.8013758899424;2396.7123945126696;2440.853548787061;2466.235467005234;2472.27068014936;2500.4499010217905;11030.613928512463;11033.062189713884;11106.278091924;11120.443977801158;11143.665690029313;5386.8922621547745;6674.03226532155;6694.163229596301;6698.4641228701585;6702.899456508157;3902.4731661225655;4190.078182799779;4262.91474170518;4287.6141147296585;4290.69965102443;1220.2251570252865;1227.7667243223848;1245.975080498385;1273.057937126529;1274.3055035005943;13970.51103211393;14430.989830711855;16188.433404830892;16248;16366;5455.809046683347;5553.916691249635;5724.427557244326;5768.105943578668;5776.42235776419;11508.884911150948;11612.12909031826;11644.24312419707;11678.6496405108;11716.17986740937;6533;6560.967195164089;6563.540552161303;6564.113844631009;6565.540412171103;6076.605020673642;6106.6336019838955;6214.25428948529;6219.502439804804;6254.249490061131;4272.893177670583;4350.912981740347;4366.497852746911;4391.099824535962;4407.2507673695545;1020.4438580973388;1256;1266.3858028855998;1297.5977446991462;1302.3423171298623;792.4254915186443;793.7182300283432;819.5574389829426;825.2366560931115;849.6728759427541;6013.579049466583;6031.819045428557;6241.251049873953;6266.561340706106;6276.592021518587;523.1759978034611;580.7415700013538;590.1354515634616;603.1706403694919;608.4615115622618;3763.5295588051495;3829.3872980323013;3902.980485097613;4154.065335299533;4231.344141658048;14280.642983925485;14926;14955.102693838404;14971.550853474413;14981.775273370908;14831.925840370945;14836.480723174636;14837.480688175861;14851.777223341658;14856.925715371353;7616.8476630468185;8070.435069545076;8332;8485.533295668674;8535.317174626016;5640.097584386478;6326.335734747785;6589.341065893564;6751.831204219738;6807.795766127024;11278.210525263154;12883.613491595268;13192.604221873358;13219.537316193888;13280.80078798838;13304.13523120994;13450.192988420726;13497.86502134966;13559.728805424034;13725.656858578615;8870.290376769713;9133.223675987616;9141.3601047926;9145.451272923647;9162.908370916206;10135.493225338556;10219.029192226726;10298.073173414428;10303.948480257552;10309.381437113798;8679.783005424915;8692.565371731514;8735.91264087351;8755.124487551195;8790.296776257755;8437.704680336159;8483.787905302417;8501;8519.275861551838;8519.74440766778;6544.574602650812;6853.451723862178;7850.921490785019;8150.062742784656;8196.918030819616;5995.46040856325;6007.30915944672;6049.939500605114;6190.226221722285;6208.161898143693;5989.64062156272;6015.2781666200335;6148.4773794227995;6212.627242365473;6298.71655775482 08:53:45 INFO - PID 1928 | |4;prototype.html;362.78958204241536;384.4387194695744;411.9485064366953;413.144790284112;413.73520946594164;6192.845178870531;6270.52971027173;6361.427471527573;6428.26075001375;6428.9999999999945;6069.696515174246;6185.288691800433;6185.65978871163;6209.57179848635;6213.949396699089;4370.84702035428;4447.866564003069;4544.250198717207;4551.408316918796;4590.816367345302;4086.693497987648;4202.82185187775;4423.491298500681;4521.773911304422;4544.522825103366;3709.8887033388905;3926.548446928611;4469.3296005599095;4494.258447356182;4501.7974191161475;4112.157007813391;4490.393796837434;4527.094581083797;4543.296738440623;4546.54534546546;2937.574051762504;3781.092537790938;3837.3668344723087;3860.6332398422105;3867.613238676138;5445.673259604397;5597.033572206737;5635.661860288355;5991.131285963555;6064.514838812885;4910.926336104962;5002.274670172841;5386.245925570423;5417.539509141728;5420.620556561043;5236.031334203185;5399.7030163341;5466.671999679992;6414.134091897581;6691.1636045494315;3833.884983450501;3900.258950799339;4073.6130067643526;4401.625861801751;4411.448568928884;6526.673666316665;6944.097267355262;7070.197099739534;7113.715451381939;7152.821179470503;3785.8674946377296;4259.914801703949;4479.776011199427;4499.437570303712;4502.392177056121;3814.5803961564206;4399.5160532341415;4568.56598623137;4575.519570445122;4592.2422800238255;5256.395514515803;5403.000444917703;5409.24270602116;5417.160340147283;5422.75597598109;6697;6702.396784289437;6707.195136583641;6729.495287853332;6737.8652426951185;1199.5621598116625;1448.2251995182526;1557.493814510266;1560.173108252628;1561.5783738390571;890.7105190813012;955.102203928319;992.1417973452911;1025.3181634213165;1027.5786927359895;5305.840824775263;6306;6315.431611154926;6327.873442531123;6344.936550634527;5933.406659334032;6644.96677516619;6854.725810967506;6970.163580370388;6970.756023539253;5862.970685146547;6263.843403914939;6277.748890044439;6287.49700023997;6293.199526210884;3457.2048428861503;3554.413521768879;3555.5022296878456;3591.5690117186105;3600.4419315006216;5904.4685978261305;6448.968165093563;6948.652567371712;7165.426765858719;7235.384992275715;6822.829429264209;7160.534565253241;7170.856582868313;7179.317964793337;7205.315495027964;6565.277819439762;6717.899231511531;6965.547239429421;7181.820454488679;7215.567065976058;5807.883842323215;6606.240282367397;6785.846406110874;6798.728050878108;6987.7204911802955;6703.731850726112;6768.830779230559;6829.69266383004;6840.3501667341525;6883.174019117738;5959.612625179349;6290.370962903673;6970.965145174445;7175.31834475724;7248;4958.256261560795;5026.547610715053;5119.950410165981;5141.254518094781;5142.331496905378;6360.459360954185;6933.5146539743755;6972.51192416526;7007.789766307019;7028.262032486719;6696.062551242732;6751.156105486814;6751.189857217166;6758.8648227036165;6774.966125169342;5679.77280908759;6544.312847150981;6731.259561448334;6796.72813087471;6810.421114205348;5819;5863.413658634103;6675.499337549801;6690.364415380531;6777.525573210025;5299.152135658276;6031.879362412639;6328.873422531616;6447;6682.866342673216;5420.945790542044;6538.771143009972;6556.901646475211;6567.835804104936;6567.835804104936 08:53:45 INFO - PID 1928 | |5;yui.html;712.8788106021976;759.3583422008403;815.2784785464862;838.1367191792456;866.2723312417568;15387.692246155084;15912.681746365066;16389.75415368769;18259.36092236772;19193.904030479862;17704.11479426027;18014.009229492374;18313.542161445937;18504.982225977565;18683.439496815085;1011.7470632341915;1041.697907606793;1043.4730461117126;1048.7692707604315;1049.6903413493;991.420019288717;1024.3802499487815;1045.7176562328166;1049.6273822792914;1052.6473631333513;1085.6091806949491;1096.1121491591796;1096.819024860901;1097.1387460843239;1105.8728246251703;1089.3355053417408;1089.5423921952759;1092.317301686446;1093.191038631411;1093.2511229807608;781.581853708269;788.5820515126934;804.1998211779259;808.9887640449438;809.2110192562263;14280.428782848674;15414.460493882772;16838.747418788727;16887;16924.323027078903;16734.49796506107;16978.405755798613;17334.91332543342;17435.389761358292;17597;13901.235432051233;14165.079269847427;14276.357563909649;14306.356213970397;14445.349959251858;3522.647735226457;3538.716902647782;3546.4503002034726;3552.0409489437966;3552.2007548301685;99244.01511969793;116120;121068;122286.77713222931;123876.38061809723;13331.66670833237;13539.39072741729;13620.616392131338;13620.93189534046;13656.268746250591;25299.241022769347;25378.746212538008;25758.09846655358;26023.869880650476;26147.084852030086;7055.259197784261;7531.472796904164;7858.803529911798;7859.6463159157975;7865.7246996356;3821.464994900716;3898.785566793797;3944.36890097583;3993.72043956926;4003.199360127986;3955.268275369066;4022.597740225954;4023.054582173187;4025.19496100781;4028.234635419261;871.8535126308883;939.793245485992;945.652445265494;950.4819873169139;950.95720692569;582.3099626942087;610.1762620462429;643.9710213040424;654.1496055128314;663.3002182697204;11323.943380283046;11776.234544754563;11789.528418863149;12028.639140825615;12186.268823870596;641.5380925733465;648.2383199740286;653.7319698923419;656.7405874679475;659.0772917914958;5215.8614967862995;5528.723563821793;5621.662700237999;5657.858553536194;5676.943230567724;61060.69469652712;83153.58423207967;86093.5695321532;92934.53532732428;103368.93262134715;71133;84299;86279.70580441298;88137;88362.55818720865;64929.67535162294;82737;83006.16993830106;83477.16522834694;84870;10316.329438586468;10400.739981500523;12038.157328986887;12398.690032749253;12591.370431478206;39112;39114.4132838002;39476.21047578975;39693.60306396899;39863.60136398715;52108.73945630248;52248.738756305975;52891.94216115579;53021.469785301655;53591.73204133954;15847.366105356117;16161.919190403973;16275.267612957683;16463.83536164623;16689.66620667604;17448.21483033292;17838.821611784235;18141.637167256846;18240.0879955999;18649.90675046616;16659.33978099265;17046.82953170502;17257.223424946158;17313;17410;16198.190090495193;16678.49964501067;17072.317107315568;17373;17394.91302543479;16330.183490825173;16753.74869376985;16967.321307147577;17037.318507260068;17126.743098853778;11406;11422.029127523921;11650.88349116532;12060.51757929673;12159;10578.841317380091;12754.553590624284;13305.135166214164;13401.195928244337;13597.66005849822 08:53:45 INFO - PID 1928 | __end_tp_report 08:53:45 INFO - PID 1928 | __start_cc_report 08:53:45 INFO - PID 1928 | _x_x_mozilla_cycle_collect,410 08:53:45 INFO - PID 1928 | __end_cc_report 08:53:45 INFO - PID 1928 | __startTimestamp1494431625658__endTimestamp 08:53:45 INFO - PID 1928 | 08:53:45 INFO - PID 1928 | ------- Summary: start ------- 08:53:45 INFO - PID 1928 | Number of tests: 6 08:53:45 INFO - PID 1928 | 08:53:45 INFO - PID 1928 | [#0] dojo.html Cycles:165 Average:6222.07 Median:6471.81 stddev:761.77 (11.8%) stddev-sans-first:721.03 08:53:45 INFO - PID 1928 | Values: 3002.6 3571.9 3638.1 3640.6 3646.7 6150.2 6205.6 6246.6 6293.8 6301.4 5182.8 6218.2 6247.2 6266.1 6339.2 4518.5 5531.5 5567.0 5567.4 5717.7 4523.3 5624.6 5680.8 5732.2 5734.4 5447.5 5559.5 5587.2 5620.6 5665.7 5595.9 5610.5 5616.4 5661.3 5673.7 5149.8 5561.9 5564.0 5577.3 5578.5 5055.7 5794.0 6282.9 6302.5 6382.7 6408.9 6499.0 6627.9 6747.5 6768.2 6846.1 6867.9 6868.5 6874.3 6874.7 6294.7 6317.2 6349.9 6355.7 6357.2 6754.9 6843.3 7001.4 7004.3 7025.6 6567.2 6637.2 6662.6 6666.5 6687.1 6792.0 6815.8 6830.1 6831.5 6855.1 6662.4 6678.9 6758.7 6759.9 6769.6 6512.5 6617.0 6618.8 6664.7 6702.4 5401.4 5411.3 5414.7 5422.2 5425.9 4746.0 4763.2 4828.4 4828.9 4846.3 6232.1 6319.3 6323.0 6351.7 6377.6 6861.8 6899.8 6904.6 6905.3 6913.1 6358.2 6370.7 6378.8 6393.9 6399.6 5821.4 5971.3 5973.6 6076.2 6081.3 6850.3 6880.4 6892.8 7022.7 7061.0 6630.4 6752.1 6897.8 6908.4 6917.0 5938.0 6793.3 6925.2 7033.5 7048.5 6756.1 6769.4 6793.7 6794.4 6811.3 5562.7 6706.7 6749.0 6792.9 6935.8 6568.5 6632.8 6660.8 6663.5 6671.5 6642.3 6643.4 6645.1 6647.2 6660.6 6287.2 6342.2 6368.8 6457.1 6527.0 6597.5 6645.9 6655.6 6702.3 6714.0 6471.8 6486.1 6486.3 6492.8 6532.8 08:53:45 INFO - PID 1928 | 08:53:45 INFO - PID 1928 | [#1] ext.html Cycles:180 Average:44646.63 Median:177952.68 stddev:79029.18 (44.4%) stddev-sans-first:79182.12 08:53:45 INFO - PID 1928 | Values: 737.7 837.6 1052.9 1180.6 1243.5 28905.8 29952.9 33580.0 37362.6 37473.3 37576.6 37728.8 37730.8 37748.2 37752.2 7640.9 7772.1 7788.4 7803.1 8143.1 8379.9 8484.6 8520.3 8520.9 8536.8 8486.9 8526.1 8528.3 8543.9 8551.8 8515.9 8540.0 8560.4 8572.1 8589.4 7694.3 7846.9 8214.5 8260.4 8311.6 37243.8 37296.8 37321.8 37355.1 37467.7 3598.3 3635.3 3635.5 3638.7 3640.1 30208.8 30260.7 30262.4 30474.0 30601.1 2997.3 3045.0 3045.5 3054.4 3059.4 302413.0 302738.5 303044.0 303125.0 303656.0 38824.4 39529.8 47190.5 47312.5 47499.8 48484.0 50444.5 50667.0 50675.2 50757.0 14148.9 14220.0 14248.6 14252.4 14292.0 11405.7 11456.7 11463.5 11467.3 11469.8 13461.3 13480.8 13508.1 13508.3 13627.8 5850.7 5934.7 5941.7 5978.5 5980.6 888.9 1028.4 1032.9 1055.2 1074.6 24100.9 24241.6 24274.0 24279.6 24290.6 1117.7 1120.6 1148.8 1158.3 1159.8 11872.9 12450.4 12454.8 12455.6 12464.9 321894.4 323546.0 323707.0 323714.0 324714.0 53038.2 53391.7 53447.0 53453.5 53457.7 1352.3 1460.0 1487.5 1549.2 1572.5 18786.7 22314.3 22750.0 23987.0 24242.0 32359.4 32485.5 38747.6 38923.0 38927.6 274274.0 274491.6 274851.6 275332.0 276366.0 33381.5 34140.0 34239.8 34674.7 34922.1 37612.6 42279.0 43402.6 43642.0 44445.8 28876.0 29962.6 30157.6 30378.4 30687.5 21394.6 29633.9 30267.4 30546.0 30768.2 36575.7 36599.5 36607.5 36646.8 36724.8 29083.4 29989.6 30034.0 30714.5 30947.4 23997.5 24080.8 24105.5 24109.1 24373.6 08:53:45 INFO - PID 1928 | 08:53:45 INFO - PID 1928 | [#2] jquery.html Cycles:180 Average:35785.51 Median:14991.25 stddev:93346.55 (622.7%) stddev-sans-first:93570.66 08:53:45 INFO - PID 1928 | Values: 429.9 436.9 437.3 459.0 459.0 18653.6 20195.5 20912.7 21080.5 21130.9 21152.9 22159.6 23001.8 23238.7 23348.9 6203.3 6356.8 6381.2 6385.5 6420.6 5978.9 6226.8 6264.0 6276.1 6287.7 6048.2 6182.4 6189.1 6233.6 6258.4 5448.6 6143.9 6209.9 6239.1 6274.9 4628.5 5501.6 5581.8 5582.7 5605.3 27628.0 27702.3 27762.7 27828.3 28019.9 2175.0 2251.4 2256.3 2259.7 2265.7 17659.3 17715.8 18089.1 18195.6 18372.0 2138.5 2168.1 2213.7 2215.3 2219.5 132285.0 158602.0 162940.0 165906.0 167723.3 12574.5 13008.4 13083.5 13083.5 13133.3 16986.2 17036.4 17058.0 17060.7 17060.9 8166.7 8446.0 8485.0 8584.5 8621.2 6483.6 6499.4 7737.7 7787.9 7901.0 7169.0 7600.4 7927.6 8026.2 8075.2 2174.7 2186.2 2194.9 2197.5 2354.5 880.0 889.2 935.4 950.7 954.5 20596.3 21228.4 21230.7 21296.4 21468.6 835.4 979.7 1001.1 1016.1 1019.6 8789.3 9006.7 10097.3 10990.6 11016.8 537075.0 540158.0 549927.0 557205.0 559689.0 127673.0 129055.4 134139.0 134898.0 135150.0 1203.7 1229.9 1251.6 1253.4 1256.5 21361.8 22546.6 22747.0 22791.4 22850.8 56501.9 57439.1 57993.0 58240.7 59173.7 75725.7 78897.0 79332.0 79352.0 79499.6 7721.4 8981.8 9084.4 9125.0 9193.5 13896.4 13961.9 14103.9 14260.7 14316.8 11345.8 11823.6 11928.0 12114.4 12205.0 9217.4 9302.8 9441.3 9530.0 11320.3 11528.5 11980.6 12116.3 12133.5 12231.6 7715.3 9051.0 9056.1 9179.0 9302.5 9565.4 12009.9 12035.3 12175.9 12333.9 08:53:45 INFO - PID 1928 | 08:53:45 INFO - PID 1928 | [#3] mootools.html Cycles:180 Average:6915.22 Median:3865.93 stddev:4354.03 (112.6%) stddev-sans-first:4345.35 08:53:45 INFO - PID 1928 | Values: 1239.5 1447.4 1449.5 1461.7 1470.9 11060.9 11108.3 11610.7 11649.2 11677.6 9496.7 11579.7 11664.0 11667.8 11675.0 2482.8 2521.6 2537.5 2539.9 2542.3 2490.6 2518.7 2531.0 2533.3 2533.7 2442.9 2490.0 2517.1 2521.7 2521.9 2442.2 2474.2 2479.8 2480.3 2482.8 2396.7 2440.9 2466.2 2472.3 2500.4 11030.6 11033.1 11106.3 11120.4 11143.7 5386.9 6674.0 6694.2 6698.5 6702.9 3902.5 4190.1 4262.9 4287.6 4290.7 1220.2 1227.8 1246.0 1273.1 1274.3 13970.5 14431.0 16188.4 16248.0 16366.0 5455.8 5553.9 5724.4 5768.1 5776.4 11508.9 11612.1 11644.2 11678.6 11716.2 6533.0 6561.0 6563.5 6564.1 6565.5 6076.6 6106.6 6214.3 6219.5 6254.2 4272.9 4350.9 4366.5 4391.1 4407.3 1020.4 1256.0 1266.4 1297.6 1302.3 792.4 793.7 819.6 825.2 849.7 6013.6 6031.8 6241.3 6266.6 6276.6 523.2 580.7 590.1 603.2 608.5 3763.5 3829.4 3903.0 4154.1 4231.3 14280.6 14926.0 14955.1 14971.6 14981.8 14831.9 14836.5 14837.5 14851.8 14856.9 7616.8 8070.4 8332.0 8485.5 8535.3 5640.1 6326.3 6589.3 6751.8 6807.8 11278.2 12883.6 13192.6 13219.5 13280.8 13304.1 13450.2 13497.9 13559.7 13725.7 8870.3 9133.2 9141.4 9145.5 9162.9 10135.5 10219.0 10298.1 10303.9 10309.4 8679.8 8692.6 8735.9 8755.1 8790.3 8437.7 8483.8 8501.0 8519.3 8519.7 6544.6 6853.5 7850.9 8150.1 8196.9 5995.5 6007.3 6049.9 6190.2 6208.2 5989.6 6015.3 6148.5 6212.6 6298.7 08:53:45 INFO - PID 1928 | 08:53:45 INFO - PID 1928 | [#4] prototype.html Cycles:180 Average:5353.47 Median:6048.20 stddev:1722.44 (28.5%) stddev-sans-first:1686.04 08:53:45 INFO - PID 1928 | Values: 362.8 384.4 411.9 413.1 413.7 6192.8 6270.5 6361.4 6428.3 6429.0 6069.7 6185.3 6185.7 6209.6 6213.9 4370.8 4447.9 4544.3 4551.4 4590.8 4086.7 4202.8 4423.5 4521.8 4544.5 3709.9 3926.5 4469.3 4494.3 4501.8 4112.2 4490.4 4527.1 4543.3 4546.5 2937.6 3781.1 3837.4 3860.6 3867.6 5445.7 5597.0 5635.7 5991.1 6064.5 4910.9 5002.3 5386.2 5417.5 5420.6 5236.0 5399.7 5466.7 6414.1 6691.2 3833.9 3900.3 4073.6 4401.6 4411.4 6526.7 6944.1 7070.2 7113.7 7152.8 3785.9 4259.9 4479.8 4499.4 4502.4 3814.6 4399.5 4568.6 4575.5 4592.2 5256.4 5403.0 5409.2 5417.2 5422.8 6697.0 6702.4 6707.2 6729.5 6737.9 1199.6 1448.2 1557.5 1560.2 1561.6 890.7 955.1 992.1 1025.3 1027.6 5305.8 6306.0 6315.4 6327.9 6344.9 5933.4 6645.0 6854.7 6970.2 6970.8 5863.0 6263.8 6277.7 6287.5 6293.2 3457.2 3554.4 3555.5 3591.6 3600.4 5904.5 6449.0 6948.7 7165.4 7235.4 6822.8 7160.5 7170.9 7179.3 7205.3 6565.3 6717.9 6965.5 7181.8 7215.6 5807.9 6606.2 6785.8 6798.7 6987.7 6703.7 6768.8 6829.7 6840.4 6883.2 5959.6 6290.4 6971.0 7175.3 7248.0 4958.3 5026.5 5120.0 5141.3 5142.3 6360.5 6933.5 6972.5 7007.8 7028.3 6696.1 6751.2 6751.2 6758.9 6775.0 5679.8 6544.3 6731.3 6796.7 6810.4 5819.0 5863.4 6675.5 6690.4 6777.5 5299.2 6031.9 6328.9 6447.0 6682.9 5420.9 6538.8 6556.9 6567.8 6567.8 08:53:45 INFO - PID 1928 | 08:53:45 INFO - PID 1928 | [#5] yui.html Cycles:180 Average:20705.75 Median:18077.82 stddev:27918.48 (154.4%) stddev-sans-first:27956.44 08:53:45 INFO - PID 1928 | Values: 712.9 759.4 815.3 838.1 866.3 15387.7 15912.7 16389.8 18259.4 19193.9 17704.1 18014.0 18313.5 18505.0 18683.4 1011.7 1041.7 1043.5 1048.8 1049.7 991.4 1024.4 1045.7 1049.6 1052.6 1085.6 1096.1 1096.8 1097.1 1105.9 1089.3 1089.5 1092.3 1093.2 1093.3 781.6 788.6 804.2 809.0 809.2 14280.4 15414.5 16838.7 16887.0 16924.3 16734.5 16978.4 17334.9 17435.4 17597.0 13901.2 14165.1 14276.4 14306.4 14445.3 3522.6 3538.7 3546.5 3552.0 3552.2 99244.0 116120.0 121068.0 122286.8 123876.4 13331.7 13539.4 13620.6 13620.9 13656.3 25299.2 25378.7 25758.1 26023.9 26147.1 7055.3 7531.5 7858.8 7859.6 7865.7 3821.5 3898.8 3944.4 3993.7 4003.2 3955.3 4022.6 4023.1 4025.2 4028.2 871.9 939.8 945.7 950.5 951.0 582.3 610.2 644.0 654.1 663.3 11323.9 11776.2 11789.5 12028.6 12186.3 641.5 648.2 653.7 656.7 659.1 5215.9 5528.7 5621.7 5657.9 5676.9 61060.7 83153.6 86093.6 92934.5 103368.9 71133.0 84299.0 86279.7 88137.0 88362.6 64929.7 82737.0 83006.2 83477.2 84870.0 10316.3 10400.7 12038.2 12398.7 12591.4 39112.0 39114.4 39476.2 39693.6 39863.6 52108.7 52248.7 52891.9 53021.5 53591.7 15847.4 16161.9 16275.3 16463.8 16689.7 17448.2 17838.8 18141.6 18240.1 18649.9 16659.3 17046.8 17257.2 17313.0 17410.0 16198.2 16678.5 17072.3 17373.0 17394.9 16330.2 16753.7 16967.3 17037.3 17126.7 11406.0 11422.0 11650.9 12060.5 12159.0 10578.8 12754.6 13305.1 13401.2 13597.7 08:53:45 INFO - PID 1928 | -------- Summary: end -------- 08:53:45 INFO - PID 1928 | 08:53:46 INFO - TEST-INFO | 1928: exit 0 08:53:46 INFO - TEST-OK | dromaeo_css | took 1114778ms 08:53:46 INFO - TEST-START | kraken 08:53:46 INFO - operating with platform_type : mac_ 08:53:46 INFO - Initialising browser for kraken test... 08:53:46 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpJBAFMw/profile http://localhost:55859/getInfo.html) 08:53:48 INFO - PID 1934 | 2017-05-10 08:53:48.837 plugin-container[1935:21554] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9443, name = 'com.apple.tsm.portname' 08:53:48 INFO - PID 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:48 INFO - PID 1934 | 2017-05-10 08:53:48.841 plugin-container[1935:21554] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9627, name = 'com.apple.CFPasteboardClient' 08:53:48 INFO - PID 1934 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:48 INFO - PID 1934 | 2017-05-10 08:53:48.841 plugin-container[1935:21554] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:53:48 INFO - PID 1934 | __metrics Screen width/height:1600/1200 08:53:48 INFO - PID 1934 | colorDepth:24 08:53:48 INFO - PID 1934 | Browser inner width/height: 1024/691 08:53:48 INFO - PID 1934 | __metrics 08:53:49 INFO - PID 1934 | 08:53:49 INFO - PID 1934 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 08:53:49 INFO - PID 1934 | 08:53:50 INFO - TEST-INFO | 1934: exit 0 08:53:50 INFO - Browser initialized. 08:53:50 INFO - Running cycle 1/1 for kraken test... 08:53:50 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpJBAFMw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:53:52 INFO - PID 1937 | 2017-05-10 08:53:52.148 plugin-container[1938:21716] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433b, name = 'com.apple.tsm.portname' 08:53:52 INFO - PID 1937 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:52 INFO - PID 1937 | 2017-05-10 08:53:52.149 plugin-container[1938:21716] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:53:52 INFO - PID 1937 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:52 INFO - PID 1937 | 2017-05-10 08:53:52.149 plugin-container[1938:21716] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:53:52 INFO - PID 1937 | 2017-05-10 08:53:52.680 plugin-container[1939:21760] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 08:53:52 INFO - PID 1937 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:52 INFO - PID 1937 | 2017-05-10 08:53:52.681 plugin-container[1939:21760] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 08:53:52 INFO - PID 1937 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:53:52 INFO - PID 1937 | 2017-05-10 08:53:52.681 plugin-container[1939:21760] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:54:02 INFO - PID 1937 | RSS: Main: 229036032 08:54:02 INFO - PID 1937 | 08:54:05 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55859/tests/kraken/driver.html?testName=audio-beat-detection) 08:54:05 INFO - PID 1937 | RSS: Main: 242012160 08:54:05 INFO - PID 1937 | 08:54:08 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55859/tests/kraken/driver.html?testName=audio-dft) 08:54:09 INFO - PID 1937 | RSS: Main: 250425344 08:54:09 INFO - PID 1937 | 08:54:12 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55859/tests/kraken/driver.html?testName=audio-fft) 08:54:13 INFO - PID 1937 | RSS: Main: 252633088 08:54:13 INFO - PID 1937 | 08:54:14 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55859/tests/kraken/driver.html?testName=audio-oscillator) 08:54:15 INFO - PID 1937 | RSS: Main: 254930944 08:54:15 INFO - PID 1937 | 08:54:17 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55859/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:54:18 INFO - PID 1937 | RSS: Main: 255434752 08:54:18 INFO - PID 1937 | 08:54:21 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55859/tests/kraken/driver.html?testName=imaging-darkroom) 08:54:21 INFO - PID 1937 | RSS: Main: 261324800 08:54:21 INFO - PID 1937 | 08:54:24 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55859/tests/kraken/driver.html?testName=imaging-desaturate) 08:54:25 INFO - PID 1937 | RSS: Main: 263172096 08:54:25 INFO - PID 1937 | 08:54:28 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55859/tests/kraken/driver.html?testName=json-parse-financial) 08:54:29 INFO - PID 1937 | RSS: Main: 262717440 08:54:29 INFO - PID 1937 | 08:54:30 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55859/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:54:31 INFO - PID 1937 | RSS: Main: 261906432 08:54:31 INFO - PID 1937 | 08:54:33 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55859/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:54:33 INFO - PID 1937 | RSS: Main: 262144000 08:54:33 INFO - PID 1937 | 08:54:35 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55859/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:54:36 INFO - PID 1937 | RSS: Main: 261697536 08:54:36 INFO - PID 1937 | 08:54:39 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55859/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:54:40 INFO - PID 1937 | RSS: Main: 261959680 08:54:40 INFO - PID 1937 | 08:54:43 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55859/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:54:44 INFO - PID 1937 | RSS: Main: 261980160 08:54:44 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | Cycle 1(1): loaded http://localhost:55859/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55859/tests/kraken/driver.html?testName=ai-astar) 08:54:45 INFO - PID 1937 | RSS: Main: 263733248 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | __start_tp_report 08:54:45 INFO - PID 1937 | _x_x_mozilla_page_load 08:54:45 INFO - PID 1937 | _x_x_mozilla_page_load_details 08:54:45 INFO - PID 1937 | |i|pagename|runs| 08:54:45 INFO - PID 1937 | |0;ai-astar;135;166;157;113;119;163;163;143;115;116 08:54:45 INFO - PID 1937 | |1;audio-beat-detection;137;106;128;144;113;112;110;112;147;112 08:54:45 INFO - PID 1937 | |2;audio-dft;203;200;208;230;226;204;200;201;201;199 08:54:45 INFO - PID 1937 | |3;audio-fft;83;58;59;84;61;62;62;62;80;53 08:54:45 INFO - PID 1937 | |4;audio-oscillator;85;71;85;71;71;69;93;104;85;86 08:54:45 INFO - PID 1937 | |5;imaging-gaussian-blur;89;110;89;89;106;77;114;106;78;126 08:54:45 INFO - PID 1937 | |6;imaging-darkroom;99;96;104;151;153;96;96;100;102;129 08:54:45 INFO - PID 1937 | |7;imaging-desaturate;93;94;90;92;90;92;75;132;140;127 08:54:45 INFO - PID 1937 | |8;json-parse-financial;50;49;50;51;51;51;51;59;51;46 08:54:45 INFO - PID 1937 | |9;json-stringify-tinderbox;58;55;60;60;62;60;56;57;58;59 08:54:45 INFO - PID 1937 | |10;stanford-crypto-aes;83;83;83;82;84;84;83;85;81;83 08:54:45 INFO - PID 1937 | |11;stanford-crypto-ccm;142;151;153;138;146;132;142;161;218;182 08:54:45 INFO - PID 1937 | |12;stanford-crypto-pbkdf2;187;184;185;179;184;180;187;183;186;194 08:54:45 INFO - PID 1937 | |13;stanford-crypto-sha256-iterative;68;70;68;71;74;70;71;69;70;71 08:54:45 INFO - PID 1937 | __end_tp_report 08:54:45 INFO - PID 1937 | __start_cc_report 08:54:45 INFO - PID 1937 | _x_x_mozilla_cycle_collect,1017 08:54:45 INFO - PID 1937 | __end_cc_report 08:54:45 INFO - PID 1937 | __startTimestamp1494431685979__endTimestamp 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | ------- Summary: start ------- 08:54:45 INFO - PID 1937 | Number of tests: 14 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#0] ai-astar Cycles:10 Average:139.00 Median:150.00 stddev:22.16 (14.8%) stddev-sans-first:23.45 08:54:45 INFO - PID 1937 | Values: 135.0 166.0 157.0 113.0 119.0 163.0 163.0 143.0 115.0 116.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#1] audio-beat-detection Cycles:10 Average:122.10 Median:120.50 stddev:15.46 (12.8%) stddev-sans-first:15.43 08:54:45 INFO - PID 1937 | Values: 137.0 106.0 128.0 144.0 113.0 112.0 110.0 112.0 147.0 112.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#2] audio-dft Cycles:10 Average:207.20 Median:203.50 stddev:11.30 (5.6%) stddev-sans-first:11.88 08:54:45 INFO - PID 1937 | Values: 203.0 200.0 208.0 230.0 226.0 204.0 200.0 201.0 201.0 199.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#3] audio-fft Cycles:10 Average:66.40 Median:62.00 stddev:11.36 (18.3%) stddev-sans-first:10.35 08:54:45 INFO - PID 1937 | Values: 83.0 58.0 59.0 84.0 61.0 62.0 62.0 62.0 80.0 53.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#4] audio-oscillator Cycles:10 Average:82.00 Median:85.00 stddev:11.45 (13.5%) stddev-sans-first:12.09 08:54:45 INFO - PID 1937 | Values: 85.0 71.0 85.0 71.0 71.0 69.0 93.0 104.0 85.0 86.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:77.50 stddev:16.31 (21.0%) stddev-sans-first:16.94 08:54:45 INFO - PID 1937 | Values: 89.0 110.0 89.0 89.0 106.0 77.0 114.0 106.0 78.0 126.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#6] imaging-darkroom Cycles:10 Average:112.60 Median:124.50 stddev:22.93 (18.4%) stddev-sans-first:23.79 08:54:45 INFO - PID 1937 | Values: 99.0 96.0 104.0 151.0 153.0 96.0 96.0 100.0 102.0 129.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#7] imaging-desaturate Cycles:10 Average:102.50 Median:91.00 stddev:21.93 (24.1%) stddev-sans-first:22.99 08:54:45 INFO - PID 1937 | Values: 93.0 94.0 90.0 92.0 90.0 92.0 75.0 132.0 140.0 127.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:3.25 (6.4%) stddev-sans-first:3.43 08:54:45 INFO - PID 1937 | Values: 50.0 49.0 50.0 51.0 51.0 51.0 51.0 59.0 51.0 46.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:59.50 stddev:2.12 (3.6%) stddev-sans-first:2.24 08:54:45 INFO - PID 1937 | Values: 58.0 55.0 60.0 60.0 62.0 60.0 56.0 57.0 58.0 59.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.00 stddev:1.10 (1.3%) stddev-sans-first:1.17 08:54:45 INFO - PID 1937 | Values: 83.0 83.0 83.0 82.0 84.0 84.0 83.0 85.0 81.0 83.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#11] stanford-crypto-ccm Cycles:10 Average:156.50 Median:152.00 stddev:25.75 (16.9%) stddev-sans-first:26.77 08:54:45 INFO - PID 1937 | Values: 142.0 151.0 153.0 138.0 146.0 132.0 142.0 161.0 218.0 182.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.90 Median:185.50 stddev:4.18 (2.3%) stddev-sans-first:4.36 08:54:45 INFO - PID 1937 | Values: 187.0 184.0 185.0 179.0 184.0 180.0 187.0 183.0 186.0 194.0 08:54:45 INFO - PID 1937 | 08:54:45 INFO - PID 1937 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:70.50 stddev:1.75 (2.5%) stddev-sans-first:1.67 08:54:45 INFO - PID 1937 | Values: 68.0 70.0 68.0 71.0 74.0 70.0 71.0 69.0 70.0 71.0 08:54:45 INFO - PID 1937 | -------- Summary: end -------- 08:54:45 INFO - PID 1937 | 08:54:46 INFO - TEST-INFO | 1937: exit 0 08:54:46 INFO - TEST-OK | kraken | took 60574ms 08:54:46 INFO - SUITE-END | took 1175s 08:54:47 INFO - Completed test suite (00:19:35) 08:54:47 INFO - javascript benchmark 08:54:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7837.789317363586, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3002.609660744103, 3571.874984375546, 3638.126849556104, 3640.5995340512554, 3646.671799538042, 6150.200473938393, 6205.596636218648, 6246.625202487854, 6293.842653933648, 6301.369863013696, 5182.766775495103, 6218.1916350874435, 6247.219097612799, 6266.091416744581, 6339.239291285052, 4518.502964673899, 5531.502164805167, 5566.972165139179, 5567.4432556744405, 5717.685527295997, 4523.341066376937, 5624.578156638248, 5680.801171958982, 5732.22614946981, 5734.4265573442735, 5447.4824891635235, 5559.499645031967, 5587.217789509472, 5620.606557540974, 5665.716714164296, 5595.860103497404, 5610.523105536035, 5616.438356164392, 5661.264035675377, 5673.6879471629045, 5149.84550463483, 5561.916571251434, 5564.0, 5577.330720313547, 5578.525825304854, 5055.721935293557, 5794.043982742843, 6282.874342513124, 6302.527310451688, 6382.68086595673, 6408.903866442007, 6499.0, 6627.93372066278, 6747.493937954673, 6768.221654509745, 6846.144231971004, 6867.862642747117, 6868.519203655746, 6874.312568743136, 6874.65626718667, 6294.65379404128, 6317.210348706411, 6349.873002539924, 6355.745770169187, 6357.205349331333, 6754.898676519758, 6843.315668433117, 7001.36987671112, 7004.334588214111, 7025.613591252428, 6567.211934567882, 6637.1703537057865, 6662.56693314933, 6666.500012499082, 6687.097241872285, 6792.0, 6815.829604259834, 6830.146231721035, 6831.4534837212905, 6855.143107111611, 6662.410597611912, 6678.899816502756, 6758.729650814011, 6759.898601520981, 6769.627670478171, 6512.511561632897, 6616.96691516549, 6618.8345291368105, 6664.666766661648, 6702.430293425016, 5401.378841433207, 5411.323584551931, 5414.675119492843, 5422.186671999231, 5425.945740542623, 4745.976270118627, 4763.237881938873, 4828.420589529209, 4828.903421931542, 4846.29728689338, 6232.127502149703, 6319.273283572448, 6323.0, 6351.682415879188, 6377.617342959437, 6861.759838405632, 6899.75850845218, 6904.585724856424, 6905.309469053054, 6913.1358580177475, 6358.1734374531015, 6370.713317900706, 6378.840528986812, 6393.904091438632, 6399.616023038632, 5821.388754180834, 5971.343152253248, 5973.641581505123, 6076.179715738407, 6081.300650425169, 6850.349216824394, 6880.415164710856, 6892.758753443605, 7022.683979221049, 7060.964695176492, 6630.370114839083, 6752.122224110834, 6897.827554311182, 6908.412784913338, 6917.0, 5938.020226662552, 6793.286704895915, 6925.238223795479, 7033.472489563201, 7048.471364647569, 6756.121704178426, 6769.356911093439, 6793.728250870107, 6794.3545363190415, 6811.284815094542, 5562.666240025612, 6706.698198580978, 6748.966255168692, 6792.864142717019, 6935.791926242221, 6568.507361947777, 6632.7678531253105, 6660.766873159416, 6663.533552651268, 6671.532992690465, 6642.302558231316, 6643.368879956396, 6645.1361323027695, 6647.169103862017, 6660.600363978177, 6287.245530536182, 6342.23893132827, 6368.840778980562, 6457.09600655918, 6526.967365163144, 6597.50518711089, 6645.867082658417, 6655.600663960178, 6702.262751097278, 6713.985804258605, 6471.805845824444, 6486.05952136947, 6486.254080780582, 6492.805215843532, 6532.804015879531], "value": 6170.581850184434, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.7086051009851, 837.5812093953024, 1052.8578641883344, 1180.6340034589277, 1243.471524602044, 28905.843766249352, 29952.850235748792, 33580.0, 37362.62637373625, 37473.25053498928, 37576.624233757655, 37728.81135594318, 37730.8113459433, 37748.24503509928, 37752.24495510095, 7640.9235907640905, 7772.145064042951, 7788.376929845599, 7803.141654418037, 8143.104258531586, 8379.916200837975, 8484.575771211446, 8520.275776559, 8520.8721869172, 8536.80484732138, 8486.915130848705, 8526.147385261485, 8528.275096616795, 8543.871841922377, 8551.786205344853, 8515.872261916045, 8540.017897941692, 8560.357973152039, 8572.14278572141, 8589.44168629037, 7694.269044440825, 7846.862204980303, 8214.4660597061, 8260.380471464665, 8311.625976830997, 37243.813780931196, 37296.81351593225, 37321.77495150681, 37355.06612334686, 37467.68863089806, 3598.280343931198, 3635.291118231946, 3635.509206257148, 3638.745287829853, 3640.1263696712863, 30208.8489557553, 30260.697393026006, 30262.394752105054, 30474.0, 30601.08196754101, 2997.2956355256715, 3045.0, 3045.482268014443, 3054.3585846972164, 3059.4034163338156, 302413.0, 302738.48630756926, 303044.0, 303125.0, 303656.0, 38824.41763373552, 39529.80235098806, 47190.52809471861, 47312.5268747315, 47499.81250468697, 48484.0, 50444.49555504471, 50667.0, 50675.23987140196, 50757.0, 14148.929255353658, 14220.00459967813, 14248.643783905278, 14252.358643860844, 14292.0, 11405.657830265105, 11456.713582160513, 11463.484143213409, 11467.311961282183, 11469.827952580717, 13461.25963072021, 13480.797788033187, 13508.054436189372, 13508.32458377097, 13627.795583066261, 5850.707464626752, 5934.732937017845, 5941.732622032019, 5978.491828194652, 5980.5514586405325, 888.9155530224618, 1028.3829702178782, 1032.879325931358, 1055.176961969665, 1074.6453670288786, 24100.87949560241, 24241.63637545438, 24274.0, 24279.63580546293, 24290.635640465407, 1117.7481221032497, 1120.6357933671588, 1148.8047032004579, 1158.3339579741682, 1159.8202278646654, 11872.88127118735, 12450.377481125908, 12454.81317780216, 12455.564055258023, 12464.937675311565, 321894.3905280459, 323546.0, 323707.0, 323714.0, 324714.0, 53038.204426933626, 53391.73304133455, 53447.0, 53453.465465345624, 53457.7327113362, 1352.317079874657, 1459.9562013139623, 1487.5165571189407, 1549.1866769945643, 1572.5439622509573, 18786.71819922675, 22314.330570082922, 22750.0, 23987.0, 24242.030318787052, 32359.352812944082, 32485.512717308786, 38747.61252387553, 38923.0, 38927.61072389353, 274274.0, 274491.62754186103, 274851.62574187, 275332.0, 276366.0, 33381.49927751037, 34140.0, 34239.801606943634, 34674.653253468154, 34922.12694682653, 37612.62387376091, 42279.0, 43402.56597433985, 43642.0, 44445.77777111223, 28876.0, 29962.550561742028, 30157.64290606972, 30378.39243215079, 30687.539686904267, 21394.57210855743, 29633.851830741572, 30267.394652106395, 30546.0, 30768.230794230323, 36575.71984980513, 36599.45100823543, 36607.45088823616, 36646.816765916, 36724.81637591795, 29083.418331632827, 29989.55015674723, 30034.0, 30714.539281910344, 30947.381052379278, 23997.52004959926, 24080.75919240785, 24105.51788964246, 24109.071274298363, 24373.63439548373], "value": 17286.19442962124, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.8581468115522, 436.8536540259012, 437.3330670727141, 458.9954100458995, 459.03602434886744, 18653.626927461442, 20195.495112622193, 20912.68630970533, 21080.472988175305, 21130.89434552829, 21152.894235528838, 22159.556808863854, 23001.76998230017, 23238.65142022871, 23348.949297281622, 6203.286622038467, 6356.841078973016, 6381.170447841774, 6385.457236134911, 6420.58266212697, 5978.940210597903, 6226.750929962796, 6264.0, 6276.089966954789, 6287.748490060415, 6048.213732214828, 6182.443580077792, 6189.102580125879, 6233.625982441045, 6258.374162583751, 5448.645838020516, 6143.863385273717, 6209.9068513972325, 6239.095331176999, 6274.937250627481, 4628.467726211495, 5501.642393244466, 5581.776728930838, 5582.720863956786, 5605.327360716699, 27628.0, 27702.3074423139, 27762.722372776214, 27828.30429239285, 28019.85990070057, 2175.0, 2251.3808702606752, 2256.3231030690727, 2259.728832540089, 2265.694131292271, 17659.293628254854, 17715.822841771547, 18089.09554522282, 18195.63608727818, 18371.98954057526, 2138.4653836540865, 2168.111074459464, 2213.734351877769, 2215.2578886073184, 2219.5117074243803, 132284.99999999904, 158602.0, 162940.0, 165906.0, 167723.32276677198, 12574.497020119094, 13008.414621342063, 13083.542076027294, 13083.542076027294, 13133.277669728257, 16986.235619397157, 17036.403725869535, 17058.0, 17060.658786824195, 17060.91469542669, 8166.6528342600395, 8445.957770211233, 8485.0, 8584.540628093238, 8621.224089831945, 6483.610983341015, 6499.447546958467, 7737.651805668758, 7787.909692643035, 7900.960495197487, 7168.996340512333, 7600.391968642495, 7927.563983980936, 8026.237507436784, 8075.192480751878, 2174.717286752744, 2186.2238905188747, 2194.923177688805, 2197.516546359798, 2354.505553833714, 880.0, 889.1863944490848, 935.3826474526779, 950.7480517662825, 954.4511905654276, 20596.27913023037, 21228.363149105553, 21230.681539776917, 21296.361109166748, 21468.570628587342, 835.418576419, 979.6962941488162, 1001.0840081325506, 1016.1261315268863, 1019.5564929255797, 8789.252913502423, 9006.729798106067, 10097.343672661253, 10990.560377584967, 11016.834747478793, 537075.0, 540158.0, 549927.0, 557205.0, 559689.0, 127673.0, 129055.35472322766, 134139.0, 134897.99999999805, 135150.0, 1203.7111093337535, 1229.8524177098627, 1251.6495381293253, 1253.3921048291572, 1256.5099611151659, 21361.78638213598, 22546.61800729838, 22747.0, 22791.430214244778, 22850.771492284865, 56501.869962599696, 57439.13841292467, 57993.0, 58240.70879645574, 59173.70413147907, 75725.6992010554, 78897.0, 79332.0, 79352.0, 79499.60250198712, 7721.382289416721, 8981.7754556134, 9084.40951338161, 9124.95437522808, 9193.494357810385, 13896.37466313998, 13961.930190349389, 14103.858961410666, 14260.71478570402, 14316.785248221493, 11345.829812552984, 11823.645290641294, 11928.0, 12114.394280286127, 12205.0, 9217.354785164975, 9302.813943720952, 9441.339106262496, 9530.046995300414, 11320.26418282809, 11528.481218345363, 11980.58067967617, 12116.333601651993, 12133.453994570089, 12231.571894983634, 7715.344195743424, 9051.04963978799, 9056.13966673165, 9179.0, 9302.534873256445, 9565.426074435556, 12009.879901200877, 12035.33290439072, 12175.939120304341, 12333.876661233273], "value": 10433.025590633915, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1239.4918083585728, 1447.442734547199, 1449.5216578529087, 1461.7136919510835, 1470.8970372073957, 11060.944695276512, 11108.277961932468, 11610.651680449579, 11649.184557080996, 11677.591284305052, 9496.715098547054, 11579.65261042168, 11664.00855927245, 11667.824982625267, 11675.0, 2482.788962938148, 2521.6343630173615, 2537.5305568469894, 2539.9111031113916, 2542.2627438042946, 2490.638857365681, 2518.7355327690602, 2531.0255551612577, 2533.3160046787357, 2533.733957934418, 2442.926712198628, 2489.987550062256, 2517.0812653381477, 2521.684789401325, 2521.9117330893423, 2442.157455677788, 2474.18351943859, 2479.838810477313, 2480.3179125740476, 2482.8013758899424, 2396.7123945126696, 2440.853548787061, 2466.235467005234, 2472.27068014936, 2500.4499010217905, 11030.613928512463, 11033.062189713884, 11106.278091924, 11120.443977801158, 11143.665690029313, 5386.8922621547745, 6674.03226532155, 6694.163229596301, 6698.4641228701585, 6702.899456508157, 3902.4731661225655, 4190.078182799779, 4262.91474170518, 4287.6141147296585, 4290.69965102443, 1220.2251570252865, 1227.7667243223848, 1245.975080498385, 1273.057937126529, 1274.3055035005943, 13970.51103211393, 14430.989830711855, 16188.433404830892, 16248.0, 16366.0, 5455.809046683347, 5553.916691249635, 5724.427557244326, 5768.105943578668, 5776.42235776419, 11508.884911150948, 11612.12909031826, 11644.24312419707, 11678.6496405108, 11716.17986740937, 6533.0, 6560.967195164089, 6563.540552161303, 6564.113844631009, 6565.540412171103, 6076.605020673642, 6106.6336019838955, 6214.25428948529, 6219.502439804804, 6254.249490061131, 4272.893177670583, 4350.912981740347, 4366.497852746911, 4391.099824535962, 4407.2507673695545, 1020.4438580973388, 1256.0, 1266.3858028855998, 1297.5977446991462, 1302.3423171298623, 792.4254915186443, 793.7182300283432, 819.5574389829426, 825.2366560931115, 849.6728759427541, 6013.579049466583, 6031.819045428557, 6241.251049873953, 6266.561340706106, 6276.592021518587, 523.1759978034611, 580.7415700013538, 590.1354515634616, 603.1706403694919, 608.4615115622618, 3763.5295588051495, 3829.3872980323013, 3902.980485097613, 4154.065335299533, 4231.344141658048, 14280.642983925485, 14926.0, 14955.102693838404, 14971.550853474413, 14981.775273370908, 14831.925840370945, 14836.480723174636, 14837.480688175861, 14851.777223341658, 14856.925715371353, 7616.8476630468185, 8070.435069545076, 8332.0, 8485.533295668674, 8535.317174626016, 5640.097584386478, 6326.335734747785, 6589.341065893564, 6751.831204219738, 6807.795766127024, 11278.210525263154, 12883.613491595268, 13192.604221873358, 13219.537316193888, 13280.80078798838, 13304.13523120994, 13450.192988420726, 13497.86502134966, 13559.728805424034, 13725.656858578615, 8870.290376769713, 9133.223675987616, 9141.3601047926, 9145.451272923647, 9162.908370916206, 10135.493225338556, 10219.029192226726, 10298.073173414428, 10303.948480257552, 10309.381437113798, 8679.783005424915, 8692.565371731514, 8735.91264087351, 8755.124487551195, 8790.296776257755, 8437.704680336159, 8483.787905302417, 8501.0, 8519.275861551838, 8519.74440766778, 6544.574602650812, 6853.451723862178, 7850.921490785019, 8150.062742784656, 8196.918030819616, 5995.46040856325, 6007.30915944672, 6049.939500605114, 6190.226221722285, 6208.161898143693, 5989.64062156272, 6015.2781666200335, 6148.4773794227995, 6212.627242365473, 6298.71655775482], "value": 5212.337436317502, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.78958204241536, 384.4387194695744, 411.9485064366953, 413.144790284112, 413.73520946594164, 6192.845178870531, 6270.52971027173, 6361.427471527573, 6428.26075001375, 6428.9999999999945, 6069.696515174246, 6185.288691800433, 6185.65978871163, 6209.57179848635, 6213.949396699089, 4370.84702035428, 4447.866564003069, 4544.250198717207, 4551.408316918796, 4590.816367345302, 4086.693497987648, 4202.82185187775, 4423.491298500681, 4521.773911304422, 4544.522825103366, 3709.8887033388905, 3926.548446928611, 4469.3296005599095, 4494.258447356182, 4501.7974191161475, 4112.157007813391, 4490.393796837434, 4527.094581083797, 4543.296738440623, 4546.54534546546, 2937.574051762504, 3781.092537790938, 3837.3668344723087, 3860.6332398422105, 3867.613238676138, 5445.673259604397, 5597.033572206737, 5635.661860288355, 5991.131285963555, 6064.514838812885, 4910.926336104962, 5002.274670172841, 5386.245925570423, 5417.539509141728, 5420.620556561043, 5236.031334203185, 5399.7030163341, 5466.671999679992, 6414.134091897581, 6691.1636045494315, 3833.884983450501, 3900.258950799339, 4073.6130067643526, 4401.625861801751, 4411.448568928884, 6526.673666316665, 6944.097267355262, 7070.197099739534, 7113.715451381939, 7152.821179470503, 3785.8674946377296, 4259.914801703949, 4479.776011199427, 4499.437570303712, 4502.392177056121, 3814.5803961564206, 4399.5160532341415, 4568.56598623137, 4575.519570445122, 4592.2422800238255, 5256.395514515803, 5403.000444917703, 5409.24270602116, 5417.160340147283, 5422.75597598109, 6697.0, 6702.396784289437, 6707.195136583641, 6729.495287853332, 6737.8652426951185, 1199.5621598116625, 1448.2251995182526, 1557.493814510266, 1560.173108252628, 1561.5783738390571, 890.7105190813012, 955.102203928319, 992.1417973452911, 1025.3181634213165, 1027.5786927359895, 5305.840824775263, 6306.0, 6315.431611154926, 6327.873442531123, 6344.936550634527, 5933.406659334032, 6644.96677516619, 6854.725810967506, 6970.163580370388, 6970.756023539253, 5862.970685146547, 6263.843403914939, 6277.748890044439, 6287.49700023997, 6293.199526210884, 3457.2048428861503, 3554.413521768879, 3555.5022296878456, 3591.5690117186105, 3600.4419315006216, 5904.4685978261305, 6448.968165093563, 6948.652567371712, 7165.426765858719, 7235.384992275715, 6822.829429264209, 7160.534565253241, 7170.856582868313, 7179.317964793337, 7205.315495027964, 6565.277819439762, 6717.899231511531, 6965.547239429421, 7181.820454488679, 7215.567065976058, 5807.883842323215, 6606.240282367397, 6785.846406110874, 6798.728050878108, 6987.7204911802955, 6703.731850726112, 6768.830779230559, 6829.69266383004, 6840.3501667341525, 6883.174019117738, 5959.612625179349, 6290.370962903673, 6970.965145174445, 7175.31834475724, 7248.0, 4958.256261560795, 5026.547610715053, 5119.950410165981, 5141.254518094781, 5142.331496905378, 6360.459360954185, 6933.5146539743755, 6972.51192416526, 7007.789766307019, 7028.262032486719, 6696.062551242732, 6751.156105486814, 6751.189857217166, 6758.8648227036165, 6774.966125169342, 5679.77280908759, 6544.312847150981, 6731.259561448334, 6796.72813087471, 6810.421114205348, 5819.0, 5863.413658634103, 6675.499337549801, 6690.364415380531, 6777.525573210025, 5299.152135658276, 6031.879362412639, 6328.873422531616, 6447.0, 6682.866342673216, 5420.945790542044, 6538.771143009972, 6556.901646475211, 6567.835804104936, 6567.835804104936], "value": 4807.426804243491, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.8788106021976, 759.3583422008403, 815.2784785464862, 838.1367191792456, 866.2723312417568, 15387.692246155084, 15912.681746365066, 16389.75415368769, 18259.36092236772, 19193.904030479862, 17704.11479426027, 18014.009229492374, 18313.542161445937, 18504.982225977565, 18683.439496815085, 1011.7470632341915, 1041.697907606793, 1043.4730461117126, 1048.7692707604315, 1049.6903413493, 991.420019288717, 1024.3802499487815, 1045.7176562328166, 1049.6273822792914, 1052.6473631333513, 1085.6091806949491, 1096.1121491591796, 1096.819024860901, 1097.1387460843239, 1105.8728246251703, 1089.3355053417408, 1089.5423921952759, 1092.317301686446, 1093.191038631411, 1093.2511229807608, 781.581853708269, 788.5820515126934, 804.1998211779259, 808.9887640449438, 809.2110192562263, 14280.428782848674, 15414.460493882772, 16838.747418788727, 16887.0, 16924.323027078903, 16734.49796506107, 16978.405755798613, 17334.91332543342, 17435.389761358292, 17597.0, 13901.235432051233, 14165.079269847427, 14276.357563909649, 14306.356213970397, 14445.349959251858, 3522.647735226457, 3538.716902647782, 3546.4503002034726, 3552.0409489437966, 3552.2007548301685, 99244.01511969793, 116120.0, 121068.0, 122286.77713222931, 123876.38061809723, 13331.66670833237, 13539.39072741729, 13620.616392131338, 13620.93189534046, 13656.268746250591, 25299.241022769347, 25378.746212538008, 25758.09846655358, 26023.869880650476, 26147.084852030086, 7055.259197784261, 7531.472796904164, 7858.803529911798, 7859.6463159157975, 7865.7246996356, 3821.464994900716, 3898.785566793797, 3944.36890097583, 3993.72043956926, 4003.199360127986, 3955.268275369066, 4022.597740225954, 4023.054582173187, 4025.19496100781, 4028.234635419261, 871.8535126308883, 939.793245485992, 945.652445265494, 950.4819873169139, 950.95720692569, 582.3099626942087, 610.1762620462429, 643.9710213040424, 654.1496055128314, 663.3002182697204, 11323.943380283046, 11776.234544754563, 11789.528418863149, 12028.639140825615, 12186.268823870596, 641.5380925733465, 648.2383199740286, 653.7319698923419, 656.7405874679475, 659.0772917914958, 5215.8614967862995, 5528.723563821793, 5621.662700237999, 5657.858553536194, 5676.943230567724, 61060.69469652712, 83153.58423207967, 86093.5695321532, 92934.53532732428, 103368.93262134715, 71133.0, 84299.0, 86279.70580441298, 88137.0, 88362.55818720865, 64929.67535162294, 82737.0, 83006.16993830106, 83477.16522834694, 84870.0, 10316.329438586468, 10400.739981500523, 12038.157328986887, 12398.690032749253, 12591.370431478206, 39112.0, 39114.4132838002, 39476.21047578975, 39693.60306396899, 39863.60136398715, 52108.73945630248, 52248.738756305975, 52891.94216115579, 53021.469785301655, 53591.73204133954, 15847.366105356117, 16161.919190403973, 16275.267612957683, 16463.83536164623, 16689.66620667604, 17448.21483033292, 17838.821611784235, 18141.637167256846, 18240.0879955999, 18649.90675046616, 16659.33978099265, 17046.82953170502, 17257.223424946158, 17313.0, 17410.0, 16198.190090495193, 16678.49964501067, 17072.317107315568, 17373.0, 17394.91302543479, 16330.183490825173, 16753.74869376985, 16967.321307147577, 17037.318507260068, 17126.743098853778, 11406.0, 11422.029127523921, 11650.88349116532, 12060.51757929673, 12159.0, 10578.841317380091, 12754.553590624284, 13305.135166214164, 13401.195928244337, 13597.66005849822], "value": 8312.850296364195, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 166.0, 157.0, 113.0, 119.0, 163.0, 163.0, 143.0, 115.0, 116.0], "value": 139.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 106.0, 128.0, 144.0, 113.0, 112.0, 110.0, 112.0, 147.0, 112.0], "value": 122.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 200.0, 208.0, 230.0, 226.0, 204.0, 200.0, 201.0, 201.0, 199.0], "value": 207.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 59.0, 84.0, 61.0, 62.0, 62.0, 62.0, 80.0, 53.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 85.0, 71.0, 71.0, 69.0, 93.0, 104.0, 85.0, 86.0], "value": 82.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 110.0, 89.0, 89.0, 106.0, 77.0, 114.0, 106.0, 78.0, 126.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 104.0, 151.0, 153.0, 96.0, 96.0, 100.0, 102.0, 129.0], "value": 112.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 90.0, 92.0, 90.0, 92.0, 75.0, 132.0, 140.0, 127.0], "value": 102.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 51.0, 51.0, 51.0, 51.0, 59.0, 51.0, 46.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 60.0, 60.0, 62.0, 60.0, 56.0, 57.0, 58.0, 59.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 83.0, 82.0, 84.0, 84.0, 83.0, 85.0, 81.0, 83.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 151.0, 153.0, 138.0, 146.0, 132.0, 142.0, 161.0, 218.0, 182.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 185.0, 179.0, 184.0, 180.0, 187.0, 183.0, 186.0, 194.0], "value": 184.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 71.0, 74.0, 70.0, 71.0, 69.0, 70.0, 71.0], "value": 70.2, "unit": "score"}]}]} 08:54:47 INFO - Return code: 0 08:54:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:54:47 INFO - # TBPL SUCCESS # 08:54:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:54:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:54:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:54:47 INFO - 2017-05-10 08:54:47.342 screenresolution[1944:22141] starting screenresolution argv=screenresolution get 08:54:47 INFO - 2017-05-10 08:54:47.344 screenresolution[1944:22141] Display 0: 1600x1200x32@60 08:54:47 INFO - 2017-05-10 08:54:47.351 screenresolution[1945:22144] starting screenresolution argv=screenresolution list 08:54:47 INFO - Available Modes on Display 0 08:54:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:54:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:54:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:54:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:54:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:54:47 INFO - Intel Iris: 08:54:47 INFO - Chipset Model: Intel Iris 08:54:47 INFO - Type: GPU 08:54:47 INFO - Bus: Built-In 08:54:47 INFO - VRAM (Dynamic, Max): 1536 MB 08:54:47 INFO - Vendor: Intel (0x8086) 08:54:47 INFO - Device ID: 0x0a2e 08:54:47 INFO - Revision ID: 0x0009 08:54:47 INFO - Displays: 08:54:47 INFO - Display: 08:54:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:54:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:54:47 INFO - Display Serial Number: 1600x1200 60 08:54:47 INFO - Main Display: Yes 08:54:47 INFO - Mirror: Off 08:54:47 INFO - Online: Yes 08:54:47 INFO - Rotation: Supported 08:54:47 INFO - Return code: 0 08:54:47 INFO - Running post-action listener: _resource_record_post_action 08:54:47 INFO - [mozharness: 2017-05-10 15:54:47.586314Z] Finished run-tests step (success) 08:54:47 INFO - Running post-run listener: _resource_record_post_run 08:54:47 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 77824; Write bytes: 527137280; Read time: 2; Write time: 1834 08:54:47 INFO - TinderboxPrint: CPU usage
54.2% 08:54:47 INFO - TinderboxPrint: I/O read bytes / time
77,824 / 2 08:54:47 INFO - TinderboxPrint: I/O write bytes / time
527,137,280 / 1,834 08:54:47 INFO - TinderboxPrint: CPU idle
2,192.4 (45.8%) 08:54:47 INFO - TinderboxPrint: CPU system
960.9 (20.1%) 08:54:47 INFO - TinderboxPrint: CPU user
1,632.3 (34.1%) 08:54:47 INFO - TinderboxPrint: Swap in / out
212,406,272 / 0 08:54:47 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204706304; Write bytes: 205735936; Read time: 15711; Write time: 381 08:54:47 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 77824; Write bytes: 311513600; Read time: 2; Write time: 1420 08:54:48 INFO - Running post-run listener: _upload_blobber_files 08:54:48 INFO - Blob upload gear active. 08:54:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:54:48 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 08:54:48 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'] 08:54:48 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 08:54:48 INFO - (blobuploader) - INFO - Open directory for files ... 08:54:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:54:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:54:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:49 INFO - (blobuploader) - INFO - Done attempting. 08:54:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:54:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:54:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:50 INFO - (blobuploader) - INFO - Done attempting. 08:54:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:54:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:54:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:51 INFO - (blobuploader) - INFO - Done attempting. 08:54:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:54:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:54:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:54:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:54:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:54:53 INFO - (blobuploader) - INFO - Done attempting. 08:54:53 INFO - (blobuploader) - INFO - Iteration through files over. 08:54:53 INFO - Return code: 0 08:54:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:54:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:54:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1669cb1640167354843f4ef07156e43b38e424e1547e0582052ab920c08185212567451ebbe7b6af5d0acd7ef6661e42b5fcc938135c84bd233eb3ec6ec20ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c9d250a5196efc116646eaa1d13186c34a754615fe19bef173cefca8879c005c580581c2aad8ef5fcb96b9f581b7efbed0524955ee8075e507c7987858f3c20", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bd403c73bba9c4e185c2fa081777679dca720a7415ccb9ccebfe41fec786717ddf6d3f260cd112d1bcd2219e19b149c32a27db6bc9f44a32db377602fe2b9bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/980f81dc386a73eda0a0e1e16a7a43f12f009980367f682187467ce3295c506003ff35e0d32c70b4e8a60e3130f9d6936acbfb3104d4d2acd68dcecad0c006b7"} 08:54:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:54:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:54:53 INFO - Contents: 08:54:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1669cb1640167354843f4ef07156e43b38e424e1547e0582052ab920c08185212567451ebbe7b6af5d0acd7ef6661e42b5fcc938135c84bd233eb3ec6ec20ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c9d250a5196efc116646eaa1d13186c34a754615fe19bef173cefca8879c005c580581c2aad8ef5fcb96b9f581b7efbed0524955ee8075e507c7987858f3c20", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bd403c73bba9c4e185c2fa081777679dca720a7415ccb9ccebfe41fec786717ddf6d3f260cd112d1bcd2219e19b149c32a27db6bc9f44a32db377602fe2b9bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/980f81dc386a73eda0a0e1e16a7a43f12f009980367f682187467ce3295c506003ff35e0d32c70b4e8a60e3130f9d6936acbfb3104d4d2acd68dcecad0c006b7"} 08:54:53 INFO - Running post-run listener: copy_logs_to_upload_dir 08:54:53 INFO - Copying logs to upload dir... 08:54:53 INFO - mkdir: /builds/slave/test/build/upload/logs 08:54:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.265832 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-10 08:54:53.766283) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 08:54:53.769380) ========= 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.S39FmXctf6/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.KJUZINrufX/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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/d1669cb1640167354843f4ef07156e43b38e424e1547e0582052ab920c08185212567451ebbe7b6af5d0acd7ef6661e42b5fcc938135c84bd233eb3ec6ec20ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c9d250a5196efc116646eaa1d13186c34a754615fe19bef173cefca8879c005c580581c2aad8ef5fcb96b9f581b7efbed0524955ee8075e507c7987858f3c20", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bd403c73bba9c4e185c2fa081777679dca720a7415ccb9ccebfe41fec786717ddf6d3f260cd112d1bcd2219e19b149c32a27db6bc9f44a32db377602fe2b9bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/980f81dc386a73eda0a0e1e16a7a43f12f009980367f682187467ce3295c506003ff35e0d32c70b4e8a60e3130f9d6936acbfb3104d4d2acd68dcecad0c006b7"} build_url:https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011342 build_url: 'https://queue.taskcluster.net/v1/task/chHUlsfiS9-sgeQPSfD6vQ/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/d1669cb1640167354843f4ef07156e43b38e424e1547e0582052ab920c08185212567451ebbe7b6af5d0acd7ef6661e42b5fcc938135c84bd233eb3ec6ec20ff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c9d250a5196efc116646eaa1d13186c34a754615fe19bef173cefca8879c005c580581c2aad8ef5fcb96b9f581b7efbed0524955ee8075e507c7987858f3c20", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7bd403c73bba9c4e185c2fa081777679dca720a7415ccb9ccebfe41fec786717ddf6d3f260cd112d1bcd2219e19b149c32a27db6bc9f44a32db377602fe2b9bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/980f81dc386a73eda0a0e1e16a7a43f12f009980367f682187467ce3295c506003ff35e0d32c70b4e8a60e3130f9d6936acbfb3104d4d2acd68dcecad0c006b7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-10 08:54:53.803685) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:54:53.804009) ========= 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.S39FmXctf6/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.KJUZINrufX/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005256 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-10 08:54:53.854024) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 08:54:53.854330) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-10 08:54:53.854669) ========= ========= Total master_lag: 0.18 =========