builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0101 starttime: 1493425033.63 results: success (0) buildid: 20170428170344 builduid: 9430a7a21c384613aff10a6d5cf5991c revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:13.629880) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:13.630384) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:13.630708) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:13.658322) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:13.658698) ========= 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.WVhjnI8C42/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.Te7sL8tLe0/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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-04-28 17:17:13-- 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% 9.37M=0.001s 2017-04-28 17:17:13 (9.37 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134407 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:13.818645) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:13.819019) ========= 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.WVhjnI8C42/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.Te7sL8tLe0/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.028364 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:13.869885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:17:13.870316) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b3b32894d9326d147238d9f071e1814ab3fdc850 --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 b3b32894d9326d147238d9f071e1814ab3fdc850 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WVhjnI8C42/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.Te7sL8tLe0/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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-04-28 17:17:13,981 truncating revision to first 12 chars 2017-04-28 17:17:13,981 Setting DEBUG logging. 2017-04-28 17:17:13,981 attempt 1/10 2017-04-28 17:17:13,981 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b3b32894d932?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-28 17:17:15,241 unpacking tar archive at: mozilla-central-b3b32894d932/testing/mozharness/ program finished with exit code 0 elapsedTime=1.547022 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-28 17:17:15.446002) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:15.446817) ========= script_repo_revision: b3b32894d9326d147238d9f071e1814ab3fdc850 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:15.447798) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:15.448493) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-28 17:17:15.470948) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-28 17:17:15.471838) ========= /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.WVhjnI8C42/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.Te7sL8tLe0/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:17:15 INFO - MultiFileLogger online at 20170428 17:17:15 in /builds/slave/test 17:17:15 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 17:17:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:17:15 INFO - {'append_to_log': False, 17:17:15 INFO - 'base_work_dir': '/builds/slave/test', 17:17:15 INFO - 'blob_upload_branch': 'Firefox', 17:17:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:17:15 INFO - 'branch': 'Firefox', 17:17:15 INFO - 'buildbot_json_path': 'buildprops.json', 17:17:15 INFO - 'config_files': ('talos/mac_config.py',), 17:17:15 INFO - 'default_actions': ('clobber', 17:17:15 INFO - 'read-buildbot-config', 17:17:15 INFO - 'download-and-extract', 17:17:15 INFO - 'populate-webroot', 17:17:15 INFO - 'create-virtualenv', 17:17:15 INFO - 'install', 17:17:15 INFO - 'run-tests'), 17:17:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:17:15 INFO - 'download_minidump_stackwalk': True, 17:17:15 INFO - 'download_symbols': 'ondemand', 17:17:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:17:15 INFO - 'tooltool.py': '/tools/tooltool.py', 17:17:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:17:15 INFO - '/tools/misc-python/virtualenv.py')}, 17:17:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:17:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:17:15 INFO - 'gecko_profile': False, 17:17:15 INFO - 'gecko_profile_interval': 0, 17:17:15 INFO - 'installer_path': 'installer.exe', 17:17:15 INFO - 'log_level': 'info', 17:17:15 INFO - 'log_name': 'talos', 17:17:15 INFO - 'log_to_console': True, 17:17:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:17:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:17:15 INFO - 'opt_config_files': (), 17:17:15 INFO - 'pip_index': False, 17:17:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:17:15 INFO - 'cmd': ('bash', 17:17:15 INFO - '-c', 17:17:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:17:15 INFO - 'enabled': True, 17:17:15 INFO - 'halt_on_failure': False, 17:17:15 INFO - 'name': 'check_screen_resolution'},), 17:17:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:17:15 INFO - 'cmd': ('bash', 17:17:15 INFO - '-c', 17:17:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:17:15 INFO - 'enabled': True, 17:17:15 INFO - 'halt_on_failure': False, 17:17:15 INFO - 'name': 'check_screen_resolution'},), 17:17:15 INFO - 'run_cmd_checks_enabled': True, 17:17:15 INFO - 'suite': 'dromaeojs-e10s', 17:17:15 INFO - 'system_bits': '32', 17:17:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:17:15 INFO - 'title': 't-yosemite-r7-0101', 17:17:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:17:15 INFO - 'use_talos_json': True, 17:17:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:17:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:17:15 INFO - 'work_dir': 'build'} 17:17:15 INFO - [mozharness: 2017-04-29 00:17:15.690882Z] Running clobber step. 17:17:15 INFO - Running pre-action listener: _resource_record_pre_action 17:17:15 INFO - Running main action method: clobber 17:17:15 INFO - rmtree: /builds/slave/test/build 17:17:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:17:18 INFO - Running post-action listener: _resource_record_post_action 17:17:18 INFO - [mozharness: 2017-04-29 00:17:18.455349Z] Finished clobber step (success) 17:17:18 INFO - [mozharness: 2017-04-29 00:17:18.455516Z] Running read-buildbot-config step. 17:17:18 INFO - Running pre-action listener: _resource_record_pre_action 17:17:18 INFO - Running main action method: read_buildbot_config 17:17:18 INFO - Using buildbot properties: 17:17:18 INFO - { 17:17:18 INFO - "project": "", 17:17:18 INFO - "product": "firefox", 17:17:18 INFO - "script_repo_revision": "production", 17:17:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:17:18 INFO - "repository": "", 17:17:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:17:18 INFO - "buildid": "20170428170344", 17:17:18 INFO - "stage_platform": "macosx64", 17:17:18 INFO - "basedir": "/builds/slave/test", 17:17:18 INFO - "buildnumber": 51, 17:17:18 INFO - "platform": "yosemite_r7", 17:17:18 INFO - "mozci_request": { 17:17:18 INFO - "priority": 0, 17:17:18 INFO - "type": "trigger_all_talos_jobs", 17:17:18 INFO - "times": 6 17:17:18 INFO - }, 17:17:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:17:18 INFO - "slavebuilddir": "test", 17:17:18 INFO - "branch": "mozilla-central", 17:17:18 INFO - "revision": "b3b32894d9326d147238d9f071e1814ab3fdc850", 17:17:18 INFO - "slavename": "t-yosemite-r7-0101", 17:17:18 INFO - "builduid": "9430a7a21c384613aff10a6d5cf5991c", 17:17:18 INFO - "repo_path": "mozilla-central" 17:17:18 INFO - } 17:17:18 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg. 17:17:18 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json. 17:17:18 INFO - Running post-action listener: _resource_record_post_action 17:17:18 INFO - [mozharness: 2017-04-29 00:17:18.459188Z] Finished read-buildbot-config step (success) 17:17:18 INFO - [mozharness: 2017-04-29 00:17:18.459297Z] Running download-and-extract step. 17:17:18 INFO - Running pre-action listener: _resource_record_pre_action 17:17:18 INFO - Running main action method: download_and_extract 17:17:18 INFO - mkdir: /builds/slave/test/build/tests 17:17:18 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')]} 17:17:18 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json 17:17:18 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:17:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/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 17:17:18 INFO - Downloaded 1325 bytes. 17:17:18 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:17:18 INFO - Using the following test package requirements: 17:17:18 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:18 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:17:18 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:17:18 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:18 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:17:18 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:18 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:17:18 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:18 INFO - u'jsshell-mac.zip'], 17:17:18 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:18 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:17:18 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:17:18 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:18 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:17:18 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:18 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:17:18 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:18 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:17:18 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:18 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:17:18 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:17:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip 17:17:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:17:18 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:17:19 INFO - Content-Length response header: 52698163 17:17:19 INFO - Bytes received: 52698163 17:17:36 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 17:17:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip 17:17:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:17:36 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:17:37 INFO - Content-Length response header: 52698163 17:17:37 INFO - Bytes received: 52698163 17:17:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip 17:17:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:17:52 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:17:53 INFO - Content-Length response header: 14408809 17:17:53 INFO - Bytes received: 14408809 17:17:53 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')]} 17:17:53 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:17:53 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:17:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:17:55 INFO - Downloaded 71219323 bytes. 17:17:55 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:17:55 INFO - mkdir: /builds/slave/test/properties 17:17:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:17:55 INFO - Writing to file /builds/slave/test/properties/build_url 17:17:55 INFO - Contents: 17:17:55 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg 17:17:55 INFO - Running post-action listener: _resource_record_post_action 17:17:55 INFO - Running post-action listener: set_extra_try_arguments 17:17:55 INFO - [mozharness: 2017-04-29 00:17:55.378642Z] Finished download-and-extract step (success) 17:17:55 INFO - [mozharness: 2017-04-29 00:17:55.378797Z] Running populate-webroot step. 17:17:55 INFO - Running pre-action listener: _resource_record_pre_action 17:17:55 INFO - Running main action method: populate_webroot 17:17:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:17:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:17:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:17:55 INFO - '--tppagecycles', 17:17:55 INFO - '7'], 17:17:55 INFO - 'tests': ['tsvgm']}}, 17:17:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:17:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:17:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:17:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:17:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:17:55 INFO - 'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:17:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:17:55 INFO - 'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['damp', 'tps']}, 17:17:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:17:55 INFO - 'tests': ['damp', 'tps']}, 17:17:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['dromaeo_dom']}, 17:17:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:17:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:17:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:17:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['a11yr', 17:17:55 INFO - 'ts_paint', 17:17:55 INFO - 'tpaint', 17:17:55 INFO - 'sessionrestore', 17:17:55 INFO - 'sessionrestore_no_auto_restore', 17:17:55 INFO - 'tabpaint']}, 17:17:55 INFO - 'other-e10s': {'tests': ['a11yr', 17:17:55 INFO - 'ts_paint', 17:17:55 INFO - 'tpaint', 17:17:55 INFO - 'sessionrestore', 17:17:55 INFO - 'sessionrestore_no_auto_restore', 17:17:55 INFO - 'tabpaint']}, 17:17:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:17:55 INFO - 'ts_paint', 17:17:55 INFO - 'tpaint', 17:17:55 INFO - 'sessionrestore', 17:17:55 INFO - 'sessionrestore_no_auto_restore', 17:17:55 INFO - 'tabpaint']}, 17:17:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:17:55 INFO - 'ts_paint', 17:17:55 INFO - 'tpaint', 17:17:55 INFO - 'sessionrestore', 17:17:55 INFO - 'sessionrestore_no_auto_restore', 17:17:55 INFO - 'tabpaint']}, 17:17:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['a11yr', 17:17:55 INFO - 'ts_paint', 17:17:55 INFO - 'tpaint', 17:17:55 INFO - 'sessionrestore', 17:17:55 INFO - 'sessionrestore_no_auto_restore', 17:17:55 INFO - 'tabpaint']}, 17:17:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['a11yr', 17:17:55 INFO - 'ts_paint', 17:17:55 INFO - 'tpaint', 17:17:55 INFO - 'sessionrestore', 17:17:55 INFO - 'sessionrestore_no_auto_restore', 17:17:55 INFO - 'tabpaint']}, 17:17:55 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:17:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:17:55 INFO - 'bloom_basic_ref']}, 17:17:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['tsvgx', 17:17:55 INFO - 'tsvgr_opacity', 17:17:55 INFO - 'tart', 17:17:55 INFO - 'tscrollx', 17:17:55 INFO - 'cart', 17:17:55 INFO - 'tsvg_static']}, 17:17:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:17:55 INFO - 'tsvgr_opacity', 17:17:55 INFO - 'tart', 17:17:55 INFO - 'tscrollx', 17:17:55 INFO - 'cart', 17:17:55 INFO - 'tsvg_static']}, 17:17:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:17:55 INFO - 'talos_options': ['--disable-e10s'], 17:17:55 INFO - 'tests': ['tp5o']}, 17:17:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:17:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:17:55 INFO - 'talos_options': ['--disable-e10s', 17:17:55 INFO - '--xperf_path', 17:17:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:55 INFO - 'tests': ['tp5n']}, 17:17:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:17:55 INFO - 'talos_options': ['--xperf_path', 17:17:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:55 INFO - 'tests': ['tp5n']}}, 17:17:55 INFO - 'talos.zip': {'path': '', 17:17:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:17:55 INFO - Running post-action listener: _resource_record_post_action 17:17:55 INFO - [mozharness: 2017-04-29 00:17:55.397616Z] Finished populate-webroot step (success) 17:17:55 INFO - [mozharness: 2017-04-29 00:17:55.397722Z] Running create-virtualenv step. 17:17:55 INFO - Running pre-action listener: _resource_record_pre_action 17:17:55 INFO - Running main action method: create_virtualenv 17:17:55 INFO - Creating virtualenv /builds/slave/test/build/venv 17:17:55 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 17:17:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:17:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:17:55 INFO - Using real prefix '/tools/python27' 17:17:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:17:56 INFO - Installing distribute.............................................................................................................................................................................................done. 17:17:59 INFO - Installing pip.................done. 17:17:59 INFO - Return code: 0 17:17:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:17:59 INFO - Reading from file tmpfile_stdout 17:17:59 INFO - Output received: 17:17:59 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) 17:17:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:17:59 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')]} 17:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:59 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')]} 17:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:59 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 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:17:59 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 17:17:59 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 17:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:59 INFO - 'HOME': '/Users/cltbld', 17:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:59 INFO - 'LOGNAME': 'cltbld', 17:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:59 INFO - 'MOZ_NO_REMOTE': '1', 17:17:59 INFO - 'NO_EM_RESTART': '1', 17:17:59 INFO - 'PAGER': '/bin/cat', 17:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:59 INFO - 'PWD': '/builds/slave/test', 17:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:59 INFO - 'SHELL': '/bin/bash', 17:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:17:59 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:59 INFO - 'USER': 'cltbld', 17:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:59 INFO - 'XPC_FLAGS': '0x0', 17:17:59 INFO - 'XPC_SERVICE_NAME': '0', 17:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:59 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 17:17:59 INFO - Cleaning up... 17:17:59 INFO - Return code: 0 17:17:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:17:59 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')]} 17:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:59 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')]} 17:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:17:59 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 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:17:59 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 17:17:59 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 17:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:59 INFO - 'HOME': '/Users/cltbld', 17:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:59 INFO - 'LOGNAME': 'cltbld', 17:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:59 INFO - 'MOZ_NO_REMOTE': '1', 17:17:59 INFO - 'NO_EM_RESTART': '1', 17:17:59 INFO - 'PAGER': '/bin/cat', 17:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:59 INFO - 'PWD': '/builds/slave/test', 17:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:59 INFO - 'SHELL': '/bin/bash', 17:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:17:59 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:59 INFO - 'USER': 'cltbld', 17:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:59 INFO - 'XPC_FLAGS': '0x0', 17:17:59 INFO - 'XPC_SERVICE_NAME': '0', 17:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:59 INFO - Downloading/unpacking psutil>=3.1.1 17:17:59 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 17:17:59 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 17:17:59 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 17:17:59 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 17:17:59 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 17:17:59 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 17:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:18:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:18:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:18:03 INFO - Installing collected packages: psutil 17:18:03 INFO - Running setup.py install for psutil 17:18:03 INFO - building 'psutil._psutil_osx' extension 17:18:03 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 17:18:04 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 17:18:04 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 17:18:04 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 17:18:04 INFO - building 'psutil._psutil_posix' extension 17:18:04 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 17:18:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:18:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:18:04 INFO - ^ 17:18:04 INFO - 1 warning generated. 17:18:04 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 17:18:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:18:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:18:04 INFO - Successfully installed psutil 17:18:04 INFO - Cleaning up... 17:18:04 INFO - Return code: 0 17:18:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:18:04 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')]} 17:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:04 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')]} 17:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:04 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 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:18:04 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 17:18:04 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 17:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:04 INFO - 'HOME': '/Users/cltbld', 17:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:04 INFO - 'LOGNAME': 'cltbld', 17:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:04 INFO - 'MOZ_NO_REMOTE': '1', 17:18:04 INFO - 'NO_EM_RESTART': '1', 17:18:04 INFO - 'PAGER': '/bin/cat', 17:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:04 INFO - 'PWD': '/builds/slave/test', 17:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:04 INFO - 'SHELL': '/bin/bash', 17:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:18:04 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:04 INFO - 'USER': 'cltbld', 17:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:04 INFO - 'XPC_FLAGS': '0x0', 17:18:04 INFO - 'XPC_SERVICE_NAME': '0', 17:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:18:04 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 17:18:04 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 17:18:04 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 17:18:04 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 17:18:04 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 17:18:04 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 17:18:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:18:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:18:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:18:07 INFO - Installing collected packages: mozsystemmonitor 17:18:07 INFO - Running setup.py install for mozsystemmonitor 17:18:07 INFO - Successfully installed mozsystemmonitor 17:18:07 INFO - Cleaning up... 17:18:08 INFO - Return code: 0 17:18:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:18:08 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')]} 17:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:08 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')]} 17:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:08 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 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:18:08 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 17:18:08 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 17:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:08 INFO - 'HOME': '/Users/cltbld', 17:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:08 INFO - 'LOGNAME': 'cltbld', 17:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:08 INFO - 'MOZ_NO_REMOTE': '1', 17:18:08 INFO - 'NO_EM_RESTART': '1', 17:18:08 INFO - 'PAGER': '/bin/cat', 17:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:08 INFO - 'PWD': '/builds/slave/test', 17:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:08 INFO - 'SHELL': '/bin/bash', 17:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:18:08 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:08 INFO - 'USER': 'cltbld', 17:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:08 INFO - 'XPC_FLAGS': '0x0', 17:18:08 INFO - 'XPC_SERVICE_NAME': '0', 17:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:08 INFO - Downloading/unpacking jsonschema==2.5.1 17:18:08 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 17:18:08 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 17:18:08 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 17:18:08 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 17:18:08 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 17:18:08 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 17:18:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:18:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:11 INFO - Downloading functools32-3.2.3-2.tar.gz 17:18:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:18:11 INFO - warning: no files found matching '*.txt' 17:18:11 INFO - no previously-included directories found matching 'build' 17:18:11 INFO - no previously-included directories found matching 'dist' 17:18:11 INFO - no previously-included directories found matching '.git*' 17:18:11 INFO - Installing collected packages: jsonschema, functools32 17:18:11 INFO - Running setup.py install for functools32 17:18:11 INFO - warning: no files found matching '*.txt' 17:18:11 INFO - no previously-included directories found matching 'build' 17:18:11 INFO - no previously-included directories found matching 'dist' 17:18:11 INFO - no previously-included directories found matching '.git*' 17:18:11 INFO - Successfully installed jsonschema functools32 17:18:11 INFO - Cleaning up... 17:18:11 INFO - Return code: 0 17:18:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:18: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')]} 17:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:12 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')]} 17:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:12 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 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:18:12 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 17:18:12 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 17:18:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:18:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:12 INFO - 'HOME': '/Users/cltbld', 17:18:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:12 INFO - 'LOGNAME': 'cltbld', 17:18:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:12 INFO - 'MOZ_NO_REMOTE': '1', 17:18:12 INFO - 'NO_EM_RESTART': '1', 17:18:12 INFO - 'PAGER': '/bin/cat', 17:18:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:12 INFO - 'PWD': '/builds/slave/test', 17:18:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:12 INFO - 'SHELL': '/bin/bash', 17:18:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:18:12 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:18:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:12 INFO - 'USER': 'cltbld', 17:18:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:12 INFO - 'XPC_FLAGS': '0x0', 17:18:12 INFO - 'XPC_SERVICE_NAME': '0', 17:18:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:18:12 INFO - Cleaning up... 17:18:12 INFO - Return code: 0 17:18:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:18:12 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')]} 17:18:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:12 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')]} 17:18:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:13 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 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:18:13 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 17:18:13 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 17:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:13 INFO - 'HOME': '/Users/cltbld', 17:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:13 INFO - 'LOGNAME': 'cltbld', 17:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:13 INFO - 'MOZ_NO_REMOTE': '1', 17:18:13 INFO - 'NO_EM_RESTART': '1', 17:18:13 INFO - 'PAGER': '/bin/cat', 17:18:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:13 INFO - 'PWD': '/builds/slave/test', 17:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:13 INFO - 'SHELL': '/bin/bash', 17:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:18:13 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:13 INFO - 'USER': 'cltbld', 17:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:13 INFO - 'XPC_FLAGS': '0x0', 17:18:13 INFO - 'XPC_SERVICE_NAME': '0', 17:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:13 INFO - Downloading/unpacking blobuploader==1.2.4 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18: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 17:18:16 INFO - Downloading blobuploader-1.2.4.tar.gz 17:18:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:18:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:16 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 17:18:16 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 17:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:18:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:18:17 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 17:18:17 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 17:18:17 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 17:18:17 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 17:18:17 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 17:18:17 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 17:18:17 INFO - Downloading docopt-0.6.1.tar.gz 17:18:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:18:17 INFO - Installing collected packages: blobuploader, requests, docopt 17:18:17 INFO - Running setup.py install for blobuploader 17:18:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:18:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:18:17 INFO - Running setup.py install for requests 17:18:18 INFO - Running setup.py install for docopt 17:18:18 INFO - Successfully installed blobuploader requests docopt 17:18:18 INFO - Cleaning up... 17:18:18 INFO - Return code: 0 17:18:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:18 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')]} 17:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:18 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')]} 17:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:18 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 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:18:18 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 17:18:18 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 17:18:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:18:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:18 INFO - 'HOME': '/Users/cltbld', 17:18:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:18 INFO - 'LOGNAME': 'cltbld', 17:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:18 INFO - 'MOZ_NO_REMOTE': '1', 17:18:18 INFO - 'NO_EM_RESTART': '1', 17:18:18 INFO - 'PAGER': '/bin/cat', 17:18:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:18 INFO - 'PWD': '/builds/slave/test', 17:18:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:18 INFO - 'SHELL': '/bin/bash', 17:18:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:18:18 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:18:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:18 INFO - 'USER': 'cltbld', 17:18:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:18 INFO - 'XPC_FLAGS': '0x0', 17:18:18 INFO - 'XPC_SERVICE_NAME': '0', 17:18:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:18:18 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-QZ9yj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:18:18 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-xOrjsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:18:19 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-VSR0_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:18:19 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-QEK5aT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:18:19 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-1hfinF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:18:19 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-hVyfq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:18:19 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-RAASAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:18:19 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-1SF2aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:18:19 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-1oHHI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:18:19 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-2GUTzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:18:19 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-by8rlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:18:20 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-094Q7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:18:20 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-30VYcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:18:20 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-LDENZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:20 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-VnVPXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:18:20 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-DLP7Ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:18:20 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-EFdA3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:18:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:18:20 INFO - Running setup.py install for manifestparser 17:18:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:18:20 INFO - Running setup.py install for mozcrash 17:18:20 INFO - Running setup.py install for mozdebug 17:18:21 INFO - Running setup.py install for mozdevice 17:18:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:18:21 INFO - Running setup.py install for mozfile 17:18:21 INFO - Running setup.py install for mozhttpd 17:18:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:18:21 INFO - Running setup.py install for mozinfo 17:18:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:18:21 INFO - Running setup.py install for mozInstall 17:18:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:18:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:18:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:18:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:18:21 INFO - Running setup.py install for mozleak 17:18:21 INFO - Running setup.py install for mozlog 17:18:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:18:22 INFO - Running setup.py install for moznetwork 17:18:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:18:22 INFO - Running setup.py install for mozprocess 17:18:22 INFO - Running setup.py install for mozprofile 17:18:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:18:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:18:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:18:22 INFO - Running setup.py install for mozrunner 17:18:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:18:22 INFO - Running setup.py install for mozscreenshot 17:18:22 INFO - Running setup.py install for moztest 17:18:23 INFO - Running setup.py install for mozversion 17:18:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:18:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:18:23 INFO - Cleaning up... 17:18:23 INFO - Return code: 0 17:18:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:23 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')]} 17:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:23 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')]} 17:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:23 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 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:18:23 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 17:18:23 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 17:18:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:18:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:23 INFO - 'HOME': '/Users/cltbld', 17:18:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:23 INFO - 'LOGNAME': 'cltbld', 17:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:23 INFO - 'MOZ_NO_REMOTE': '1', 17:18:23 INFO - 'NO_EM_RESTART': '1', 17:18:23 INFO - 'PAGER': '/bin/cat', 17:18:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:23 INFO - 'PWD': '/builds/slave/test', 17:18:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:23 INFO - 'SHELL': '/bin/bash', 17:18:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:18:23 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:18:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:23 INFO - 'USER': 'cltbld', 17:18:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:23 INFO - 'XPC_FLAGS': '0x0', 17:18:23 INFO - 'XPC_SERVICE_NAME': '0', 17:18:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:18:23 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-3E5DM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:18:23 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)) 17:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:18:23 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-GtzJw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:18:23 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)) 17:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:18:23 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-_49_Yq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:18:23 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)) 17:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:18:23 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-lk2e6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:18:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:18:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:18:23 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-buP55g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:18:24 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)) 17:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:18:24 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-aqdrcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:18:24 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)) 17:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:18:24 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-Fegcux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:18:24 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)) 17:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:18:24 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-kapctK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:18:24 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)) 17:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:18:24 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-u2pz87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:18:24 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)) 17:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:18:24 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-xRxSk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:18:24 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)) 17:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:18:24 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-wCyuTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:18:24 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)) 17:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:18:24 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-EGycOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:18:24 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)) 17:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:18:24 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-CFTR02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:18:24 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)) 17:18:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:18:24 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-UM0n0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:18:25 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)) 17:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:25 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-ror21D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:18:25 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)) 17:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:18:25 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-KNOwGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:18:25 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)) 17:18:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:18:25 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-XTnNHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:18:25 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)) 17:18:25 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)) 17:18:25 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)) 17:18:25 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)) 17:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:18:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:18:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:25 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 17:18:25 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 17:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:27 INFO - Downloading blessings-1.6.tar.gz 17:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:18:27 INFO - Installing collected packages: blessings 17:18:27 INFO - Running setup.py install for blessings 17:18:28 INFO - Successfully installed blessings 17:18:28 INFO - Cleaning up... 17:18:28 INFO - Return code: 0 17:18:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:18:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:18:28 INFO - Reading from file tmpfile_stdout 17:18:28 INFO - Current package versions: 17:18:28 INFO - blessings == 1.6 17:18:28 INFO - blobuploader == 1.2.4 17:18:28 INFO - docopt == 0.6.1 17:18:28 INFO - functools32 == 3.2.3-2 17:18:28 INFO - jsonschema == 2.5.1 17:18:28 INFO - manifestparser == 1.1 17:18:28 INFO - mozInstall == 1.12 17:18:28 INFO - mozcrash == 1.0 17:18:28 INFO - mozdebug == 0.1 17:18:28 INFO - mozdevice == 0.50 17:18:28 INFO - mozfile == 1.2 17:18:28 INFO - mozhttpd == 0.7 17:18:28 INFO - mozinfo == 0.9 17:18:28 INFO - mozleak == 0.1 17:18:28 INFO - mozlog == 3.4 17:18:28 INFO - moznetwork == 0.27 17:18:28 INFO - mozprocess == 0.25 17:18:28 INFO - mozprofile == 0.28 17:18:28 INFO - mozrunner == 6.13 17:18:28 INFO - mozscreenshot == 0.1 17:18:28 INFO - mozsystemmonitor == 0.3 17:18:28 INFO - moztest == 0.8 17:18:28 INFO - mozversion == 1.4 17:18:28 INFO - psutil == 3.1.1 17:18:28 INFO - requests == 1.2.3 17:18:28 INFO - wsgiref == 0.1.2 17:18:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:28 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 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:18:28 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 17:18:28 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 17:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:28 INFO - 'HOME': '/Users/cltbld', 17:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:28 INFO - 'LOGNAME': 'cltbld', 17:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:28 INFO - 'MOZ_NO_REMOTE': '1', 17:18:28 INFO - 'NO_EM_RESTART': '1', 17:18:28 INFO - 'PAGER': '/bin/cat', 17:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:28 INFO - 'PWD': '/builds/slave/test', 17:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:28 INFO - 'SHELL': '/bin/bash', 17:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:18:28 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:28 INFO - 'USER': 'cltbld', 17:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:28 INFO - 'XPC_FLAGS': '0x0', 17:18:28 INFO - 'XPC_SERVICE_NAME': '0', 17:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:28 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)) 17:18:28 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)) 17:18:28 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)) 17:18:28 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)) 17:18:28 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)) 17:18:28 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)) 17:18:28 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)) 17:18:28 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)) 17:18:28 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)) 17:18:28 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)) 17:18:28 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)) 17:18:28 INFO - Cleaning up... 17:18:28 INFO - Return code: 0 17:18:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f666b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f669768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff06bc6ca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, '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.WVhjnI8C42/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.Te7sL8tLe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 17:18:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:18:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:18:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:28 INFO - 'HOME': '/Users/cltbld', 17:18:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:28 INFO - 'LOGNAME': 'cltbld', 17:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:28 INFO - 'MOZ_NO_REMOTE': '1', 17:18:28 INFO - 'NO_EM_RESTART': '1', 17:18:28 INFO - 'PAGER': '/bin/cat', 17:18:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:28 INFO - 'PWD': '/builds/slave/test', 17:18:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:28 INFO - 'SHELL': '/bin/bash', 17:18:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:18:28 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:18:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:28 INFO - 'USER': 'cltbld', 17:18:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:28 INFO - 'XPC_FLAGS': '0x0', 17:18:28 INFO - 'XPC_SERVICE_NAME': '0', 17:18:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:18:29 INFO - Cleaning up... 17:18:29 INFO - Return code: 0 17:18:29 INFO - Running post-action listener: _resource_record_post_action 17:18:29 INFO - Running post-action listener: _start_resource_monitoring 17:18:29 INFO - Starting resource monitoring. 17:18:29 INFO - [mozharness: 2017-04-29 00:18:29.098385Z] Finished create-virtualenv step (success) 17:18:29 INFO - [mozharness: 2017-04-29 00:18:29.099312Z] Running install step. 17:18:29 INFO - Running pre-action listener: _resource_record_pre_action 17:18:29 INFO - Running main action method: install 17:18:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:18:29 INFO - Reading from file tmpfile_stdout 17:18:29 INFO - Detecting whether we're running mozinstall >=1.0... 17:18:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:18:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:18:29 INFO - Reading from file tmpfile_stdout 17:18:29 INFO - Output received: 17:18:29 INFO - Usage: mozinstall [options] installer 17:18:29 INFO - Options: 17:18:29 INFO - -h, --help show this help message and exit 17:18:29 INFO - -d DEST, --destination=DEST 17:18:29 INFO - Directory to install application into. [default: 17:18:29 INFO - "/builds/slave/test"] 17:18:29 INFO - --app=APP Application being installed. [default: firefox] 17:18:29 INFO - mkdir: /builds/slave/test/build/application 17:18:29 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'] 17:18:29 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 17:18:49 INFO - Reading from file tmpfile_stdout 17:18:49 INFO - Output received: 17:18:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:18:49 INFO - Running post-action listener: _resource_record_post_action 17:18:49 INFO - [mozharness: 2017-04-29 00:18:49.041511Z] Finished install step (success) 17:18:49 INFO - [mozharness: 2017-04-29 00:18:49.041665Z] Running run-tests step. 17:18:49 INFO - Running pre-action listener: _resource_record_pre_action 17:18:49 INFO - Running main action method: run_tests 17:18:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:18:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:18:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:18:49 INFO - 2017-04-28 17:18:49.056 screenresolution[1621:7472] starting screenresolution argv=screenresolution get 17:18:49 INFO - 2017-04-28 17:18:49.058 screenresolution[1621:7472] Display 0: 1600x1200x32@60 17:18:49 INFO - 2017-04-28 17:18:49.064 screenresolution[1622:7475] starting screenresolution argv=screenresolution list 17:18:49 INFO - Available Modes on Display 0 17:18:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:18:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:18:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:18:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:18:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:18:49 INFO - Intel Iris: 17:18:49 INFO - Chipset Model: Intel Iris 17:18:49 INFO - Type: GPU 17:18:49 INFO - Bus: Built-In 17:18:49 INFO - VRAM (Dynamic, Max): 1536 MB 17:18:49 INFO - Vendor: Intel (0x8086) 17:18:49 INFO - Device ID: 0x0a2e 17:18:49 INFO - Revision ID: 0x0009 17:18:49 INFO - Displays: 17:18:49 INFO - Display: 17:18:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:18:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:18:49 INFO - Display Serial Number: 1600x1200 60 17:18:49 INFO - Main Display: Yes 17:18:49 INFO - Mirror: Off 17:18:49 INFO - Online: Yes 17:18:49 INFO - Rotation: Supported 17:18:49 INFO - Return code: 0 17:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:18:49 INFO - Python 2.7.3 17:18:49 INFO - Return code: 0 17:18:49 INFO - grabbing minidump binary from tooltool 17:18: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')]} 17:18:49 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 0x10f444d30>, '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 0x10f36adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f439be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:18:49 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 17:18:49 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 17:18:49 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 17:18:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:18:49 INFO - Return code: 0 17:18:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:18:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:18:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:18:49 INFO - ENV: RUST_BACKTRACE is now 1 17:18:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:18:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--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 17:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0101 --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 17:18:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WVhjnI8C42/Render', 17:18:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:49 INFO - 'HOME': '/Users/cltbld', 17:18:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:49 INFO - 'LOGNAME': 'cltbld', 17:18:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:18:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:49 INFO - 'MOZ_NO_REMOTE': '1', 17:18:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:18:49 INFO - 'NO_EM_RESTART': '1', 17:18:49 INFO - 'PAGER': '/bin/cat', 17:18:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:49 INFO - 'PWD': '/builds/slave/test', 17:18:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:18:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:49 INFO - 'RUST_BACKTRACE': '1', 17:18:49 INFO - 'SHELL': '/bin/bash', 17:18:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Te7sL8tLe0/Listeners', 17:18:49 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 17:18:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:49 INFO - 'USER': 'cltbld', 17:18:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:49 INFO - 'XPC_FLAGS': '0x0', 17:18:49 INFO - 'XPC_SERVICE_NAME': '0', 17:18:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--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 17:18:49 INFO - mozversion application_buildid: 20170428075320 17:18:49 INFO - mozversion application_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:18:49 INFO - mozversion application_display_name: Nightly 17:18:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:18:49 INFO - mozversion application_name: Firefox 17:18:49 INFO - mozversion application_remotingname: firefox 17:18:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:18:49 INFO - mozversion application_vendor: Mozilla 17:18:49 INFO - mozversion application_version: 55.0a1 17:18:49 INFO - mozversion platform_buildid: 20170428075320 17:18:49 INFO - mozversion platform_changeset: b3b32894d9326d147238d9f071e1814ab3fdc850 17:18:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:18:49 INFO - mozversion platform_version: 55.0a1 17:18:49 INFO - using testdate: 1493425129 17:18:49 INFO - actual date: 1493425129 17:18:49 INFO - starting webserver on 'localhost:49229' 17:18:49 INFO - SUITE-START | Running 2 tests 17:18:49 INFO - TEST-START | dromaeo_css 17:18:49 INFO - operating with platform_type : mac_ 17:18:49 INFO - Initialising browser for dromaeo_css test... 17:18:49 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmp7VPXr6/profile http://localhost:49229/getInfo.html) 17:18:52 INFO - PID 1632 | 2017-04-28 17:18:52.082 plugin-container[1634:7656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4957, name = 'com.apple.tsm.portname' 17:18:52 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:52 INFO - PID 1632 | 2017-04-28 17:18:52.085 plugin-container[1634:7656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 17:18:52 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:52 INFO - PID 1632 | 2017-04-28 17:18:52.085 plugin-container[1634:7656] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:18:52 INFO - PID 1632 | __metrics Screen width/height:1600/1200 17:18:52 INFO - PID 1632 | colorDepth:24 17:18:52 INFO - PID 1632 | Browser inner width/height: 1024/689 17:18:52 INFO - PID 1632 | __metrics 17:18:52 ERROR - PID 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:18:53 INFO - TEST-INFO | 1632: exit 0 17:18:53 INFO - Browser initialized. 17:18:53 INFO - Running cycle 1/1 for dromaeo_css test... 17:18:53 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmp7VPXr6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:18:55 INFO - PID 1635 | 2017-04-28 17:18:55.261 plugin-container[1636:7782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.tsm.portname' 17:18:55 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:55 INFO - PID 1635 | 2017-04-28 17:18:55.262 plugin-container[1636:7782] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 17:18:55 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:55 INFO - PID 1635 | 2017-04-28 17:18:55.262 plugin-container[1636:7782] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:19:05 INFO - PID 1635 | RSS: Main: 251424768 17:19:05 INFO - PID 1635 | 17:22:08 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 17:22:10 INFO - PID 1635 | RSS: Main: 254361600 17:22:10 INFO - PID 1635 | 17:23:53 INFO - PID 1635 | ************************* 17:23:53 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:23:53 INFO - PID 1635 | 17:23:53 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:23:53 INFO - PID 1635 | Full stack: 17:23:53 INFO - PID 1635 | ************************* 17:23:53 INFO - PID 1635 | ************************* 17:23:53 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:23:53 INFO - PID 1635 | 17:23:53 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:23:53 INFO - PID 1635 | Full stack: 17:23:53 INFO - PID 1635 | ************************* 17:23:53 INFO - PID 1635 | ************************* 17:23:53 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:23:53 INFO - PID 1635 | 17:23:53 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:23:53 INFO - PID 1635 | Full stack: 17:23:53 INFO - PID 1635 | ************************* 17:23:53 INFO - PID 1635 | ************************* 17:23:53 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 17:23:53 INFO - PID 1635 | 17:23:53 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:23:53 INFO - PID 1635 | Full stack: 17:23:53 INFO - PID 1635 | ************************* 17:25:12 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 17:25:12 INFO - PID 1635 | RSS: Main: 259940352 17:25:12 INFO - PID 1635 | 17:28:14 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 17:28:15 INFO - PID 1635 | RSS: Main: 269819904 17:28:15 INFO - PID 1635 | 17:31:16 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 17:31:17 INFO - PID 1635 | RSS: Main: 262815744 17:31:17 INFO - PID 1635 | 17:34:20 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 17:34:20 INFO - PID 1635 | RSS: Main: 271372288 17:34:20 INFO - PID 1635 | 17:37:22 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 17:37:22 INFO - PID 1635 | RSS: Main: 265949184 17:37:22 INFO - PID 1635 | 17:37:22 INFO - PID 1635 | __start_tp_report 17:37:22 INFO - PID 1635 | _x_x_mozilla_page_load 17:37:22 INFO - PID 1635 | _x_x_mozilla_page_load_details 17:37:22 INFO - PID 1635 | |i|pagename|runs| 17:37:22 INFO - PID 1635 | |0;dojo.html;2880.337522369855;2960.9111726648202;2970.8068975516603;3056.984715076425;3631.7639353442023;6145.12573665032;6207.037909124082;6273.278572964105;6276.905846412307;6311.936880631204;6197.318294987548;6240.344763799803;6249.656268905208;6283.528735344856;6323.20959880015;5446.400895901447;5447;5465.72671366432;5488.91766623499;5523.309586301712;5002.524760147799;5590.944090559104;5594.356648985357;5618.690971996539;5658.74535645897;4597.402337696111;5360.383555891083;5432.6468779529205;5533.861653458655;5552.167174923774;5453.4273901240595;5454.645448045864;5473.589480788957;5503.11950087984;5525.944740552583;5471.835844924659;5494.670319780826;5511.980283647538;5513.558915286808;5515.917261241085;5211.270422140903;5960.791372301948;6309.463695585835;6316.6841657917375;6320.873582528324;6496.870062598721;6536.869262614769;6599.043138744855;6734.7642832501115;6736.865262694768;6770.289119642416;6792.898106528406;6793.354631310018;6794.320567943166;6819.011243369684;5146.562542183956;6048.608819971387;6348.460380867632;6365.140706004677;6378.681065946684;6699.296573859772;6764.019217213575;6983;7000.08998830159;7026.26224246457;6530.053142294436;6575.243846957661;6578.638174900427;6597.043428702811;6618.205815302194;6715.127033485615;6726.932730672728;6740.730370785212;6754.020667003355;6765.526413151032;6420.678966051679;6560.343965603402;6623.602583844985;6644;6660;6509.381608747161;6629.469642428594;6682.264950855402;6703.497237707192;6733.158355205599;5142.280080788692;5341.4925582069645;5353.652012619167;5377.973110134502;5380.892382152335;4741.976290118528;4749.833755818599;4756.6908150970075;4768.260919557474;4796.904061918812;5732.398098199712;6298.212723409574;6320.336364681642;6381.042843573501;6420.261669907927;6730.125083739083;6807.183138023469;6810.353016463428;6811.352921472452;6837.760678376233;6362.872742545123;6373.012183111625;6376.872462550723;6391.07329437229;6401.423871851555;5760.07838745807;5819.185314056035;5822.214001109881;5828.213191219131;5914.674692891932;6864.107666003388;6935.895961560581;6961.825954351182;7032.648367581703;7044.964775176091;6539.182602174728;6823.112995310578;6835.487338449537;6859.725610975505;6870.862582748217;6824.488163387865;6837.760678376233;6905.274946130584;7050.083489146378;7055.1886533049355;6717.966410167918;6740.12378390806;6745.190577130776;6759.763408280884;6776.254611992776;6562.80311590653;6659.433948114464;6684.732610695518;6698.698558564779;6743.831404214934;6570.080188773671;6605.801825945229;6609.768658096944;6613.5370524062855;6618.602883826986;6604.405603495708;6606.735730570916;6619.106420633346;6619.304972977768;6625;5801;6308.905366419415;6322.715477803418;6371.331010244043;6426.710798014195;6580.76967306142;6581.835454113685;6607.108040414482;6629.933700662931;6647.534672572873;6418.454431373386;6425.807225783234;6451.258105317948;6452.709628066655;6453.128827608401 17:37:22 INFO - PID 1635 | |1;ext.html;805.3838813307821;836.2682652678908;1202.93985300735;1247.3451437995045;1252.442663014958;37807.62192378075;38598.80700596493;38605.8418247453;38734.80632596837;38832.805835970816;31697.36605267893;38077.809610951976;38528.42207366892;38562;38640.03399915004;7855.4893931894385;8017.799555011142;8018.238267364591;8356.415050946436;8545.778097694612;8572.914270857274;8660.350473714465;8664.48013119215;8666.52334121623;8687.869681954775;8584.699535516258;8593.183647553473;8599.355048371397;8687.131286871325;8689;8600.56997150143;8646.135386461303;8660;8666.046734859161;8681.913180868174;8477.364197685198;8483.44857584255;8515.787105322355;8521.786955326104;8551.033262349632;37641.87074387773;37763.62236377656;37798.866034019025;37848.81075594604;37942.81028594867;3613.33153366628;3616.18635806944;3654.3056819204267;3658.396364599838;3661.5789184243886;30994.690053099406;31029.844850775826;31039;31134.688653113404;31210.53184202239;3076.7077127672837;3137.7646676499353;3146.1348129264406;3152.7162555369896;3157.6526582075953;248077;258833.41166588283;290578.5471072652;301662;302626;39460.802695986335;39912.401313980314;48541;49427;49449.75275123673;46916;50949.490505095215;51252.97494050173;51327.230091548656;51457.742711286955;11792.823107653392;14469.855301447062;14527.055741376987;14553.708925821637;14559.272036398137;11502.769944601228;11693.88306116945;11699.941500292443;11703.765924681458;11707.765844683228;13566;13619.727605448034;13665.931670341784;13726.862731372757;13792.172469651652;6037.184980027728;6080.0543825975265;6097.603655762361;6105.358937311607;6109.725062372204;1035.4874337203132;1042.3641578637025;1046.7487802927242;1069.8930106989394;1077.8652668416448;23279.3016209514;24356.87821560881;24420.511589768106;24496.632550511757;24637.5072498549;910.8588168833842;1045.4668119259081;1138.65271092316;1170.5785917069848;1180.3390101543164;12465.626031219077;12490.50037998488;12513.93743031279;12537.561185358469;12601.495940162291;320836;322522;323029.3848530742;323860;324216;54337;54360.72819635877;54553;54738.90522189614;54739;1539.1534655939188;1543.4443600303882;1579.8104227492774;1581.2093953023489;1596.2098761113323;24162.63756043696;24235.39411514656;24300.51398972046;24307.635385469584;24452.75547244576;39073.21853562856;39127;39138;39163.41254881122;39263.60736392599;285856;286332;286576;287352;287454;34791.82604087065;34882.825585871906;35617;35621;35622.82188589041;43857.78071109624;44601;45974;46186.84532886705;46411.30383044325;29754.851225743732;30382.54426183653;30616.540751888293;30683.926062587703;31688.04935851928;30720.23199419943;30823.383532329677;31227.907023254076;31662.525062124547;31744.841275793475;29324.56013159847;36950.07624809294;37560;37628.81185594055;37673.43489847709;29688.406231875673;30757.769689212182;31644.05067847968;31846;31904.6809531911;17765.911170444066;22326;24686.80767358438;24931.002759890125;25023.87488062548 17:37:22 INFO - PID 1635 | |2;jquery.html;363.77809536182934;373.88409593026165;381.1937751655248;435.41654183394246;436.3389464960587;18425.723614145798;21264.787352126474;21570.460738481546;22013.449663758412;22023.678579285235;21752.673709894363;21848.672269915965;21951;22057.22799702007;22271.440999130067;5900.88198236035;6149.2313460817395;6152.815415537541;6154.415330543591;6165.136880836664;5905.822825315226;6039.909401358983;6066.150738896558;6094.451499365056;6097.481714054288;5941.197938278342;6029.517638588903;6032.215811944463;6046.213992181032;6050.789842031611;5897.849919265724;5948.97025514874;5949.583529152939;5978.551608629348;6124.142620033199;5365.7853685852515;5409.83770486886;5423.403425623178;5428.45715428458;5490.396056433789;21709.891450542804;25888;26390.86804565984;26505.337366565796;26625;2254;2269.931902042941;2305.0318866076286;2305.9423514412274;2312.791848733605;14724.484643037442;17211.05339206343;17983.280668773234;18160.364387246384;18243.908780456146;2129.9148034078617;2139.3261122746285;2172.7718589548026;2189.6606026065824;2190.9561808763738;124400.88997775129;125944;133028;142860;145519;10339.741506462247;12230.571929982409;12575.308358040396;12626.116171867881;12738.426770795337;16395.918020409823;16462.75305870413;16464.753028704577;16465.91767041157;16490.257938392802;7453;8797.648094076165;9012.233960113464;9077.137671921157;9158.809354783836;8488.660453581911;8490;8576.785580360542;8749.825003499895;8794.03265640779;7159.498835081598;7316;7778.183290754502;7880;7958.612416275094;2213.7786221378055;2219.966700499494;2223.8888055597154;2227.855189412683;2239.451334423077;991.1971303244395;992.6327258914248;996.9052939970691;1017.1760873692185;1041.557338131291;21153.68269475959;21434.356969290948;21556.353309400743;21565.78434215669;21609.891950540146;974.2985050763439;1029.7940411917646;1052.9105026072716;1060.188955449082;1072.1101485766794;9844.163246124159;11246.66260012201;11268.323900565993;11269.154813389028;11290.266132701348;561768;562796;572223;577610.3358449626;577822;134881;140550;145525;146864;176584.11707941635;934.6074648647584;1049.506731836036;1142.9199956003167;1215.3862299538853;1219.231883913129;20311.898440508296;21181.6822747662;21680.699158050567;22392.888035559718;22448.877556121803;46724;61840.38159618346;62556.12331630058;63014.36985630085;63421;83502;85656.14343856482;86314.56842715746;86594.56702716446;86863.39409817716;7577.5453472791805;8843.292536597186;8916.465012099296;9039.638414463348;9185;14393.280335983369;14583.343749531508;14611.269436528344;14906.925465372604;14994.625134371729;12120.818187727367;12162.878371216175;12207.633770986884;12352.814707779571;12369.319687416919;11104;11375.658730238107;11391.829122563002;11417.543298268309;11626.70933226676;9889.653862114792;12065.638030859089;12479.939205167298;12561.057920655805;12607.684807879876;8966.596503157243;9032.412893161923;9109.18017378439;9218.723438296862;9301.86047209306;11721.355325457182;12180.57367992116;12215.045592705124;12315.322657253937;12358.44387002605 17:37:22 INFO - PID 1635 | |3;mootools.html;1243.97512049759;1269.3462866623684;1391.2695834686795;1452.3174108169164;1454.7526920423527;11258.98669119779;11723.120765942567;11807.055435565166;11850.229735067214;12015.399230038507;9783.608655653765;10405.167586593074;10791.676249712502;11887.22733022353;11937.820932686016;2224.021430570544;2249.955000899981;2256.17649557911;2263.0721404224273;2264.2641141628956;2201.328594778592;2234.1845226492296;2246.4608493961414;2248.347979086063;2251.1108112295633;1861.9906900465478;2209.5028618560777;2229.1752051740878;2248.4266512039408;2264.864108153508;2177.6951226828255;2190.3647942096773;2210.8562943408706;2230.3718285651485;2237.328801359602;1852.8517718582482;1929.0740444586536;2124.7662757096705;2194.3965409512352;2205.7683943186057;11921.225120367235;11974.940125299405;12055.758884822255;12060.216085954386;12084.697882552919;6564.442022428099;6811.386975172259;6815.284395138488;6816.488763342769;6823.658817059128;4038.515378154611;4344.695871289011;4415.867523974253;4430.24685803411;4432.002799370148;1233.8519377674736;1244.2845363915694;1254.91215614907;1265.9998601105208;1277.948882044717;13304.933475332562;15091.471798487;16571.751423728652;16761.161941902858;16764.99410035402;4715.363425937455;5381.408045115034;5409.026375252413;5612.579056570692;5702.914456283159;9159.587818548182;10034;11722.355270460206;11942.223755455867;11942.462589183508;6591.143151390289;6624.503162262849;6629;6644.601323920484;6647;6102.267727872595;6123.601965872204;6125.01999680058;6220.626762394271;6244.1570387997945;3975.7216994810087;4157.293260145782;4319.006628475406;4429.955700442954;4447.73313601185;1255.9434825432877;1265.5570550307318;1281.8654041325526;1291.6577107066635;1296.4425297122327;720.2869159532025;773.7562667759638;801.5831767480994;803.8593246181895;805.8670319397233;5920.822375328747;6510.21877374718;6701.463882889358;6775.593464392152;7033.648317584202;865.8441480533564;940.4075432477632;945.550863339908;951.3340661536952;969.8205831921117;3277.4264503711756;3811.333016722118;3872.244912242142;3893.6690381317903;3934.3311637021775;14564.781528277084;14753.77869331961;15199.392024318904;15245.23773811305;15459.91780575349;14955.327010284564;15095.622609434633;15266.847331526764;15278.388864445298;15291.235438228045;8640.395172338001;8653.048164701877;8829.955850220707;8852.778680532909;8875.689350872688;7103.396211321889;7203.927960720535;7263.636818159177;7268.672909719181;7313.634318284171;13351.399187036412;13450.663733406744;13673;13685.521006764715;13697.972652050938;13120.60638180856;13845;13950.302484875918;14035.438582456587;14040.578782636538;7768.456208065381;8227;8495.787605309917;8597.054324024477;9333.439993600407;10834.02493775564;10863.076638485818;10919.344839309666;11001.394923279297;11013.614523491638;9307.963634557;9331.58007889633;9384.108509691569;9393.295502837178;9432.575534100844;9010.189082982562;9128.589213485277;9218.032106628707;9231.676891308773;9312.301577381859;8076;8273.793155171168;8306.83386332258;8395.24442800151;8439.409241353047;4909.288153217834;4980.8754781130765;5334.306540149756;6075.848103797441;6134.110553969738;5483.533899618577;5947.851303717442;6074.240719910012;6288.654124023223;6414.743410263537 17:37:22 INFO - PID 1635 | |4;prototype.html;407.9857204997826;414.4384359193293;417.46772864597654;419.797280790535;426.3923908430486;6171.845703857406;6216.906746398802;6308.621482711041;6321.715522801479;6326.145970294009;6014.969925150368;6130.8160755177305;6159.168512250845;6193.659348735829;6219.533534984872;4346.8913277168;4465.9106817863785;4468.061707041526;4469.374287599739;4479.484859241196;3665.981670091646;3677.9080522986874;4451.844185453509;4470.524726840151;4498.627918484861;4019.954811748937;4299.5485474025245;4440.533743956887;4442.62237709795;4442.689011769161;4376.803043863018;4398.296272596369;4450.710703804242;4469.128519938613;4472.53038430965;3259.364423937333;3262.722668573174;3780.2250538639514;3810.942835857464;3836.865709700147;6017.849553761147;6029.60807547508;6041.305249896274;6042.516598672096;6078.149059131725;5190.351206099238;5240.737963101829;5301.7614207360375;5309.62832601718;5320.17537281722;6536.934630653727;6544.607323560554;6553.410193082598;6566.343365663443;6572.507061970371;4166.7708276044805;4244.448221731186;4278.42241297424;4317.654587632982;4319.935200971988;6762.661866906635;6912.412444942186;6969.128858892638;6972.1284839395075;7059.788206353817;4312.698111132253;4348.804078878334;4359.713884404045;4360.236958532308;4372.540883207217;4428.7121337112985;4430.82276708928;4432.756198409054;4435.556444355539;4447.822087116479;4817.518248175154;5146.047981123429;5229.529342359206;5282.788688452419;5294.914542518774;6078.392160783975;6394.8721025579225;6619.205695316593;6659.134312539436;6690.632015239111;1493.7834014067898;1516.98362097395;1517.7419838627238;1518.1270769307691;1519.187234829361;878.8286284174652;882.4705176893813;970.1123472023161;977.5796407544682;990.2672022703292;5225.771943593329;5258.030187208709;5545.724483368847;6319.368063193644;6345.270293916166;6073.18012068374;6734.966325168343;6882.7591034313555;6891.655417229119;6902.827429314308;5861.8276344730375;6013.51891848651;6313.40022697843;6325.810225693144;6364;3280.9343813123605;3486.285311511185;3583.7133029357587;3601.1717305019993;3615.096225943514;6422.389872962061;6439.937410327244;6478.708458119396;7015.684294206773;7108.786736397917;5536.529395001389;6417.326180750954;6954.478414118961;7052.400545953549;7062.585883822611;6085.421884920926;6889.896651550231;7121;7127.2160062393095;7143.571385716874;5749.798757043483;6335.632689147377;6436.67816609177;6900.412345370486;6936.514443988873;5733.541316694738;6202.2552747147565;6445;6792.132163655035;6810.421114205348;6131.988221943329;7002.229754727078;7002.894956575554;7025.578465292099;7063.82340441493;4327.826886924614;4350.542568239549;4358.389825424507;4361.912761744811;4366.214081465239;6763.560368576027;6778.694958726967;6828.214755303005;6863.529129513471;6896.551724137858;5691.4877661010705;5717.913596416667;6099.9390006099375;6568.540202185801;6597.340265973365;5406.324209473816;5530.91703624454;5798.8692205019615;6490.415862572202;6500.512461565307;6472.611643301417;6504.894167991358;6519.4132528072705;6525.857974854286;6532.738690452328;6441.871162576628;6565.868682626417;6590.604563726;6614.404703576702;6649.567778094409;5943.7116710066075;6038.9129956606475;6072.908906366496;6428.4857211423905;6464.4828413725845 17:37:22 INFO - PID 1635 | |5;yui.html;673.383853773797;841.1756478650926;848.6562942008485;878.639757699343;881.2113158722943;16288.83711162888;16665.166741662928;16709.081000544982;18309.542261443472;19374.321898733546;17901;18200.726989095176;18251.726224106616;18486.630267394645;18562.907185464086;994.4828689081673;1008.8890222075603;1012.3622118065608;1014.5130337438034;1019.4851599942019;947.8578213268031;1011.4790882695418;1019.938803671782;1035.9585616575366;1043.911267542256;1081.0810810810788;1082.767205050918;1083.3824719909614;1083.9187060970419;1084.9457527123652;1013.4375421640949;1046.5813674530211;1073.9677809665723;1075.876274228458;1085.0831047764627;776.47587878182;796.4464697035562;798.4251339035885;801.8356236971407;810.1007881251805;16688.83311166897;16889.66220675592;16965.57586060346;17368.218430170673;17380.739288910678;17191.312347506086;17281.740773888403;17568.472945811518;17650.91174544132;17692.734608980874;14034.85965140356;14276.857231427657;14330.498432554914;14448.277586120652;14464.783028254584;2446.8531888086595;2844.5164322065093;2853.2296280004284;2856.7428931396275;2866.1974647098846;116965;118009;128623.35688321592;138875.30562347226;144263.5573644271;13137.014723895745;13482.39329230187;13515.729685406433;13617.931910340385;13655.04414690962;18334.266629334677;24562;25131.62302565463;25334.619980700303;26035.479290414085;7733.535987840747;7953.960230198928;7969.322607578305;7991.400644951652;7993.040835099709;3947.5657677655436;3967.8809635710977;3986.7807270599815;4023.3361495353524;4031.0728532437115;4276.631477927096;4376.081022985145;4381.824727010884;4438.356438316426;4445.066536027406;939.6335429182624;961.3510880156007;971.1939090554822;972.4408465132577;980.8185485685028;655.7377049180366;673.2863165045068;673.441043933534;679.6771533521538;692.6398272898065;11370.545178192946;11809.763804724029;12048.33734144613;12097.818532722016;12292.323922184187;650.7375691159136;659.2517492645882;660.3066779881106;692.2281655953575;697.1146643762394;5418.241446197536;5624.943750562524;5644.689542075143;5654.095344744904;5729.997250481149;107228;108128;108365.54417273628;108797;110232;85297.57351213205;85764.57117714372;85890;86894;97966;69477;69813;71043.2895671047;71097.6445117771;71129.2887071133;12127.636170914886;12443.937780311402;12475.875241247835;12534.623961281177;12574.811377829523;33026.17434564155;36339.27321453609;39477.60522394854;39625.603743962194;40180;62124.37875621186;65486.36284092745;66663;66917.1544192138;68020;16647.83352166463;16649.91675041617;16814.57963550921;16931.746023809406;16939.781441526564;16104.67790644204;17922.014289214218;18247.178876950835;18421.90789046046;18435.446936591925;11691.064714822964;16450;16898.15509224509;16968.49094527166;17096.48710538686;16077.517674469784;16213.918930405272;16820.747688784923;16863.15684215809;16890.831091688924;13923.721525569634;15656.765148523009;15947.840521595099;16490.34038638441;16566.923149995695;11860;11982.281063136239;12047.216930899463;12150.21023633461;12181.025517958718;12255.938720306698;12711.872881271438;12916.160449570747;12928.806067908801;12944.741105177656 17:37:22 INFO - PID 1635 | __end_tp_report 17:37:22 INFO - PID 1635 | __start_cc_report 17:37:22 INFO - PID 1635 | _x_x_mozilla_cycle_collect,413 17:37:22 INFO - PID 1635 | __end_cc_report 17:37:22 INFO - PID 1635 | __startTimestamp1493426242908__endTimestamp 17:37:22 INFO - PID 1635 | 17:37:22 INFO - PID 1635 | ------- Summary: start ------- 17:37:22 INFO - PID 1635 | Number of tests: 6 17:37:22 INFO - PID 1635 | 17:37:22 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:6173.77 Median:6426.71 stddev:802.02 (12.5%) stddev-sans-first:761.73 17:37:22 INFO - PID 1635 | Values: 2880.3 2960.9 2970.8 3057.0 3631.8 6145.1 6207.0 6273.3 6276.9 6311.9 6197.3 6240.3 6249.7 6283.5 6323.2 5446.4 5447.0 5465.7 5488.9 5523.3 5002.5 5590.9 5594.4 5618.7 5658.7 4597.4 5360.4 5432.6 5533.9 5552.2 5453.4 5454.6 5473.6 5503.1 5525.9 5471.8 5494.7 5512.0 5513.6 5515.9 5211.3 5960.8 6309.5 6316.7 6320.9 6496.9 6536.9 6599.0 6734.8 6736.9 6770.3 6792.9 6793.4 6794.3 6819.0 5146.6 6048.6 6348.5 6365.1 6378.7 6699.3 6764.0 6983.0 7000.1 7026.3 6530.1 6575.2 6578.6 6597.0 6618.2 6715.1 6726.9 6740.7 6754.0 6765.5 6420.7 6560.3 6623.6 6644.0 6660.0 6509.4 6629.5 6682.3 6703.5 6733.2 5142.3 5341.5 5353.7 5378.0 5380.9 4742.0 4749.8 4756.7 4768.3 4796.9 5732.4 6298.2 6320.3 6381.0 6420.3 6730.1 6807.2 6810.4 6811.4 6837.8 6362.9 6373.0 6376.9 6391.1 6401.4 5760.1 5819.2 5822.2 5828.2 5914.7 6864.1 6935.9 6961.8 7032.6 7045.0 6539.2 6823.1 6835.5 6859.7 6870.9 6824.5 6837.8 6905.3 7050.1 7055.2 6718.0 6740.1 6745.2 6759.8 6776.3 6562.8 6659.4 6684.7 6698.7 6743.8 6570.1 6605.8 6609.8 6613.5 6618.6 6604.4 6606.7 6619.1 6619.3 6625.0 5801.0 6308.9 6322.7 6371.3 6426.7 6580.8 6581.8 6607.1 6629.9 6647.5 6418.5 6425.8 6451.3 6452.7 6453.1 17:37:22 INFO - PID 1635 | 17:37:22 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:44917.35 Median:322775.69 stddev:77970.69 (24.2%) stddev-sans-first:78119.07 17:37:22 INFO - PID 1635 | Values: 805.4 836.3 1202.9 1247.3 1252.4 37807.6 38598.8 38605.8 38734.8 38832.8 31697.4 38077.8 38528.4 38562.0 38640.0 7855.5 8017.8 8018.2 8356.4 8545.8 8572.9 8660.4 8664.5 8666.5 8687.9 8584.7 8593.2 8599.4 8687.1 8689.0 8600.6 8646.1 8660.0 8666.0 8681.9 8477.4 8483.4 8515.8 8521.8 8551.0 37641.9 37763.6 37798.9 37848.8 37942.8 3613.3 3616.2 3654.3 3658.4 3661.6 30994.7 31029.8 31039.0 31134.7 31210.5 3076.7 3137.8 3146.1 3152.7 3157.7 248077.0 258833.4 290578.5 301662.0 302626.0 39460.8 39912.4 48541.0 49427.0 49449.8 46916.0 50949.5 51253.0 51327.2 51457.7 11792.8 14469.9 14527.1 14553.7 14559.3 11502.8 11693.9 11699.9 11703.8 11707.8 13566.0 13619.7 13665.9 13726.9 13792.2 6037.2 6080.1 6097.6 6105.4 6109.7 1035.5 1042.4 1046.7 1069.9 1077.9 23279.3 24356.9 24420.5 24496.6 24637.5 910.9 1045.5 1138.7 1170.6 1180.3 12465.6 12490.5 12513.9 12537.6 12601.5 320836.0 322522.0 323029.4 323860.0 324216.0 54337.0 54360.7 54553.0 54738.9 54739.0 1539.2 1543.4 1579.8 1581.2 1596.2 24162.6 24235.4 24300.5 24307.6 24452.8 39073.2 39127.0 39138.0 39163.4 39263.6 285856.0 286332.0 286576.0 287352.0 287454.0 34791.8 34882.8 35617.0 35621.0 35622.8 43857.8 44601.0 45974.0 46186.8 46411.3 29754.9 30382.5 30616.5 30683.9 31688.0 30720.2 30823.4 31227.9 31662.5 31744.8 29324.6 36950.1 37560.0 37628.8 37673.4 29688.4 30757.8 31644.1 31846.0 31904.7 17765.9 22326.0 24686.8 24931.0 25023.9 17:37:22 INFO - PID 1635 | 17:37:22 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:36452.45 Median:12255.45 stddev:96554.37 (787.8%) stddev-sans-first:96787.21 17:37:22 INFO - PID 1635 | Values: 363.8 373.9 381.2 435.4 436.3 18425.7 21264.8 21570.5 22013.4 22023.7 21752.7 21848.7 21951.0 22057.2 22271.4 5900.9 6149.2 6152.8 6154.4 6165.1 5905.8 6039.9 6066.2 6094.5 6097.5 5941.2 6029.5 6032.2 6046.2 6050.8 5897.8 5949.0 5949.6 5978.6 6124.1 5365.8 5409.8 5423.4 5428.5 5490.4 21709.9 25888.0 26390.9 26505.3 26625.0 2254.0 2269.9 2305.0 2305.9 2312.8 14724.5 17211.1 17983.3 18160.4 18243.9 2129.9 2139.3 2172.8 2189.7 2191.0 124400.9 125944.0 133028.0 142860.0 145519.0 10339.7 12230.6 12575.3 12626.1 12738.4 16395.9 16462.8 16464.8 16465.9 16490.3 7453.0 8797.6 9012.2 9077.1 9158.8 8488.7 8490.0 8576.8 8749.8 8794.0 7159.5 7316.0 7778.2 7880.0 7958.6 2213.8 2220.0 2223.9 2227.9 2239.5 991.2 992.6 996.9 1017.2 1041.6 21153.7 21434.4 21556.4 21565.8 21609.9 974.3 1029.8 1052.9 1060.2 1072.1 9844.2 11246.7 11268.3 11269.2 11290.3 561768.0 562796.0 572223.0 577610.3 577822.0 134881.0 140550.0 145525.0 146864.0 176584.1 934.6 1049.5 1142.9 1215.4 1219.2 20311.9 21181.7 21680.7 22392.9 22448.9 46724.0 61840.4 62556.1 63014.4 63421.0 83502.0 85656.1 86314.6 86594.6 86863.4 7577.5 8843.3 8916.5 9039.6 9185.0 14393.3 14583.3 14611.3 14906.9 14994.6 12120.8 12162.9 12207.6 12352.8 12369.3 11104.0 11375.7 11391.8 11417.5 11626.7 9889.7 12065.6 12479.9 12561.1 12607.7 8966.6 9032.4 9109.2 9218.7 9301.9 11721.4 12180.6 12215.0 12315.3 12358.4 17:37:22 INFO - PID 1635 | 17:37:22 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:7031.93 Median:3882.96 stddev:4530.40 (116.7%) stddev-sans-first:4522.23 17:37:22 INFO - PID 1635 | Values: 1244.0 1269.3 1391.3 1452.3 1454.8 11259.0 11723.1 11807.1 11850.2 12015.4 9783.6 10405.2 10791.7 11887.2 11937.8 2224.0 2250.0 2256.2 2263.1 2264.3 2201.3 2234.2 2246.5 2248.3 2251.1 1862.0 2209.5 2229.2 2248.4 2264.9 2177.7 2190.4 2210.9 2230.4 2237.3 1852.9 1929.1 2124.8 2194.4 2205.8 11921.2 11974.9 12055.8 12060.2 12084.7 6564.4 6811.4 6815.3 6816.5 6823.7 4038.5 4344.7 4415.9 4430.2 4432.0 1233.9 1244.3 1254.9 1266.0 1277.9 13304.9 15091.5 16571.8 16761.2 16765.0 4715.4 5381.4 5409.0 5612.6 5702.9 9159.6 10034.0 11722.4 11942.2 11942.5 6591.1 6624.5 6629.0 6644.6 6647.0 6102.3 6123.6 6125.0 6220.6 6244.2 3975.7 4157.3 4319.0 4430.0 4447.7 1255.9 1265.6 1281.9 1291.7 1296.4 720.3 773.8 801.6 803.9 805.9 5920.8 6510.2 6701.5 6775.6 7033.6 865.8 940.4 945.6 951.3 969.8 3277.4 3811.3 3872.2 3893.7 3934.3 14564.8 14753.8 15199.4 15245.2 15459.9 14955.3 15095.6 15266.8 15278.4 15291.2 8640.4 8653.0 8830.0 8852.8 8875.7 7103.4 7203.9 7263.6 7268.7 7313.6 13351.4 13450.7 13673.0 13685.5 13698.0 13120.6 13845.0 13950.3 14035.4 14040.6 7768.5 8227.0 8495.8 8597.1 9333.4 10834.0 10863.1 10919.3 11001.4 11013.6 9308.0 9331.6 9384.1 9393.3 9432.6 9010.2 9128.6 9218.0 9231.7 9312.3 8076.0 8273.8 8306.8 8395.2 8439.4 4909.3 4980.9 5334.3 6075.8 6134.1 5483.5 5947.9 6074.2 6288.7 6414.7 17:37:22 INFO - PID 1635 | 17:37:22 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:5285.43 Median:6073.04 stddev:1689.01 (27.8%) stddev-sans-first:1653.60 17:37:22 INFO - PID 1635 | Values: 408.0 414.4 417.5 419.8 426.4 6171.8 6216.9 6308.6 6321.7 6326.1 6015.0 6130.8 6159.2 6193.7 6219.5 4346.9 4465.9 4468.1 4469.4 4479.5 3666.0 3677.9 4451.8 4470.5 4498.6 4020.0 4299.5 4440.5 4442.6 4442.7 4376.8 4398.3 4450.7 4469.1 4472.5 3259.4 3262.7 3780.2 3810.9 3836.9 6017.8 6029.6 6041.3 6042.5 6078.1 5190.4 5240.7 5301.8 5309.6 5320.2 6536.9 6544.6 6553.4 6566.3 6572.5 4166.8 4244.4 4278.4 4317.7 4319.9 6762.7 6912.4 6969.1 6972.1 7059.8 4312.7 4348.8 4359.7 4360.2 4372.5 4428.7 4430.8 4432.8 4435.6 4447.8 4817.5 5146.0 5229.5 5282.8 5294.9 6078.4 6394.9 6619.2 6659.1 6690.6 1493.8 1517.0 1517.7 1518.1 1519.2 878.8 882.5 970.1 977.6 990.3 5225.8 5258.0 5545.7 6319.4 6345.3 6073.2 6735.0 6882.8 6891.7 6902.8 5861.8 6013.5 6313.4 6325.8 6364.0 3280.9 3486.3 3583.7 3601.2 3615.1 6422.4 6439.9 6478.7 7015.7 7108.8 5536.5 6417.3 6954.5 7052.4 7062.6 6085.4 6889.9 7121.0 7127.2 7143.6 5749.8 6335.6 6436.7 6900.4 6936.5 5733.5 6202.3 6445.0 6792.1 6810.4 6132.0 7002.2 7002.9 7025.6 7063.8 4327.8 4350.5 4358.4 4361.9 4366.2 6763.6 6778.7 6828.2 6863.5 6896.6 5691.5 5717.9 6099.9 6568.5 6597.3 5406.3 5530.9 5798.9 6490.4 6500.5 6472.6 6504.9 6519.4 6525.9 6532.7 6441.9 6565.9 6590.6 6614.4 6649.6 5943.7 6038.9 6072.9 6428.5 6464.5 17:37:22 INFO - PID 1635 | 17:37:22 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:21860.41 Median:17671.82 stddev:30968.43 (175.2%) stddev-sans-first:31014.44 17:37:22 INFO - PID 1635 | Values: 673.4 841.2 848.7 878.6 881.2 16288.8 16665.2 16709.1 18309.5 19374.3 17901.0 18200.7 18251.7 18486.6 18562.9 994.5 1008.9 1012.4 1014.5 1019.5 947.9 1011.5 1019.9 1036.0 1043.9 1081.1 1082.8 1083.4 1083.9 1084.9 1013.4 1046.6 1074.0 1075.9 1085.1 776.5 796.4 798.4 801.8 810.1 16688.8 16889.7 16965.6 17368.2 17380.7 17191.3 17281.7 17568.5 17650.9 17692.7 14034.9 14276.9 14330.5 14448.3 14464.8 2446.9 2844.5 2853.2 2856.7 2866.2 116965.0 118009.0 128623.4 138875.3 144263.6 13137.0 13482.4 13515.7 13617.9 13655.0 18334.3 24562.0 25131.6 25334.6 26035.5 7733.5 7954.0 7969.3 7991.4 7993.0 3947.6 3967.9 3986.8 4023.3 4031.1 4276.6 4376.1 4381.8 4438.4 4445.1 939.6 961.4 971.2 972.4 980.8 655.7 673.3 673.4 679.7 692.6 11370.5 11809.8 12048.3 12097.8 12292.3 650.7 659.3 660.3 692.2 697.1 5418.2 5624.9 5644.7 5654.1 5730.0 107228.0 108128.0 108365.5 108797.0 110232.0 85297.6 85764.6 85890.0 86894.0 97966.0 69477.0 69813.0 71043.3 71097.6 71129.3 12127.6 12443.9 12475.9 12534.6 12574.8 33026.2 36339.3 39477.6 39625.6 40180.0 62124.4 65486.4 66663.0 66917.2 68020.0 16647.8 16649.9 16814.6 16931.7 16939.8 16104.7 17922.0 18247.2 18421.9 18435.4 11691.1 16450.0 16898.2 16968.5 17096.5 16077.5 16213.9 16820.7 16863.2 16890.8 13923.7 15656.8 15947.8 16490.3 16566.9 11860.0 11982.3 12047.2 12150.2 12181.0 12255.9 12711.9 12916.2 12928.8 12944.7 17:37:22 INFO - PID 1635 | -------- Summary: end -------- 17:37:22 INFO - PID 1635 | 17:37:23 INFO - TEST-INFO | 1635: exit 0 17:37:23 INFO - TEST-OK | dromaeo_css | took 1113865ms 17:37:23 INFO - TEST-START | kraken 17:37:23 INFO - operating with platform_type : mac_ 17:37:23 INFO - Initialising browser for kraken test... 17:37:23 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpV0aUpN/profile http://localhost:49229/getInfo.html) 17:37:25 INFO - PID 1647 | 2017-04-28 17:37:25.817 plugin-container[1648:10232] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954f, name = 'com.apple.tsm.portname' 17:37:25 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:25 INFO - PID 1647 | 2017-04-28 17:37:25.819 plugin-container[1648:10232] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 17:37:25 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:25 INFO - PID 1647 | 2017-04-28 17:37:25.819 plugin-container[1648:10232] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:37:26 INFO - PID 1647 | __metrics Screen width/height:1600/1200 17:37:26 INFO - PID 1647 | colorDepth:24 17:37:26 INFO - PID 1647 | Browser inner width/height: 1024/689 17:37:26 INFO - PID 1647 | __metrics 17:37:26 ERROR - PID 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3424: TypeError: this.tabs is undefined 17:37:26 INFO - TEST-INFO | 1647: exit 0 17:37:26 INFO - Browser initialized. 17:37:26 INFO - Running cycle 1/1 for kraken test... 17:37:26 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpV0aUpN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:37:29 INFO - PID 1649 | 2017-04-28 17:37:29.317 plugin-container[1650:10350] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 17:37:29 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:29 INFO - PID 1649 | 2017-04-28 17:37:29.318 plugin-container[1650:10350] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 17:37:29 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:29 INFO - PID 1649 | 2017-04-28 17:37:29.318 plugin-container[1650:10350] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:37:39 INFO - PID 1649 | RSS: Main: 252477440 17:37:39 INFO - PID 1649 | 17:37:42 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 17:37:43 INFO - PID 1649 | RSS: Main: 259616768 17:37:43 INFO - PID 1649 | 17:37:45 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 17:37:46 INFO - PID 1649 | RSS: Main: 266485760 17:37:46 INFO - PID 1649 | 17:37:50 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 17:37:50 INFO - PID 1649 | RSS: Main: 262873088 17:37:50 INFO - PID 1649 | 17:37:52 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 17:37:53 INFO - PID 1649 | RSS: Main: 263720960 17:37:53 INFO - PID 1649 | 17:37:55 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:37:56 INFO - PID 1649 | RSS: Main: 265474048 17:37:56 INFO - PID 1649 | 17:37:58 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 17:37:59 INFO - PID 1649 | RSS: Main: 270934016 17:37:59 INFO - PID 1649 | 17:38:02 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 17:38:03 INFO - PID 1649 | RSS: Main: 271904768 17:38:03 INFO - PID 1649 | 17:38:06 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 17:38:07 INFO - PID 1649 | RSS: Main: 271794176 17:38:07 INFO - PID 1649 | 17:38:08 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:38:09 INFO - PID 1649 | RSS: Main: 271515648 17:38:09 INFO - PID 1649 | 17:38:11 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:38:11 INFO - PID 1649 | RSS: Main: 271556608 17:38:11 INFO - PID 1649 | 17:38:13 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:38:14 INFO - PID 1649 | RSS: Main: 271233024 17:38:14 INFO - PID 1649 | 17:38:17 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:38:18 INFO - PID 1649 | RSS: Main: 271544320 17:38:18 INFO - PID 1649 | 17:38:21 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:38:21 INFO - PID 1649 | RSS: Main: 271405056 17:38:21 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 17:38:23 INFO - PID 1649 | RSS: Main: 272871424 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | __start_tp_report 17:38:23 INFO - PID 1649 | _x_x_mozilla_page_load 17:38:23 INFO - PID 1649 | _x_x_mozilla_page_load_details 17:38:23 INFO - PID 1649 | |i|pagename|runs| 17:38:23 INFO - PID 1649 | |0;ai-astar;105;168;125;114;114;114;115;115;114;115 17:38:23 INFO - PID 1649 | |1;audio-beat-detection;148;110;129;189;150;113;109;111;134;114 17:38:23 INFO - PID 1649 | |2;audio-dft;207;215;264;267;196;193;201;197;194;233 17:38:23 INFO - PID 1649 | |3;audio-fft;81;57;56;88;84;89;82;73;77;57 17:38:23 INFO - PID 1649 | |4;audio-oscillator;92;70;87;91;106;102;105;78;76;92 17:38:23 INFO - PID 1649 | |5;imaging-gaussian-blur;89;90;89;89;106;90;90;82;78;109 17:38:23 INFO - PID 1649 | |6;imaging-darkroom;142;132;97;128;97;98;98;96;96;105 17:38:23 INFO - PID 1649 | |7;imaging-desaturate;92;93;98;92;93;107;149;136;144;89 17:38:23 INFO - PID 1649 | |8;json-parse-financial;51;52;50;51;52;50;52;51;53;51 17:38:23 INFO - PID 1649 | |9;json-stringify-tinderbox;55;79;88;60;63;61;61;58;58;65 17:38:23 INFO - PID 1649 | |10;stanford-crypto-aes;83;83;81;83;84;85;84;86;82;84 17:38:23 INFO - PID 1649 | |11;stanford-crypto-ccm;140;138;141;142;153;150;138;140;178;134 17:38:23 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;186;182;185;184;186;184;188;180;185;182 17:38:23 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;65;69;67;70;68;97;105;96;95;102 17:38:23 INFO - PID 1649 | __end_tp_report 17:38:23 INFO - PID 1649 | __start_cc_report 17:38:23 INFO - PID 1649 | _x_x_mozilla_cycle_collect,1008 17:38:23 INFO - PID 1649 | __end_cc_report 17:38:23 INFO - PID 1649 | __startTimestamp1493426303840__endTimestamp 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | ------- Summary: start ------- 17:38:23 INFO - PID 1649 | Number of tests: 14 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:119.90 Median:115.00 stddev:17.55 (15.3%) stddev-sans-first:17.77 17:38:23 INFO - PID 1649 | Values: 105.0 168.0 125.0 114.0 114.0 114.0 115.0 115.0 114.0 115.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:131.50 stddev:25.74 (19.6%) stddev-sans-first:26.53 17:38:23 INFO - PID 1649 | Values: 148.0 110.0 129.0 189.0 150.0 113.0 109.0 111.0 134.0 114.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:216.70 Median:211.00 stddev:28.42 (13.5%) stddev-sans-first:29.93 17:38:23 INFO - PID 1649 | Values: 207.0 215.0 264.0 267.0 196.0 193.0 201.0 197.0 194.0 233.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:74.40 Median:81.50 stddev:13.10 (16.1%) stddev-sans-first:13.67 17:38:23 INFO - PID 1649 | Values: 81.0 57.0 56.0 88.0 84.0 89.0 82.0 73.0 77.0 57.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:89.90 Median:82.50 stddev:12.40 (15.0%) stddev-sans-first:13.12 17:38:23 INFO - PID 1649 | Values: 92.0 70.0 87.0 91.0 106.0 102.0 105.0 78.0 76.0 92.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:89.00 stddev:9.51 (10.7%) stddev-sans-first:10.05 17:38:23 INFO - PID 1649 | Values: 89.0 90.0 89.0 89.0 106.0 90.0 90.0 82.0 78.0 109.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:96.50 stddev:17.84 (18.5%) stddev-sans-first:14.34 17:38:23 INFO - PID 1649 | Values: 142.0 132.0 97.0 128.0 97.0 98.0 98.0 96.0 96.0 105.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:109.30 Median:92.00 stddev:23.96 (26.0%) stddev-sans-first:24.59 17:38:23 INFO - PID 1649 | Values: 92.0 93.0 98.0 92.0 93.0 107.0 149.0 136.0 144.0 89.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:51.50 stddev:0.95 (1.8%) stddev-sans-first:1.00 17:38:23 INFO - PID 1649 | Values: 51.0 52.0 50.0 51.0 52.0 50.0 52.0 51.0 53.0 51.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:62.00 stddev:10.45 (16.9%) stddev-sans-first:10.47 17:38:23 INFO - PID 1649 | Values: 55.0 79.0 88.0 60.0 63.0 61.0 61.0 58.0 58.0 65.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:1.43 (1.7%) stddev-sans-first:1.51 17:38:23 INFO - PID 1649 | Values: 83.0 83.0 81.0 83.0 84.0 85.0 84.0 86.0 82.0 84.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:141.50 stddev:12.78 (9.0%) stddev-sans-first:13.41 17:38:23 INFO - PID 1649 | Values: 140.0 138.0 141.0 142.0 153.0 150.0 138.0 140.0 178.0 134.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.20 Median:185.00 stddev:2.35 (1.3%) stddev-sans-first:2.40 17:38:23 INFO - PID 1649 | Values: 186.0 182.0 185.0 184.0 186.0 184.0 188.0 180.0 185.0 182.0 17:38:23 INFO - PID 1649 | 17:38:23 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.40 Median:69.50 stddev:16.74 (24.1%) stddev-sans-first:16.38 17:38:23 INFO - PID 1649 | Values: 65.0 69.0 67.0 70.0 68.0 97.0 105.0 96.0 95.0 102.0 17:38:23 INFO - PID 1649 | -------- Summary: end -------- 17:38:23 INFO - PID 1649 | 17:38:24 INFO - TEST-INFO | 1649: exit 0 17:38:24 INFO - TEST-OK | kraken | took 61372ms 17:38:24 INFO - SUITE-END | took 1175s 17:38:24 INFO - Completed test suite (00:19:35) 17:38:24 INFO - javascript benchmark 17:38:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7875.742159889773, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2880.337522369855, 2960.9111726648202, 2970.8068975516603, 3056.984715076425, 3631.7639353442023, 6145.12573665032, 6207.037909124082, 6273.278572964105, 6276.905846412307, 6311.936880631204, 6197.318294987548, 6240.344763799803, 6249.656268905208, 6283.528735344856, 6323.20959880015, 5446.400895901447, 5447.0, 5465.72671366432, 5488.91766623499, 5523.309586301712, 5002.524760147799, 5590.944090559104, 5594.356648985357, 5618.690971996539, 5658.74535645897, 4597.402337696111, 5360.383555891083, 5432.6468779529205, 5533.861653458655, 5552.167174923774, 5453.4273901240595, 5454.645448045864, 5473.589480788957, 5503.11950087984, 5525.944740552583, 5471.835844924659, 5494.670319780826, 5511.980283647538, 5513.558915286808, 5515.917261241085, 5211.270422140903, 5960.791372301948, 6309.463695585835, 6316.6841657917375, 6320.873582528324, 6496.870062598721, 6536.869262614769, 6599.043138744855, 6734.7642832501115, 6736.865262694768, 6770.289119642416, 6792.898106528406, 6793.354631310018, 6794.320567943166, 6819.011243369684, 5146.562542183956, 6048.608819971387, 6348.460380867632, 6365.140706004677, 6378.681065946684, 6699.296573859772, 6764.019217213575, 6983.0, 7000.08998830159, 7026.26224246457, 6530.053142294436, 6575.243846957661, 6578.638174900427, 6597.043428702811, 6618.205815302194, 6715.127033485615, 6726.932730672728, 6740.730370785212, 6754.020667003355, 6765.526413151032, 6420.678966051679, 6560.343965603402, 6623.602583844985, 6644.0, 6660.0, 6509.381608747161, 6629.469642428594, 6682.264950855402, 6703.497237707192, 6733.158355205599, 5142.280080788692, 5341.4925582069645, 5353.652012619167, 5377.973110134502, 5380.892382152335, 4741.976290118528, 4749.833755818599, 4756.6908150970075, 4768.260919557474, 4796.904061918812, 5732.398098199712, 6298.212723409574, 6320.336364681642, 6381.042843573501, 6420.261669907927, 6730.125083739083, 6807.183138023469, 6810.353016463428, 6811.352921472452, 6837.760678376233, 6362.872742545123, 6373.012183111625, 6376.872462550723, 6391.07329437229, 6401.423871851555, 5760.07838745807, 5819.185314056035, 5822.214001109881, 5828.213191219131, 5914.674692891932, 6864.107666003388, 6935.895961560581, 6961.825954351182, 7032.648367581703, 7044.964775176091, 6539.182602174728, 6823.112995310578, 6835.487338449537, 6859.725610975505, 6870.862582748217, 6824.488163387865, 6837.760678376233, 6905.274946130584, 7050.083489146378, 7055.1886533049355, 6717.966410167918, 6740.12378390806, 6745.190577130776, 6759.763408280884, 6776.254611992776, 6562.80311590653, 6659.433948114464, 6684.732610695518, 6698.698558564779, 6743.831404214934, 6570.080188773671, 6605.801825945229, 6609.768658096944, 6613.5370524062855, 6618.602883826986, 6604.405603495708, 6606.735730570916, 6619.106420633346, 6619.304972977768, 6625.0, 5801.0, 6308.905366419415, 6322.715477803418, 6371.331010244043, 6426.710798014195, 6580.76967306142, 6581.835454113685, 6607.108040414482, 6629.933700662931, 6647.534672572873, 6418.454431373386, 6425.807225783234, 6451.258105317948, 6452.709628066655, 6453.128827608401], "value": 6111.035672099254, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.3838813307821, 836.2682652678908, 1202.93985300735, 1247.3451437995045, 1252.442663014958, 37807.62192378075, 38598.80700596493, 38605.8418247453, 38734.80632596837, 38832.805835970816, 31697.36605267893, 38077.809610951976, 38528.42207366892, 38562.0, 38640.03399915004, 7855.4893931894385, 8017.799555011142, 8018.238267364591, 8356.415050946436, 8545.778097694612, 8572.914270857274, 8660.350473714465, 8664.48013119215, 8666.52334121623, 8687.869681954775, 8584.699535516258, 8593.183647553473, 8599.355048371397, 8687.131286871325, 8689.0, 8600.56997150143, 8646.135386461303, 8660.0, 8666.046734859161, 8681.913180868174, 8477.364197685198, 8483.44857584255, 8515.787105322355, 8521.786955326104, 8551.033262349632, 37641.87074387773, 37763.62236377656, 37798.866034019025, 37848.81075594604, 37942.81028594867, 3613.33153366628, 3616.18635806944, 3654.3056819204267, 3658.396364599838, 3661.5789184243886, 30994.690053099406, 31029.844850775826, 31039.0, 31134.688653113404, 31210.53184202239, 3076.7077127672837, 3137.7646676499353, 3146.1348129264406, 3152.7162555369896, 3157.6526582075953, 248077.0, 258833.41166588283, 290578.5471072652, 301662.0, 302626.0, 39460.802695986335, 39912.401313980314, 48541.0, 49427.0, 49449.75275123673, 46916.0, 50949.490505095215, 51252.97494050173, 51327.230091548656, 51457.742711286955, 11792.823107653392, 14469.855301447062, 14527.055741376987, 14553.708925821637, 14559.272036398137, 11502.769944601228, 11693.88306116945, 11699.941500292443, 11703.765924681458, 11707.765844683228, 13566.0, 13619.727605448034, 13665.931670341784, 13726.862731372757, 13792.172469651652, 6037.184980027728, 6080.0543825975265, 6097.603655762361, 6105.358937311607, 6109.725062372204, 1035.4874337203132, 1042.3641578637025, 1046.7487802927242, 1069.8930106989394, 1077.8652668416448, 23279.3016209514, 24356.87821560881, 24420.511589768106, 24496.632550511757, 24637.5072498549, 910.8588168833842, 1045.4668119259081, 1138.65271092316, 1170.5785917069848, 1180.3390101543164, 12465.626031219077, 12490.50037998488, 12513.93743031279, 12537.561185358469, 12601.495940162291, 320836.0, 322522.0, 323029.3848530742, 323860.0, 324216.0, 54337.0, 54360.72819635877, 54553.0, 54738.90522189614, 54739.0, 1539.1534655939188, 1543.4443600303882, 1579.8104227492774, 1581.2093953023489, 1596.2098761113323, 24162.63756043696, 24235.39411514656, 24300.51398972046, 24307.635385469584, 24452.75547244576, 39073.21853562856, 39127.0, 39138.0, 39163.41254881122, 39263.60736392599, 285856.0, 286332.0, 286576.0, 287352.0, 287454.0, 34791.82604087065, 34882.825585871906, 35617.0, 35621.0, 35622.82188589041, 43857.78071109624, 44601.0, 45974.0, 46186.84532886705, 46411.30383044325, 29754.851225743732, 30382.54426183653, 30616.540751888293, 30683.926062587703, 31688.04935851928, 30720.23199419943, 30823.383532329677, 31227.907023254076, 31662.525062124547, 31744.841275793475, 29324.56013159847, 36950.07624809294, 37560.0, 37628.81185594055, 37673.43489847709, 29688.406231875673, 30757.769689212182, 31644.05067847968, 31846.0, 31904.6809531911, 17765.911170444066, 22326.0, 24686.80767358438, 24931.002759890125, 25023.87488062548], "value": 17688.788892247903, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.77809536182934, 373.88409593026165, 381.1937751655248, 435.41654183394246, 436.3389464960587, 18425.723614145798, 21264.787352126474, 21570.460738481546, 22013.449663758412, 22023.678579285235, 21752.673709894363, 21848.672269915965, 21951.0, 22057.22799702007, 22271.440999130067, 5900.88198236035, 6149.2313460817395, 6152.815415537541, 6154.415330543591, 6165.136880836664, 5905.822825315226, 6039.909401358983, 6066.150738896558, 6094.451499365056, 6097.481714054288, 5941.197938278342, 6029.517638588903, 6032.215811944463, 6046.213992181032, 6050.789842031611, 5897.849919265724, 5948.97025514874, 5949.583529152939, 5978.551608629348, 6124.142620033199, 5365.7853685852515, 5409.83770486886, 5423.403425623178, 5428.45715428458, 5490.396056433789, 21709.891450542804, 25888.0, 26390.86804565984, 26505.337366565796, 26625.0, 2254.0, 2269.931902042941, 2305.0318866076286, 2305.9423514412274, 2312.791848733605, 14724.484643037442, 17211.05339206343, 17983.280668773234, 18160.364387246384, 18243.908780456146, 2129.9148034078617, 2139.3261122746285, 2172.7718589548026, 2189.6606026065824, 2190.9561808763738, 124400.88997775129, 125944.0, 133028.0, 142860.0, 145519.0, 10339.741506462247, 12230.571929982409, 12575.308358040396, 12626.116171867881, 12738.426770795337, 16395.918020409823, 16462.75305870413, 16464.753028704577, 16465.91767041157, 16490.257938392802, 7453.0, 8797.648094076165, 9012.233960113464, 9077.137671921157, 9158.809354783836, 8488.660453581911, 8490.0, 8576.785580360542, 8749.825003499895, 8794.03265640779, 7159.498835081598, 7316.0, 7778.183290754502, 7880.0, 7958.612416275094, 2213.7786221378055, 2219.966700499494, 2223.8888055597154, 2227.855189412683, 2239.451334423077, 991.1971303244395, 992.6327258914248, 996.9052939970691, 1017.1760873692185, 1041.557338131291, 21153.68269475959, 21434.356969290948, 21556.353309400743, 21565.78434215669, 21609.891950540146, 974.2985050763439, 1029.7940411917646, 1052.9105026072716, 1060.188955449082, 1072.1101485766794, 9844.163246124159, 11246.66260012201, 11268.323900565993, 11269.154813389028, 11290.266132701348, 561768.0, 562796.0, 572223.0, 577610.3358449626, 577822.0, 134881.0, 140550.0, 145525.0, 146864.0, 176584.11707941635, 934.6074648647584, 1049.506731836036, 1142.9199956003167, 1215.3862299538853, 1219.231883913129, 20311.898440508296, 21181.6822747662, 21680.699158050567, 22392.888035559718, 22448.877556121803, 46724.0, 61840.38159618346, 62556.12331630058, 63014.36985630085, 63421.0, 83502.0, 85656.14343856482, 86314.56842715746, 86594.56702716446, 86863.39409817716, 7577.5453472791805, 8843.292536597186, 8916.465012099296, 9039.638414463348, 9185.0, 14393.280335983369, 14583.343749531508, 14611.269436528344, 14906.925465372604, 14994.625134371729, 12120.818187727367, 12162.878371216175, 12207.633770986884, 12352.814707779571, 12369.319687416919, 11104.0, 11375.658730238107, 11391.829122563002, 11417.543298268309, 11626.70933226676, 9889.653862114792, 12065.638030859089, 12479.939205167298, 12561.057920655805, 12607.684807879876, 8966.596503157243, 9032.412893161923, 9109.18017378439, 9218.723438296862, 9301.86047209306, 11721.355325457182, 12180.57367992116, 12215.045592705124, 12315.322657253937, 12358.44387002605], "value": 10509.622423302908, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1243.97512049759, 1269.3462866623684, 1391.2695834686795, 1452.3174108169164, 1454.7526920423527, 11258.98669119779, 11723.120765942567, 11807.055435565166, 11850.229735067214, 12015.399230038507, 9783.608655653765, 10405.167586593074, 10791.676249712502, 11887.22733022353, 11937.820932686016, 2224.021430570544, 2249.955000899981, 2256.17649557911, 2263.0721404224273, 2264.2641141628956, 2201.328594778592, 2234.1845226492296, 2246.4608493961414, 2248.347979086063, 2251.1108112295633, 1861.9906900465478, 2209.5028618560777, 2229.1752051740878, 2248.4266512039408, 2264.864108153508, 2177.6951226828255, 2190.3647942096773, 2210.8562943408706, 2230.3718285651485, 2237.328801359602, 1852.8517718582482, 1929.0740444586536, 2124.7662757096705, 2194.3965409512352, 2205.7683943186057, 11921.225120367235, 11974.940125299405, 12055.758884822255, 12060.216085954386, 12084.697882552919, 6564.442022428099, 6811.386975172259, 6815.284395138488, 6816.488763342769, 6823.658817059128, 4038.515378154611, 4344.695871289011, 4415.867523974253, 4430.24685803411, 4432.002799370148, 1233.8519377674736, 1244.2845363915694, 1254.91215614907, 1265.9998601105208, 1277.948882044717, 13304.933475332562, 15091.471798487, 16571.751423728652, 16761.161941902858, 16764.99410035402, 4715.363425937455, 5381.408045115034, 5409.026375252413, 5612.579056570692, 5702.914456283159, 9159.587818548182, 10034.0, 11722.355270460206, 11942.223755455867, 11942.462589183508, 6591.143151390289, 6624.503162262849, 6629.0, 6644.601323920484, 6647.0, 6102.267727872595, 6123.601965872204, 6125.01999680058, 6220.626762394271, 6244.1570387997945, 3975.7216994810087, 4157.293260145782, 4319.006628475406, 4429.955700442954, 4447.73313601185, 1255.9434825432877, 1265.5570550307318, 1281.8654041325526, 1291.6577107066635, 1296.4425297122327, 720.2869159532025, 773.7562667759638, 801.5831767480994, 803.8593246181895, 805.8670319397233, 5920.822375328747, 6510.21877374718, 6701.463882889358, 6775.593464392152, 7033.648317584202, 865.8441480533564, 940.4075432477632, 945.550863339908, 951.3340661536952, 969.8205831921117, 3277.4264503711756, 3811.333016722118, 3872.244912242142, 3893.6690381317903, 3934.3311637021775, 14564.781528277084, 14753.77869331961, 15199.392024318904, 15245.23773811305, 15459.91780575349, 14955.327010284564, 15095.622609434633, 15266.847331526764, 15278.388864445298, 15291.235438228045, 8640.395172338001, 8653.048164701877, 8829.955850220707, 8852.778680532909, 8875.689350872688, 7103.396211321889, 7203.927960720535, 7263.636818159177, 7268.672909719181, 7313.634318284171, 13351.399187036412, 13450.663733406744, 13673.0, 13685.521006764715, 13697.972652050938, 13120.60638180856, 13845.0, 13950.302484875918, 14035.438582456587, 14040.578782636538, 7768.456208065381, 8227.0, 8495.787605309917, 8597.054324024477, 9333.439993600407, 10834.02493775564, 10863.076638485818, 10919.344839309666, 11001.394923279297, 11013.614523491638, 9307.963634557, 9331.58007889633, 9384.108509691569, 9393.295502837178, 9432.575534100844, 9010.189082982562, 9128.589213485277, 9218.032106628707, 9231.676891308773, 9312.301577381859, 8076.0, 8273.793155171168, 8306.83386332258, 8395.24442800151, 8439.409241353047, 4909.288153217834, 4980.8754781130765, 5334.306540149756, 6075.848103797441, 6134.110553969738, 5483.533899618577, 5947.851303717442, 6074.240719910012, 6288.654124023223, 6414.743410263537], "value": 5251.608397278338, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.9857204997826, 414.4384359193293, 417.46772864597654, 419.797280790535, 426.3923908430486, 6171.845703857406, 6216.906746398802, 6308.621482711041, 6321.715522801479, 6326.145970294009, 6014.969925150368, 6130.8160755177305, 6159.168512250845, 6193.659348735829, 6219.533534984872, 4346.8913277168, 4465.9106817863785, 4468.061707041526, 4469.374287599739, 4479.484859241196, 3665.981670091646, 3677.9080522986874, 4451.844185453509, 4470.524726840151, 4498.627918484861, 4019.954811748937, 4299.5485474025245, 4440.533743956887, 4442.62237709795, 4442.689011769161, 4376.803043863018, 4398.296272596369, 4450.710703804242, 4469.128519938613, 4472.53038430965, 3259.364423937333, 3262.722668573174, 3780.2250538639514, 3810.942835857464, 3836.865709700147, 6017.849553761147, 6029.60807547508, 6041.305249896274, 6042.516598672096, 6078.149059131725, 5190.351206099238, 5240.737963101829, 5301.7614207360375, 5309.62832601718, 5320.17537281722, 6536.934630653727, 6544.607323560554, 6553.410193082598, 6566.343365663443, 6572.507061970371, 4166.7708276044805, 4244.448221731186, 4278.42241297424, 4317.654587632982, 4319.935200971988, 6762.661866906635, 6912.412444942186, 6969.128858892638, 6972.1284839395075, 7059.788206353817, 4312.698111132253, 4348.804078878334, 4359.713884404045, 4360.236958532308, 4372.540883207217, 4428.7121337112985, 4430.82276708928, 4432.756198409054, 4435.556444355539, 4447.822087116479, 4817.518248175154, 5146.047981123429, 5229.529342359206, 5282.788688452419, 5294.914542518774, 6078.392160783975, 6394.8721025579225, 6619.205695316593, 6659.134312539436, 6690.632015239111, 1493.7834014067898, 1516.98362097395, 1517.7419838627238, 1518.1270769307691, 1519.187234829361, 878.8286284174652, 882.4705176893813, 970.1123472023161, 977.5796407544682, 990.2672022703292, 5225.771943593329, 5258.030187208709, 5545.724483368847, 6319.368063193644, 6345.270293916166, 6073.18012068374, 6734.966325168343, 6882.7591034313555, 6891.655417229119, 6902.827429314308, 5861.8276344730375, 6013.51891848651, 6313.40022697843, 6325.810225693144, 6364.0, 3280.9343813123605, 3486.285311511185, 3583.7133029357587, 3601.1717305019993, 3615.096225943514, 6422.389872962061, 6439.937410327244, 6478.708458119396, 7015.684294206773, 7108.786736397917, 5536.529395001389, 6417.326180750954, 6954.478414118961, 7052.400545953549, 7062.585883822611, 6085.421884920926, 6889.896651550231, 7121.0, 7127.2160062393095, 7143.571385716874, 5749.798757043483, 6335.632689147377, 6436.67816609177, 6900.412345370486, 6936.514443988873, 5733.541316694738, 6202.2552747147565, 6445.0, 6792.132163655035, 6810.421114205348, 6131.988221943329, 7002.229754727078, 7002.894956575554, 7025.578465292099, 7063.82340441493, 4327.826886924614, 4350.542568239549, 4358.389825424507, 4361.912761744811, 4366.214081465239, 6763.560368576027, 6778.694958726967, 6828.214755303005, 6863.529129513471, 6896.551724137858, 5691.4877661010705, 5717.913596416667, 6099.9390006099375, 6568.540202185801, 6597.340265973365, 5406.324209473816, 5530.91703624454, 5798.8692205019615, 6490.415862572202, 6500.512461565307, 6472.611643301417, 6504.894167991358, 6519.4132528072705, 6525.857974854286, 6532.738690452328, 6441.871162576628, 6565.868682626417, 6590.604563726, 6614.404703576702, 6649.567778094409, 5943.7116710066075, 6038.9129956606475, 6072.908906366496, 6428.4857211423905, 6464.4828413725845], "value": 4755.584087309207, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.383853773797, 841.1756478650926, 848.6562942008485, 878.639757699343, 881.2113158722943, 16288.83711162888, 16665.166741662928, 16709.081000544982, 18309.542261443472, 19374.321898733546, 17901.0, 18200.726989095176, 18251.726224106616, 18486.630267394645, 18562.907185464086, 994.4828689081673, 1008.8890222075603, 1012.3622118065608, 1014.5130337438034, 1019.4851599942019, 947.8578213268031, 1011.4790882695418, 1019.938803671782, 1035.9585616575366, 1043.911267542256, 1081.0810810810788, 1082.767205050918, 1083.3824719909614, 1083.9187060970419, 1084.9457527123652, 1013.4375421640949, 1046.5813674530211, 1073.9677809665723, 1075.876274228458, 1085.0831047764627, 776.47587878182, 796.4464697035562, 798.4251339035885, 801.8356236971407, 810.1007881251805, 16688.83311166897, 16889.66220675592, 16965.57586060346, 17368.218430170673, 17380.739288910678, 17191.312347506086, 17281.740773888403, 17568.472945811518, 17650.91174544132, 17692.734608980874, 14034.85965140356, 14276.857231427657, 14330.498432554914, 14448.277586120652, 14464.783028254584, 2446.8531888086595, 2844.5164322065093, 2853.2296280004284, 2856.7428931396275, 2866.1974647098846, 116965.0, 118009.0, 128623.35688321592, 138875.30562347226, 144263.5573644271, 13137.014723895745, 13482.39329230187, 13515.729685406433, 13617.931910340385, 13655.04414690962, 18334.266629334677, 24562.0, 25131.62302565463, 25334.619980700303, 26035.479290414085, 7733.535987840747, 7953.960230198928, 7969.322607578305, 7991.400644951652, 7993.040835099709, 3947.5657677655436, 3967.8809635710977, 3986.7807270599815, 4023.3361495353524, 4031.0728532437115, 4276.631477927096, 4376.081022985145, 4381.824727010884, 4438.356438316426, 4445.066536027406, 939.6335429182624, 961.3510880156007, 971.1939090554822, 972.4408465132577, 980.8185485685028, 655.7377049180366, 673.2863165045068, 673.441043933534, 679.6771533521538, 692.6398272898065, 11370.545178192946, 11809.763804724029, 12048.33734144613, 12097.818532722016, 12292.323922184187, 650.7375691159136, 659.2517492645882, 660.3066779881106, 692.2281655953575, 697.1146643762394, 5418.241446197536, 5624.943750562524, 5644.689542075143, 5654.095344744904, 5729.997250481149, 107228.0, 108128.0, 108365.54417273628, 108797.0, 110232.0, 85297.57351213205, 85764.57117714372, 85890.0, 86894.0, 97966.0, 69477.0, 69813.0, 71043.2895671047, 71097.6445117771, 71129.2887071133, 12127.636170914886, 12443.937780311402, 12475.875241247835, 12534.623961281177, 12574.811377829523, 33026.17434564155, 36339.27321453609, 39477.60522394854, 39625.603743962194, 40180.0, 62124.37875621186, 65486.36284092745, 66663.0, 66917.1544192138, 68020.0, 16647.83352166463, 16649.91675041617, 16814.57963550921, 16931.746023809406, 16939.781441526564, 16104.67790644204, 17922.014289214218, 18247.178876950835, 18421.90789046046, 18435.446936591925, 11691.064714822964, 16450.0, 16898.15509224509, 16968.49094527166, 17096.48710538686, 16077.517674469784, 16213.918930405272, 16820.747688784923, 16863.15684215809, 16890.831091688924, 13923.721525569634, 15656.765148523009, 15947.840521595099, 16490.34038638441, 16566.923149995695, 11860.0, 11982.281063136239, 12047.216930899463, 12150.21023633461, 12181.025517958718, 12255.938720306698, 12711.872881271438, 12916.160449570747, 12928.806067908801, 12944.741105177656], "value": 8410.483713172667, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1553.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 168.0, 125.0, 114.0, 114.0, 114.0, 115.0, 115.0, 114.0, 115.0], "value": 119.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 110.0, 129.0, 189.0, 150.0, 113.0, 109.0, 111.0, 134.0, 114.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 215.0, 264.0, 267.0, 196.0, 193.0, 201.0, 197.0, 194.0, 233.0], "value": 216.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 57.0, 56.0, 88.0, 84.0, 89.0, 82.0, 73.0, 77.0, 57.0], "value": 74.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 87.0, 91.0, 106.0, 102.0, 105.0, 78.0, 76.0, 92.0], "value": 89.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 89.0, 89.0, 106.0, 90.0, 90.0, 82.0, 78.0, 109.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 132.0, 97.0, 128.0, 97.0, 98.0, 98.0, 96.0, 96.0, 105.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 98.0, 92.0, 93.0, 107.0, 149.0, 136.0, 144.0, 89.0], "value": 109.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 50.0, 51.0, 52.0, 50.0, 52.0, 51.0, 53.0, 51.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 79.0, 88.0, 60.0, 63.0, 61.0, 61.0, 58.0, 58.0, 65.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 81.0, 83.0, 84.0, 85.0, 84.0, 86.0, 82.0, 84.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 141.0, 142.0, 153.0, 150.0, 138.0, 140.0, 178.0, 134.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 185.0, 184.0, 186.0, 184.0, 188.0, 180.0, 185.0, 182.0], "value": 184.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 67.0, 70.0, 68.0, 97.0, 105.0, 96.0, 95.0, 102.0], "value": 83.4, "unit": "score"}]}]} 17:38:25 INFO - Return code: 0 17:38:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:38:25 ERROR - # TBPL SUCCESS # 17:38:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:38:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:38:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:38:25 INFO - 2017-04-28 17:38:25.150 screenresolution[1654:10744] starting screenresolution argv=screenresolution get 17:38:25 INFO - 2017-04-28 17:38:25.152 screenresolution[1654:10744] Display 0: 1600x1200x32@60 17:38:25 INFO - 2017-04-28 17:38:25.158 screenresolution[1655:10747] starting screenresolution argv=screenresolution list 17:38:25 INFO - Available Modes on Display 0 17:38:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:38:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:38:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:38:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:38:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:38:25 INFO - Intel Iris: 17:38:25 INFO - Chipset Model: Intel Iris 17:38:25 INFO - Type: GPU 17:38:25 INFO - Bus: Built-In 17:38:25 INFO - VRAM (Dynamic, Max): 1536 MB 17:38:25 INFO - Vendor: Intel (0x8086) 17:38:25 INFO - Device ID: 0x0a2e 17:38:25 INFO - Revision ID: 0x0009 17:38:25 INFO - Displays: 17:38:25 INFO - Display: 17:38:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:38:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:38:25 INFO - Display Serial Number: 1600x1200 60 17:38:25 INFO - Main Display: Yes 17:38:25 INFO - Mirror: Off 17:38:25 INFO - Online: Yes 17:38:25 INFO - Rotation: Supported 17:38:25 INFO - Return code: 0 17:38:25 INFO - Running post-action listener: _resource_record_post_action 17:38:25 INFO - [mozharness: 2017-04-29 00:38:25.403468Z] Finished run-tests step (success) 17:38:25 INFO - Running post-run listener: _resource_record_post_run 17:38:25 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 39074304; Write bytes: 510336512; Read time: 389; Write time: 1544 17:38:25 INFO - TinderboxPrint: CPU usage
54.1% 17:38:25 INFO - TinderboxPrint: I/O read bytes / time
39,074,304 / 389 17:38:25 INFO - TinderboxPrint: I/O write bytes / time
510,336,512 / 1,544 17:38:25 INFO - TinderboxPrint: CPU idle
2,194.1 (45.9%) 17:38:25 INFO - TinderboxPrint: CPU system
958.9 (20.1%) 17:38:25 INFO - TinderboxPrint: CPU user
1,628.6 (34.1%) 17:38:25 INFO - TinderboxPrint: Swap in / out
247,791,616 / 0 17:38:25 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 195416576; Write bytes: 195983360; Read time: 14977; Write time: 401 17:38:25 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38103552; Write bytes: 297510400; Read time: 372; Write time: 1094 17:38:25 INFO - Running post-run listener: _upload_blobber_files 17:38:25 INFO - Blob upload gear active. 17:38:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:38:25 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 17:38:25 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'] 17:38:25 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 17:38:26 INFO - (blobuploader) - INFO - Open directory for files ... 17:38:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:38:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:38:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:27 INFO - (blobuploader) - INFO - Done attempting. 17:38:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:38:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:38:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:28 INFO - (blobuploader) - INFO - Done attempting. 17:38:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:38:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:38:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:30 INFO - (blobuploader) - INFO - Done attempting. 17:38:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:38:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:38:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:32 INFO - (blobuploader) - INFO - Done attempting. 17:38:32 INFO - (blobuploader) - INFO - Iteration through files over. 17:38:32 INFO - Return code: 0 17:38:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:38:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:38:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ac52092d1f431facd8c0ca77763db40a18da0c6faab62b3ad125f22518e893bf1dbb353a1bdf2011fd2781d0f18536f77c1d966b2de51d1918c10ad6da1fee1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012d6a84f9816d4c8324c675f94fcb410b624b28ed65c6ad61409bd089e8bdca4664e1e56d022af971039a4176dacec3ac974a450f053dd0d0daa273c43b4782", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe3ea40a8dd47e7b9dc87cd8940cbcbb36fdcee8e3461b8aa220af5fd648acbc6d3bbd388968b56eafd61cb6962ecf4f556f3716a9de47d396f28df03022a683", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/877d7beecd46c04a91ddcabea0189bef8c1f7640ec920704a6b4a006e79fb5b8f0076d399dde6db64e40b239d87fdd8cc7ac5995c623fee803683a81947f05f5"} 17:38:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:38:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:38:32 INFO - Contents: 17:38:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ac52092d1f431facd8c0ca77763db40a18da0c6faab62b3ad125f22518e893bf1dbb353a1bdf2011fd2781d0f18536f77c1d966b2de51d1918c10ad6da1fee1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012d6a84f9816d4c8324c675f94fcb410b624b28ed65c6ad61409bd089e8bdca4664e1e56d022af971039a4176dacec3ac974a450f053dd0d0daa273c43b4782", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe3ea40a8dd47e7b9dc87cd8940cbcbb36fdcee8e3461b8aa220af5fd648acbc6d3bbd388968b56eafd61cb6962ecf4f556f3716a9de47d396f28df03022a683", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/877d7beecd46c04a91ddcabea0189bef8c1f7640ec920704a6b4a006e79fb5b8f0076d399dde6db64e40b239d87fdd8cc7ac5995c623fee803683a81947f05f5"} 17:38:32 INFO - Running post-run listener: copy_logs_to_upload_dir 17:38:32 INFO - Copying logs to upload dir... 17:38:32 INFO - mkdir: /builds/slave/test/build/upload/logs 17:38:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.696540 ========= master_lag: 2.82 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-28 17:38:34.986566) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:38:34.990087) ========= 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.WVhjnI8C42/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.Te7sL8tLe0/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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/5ac52092d1f431facd8c0ca77763db40a18da0c6faab62b3ad125f22518e893bf1dbb353a1bdf2011fd2781d0f18536f77c1d966b2de51d1918c10ad6da1fee1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012d6a84f9816d4c8324c675f94fcb410b624b28ed65c6ad61409bd089e8bdca4664e1e56d022af971039a4176dacec3ac974a450f053dd0d0daa273c43b4782", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe3ea40a8dd47e7b9dc87cd8940cbcbb36fdcee8e3461b8aa220af5fd648acbc6d3bbd388968b56eafd61cb6962ecf4f556f3716a9de47d396f28df03022a683", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/877d7beecd46c04a91ddcabea0189bef8c1f7640ec920704a6b4a006e79fb5b8f0076d399dde6db64e40b239d87fdd8cc7ac5995c623fee803683a81947f05f5"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011090 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1493391200/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5ac52092d1f431facd8c0ca77763db40a18da0c6faab62b3ad125f22518e893bf1dbb353a1bdf2011fd2781d0f18536f77c1d966b2de51d1918c10ad6da1fee1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/012d6a84f9816d4c8324c675f94fcb410b624b28ed65c6ad61409bd089e8bdca4664e1e56d022af971039a4176dacec3ac974a450f053dd0d0daa273c43b4782", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fe3ea40a8dd47e7b9dc87cd8940cbcbb36fdcee8e3461b8aa220af5fd648acbc6d3bbd388968b56eafd61cb6962ecf4f556f3716a9de47d396f28df03022a683", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/877d7beecd46c04a91ddcabea0189bef8c1f7640ec920704a6b4a006e79fb5b8f0076d399dde6db64e40b239d87fdd8cc7ac5995c623fee803683a81947f05f5"}' ========= master_lag: 0.38 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-28 17:38:35.377791) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:38:35.378119) ========= 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.WVhjnI8C42/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.Te7sL8tLe0/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.005024 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-28 17:38:35.406399) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:38:35.406666) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-28 17:38:35.406957) ========= ========= Total master_lag: 3.29 =========