builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0245 starttime: 1490725867.47 results: success (0) buildid: 20170328095415 builduid: f53979a631b14ddd8716d40d7addd8f2 revision: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:07.470780) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:07.471292) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:07.471647) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:07.499365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:07.499819) ========= 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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-03-28 11:31:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 56.9M=0s 2017-03-28 11:31:07 (56.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133525 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:07.658392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:07.658809) ========= 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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.032493 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:07.713939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 11:31:07.714331) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0e0eb96528a1d032fe6ed54f67d32290d533fbfd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0e0eb96528a1d032fe6ed54f67d32290d533fbfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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-03-28 11:31:07,822 truncating revision to first 12 chars 2017-03-28 11:31:07,822 Setting DEBUG logging. 2017-03-28 11:31:07,822 attempt 1/10 2017-03-28 11:31:07,822 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0e0eb96528a1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-28 11:31:08,461 unpacking tar archive at: mozilla-central-0e0eb96528a1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.918640 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-28 11:31:08.754345) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:08.754692) ========= script_repo_revision: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:08.755104) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:08.755374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-28 11:31:08.776236) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-28 11:31:08.776649) ========= /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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:31:08 INFO - MultiFileLogger online at 20170328 11:31:08 in /builds/slave/test 11:31:08 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 11:31:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:31:08 INFO - {'append_to_log': False, 11:31:08 INFO - 'base_work_dir': '/builds/slave/test', 11:31:08 INFO - 'blob_upload_branch': 'Firefox', 11:31:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:31:08 INFO - 'branch': 'Firefox', 11:31:08 INFO - 'buildbot_json_path': 'buildprops.json', 11:31:08 INFO - 'config_files': ('talos/mac_config.py',), 11:31:08 INFO - 'default_actions': ('clobber', 11:31:08 INFO - 'read-buildbot-config', 11:31:08 INFO - 'download-and-extract', 11:31:08 INFO - 'populate-webroot', 11:31:08 INFO - 'create-virtualenv', 11:31:08 INFO - 'install', 11:31:08 INFO - 'run-tests'), 11:31:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:31:08 INFO - 'download_minidump_stackwalk': True, 11:31:08 INFO - 'download_symbols': 'ondemand', 11:31:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:31:08 INFO - 'tooltool.py': '/tools/tooltool.py', 11:31:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:31:08 INFO - '/tools/misc-python/virtualenv.py')}, 11:31:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:31:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:31:08 INFO - 'gecko_profile': False, 11:31:08 INFO - 'gecko_profile_interval': 0, 11:31:08 INFO - 'installer_path': 'installer.exe', 11:31:08 INFO - 'log_level': 'info', 11:31:08 INFO - 'log_name': 'talos', 11:31:08 INFO - 'log_to_console': True, 11:31:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:31:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:31:08 INFO - 'opt_config_files': (), 11:31:08 INFO - 'pip_index': False, 11:31:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:31:08 INFO - 'cmd': ('bash', 11:31:08 INFO - '-c', 11:31:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:31:08 INFO - 'enabled': True, 11:31:08 INFO - 'halt_on_failure': False, 11:31:08 INFO - 'name': 'check_screen_resolution'},), 11:31:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:31:08 INFO - 'cmd': ('bash', 11:31:08 INFO - '-c', 11:31:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:31:08 INFO - 'enabled': True, 11:31:08 INFO - 'halt_on_failure': False, 11:31:08 INFO - 'name': 'check_screen_resolution'},), 11:31:08 INFO - 'run_cmd_checks_enabled': True, 11:31:08 INFO - 'suite': 'dromaeojs-e10s', 11:31:08 INFO - 'system_bits': '32', 11:31:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:31:08 INFO - 'title': 't-yosemite-r7-0245', 11:31:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:31:08 INFO - 'use_talos_json': True, 11:31:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:31:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:31:08 INFO - 'work_dir': 'build'} 11:31:08 INFO - [mozharness: 2017-03-28 18:31:08.993072Z] Running clobber step. 11:31:08 INFO - Running pre-action listener: _resource_record_pre_action 11:31:08 INFO - Running main action method: clobber 11:31:08 INFO - rmtree: /builds/slave/test/build 11:31:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:31:11 INFO - Running post-action listener: _resource_record_post_action 11:31:11 INFO - [mozharness: 2017-03-28 18:31:11.594822Z] Finished clobber step (success) 11:31:11 INFO - [mozharness: 2017-03-28 18:31:11.595093Z] Running read-buildbot-config step. 11:31:11 INFO - Running pre-action listener: _resource_record_pre_action 11:31:11 INFO - Running main action method: read_buildbot_config 11:31:11 INFO - Using buildbot properties: 11:31:11 INFO - { 11:31:11 INFO - "project": "", 11:31:11 INFO - "product": "firefox", 11:31:11 INFO - "script_repo_revision": "production", 11:31:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:31:11 INFO - "repository": "", 11:31:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:31:11 INFO - "buildid": "20170328095415", 11:31:11 INFO - "stage_platform": "macosx64", 11:31:11 INFO - "basedir": "/builds/slave/test", 11:31:11 INFO - "nightly_build": "True", 11:31:11 INFO - "buildnumber": 31, 11:31:11 INFO - "slavename": "t-yosemite-r7-0245", 11:31:11 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 11:31:11 INFO - "platform": "yosemite_r7", 11:31:11 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 11:31:11 INFO - "branch": "mozilla-central", 11:31:11 INFO - "repo_path": "mozilla-central", 11:31:11 INFO - "revision": "0e0eb96528a1d032fe6ed54f67d32290d533fbfd", 11:31:11 INFO - "pgo_build": "False", 11:31:11 INFO - "builduid": "f53979a631b14ddd8716d40d7addd8f2", 11:31:11 INFO - "slavebuilddir": "test" 11:31:11 INFO - } 11:31:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 11:31:11 INFO - Running post-action listener: _resource_record_post_action 11:31:11 INFO - [mozharness: 2017-03-28 18:31:11.600968Z] Finished read-buildbot-config step (success) 11:31:11 INFO - [mozharness: 2017-03-28 18:31:11.601171Z] Running download-and-extract step. 11:31:11 INFO - Running pre-action listener: _resource_record_pre_action 11:31:11 INFO - Running main action method: download_and_extract 11:31:11 INFO - mkdir: /builds/slave/test/build/tests 11:31: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')]} 11:31:11 INFO - trying https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:31:11 INFO - Downloading https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/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 11:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/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 11:31:12 INFO - Downloaded 1325 bytes. 11:31:12 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:31:12 INFO - Using the following test package requirements: 11:31:12 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:31:12 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:31:12 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:31:12 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:31:12 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'jsshell-mac.zip'], 11:31:12 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:31:12 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:31:12 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:31:12 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:31:12 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:31:12 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:31:12 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:31:12 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:31:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:31:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:31:12 INFO - Fetch https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:31:14 INFO - Expected file size: 52503867 11:31:14 INFO - Obtained file size: 52503867 11:31:30 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 11:31:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:31:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:31:30 INFO - Fetch https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:31:32 INFO - Expected file size: 52503867 11:31:32 INFO - Obtained file size: 52503867 11:31:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:31:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:31:48 INFO - Fetch https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:31:49 INFO - Expected file size: 14404171 11:31:49 INFO - Obtained file size: 14404171 11:31: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')]} 11:31:49 INFO - trying https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:31:49 INFO - Downloading https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/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 11:31:52 INFO - Downloaded 75645540 bytes. 11:31:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:31:52 INFO - mkdir: /builds/slave/test/properties 11:31:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:31:52 INFO - Writing to file /builds/slave/test/properties/build_url 11:31:52 INFO - Contents: 11:31:52 INFO - build_url:https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:31:52 INFO - Running post-action listener: _resource_record_post_action 11:31:52 INFO - Running post-action listener: set_extra_try_arguments 11:31:52 INFO - [mozharness: 2017-03-28 18:31:52.760117Z] Finished download-and-extract step (success) 11:31:52 INFO - [mozharness: 2017-03-28 18:31:52.760270Z] Running populate-webroot step. 11:31:52 INFO - Running pre-action listener: _resource_record_pre_action 11:31:52 INFO - Running main action method: populate_webroot 11:31:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:31:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:31:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:31:52 INFO - '--tppagecycles', 11:31:52 INFO - '7'], 11:31:52 INFO - 'tests': ['tsvgm']}}, 11:31:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:31:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:31:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:31:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:31:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:31:52 INFO - 'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:31:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:31:52 INFO - 'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['damp', 'tps']}, 11:31:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:31:52 INFO - 'tests': ['damp', 'tps']}, 11:31:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['dromaeo_dom']}, 11:31:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:31:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['basic_compositor_video']}, 11:31:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:31:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['a11yr', 11:31:52 INFO - 'ts_paint', 11:31:52 INFO - 'tpaint', 11:31:52 INFO - 'sessionrestore', 11:31:52 INFO - 'sessionrestore_no_auto_restore', 11:31:52 INFO - 'tabpaint']}, 11:31:52 INFO - 'other-e10s': {'tests': ['a11yr', 11:31:52 INFO - 'ts_paint', 11:31:52 INFO - 'tpaint', 11:31:52 INFO - 'sessionrestore', 11:31:52 INFO - 'sessionrestore_no_auto_restore', 11:31:52 INFO - 'tabpaint']}, 11:31:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:31:52 INFO - 'ts_paint', 11:31:52 INFO - 'tpaint', 11:31:52 INFO - 'sessionrestore', 11:31:52 INFO - 'sessionrestore_no_auto_restore', 11:31:52 INFO - 'tabpaint']}, 11:31:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:31:52 INFO - 'ts_paint', 11:31:52 INFO - 'tpaint', 11:31:52 INFO - 'sessionrestore', 11:31:52 INFO - 'sessionrestore_no_auto_restore', 11:31:52 INFO - 'tabpaint']}, 11:31:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['a11yr', 11:31:52 INFO - 'ts_paint', 11:31:52 INFO - 'tpaint', 11:31:52 INFO - 'sessionrestore', 11:31:52 INFO - 'sessionrestore_no_auto_restore', 11:31:52 INFO - 'tabpaint']}, 11:31:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['a11yr', 11:31:52 INFO - 'ts_paint', 11:31:52 INFO - 'tpaint', 11:31:52 INFO - 'sessionrestore', 11:31:52 INFO - 'sessionrestore_no_auto_restore', 11:31:52 INFO - 'tabpaint']}, 11:31:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['tsvgx', 11:31:52 INFO - 'tsvgr_opacity', 11:31:52 INFO - 'tart', 11:31:52 INFO - 'tscrollx', 11:31:52 INFO - 'cart', 11:31:52 INFO - 'tsvg_static']}, 11:31:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:31:52 INFO - 'tsvgr_opacity', 11:31:52 INFO - 'tart', 11:31:52 INFO - 'tscrollx', 11:31:52 INFO - 'cart', 11:31:52 INFO - 'tsvg_static']}, 11:31:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:31:52 INFO - 'talos_options': ['--disable-e10s'], 11:31:52 INFO - 'tests': ['tp5o']}, 11:31:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:31:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:31:52 INFO - 'talos_options': ['--disable-e10s', 11:31:52 INFO - '--xperf_path', 11:31:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:52 INFO - 'tests': ['tp5n']}, 11:31:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:31:52 INFO - 'talos_options': ['--xperf_path', 11:31:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:52 INFO - 'tests': ['tp5n']}}, 11:31:52 INFO - 'talos.zip': {'path': '', 11:31:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:31:52 INFO - Running post-action listener: _resource_record_post_action 11:31:52 INFO - [mozharness: 2017-03-28 18:31:52.779768Z] Finished populate-webroot step (success) 11:31:52 INFO - [mozharness: 2017-03-28 18:31:52.779904Z] Running create-virtualenv step. 11:31:52 INFO - Running pre-action listener: _resource_record_pre_action 11:31:52 INFO - Running main action method: create_virtualenv 11:31:52 INFO - Creating virtualenv /builds/slave/test/build/venv 11:31:52 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 11:31:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:31:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:31:52 INFO - Using real prefix '/tools/python27' 11:31:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:31:53 INFO - Installing distribute.............................................................................................................................................................................................done. 11:31:56 INFO - Installing pip.................done. 11:31:56 INFO - Return code: 0 11:31:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:31:56 INFO - Reading from file tmpfile_stdout 11:31:56 INFO - Output received: 11:31:56 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) 11:31:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:31:56 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')]} 11:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:56 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')]} 11:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:56 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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:31:56 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 11:31:56 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 11:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:56 INFO - 'HOME': '/Users/cltbld', 11:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:56 INFO - 'LOGNAME': 'cltbld', 11:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:56 INFO - 'MOZ_NO_REMOTE': '1', 11:31:56 INFO - 'NO_EM_RESTART': '1', 11:31:56 INFO - 'PAGER': '/bin/cat', 11:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:56 INFO - 'PWD': '/builds/slave/test', 11:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:56 INFO - 'SHELL': '/bin/bash', 11:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:31:56 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:56 INFO - 'USER': 'cltbld', 11:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:56 INFO - 'XPC_FLAGS': '0x0', 11:31:56 INFO - 'XPC_SERVICE_NAME': '0', 11:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:57 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 11:31:57 INFO - Cleaning up... 11:31:57 INFO - Return code: 0 11:31:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:31:57 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')]} 11:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:57 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')]} 11:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:31:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:31:57 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 11:31:57 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 11:31:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:31:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:31:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:31:57 INFO - 'HOME': '/Users/cltbld', 11:31:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:31:57 INFO - 'LOGNAME': 'cltbld', 11:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:31:57 INFO - 'MOZ_NO_REMOTE': '1', 11:31:57 INFO - 'NO_EM_RESTART': '1', 11:31:57 INFO - 'PAGER': '/bin/cat', 11:31:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:31:57 INFO - 'PWD': '/builds/slave/test', 11:31:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:31:57 INFO - 'SHELL': '/bin/bash', 11:31:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:31:57 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:31:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:31:57 INFO - 'USER': 'cltbld', 11:31:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:31:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:31:57 INFO - 'XPC_FLAGS': '0x0', 11:31:57 INFO - 'XPC_SERVICE_NAME': '0', 11:31:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:31:57 INFO - Downloading/unpacking psutil>=3.1.1 11:31:57 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 11:31:57 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 11:31:57 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 11:31:57 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 11:31:57 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 11:31:57 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 11:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:32:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:32:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:32:00 INFO - Installing collected packages: psutil 11:32:00 INFO - Running setup.py install for psutil 11:32:00 INFO - building 'psutil._psutil_osx' extension 11:32:00 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 11:32:01 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 11:32:01 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 11:32:01 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 11:32:01 INFO - building 'psutil._psutil_posix' extension 11:32:01 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 11:32:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:32:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:32:01 INFO - ^ 11:32:01 INFO - 1 warning generated. 11:32:01 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 11:32:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:32:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:32:01 INFO - Successfully installed psutil 11:32:01 INFO - Cleaning up... 11:32:02 INFO - Return code: 0 11:32:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:32:02 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')]} 11:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:02 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')]} 11:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:02 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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:32:02 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 11:32:02 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 11:32:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:32:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:02 INFO - 'HOME': '/Users/cltbld', 11:32:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:02 INFO - 'LOGNAME': 'cltbld', 11:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:02 INFO - 'MOZ_NO_REMOTE': '1', 11:32:02 INFO - 'NO_EM_RESTART': '1', 11:32:02 INFO - 'PAGER': '/bin/cat', 11:32:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:02 INFO - 'PWD': '/builds/slave/test', 11:32:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:02 INFO - 'SHELL': '/bin/bash', 11:32:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:32:02 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:32:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:02 INFO - 'USER': 'cltbld', 11:32:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:02 INFO - 'XPC_FLAGS': '0x0', 11:32:02 INFO - 'XPC_SERVICE_NAME': '0', 11:32:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:03 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 11:32:03 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 11:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:32:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:32:06 INFO - Installing collected packages: mozsystemmonitor 11:32:06 INFO - Running setup.py install for mozsystemmonitor 11:32:06 INFO - Successfully installed mozsystemmonitor 11:32:06 INFO - Cleaning up... 11:32:06 INFO - Return code: 0 11:32:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:32:06 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')]} 11:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:06 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')]} 11:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:06 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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:32:06 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 11:32:06 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 11:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:06 INFO - 'HOME': '/Users/cltbld', 11:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:06 INFO - 'LOGNAME': 'cltbld', 11:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:06 INFO - 'MOZ_NO_REMOTE': '1', 11:32:06 INFO - 'NO_EM_RESTART': '1', 11:32:06 INFO - 'PAGER': '/bin/cat', 11:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:06 INFO - 'PWD': '/builds/slave/test', 11:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:06 INFO - 'SHELL': '/bin/bash', 11:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:32:06 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:06 INFO - 'USER': 'cltbld', 11:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:06 INFO - 'XPC_FLAGS': '0x0', 11:32:06 INFO - 'XPC_SERVICE_NAME': '0', 11:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:06 INFO - Downloading/unpacking jsonschema==2.5.1 11:32:06 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 11:32:06 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 11:32:06 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 11:32:06 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 11:32:06 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 11:32:06 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 11:32:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:32:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:32:09 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 11:32:09 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 11:32:09 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 11:32:09 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 11:32:09 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 11:32:09 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 11:32:09 INFO - Downloading functools32-3.2.3-2.tar.gz 11:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:32:09 INFO - warning: no files found matching '*.txt' 11:32:09 INFO - no previously-included directories found matching 'build' 11:32:09 INFO - no previously-included directories found matching 'dist' 11:32:09 INFO - no previously-included directories found matching '.git*' 11:32:09 INFO - Installing collected packages: jsonschema, functools32 11:32:09 INFO - Running setup.py install for functools32 11:32:09 INFO - warning: no files found matching '*.txt' 11:32:09 INFO - no previously-included directories found matching 'build' 11:32:09 INFO - no previously-included directories found matching 'dist' 11:32:09 INFO - no previously-included directories found matching '.git*' 11:32:09 INFO - Successfully installed jsonschema functools32 11:32:09 INFO - Cleaning up... 11:32:09 INFO - Return code: 0 11:32:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:32:09 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')]} 11:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:09 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')]} 11:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:09 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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:32:09 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 11:32:09 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 11:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:09 INFO - 'HOME': '/Users/cltbld', 11:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:09 INFO - 'LOGNAME': 'cltbld', 11:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:09 INFO - 'MOZ_NO_REMOTE': '1', 11:32:09 INFO - 'NO_EM_RESTART': '1', 11:32:09 INFO - 'PAGER': '/bin/cat', 11:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:09 INFO - 'PWD': '/builds/slave/test', 11:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:09 INFO - 'SHELL': '/bin/bash', 11:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:32:09 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:09 INFO - 'USER': 'cltbld', 11:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:09 INFO - 'XPC_FLAGS': '0x0', 11:32:09 INFO - 'XPC_SERVICE_NAME': '0', 11:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:32:10 INFO - Cleaning up... 11:32:10 INFO - Return code: 0 11:32:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:32:10 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')]} 11:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:10 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')]} 11:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:10 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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:32:10 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 11:32:10 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 11:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:10 INFO - 'HOME': '/Users/cltbld', 11:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:10 INFO - 'LOGNAME': 'cltbld', 11:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:10 INFO - 'MOZ_NO_REMOTE': '1', 11:32:10 INFO - 'NO_EM_RESTART': '1', 11:32:10 INFO - 'PAGER': '/bin/cat', 11:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:10 INFO - 'PWD': '/builds/slave/test', 11:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:10 INFO - 'SHELL': '/bin/bash', 11:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:32:10 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:10 INFO - 'USER': 'cltbld', 11:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:10 INFO - 'XPC_FLAGS': '0x0', 11:32:10 INFO - 'XPC_SERVICE_NAME': '0', 11:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:10 INFO - Downloading/unpacking blobuploader==1.2.4 11:32:10 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 11:32:10 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 11:32:10 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 11:32:10 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 11:32:10 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 11:32:10 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 11:32:13 INFO - Downloading blobuploader-1.2.4.tar.gz 11:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:32:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:13 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 11:32:13 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 11:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:32:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:13 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 11:32:13 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 11:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:14 INFO - Downloading docopt-0.6.1.tar.gz 11:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:32:14 INFO - Installing collected packages: blobuploader, requests, docopt 11:32:14 INFO - Running setup.py install for blobuploader 11:32:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:32:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:32:14 INFO - Running setup.py install for requests 11:32:15 INFO - Running setup.py install for docopt 11:32:15 INFO - Successfully installed blobuploader requests docopt 11:32:15 INFO - Cleaning up... 11:32:15 INFO - Return code: 0 11:32:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:15 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')]} 11:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:15 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')]} 11:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:15 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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:32:15 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 11:32:15 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 11:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:15 INFO - 'HOME': '/Users/cltbld', 11:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:15 INFO - 'LOGNAME': 'cltbld', 11:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:15 INFO - 'MOZ_NO_REMOTE': '1', 11:32:15 INFO - 'NO_EM_RESTART': '1', 11:32:15 INFO - 'PAGER': '/bin/cat', 11:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:15 INFO - 'PWD': '/builds/slave/test', 11:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:15 INFO - 'SHELL': '/bin/bash', 11:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:32:15 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:15 INFO - 'USER': 'cltbld', 11:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:15 INFO - 'XPC_FLAGS': '0x0', 11:32:15 INFO - 'XPC_SERVICE_NAME': '0', 11:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:32:15 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-0kAGnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:32:15 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-z1LZRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:32:15 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-TO2gVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:32:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:32:15 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-MeOSZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Xy4YSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-IKNk37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-G8tyE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-7cHyPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-NSGmdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-UAIH7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-LlNQ52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Ko2btk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-jsFSqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:32:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:32:16 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-AeXDK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-DCKujp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:32:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-CgSoRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:32:17 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-IGdkdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:32:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:32:17 INFO - Running setup.py install for manifestparser 11:32:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Running setup.py install for mozcrash 11:32:17 INFO - Running setup.py install for mozdebug 11:32:17 INFO - Running setup.py install for mozdevice 11:32:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Running setup.py install for mozfile 11:32:18 INFO - Running setup.py install for mozhttpd 11:32:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Running setup.py install for mozinfo 11:32:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Running setup.py install for mozInstall 11:32:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Running setup.py install for mozleak 11:32:18 INFO - Running setup.py install for mozlog 11:32:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Running setup.py install for moznetwork 11:32:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:32:18 INFO - Running setup.py install for mozprocess 11:32:19 INFO - Running setup.py install for mozprofile 11:32:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:32:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:32:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:32:19 INFO - Running setup.py install for mozrunner 11:32:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:32:19 INFO - Running setup.py install for mozscreenshot 11:32:19 INFO - Running setup.py install for moztest 11:32:19 INFO - Running setup.py install for mozversion 11:32:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:32:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:32:19 INFO - Cleaning up... 11:32:19 INFO - Return code: 0 11:32:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:19 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')]} 11:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:19 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')]} 11:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:19 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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:32:19 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 11:32:19 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 11:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:19 INFO - 'HOME': '/Users/cltbld', 11:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:19 INFO - 'LOGNAME': 'cltbld', 11:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:19 INFO - 'MOZ_NO_REMOTE': '1', 11:32:19 INFO - 'NO_EM_RESTART': '1', 11:32:19 INFO - 'PAGER': '/bin/cat', 11:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:19 INFO - 'PWD': '/builds/slave/test', 11:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:19 INFO - 'SHELL': '/bin/bash', 11:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:32:19 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:19 INFO - 'USER': 'cltbld', 11:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:19 INFO - 'XPC_FLAGS': '0x0', 11:32:19 INFO - 'XPC_SERVICE_NAME': '0', 11:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:32:20 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-_VnrCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:32:20 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-m0P86n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:32:20 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-86VOsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:32:20 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-wc4Hdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:32:20 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-wJYWfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:32:20 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-VpDdYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:32:20 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-s2ycDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:32:20 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)) 11:32:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:32:20 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-pqn2nT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:32:21 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)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:32:21 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-Awuoep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:32:21 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)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:32:21 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-kjZD1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:32:21 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)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:32:21 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-fe03SV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:32:21 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)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:32:21 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-aPeWbs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:32:21 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)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:32:21 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-rDAx0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:32:21 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)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:32:21 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-6ip0r_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:32:21 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)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:21 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-mwcobg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:21 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)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:32:21 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-55Z5cT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:32:21 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)) 11:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:32:21 INFO - Running setup.py (path:/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/pip-ipL8A2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:32:21 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)) 11:32:21 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)) 11:32:21 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)) 11:32:21 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)) 11:32:21 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:32:21 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:32:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:32:21 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 11:32:21 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 11:32:21 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 11:32:21 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 11:32:21 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 11:32:21 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 11:32:24 INFO - Downloading blessings-1.6.tar.gz 11:32:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:32:24 INFO - Installing collected packages: blessings 11:32:24 INFO - Running setup.py install for blessings 11:32:24 INFO - Successfully installed blessings 11:32:24 INFO - Cleaning up... 11:32:24 INFO - Return code: 0 11:32:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:32:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:32:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:32:25 INFO - Reading from file tmpfile_stdout 11:32:25 INFO - Current package versions: 11:32:25 INFO - blessings == 1.6 11:32:25 INFO - blobuploader == 1.2.4 11:32:25 INFO - docopt == 0.6.1 11:32:25 INFO - functools32 == 3.2.3-2 11:32:25 INFO - jsonschema == 2.5.1 11:32:25 INFO - manifestparser == 1.1 11:32:25 INFO - mozInstall == 1.12 11:32:25 INFO - mozcrash == 1.0 11:32:25 INFO - mozdebug == 0.1 11:32:25 INFO - mozdevice == 0.49 11:32:25 INFO - mozfile == 1.2 11:32:25 INFO - mozhttpd == 0.7 11:32:25 INFO - mozinfo == 0.9 11:32:25 INFO - mozleak == 0.1 11:32:25 INFO - mozlog == 3.4 11:32:25 INFO - moznetwork == 0.27 11:32:25 INFO - mozprocess == 0.25 11:32:25 INFO - mozprofile == 0.28 11:32:25 INFO - mozrunner == 6.13 11:32:25 INFO - mozscreenshot == 0.1 11:32:25 INFO - mozsystemmonitor == 0.3 11:32:25 INFO - moztest == 0.8 11:32:25 INFO - mozversion == 1.4 11:32:25 INFO - psutil == 3.1.1 11:32:25 INFO - requests == 1.2.3 11:32:25 INFO - wsgiref == 0.1.2 11:32:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:25 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')]} 11:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:25 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')]} 11:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:25 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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:32:25 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 11:32:25 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 11:32:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:32:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:25 INFO - 'HOME': '/Users/cltbld', 11:32:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:25 INFO - 'LOGNAME': 'cltbld', 11:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:25 INFO - 'MOZ_NO_REMOTE': '1', 11:32:25 INFO - 'NO_EM_RESTART': '1', 11:32:25 INFO - 'PAGER': '/bin/cat', 11:32:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:25 INFO - 'PWD': '/builds/slave/test', 11:32:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:25 INFO - 'SHELL': '/bin/bash', 11:32:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:32:25 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:32:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:25 INFO - 'USER': 'cltbld', 11:32:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:25 INFO - 'XPC_FLAGS': '0x0', 11:32:25 INFO - 'XPC_SERVICE_NAME': '0', 11:32:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:25 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)) 11:32:25 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)) 11:32:25 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)) 11:32:25 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)) 11:32:25 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)) 11:32:25 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)) 11:32:25 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)) 11:32:25 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)) 11:32:25 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)) 11:32:25 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)) 11:32:25 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)) 11:32:25 INFO - Cleaning up... 11:32:25 INFO - Return code: 0 11:32:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:32:25 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')]} 11:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:25 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')]} 11:32:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10bc6ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc6d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9c8c70c90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, '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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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 11:32:25 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 11:32:25 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 11:32:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:32:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:25 INFO - 'HOME': '/Users/cltbld', 11:32:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:25 INFO - 'LOGNAME': 'cltbld', 11:32:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:25 INFO - 'MOZ_NO_REMOTE': '1', 11:32:25 INFO - 'NO_EM_RESTART': '1', 11:32:25 INFO - 'PAGER': '/bin/cat', 11:32:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:25 INFO - 'PWD': '/builds/slave/test', 11:32:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:25 INFO - 'SHELL': '/bin/bash', 11:32:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:32:25 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:32:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:25 INFO - 'USER': 'cltbld', 11:32:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:25 INFO - 'XPC_FLAGS': '0x0', 11:32:25 INFO - 'XPC_SERVICE_NAME': '0', 11:32:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:32:25 INFO - Cleaning up... 11:32:25 INFO - Return code: 0 11:32:25 INFO - Running post-action listener: _resource_record_post_action 11:32:25 INFO - Running post-action listener: _start_resource_monitoring 11:32:25 INFO - Starting resource monitoring. 11:32:25 INFO - [mozharness: 2017-03-28 18:32:25.736950Z] Finished create-virtualenv step (success) 11:32:25 INFO - [mozharness: 2017-03-28 18:32:25.737857Z] Running install step. 11:32:25 INFO - Running pre-action listener: _resource_record_pre_action 11:32:25 INFO - Running main action method: install 11:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:32:25 INFO - Reading from file tmpfile_stdout 11:32:25 INFO - Detecting whether we're running mozinstall >=1.0... 11:32:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:32:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:32:26 INFO - Reading from file tmpfile_stdout 11:32:26 INFO - Output received: 11:32:26 INFO - Usage: mozinstall [options] installer 11:32:26 INFO - Options: 11:32:26 INFO - -h, --help show this help message and exit 11:32:26 INFO - -d DEST, --destination=DEST 11:32:26 INFO - Directory to install application into. [default: 11:32:26 INFO - "/builds/slave/test"] 11:32:26 INFO - --app=APP Application being installed. [default: firefox] 11:32:26 INFO - mkdir: /builds/slave/test/build/application 11:32:26 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'] 11:32:26 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 11:32:46 INFO - Reading from file tmpfile_stdout 11:32:46 INFO - Output received: 11:32:46 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 11:32:46 INFO - Running post-action listener: _resource_record_post_action 11:32:46 INFO - [mozharness: 2017-03-28 18:32:46.511087Z] Finished install step (success) 11:32:46 INFO - [mozharness: 2017-03-28 18:32:46.511275Z] Running run-tests step. 11:32:46 INFO - Running pre-action listener: _resource_record_pre_action 11:32:46 INFO - Running main action method: run_tests 11:32:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:32:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:32:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:32:46 INFO - 2017-03-28 11:32:46.524 screenresolution[1622:7593] starting screenresolution argv=screenresolution get 11:32:46 INFO - 2017-03-28 11:32:46.526 screenresolution[1622:7593] Display 0: 1600x1200x32@60 11:32:46 INFO - 2017-03-28 11:32:46.532 screenresolution[1623:7596] starting screenresolution argv=screenresolution list 11:32:46 INFO - Available Modes on Display 0 11:32:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:32:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:32:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:32:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:32:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:32:46 INFO - Intel Iris: 11:32:46 INFO - Chipset Model: Intel Iris 11:32:46 INFO - Type: GPU 11:32:46 INFO - Bus: Built-In 11:32:46 INFO - VRAM (Dynamic, Max): 1536 MB 11:32:46 INFO - Vendor: Intel (0x8086) 11:32:46 INFO - Device ID: 0x0a2e 11:32:46 INFO - Revision ID: 0x0009 11:32:46 INFO - Displays: 11:32:46 INFO - Display: 11:32:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:32:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:32:46 INFO - Display Serial Number: 1600x1200 60 11:32:46 INFO - Main Display: Yes 11:32:46 INFO - Mirror: Off 11:32:46 INFO - Online: Yes 11:32:46 INFO - Rotation: Supported 11:32:46 INFO - Return code: 0 11:32:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:32:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:32:46 INFO - Python 2.7.3 11:32:46 INFO - Return code: 0 11:32:46 INFO - grabbing minidump binary from tooltool 11:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:32:46 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 0x10ba48d30>, '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 0x10b96fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba3dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:32:46 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 11:32:46 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 11:32:46 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 11:32:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:32:46 INFO - Return code: 0 11:32:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:32:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:32:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:32:46 INFO - ENV: RUST_BACKTRACE is now 1 11:32:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:32:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:32:46 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--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 11:32:46 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0245 --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 11:32:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lqqy6TTwIC/Render', 11:32:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:46 INFO - 'HOME': '/Users/cltbld', 11:32:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:46 INFO - 'LOGNAME': 'cltbld', 11:32:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:32:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:46 INFO - 'MOZ_NO_REMOTE': '1', 11:32:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:32:46 INFO - 'NO_EM_RESTART': '1', 11:32:46 INFO - 'PAGER': '/bin/cat', 11:32:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:46 INFO - 'PWD': '/builds/slave/test', 11:32:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:32:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:46 INFO - 'RUST_BACKTRACE': '1', 11:32:46 INFO - 'SHELL': '/bin/bash', 11:32:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hnq8RPdsLl/Listeners', 11:32:46 INFO - 'TMPDIR': '/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/', 11:32:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:46 INFO - 'USER': 'cltbld', 11:32:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:46 INFO - 'XPC_FLAGS': '0x0', 11:32:46 INFO - 'XPC_SERVICE_NAME': '0', 11:32:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:46 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0245', '--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 11:32:47 INFO - mozversion application_buildid: 20170328095415 11:32:47 INFO - mozversion application_changeset: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd 11:32:47 INFO - mozversion application_display_name: Nightly 11:32:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:32:47 INFO - mozversion application_name: Firefox 11:32:47 INFO - mozversion application_remotingname: firefox 11:32:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:32:47 INFO - mozversion application_vendor: Mozilla 11:32:47 INFO - mozversion application_version: 55.0a1 11:32:47 INFO - mozversion platform_buildid: 20170328095415 11:32:47 INFO - mozversion platform_changeset: 0e0eb96528a1d032fe6ed54f67d32290d533fbfd 11:32:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:32:47 INFO - mozversion platform_version: 55.0a1 11:32:47 INFO - using testdate: 1490725967 11:32:47 INFO - actual date: 1490725967 11:32:47 INFO - starting webserver on 'localhost:49236' 11:32:47 INFO - SUITE-START | Running 2 tests 11:32:47 INFO - TEST-START | dromaeo_css 11:32:47 INFO - operating with platform_type : mac_ 11:32:47 INFO - Initialising browser for dromaeo_css test... 11:32:47 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmp4v4wTY/profile http://localhost:49236/getInfo.html) 11:32:50 INFO - PID 1633 | 2017-03-28 11:32:50.367 plugin-container[1636:7777] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 11:32:50 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:32:50 INFO - PID 1633 | 2017-03-28 11:32:50.369 plugin-container[1636:7777] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 11:32:50 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:32:50 INFO - PID 1633 | 2017-03-28 11:32:50.369 plugin-container[1636:7777] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:32:50 INFO - PID 1633 | __metrics Screen width/height:1600/1200 11:32:50 INFO - PID 1633 | colorDepth:24 11:32:50 INFO - PID 1633 | Browser inner width/height: 1024/689 11:32:50 INFO - PID 1633 | __metrics 11:32:50 ERROR - PID 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 11:32:50 ERROR - PID 1633 | 1490725970522 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmp4v4wTY/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmp4v4wTY/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmp4v4wTY/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmp4v4wTY/profile/extensions/talos-powers@TalosPowersService.js:55:9 11:32:51 INFO - TEST-INFO | 1633: exit 0 11:32:51 INFO - Browser initialized. 11:32:51 INFO - Running cycle 1/1 for dromaeo_css test... 11:32:51 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmp4v4wTY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:32:53 INFO - PID 1637 | 2017-03-28 11:32:53.794 plugin-container[1638:7911] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 11:32:53 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:32:53 INFO - PID 1637 | 2017-03-28 11:32:53.795 plugin-container[1638:7911] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 11:32:53 INFO - PID 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:32:53 INFO - PID 1637 | 2017-03-28 11:32:53.795 plugin-container[1638:7911] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:33:03 INFO - PID 1637 | RSS: Main: 228859904 11:33:03 INFO - PID 1637 | 11:36:08 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 11:36:09 INFO - PID 1637 | RSS: Main: 251084800 11:36:09 INFO - PID 1637 | 11:37:51 INFO - PID 1637 | ************************* 11:37:51 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 11:37:51 INFO - PID 1637 | 11:37:51 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:37:51 INFO - PID 1637 | Full stack: 11:37:51 INFO - PID 1637 | ************************* 11:37:51 INFO - PID 1637 | ************************* 11:37:51 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 11:37:51 INFO - PID 1637 | 11:37:51 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:37:51 INFO - PID 1637 | Full stack: 11:37:51 INFO - PID 1637 | ************************* 11:37:51 INFO - PID 1637 | ************************* 11:37:51 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 11:37:51 INFO - PID 1637 | 11:37:51 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:37:51 INFO - PID 1637 | Full stack: 11:37:51 INFO - PID 1637 | ************************* 11:37:51 INFO - PID 1637 | ************************* 11:37:51 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 11:37:51 INFO - PID 1637 | 11:37:51 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 11:37:51 INFO - PID 1637 | Full stack: 11:37:51 INFO - PID 1637 | ************************* 11:39:12 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 11:39:12 INFO - PID 1637 | RSS: Main: 258224128 11:39:12 INFO - PID 1637 | 11:42:14 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 11:42:15 INFO - PID 1637 | RSS: Main: 272482304 11:42:15 INFO - PID 1637 | 11:45:17 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 11:45:17 INFO - PID 1637 | RSS: Main: 264888320 11:45:17 INFO - PID 1637 | 11:48:21 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 11:48:21 INFO - PID 1637 | RSS: Main: 275099648 11:48:21 INFO - PID 1637 | 11:51:24 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 11:51:24 INFO - PID 1637 | RSS: Main: 268795904 11:51:24 INFO - PID 1637 | 11:51:24 INFO - PID 1637 | __start_tp_report 11:51:24 INFO - PID 1637 | _x_x_mozilla_page_load 11:51:24 INFO - PID 1637 | _x_x_mozilla_page_load_details 11:51:24 INFO - PID 1637 | |i|pagename|runs| 11:51:24 INFO - PID 1637 | |0;dojo.html;3335.182880194353;3598.1004748812798;3616.566012078551;3648.8175591220433;3649.288388764191;5136.897262054752;6128.869808884168;6164.630122192666;6183.072539119129;6226.782062627809;6138.57232748375;6165.290991535975;6189.2572891253;6197.938020619804;6228.501719862412;4180.498340199165;5658.34928983166;5680.943190568093;5785.739641716112;5903.88192236155;4714.528547145293;5630.239917611131;5724.370319264877;5912.083627037816;5934.762609495637;5603.187537807036;5690.772369105231;5741.511971482387;5754.338251101135;5785.5660825437835;5700.087985922274;5702.657840529581;5710;5728.713564321767;5732.627379220379;5623.634463759842;5640.943590564083;5670.659760414388;5717.942820571782;5723.456271654186;5978.073398623221;6108.053251745987;6141.762184092961;6173.135760993465;6216.471599914013;6681.5656982296;6707.664616769142;6712.5301228913995;6715.02632118345;6740.191177058735;5803.709814509258;6749.257581666062;6811.148606424197;6813.965930170367;6814.318568143196;6154.569180157436;6174.166487524127;6199.721012554446;6305.873882522324;6319.55763096588;6916.585004899622;6941;6978.476614253951;6980.476464265099;6982.47631427645;6318.589291696025;6405.839854003594;6423.839404014937;6510.511711621647;6628.834279142963;6490.448311893448;6614.603123812587;6663.766768163091;6768.864622707519;6789.490788190906;6690.163729533809;6691.631960242234;6695.062691223233;6705.362990515893;6715.966420167868;6382.361763823674;6403.519736019724;6525.021246812921;6583.9012414813815;6612.371824676744;5410.1614249790555;5422.349318081777;5427.158790387496;5459.945400546023;5483.040467918098;4733.763311834464;4760.690555113906;4773.66584339093;4788.521147885253;4811.542903424169;6147.493864003259;6200.441960223511;6225.657588832658;6282.623042617458;6315.494760419156;6746.190457145174;6755.932440675629;6789.287124851919;6791.117154769849;6800;6215.471684906744;6278.434940855345;6285.43431091204;6289.96855015722;6310.368963103653;5874.530037596982;5948.940510594925;6053.8486537836025;6058.485028772516;6065.302490213594;5892.880352732967;6797.762078327235;6872.450203983769;6918.861622767416;6954.200266969363;6702.564333318319;6736.7642132525125;6747.291534388818;6753.797386078426;6771.864562708817;5755.740991655469;6864.897026544706;6914.965425172842;6934.757283495255;6976.825579360557;6393.073004414241;6485.254195767358;6571.868562628816;6643.60138391698;6677.565958212701;5597.6641401516035;6005;6040.214772079602;6714.127163468913;6726.596404215763;6286.245650521783;6302.905456418249;6393.328700486382;6533.901991470037;6575.901361479486;6385.87228255423;6556.311587283267;6579.374959378852;6586.176727909012;6632.403083722488;5883.529317654663;6160.661163635864;6248.281447633399;6332.588381755171;6348.047792830927;5145.562627176732;6515.706793194223;6569.704363303567;6576.704048317742;6592.934070659232;6397;6407.10300557913;6407.487401007815;6424.201223657384;6437.227532696106 11:51:24 INFO - PID 1637 | |1;ext.html;677.6103740349297;698.4552049401467;833.1918039501683;1121.2095472691753;1255.4852510470703;36025.81987090064;37089.25821483569;38708.41937370942;38822.41766373507;38870.61129388705;38567.42148867769;38741.41887871684;38880.80559597198;39001;39055;7902.723404680823;8074.6770129194765;8373.874391884126;8642.827143457127;8658;8481.448705834131;8558.186972237627;8558.529280889548;8572;8586.742397728078;8424.957875210615;8563.35774816888;8570.057293697688;8571;8606.31149508038;8532.658693652216;8546.230839224469;8596.742097737078;8655.177758113032;8677.696280630147;8464.915350846475;8514.361422893246;8566.143385661446;8583.227509524173;8584.012838523588;38253.80873095644;38375.84872453831;38392.80803595992;38470.80764596187;38512.422313665316;3005.5792189093545;3029.33354661974;3029.8939537116094;3056.9847150764326;3059.5410688396696;27370.45259094807;27574.310642233904;27722.5841612376;27981.160565183076;28787;2779.110684580935;2812.8031037827154;2819.422018486226;2829.4906916754976;2832.4901517726803;312072;316362;316540;317396.41301793343;317614;39774.60225397767;48890.77773055702;49329.013419731404;49463.25805112926;49614;50706.74646626743;51332.48667513352;51540.74229628903;51587.48412515902;51680;14347.78478322826;14538.709225815635;14678.486252981094;14690;14721.852781472047;10650.041496265218;10950.890491095146;11826.99470544996;11874.228175168586;11876.940615296868;10833.320835270546;11993.860583244752;12111.091668124925;12609.810852837214;13023.934880325538;5965.717370765306;5986.623076692298;6025.849353766103;6033.3061697905305;6042.879142417215;958.3195930889008;971.455984648599;976.6191185437685;981.651513712636;982.8034393121261;27969.44061118807;28633.856830715715;29026.41947161045;29037.12888613345;29269.707302927123;1049.4070849969742;1049.5382031905938;1068.6580294305902;1077.3697387028676;1094.3816743539874;11716.531338746354;11922.701932451584;11937.880621193677;11954.402279885971;11962.760744785055;475855;478776.60611696716;479746.62027037755;480031;480488;39406.62076827355;40092.1981560373;52602.473975260524;52841;53050.20424693633;1278.360819590205;1287.189070885355;1480.7556753135827;1485.264793927013;1505.2398538737866;27940.720592793812;28228.15315540537;28860.855695721388;29113.7088629111;29223.41553168882;38258.80870595629;43808.34287485754;47398;47853.521464784906;47885.52114478811;469101;470800;471931;473125;479441;34950.56518312377;35462.29075418426;35491.46762798508;35541.466877997365;35838.283234335686;43849.56150438583;44090;44095.33856992084;44116;44125.33811992758;28724.856375717987;30590.08229753111;30688.232794180323;30829.69170308358;30938.53592196164;29390.706092938795;29567.965121220655;29645.70354296516;30454.543181852732;30624.387512250076;35048.299034018666;35709.64290357063;35867.461988070725;36132.4580131293;36172.45741313935;30326.393472129992;30610.387792244477;31000.53499197559;31382;31420.84289578629;22791.772082278963;24259.51480970406;24426.877865610557;24437;24842.882070306518 11:51:24 INFO - PID 1637 | |2;jquery.html;400.19960079840314;412.27439706117264;424.5775453423844;424.69209822878406;447.8132947688625;16734.16329183542;17920.641587168266;18813.529661758464;20046.89976550117;21720.565588688238;20269.479789015808;20454;22718.772812271873;23163.30510084696;23239.535209295806;6224.719887605046;6229.626222426647;6429.035644653292;6507.869842603145;6517;6065.363136870631;6308.747650093991;6326.114343991845;6384.265809431929;6421.614703117804;5362.51737343639;6337.556371054028;6342.207224096988;6349.206349206349;6360;4950.43070046942;5380.650257733235;6104.816855494342;6196.628202307854;6281.999999999977;4844.346013288232;5421.430749771296;5459.3994660587305;5560.277163968699;5572.275604171432;27279.590806137727;27838;27885.72114278872;28231.870725170967;28761.56857647137;1675.589480577254;1679.40381164686;1980.6929925861514;2031.6343058249508;2060.917563297466;13871.375788089561;14430.783538246935;14674.779878301833;16199.35202591895;16215.756763648555;1691.365737848307;1698.634793519387;1703.0548045834557;1741.9216135273944;1760.2430954689478;150156.49530514103;158403;158700;159855.80288394168;160469;11924.821127683092;12502.937485312515;12744.36278186087;12753.298568578632;13097.41061652228;16625.7506137408;16628.83371166297;16642.916785416237;16645;16669.583260418345;8732.65069397217;9076.364654474255;9117.680881169259;9210.263178945668;9256.120668536478;8558.186972237627;8707.651693932172;8758.605862736193;8835.293176545862;8881.245094167069;7880.448368614257;7990.240927111915;8009.759707208793;8053.27520523144;8085.676572937016;2141.271967531016;2147.967780483294;2154.4829240982053;2156.051337411534;2257.808086312649;701.5933054226243;827.6358402302967;943.8348289049526;954.9092836180552;992.7617371830709;18997.58568807883;19388.806111938982;19685.803141968398;20142.194312227348;20245.291414800413;861.8190180062131;982.8673129127476;993.4933184076173;1008.445355054717;1012.1599072769584;10397.948010259901;10737.946310268555;10777.946110269555;10808.189385796097;10851.565937362575;708254;717349;727475;733154;744962;139775;141033;143380.5661943388;146116;146196;1171.1680783132879;1182.810750279951;1206.8129439936824;1208.5769980506927;1210.1952201785714;17556;18041.00774457365;18075.638487230444;18117.36589219371;18239.08804559793;55107.72446137744;58346;59080.704596476746;59607.403925960185;60920.78158436718;81511.5924420374;83024.58487707765;83971.3205735894;84394.15605843863;85724;8631.093735157718;8632.266257368194;8928.313502044473;9066.456012639263;9197.21823644971;10854;13610.115342502706;13778.517751879037;14018.088824226392;14209.431622735388;11610.071194304266;11659.650210493699;11758.647240582795;11852.58515951972;11939.283642981449;10680.305780124267;11135.72160695989;11240.662780116609;11461.197716160123;11549.480273387886;11913.82129268079;12018;12272.75454490923;12282.692932676755;12293;8735.781605459915;8848.557572121239;8857.501286991732;8940;9215.032421595635;11815.76368472643;12005.279683219034;12068.336241506802;12148.392580371123;12207.886988817794 11:51:24 INFO - PID 1637 | |3;mootools.html;1235.950561977521;1427.8215223097106;1432.2838580709645;1435.5693292012393;1451.5355086372358;10286.948565257173;10647;10705;10729.088027517651;10867.29362591431;10470.371777693324;10677.8398324025;10692.198085143606;10697.037266646;10720.35677859329;2407.747186545418;2458.139651122111;2464.605663093905;2466;2471.270975062352;2383.2553480858846;2425.7816796488314;2472.888720007595;2480.8635525046116;2481.6649752283483;2012.9899350503301;2422.672939153218;2453.411181316489;2459.6802415685934;2465.1988103866224;2370.810335173182;2433.8783060846977;2446.2905757330354;2451.8161137914726;2453.815963802722;2363.7045369328835;2438.926832195037;2449.7182823975295;2458.704955405363;2463.1625247415964;10857.294275872122;10884.401357925311;10900.291481053786;10900.96440838119;10902.836457453066;5459.12653975366;5554.250176226198;6234.906476402857;6474.384933431316;6492;3802.543694756619;3929.7445666031617;3939.207660514459;3995.9400608990886;4007.238624661334;1000.5347513406228;1188.821676748486;1197.8502687164105;1231.7536492701495;1233.5065973610629;13409;13737.24445155516;14972.775408368883;15139.924300378427;15213.695726085416;5180.222966555047;5218.165093585008;5421.701806400607;5444.8094316698725;5450.781968721207;8983.191512763882;11163.72090697739;11257.605983790529;11307.49116289769;11315.03822175108;6193.040078787795;6232.18981532407;6295.811125666238;6298.968505157445;6302.054691796267;4969.950300497021;5613.877224555105;5883.382244864313;5941.079132734347;5950.345461999176;3787.2614840106467;3909.569947305794;4170.749755014648;4272.209641216385;4308.763018033976;1290.4967062845499;1292.3796577642784;1292.6509842342514;1310.3251825309974;1311.1608570514688;751.5040073551539;760.8059944714173;784.3215618489965;786.7443080998698;799.8000499875031;5894.469497745224;5907.409259074144;5994.520438364921;6055.6063855849225;6067.75728970845;574.5174053794832;583.2650859916536;859.3898332184094;866.1208872993916;920.1442658327819;3210.5826242588314;3508.4737289406285;3968.1666849961252;3993.562317565674;4119.784663524268;11803.822942655866;12711.300878451773;13379.464821407035;13587.59237222885;13630.386632601358;13408.597742067754;13530.797038044437;13532.797008044887;13536.120152190077;13619.727605447835;10139.64511242103;10210.030047145508;10275.201839678177;10278.537465814057;10338.172946164288;5729.369769325288;6006.129111278753;6303.622497616727;6473.546851720334;6553.4101930826455;11996.340201289013;12160.878391215974;12181;12201.389930503618;12303.384830758605;12553.435095420911;12567;12622.43199056063;12811.359432028548;12862.292573908151;8089.2315230053055;8285.58572071382;8364.498130112213;8381.748547543584;8384.287335576544;8846.159614836837;9584.856227156459;9622.326437149612;10049.648262311077;10049.69850904474;8382.413231073766;8382.538960357238;8444.986601607601;8462.238398544161;8465.449745766506;8243.958780206061;8247.752567422747;8325.625346859528;8347.207015333533;8389.622466988878;7542.208068152765;7606.923930760621;7700.460967732205;7709.2290770922455;7738.690452381842;5762.078067509178;5892.587518873809;5931.110333449845;5941.405859414024;5995.160677505066;5823.7379317931645;5907.734151963258;5949.018411961978;6065.939340606537;6142.293636231891 11:51:24 INFO - PID 1637 | |4;prototype.html;383.3923231677791;391.9451276821245;396.5063495947544;396.8075483390557;399.70421887803036;5421.728913554326;6220.12918191453;6252.781152659658;6336.493080553556;6365.936340636604;6150.384961503847;6221.937780622204;6246.375362463751;6309.369063093688;6314.400131987465;3840.289546433905;4000.7599544027307;4754.381930349058;4779.091972525226;4798.208295631217;4043.9595604043875;4056.776877271749;4939.468764682942;4953.257011448276;4956.033573453178;4760.33355330254;4864.221724524077;4896.314515967767;4904.828331008415;4905.779239934212;4463.999999999984;4870.6347023972985;4878.975605121987;4911.887888208069;4925.187344088221;4325.870223893288;4333.133373325348;4343.804528796211;4349.238883195429;4357.346398040288;5136.820211292624;6104.542159338067;6146.139540464339;6171;6227.221597300338;5223.806001479388;5297.549708274802;5422.674639521641;5576.888462230773;5626.437356264381;6749.907528666129;6754.459643228579;6759.324067593201;6777.796666100025;6798.52410331277;4001.419794129835;4507.256302710049;4527.4340707411575;4792.305115758196;4803.78382972767;6722.731090756364;6852.45180385568;6954.200266969313;7054.814711187273;7177.605231712254;4910.8281210157475;4918.975405122951;4928.482509336468;4940.629452791055;4954.950450495521;4767.713937163712;5026.170681837529;5030.320906677625;5056;5058.571885494909;5145.588352931832;5232.128962751598;5487.945120548743;5614.361250212393;5636.464535869086;5605.570579502282;6515.7067931943175;6810.87620542605;6846.315368463114;6870.62211578358;1451.608065822222;1474.9336279867432;1561.7657351397381;1713.4945191168636;1740.9216585253694;988.9406635601887;1006.3257617396362;1179.622520793355;1184.419634379148;1185.1289302362757;6335.493160547145;6337;6343.777967771106;6349.269833969152;6367.42693157618;5788.479036886701;6857.725690972405;6990.09128813251;6997.510174287751;7023.438124949992;5149.330587023663;6099.542534309944;6177.96911015442;6319.778807741799;6364.649944253018;3013.517837146046;3186.6176058873084;3282.441984862531;3647.1611529348397;3673.94489082664;5727.656340619576;6113;7000.684969176399;7245.818854528574;7329.230430804795;5411.891762164734;5727.3699893011735;6546.509011824132;7129.857402851914;7259.89110163348;6601.240857301472;6916.827079322957;7218.89171662415;7229.710811567478;7336.266373362621;6994.5803251805055;7018.894716579358;7019.157701075904;7030.402415794715;7060.3292687194635;5996.5502587307;6242.282137554058;6503.577267477599;6802.3537763912345;6987.336203060701;6125;7202.3877970373105;7284.38082762971;7310.305520975498;7313.305236002571;4500.459944806644;4504.054148628825;4519.841805536922;4521.796519156712;4522.841700540465;6528.706208220547;6803.6052609214;6910.723571057101;6961.8955715665315;7028.543144695578;5764.13537969308;6012.759489620366;6719.731210751515;6780.694868731018;6781.254062053269;6372.840678983062;6692.799216023527;6714.328567143247;6764.738214924566;6766.451509762144;5819.5635327351465;6588.637624930675;6590.143281373391;6603.735850565923;6657.533972622064;5805.82582522525;6370.426661600478;6752.291009443938;6754.662266886734;6796.320367963164;6506.3493650634555;6601.471882249312;6607.975763756625;6631.6684165792485;6633.9668301658185 11:51:24 INFO - PID 1637 | |5;yui.html;654.9880434728348;694.7985084325546;735.7755884455239;740.2227660955997;740.4261697184683;14985.625359366008;15687.999999999985;15720.685586288282;16225.513234602951;16226;14922.179280139588;15020.549383518484;15067.321970511326;15093.92453037736;15115.62210944727;896.6144557840142;926.5969303353063;928.3316012471043;960.4381436859445;963.388248462228;970.403202030753;975.3075316525276;975.7316737897099;975.8438649816031;977.2475194100539;895.476146454325;982.243672372273;1016.3139880580616;1018.1701912940966;1019.0726438940566;1006.2754816532122;1010.5755582655303;1012.7923775626052;1013.3970287678819;1014.1582486536195;740.1636151149221;745.202633182495;750.6659536506257;750.9061367329084;753.2580408297822;15034.248287585577;15078.5476435706;15112.773308400383;16196.514104576881;16480.42318518846;14606.34271457787;14728.705425891421;14767.926160369238;14861.331240094221;15027.924860375737;9914.13658458843;11709.121815863758;11920.284782913053;12013.279203247746;12368.381580921008;3000.6549246836676;3036.104349216986;3037.407705497429;3054.9389012219854;3174.2699179188685;94984;98103;118773;120003;123104;11237.438128093563;11484.88515114838;11803.88196118045;11894.88105118955;12029;25126.246212613652;25399;25401;25409.87295063513;25447.36381590475;7699.499532530368;7842.333401660809;7901.170377110436;7979.521228726295;8190.180981901762;4121.155163191538;4185.707000509996;4192.287311157111;4197.139586384784;4211.642010429148;3424.503447000171;3492.6158122606494;4171.749695018309;4186.91626167475;4217.304144816071;793.9880901786478;801.3749275565068;803.8070862992956;809.9462191730469;821.035283541836;457.69563240444734;555.6876262926436;572.6449601247253;580.2949416458938;584.6024703201864;8692.222433023446;9948.950255248677;11062.944685276521;11181.161412894066;11230.494627741771;561.1274468201904;580.8605934575671;584.2083976212172;601.5758889982552;602.3073465514606;4178.132361966248;4229.873103806891;4541.6593755468475;4553.453585569753;4570.451545814457;78997.21002790013;96316.07367852604;96590;99890.00109998952;100914;55544.444555554735;72338;86054.84862878478;89890.75273118225;92038;53407;63907;75964.62017689877;77110.22889771254;77655.84613615602;10540.525676344736;10872.836907446554;10946.288491248044;11070.169737269898;11147.83278250843;28922.843086276313;38098.047548811504;38210.80894595509;38637.80681096577;38835;50871.49128508816;58031.70984145052;69785.30214697788;71350.28649713645;72541;13529.59411217665;13683.452661893412;13944.58166255014;14012.789808152682;14461.638459038608;14806.259687015821;14917.776233356291;15253;15419.691606168039;15571.454999074978;13908.721825563229;13979.720405591628;14144.504942326968;14438.566842994727;14499.347529360994;13596.93201533986;13897.244914702225;14077.366518506487;14093.788593170906;14782.408703652163;13437.932810335886;13583.52457663977;14017.859821401655;14042.719145617235;14063.859361406256;9725.756856078655;9733.124018838338;9803.804520853277;9814.263930205121;10045.447500387256;7937.722179723913;9370.765730856783;10060.49697515136;11095.116709151733;11098.167637427387 11:51:24 INFO - PID 1637 | __end_tp_report 11:51:24 INFO - PID 1637 | __start_cc_report 11:51:24 INFO - PID 1637 | _x_x_mozilla_cycle_collect,421 11:51:24 INFO - PID 1637 | __end_cc_report 11:51:24 INFO - PID 1637 | __startTimestamp1490727084862__endTimestamp 11:51:24 INFO - PID 1637 | 11:51:24 INFO - PID 1637 | ------- Summary: start ------- 11:51:24 INFO - PID 1637 | Number of tests: 6 11:51:24 INFO - PID 1637 | 11:51:24 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6152.94 Median:6302.91 stddev:705.19 (11.2%) stddev-sans-first:671.81 11:51:24 INFO - PID 1637 | Values: 3335.2 3598.1 3616.6 3648.8 3649.3 5136.9 6128.9 6164.6 6183.1 6226.8 6138.6 6165.3 6189.3 6197.9 6228.5 4180.5 5658.3 5680.9 5785.7 5903.9 4714.5 5630.2 5724.4 5912.1 5934.8 5603.2 5690.8 5741.5 5754.3 5785.6 5700.1 5702.7 5710.0 5728.7 5732.6 5623.6 5640.9 5670.7 5717.9 5723.5 5978.1 6108.1 6141.8 6173.1 6216.5 6681.6 6707.7 6712.5 6715.0 6740.2 5803.7 6749.3 6811.1 6814.0 6814.3 6154.6 6174.2 6199.7 6305.9 6319.6 6916.6 6941.0 6978.5 6980.5 6982.5 6318.6 6405.8 6423.8 6510.5 6628.8 6490.4 6614.6 6663.8 6768.9 6789.5 6690.2 6691.6 6695.1 6705.4 6716.0 6382.4 6403.5 6525.0 6583.9 6612.4 5410.2 5422.3 5427.2 5459.9 5483.0 4733.8 4760.7 4773.7 4788.5 4811.5 6147.5 6200.4 6225.7 6282.6 6315.5 6746.2 6755.9 6789.3 6791.1 6800.0 6215.5 6278.4 6285.4 6290.0 6310.4 5874.5 5948.9 6053.8 6058.5 6065.3 5892.9 6797.8 6872.5 6918.9 6954.2 6702.6 6736.8 6747.3 6753.8 6771.9 5755.7 6864.9 6915.0 6934.8 6976.8 6393.1 6485.3 6571.9 6643.6 6677.6 5597.7 6005.0 6040.2 6714.1 6726.6 6286.2 6302.9 6393.3 6533.9 6575.9 6385.9 6556.3 6579.4 6586.2 6632.4 5883.5 6160.7 6248.3 6332.6 6348.0 5145.6 6515.7 6569.7 6576.7 6592.9 6397.0 6407.1 6407.5 6424.2 6437.2 11:51:24 INFO - PID 1637 | 11:51:24 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:55536.53 Median:36079.14 stddev:114197.47 (316.5%) stddev-sans-first:114443.55 11:51:24 INFO - PID 1637 | Values: 677.6 698.5 833.2 1121.2 1255.5 36025.8 37089.3 38708.4 38822.4 38870.6 38567.4 38741.4 38880.8 39001.0 39055.0 7902.7 8074.7 8373.9 8642.8 8658.0 8481.4 8558.2 8558.5 8572.0 8586.7 8425.0 8563.4 8570.1 8571.0 8606.3 8532.7 8546.2 8596.7 8655.2 8677.7 8464.9 8514.4 8566.1 8583.2 8584.0 38253.8 38375.8 38392.8 38470.8 38512.4 3005.6 3029.3 3029.9 3057.0 3059.5 27370.5 27574.3 27722.6 27981.2 28787.0 2779.1 2812.8 2819.4 2829.5 2832.5 312072.0 316362.0 316540.0 317396.4 317614.0 39774.6 48890.8 49329.0 49463.3 49614.0 50706.7 51332.5 51540.7 51587.5 51680.0 14347.8 14538.7 14678.5 14690.0 14721.9 10650.0 10950.9 11827.0 11874.2 11876.9 10833.3 11993.9 12111.1 12609.8 13023.9 5965.7 5986.6 6025.8 6033.3 6042.9 958.3 971.5 976.6 981.7 982.8 27969.4 28633.9 29026.4 29037.1 29269.7 1049.4 1049.5 1068.7 1077.4 1094.4 11716.5 11922.7 11937.9 11954.4 11962.8 475855.0 478776.6 479746.6 480031.0 480488.0 39406.6 40092.2 52602.5 52841.0 53050.2 1278.4 1287.2 1480.8 1485.3 1505.2 27940.7 28228.2 28860.9 29113.7 29223.4 38258.8 43808.3 47398.0 47853.5 47885.5 469101.0 470800.0 471931.0 473125.0 479441.0 34950.6 35462.3 35491.5 35541.5 35838.3 43849.6 44090.0 44095.3 44116.0 44125.3 28724.9 30590.1 30688.2 30829.7 30938.5 29390.7 29568.0 29645.7 30454.5 30624.4 35048.3 35709.6 35867.5 36132.5 36172.5 30326.4 30610.4 31000.5 31382.0 31420.8 22791.8 24259.5 24426.9 24437.0 24842.9 11:51:24 INFO - PID 1637 | 11:51:24 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:40958.40 Median:2155.27 stddev:121380.23 (5631.8%) stddev-sans-first:121682.53 11:51:24 INFO - PID 1637 | Values: 400.2 412.3 424.6 424.7 447.8 16734.2 17920.6 18813.5 20046.9 21720.6 20269.5 20454.0 22718.8 23163.3 23239.5 6224.7 6229.6 6429.0 6507.9 6517.0 6065.4 6308.7 6326.1 6384.3 6421.6 5362.5 6337.6 6342.2 6349.2 6360.0 4950.4 5380.7 6104.8 6196.6 6282.0 4844.3 5421.4 5459.4 5560.3 5572.3 27279.6 27838.0 27885.7 28231.9 28761.6 1675.6 1679.4 1980.7 2031.6 2060.9 13871.4 14430.8 14674.8 16199.4 16215.8 1691.4 1698.6 1703.1 1741.9 1760.2 150156.5 158403.0 158700.0 159855.8 160469.0 11924.8 12502.9 12744.4 12753.3 13097.4 16625.8 16628.8 16642.9 16645.0 16669.6 8732.7 9076.4 9117.7 9210.3 9256.1 8558.2 8707.7 8758.6 8835.3 8881.2 7880.4 7990.2 8009.8 8053.3 8085.7 2141.3 2148.0 2154.5 2156.1 2257.8 701.6 827.6 943.8 954.9 992.8 18997.6 19388.8 19685.8 20142.2 20245.3 861.8 982.9 993.5 1008.4 1012.2 10397.9 10737.9 10777.9 10808.2 10851.6 708254.0 717349.0 727475.0 733154.0 744962.0 139775.0 141033.0 143380.6 146116.0 146196.0 1171.2 1182.8 1206.8 1208.6 1210.2 17556.0 18041.0 18075.6 18117.4 18239.1 55107.7 58346.0 59080.7 59607.4 60920.8 81511.6 83024.6 83971.3 84394.2 85724.0 8631.1 8632.3 8928.3 9066.5 9197.2 10854.0 13610.1 13778.5 14018.1 14209.4 11610.1 11659.7 11758.6 11852.6 11939.3 10680.3 11135.7 11240.7 11461.2 11549.5 11913.8 12018.0 12272.8 12282.7 12293.0 8735.8 8848.6 8857.5 8940.0 9215.0 11815.8 12005.3 12068.3 12148.4 12207.9 11:51:24 INFO - PID 1637 | 11:51:24 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:6616.31 Median:2846.12 stddev:4058.23 (142.6%) stddev-sans-first:4049.48 11:51:24 INFO - PID 1637 | Values: 1236.0 1427.8 1432.3 1435.6 1451.5 10286.9 10647.0 10705.0 10729.1 10867.3 10470.4 10677.8 10692.2 10697.0 10720.4 2407.7 2458.1 2464.6 2466.0 2471.3 2383.3 2425.8 2472.9 2480.9 2481.7 2013.0 2422.7 2453.4 2459.7 2465.2 2370.8 2433.9 2446.3 2451.8 2453.8 2363.7 2438.9 2449.7 2458.7 2463.2 10857.3 10884.4 10900.3 10901.0 10902.8 5459.1 5554.3 6234.9 6474.4 6492.0 3802.5 3929.7 3939.2 3995.9 4007.2 1000.5 1188.8 1197.9 1231.8 1233.5 13409.0 13737.2 14972.8 15139.9 15213.7 5180.2 5218.2 5421.7 5444.8 5450.8 8983.2 11163.7 11257.6 11307.5 11315.0 6193.0 6232.2 6295.8 6299.0 6302.1 4970.0 5613.9 5883.4 5941.1 5950.3 3787.3 3909.6 4170.7 4272.2 4308.8 1290.5 1292.4 1292.7 1310.3 1311.2 751.5 760.8 784.3 786.7 799.8 5894.5 5907.4 5994.5 6055.6 6067.8 574.5 583.3 859.4 866.1 920.1 3210.6 3508.5 3968.2 3993.6 4119.8 11803.8 12711.3 13379.5 13587.6 13630.4 13408.6 13530.8 13532.8 13536.1 13619.7 10139.6 10210.0 10275.2 10278.5 10338.2 5729.4 6006.1 6303.6 6473.5 6553.4 11996.3 12160.9 12181.0 12201.4 12303.4 12553.4 12567.0 12622.4 12811.4 12862.3 8089.2 8285.6 8364.5 8381.7 8384.3 8846.2 9584.9 9622.3 10049.6 10049.7 8382.4 8382.5 8445.0 8462.2 8465.4 8244.0 8247.8 8325.6 8347.2 8389.6 7542.2 7606.9 7700.5 7709.2 7738.7 5762.1 5892.6 5931.1 5941.4 5995.2 5823.7 5907.7 5949.0 6065.9 6142.3 11:51:24 INFO - PID 1637 | 11:51:24 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:5477.32 Median:6135.57 stddev:1682.55 (27.4%) stddev-sans-first:1643.25 11:51:24 INFO - PID 1637 | Values: 383.4 391.9 396.5 396.8 399.7 5421.7 6220.1 6252.8 6336.5 6365.9 6150.4 6221.9 6246.4 6309.4 6314.4 3840.3 4000.8 4754.4 4779.1 4798.2 4044.0 4056.8 4939.5 4953.3 4956.0 4760.3 4864.2 4896.3 4904.8 4905.8 4464.0 4870.6 4879.0 4911.9 4925.2 4325.9 4333.1 4343.8 4349.2 4357.3 5136.8 6104.5 6146.1 6171.0 6227.2 5223.8 5297.5 5422.7 5576.9 5626.4 6749.9 6754.5 6759.3 6777.8 6798.5 4001.4 4507.3 4527.4 4792.3 4803.8 6722.7 6852.5 6954.2 7054.8 7177.6 4910.8 4919.0 4928.5 4940.6 4955.0 4767.7 5026.2 5030.3 5056.0 5058.6 5145.6 5232.1 5487.9 5614.4 5636.5 5605.6 6515.7 6810.9 6846.3 6870.6 1451.6 1474.9 1561.8 1713.5 1740.9 988.9 1006.3 1179.6 1184.4 1185.1 6335.5 6337.0 6343.8 6349.3 6367.4 5788.5 6857.7 6990.1 6997.5 7023.4 5149.3 6099.5 6178.0 6319.8 6364.6 3013.5 3186.6 3282.4 3647.2 3673.9 5727.7 6113.0 7000.7 7245.8 7329.2 5411.9 5727.4 6546.5 7129.9 7259.9 6601.2 6916.8 7218.9 7229.7 7336.3 6994.6 7018.9 7019.2 7030.4 7060.3 5996.6 6242.3 6503.6 6802.4 6987.3 6125.0 7202.4 7284.4 7310.3 7313.3 4500.5 4504.1 4519.8 4521.8 4522.8 6528.7 6803.6 6910.7 6961.9 7028.5 5764.1 6012.8 6719.7 6780.7 6781.3 6372.8 6692.8 6714.3 6764.7 6766.5 5819.6 6588.6 6590.1 6603.7 6657.5 5805.8 6370.4 6752.3 6754.7 6796.3 6506.3 6601.5 6608.0 6631.7 6634.0 11:51:24 INFO - PID 1637 | 11:51:24 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:19674.88 Median:20795.33 stddev:27931.08 (134.3%) stddev-sans-first:27972.92 11:51:24 INFO - PID 1637 | Values: 655.0 694.8 735.8 740.2 740.4 14985.6 15688.0 15720.7 16225.5 16226.0 14922.2 15020.5 15067.3 15093.9 15115.6 896.6 926.6 928.3 960.4 963.4 970.4 975.3 975.7 975.8 977.2 895.5 982.2 1016.3 1018.2 1019.1 1006.3 1010.6 1012.8 1013.4 1014.2 740.2 745.2 750.7 750.9 753.3 15034.2 15078.5 15112.8 16196.5 16480.4 14606.3 14728.7 14767.9 14861.3 15027.9 9914.1 11709.1 11920.3 12013.3 12368.4 3000.7 3036.1 3037.4 3054.9 3174.3 94984.0 98103.0 118773.0 120003.0 123104.0 11237.4 11484.9 11803.9 11894.9 12029.0 25126.2 25399.0 25401.0 25409.9 25447.4 7699.5 7842.3 7901.2 7979.5 8190.2 4121.2 4185.7 4192.3 4197.1 4211.6 3424.5 3492.6 4171.7 4186.9 4217.3 794.0 801.4 803.8 809.9 821.0 457.7 555.7 572.6 580.3 584.6 8692.2 9949.0 11062.9 11181.2 11230.5 561.1 580.9 584.2 601.6 602.3 4178.1 4229.9 4541.7 4553.5 4570.5 78997.2 96316.1 96590.0 99890.0 100914.0 55544.4 72338.0 86054.8 89890.8 92038.0 53407.0 63907.0 75964.6 77110.2 77655.8 10540.5 10872.8 10946.3 11070.2 11147.8 28922.8 38098.0 38210.8 38637.8 38835.0 50871.5 58031.7 69785.3 71350.3 72541.0 13529.6 13683.5 13944.6 14012.8 14461.6 14806.3 14917.8 15253.0 15419.7 15571.5 13908.7 13979.7 14144.5 14438.6 14499.3 13596.9 13897.2 14077.4 14093.8 14782.4 13437.9 13583.5 14017.9 14042.7 14063.9 9725.8 9733.1 9803.8 9814.3 10045.4 7937.7 9370.8 10060.5 11095.1 11098.2 11:51:24 INFO - PID 1637 | -------- Summary: end -------- 11:51:24 INFO - PID 1637 | 11:51:25 INFO - TEST-INFO | 1637: exit 0 11:51:25 INFO - TEST-OK | dromaeo_css | took 1118498ms 11:51:25 INFO - TEST-START | kraken 11:51:25 INFO - operating with platform_type : mac_ 11:51:25 INFO - Initialising browser for kraken test... 11:51:25 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpIRQpXG/profile http://localhost:49236/getInfo.html) 11:51:28 INFO - PID 1650 | 2017-03-28 11:51:28.505 plugin-container[1652:10382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 11:51:28 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:51:28 INFO - PID 1650 | 2017-03-28 11:51:28.508 plugin-container[1652:10382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 11:51:28 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:51:28 INFO - PID 1650 | 2017-03-28 11:51:28.508 plugin-container[1652:10382] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:51:28 INFO - PID 1650 | __metrics Screen width/height:1600/1200 11:51:28 INFO - PID 1650 | colorDepth:24 11:51:28 INFO - PID 1650 | Browser inner width/height: 1024/689 11:51:28 INFO - PID 1650 | __metrics 11:51:28 ERROR - PID 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 11:51:28 ERROR - PID 1650 | 1490727088670 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < forceQuit@jar:file:///var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpIRQpXG/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpIRQpXG/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpIRQpXG/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpIRQpXG/profile/extensions/talos-powers@TalosPowersService.js:55:9 11:51:29 INFO - TEST-INFO | 1650: exit 0 11:51:29 INFO - Browser initialized. 11:51:29 INFO - Running cycle 1/1 for kraken test... 11:51:29 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/T/tmpIRQpXG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:51:32 INFO - PID 1653 | 2017-03-28 11:51:32.302 plugin-container[1654:10502] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x892f, name = 'com.apple.tsm.portname' 11:51:32 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:51:32 INFO - PID 1653 | 2017-03-28 11:51:32.302 plugin-container[1654:10502] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 11:51:32 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:51:32 INFO - PID 1653 | 2017-03-28 11:51:32.302 plugin-container[1654:10502] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:51:41 INFO - PID 1653 | RSS: Main: 229662720 11:51:41 INFO - PID 1653 | 11:51:44 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 11:51:45 INFO - PID 1653 | RSS: Main: 244338688 11:51:45 INFO - PID 1653 | 11:51:48 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 11:51:49 INFO - PID 1653 | RSS: Main: 252899328 11:51:49 INFO - PID 1653 | 11:51:52 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 11:51:53 INFO - PID 1653 | RSS: Main: 254013440 11:51:53 INFO - PID 1653 | 11:51:55 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 11:51:56 INFO - PID 1653 | RSS: Main: 255950848 11:51:56 INFO - PID 1653 | 11:51:58 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:51:58 INFO - PID 1653 | RSS: Main: 256643072 11:51:58 INFO - PID 1653 | 11:52:02 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 11:52:02 INFO - PID 1653 | RSS: Main: 261763072 11:52:02 INFO - PID 1653 | 11:52:06 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 11:52:06 INFO - PID 1653 | RSS: Main: 260141056 11:52:06 INFO - PID 1653 | 11:52:10 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 11:52:11 INFO - PID 1653 | RSS: Main: 259870720 11:52:11 INFO - PID 1653 | 11:52:12 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:52:13 INFO - PID 1653 | RSS: Main: 261214208 11:52:13 INFO - PID 1653 | 11:52:15 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:52:16 INFO - PID 1653 | RSS: Main: 261505024 11:52:16 INFO - PID 1653 | 11:52:18 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:52:19 INFO - PID 1653 | RSS: Main: 261578752 11:52:19 INFO - PID 1653 | 11:52:22 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:52:23 INFO - PID 1653 | RSS: Main: 262053888 11:52:23 INFO - PID 1653 | 11:52:26 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:52:27 INFO - PID 1653 | RSS: Main: 261767168 11:52:27 INFO - PID 1653 | 11:52:28 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 11:52:29 INFO - PID 1653 | RSS: Main: 264622080 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | __start_tp_report 11:52:29 INFO - PID 1653 | _x_x_mozilla_page_load 11:52:29 INFO - PID 1653 | _x_x_mozilla_page_load_details 11:52:29 INFO - PID 1653 | |i|pagename|runs| 11:52:29 INFO - PID 1653 | |0;ai-astar;111;123;120;123;123;124;124;141;104;183 11:52:29 INFO - PID 1653 | |1;audio-beat-detection;154;120;116;162;161;110;105;148;167;109 11:52:29 INFO - PID 1653 | |2;audio-dft;273;222;230;292;216;199;205;201;277;302 11:52:29 INFO - PID 1653 | |3;audio-fft;80;62;63;115;63;67;51;64;98;94 11:52:29 INFO - PID 1653 | |4;audio-oscillator;91;72;87;75;74;72;90;108;85;87 11:52:29 INFO - PID 1653 | |5;imaging-gaussian-blur;112;116;108;93;94;94;96;82;81;135 11:52:29 INFO - PID 1653 | |6;imaging-darkroom;108;157;142;100;100;105;101;100;158;154 11:52:29 INFO - PID 1653 | |7;imaging-desaturate;154;118;104;161;156;100;101;104;100;158 11:52:29 INFO - PID 1653 | |8;json-parse-financial;59;87;106;111;58;71;97;108;82;59 11:52:29 INFO - PID 1653 | |9;json-stringify-tinderbox;62;67;66;99;87;86;67;61;61;63 11:52:29 INFO - PID 1653 | |10;stanford-crypto-aes;92;98;88;89;87;99;91;79;105;77 11:52:29 INFO - PID 1653 | |11;stanford-crypto-ccm;165;188;148;170;166;167;151;183;214;165 11:52:29 INFO - PID 1653 | |12;stanford-crypto-pbkdf2;199;201;197;194;196;201;197;198;195;195 11:52:29 INFO - PID 1653 | |13;stanford-crypto-sha256-iterative;71;70;72;72;72;73;73;72;71;72 11:52:29 INFO - PID 1653 | __end_tp_report 11:52:29 INFO - PID 1653 | __start_cc_report 11:52:29 INFO - PID 1653 | _x_x_mozilla_cycle_collect,1030 11:52:29 INFO - PID 1653 | __end_cc_report 11:52:29 INFO - PID 1653 | __startTimestamp1490727149037__endTimestamp 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | ------- Summary: start ------- 11:52:29 INFO - PID 1653 | Number of tests: 14 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#0] ai-astar Cycles:10 Average:127.60 Median:123.50 stddev:21.68 (17.6%) stddev-sans-first:22.14 11:52:29 INFO - PID 1653 | Values: 111.0 123.0 120.0 123.0 123.0 124.0 124.0 141.0 104.0 183.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#1] audio-beat-detection Cycles:10 Average:135.20 Median:151.00 stddev:25.27 (16.7%) stddev-sans-first:25.87 11:52:29 INFO - PID 1653 | Values: 154.0 120.0 116.0 162.0 161.0 110.0 105.0 148.0 167.0 109.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#2] audio-dft Cycles:10 Average:241.70 Median:251.50 stddev:40.01 (15.9%) stddev-sans-first:40.80 11:52:29 INFO - PID 1653 | Values: 273.0 222.0 230.0 292.0 216.0 199.0 205.0 201.0 277.0 302.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#3] audio-fft Cycles:10 Average:75.70 Median:65.50 stddev:20.35 (31.1%) stddev-sans-first:21.53 11:52:29 INFO - PID 1653 | Values: 80.0 62.0 63.0 115.0 63.0 67.0 51.0 64.0 98.0 94.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#4] audio-oscillator Cycles:10 Average:84.10 Median:86.00 stddev:11.30 (13.1%) stddev-sans-first:11.70 11:52:29 INFO - PID 1653 | Values: 91.0 72.0 87.0 75.0 74.0 72.0 90.0 108.0 85.0 87.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:101.10 Median:87.50 stddev:16.66 (19.0%) stddev-sans-first:17.20 11:52:29 INFO - PID 1653 | Values: 112.0 116.0 108.0 93.0 94.0 94.0 96.0 82.0 81.0 135.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#6] imaging-darkroom Cycles:10 Average:122.50 Median:125.00 stddev:26.50 (21.2%) stddev-sans-first:27.58 11:52:29 INFO - PID 1653 | Values: 108.0 157.0 142.0 100.0 100.0 105.0 101.0 100.0 158.0 154.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#7] imaging-desaturate Cycles:10 Average:125.60 Median:136.00 stddev:27.77 (20.4%) stddev-sans-first:27.49 11:52:29 INFO - PID 1653 | Values: 154.0 118.0 104.0 161.0 156.0 100.0 101.0 104.0 100.0 158.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#8] json-parse-financial Cycles:10 Average:83.80 Median:65.00 stddev:21.25 (32.7%) stddev-sans-first:20.56 11:52:29 INFO - PID 1653 | Values: 59.0 87.0 106.0 111.0 58.0 71.0 97.0 108.0 82.0 59.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:67.00 stddev:13.58 (20.3%) stddev-sans-first:13.92 11:52:29 INFO - PID 1653 | Values: 62.0 67.0 66.0 99.0 87.0 86.0 67.0 61.0 61.0 63.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:90.00 stddev:8.67 (9.6%) stddev-sans-first:9.18 11:52:29 INFO - PID 1653 | Values: 92.0 98.0 88.0 89.0 87.0 99.0 91.0 79.0 105.0 77.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:171.70 Median:168.50 stddev:19.21 (11.4%) stddev-sans-first:20.22 11:52:29 INFO - PID 1653 | Values: 165.0 188.0 148.0 170.0 166.0 167.0 151.0 183.0 214.0 165.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.30 Median:197.50 stddev:2.45 (1.2%) stddev-sans-first:2.52 11:52:29 INFO - PID 1653 | Values: 199.0 201.0 197.0 194.0 196.0 201.0 197.0 198.0 195.0 195.0 11:52:29 INFO - PID 1653 | 11:52:29 INFO - PID 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:72.00 stddev:0.92 (1.3%) stddev-sans-first:0.93 11:52:29 INFO - PID 1653 | Values: 71.0 70.0 72.0 72.0 72.0 73.0 73.0 72.0 71.0 72.0 11:52:29 INFO - PID 1653 | -------- Summary: end -------- 11:52:29 INFO - PID 1653 | 11:52:29 INFO - TEST-INFO | 1653: exit 0 11:52:29 INFO - TEST-OK | kraken | took 64321ms 11:52:29 INFO - SUITE-END | took 1182s 11:52:30 INFO - Completed test suite (00:19:43) 11:52:30 INFO - javascript benchmark 11:52:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7734.256628589689, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3335.182880194353, 3598.1004748812798, 3616.566012078551, 3648.8175591220433, 3649.288388764191, 5136.897262054752, 6128.869808884168, 6164.630122192666, 6183.072539119129, 6226.782062627809, 6138.57232748375, 6165.290991535975, 6189.2572891253, 6197.938020619804, 6228.501719862412, 4180.498340199165, 5658.34928983166, 5680.943190568093, 5785.739641716112, 5903.88192236155, 4714.528547145293, 5630.239917611131, 5724.370319264877, 5912.083627037816, 5934.762609495637, 5603.187537807036, 5690.772369105231, 5741.511971482387, 5754.338251101135, 5785.5660825437835, 5700.087985922274, 5702.657840529581, 5710.0, 5728.713564321767, 5732.627379220379, 5623.634463759842, 5640.943590564083, 5670.659760414388, 5717.942820571782, 5723.456271654186, 5978.073398623221, 6108.053251745987, 6141.762184092961, 6173.135760993465, 6216.471599914013, 6681.5656982296, 6707.664616769142, 6712.5301228913995, 6715.02632118345, 6740.191177058735, 5803.709814509258, 6749.257581666062, 6811.148606424197, 6813.965930170367, 6814.318568143196, 6154.569180157436, 6174.166487524127, 6199.721012554446, 6305.873882522324, 6319.55763096588, 6916.585004899622, 6941.0, 6978.476614253951, 6980.476464265099, 6982.47631427645, 6318.589291696025, 6405.839854003594, 6423.839404014937, 6510.511711621647, 6628.834279142963, 6490.448311893448, 6614.603123812587, 6663.766768163091, 6768.864622707519, 6789.490788190906, 6690.163729533809, 6691.631960242234, 6695.062691223233, 6705.362990515893, 6715.966420167868, 6382.361763823674, 6403.519736019724, 6525.021246812921, 6583.9012414813815, 6612.371824676744, 5410.1614249790555, 5422.349318081777, 5427.158790387496, 5459.945400546023, 5483.040467918098, 4733.763311834464, 4760.690555113906, 4773.66584339093, 4788.521147885253, 4811.542903424169, 6147.493864003259, 6200.441960223511, 6225.657588832658, 6282.623042617458, 6315.494760419156, 6746.190457145174, 6755.932440675629, 6789.287124851919, 6791.117154769849, 6800.0, 6215.471684906744, 6278.434940855345, 6285.43431091204, 6289.96855015722, 6310.368963103653, 5874.530037596982, 5948.940510594925, 6053.8486537836025, 6058.485028772516, 6065.302490213594, 5892.880352732967, 6797.762078327235, 6872.450203983769, 6918.861622767416, 6954.200266969363, 6702.564333318319, 6736.7642132525125, 6747.291534388818, 6753.797386078426, 6771.864562708817, 5755.740991655469, 6864.897026544706, 6914.965425172842, 6934.757283495255, 6976.825579360557, 6393.073004414241, 6485.254195767358, 6571.868562628816, 6643.60138391698, 6677.565958212701, 5597.6641401516035, 6005.0, 6040.214772079602, 6714.127163468913, 6726.596404215763, 6286.245650521783, 6302.905456418249, 6393.328700486382, 6533.901991470037, 6575.901361479486, 6385.87228255423, 6556.311587283267, 6579.374959378852, 6586.176727909012, 6632.403083722488, 5883.529317654663, 6160.661163635864, 6248.281447633399, 6332.588381755171, 6348.047792830927, 5145.562627176732, 6515.706793194223, 6569.704363303567, 6576.704048317742, 6592.934070659232, 6397.0, 6407.10300557913, 6407.487401007815, 6424.201223657384, 6437.227532696106], "value": 6110.315135846291, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.6103740349297, 698.4552049401467, 833.1918039501683, 1121.2095472691753, 1255.4852510470703, 36025.81987090064, 37089.25821483569, 38708.41937370942, 38822.41766373507, 38870.61129388705, 38567.42148867769, 38741.41887871684, 38880.80559597198, 39001.0, 39055.0, 7902.723404680823, 8074.6770129194765, 8373.874391884126, 8642.827143457127, 8658.0, 8481.448705834131, 8558.186972237627, 8558.529280889548, 8572.0, 8586.742397728078, 8424.957875210615, 8563.35774816888, 8570.057293697688, 8571.0, 8606.31149508038, 8532.658693652216, 8546.230839224469, 8596.742097737078, 8655.177758113032, 8677.696280630147, 8464.915350846475, 8514.361422893246, 8566.143385661446, 8583.227509524173, 8584.012838523588, 38253.80873095644, 38375.84872453831, 38392.80803595992, 38470.80764596187, 38512.422313665316, 3005.5792189093545, 3029.33354661974, 3029.8939537116094, 3056.9847150764326, 3059.5410688396696, 27370.45259094807, 27574.310642233904, 27722.5841612376, 27981.160565183076, 28787.0, 2779.110684580935, 2812.8031037827154, 2819.422018486226, 2829.4906916754976, 2832.4901517726803, 312072.0, 316362.0, 316540.0, 317396.41301793343, 317614.0, 39774.60225397767, 48890.77773055702, 49329.013419731404, 49463.25805112926, 49614.0, 50706.74646626743, 51332.48667513352, 51540.74229628903, 51587.48412515902, 51680.0, 14347.78478322826, 14538.709225815635, 14678.486252981094, 14690.0, 14721.852781472047, 10650.041496265218, 10950.890491095146, 11826.99470544996, 11874.228175168586, 11876.940615296868, 10833.320835270546, 11993.860583244752, 12111.091668124925, 12609.810852837214, 13023.934880325538, 5965.717370765306, 5986.623076692298, 6025.849353766103, 6033.3061697905305, 6042.879142417215, 958.3195930889008, 971.455984648599, 976.6191185437685, 981.651513712636, 982.8034393121261, 27969.44061118807, 28633.856830715715, 29026.41947161045, 29037.12888613345, 29269.707302927123, 1049.4070849969742, 1049.5382031905938, 1068.6580294305902, 1077.3697387028676, 1094.3816743539874, 11716.531338746354, 11922.701932451584, 11937.880621193677, 11954.402279885971, 11962.760744785055, 475855.0, 478776.60611696716, 479746.62027037755, 480031.0, 480488.0, 39406.62076827355, 40092.1981560373, 52602.473975260524, 52841.0, 53050.20424693633, 1278.360819590205, 1287.189070885355, 1480.7556753135827, 1485.264793927013, 1505.2398538737866, 27940.720592793812, 28228.15315540537, 28860.855695721388, 29113.7088629111, 29223.41553168882, 38258.80870595629, 43808.34287485754, 47398.0, 47853.521464784906, 47885.52114478811, 469101.0, 470800.0, 471931.0, 473125.0, 479441.0, 34950.56518312377, 35462.29075418426, 35491.46762798508, 35541.466877997365, 35838.283234335686, 43849.56150438583, 44090.0, 44095.33856992084, 44116.0, 44125.33811992758, 28724.856375717987, 30590.08229753111, 30688.232794180323, 30829.69170308358, 30938.53592196164, 29390.706092938795, 29567.965121220655, 29645.70354296516, 30454.543181852732, 30624.387512250076, 35048.299034018666, 35709.64290357063, 35867.461988070725, 36132.4580131293, 36172.45741313935, 30326.393472129992, 30610.387792244477, 31000.53499197559, 31382.0, 31420.84289578629, 22791.772082278963, 24259.51480970406, 24426.877865610557, 24437.0, 24842.882070306518], "value": 17941.497688421117, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.19960079840314, 412.27439706117264, 424.5775453423844, 424.69209822878406, 447.8132947688625, 16734.16329183542, 17920.641587168266, 18813.529661758464, 20046.89976550117, 21720.565588688238, 20269.479789015808, 20454.0, 22718.772812271873, 23163.30510084696, 23239.535209295806, 6224.719887605046, 6229.626222426647, 6429.035644653292, 6507.869842603145, 6517.0, 6065.363136870631, 6308.747650093991, 6326.114343991845, 6384.265809431929, 6421.614703117804, 5362.51737343639, 6337.556371054028, 6342.207224096988, 6349.206349206349, 6360.0, 4950.43070046942, 5380.650257733235, 6104.816855494342, 6196.628202307854, 6281.999999999977, 4844.346013288232, 5421.430749771296, 5459.3994660587305, 5560.277163968699, 5572.275604171432, 27279.590806137727, 27838.0, 27885.72114278872, 28231.870725170967, 28761.56857647137, 1675.589480577254, 1679.40381164686, 1980.6929925861514, 2031.6343058249508, 2060.917563297466, 13871.375788089561, 14430.783538246935, 14674.779878301833, 16199.35202591895, 16215.756763648555, 1691.365737848307, 1698.634793519387, 1703.0548045834557, 1741.9216135273944, 1760.2430954689478, 150156.49530514103, 158403.0, 158700.0, 159855.80288394168, 160469.0, 11924.821127683092, 12502.937485312515, 12744.36278186087, 12753.298568578632, 13097.41061652228, 16625.7506137408, 16628.83371166297, 16642.916785416237, 16645.0, 16669.583260418345, 8732.65069397217, 9076.364654474255, 9117.680881169259, 9210.263178945668, 9256.120668536478, 8558.186972237627, 8707.651693932172, 8758.605862736193, 8835.293176545862, 8881.245094167069, 7880.448368614257, 7990.240927111915, 8009.759707208793, 8053.27520523144, 8085.676572937016, 2141.271967531016, 2147.967780483294, 2154.4829240982053, 2156.051337411534, 2257.808086312649, 701.5933054226243, 827.6358402302967, 943.8348289049526, 954.9092836180552, 992.7617371830709, 18997.58568807883, 19388.806111938982, 19685.803141968398, 20142.194312227348, 20245.291414800413, 861.8190180062131, 982.8673129127476, 993.4933184076173, 1008.445355054717, 1012.1599072769584, 10397.948010259901, 10737.946310268555, 10777.946110269555, 10808.189385796097, 10851.565937362575, 708254.0, 717349.0, 727475.0, 733154.0, 744962.0, 139775.0, 141033.0, 143380.5661943388, 146116.0, 146196.0, 1171.1680783132879, 1182.810750279951, 1206.8129439936824, 1208.5769980506927, 1210.1952201785714, 17556.0, 18041.00774457365, 18075.638487230444, 18117.36589219371, 18239.08804559793, 55107.72446137744, 58346.0, 59080.704596476746, 59607.403925960185, 60920.78158436718, 81511.5924420374, 83024.58487707765, 83971.3205735894, 84394.15605843863, 85724.0, 8631.093735157718, 8632.266257368194, 8928.313502044473, 9066.456012639263, 9197.21823644971, 10854.0, 13610.115342502706, 13778.517751879037, 14018.088824226392, 14209.431622735388, 11610.071194304266, 11659.650210493699, 11758.647240582795, 11852.58515951972, 11939.283642981449, 10680.305780124267, 11135.72160695989, 11240.662780116609, 11461.197716160123, 11549.480273387886, 11913.82129268079, 12018.0, 12272.75454490923, 12282.692932676755, 12293.0, 8735.781605459915, 8848.557572121239, 8857.501286991732, 8940.0, 9215.032421595635, 11815.76368472643, 12005.279683219034, 12068.336241506802, 12148.392580371123, 12207.886988817794], "value": 10350.66627713869, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1235.950561977521, 1427.8215223097106, 1432.2838580709645, 1435.5693292012393, 1451.5355086372358, 10286.948565257173, 10647.0, 10705.0, 10729.088027517651, 10867.29362591431, 10470.371777693324, 10677.8398324025, 10692.198085143606, 10697.037266646, 10720.35677859329, 2407.747186545418, 2458.139651122111, 2464.605663093905, 2466.0, 2471.270975062352, 2383.2553480858846, 2425.7816796488314, 2472.888720007595, 2480.8635525046116, 2481.6649752283483, 2012.9899350503301, 2422.672939153218, 2453.411181316489, 2459.6802415685934, 2465.1988103866224, 2370.810335173182, 2433.8783060846977, 2446.2905757330354, 2451.8161137914726, 2453.815963802722, 2363.7045369328835, 2438.926832195037, 2449.7182823975295, 2458.704955405363, 2463.1625247415964, 10857.294275872122, 10884.401357925311, 10900.291481053786, 10900.96440838119, 10902.836457453066, 5459.12653975366, 5554.250176226198, 6234.906476402857, 6474.384933431316, 6492.0, 3802.543694756619, 3929.7445666031617, 3939.207660514459, 3995.9400608990886, 4007.238624661334, 1000.5347513406228, 1188.821676748486, 1197.8502687164105, 1231.7536492701495, 1233.5065973610629, 13409.0, 13737.24445155516, 14972.775408368883, 15139.924300378427, 15213.695726085416, 5180.222966555047, 5218.165093585008, 5421.701806400607, 5444.8094316698725, 5450.781968721207, 8983.191512763882, 11163.72090697739, 11257.605983790529, 11307.49116289769, 11315.03822175108, 6193.040078787795, 6232.18981532407, 6295.811125666238, 6298.968505157445, 6302.054691796267, 4969.950300497021, 5613.877224555105, 5883.382244864313, 5941.079132734347, 5950.345461999176, 3787.2614840106467, 3909.569947305794, 4170.749755014648, 4272.209641216385, 4308.763018033976, 1290.4967062845499, 1292.3796577642784, 1292.6509842342514, 1310.3251825309974, 1311.1608570514688, 751.5040073551539, 760.8059944714173, 784.3215618489965, 786.7443080998698, 799.8000499875031, 5894.469497745224, 5907.409259074144, 5994.520438364921, 6055.6063855849225, 6067.75728970845, 574.5174053794832, 583.2650859916536, 859.3898332184094, 866.1208872993916, 920.1442658327819, 3210.5826242588314, 3508.4737289406285, 3968.1666849961252, 3993.562317565674, 4119.784663524268, 11803.822942655866, 12711.300878451773, 13379.464821407035, 13587.59237222885, 13630.386632601358, 13408.597742067754, 13530.797038044437, 13532.797008044887, 13536.120152190077, 13619.727605447835, 10139.64511242103, 10210.030047145508, 10275.201839678177, 10278.537465814057, 10338.172946164288, 5729.369769325288, 6006.129111278753, 6303.622497616727, 6473.546851720334, 6553.4101930826455, 11996.340201289013, 12160.878391215974, 12181.0, 12201.389930503618, 12303.384830758605, 12553.435095420911, 12567.0, 12622.43199056063, 12811.359432028548, 12862.292573908151, 8089.2315230053055, 8285.58572071382, 8364.498130112213, 8381.748547543584, 8384.287335576544, 8846.159614836837, 9584.856227156459, 9622.326437149612, 10049.648262311077, 10049.69850904474, 8382.413231073766, 8382.538960357238, 8444.986601607601, 8462.238398544161, 8465.449745766506, 8243.958780206061, 8247.752567422747, 8325.625346859528, 8347.207015333533, 8389.622466988878, 7542.208068152765, 7606.923930760621, 7700.460967732205, 7709.2290770922455, 7738.690452381842, 5762.078067509178, 5892.587518873809, 5931.110333449845, 5941.405859414024, 5995.160677505066, 5823.7379317931645, 5907.734151963258, 5949.018411961978, 6065.939340606537, 6142.293636231891], "value": 5059.509323294525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.3923231677791, 391.9451276821245, 396.5063495947544, 396.8075483390557, 399.70421887803036, 5421.728913554326, 6220.12918191453, 6252.781152659658, 6336.493080553556, 6365.936340636604, 6150.384961503847, 6221.937780622204, 6246.375362463751, 6309.369063093688, 6314.400131987465, 3840.289546433905, 4000.7599544027307, 4754.381930349058, 4779.091972525226, 4798.208295631217, 4043.9595604043875, 4056.776877271749, 4939.468764682942, 4953.257011448276, 4956.033573453178, 4760.33355330254, 4864.221724524077, 4896.314515967767, 4904.828331008415, 4905.779239934212, 4463.999999999984, 4870.6347023972985, 4878.975605121987, 4911.887888208069, 4925.187344088221, 4325.870223893288, 4333.133373325348, 4343.804528796211, 4349.238883195429, 4357.346398040288, 5136.820211292624, 6104.542159338067, 6146.139540464339, 6171.0, 6227.221597300338, 5223.806001479388, 5297.549708274802, 5422.674639521641, 5576.888462230773, 5626.437356264381, 6749.907528666129, 6754.459643228579, 6759.324067593201, 6777.796666100025, 6798.52410331277, 4001.419794129835, 4507.256302710049, 4527.4340707411575, 4792.305115758196, 4803.78382972767, 6722.731090756364, 6852.45180385568, 6954.200266969313, 7054.814711187273, 7177.605231712254, 4910.8281210157475, 4918.975405122951, 4928.482509336468, 4940.629452791055, 4954.950450495521, 4767.713937163712, 5026.170681837529, 5030.320906677625, 5056.0, 5058.571885494909, 5145.588352931832, 5232.128962751598, 5487.945120548743, 5614.361250212393, 5636.464535869086, 5605.570579502282, 6515.7067931943175, 6810.87620542605, 6846.315368463114, 6870.62211578358, 1451.608065822222, 1474.9336279867432, 1561.7657351397381, 1713.4945191168636, 1740.9216585253694, 988.9406635601887, 1006.3257617396362, 1179.622520793355, 1184.419634379148, 1185.1289302362757, 6335.493160547145, 6337.0, 6343.777967771106, 6349.269833969152, 6367.42693157618, 5788.479036886701, 6857.725690972405, 6990.09128813251, 6997.510174287751, 7023.438124949992, 5149.330587023663, 6099.542534309944, 6177.96911015442, 6319.778807741799, 6364.649944253018, 3013.517837146046, 3186.6176058873084, 3282.441984862531, 3647.1611529348397, 3673.94489082664, 5727.656340619576, 6113.0, 7000.684969176399, 7245.818854528574, 7329.230430804795, 5411.891762164734, 5727.3699893011735, 6546.509011824132, 7129.857402851914, 7259.89110163348, 6601.240857301472, 6916.827079322957, 7218.89171662415, 7229.710811567478, 7336.266373362621, 6994.5803251805055, 7018.894716579358, 7019.157701075904, 7030.402415794715, 7060.3292687194635, 5996.5502587307, 6242.282137554058, 6503.577267477599, 6802.3537763912345, 6987.336203060701, 6125.0, 7202.3877970373105, 7284.38082762971, 7310.305520975498, 7313.305236002571, 4500.459944806644, 4504.054148628825, 4519.841805536922, 4521.796519156712, 4522.841700540465, 6528.706208220547, 6803.6052609214, 6910.723571057101, 6961.8955715665315, 7028.543144695578, 5764.13537969308, 6012.759489620366, 6719.731210751515, 6780.694868731018, 6781.254062053269, 6372.840678983062, 6692.799216023527, 6714.328567143247, 6764.738214924566, 6766.451509762144, 5819.5635327351465, 6588.637624930675, 6590.143281373391, 6603.735850565923, 6657.533972622064, 5805.82582522525, 6370.426661600478, 6752.291009443938, 6754.662266886734, 6796.320367963164, 6506.3493650634555, 6601.471882249312, 6607.975763756625, 6631.6684165792485, 6633.9668301658185], "value": 4950.241634214112, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.9880434728348, 694.7985084325546, 735.7755884455239, 740.2227660955997, 740.4261697184683, 14985.625359366008, 15687.999999999985, 15720.685586288282, 16225.513234602951, 16226.0, 14922.179280139588, 15020.549383518484, 15067.321970511326, 15093.92453037736, 15115.62210944727, 896.6144557840142, 926.5969303353063, 928.3316012471043, 960.4381436859445, 963.388248462228, 970.403202030753, 975.3075316525276, 975.7316737897099, 975.8438649816031, 977.2475194100539, 895.476146454325, 982.243672372273, 1016.3139880580616, 1018.1701912940966, 1019.0726438940566, 1006.2754816532122, 1010.5755582655303, 1012.7923775626052, 1013.3970287678819, 1014.1582486536195, 740.1636151149221, 745.202633182495, 750.6659536506257, 750.9061367329084, 753.2580408297822, 15034.248287585577, 15078.5476435706, 15112.773308400383, 16196.514104576881, 16480.42318518846, 14606.34271457787, 14728.705425891421, 14767.926160369238, 14861.331240094221, 15027.924860375737, 9914.13658458843, 11709.121815863758, 11920.284782913053, 12013.279203247746, 12368.381580921008, 3000.6549246836676, 3036.104349216986, 3037.407705497429, 3054.9389012219854, 3174.2699179188685, 94984.0, 98103.0, 118773.0, 120003.0, 123104.0, 11237.438128093563, 11484.88515114838, 11803.88196118045, 11894.88105118955, 12029.0, 25126.246212613652, 25399.0, 25401.0, 25409.87295063513, 25447.36381590475, 7699.499532530368, 7842.333401660809, 7901.170377110436, 7979.521228726295, 8190.180981901762, 4121.155163191538, 4185.707000509996, 4192.287311157111, 4197.139586384784, 4211.642010429148, 3424.503447000171, 3492.6158122606494, 4171.749695018309, 4186.91626167475, 4217.304144816071, 793.9880901786478, 801.3749275565068, 803.8070862992956, 809.9462191730469, 821.035283541836, 457.69563240444734, 555.6876262926436, 572.6449601247253, 580.2949416458938, 584.6024703201864, 8692.222433023446, 9948.950255248677, 11062.944685276521, 11181.161412894066, 11230.494627741771, 561.1274468201904, 580.8605934575671, 584.2083976212172, 601.5758889982552, 602.3073465514606, 4178.132361966248, 4229.873103806891, 4541.6593755468475, 4553.453585569753, 4570.451545814457, 78997.21002790013, 96316.07367852604, 96590.0, 99890.00109998952, 100914.0, 55544.444555554735, 72338.0, 86054.84862878478, 89890.75273118225, 92038.0, 53407.0, 63907.0, 75964.62017689877, 77110.22889771254, 77655.84613615602, 10540.525676344736, 10872.836907446554, 10946.288491248044, 11070.169737269898, 11147.83278250843, 28922.843086276313, 38098.047548811504, 38210.80894595509, 38637.80681096577, 38835.0, 50871.49128508816, 58031.70984145052, 69785.30214697788, 71350.28649713645, 72541.0, 13529.59411217665, 13683.452661893412, 13944.58166255014, 14012.789808152682, 14461.638459038608, 14806.259687015821, 14917.776233356291, 15253.0, 15419.691606168039, 15571.454999074978, 13908.721825563229, 13979.720405591628, 14144.504942326968, 14438.566842994727, 14499.347529360994, 13596.93201533986, 13897.244914702225, 14077.366518506487, 14093.788593170906, 14782.408703652163, 13437.932810335886, 13583.52457663977, 14017.859821401655, 14042.719145617235, 14063.859361406256, 9725.756856078655, 9733.124018838338, 9803.804520853277, 9814.263930205121, 10045.447500387256, 7937.722179723913, 9370.765730856783, 10060.49697515136, 11095.116709151733, 11098.167637427387], "value": 7531.03220538582, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1700.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 123.0, 120.0, 123.0, 123.0, 124.0, 124.0, 141.0, 104.0, 183.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 116.0, 162.0, 161.0, 110.0, 105.0, 148.0, 167.0, 109.0], "value": 135.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [273.0, 222.0, 230.0, 292.0, 216.0, 199.0, 205.0, 201.0, 277.0, 302.0], "value": 241.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 62.0, 63.0, 115.0, 63.0, 67.0, 51.0, 64.0, 98.0, 94.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 72.0, 87.0, 75.0, 74.0, 72.0, 90.0, 108.0, 85.0, 87.0], "value": 84.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 116.0, 108.0, 93.0, 94.0, 94.0, 96.0, 82.0, 81.0, 135.0], "value": 101.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 157.0, 142.0, 100.0, 100.0, 105.0, 101.0, 100.0, 158.0, 154.0], "value": 122.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 118.0, 104.0, 161.0, 156.0, 100.0, 101.0, 104.0, 100.0, 158.0], "value": 125.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 87.0, 106.0, 111.0, 58.0, 71.0, 97.0, 108.0, 82.0, 59.0], "value": 83.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 67.0, 66.0, 99.0, 87.0, 86.0, 67.0, 61.0, 61.0, 63.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 98.0, 88.0, 89.0, 87.0, 99.0, 91.0, 79.0, 105.0, 77.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 188.0, 148.0, 170.0, 166.0, 167.0, 151.0, 183.0, 214.0, 165.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 197.0, 194.0, 196.0, 201.0, 197.0, 198.0, 195.0, 195.0], "value": 197.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 72.0, 72.0, 72.0, 73.0, 73.0, 72.0, 71.0, 72.0], "value": 71.8, "unit": "score"}]}]} 11:52:30 INFO - Return code: 0 11:52:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:52:30 ERROR - # TBPL SUCCESS # 11:52:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:52:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:52:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:52:30 INFO - 2017-03-28 11:52:30.380 screenresolution[1658:10891] starting screenresolution argv=screenresolution get 11:52:30 INFO - 2017-03-28 11:52:30.383 screenresolution[1658:10891] Display 0: 1600x1200x32@60 11:52:30 INFO - 2017-03-28 11:52:30.389 screenresolution[1659:10894] starting screenresolution argv=screenresolution list 11:52:30 INFO - Available Modes on Display 0 11:52:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:52:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:52:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:52:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:52:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:52:30 INFO - Intel Iris: 11:52:30 INFO - Chipset Model: Intel Iris 11:52:30 INFO - Type: GPU 11:52:30 INFO - Bus: Built-In 11:52:30 INFO - VRAM (Dynamic, Max): 1536 MB 11:52:30 INFO - Vendor: Intel (0x8086) 11:52:30 INFO - Device ID: 0x0a2e 11:52:30 INFO - Revision ID: 0x0009 11:52:30 INFO - Displays: 11:52:30 INFO - Display: 11:52:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:52:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:52:30 INFO - Display Serial Number: 1600x1200 60 11:52:30 INFO - Main Display: Yes 11:52:30 INFO - Mirror: Off 11:52:30 INFO - Online: Yes 11:52:30 INFO - Rotation: Supported 11:52:30 INFO - Return code: 0 11:52:30 INFO - Running post-action listener: _resource_record_post_action 11:52:30 INFO - [mozharness: 2017-03-28 18:52:30.624615Z] Finished run-tests step (success) 11:52:30 INFO - Running post-run listener: _resource_record_post_run 11:52:30 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 39737344; Write bytes: 518108160; Read time: 254; Write time: 1624 11:52:30 INFO - TinderboxPrint: CPU usage
54.3% 11:52:30 INFO - TinderboxPrint: I/O read bytes / time
39,737,344 / 254 11:52:30 INFO - TinderboxPrint: I/O write bytes / time
518,108,160 / 1,624 11:52:30 INFO - TinderboxPrint: CPU idle
2,203.6 (45.7%) 11:52:30 INFO - TinderboxPrint: CPU system
972.4 (20.2%) 11:52:30 INFO - TinderboxPrint: CPU user
1,641.7 (34.1%) 11:52:30 INFO - TinderboxPrint: Swap in / out
251,121,664 / 0 11:52:30 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203923968; Write bytes: 204150784; Read time: 15725; Write time: 436 11:52:30 INFO - run-tests - Wall time: 1184s; CPU: 54.0%; Read bytes: 38774784; Write bytes: 303987712; Read time: 241; Write time: 1159 11:52:31 INFO - Running post-run listener: _upload_blobber_files 11:52:31 INFO - Blob upload gear active. 11:52:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:52:31 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 11:52:31 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'] 11:52:31 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 11:52:31 INFO - (blobuploader) - INFO - Open directory for files ... 11:52:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:52:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:52:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:32 INFO - (blobuploader) - INFO - Done attempting. 11:52:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:52:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:52:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:33 INFO - (blobuploader) - INFO - Done attempting. 11:52:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:52:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:52:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:34 INFO - (blobuploader) - INFO - Done attempting. 11:52:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:52:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:52:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:36 INFO - (blobuploader) - INFO - Done attempting. 11:52:36 INFO - (blobuploader) - INFO - Iteration through files over. 11:52:36 INFO - Return code: 0 11:52:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:52:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:52:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d33dfe4df4c585a7e2914f45d872446c01d19486d245acc27a086c8938df97224fd9c9d7d7618b2f79b6af4a2de6c7fafb91a6c0ea68f2c024be76341eb961c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b201cdd1dcbaa6e34ef9effbe35328211143d77510dd754eb76fb88b62c87ad71edaef0b5b0dcb55973982378ea6f6ced191e865aec405c207c95e1192af24f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d90a0aec6f6c65ef37edcdb8cba8d21b5df415d5ea71a8df4a623c9a083847ddee938b744875810e2e8d8e2fa9bff9e0a7a869d8f81bf31f4bbd3c83208bd5ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcc42c7671bdd729de8436375df9a0acb309b23c3c2c6bd45c7e49a18548dc4cf6fdd7e0fa520ebf82bac0f5f5b726b730e1787ee210cc08ab380642f384f16f"} 11:52:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:52:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:52:36 INFO - Contents: 11:52:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d33dfe4df4c585a7e2914f45d872446c01d19486d245acc27a086c8938df97224fd9c9d7d7618b2f79b6af4a2de6c7fafb91a6c0ea68f2c024be76341eb961c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b201cdd1dcbaa6e34ef9effbe35328211143d77510dd754eb76fb88b62c87ad71edaef0b5b0dcb55973982378ea6f6ced191e865aec405c207c95e1192af24f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d90a0aec6f6c65ef37edcdb8cba8d21b5df415d5ea71a8df4a623c9a083847ddee938b744875810e2e8d8e2fa9bff9e0a7a869d8f81bf31f4bbd3c83208bd5ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcc42c7671bdd729de8436375df9a0acb309b23c3c2c6bd45c7e49a18548dc4cf6fdd7e0fa520ebf82bac0f5f5b726b730e1787ee210cc08ab380642f384f16f"} 11:52:36 INFO - Running post-run listener: copy_logs_to_upload_dir 11:52:36 INFO - Copying logs to upload dir... 11:52:36 INFO - mkdir: /builds/slave/test/build/upload/logs 11:52:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.860441 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-28 11:52:36.687565) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 11:52:36.693798) ========= 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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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/d33dfe4df4c585a7e2914f45d872446c01d19486d245acc27a086c8938df97224fd9c9d7d7618b2f79b6af4a2de6c7fafb91a6c0ea68f2c024be76341eb961c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b201cdd1dcbaa6e34ef9effbe35328211143d77510dd754eb76fb88b62c87ad71edaef0b5b0dcb55973982378ea6f6ced191e865aec405c207c95e1192af24f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d90a0aec6f6c65ef37edcdb8cba8d21b5df415d5ea71a8df4a623c9a083847ddee938b744875810e2e8d8e2fa9bff9e0a7a869d8f81bf31f4bbd3c83208bd5ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcc42c7671bdd729de8436375df9a0acb309b23c3c2c6bd45c7e49a18548dc4cf6fdd7e0fa520ebf82bac0f5f5b726b730e1787ee210cc08ab380642f384f16f"} build_url:https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/UV5QR1QETgaECTXnhHVONQ/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/d33dfe4df4c585a7e2914f45d872446c01d19486d245acc27a086c8938df97224fd9c9d7d7618b2f79b6af4a2de6c7fafb91a6c0ea68f2c024be76341eb961c1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b201cdd1dcbaa6e34ef9effbe35328211143d77510dd754eb76fb88b62c87ad71edaef0b5b0dcb55973982378ea6f6ced191e865aec405c207c95e1192af24f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d90a0aec6f6c65ef37edcdb8cba8d21b5df415d5ea71a8df4a623c9a083847ddee938b744875810e2e8d8e2fa9bff9e0a7a869d8f81bf31f4bbd3c83208bd5ce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dcc42c7671bdd729de8436375df9a0acb309b23c3c2c6bd45c7e49a18548dc4cf6fdd7e0fa520ebf82bac0f5f5b726b730e1787ee210cc08ab380642f384f16f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-28 11:52:36.735272) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:52:36.735896) ========= 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.lqqy6TTwIC/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.Hnq8RPdsLl/Listeners TMPDIR=/var/folders/sk/y86qdwzs1q5_clfw5d0041sc00000w/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.004566 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-28 11:52:36.785080) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 11:52:36.785401) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-28 11:52:36.785738) ========= ========= Total master_lag: 0.29 =========