builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0270 starttime: 1492078848.86 results: success (0) buildid: 20170412201651 builduid: af536461de42444fa39adfc9e279b3aa revision: bedd7990a3fdc0d1bf4121b788accdf2c35435e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:48.862697) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:48.863175) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:48.863479) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:48.888833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:48.889228) ========= 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.tJidG6fSds/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.4puwtSYsHF/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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-13 03:20:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 147M=0s 2017-04-13 03:20:49 (147 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125147 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:49.039685) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:49.040229) ========= 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.tJidG6fSds/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.4puwtSYsHF/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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.021929 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:49.090211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 03:20:49.090570) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev bedd7990a3fdc0d1bf4121b788accdf2c35435e9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev bedd7990a3fdc0d1bf4121b788accdf2c35435e9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tJidG6fSds/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.4puwtSYsHF/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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-13 03:20:49,633 truncating revision to first 12 chars 2017-04-13 03:20:49,633 Setting DEBUG logging. 2017-04-13 03:20:49,633 attempt 1/10 2017-04-13 03:20:49,633 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/bedd7990a3fd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 03:20:51,372 unpacking tar archive at: pine-bedd7990a3fd/testing/mozharness/ program finished with exit code 0 elapsedTime=2.005485 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 03:20:51.119456) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:51.119799) ========= script_repo_revision: bedd7990a3fdc0d1bf4121b788accdf2c35435e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:51.120189) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:51.120493) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 03:20:51.140513) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-13 03:20:51.140896) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tJidG6fSds/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.4puwtSYsHF/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:20:51 INFO - MultiFileLogger online at 20170413 03:20:51 in /builds/slave/test 03:20:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 03:20:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:20:51 INFO - {'append_to_log': False, 03:20:51 INFO - 'base_work_dir': '/builds/slave/test', 03:20:51 INFO - 'blob_upload_branch': 'Pine', 03:20:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:20:51 INFO - 'branch': 'Pine', 03:20:51 INFO - 'buildbot_json_path': 'buildprops.json', 03:20:51 INFO - 'config_files': ('talos/mac_config.py',), 03:20:51 INFO - 'default_actions': ('clobber', 03:20:51 INFO - 'read-buildbot-config', 03:20:51 INFO - 'download-and-extract', 03:20:51 INFO - 'populate-webroot', 03:20:51 INFO - 'create-virtualenv', 03:20:51 INFO - 'install', 03:20:51 INFO - 'run-tests'), 03:20:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:20:51 INFO - 'download_minidump_stackwalk': True, 03:20:51 INFO - 'download_symbols': 'ondemand', 03:20:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:20:51 INFO - 'tooltool.py': '/tools/tooltool.py', 03:20:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:20:51 INFO - '/tools/misc-python/virtualenv.py')}, 03:20:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:20:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:20:51 INFO - 'gecko_profile': False, 03:20:51 INFO - 'gecko_profile_interval': 0, 03:20:51 INFO - 'installer_path': 'installer.exe', 03:20:51 INFO - 'log_level': 'info', 03:20:51 INFO - 'log_name': 'talos', 03:20:51 INFO - 'log_to_console': True, 03:20:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:20:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:20:51 INFO - 'opt_config_files': (), 03:20:51 INFO - 'pip_index': False, 03:20:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:51 INFO - 'cmd': ('bash', 03:20:51 INFO - '-c', 03:20:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:51 INFO - 'enabled': True, 03:20:51 INFO - 'halt_on_failure': False, 03:20:51 INFO - 'name': 'check_screen_resolution'},), 03:20:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:20:51 INFO - 'cmd': ('bash', 03:20:51 INFO - '-c', 03:20:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:20:51 INFO - 'enabled': True, 03:20:51 INFO - 'halt_on_failure': False, 03:20:51 INFO - 'name': 'check_screen_resolution'},), 03:20:51 INFO - 'run_cmd_checks_enabled': True, 03:20:51 INFO - 'suite': 'dromaeojs-e10s', 03:20:51 INFO - 'system_bits': '32', 03:20:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:20:51 INFO - 'title': 't-yosemite-r7-0270', 03:20:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:20:51 INFO - 'use_talos_json': True, 03:20:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:20:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:20:51 INFO - 'work_dir': 'build'} 03:20:51 INFO - [mozharness: 2017-04-13 10:20:51.807132Z] Running clobber step. 03:20:51 INFO - Running pre-action listener: _resource_record_pre_action 03:20:51 INFO - Running main action method: clobber 03:20:51 INFO - rmtree: /builds/slave/test/build 03:20:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:20:53 INFO - Running post-action listener: _resource_record_post_action 03:20:53 INFO - [mozharness: 2017-04-13 10:20:53.882274Z] Finished clobber step (success) 03:20:53 INFO - [mozharness: 2017-04-13 10:20:53.882442Z] Running read-buildbot-config step. 03:20:53 INFO - Running pre-action listener: _resource_record_pre_action 03:20:53 INFO - Running main action method: read_buildbot_config 03:20:53 INFO - Using buildbot properties: 03:20:53 INFO - { 03:20:53 INFO - "project": "", 03:20:53 INFO - "product": "firefox", 03:20:53 INFO - "script_repo_revision": "default", 03:20:53 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 03:20:53 INFO - "repository": "", 03:20:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 03:20:53 INFO - "buildid": "20170412201651", 03:20:53 INFO - "stage_platform": "macosx64", 03:20:53 INFO - "basedir": "/builds/slave/test", 03:20:53 INFO - "buildnumber": 1, 03:20:53 INFO - "platform": "yosemite_r7", 03:20:53 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 03:20:53 INFO - "slavebuilddir": "test", 03:20:53 INFO - "branch": "pine", 03:20:53 INFO - "revision": "bedd7990a3fdc0d1bf4121b788accdf2c35435e9", 03:20:53 INFO - "slavename": "t-yosemite-r7-0270", 03:20:53 INFO - "builduid": "af536461de42444fa39adfc9e279b3aa", 03:20:53 INFO - "repo_path": "projects/pine" 03:20:53 INFO - } 03:20:53 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.dmg. 03:20:53 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.test_packages.json. 03:20:53 INFO - Running post-action listener: _resource_record_post_action 03:20:53 INFO - [mozharness: 2017-04-13 10:20:53.885742Z] Finished read-buildbot-config step (success) 03:20:53 INFO - [mozharness: 2017-04-13 10:20:53.885859Z] Running download-and-extract step. 03:20:53 INFO - Running pre-action listener: _resource_record_pre_action 03:20:53 INFO - Running main action method: download_and_extract 03:20:53 INFO - mkdir: /builds/slave/test/build/tests 03:20: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')]} 03:20:53 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.test_packages.json 03:20:53 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:20:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/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 03:20:54 INFO - Downloaded 1325 bytes. 03:20:54 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 03:20:54 INFO - Using the following test package requirements: 03:20:54 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 03:20:54 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:20:54 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 03:20:54 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 03:20:54 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:20:54 INFO - u'jsshell-mac.zip'], 03:20:54 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 03:20:54 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 03:20:54 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 03:20:54 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 03:20:54 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 03:20:54 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 03:20:54 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 03:20:54 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:20:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.common.tests.zip 03:20:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:20:54 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:20:55 INFO - Expected file size: 52593708 03:20:55 INFO - Obtained file size: 52593708 03:21:12 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 03:21:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.common.tests.zip 03:21:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:21:12 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.common.tests.zip into memory 03:21:13 INFO - Expected file size: 52593708 03:21:13 INFO - Obtained file size: 52593708 03:21:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.talos.tests.zip 03:21:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:21:28 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 03:21:29 INFO - Expected file size: 14405596 03:21:29 INFO - Obtained file size: 14405596 03:21:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:29 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.dmg 03:21:29 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 03:21:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 03:21:30 INFO - Downloaded 71024089 bytes. 03:21:30 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.dmg 03:21:30 INFO - mkdir: /builds/slave/test/properties 03:21:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:21:30 INFO - Writing to file /builds/slave/test/properties/build_url 03:21:30 INFO - Contents: 03:21:30 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.dmg 03:21:31 INFO - Running post-action listener: _resource_record_post_action 03:21:31 INFO - Running post-action listener: set_extra_try_arguments 03:21:31 INFO - [mozharness: 2017-04-13 10:21:31.276587Z] Finished download-and-extract step (success) 03:21:31 INFO - [mozharness: 2017-04-13 10:21:31.276711Z] Running populate-webroot step. 03:21:31 INFO - Running pre-action listener: _resource_record_pre_action 03:21:31 INFO - Running main action method: populate_webroot 03:21:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:21:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:21:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:21:31 INFO - '--tppagecycles', 03:21:31 INFO - '7'], 03:21:31 INFO - 'tests': ['tsvgm']}}, 03:21:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:21:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:21:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:21:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:21:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:21:31 INFO - 'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:21:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:21:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:21:31 INFO - 'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['damp', 'tps']}, 03:21:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:21:31 INFO - 'tests': ['damp', 'tps']}, 03:21:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['dromaeo_dom']}, 03:21:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:21:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['basic_compositor_video']}, 03:21:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:21:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['a11yr', 03:21:31 INFO - 'ts_paint', 03:21:31 INFO - 'tpaint', 03:21:31 INFO - 'sessionrestore', 03:21:31 INFO - 'sessionrestore_no_auto_restore', 03:21:31 INFO - 'tabpaint']}, 03:21:31 INFO - 'other-e10s': {'tests': ['a11yr', 03:21:31 INFO - 'ts_paint', 03:21:31 INFO - 'tpaint', 03:21:31 INFO - 'sessionrestore', 03:21:31 INFO - 'sessionrestore_no_auto_restore', 03:21:31 INFO - 'tabpaint']}, 03:21:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:21:31 INFO - 'ts_paint', 03:21:31 INFO - 'tpaint', 03:21:31 INFO - 'sessionrestore', 03:21:31 INFO - 'sessionrestore_no_auto_restore', 03:21:31 INFO - 'tabpaint']}, 03:21:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:21:31 INFO - 'ts_paint', 03:21:31 INFO - 'tpaint', 03:21:31 INFO - 'sessionrestore', 03:21:31 INFO - 'sessionrestore_no_auto_restore', 03:21:31 INFO - 'tabpaint']}, 03:21:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['a11yr', 03:21:31 INFO - 'ts_paint', 03:21:31 INFO - 'tpaint', 03:21:31 INFO - 'sessionrestore', 03:21:31 INFO - 'sessionrestore_no_auto_restore', 03:21:31 INFO - 'tabpaint']}, 03:21:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['a11yr', 03:21:31 INFO - 'ts_paint', 03:21:31 INFO - 'tpaint', 03:21:31 INFO - 'sessionrestore', 03:21:31 INFO - 'sessionrestore_no_auto_restore', 03:21:31 INFO - 'tabpaint']}, 03:21:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['tsvgx', 03:21:31 INFO - 'tsvgr_opacity', 03:21:31 INFO - 'tart', 03:21:31 INFO - 'tscrollx', 03:21:31 INFO - 'cart', 03:21:31 INFO - 'tsvg_static']}, 03:21:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:21:31 INFO - 'tsvgr_opacity', 03:21:31 INFO - 'tart', 03:21:31 INFO - 'tscrollx', 03:21:31 INFO - 'cart', 03:21:31 INFO - 'tsvg_static']}, 03:21:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:21:31 INFO - 'talos_options': ['--disable-e10s'], 03:21:31 INFO - 'tests': ['tp5o']}, 03:21:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:21:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:21:31 INFO - 'talos_options': ['--disable-e10s', 03:21:31 INFO - '--xperf_path', 03:21:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:31 INFO - 'tests': ['tp5n']}, 03:21:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:21:31 INFO - 'talos_options': ['--xperf_path', 03:21:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:21:31 INFO - 'tests': ['tp5n']}}, 03:21:31 INFO - 'talos.zip': {'path': '', 03:21:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:21:31 INFO - Running post-action listener: _resource_record_post_action 03:21:31 INFO - [mozharness: 2017-04-13 10:21:31.295386Z] Finished populate-webroot step (success) 03:21:31 INFO - [mozharness: 2017-04-13 10:21:31.295498Z] Running create-virtualenv step. 03:21:31 INFO - Running pre-action listener: _resource_record_pre_action 03:21:31 INFO - Running main action method: create_virtualenv 03:21:31 INFO - Creating virtualenv /builds/slave/test/build/venv 03:21:31 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 03:21:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:21:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:21:31 INFO - Using real prefix '/tools/python27' 03:21:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:21:32 INFO - Installing distribute.............................................................................................................................................................................................done. 03:21:35 INFO - Installing pip.................done. 03:21:35 INFO - Return code: 0 03:21:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:21:35 INFO - Reading from file tmpfile_stdout 03:21:35 INFO - Output received: 03:21:35 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) 03:21:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:35 INFO - 'HOME': '/Users/cltbld', 03:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:35 INFO - 'LOGNAME': 'cltbld', 03:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:35 INFO - 'MOZ_NO_REMOTE': '1', 03:21:35 INFO - 'NO_EM_RESTART': '1', 03:21:35 INFO - 'PAGER': '/bin/cat', 03:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:35 INFO - 'PWD': '/builds/slave/test', 03:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:35 INFO - 'SHELL': '/bin/bash', 03:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:21:35 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:35 INFO - 'USER': 'cltbld', 03:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:35 INFO - 'XPC_FLAGS': '0x0', 03:21:35 INFO - 'XPC_SERVICE_NAME': '0', 03:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:35 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 03:21:35 INFO - Cleaning up... 03:21:35 INFO - Return code: 0 03:21:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:35 INFO - 'HOME': '/Users/cltbld', 03:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:35 INFO - 'LOGNAME': 'cltbld', 03:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:35 INFO - 'MOZ_NO_REMOTE': '1', 03:21:35 INFO - 'NO_EM_RESTART': '1', 03:21:35 INFO - 'PAGER': '/bin/cat', 03:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:35 INFO - 'PWD': '/builds/slave/test', 03:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:35 INFO - 'SHELL': '/bin/bash', 03:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:21:35 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:35 INFO - 'USER': 'cltbld', 03:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:35 INFO - 'XPC_FLAGS': '0x0', 03:21:35 INFO - 'XPC_SERVICE_NAME': '0', 03:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:35 INFO - Downloading/unpacking psutil>=3.1.1 03:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:21:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:39 INFO - Installing collected packages: psutil 03:21:39 INFO - Running setup.py install for psutil 03:21:39 INFO - building 'psutil._psutil_osx' extension 03:21:39 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 03:21:40 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 03:21:40 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 03:21:40 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 03:21:40 INFO - building 'psutil._psutil_posix' extension 03:21:40 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 03:21:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:21:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:21:40 INFO - ^ 03:21:40 INFO - 1 warning generated. 03:21:40 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 03:21:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:21:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:21:40 INFO - Successfully installed psutil 03:21:40 INFO - Cleaning up... 03:21:40 INFO - Return code: 0 03:21:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:40 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 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:21:40 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 03:21:40 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 03:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:40 INFO - 'HOME': '/Users/cltbld', 03:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:40 INFO - 'LOGNAME': 'cltbld', 03:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:40 INFO - 'MOZ_NO_REMOTE': '1', 03:21:40 INFO - 'NO_EM_RESTART': '1', 03:21:40 INFO - 'PAGER': '/bin/cat', 03:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:40 INFO - 'PWD': '/builds/slave/test', 03:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:40 INFO - 'SHELL': '/bin/bash', 03:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:21:40 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:40 INFO - 'USER': 'cltbld', 03:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:40 INFO - 'XPC_FLAGS': '0x0', 03:21:40 INFO - 'XPC_SERVICE_NAME': '0', 03:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:21:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:21:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:21:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:21:43 INFO - Installing collected packages: mozsystemmonitor 03:21:43 INFO - Running setup.py install for mozsystemmonitor 03:21:43 INFO - Successfully installed mozsystemmonitor 03:21:43 INFO - Cleaning up... 03:21:43 INFO - Return code: 0 03:21:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:21:43 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')]} 03:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:43 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')]} 03:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:43 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 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:21:43 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 03:21:43 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 03:21:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:21:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:43 INFO - 'HOME': '/Users/cltbld', 03:21:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:43 INFO - 'LOGNAME': 'cltbld', 03:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:43 INFO - 'MOZ_NO_REMOTE': '1', 03:21:43 INFO - 'NO_EM_RESTART': '1', 03:21:43 INFO - 'PAGER': '/bin/cat', 03:21:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:43 INFO - 'PWD': '/builds/slave/test', 03:21:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:43 INFO - 'SHELL': '/bin/bash', 03:21:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:21:43 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:21:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:43 INFO - 'USER': 'cltbld', 03:21:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:43 INFO - 'XPC_FLAGS': '0x0', 03:21:43 INFO - 'XPC_SERVICE_NAME': '0', 03:21:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:43 INFO - Downloading/unpacking jsonschema==2.5.1 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:43 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 03:21:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:21:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:21:46 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 03:21:46 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 03:21:46 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 03:21:46 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 03:21:46 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 03:21:46 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 03:21:46 INFO - Downloading functools32-3.2.3-2.tar.gz 03:21:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:21:47 INFO - warning: no files found matching '*.txt' 03:21:47 INFO - no previously-included directories found matching 'build' 03:21:47 INFO - no previously-included directories found matching 'dist' 03:21:47 INFO - no previously-included directories found matching '.git*' 03:21:47 INFO - Installing collected packages: jsonschema, functools32 03:21:47 INFO - Running setup.py install for functools32 03:21:47 INFO - warning: no files found matching '*.txt' 03:21:47 INFO - no previously-included directories found matching 'build' 03:21:47 INFO - no previously-included directories found matching 'dist' 03:21:47 INFO - no previously-included directories found matching '.git*' 03:21:47 INFO - Successfully installed jsonschema functools32 03:21:47 INFO - Cleaning up... 03:21:47 INFO - Return code: 0 03:21:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:21:47 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')]} 03:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:47 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')]} 03:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:48 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 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:21:48 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 03:21:48 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 03:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:48 INFO - 'HOME': '/Users/cltbld', 03:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:48 INFO - 'LOGNAME': 'cltbld', 03:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:48 INFO - 'MOZ_NO_REMOTE': '1', 03:21:48 INFO - 'NO_EM_RESTART': '1', 03:21:48 INFO - 'PAGER': '/bin/cat', 03:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:48 INFO - 'PWD': '/builds/slave/test', 03:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:48 INFO - 'SHELL': '/bin/bash', 03:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:21:48 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:48 INFO - 'USER': 'cltbld', 03:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:48 INFO - 'XPC_FLAGS': '0x0', 03:21:48 INFO - 'XPC_SERVICE_NAME': '0', 03:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:21:48 INFO - Cleaning up... 03:21:48 INFO - Return code: 0 03:21:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:21:48 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')]} 03:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:48 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')]} 03:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:48 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 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:21:48 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 03:21:48 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 03:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:48 INFO - 'HOME': '/Users/cltbld', 03:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:48 INFO - 'LOGNAME': 'cltbld', 03:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:48 INFO - 'MOZ_NO_REMOTE': '1', 03:21:48 INFO - 'NO_EM_RESTART': '1', 03:21:48 INFO - 'PAGER': '/bin/cat', 03:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:48 INFO - 'PWD': '/builds/slave/test', 03:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:48 INFO - 'SHELL': '/bin/bash', 03:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:21:48 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:48 INFO - 'USER': 'cltbld', 03:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:48 INFO - 'XPC_FLAGS': '0x0', 03:21:48 INFO - 'XPC_SERVICE_NAME': '0', 03:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:21:49 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 03:21:49 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 03:21:49 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 03:21:49 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 03:21:49 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 03:21:49 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 03:21:52 INFO - Downloading blobuploader-1.2.4.tar.gz 03:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:21:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:21:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 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 03:21:53 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 03:21:54 INFO - Downloading docopt-0.6.1.tar.gz 03:21:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:21:54 INFO - Installing collected packages: blobuploader, requests, docopt 03:21:54 INFO - Running setup.py install for blobuploader 03:21:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:21:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:21:54 INFO - Running setup.py install for requests 03:21:54 INFO - Running setup.py install for docopt 03:21:55 INFO - Successfully installed blobuploader requests docopt 03:21:55 INFO - Cleaning up... 03:21:55 INFO - Return code: 0 03:21:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21:55 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')]} 03:21:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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')]} 03:21:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:55 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 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:21:55 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 03:21:55 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 03:21:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:21:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:55 INFO - 'HOME': '/Users/cltbld', 03:21:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:55 INFO - 'LOGNAME': 'cltbld', 03:21:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:55 INFO - 'MOZ_NO_REMOTE': '1', 03:21:55 INFO - 'NO_EM_RESTART': '1', 03:21:55 INFO - 'PAGER': '/bin/cat', 03:21:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:55 INFO - 'PWD': '/builds/slave/test', 03:21:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:55 INFO - 'SHELL': '/bin/bash', 03:21:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:21:55 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:21:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:55 INFO - 'USER': 'cltbld', 03:21:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:55 INFO - 'XPC_FLAGS': '0x0', 03:21:55 INFO - 'XPC_SERVICE_NAME': '0', 03:21:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:21:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:21:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-FItDa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:21:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-nMTyVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:21:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-FZlUxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:21:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-vIJfXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:21:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-wfCa4U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:21:55 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-t11kOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-mxH0IE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-qI2T55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-85eCBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-34HqWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-7oK87c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-Jv59Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-6K9C0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-ueyRK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-_KG4Iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:21:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:21:56 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-9XItyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:21:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:21:57 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-6mH4Pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:21:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:21:57 INFO - Running setup.py install for manifestparser 03:21:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:21:57 INFO - Running setup.py install for mozcrash 03:21:57 INFO - Running setup.py install for mozdebug 03:21:57 INFO - Running setup.py install for mozdevice 03:21:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:21:57 INFO - Running setup.py install for mozfile 03:21:57 INFO - Running setup.py install for mozhttpd 03:21:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:21:58 INFO - Running setup.py install for mozinfo 03:21:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:21:58 INFO - Running setup.py install for mozInstall 03:21:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:21:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:21:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:21:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:21:58 INFO - Running setup.py install for mozleak 03:21:58 INFO - Running setup.py install for mozlog 03:21:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:21:58 INFO - Running setup.py install for moznetwork 03:21:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:21:58 INFO - Running setup.py install for mozprocess 03:21:58 INFO - Running setup.py install for mozprofile 03:21:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:21:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:21:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:21:59 INFO - Running setup.py install for mozrunner 03:21:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:21:59 INFO - Running setup.py install for mozscreenshot 03:21:59 INFO - Running setup.py install for moztest 03:21:59 INFO - Running setup.py install for mozversion 03:21:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:21:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:21:59 INFO - Cleaning up... 03:21:59 INFO - Return code: 0 03:21:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:21: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')]} 03:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:21:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21: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')]} 03:21:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:21:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:21:59 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 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:21:59 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 03:21:59 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 03:21:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:21:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:21:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:21:59 INFO - 'HOME': '/Users/cltbld', 03:21:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:21:59 INFO - 'LOGNAME': 'cltbld', 03:21:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:21:59 INFO - 'MOZ_NO_REMOTE': '1', 03:21:59 INFO - 'NO_EM_RESTART': '1', 03:21:59 INFO - 'PAGER': '/bin/cat', 03:21:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:21:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:21:59 INFO - 'PWD': '/builds/slave/test', 03:21:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:21:59 INFO - 'SHELL': '/bin/bash', 03:21:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:21:59 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:21:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:21:59 INFO - 'USER': 'cltbld', 03:21:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:21:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:21:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:21:59 INFO - 'XPC_FLAGS': '0x0', 03:21:59 INFO - 'XPC_SERVICE_NAME': '0', 03:21:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:00 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-hTfJFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:00 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)) 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:00 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-a8uzTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:00 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)) 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:00 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-UpGFIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:00 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)) 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:00 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-Rf7mrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:00 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)) 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:00 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-Tc6vV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:00 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)) 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:00 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-naDPLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:00 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)) 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:00 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-XFEcuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:00 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)) 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:00 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-Ao4Pnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:00 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)) 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:00 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-Wt5iyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:00 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)) 03:22:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:01 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-G9QtQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:01 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)) 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:01 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-DYD1x_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:01 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)) 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:01 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-GPfHrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:01 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)) 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:01 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-0wGKxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:01 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)) 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:01 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-LYDo2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:01 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)) 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:01 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-1XBTZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:01 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)) 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:01 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-k7iVBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:01 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)) 03:22:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:01 INFO - Running setup.py (path:/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/pip-GHOw2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:01 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)) 03:22:01 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)) 03:22:01 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)) 03:22:01 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)) 03:22:01 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)) 03:22:01 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)) 03:22:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:22:01 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 03:22:01 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 03:22:01 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 03:22:01 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 03:22:01 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 03:22:01 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 03:22:04 INFO - Downloading blessings-1.6.tar.gz 03:22:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:22:04 INFO - Installing collected packages: blessings 03:22:04 INFO - Running setup.py install for blessings 03:22:04 INFO - Successfully installed blessings 03:22:04 INFO - Cleaning up... 03:22:04 INFO - Return code: 0 03:22:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:22:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:05 INFO - Reading from file tmpfile_stdout 03:22:05 INFO - Current package versions: 03:22:05 INFO - blessings == 1.6 03:22:05 INFO - blobuploader == 1.2.4 03:22:05 INFO - docopt == 0.6.1 03:22:05 INFO - functools32 == 3.2.3-2 03:22:05 INFO - jsonschema == 2.5.1 03:22:05 INFO - manifestparser == 1.1 03:22:05 INFO - mozInstall == 1.12 03:22:05 INFO - mozcrash == 1.0 03:22:05 INFO - mozdebug == 0.1 03:22:05 INFO - mozdevice == 0.50 03:22:05 INFO - mozfile == 1.2 03:22:05 INFO - mozhttpd == 0.7 03:22:05 INFO - mozinfo == 0.9 03:22:05 INFO - mozleak == 0.1 03:22:05 INFO - mozlog == 3.4 03:22:05 INFO - moznetwork == 0.27 03:22:05 INFO - mozprocess == 0.25 03:22:05 INFO - mozprofile == 0.28 03:22:05 INFO - mozrunner == 6.13 03:22:05 INFO - mozscreenshot == 0.1 03:22:05 INFO - mozsystemmonitor == 0.3 03:22:05 INFO - moztest == 0.8 03:22:05 INFO - mozversion == 1.4 03:22:05 INFO - psutil == 3.1.1 03:22:05 INFO - requests == 1.2.3 03:22:05 INFO - wsgiref == 0.1.2 03:22:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:05 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')]} 03:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:05 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')]} 03:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:05 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 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:22:05 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 03:22:05 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 03:22:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:22:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:05 INFO - 'HOME': '/Users/cltbld', 03:22:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:05 INFO - 'LOGNAME': 'cltbld', 03:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:05 INFO - 'MOZ_NO_REMOTE': '1', 03:22:05 INFO - 'NO_EM_RESTART': '1', 03:22:05 INFO - 'PAGER': '/bin/cat', 03:22:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:05 INFO - 'PWD': '/builds/slave/test', 03:22:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:05 INFO - 'SHELL': '/bin/bash', 03:22:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:22:05 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:22:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:05 INFO - 'USER': 'cltbld', 03:22:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:05 INFO - 'XPC_FLAGS': '0x0', 03:22:05 INFO - 'XPC_SERVICE_NAME': '0', 03:22:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:05 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)) 03:22:05 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)) 03:22:05 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)) 03:22:05 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)) 03:22:05 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)) 03:22:05 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)) 03:22:05 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)) 03:22:05 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)) 03:22:05 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)) 03:22:05 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)) 03:22:05 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)) 03:22:05 INFO - Cleaning up... 03:22:05 INFO - Return code: 0 03:22:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:22:05 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')]} 03:22:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:05 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')]} 03:22:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:05 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 0x105017b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10501a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98e2572360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, '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.tJidG6fSds/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.4puwtSYsHF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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 03:22:05 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 03:22:05 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 03:22:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:22:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:05 INFO - 'HOME': '/Users/cltbld', 03:22:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:05 INFO - 'LOGNAME': 'cltbld', 03:22:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:05 INFO - 'MOZ_NO_REMOTE': '1', 03:22:05 INFO - 'NO_EM_RESTART': '1', 03:22:05 INFO - 'PAGER': '/bin/cat', 03:22:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:05 INFO - 'PWD': '/builds/slave/test', 03:22:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:05 INFO - 'SHELL': '/bin/bash', 03:22:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:22:05 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:22:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:05 INFO - 'USER': 'cltbld', 03:22:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:05 INFO - 'XPC_FLAGS': '0x0', 03:22:05 INFO - 'XPC_SERVICE_NAME': '0', 03:22:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:22:05 INFO - Cleaning up... 03:22:05 INFO - Return code: 0 03:22:05 INFO - Running post-action listener: _resource_record_post_action 03:22:05 INFO - Running post-action listener: _start_resource_monitoring 03:22:05 INFO - Starting resource monitoring. 03:22:05 INFO - [mozharness: 2017-04-13 10:22:05.802807Z] Finished create-virtualenv step (success) 03:22:05 INFO - [mozharness: 2017-04-13 10:22:05.803787Z] Running install step. 03:22:05 INFO - Running pre-action listener: _resource_record_pre_action 03:22:05 INFO - Running main action method: install 03:22:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:22:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:22:06 INFO - Reading from file tmpfile_stdout 03:22:06 INFO - Detecting whether we're running mozinstall >=1.0... 03:22:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:22:06 INFO - Reading from file tmpfile_stdout 03:22:06 INFO - Output received: 03:22:06 INFO - Usage: mozinstall [options] installer 03:22:06 INFO - Options: 03:22:06 INFO - -h, --help show this help message and exit 03:22:06 INFO - -d DEST, --destination=DEST 03:22:06 INFO - Directory to install application into. [default: 03:22:06 INFO - "/builds/slave/test"] 03:22:06 INFO - --app=APP Application being installed. [default: firefox] 03:22:06 INFO - mkdir: /builds/slave/test/build/application 03:22:06 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'] 03:22:06 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 03:22:25 INFO - Reading from file tmpfile_stdout 03:22:25 INFO - Output received: 03:22:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:22:25 INFO - Running post-action listener: _resource_record_post_action 03:22:25 INFO - [mozharness: 2017-04-13 10:22:25.840210Z] Finished install step (success) 03:22:25 INFO - [mozharness: 2017-04-13 10:22:25.840327Z] Running run-tests step. 03:22:25 INFO - Running pre-action listener: _resource_record_pre_action 03:22:25 INFO - Running main action method: run_tests 03:22:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:22:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:22:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:22:25 INFO - 2017-04-13 03:22:25.855 screenresolution[1896:14306] starting screenresolution argv=screenresolution get 03:22:25 INFO - 2017-04-13 03:22:25.857 screenresolution[1896:14306] Display 0: 1600x1200x32@60 03:22:25 INFO - 2017-04-13 03:22:25.863 screenresolution[1897:14309] starting screenresolution argv=screenresolution list 03:22:25 INFO - Available Modes on Display 0 03:22:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:22:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:22:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:22:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:22:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:22:26 INFO - Intel Iris: 03:22:26 INFO - Chipset Model: Intel Iris 03:22:26 INFO - Type: GPU 03:22:26 INFO - Bus: Built-In 03:22:26 INFO - VRAM (Dynamic, Max): 1536 MB 03:22:26 INFO - Vendor: Intel (0x8086) 03:22:26 INFO - Device ID: 0x0a2e 03:22:26 INFO - Revision ID: 0x0009 03:22:26 INFO - Displays: 03:22:26 INFO - Display: 03:22:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:22:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:22:26 INFO - Display Serial Number: 1600x1200 60 03:22:26 INFO - Main Display: Yes 03:22:26 INFO - Mirror: Off 03:22:26 INFO - Online: Yes 03:22:26 INFO - Rotation: Supported 03:22:26 INFO - Return code: 0 03:22:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:22:26 INFO - Python 2.7.3 03:22:26 INFO - Return code: 0 03:22:26 INFO - grabbing minidump binary from tooltool 03:22:26 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')]} 03:22:26 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 0x104df5d30>, '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 0x104d1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104deabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:22:26 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 03:22:26 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 03:22:26 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 03:22:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:22:26 INFO - Return code: 0 03:22:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:22:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:22:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:22:26 INFO - ENV: RUST_BACKTRACE is now 1 03:22:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:22:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:22:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0270', '--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 03:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0270 --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 03:22:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJidG6fSds/Render', 03:22:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:26 INFO - 'HOME': '/Users/cltbld', 03:22:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:26 INFO - 'LOGNAME': 'cltbld', 03:22:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:26 INFO - 'MOZ_NO_REMOTE': '1', 03:22:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:26 INFO - 'NO_EM_RESTART': '1', 03:22:26 INFO - 'PAGER': '/bin/cat', 03:22:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:26 INFO - 'PWD': '/builds/slave/test', 03:22:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:26 INFO - 'RUST_BACKTRACE': '1', 03:22:26 INFO - 'SHELL': '/bin/bash', 03:22:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4puwtSYsHF/Listeners', 03:22:26 INFO - 'TMPDIR': '/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/', 03:22:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:26 INFO - 'USER': 'cltbld', 03:22:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:26 INFO - 'XPC_FLAGS': '0x0', 03:22:26 INFO - 'XPC_SERVICE_NAME': '0', 03:22:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0270', '--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 03:22:26 INFO - mozversion application_buildid: 20170412134908 03:22:26 INFO - mozversion application_changeset: bedd7990a3fdc0d1bf4121b788accdf2c35435e9 03:22:26 INFO - mozversion application_display_name: Nightly 03:22:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:22:26 INFO - mozversion application_name: Firefox 03:22:26 INFO - mozversion application_remotingname: firefox 03:22:26 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 03:22:26 INFO - mozversion application_vendor: Mozilla 03:22:26 INFO - mozversion application_version: 55.0a1 03:22:26 INFO - mozversion platform_buildid: 20170412134908 03:22:26 INFO - mozversion platform_changeset: bedd7990a3fdc0d1bf4121b788accdf2c35435e9 03:22:26 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 03:22:26 INFO - mozversion platform_version: 55.0a1 03:22:26 INFO - using testdate: 1492078946 03:22:26 INFO - actual date: 1492078946 03:22:26 INFO - starting webserver on 'localhost:49361' 03:22:26 INFO - SUITE-START | Running 2 tests 03:22:26 INFO - TEST-START | dromaeo_css 03:22:26 INFO - operating with platform_type : mac_ 03:22:26 INFO - Initialising browser for dromaeo_css test... 03:22:26 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/tmp99F0Xu/profile http://localhost:49361/getInfo.html) 03:22:29 INFO - PID 1907 | 2017-04-13 03:22:29.488 plugin-container[1909:14477] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 03:22:29 INFO - PID 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:29 INFO - PID 1907 | 2017-04-13 03:22:29.490 plugin-container[1909:14477] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d0f, name = 'com.apple.CFPasteboardClient' 03:22:29 INFO - PID 1907 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:29 INFO - PID 1907 | 2017-04-13 03:22:29.490 plugin-container[1909:14477] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:22:29 INFO - PID 1907 | __metrics Screen width/height:1600/1200 03:22:29 INFO - PID 1907 | colorDepth:24 03:22:29 INFO - PID 1907 | Browser inner width/height: 1024/689 03:22:29 INFO - PID 1907 | __metrics 03:22:29 ERROR - PID 1907 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 03:22:30 INFO - TEST-INFO | 1907: exit 0 03:22:30 INFO - Browser initialized. 03:22:30 INFO - Running cycle 1/1 for dromaeo_css test... 03:22:30 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/tmp99F0Xu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:33 INFO - PID 1910 | 2017-04-13 03:22:33.060 plugin-container[1911:14608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 03:22:33 INFO - PID 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:33 INFO - PID 1910 | 2017-04-13 03:22:33.062 plugin-container[1911:14608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 03:22:33 INFO - PID 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:22:33 INFO - PID 1910 | 2017-04-13 03:22:33.062 plugin-container[1911:14608] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:22:42 INFO - PID 1910 | RSS: Main: 235339776 03:22:42 INFO - PID 1910 | 03:25:46 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49361/tests/dromaeo/cssquery-ext.html) 03:25:48 INFO - PID 1910 | RSS: Main: 253632512 03:25:48 INFO - PID 1910 | 03:27:31 INFO - PID 1910 | ************************* 03:27:31 INFO - PID 1910 | A coding exception was thrown and uncaught in a Task. 03:27:31 INFO - PID 1910 | 03:27:31 ERROR - PID 1910 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:27:31 INFO - PID 1910 | Full stack: 03:27:31 INFO - PID 1910 | ************************* 03:27:31 INFO - PID 1910 | ************************* 03:27:31 INFO - PID 1910 | A coding exception was thrown and uncaught in a Task. 03:27:31 INFO - PID 1910 | 03:27:31 ERROR - PID 1910 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:27:31 INFO - PID 1910 | Full stack: 03:27:31 INFO - PID 1910 | ************************* 03:27:31 INFO - PID 1910 | ************************* 03:27:31 INFO - PID 1910 | A coding exception was thrown and uncaught in a Task. 03:27:31 INFO - PID 1910 | 03:27:31 ERROR - PID 1910 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:27:31 INFO - PID 1910 | Full stack: 03:27:31 INFO - PID 1910 | ************************* 03:27:31 INFO - PID 1910 | ************************* 03:27:31 INFO - PID 1910 | A coding exception was thrown and uncaught in a Task. 03:27:31 INFO - PID 1910 | 03:27:31 ERROR - PID 1910 | Full message: TypeError: NetworkError when attempting to fetch resource. 03:27:31 INFO - PID 1910 | Full stack: 03:27:31 INFO - PID 1910 | ************************* 03:28:50 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-ext.html (next: http://localhost:49361/tests/dromaeo/cssquery-jquery.html) 03:28:51 INFO - PID 1910 | RSS: Main: 264675328 03:28:51 INFO - PID 1910 | 03:31:52 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49361/tests/dromaeo/cssquery-mootools.html) 03:31:53 INFO - PID 1910 | RSS: Main: 276856832 03:31:53 INFO - PID 1910 | 03:34:55 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49361/tests/dromaeo/cssquery-prototype.html) 03:34:55 INFO - PID 1910 | RSS: Main: 269258752 03:34:55 INFO - PID 1910 | 03:37:58 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49361/tests/dromaeo/cssquery-yui.html) 03:37:59 INFO - PID 1910 | RSS: Main: 279678976 03:37:59 INFO - PID 1910 | 03:41:01 INFO - PID 1910 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-yui.html (next: http://localhost:49361/tests/dromaeo/cssquery-dojo.html) 03:41:01 INFO - PID 1910 | RSS: Main: 273375232 03:41:01 INFO - PID 1910 | 03:41:01 INFO - PID 1910 | __start_tp_report 03:41:01 INFO - PID 1910 | _x_x_mozilla_page_load 03:41:01 INFO - PID 1910 | _x_x_mozilla_page_load_details 03:41:01 INFO - PID 1910 | |i|pagename|runs| 03:41:01 INFO - PID 1910 | |0;dojo.html;3577.2308953574975;3587.0315014945963;3603.063203567072;3617.3669607818624;3630.1832087780235;6129.846753831156;6223.595466294693;6236.875262494747;6276.340984196662;6278.026905829593;6127.586602260213;6186.628802271867;6200.3179650238435;6250.187475628162;6276.372362763721;5476.2333273341765;5481.369642491105;5506.669599824003;5528.419515950827;5585.413531579186;5404.32445944257;5503.367112782022;5588.469095435927;5591;5605.5515558755405;5437.429069947638;5481.671099734029;5506.0089183946875;5532.5573954083575;5569.081101618228;4425.291953287491;5446.700431476267;5451.890962180773;5490.011797876381;5545.112781954868;5441.5102640762525;5445.83662490122;5447.700376479292;5453.4273901240595;5467.644603100785;4996;6094.116353128771;6104.450599446027;6135.477655820245;6224.377562243784;5578.804741834017;6608.636524991123;6721.932780672179;6723.865522689568;6724.260331363546;6799.5920244785475;6845.281245469204;6846.110005699227;6847.383735463783;6859.382655561023;6155.230596175478;6336.778212762577;6339.714712837841;6345.175127233476;6345.873082538324;6748.797536073827;6910.412614927787;6959.61722105289;6983.580985140908;6996.650167491605;6320.55756097078;6402.6158430494315;6415.871682566322;6590.736370545124;6670.933290667128;6713.395794378529;6715.865682686319;6760.932390676128;6774.153230846145;6784.7964561063245;5794.942050579441;6283.560150789493;6687.598744075371;6696.8995465068065;6715.56348837324;5429.701366424884;6442.581232219891;6490.8052758417325;6640.136782218377;6722.058911752359;5340.172273297645;5349.117395629755;5357.116075847442;5360.812371566976;5380.811671591554;4701.670883038155;4708.552687494683;4716.056788642285;4722.976385118052;4766.737829419346;5344.786208551692;5823.980803359395;6231.532635052298;6274.529410294246;6343.809685709436;6211.440970312603;6744.763933262312;6752.257251702309;6810.353016463428;6863.588184708933;6399.9040014399825;6409.262934762562;6418.775342862978;6430.485561155097;6441.777586732664;5763.8559036024435;5956.761729530857;6010.398960103955;6025.9096113558335;6066.02943529042;6722.831929201711;6864.107666003388;6911.067005954333;6944.583325000516;6967.09427774386;6787.049813026081;6788.830279243059;6818.693158807965;6831.555948863308;6842.486813488908;6864.862702746017;6874.8625027500175;6892.414144797748;6959.686814093277;6960.47796415281;6395.680215989275;6490.188726409199;6633.635150066792;6669.933300666931;6685.164354455693;6696.330366963265;6814.1482314710665;6835.384815366641;6835.453163746789;6858.108445902001;5441.5374693151525;6335.208098987627;6377.80866573985;6382.170317858834;6590.505712071518;5523.558115350843;6583.572067815577;6590.538662293593;6605.933940660532;6626.469882409492;4603.079384123122;6031.673031932968;6255.906161407492;6313.715882785194;6460.224773027079;6352.396522330371;6469.417752402306;6545.639989800607;6561.540692151694;6602;6421.068945002855;6443.291237963914;6450.774222902176;6451.645159516084;6465.903011454738 03:41:01 INFO - PID 1910 | |1;ext.html;801.4109629422376;830.2569200565492;1096.935972107056;1140.350000499715;1281.6923938254822;28665;34195.487067693975;35260.94217173482;38133;38151.80924095379;37698.8115059425;38128.42807357892;38202.617973820255;38226.23547529048;38286.61713382865;7003.47473939454;7276.818079548001;7994.760157195294;8156.959215203916;8323.334133269324;7242.384397326234;8417.11620279871;8479.19447652472;8489.787755306135;8491.19333663301;7127.8218044548785;8439.071702112762;8483.787905302355;8502.234798868101;8586.141385861427;8389.119142490012;8427.241548260656;8440.746777596682;8458.915410845875;8468.618912148968;8436.409451338408;8469.66121355145;8488.618012189467;8494.660213591449;8519.275861551776;37117.257654846755;37446.43830342547;37466.81266593677;37657.435138472945;37722.868313950625;3649.981750091259;3677.2277821657485;3697.574778900434;3704.462852886343;3706.8887933362043;23678.17126400585;30438.54342184869;30680.846595767103;30780.53829192564;30869.073927782203;3063.862126204326;3110.9222269443217;3114.595102636643;3120.23554229213;3121.406932682783;249475.75262123754;249838;250444;251001;251229;35184.64815351814;39564.406533902016;45867.31199032017;48103.037939241716;48160.27759583609;50513;50660.240096398586;50957.235641465406;50972;51027.48972510227;14556.417743290362;14695.11829290246;14714.55856324312;14754.336054877556;14769.33537990793;9961.950190249147;11849.466773995191;11860.525578976916;11917.761644767055;11928.986036186847;11462.48418821155;11557.075433965263;13093.869061309455;13579.895116256996;13580.93209533946;5829.242198514166;5833.533317334603;5873.676947767828;5886.499647529922;5891.823245302648;1044.9007344302279;1067.3649178738638;1077.504347999913;1077.5635867473686;1094.0645747885603;19580.377898313258;20753.066112024993;21692;23829.52340953206;24662.59863648106;1146.908247340211;1160.042964554246;1174.8766379530198;1191.9463624136936;1200.2078628105583;10223.948880255552;12463.688407789878;12543.811842822364;12570.874291257152;12570.937145314216;360733;361417;363124;365287;370461;49856.501434985184;50832.72918177001;54711.72644136754;54914.45085549093;55035;1496.483713118994;1546.9535913922375;1554.945576904803;1559.532140357888;1586.7223235933666;24209.757902421454;24289.51420971606;24348.269551913472;24403.26790196297;24495.51008979846;38344.42483362696;38422.80788596039;38570.228595427376;38588.6141138585;38618.22763544657;322060.38969805;322286;322790;323834;324205;34749.82625086858;34945;35134.82432587821;35155.593776248665;35257;44900.77549612231;45849.77075114736;45864.77067614774;45877;45983.31025034694;30062.69937300599;30790.692093079677;31505.054848354586;31510.684893150774;31924.36151277008;23910.64134037956;30716.539251911687;31275.218119547193;31411.685883140875;31589.36821263608;37512.249755005294;37534;37701.24597508089;37730.43404348992;37798.81100594479;24839.503209935338;29545.556816648197;30163.849180753954;30472.238194045327;31375.21561960969;18363.659827074545;23546.646800298353;24252.757472425757;24306.149284774947;24322.75677243205 03:41:01 INFO - PID 1910 | |2;jquery.html;390.8964124507006;425.865852256539;438.1478025240906;438.2112198043521;458.85775409623017;19841.60316793665;21420;22053;22311.99596018179;22394.104235830568;18117;22175.1129954802;23347.066117355287;23815.5236895262;23872.164474243404;6263.029230469285;6398.104265402847;6460.192475940507;6462.870742585145;6464.676766161697;6194.442500174983;6338.714757835885;6349.714262858182;6410.839729006789;6421.325760795119;6171.660558669271;6295.842603934892;6328.177336946191;6374.585651932633;6379.585326953733;6316.8629646663585;6349.873002539969;6371.267304259976;6374.968125159345;6375.71309291083;5541.445855414467;5630.8029218977135;5666.093425051972;5666.405027472098;5692.345380281279;27292.31769205766;27738.306542336402;27874.3031424214;28020.159395218176;28409.85795071032;2201.1745595401726;2267.7958983691547;2269.580127676385;2270.6139956207485;2270.8751018693965;17797.644047118985;17982.37061702847;18056.27774889003;18512.16695248717;18724.719129213074;2127.414960885769;2138.8823614701187;2177.2488491470417;2179.2917301876796;2181.2256648889734;135764;161373;162560;164235;168578.1571092149;12452.501899924082;13037.478500860048;13201.669958251121;13224.338783060808;13255.602331930058;16924.32302707878;17144.228509717093;17203.397881074354;17231.82768172302;17259.74110388345;9191.586378612978;9233.815323693489;9296.488693121943;9442.15020648145;9445.811083778424;8852.911470885338;8861.113888611217;8952.373333866566;9040.005599384063;9065.909340906506;7986.720464783704;8269.95865020671;8282.627281772335;8288.378371622153;8292.04641466227;1748.3793253395115;1852.4720454670355;1875.7749070111674;2183.6942828004094;2417.1419146203198;824.47233770387;909.2271569166156;941.7786820097272;968.4431451915177;991.8809742830906;20738.377848664262;21468.926553672252;21479.248226312004;21496.92515374225;21571.460713481974;837.7151768398774;917.5733284022828;988.2686811759246;1013.6097602422973;1034.715453250362;8418.873716894252;8735.995360533492;9057.094290571022;9388.906110938804;9803.019698030283;667665;675192;695233;733120;754377;136153;156090.0977475572;165760;172250;172679;1125.2123513540555;1160.878107798686;1200.2918278215895;1239.3369547292154;1239.975200495985;20435.38693839187;21790.801505916694;21911;22221.777782222616;22428.551428971656;49418.01163976772;58756.94350697705;63275.36724632879;63942.3605763955;64850.22339053924;89594.42300510395;89835.65246521439;90779.54610226907;91514;92249;8797.900262467192;8881;8887.822243555222;9164.037776033421;9400.952995235253;14749.262536873328;14766.852331476977;14801.481948131765;14879.256037198313;15038.398464061314;11134.554617815196;11898.453201083803;11926.344051077274;11949.2830430171;12352.4441400139;11386.715332116763;11461.713457163638;11705.648830535098;11929.701757456132;11931.522739090338;12140.089493287862;12320.881202183211;12404.379781011094;12493.875061249271;12500.562480313145;8951.104889511258;8994.640214391351;9070.501122438329;9339.486328252011;9354.345195836226;11847.526098955945;12125.69685757863;12210.694732631755;12319.692007699521;12439 03:41:01 INFO - PID 1910 | |3;mootools.html;1245.1532957588836;1293.7929931211006;1405.4940221520258;1412.8375236847767;1416.5325442603942;11092.944535277333;11402.258853174548;11789.528418863247;11809.11431642627;12094.516219351237;9821.116099551038;11755.294682319067;11755.706107347321;11915.702107447318;11933.34366609836;2296.610550616878;2331.755165707602;2472.81453890958;2514.383975925901;2515.8364706294124;2052.9794702052936;2301.1255722825385;2401.2556107606697;2514.660520829683;2516.3709072731817;2419.322589674894;2461.3231361375674;2472.159465781634;2508.435601989556;2516.509280690266;2416.4562973331035;2483.863387513686;2491.3273416177713;2502.1492692484553;2503.824732268742;1994.8803071815737;2394.8084153267696;2401.891914863835;2461.1016978802786;2516.484120755241;11921.225120367148;12010.279383237033;12017.579384721581;12024.939875300655;12055.819162712567;6542.378474044958;6824.0446337512785;6826.873565861627;6827.965860170717;6841;4054.898627534306;4288.206681763883;4350.347447882811;4395.494518130424;4430;1087.9401632910187;1185.146694380045;1221.32216619776;1269.60642200918;1269.9428525716364;14512.78230826528;14995.100293982505;16535.503934881974;16658.16709164549;16739.916300418543;5070.909754402821;5479;5661.094224923992;5683.744231509592;5696.8006119785605;10430.791384172275;12108.939455302667;12335.074869384833;12470.189437686522;12508.374581271082;6510.511711621647;6557.803265902031;6590.835229119214;6601.141851559362;6602.570832895846;6142.86357023952;6193.597416167935;6196.659183744847;6220.315765265817;6235.314115447297;4330.848420305274;4398.274284743045;4418.756968366706;4481.3726078349055;4481.887952801206;1235.9073069519823;1240.5658019693215;1242.571043300212;1256.2650849253107;1261.5647601577625;804.0833449867156;805.7179987004502;828.159418190537;832.8334333133276;861.5261606116736;5914.822555323347;6141.846453838601;6149.538784591084;6363.204599425072;6412.35876412355;590.3270271890049;594.9286085669747;602.8251806975928;610.4749915073033;616.4082480818464;3562.020444377765;4194.580541945781;4205.222033923734;4456.554344565582;4636.559526844944;14698;15372.461963831212;15395.846041539666;15396.46112386061;15419.383224671112;14924.47764328265;15296.464623738117;15354.309056092503;15408.691826163415;15501.689966200613;10845.51195196218;11087.002169804757;11125.499352528994;11151.163662725166;11163.497642606102;5974.65570246691;6670.533062685566;7169.641517924188;7381.778546643609;7405.111386633638;12782.424790884246;12830.807537886752;12890.162139460905;13085.018623603459;13132.671683207996;13148.211107333591;13180.670483237996;13252.20486770797;13408.32958352059;13464.865351346361;9605.18355939753;9652.179564737076;9701.466419347003;9762.36544624597;9805.803883922139;10911.563537458413;11035.613753518588;11048.944755276172;11058.889411106107;11059.944700276768;9092.136247056518;9308.301877359361;9325.160735533835;9367.85948210791;9381;8557.144285571392;9181.081891811033;9224.907750922404;9230.446173229358;9242.168204861595;7543.811404714926;7786.454948153575;7895;8467.745967620982;8489.83020339602;6131.264248290234;6152.661603611666;6258.499320054294;6291.874162516816;6366.490680745622;5464.808731694372;6309.337519560564;6380.457661098859;6392.840178995562;6509.73961041572 03:41:01 INFO - PID 1910 | |4;prototype.html;356.2660918507873;389.76419266343845;394.27059939112644;404.12708549533016;408.75678971012246;5133.974330128344;5156.303898973638;5888.175655408241;5994.70026498675;6005.0692142717835;4400.229959757045;5448.291722076125;5885.7939972100985;6024.69876506174;6044.818655440333;3223.9838800805965;4156.376543518474;4442.155990361837;4456.732596044231;4474.552544745532;4055.5538890721996;4203.159368126372;4441.75570343631;4453.48784889737;4477.261251893434;3532.5231093802395;4201.537830838605;4379.540148284432;4418.756968366739;4418.86743397697;3644.908877278076;3824.7131465139946;4268.274393353137;4355.477342718894;4432.822687092496;3646.2889736501393;3783.735138540303;3789.829457674411;3807.200487897545;3815.1606646537716;4802.4957379475;5729.570282228808;5776.508996735282;5789.594728369016;5817.767289308423;5089.643724939256;5209.426963034063;5294.602904782157;5296.09966305729;5318.601104917108;6414.61512309263;6479.7732079377465;6488.351164883521;6509.860774364467;6520.380563846427;3755.530558680165;4303.139372125556;4311.8706438806885;4316.201502722007;4331.177076355482;6133.252023173419;6581.26149172595;6831.487638427088;6882.931170688279;6996.370326670624;4113.424120623115;4226.873193804191;4433.334999750064;4435.2016637005;4437.334399839985;4465.955340446619;4466.843660471868;4490.159034795718;4494.685372023927;4506.369108324837;4089.795510224477;4688.484266730657;4984.2025275955675;5118.462561430996;5302.231176479466;6434.871302573923;6481.347256449575;6505.902411463831;6547.934520654828;6568.405479835438;1517.2641268984535;1526.9465568705043;1527.3814105287377;1529.8240702319154;1541.9460318888785;866.8526350520426;973.7516933181993;1002.7593377589326;1012.3571532077184;1017.9032991865761;4940.925886111711;5194.792208311701;5478.096114141122;5693.402192769782;6177.567570270128;5960.523158147338;6132.386761323832;6399.712012959335;6530.934690653033;6709.36261055199;6093.8476538086015;6120.693965301717;6130.938690613037;6159.72281247336;6226.221722284715;3075.4156710224984;3130.358276553301;3321.8837340693444;3447.1037530242315;3489.337025965109;6515.0553169791065;6707.932920670828;6762.898556521656;6883.27725588816;6906.792796216121;6082.178905847654;6359.491240700733;6446.129772480749;6828.351306625863;6907.6513985244255;5695.658260504383;5980.491658209014;6596.80209593713;6768.69540870662;6910.8617827644175;6681.5656982296;6714.52998290115;6731.798046058626;6807.426101296859;6809.829754255985;4774.140654682191;6481.287058423664;6482.740690372332;6682.498812588978;6721.596704197764;5760.913586296293;6724.8991265132045;6849.759933650662;6971.51199416036;7003.6848341823725;4293.184294983943;4373.803178857022;4382.758948257749;4397.230484665235;4407.008423104776;6672.83317917056;6785.59286442815;6792.490563207878;6792.592444453348;6794.150731158606;6267.561270711007;6469.45009674183;6544.018397240452;6550.7707230246715;6555.050227397594;5920.526357891272;6503.447206987459;6537.248216454978;6555.672216389066;6555.80332590023;4855.9074208302845;5985.790497332572;6434;6491.610503369813;6496.740130394731;5710.685912274698;6305.96847015762;6319.462845657987;6556.213254409311;6688.230853451721;6426.582272152295;6448.387403196689;6450.709718062604;6456.418922296828;6485.351464853666 03:41:01 INFO - PID 1910 | |5;yui.html;743.9962800185998;834.5118105908043;850.7787975126465;874.4840544078994;875.9211670949613;16251.75622365664;16748.66502669946;16818.07500587467;18260.908695456503;19080.427587172406;16586.83413165868;16678.249478773454;16826.24281907314;18108.185131669074;18616.813831861677;996.7358649957749;1023.825949588568;1025.7948410317929;1025.8563801067855;1027.1782573940854;980.1374790184627;1024.651618449727;1024.7284469615558;1025.0108645157422;1027.2501074215827;1064.872215334157;1070.7376692710259;1073.1951036722458;1075.0271004740723;1075.8224892892663;1057.4765491081887;1068.9684454501405;1073.1843798712957;1075.5482697267166;1076.682378698286;792.1484404265381;793.3454899707707;795.4113955672818;795.5107608820533;796.0924546017544;14093.2953352333;16537.751933721003;17096.57258568546;17288.913555432267;17577.68167387451;16734;16779.077150756704;17034.233459494353;17140.571485712957;17269.136543172917;13782.241976691277;13854.86145138556;13926.93036534811;13972.371243294076;14120.576382708536;3112.393083348748;3510.912227194315;3536.7877927324444;3545.574531056264;3546.0957455848593;113154;114050.42974785157;114839;116137;117063.41468292604;12836.358182090858;12889.419976101099;13186.406611702496;13188;13235.205887646773;24872.5025499489;25542.48915021689;25621.10326138576;25680.97276108972;25943.35141621437;7582.696692132363;8642.006169290486;8642.740717778477;8651.005134409497;8652.783680407914;4454.821807127679;4525.389072475174;4770.212914869077;4886.731229782325;4946.08497427977;3405.9659403406145;4075.4294398784195;4105.753654780723;4109.4246805447265;4112.527059388149;755.1881727143343;760.5930827007525;772.2277722277722;928.2481190235931;952.9047095290554;660.6795704083515;672.1161672400777;674.6828990374614;687.9277675844064;689.6172624193521;10650.840237396445;10921.290116142425;11165.665030049111;11179.944100279608;11442.313461192189;637.4295005969724;653.4478365780908;663.3167837127767;672.4788289075907;684.1448189762797;5316.548093412026;5380.650257733235;5411.675299482044;5553.055980483327;5680.744366503517;94539;95776;96069.5589566157;96758;98435.50782246186;67988;88294;89100;89396;89944.5502772482;81250.59374703206;81733.18266817255;81975.9999999988;82741.75887361694;84144;9439.669611563562;11048.337099774039;11344.546218151181;11581.42092895335;11601.883981160418;40025.399619005155;40754;41095.1780964385;41988.37017444802;42100;53689.19466207932;53862.38412847621;54634.90730185454;54786;54983.900321992536;13896.861031389557;14160.716785664023;14408.927955360576;14450.710985780015;14491;15601.921990389976;15784.289706963444;15816.920915395349;15938.203089845692;15950.760738588699;14557.12657240569;14735.189564574051;14742.410303587736;14767.04014239071;14915.776263356276;14379.78430323525;14387.85612143907;14682.706345873235;14704.338304776096;14730.55808325752;14303;14346.784798228244;14463.85536144625;14519.346629401913;14570;10453.954604539485;10720.785584288114;10864.456777160953;10894.56421743153;10962.123030157727;11319.264247823865;11728.530858765895;11908.166428349932;12036.03711703044;12078.939605301917 03:41:01 INFO - PID 1910 | __end_tp_report 03:41:01 INFO - PID 1910 | __start_cc_report 03:41:01 INFO - PID 1910 | _x_x_mozilla_cycle_collect,432 03:41:01 INFO - PID 1910 | __end_cc_report 03:41:01 INFO - PID 1910 | __startTimestamp1492080061309__endTimestamp 03:41:01 INFO - PID 1910 | 03:41:01 INFO - PID 1910 | ------- Summary: start ------- 03:41:01 INFO - PID 1910 | Number of tests: 6 03:41:01 INFO - PID 1910 | 03:41:01 INFO - PID 1910 | [#0] dojo.html Cycles:165 Average:6133.68 Median:6345.87 stddev:748.62 (11.8%) stddev-sans-first:723.56 03:41:01 INFO - PID 1910 | Values: 3577.2 3587.0 3603.1 3617.4 3630.2 6129.8 6223.6 6236.9 6276.3 6278.0 6127.6 6186.6 6200.3 6250.2 6276.4 5476.2 5481.4 5506.7 5528.4 5585.4 5404.3 5503.4 5588.5 5591.0 5605.6 5437.4 5481.7 5506.0 5532.6 5569.1 4425.3 5446.7 5451.9 5490.0 5545.1 5441.5 5445.8 5447.7 5453.4 5467.6 4996.0 6094.1 6104.5 6135.5 6224.4 5578.8 6608.6 6721.9 6723.9 6724.3 6799.6 6845.3 6846.1 6847.4 6859.4 6155.2 6336.8 6339.7 6345.2 6345.9 6748.8 6910.4 6959.6 6983.6 6996.7 6320.6 6402.6 6415.9 6590.7 6670.9 6713.4 6715.9 6760.9 6774.2 6784.8 5794.9 6283.6 6687.6 6696.9 6715.6 5429.7 6442.6 6490.8 6640.1 6722.1 5340.2 5349.1 5357.1 5360.8 5380.8 4701.7 4708.6 4716.1 4723.0 4766.7 5344.8 5824.0 6231.5 6274.5 6343.8 6211.4 6744.8 6752.3 6810.4 6863.6 6399.9 6409.3 6418.8 6430.5 6441.8 5763.9 5956.8 6010.4 6025.9 6066.0 6722.8 6864.1 6911.1 6944.6 6967.1 6787.0 6788.8 6818.7 6831.6 6842.5 6864.9 6874.9 6892.4 6959.7 6960.5 6395.7 6490.2 6633.6 6669.9 6685.2 6696.3 6814.1 6835.4 6835.5 6858.1 5441.5 6335.2 6377.8 6382.2 6590.5 5523.6 6583.6 6590.5 6605.9 6626.5 4603.1 6031.7 6255.9 6313.7 6460.2 6352.4 6469.4 6545.6 6561.5 6602.0 6421.1 6443.3 6450.8 6451.6 6465.9 03:41:01 INFO - PID 1910 | 03:41:01 INFO - PID 1910 | [#1] ext.html Cycles:180 Average:45853.98 Median:35039.91 stddev:83214.83 (237.5%) stddev-sans-first:83379.52 03:41:01 INFO - PID 1910 | Values: 801.4 830.3 1096.9 1140.4 1281.7 28665.0 34195.5 35260.9 38133.0 38151.8 37698.8 38128.4 38202.6 38226.2 38286.6 7003.5 7276.8 7994.8 8157.0 8323.3 7242.4 8417.1 8479.2 8489.8 8491.2 7127.8 8439.1 8483.8 8502.2 8586.1 8389.1 8427.2 8440.7 8458.9 8468.6 8436.4 8469.7 8488.6 8494.7 8519.3 37117.3 37446.4 37466.8 37657.4 37722.9 3650.0 3677.2 3697.6 3704.5 3706.9 23678.2 30438.5 30680.8 30780.5 30869.1 3063.9 3110.9 3114.6 3120.2 3121.4 249475.8 249838.0 250444.0 251001.0 251229.0 35184.6 39564.4 45867.3 48103.0 48160.3 50513.0 50660.2 50957.2 50972.0 51027.5 14556.4 14695.1 14714.6 14754.3 14769.3 9962.0 11849.5 11860.5 11917.8 11929.0 11462.5 11557.1 13093.9 13579.9 13580.9 5829.2 5833.5 5873.7 5886.5 5891.8 1044.9 1067.4 1077.5 1077.6 1094.1 19580.4 20753.1 21692.0 23829.5 24662.6 1146.9 1160.0 1174.9 1191.9 1200.2 10223.9 12463.7 12543.8 12570.9 12570.9 360733.0 361417.0 363124.0 365287.0 370461.0 49856.5 50832.7 54711.7 54914.5 55035.0 1496.5 1547.0 1554.9 1559.5 1586.7 24209.8 24289.5 24348.3 24403.3 24495.5 38344.4 38422.8 38570.2 38588.6 38618.2 322060.4 322286.0 322790.0 323834.0 324205.0 34749.8 34945.0 35134.8 35155.6 35257.0 44900.8 45849.8 45864.8 45877.0 45983.3 30062.7 30790.7 31505.1 31510.7 31924.4 23910.6 30716.5 31275.2 31411.7 31589.4 37512.2 37534.0 37701.2 37730.4 37798.8 24839.5 29545.6 30163.8 30472.2 31375.2 18363.7 23546.6 24252.8 24306.1 24322.8 03:41:01 INFO - PID 1910 | 03:41:01 INFO - PID 1910 | [#2] jquery.html Cycles:180 Average:41555.26 Median:13144.65 stddev:118677.93 (902.9%) stddev-sans-first:118970.61 03:41:01 INFO - PID 1910 | Values: 390.9 425.9 438.1 438.2 458.9 19841.6 21420.0 22053.0 22312.0 22394.1 18117.0 22175.1 23347.1 23815.5 23872.2 6263.0 6398.1 6460.2 6462.9 6464.7 6194.4 6338.7 6349.7 6410.8 6421.3 6171.7 6295.8 6328.2 6374.6 6379.6 6316.9 6349.9 6371.3 6375.0 6375.7 5541.4 5630.8 5666.1 5666.4 5692.3 27292.3 27738.3 27874.3 28020.2 28409.9 2201.2 2267.8 2269.6 2270.6 2270.9 17797.6 17982.4 18056.3 18512.2 18724.7 2127.4 2138.9 2177.2 2179.3 2181.2 135764.0 161373.0 162560.0 164235.0 168578.2 12452.5 13037.5 13201.7 13224.3 13255.6 16924.3 17144.2 17203.4 17231.8 17259.7 9191.6 9233.8 9296.5 9442.2 9445.8 8852.9 8861.1 8952.4 9040.0 9065.9 7986.7 8270.0 8282.6 8288.4 8292.0 1748.4 1852.5 1875.8 2183.7 2417.1 824.5 909.2 941.8 968.4 991.9 20738.4 21468.9 21479.2 21496.9 21571.5 837.7 917.6 988.3 1013.6 1034.7 8418.9 8736.0 9057.1 9388.9 9803.0 667665.0 675192.0 695233.0 733120.0 754377.0 136153.0 156090.1 165760.0 172250.0 172679.0 1125.2 1160.9 1200.3 1239.3 1240.0 20435.4 21790.8 21911.0 22221.8 22428.6 49418.0 58756.9 63275.4 63942.4 64850.2 89594.4 89835.7 90779.5 91514.0 92249.0 8797.9 8881.0 8887.8 9164.0 9401.0 14749.3 14766.9 14801.5 14879.3 15038.4 11134.6 11898.5 11926.3 11949.3 12352.4 11386.7 11461.7 11705.6 11929.7 11931.5 12140.1 12320.9 12404.4 12493.9 12500.6 8951.1 8994.6 9070.5 9339.5 9354.3 11847.5 12125.7 12210.7 12319.7 12439.0 03:41:01 INFO - PID 1910 | 03:41:01 INFO - PID 1910 | [#3] mootools.html Cycles:180 Average:7190.17 Median:2516.10 stddev:4558.70 (181.2%) stddev-sans-first:4549.59 03:41:01 INFO - PID 1910 | Values: 1245.2 1293.8 1405.5 1412.8 1416.5 11092.9 11402.3 11789.5 11809.1 12094.5 9821.1 11755.3 11755.7 11915.7 11933.3 2296.6 2331.8 2472.8 2514.4 2515.8 2053.0 2301.1 2401.3 2514.7 2516.4 2419.3 2461.3 2472.2 2508.4 2516.5 2416.5 2483.9 2491.3 2502.1 2503.8 1994.9 2394.8 2401.9 2461.1 2516.5 11921.2 12010.3 12017.6 12024.9 12055.8 6542.4 6824.0 6826.9 6828.0 6841.0 4054.9 4288.2 4350.3 4395.5 4430.0 1087.9 1185.1 1221.3 1269.6 1269.9 14512.8 14995.1 16535.5 16658.2 16739.9 5070.9 5479.0 5661.1 5683.7 5696.8 10430.8 12108.9 12335.1 12470.2 12508.4 6510.5 6557.8 6590.8 6601.1 6602.6 6142.9 6193.6 6196.7 6220.3 6235.3 4330.8 4398.3 4418.8 4481.4 4481.9 1235.9 1240.6 1242.6 1256.3 1261.6 804.1 805.7 828.2 832.8 861.5 5914.8 6141.8 6149.5 6363.2 6412.4 590.3 594.9 602.8 610.5 616.4 3562.0 4194.6 4205.2 4456.6 4636.6 14698.0 15372.5 15395.8 15396.5 15419.4 14924.5 15296.5 15354.3 15408.7 15501.7 10845.5 11087.0 11125.5 11151.2 11163.5 5974.7 6670.5 7169.6 7381.8 7405.1 12782.4 12830.8 12890.2 13085.0 13132.7 13148.2 13180.7 13252.2 13408.3 13464.9 9605.2 9652.2 9701.5 9762.4 9805.8 10911.6 11035.6 11048.9 11058.9 11059.9 9092.1 9308.3 9325.2 9367.9 9381.0 8557.1 9181.1 9224.9 9230.4 9242.2 7543.8 7786.5 7895.0 8467.7 8489.8 6131.3 6152.7 6258.5 6291.9 6366.5 5464.8 6309.3 6380.5 6392.8 6509.7 03:41:01 INFO - PID 1910 | 03:41:01 INFO - PID 1910 | [#4] prototype.html Cycles:180 Average:5178.65 Median:5851.78 stddev:1666.31 (28.5%) stddev-sans-first:1631.20 03:41:01 INFO - PID 1910 | Values: 356.3 389.8 394.3 404.1 408.8 5134.0 5156.3 5888.2 5994.7 6005.1 4400.2 5448.3 5885.8 6024.7 6044.8 3224.0 4156.4 4442.2 4456.7 4474.6 4055.6 4203.2 4441.8 4453.5 4477.3 3532.5 4201.5 4379.5 4418.8 4418.9 3644.9 3824.7 4268.3 4355.5 4432.8 3646.3 3783.7 3789.8 3807.2 3815.2 4802.5 5729.6 5776.5 5789.6 5817.8 5089.6 5209.4 5294.6 5296.1 5318.6 6414.6 6479.8 6488.4 6509.9 6520.4 3755.5 4303.1 4311.9 4316.2 4331.2 6133.3 6581.3 6831.5 6882.9 6996.4 4113.4 4226.9 4433.3 4435.2 4437.3 4466.0 4466.8 4490.2 4494.7 4506.4 4089.8 4688.5 4984.2 5118.5 5302.2 6434.9 6481.3 6505.9 6547.9 6568.4 1517.3 1526.9 1527.4 1529.8 1541.9 866.9 973.8 1002.8 1012.4 1017.9 4940.9 5194.8 5478.1 5693.4 6177.6 5960.5 6132.4 6399.7 6530.9 6709.4 6093.8 6120.7 6130.9 6159.7 6226.2 3075.4 3130.4 3321.9 3447.1 3489.3 6515.1 6707.9 6762.9 6883.3 6906.8 6082.2 6359.5 6446.1 6828.4 6907.7 5695.7 5980.5 6596.8 6768.7 6910.9 6681.6 6714.5 6731.8 6807.4 6809.8 4774.1 6481.3 6482.7 6682.5 6721.6 5760.9 6724.9 6849.8 6971.5 7003.7 4293.2 4373.8 4382.8 4397.2 4407.0 6672.8 6785.6 6792.5 6792.6 6794.2 6267.6 6469.5 6544.0 6550.8 6555.1 5920.5 6503.4 6537.2 6555.7 6555.8 4855.9 5985.8 6434.0 6491.6 6496.7 5710.7 6306.0 6319.5 6556.2 6688.2 6426.6 6448.4 6450.7 6456.4 6485.4 03:41:01 INFO - PID 1910 | 03:41:01 INFO - PID 1910 | [#5] yui.html Cycles:180 Average:20767.81 Median:17279.03 stddev:28782.54 (166.6%) stddev-sans-first:28824.01 03:41:01 INFO - PID 1910 | Values: 744.0 834.5 850.8 874.5 875.9 16251.8 16748.7 16818.1 18260.9 19080.4 16586.8 16678.2 16826.2 18108.2 18616.8 996.7 1023.8 1025.8 1025.9 1027.2 980.1 1024.7 1024.7 1025.0 1027.3 1064.9 1070.7 1073.2 1075.0 1075.8 1057.5 1069.0 1073.2 1075.5 1076.7 792.1 793.3 795.4 795.5 796.1 14093.3 16537.8 17096.6 17288.9 17577.7 16734.0 16779.1 17034.2 17140.6 17269.1 13782.2 13854.9 13926.9 13972.4 14120.6 3112.4 3510.9 3536.8 3545.6 3546.1 113154.0 114050.4 114839.0 116137.0 117063.4 12836.4 12889.4 13186.4 13188.0 13235.2 24872.5 25542.5 25621.1 25681.0 25943.4 7582.7 8642.0 8642.7 8651.0 8652.8 4454.8 4525.4 4770.2 4886.7 4946.1 3406.0 4075.4 4105.8 4109.4 4112.5 755.2 760.6 772.2 928.2 952.9 660.7 672.1 674.7 687.9 689.6 10650.8 10921.3 11165.7 11179.9 11442.3 637.4 653.4 663.3 672.5 684.1 5316.5 5380.7 5411.7 5553.1 5680.7 94539.0 95776.0 96069.6 96758.0 98435.5 67988.0 88294.0 89100.0 89396.0 89944.6 81250.6 81733.2 81976.0 82741.8 84144.0 9439.7 11048.3 11344.5 11581.4 11601.9 40025.4 40754.0 41095.2 41988.4 42100.0 53689.2 53862.4 54634.9 54786.0 54983.9 13896.9 14160.7 14408.9 14450.7 14491.0 15601.9 15784.3 15816.9 15938.2 15950.8 14557.1 14735.2 14742.4 14767.0 14915.8 14379.8 14387.9 14682.7 14704.3 14730.6 14303.0 14346.8 14463.9 14519.3 14570.0 10454.0 10720.8 10864.5 10894.6 10962.1 11319.3 11728.5 11908.2 12036.0 12078.9 03:41:01 INFO - PID 1910 | -------- Summary: end -------- 03:41:01 INFO - PID 1910 | 03:41:01 INFO - TEST-INFO | 1910: exit 0 03:41:01 INFO - TEST-OK | dromaeo_css | took 1115526ms 03:41:01 INFO - TEST-START | kraken 03:41:01 INFO - operating with platform_type : mac_ 03:41:01 INFO - Initialising browser for kraken test... 03:41:01 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/tmpcOmZWQ/profile http://localhost:49361/getInfo.html) 03:41:04 INFO - PID 1938 | 2017-04-13 03:41:04.745 plugin-container[1939:16744] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 03:41:04 INFO - PID 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:41:04 INFO - PID 1938 | 2017-04-13 03:41:04.748 plugin-container[1939:16744] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 03:41:04 INFO - PID 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:41:04 INFO - PID 1938 | 2017-04-13 03:41:04.748 plugin-container[1939:16744] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:41:05 INFO - PID 1938 | __metrics Screen width/height:1600/1200 03:41:05 INFO - PID 1938 | colorDepth:24 03:41:05 INFO - PID 1938 | Browser inner width/height: 1024/689 03:41:05 INFO - PID 1938 | __metrics 03:41:05 ERROR - PID 1938 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 03:41:06 INFO - TEST-INFO | 1938: exit 0 03:41:06 INFO - Browser initialized. 03:41:06 INFO - Running cycle 1/1 for kraken test... 03:41:06 INFO - TEST-INFO | started process 1940 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/T/tmpcOmZWQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:41:09 INFO - PID 1940 | 2017-04-13 03:41:09.140 plugin-container[1941:16865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 03:41:09 INFO - PID 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:41:09 INFO - PID 1940 | 2017-04-13 03:41:09.141 plugin-container[1941:16865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 03:41:09 INFO - PID 1940 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:41:09 INFO - PID 1940 | 2017-04-13 03:41:09.141 plugin-container[1941:16865] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:41:18 INFO - PID 1940 | RSS: Main: 250810368 03:41:18 INFO - PID 1940 | 03:41:21 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-beat-detection) 03:41:22 INFO - PID 1940 | RSS: Main: 260321280 03:41:22 INFO - PID 1940 | 03:41:25 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-dft) 03:41:25 INFO - PID 1940 | RSS: Main: 267501568 03:41:25 INFO - PID 1940 | 03:41:29 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-fft) 03:41:30 INFO - PID 1940 | RSS: Main: 263528448 03:41:30 INFO - PID 1940 | 03:41:32 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-oscillator) 03:41:32 INFO - PID 1940 | RSS: Main: 264531968 03:41:32 INFO - PID 1940 | 03:41:35 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49361/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:41:35 INFO - PID 1940 | RSS: Main: 266227712 03:41:35 INFO - PID 1940 | 03:41:39 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49361/tests/kraken/driver.html?testName=imaging-darkroom) 03:41:39 INFO - PID 1940 | RSS: Main: 272019456 03:41:39 INFO - PID 1940 | 03:41:42 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49361/tests/kraken/driver.html?testName=imaging-desaturate) 03:41:43 INFO - PID 1940 | RSS: Main: 272412672 03:41:43 INFO - PID 1940 | 03:41:46 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49361/tests/kraken/driver.html?testName=json-parse-financial) 03:41:47 INFO - PID 1940 | RSS: Main: 272015360 03:41:47 INFO - PID 1940 | 03:41:48 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49361/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:41:49 INFO - PID 1940 | RSS: Main: 271769600 03:41:49 INFO - PID 1940 | 03:41:51 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:41:52 INFO - PID 1940 | RSS: Main: 271695872 03:41:52 INFO - PID 1940 | 03:41:54 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:41:54 INFO - PID 1940 | RSS: Main: 271740928 03:41:54 INFO - PID 1940 | 03:41:57 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:41:58 INFO - PID 1940 | RSS: Main: 272048128 03:41:58 INFO - PID 1940 | 03:42:02 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:42:02 INFO - PID 1940 | RSS: Main: 271884288 03:42:02 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49361/tests/kraken/driver.html?testName=ai-astar) 03:42:04 INFO - PID 1940 | RSS: Main: 275267584 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | __start_tp_report 03:42:04 INFO - PID 1940 | _x_x_mozilla_page_load 03:42:04 INFO - PID 1940 | _x_x_mozilla_page_load_details 03:42:04 INFO - PID 1940 | |i|pagename|runs| 03:42:04 INFO - PID 1940 | |0;ai-astar;111;123;123;124;120;118;123;108;181;179 03:42:04 INFO - PID 1940 | |1;audio-beat-detection;180;139;122;155;113;119;117;114;137;116 03:42:04 INFO - PID 1940 | |2;audio-dft;220;223;231;227;222;219;216;213;213;217 03:42:04 INFO - PID 1940 | |3;audio-fft;82;65;54;92;85;92;58;91;108;114 03:42:04 INFO - PID 1940 | |4;audio-oscillator;96;112;125;108;75;107;125;111;106;85 03:42:04 INFO - PID 1940 | |5;imaging-gaussian-blur;121;81;135;105;107;101;81;125;81;82 03:42:04 INFO - PID 1940 | |6;imaging-darkroom;99;100;101;100;99;100;101;108;100;99 03:42:04 INFO - PID 1940 | |7;imaging-desaturate;110;149;103;105;111;166;137;102;104;101 03:42:04 INFO - PID 1940 | |8;json-parse-financial;52;51;51;51;53;52;52;62;53;46 03:42:04 INFO - PID 1940 | |9;json-stringify-tinderbox;63;98;88;69;64;59;59;56;58;59 03:42:04 INFO - PID 1940 | |10;stanford-crypto-aes;86;85;89;88;88;86;86;85;92;87 03:42:04 INFO - PID 1940 | |11;stanford-crypto-ccm;153;171;149;147;159;156;169;143;193;153 03:42:04 INFO - PID 1940 | |12;stanford-crypto-pbkdf2;194;199;195;195;192;231;254;197;196;190 03:42:04 INFO - PID 1940 | |13;stanford-crypto-sha256-iterative;72;71;73;69;69;73;71;72;70;72 03:42:04 INFO - PID 1940 | __end_tp_report 03:42:04 INFO - PID 1940 | __start_cc_report 03:42:04 INFO - PID 1940 | _x_x_mozilla_cycle_collect,1018 03:42:04 INFO - PID 1940 | __end_cc_report 03:42:04 INFO - PID 1940 | __startTimestamp1492080124670__endTimestamp 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | ------- Summary: start ------- 03:42:04 INFO - PID 1940 | Number of tests: 14 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#0] ai-astar Cycles:10 Average:131.00 Median:123.00 stddev:26.38 (21.4%) stddev-sans-first:26.97 03:42:04 INFO - PID 1940 | Values: 111.0 123.0 123.0 124.0 120.0 118.0 123.0 108.0 181.0 179.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:129.50 stddev:21.95 (16.9%) stddev-sans-first:14.53 03:42:04 INFO - PID 1940 | Values: 180.0 139.0 122.0 155.0 113.0 119.0 117.0 114.0 137.0 116.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#2] audio-dft Cycles:10 Average:220.10 Median:221.00 stddev:5.84 (2.6%) stddev-sans-first:6.19 03:42:04 INFO - PID 1940 | Values: 220.0 223.0 231.0 227.0 222.0 219.0 216.0 213.0 213.0 217.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#3] audio-fft Cycles:10 Average:84.10 Median:83.50 stddev:19.99 (23.9%) stddev-sans-first:21.18 03:42:04 INFO - PID 1940 | Values: 82.0 65.0 54.0 92.0 85.0 92.0 58.0 91.0 108.0 114.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#4] audio-oscillator Cycles:10 Average:105.00 Median:125.00 stddev:15.92 (12.7%) stddev-sans-first:16.55 03:42:04 INFO - PID 1940 | Values: 96.0 112.0 125.0 108.0 75.0 107.0 125.0 111.0 106.0 85.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#5] imaging-gaussian-blur Cycles:10 Average:101.90 Median:108.00 stddev:20.38 (18.9%) stddev-sans-first:20.41 03:42:04 INFO - PID 1940 | Values: 121.0 81.0 135.0 105.0 107.0 101.0 81.0 125.0 81.0 82.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#6] imaging-darkroom Cycles:10 Average:100.70 Median:104.50 stddev:2.67 (2.6%) stddev-sans-first:2.76 03:42:04 INFO - PID 1940 | Values: 99.0 100.0 101.0 100.0 99.0 100.0 101.0 108.0 100.0 99.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#7] imaging-desaturate Cycles:10 Average:118.80 Median:110.50 stddev:23.26 (21.0%) stddev-sans-first:24.45 03:42:04 INFO - PID 1940 | Values: 110.0 149.0 103.0 105.0 111.0 166.0 137.0 102.0 104.0 101.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.00 stddev:3.95 (7.6%) stddev-sans-first:4.18 03:42:04 INFO - PID 1940 | Values: 52.0 51.0 51.0 51.0 53.0 52.0 52.0 62.0 53.0 46.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#9] json-stringify-tinderbox Cycles:10 Average:67.30 Median:63.50 stddev:14.24 (22.4%) stddev-sans-first:15.01 03:42:04 INFO - PID 1940 | Values: 63.0 98.0 88.0 69.0 64.0 59.0 59.0 56.0 58.0 59.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:87.50 stddev:2.15 (2.5%) stddev-sans-first:2.24 03:42:04 INFO - PID 1940 | Values: 86.0 85.0 89.0 88.0 88.0 86.0 86.0 85.0 92.0 87.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#11] stanford-crypto-ccm Cycles:10 Average:159.30 Median:157.50 stddev:14.83 (9.4%) stddev-sans-first:15.56 03:42:04 INFO - PID 1940 | Values: 153.0 171.0 149.0 147.0 159.0 156.0 169.0 143.0 193.0 153.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:204.30 Median:196.50 stddev:21.00 (10.7%) stddev-sans-first:21.94 03:42:04 INFO - PID 1940 | Values: 194.0 199.0 195.0 195.0 192.0 231.0 254.0 197.0 196.0 190.0 03:42:04 INFO - PID 1940 | 03:42:04 INFO - PID 1940 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:72.00 stddev:1.48 (2.0%) stddev-sans-first:1.54 03:42:04 INFO - PID 1940 | Values: 72.0 71.0 73.0 69.0 69.0 73.0 71.0 72.0 70.0 72.0 03:42:04 INFO - PID 1940 | -------- Summary: end -------- 03:42:04 INFO - PID 1940 | 03:42:05 INFO - TEST-INFO | 1940: exit 0 03:42:05 INFO - TEST-OK | kraken | took 63796ms 03:42:05 INFO - SUITE-END | took 1179s 03:42:05 INFO - Completed test suite (00:19:39) 03:42:05 INFO - javascript benchmark 03:42:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7848.8706877942295, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3577.2308953574975, 3587.0315014945963, 3603.063203567072, 3617.3669607818624, 3630.1832087780235, 6129.846753831156, 6223.595466294693, 6236.875262494747, 6276.340984196662, 6278.026905829593, 6127.586602260213, 6186.628802271867, 6200.3179650238435, 6250.187475628162, 6276.372362763721, 5476.2333273341765, 5481.369642491105, 5506.669599824003, 5528.419515950827, 5585.413531579186, 5404.32445944257, 5503.367112782022, 5588.469095435927, 5591.0, 5605.5515558755405, 5437.429069947638, 5481.671099734029, 5506.0089183946875, 5532.5573954083575, 5569.081101618228, 4425.291953287491, 5446.700431476267, 5451.890962180773, 5490.011797876381, 5545.112781954868, 5441.5102640762525, 5445.83662490122, 5447.700376479292, 5453.4273901240595, 5467.644603100785, 4996.0, 6094.116353128771, 6104.450599446027, 6135.477655820245, 6224.377562243784, 5578.804741834017, 6608.636524991123, 6721.932780672179, 6723.865522689568, 6724.260331363546, 6799.5920244785475, 6845.281245469204, 6846.110005699227, 6847.383735463783, 6859.382655561023, 6155.230596175478, 6336.778212762577, 6339.714712837841, 6345.175127233476, 6345.873082538324, 6748.797536073827, 6910.412614927787, 6959.61722105289, 6983.580985140908, 6996.650167491605, 6320.55756097078, 6402.6158430494315, 6415.871682566322, 6590.736370545124, 6670.933290667128, 6713.395794378529, 6715.865682686319, 6760.932390676128, 6774.153230846145, 6784.7964561063245, 5794.942050579441, 6283.560150789493, 6687.598744075371, 6696.8995465068065, 6715.56348837324, 5429.701366424884, 6442.581232219891, 6490.8052758417325, 6640.136782218377, 6722.058911752359, 5340.172273297645, 5349.117395629755, 5357.116075847442, 5360.812371566976, 5380.811671591554, 4701.670883038155, 4708.552687494683, 4716.056788642285, 4722.976385118052, 4766.737829419346, 5344.786208551692, 5823.980803359395, 6231.532635052298, 6274.529410294246, 6343.809685709436, 6211.440970312603, 6744.763933262312, 6752.257251702309, 6810.353016463428, 6863.588184708933, 6399.9040014399825, 6409.262934762562, 6418.775342862978, 6430.485561155097, 6441.777586732664, 5763.8559036024435, 5956.761729530857, 6010.398960103955, 6025.9096113558335, 6066.02943529042, 6722.831929201711, 6864.107666003388, 6911.067005954333, 6944.583325000516, 6967.09427774386, 6787.049813026081, 6788.830279243059, 6818.693158807965, 6831.555948863308, 6842.486813488908, 6864.862702746017, 6874.8625027500175, 6892.414144797748, 6959.686814093277, 6960.47796415281, 6395.680215989275, 6490.188726409199, 6633.635150066792, 6669.933300666931, 6685.164354455693, 6696.330366963265, 6814.1482314710665, 6835.384815366641, 6835.453163746789, 6858.108445902001, 5441.5374693151525, 6335.208098987627, 6377.80866573985, 6382.170317858834, 6590.505712071518, 5523.558115350843, 6583.572067815577, 6590.538662293593, 6605.933940660532, 6626.469882409492, 4603.079384123122, 6031.673031932968, 6255.906161407492, 6313.715882785194, 6460.224773027079, 6352.396522330371, 6469.417752402306, 6545.639989800607, 6561.540692151694, 6602.0, 6421.068945002855, 6443.291237963914, 6450.774222902176, 6451.645159516084, 6465.903011454738], "value": 6086.340834249105, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [801.4109629422376, 830.2569200565492, 1096.935972107056, 1140.350000499715, 1281.6923938254822, 28665.0, 34195.487067693975, 35260.94217173482, 38133.0, 38151.80924095379, 37698.8115059425, 38128.42807357892, 38202.617973820255, 38226.23547529048, 38286.61713382865, 7003.47473939454, 7276.818079548001, 7994.760157195294, 8156.959215203916, 8323.334133269324, 7242.384397326234, 8417.11620279871, 8479.19447652472, 8489.787755306135, 8491.19333663301, 7127.8218044548785, 8439.071702112762, 8483.787905302355, 8502.234798868101, 8586.141385861427, 8389.119142490012, 8427.241548260656, 8440.746777596682, 8458.915410845875, 8468.618912148968, 8436.409451338408, 8469.66121355145, 8488.618012189467, 8494.660213591449, 8519.275861551776, 37117.257654846755, 37446.43830342547, 37466.81266593677, 37657.435138472945, 37722.868313950625, 3649.981750091259, 3677.2277821657485, 3697.574778900434, 3704.462852886343, 3706.8887933362043, 23678.17126400585, 30438.54342184869, 30680.846595767103, 30780.53829192564, 30869.073927782203, 3063.862126204326, 3110.9222269443217, 3114.595102636643, 3120.23554229213, 3121.406932682783, 249475.75262123754, 249838.0, 250444.0, 251001.0, 251229.0, 35184.64815351814, 39564.406533902016, 45867.31199032017, 48103.037939241716, 48160.27759583609, 50513.0, 50660.240096398586, 50957.235641465406, 50972.0, 51027.48972510227, 14556.417743290362, 14695.11829290246, 14714.55856324312, 14754.336054877556, 14769.33537990793, 9961.950190249147, 11849.466773995191, 11860.525578976916, 11917.761644767055, 11928.986036186847, 11462.48418821155, 11557.075433965263, 13093.869061309455, 13579.895116256996, 13580.93209533946, 5829.242198514166, 5833.533317334603, 5873.676947767828, 5886.499647529922, 5891.823245302648, 1044.9007344302279, 1067.3649178738638, 1077.504347999913, 1077.5635867473686, 1094.0645747885603, 19580.377898313258, 20753.066112024993, 21692.0, 23829.52340953206, 24662.59863648106, 1146.908247340211, 1160.042964554246, 1174.8766379530198, 1191.9463624136936, 1200.2078628105583, 10223.948880255552, 12463.688407789878, 12543.811842822364, 12570.874291257152, 12570.937145314216, 360733.0, 361417.0, 363124.0, 365287.0, 370461.0, 49856.501434985184, 50832.72918177001, 54711.72644136754, 54914.45085549093, 55035.0, 1496.483713118994, 1546.9535913922375, 1554.945576904803, 1559.532140357888, 1586.7223235933666, 24209.757902421454, 24289.51420971606, 24348.269551913472, 24403.26790196297, 24495.51008979846, 38344.42483362696, 38422.80788596039, 38570.228595427376, 38588.6141138585, 38618.22763544657, 322060.38969805, 322286.0, 322790.0, 323834.0, 324205.0, 34749.82625086858, 34945.0, 35134.82432587821, 35155.593776248665, 35257.0, 44900.77549612231, 45849.77075114736, 45864.77067614774, 45877.0, 45983.31025034694, 30062.69937300599, 30790.692093079677, 31505.054848354586, 31510.684893150774, 31924.36151277008, 23910.64134037956, 30716.539251911687, 31275.218119547193, 31411.685883140875, 31589.36821263608, 37512.249755005294, 37534.0, 37701.24597508089, 37730.43404348992, 37798.81100594479, 24839.503209935338, 29545.556816648197, 30163.849180753954, 30472.238194045327, 31375.21561960969, 18363.659827074545, 23546.646800298353, 24252.757472425757, 24306.149284774947, 24322.75677243205], "value": 17458.05383191209, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.8964124507006, 425.865852256539, 438.1478025240906, 438.2112198043521, 458.85775409623017, 19841.60316793665, 21420.0, 22053.0, 22311.99596018179, 22394.104235830568, 18117.0, 22175.1129954802, 23347.066117355287, 23815.5236895262, 23872.164474243404, 6263.029230469285, 6398.104265402847, 6460.192475940507, 6462.870742585145, 6464.676766161697, 6194.442500174983, 6338.714757835885, 6349.714262858182, 6410.839729006789, 6421.325760795119, 6171.660558669271, 6295.842603934892, 6328.177336946191, 6374.585651932633, 6379.585326953733, 6316.8629646663585, 6349.873002539969, 6371.267304259976, 6374.968125159345, 6375.71309291083, 5541.445855414467, 5630.8029218977135, 5666.093425051972, 5666.405027472098, 5692.345380281279, 27292.31769205766, 27738.306542336402, 27874.3031424214, 28020.159395218176, 28409.85795071032, 2201.1745595401726, 2267.7958983691547, 2269.580127676385, 2270.6139956207485, 2270.8751018693965, 17797.644047118985, 17982.37061702847, 18056.27774889003, 18512.16695248717, 18724.719129213074, 2127.414960885769, 2138.8823614701187, 2177.2488491470417, 2179.2917301876796, 2181.2256648889734, 135764.0, 161373.0, 162560.0, 164235.0, 168578.1571092149, 12452.501899924082, 13037.478500860048, 13201.669958251121, 13224.338783060808, 13255.602331930058, 16924.32302707878, 17144.228509717093, 17203.397881074354, 17231.82768172302, 17259.74110388345, 9191.586378612978, 9233.815323693489, 9296.488693121943, 9442.15020648145, 9445.811083778424, 8852.911470885338, 8861.113888611217, 8952.373333866566, 9040.005599384063, 9065.909340906506, 7986.720464783704, 8269.95865020671, 8282.627281772335, 8288.378371622153, 8292.04641466227, 1748.3793253395115, 1852.4720454670355, 1875.7749070111674, 2183.6942828004094, 2417.1419146203198, 824.47233770387, 909.2271569166156, 941.7786820097272, 968.4431451915177, 991.8809742830906, 20738.377848664262, 21468.926553672252, 21479.248226312004, 21496.92515374225, 21571.460713481974, 837.7151768398774, 917.5733284022828, 988.2686811759246, 1013.6097602422973, 1034.715453250362, 8418.873716894252, 8735.995360533492, 9057.094290571022, 9388.906110938804, 9803.019698030283, 667665.0, 675192.0, 695233.0, 733120.0, 754377.0, 136153.0, 156090.0977475572, 165760.0, 172250.0, 172679.0, 1125.2123513540555, 1160.878107798686, 1200.2918278215895, 1239.3369547292154, 1239.975200495985, 20435.38693839187, 21790.801505916694, 21911.0, 22221.777782222616, 22428.551428971656, 49418.01163976772, 58756.94350697705, 63275.36724632879, 63942.3605763955, 64850.22339053924, 89594.42300510395, 89835.65246521439, 90779.54610226907, 91514.0, 92249.0, 8797.900262467192, 8881.0, 8887.822243555222, 9164.037776033421, 9400.952995235253, 14749.262536873328, 14766.852331476977, 14801.481948131765, 14879.256037198313, 15038.398464061314, 11134.554617815196, 11898.453201083803, 11926.344051077274, 11949.2830430171, 12352.4441400139, 11386.715332116763, 11461.713457163638, 11705.648830535098, 11929.701757456132, 11931.522739090338, 12140.089493287862, 12320.881202183211, 12404.379781011094, 12493.875061249271, 12500.562480313145, 8951.104889511258, 8994.640214391351, 9070.501122438329, 9339.486328252011, 9354.345195836226, 11847.526098955945, 12125.69685757863, 12210.694732631755, 12319.692007699521, 12439.0], "value": 10808.213511569451, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1245.1532957588836, 1293.7929931211006, 1405.4940221520258, 1412.8375236847767, 1416.5325442603942, 11092.944535277333, 11402.258853174548, 11789.528418863247, 11809.11431642627, 12094.516219351237, 9821.116099551038, 11755.294682319067, 11755.706107347321, 11915.702107447318, 11933.34366609836, 2296.610550616878, 2331.755165707602, 2472.81453890958, 2514.383975925901, 2515.8364706294124, 2052.9794702052936, 2301.1255722825385, 2401.2556107606697, 2514.660520829683, 2516.3709072731817, 2419.322589674894, 2461.3231361375674, 2472.159465781634, 2508.435601989556, 2516.509280690266, 2416.4562973331035, 2483.863387513686, 2491.3273416177713, 2502.1492692484553, 2503.824732268742, 1994.8803071815737, 2394.8084153267696, 2401.891914863835, 2461.1016978802786, 2516.484120755241, 11921.225120367148, 12010.279383237033, 12017.579384721581, 12024.939875300655, 12055.819162712567, 6542.378474044958, 6824.0446337512785, 6826.873565861627, 6827.965860170717, 6841.0, 4054.898627534306, 4288.206681763883, 4350.347447882811, 4395.494518130424, 4430.0, 1087.9401632910187, 1185.146694380045, 1221.32216619776, 1269.60642200918, 1269.9428525716364, 14512.78230826528, 14995.100293982505, 16535.503934881974, 16658.16709164549, 16739.916300418543, 5070.909754402821, 5479.0, 5661.094224923992, 5683.744231509592, 5696.8006119785605, 10430.791384172275, 12108.939455302667, 12335.074869384833, 12470.189437686522, 12508.374581271082, 6510.511711621647, 6557.803265902031, 6590.835229119214, 6601.141851559362, 6602.570832895846, 6142.86357023952, 6193.597416167935, 6196.659183744847, 6220.315765265817, 6235.314115447297, 4330.848420305274, 4398.274284743045, 4418.756968366706, 4481.3726078349055, 4481.887952801206, 1235.9073069519823, 1240.5658019693215, 1242.571043300212, 1256.2650849253107, 1261.5647601577625, 804.0833449867156, 805.7179987004502, 828.159418190537, 832.8334333133276, 861.5261606116736, 5914.822555323347, 6141.846453838601, 6149.538784591084, 6363.204599425072, 6412.35876412355, 590.3270271890049, 594.9286085669747, 602.8251806975928, 610.4749915073033, 616.4082480818464, 3562.020444377765, 4194.580541945781, 4205.222033923734, 4456.554344565582, 4636.559526844944, 14698.0, 15372.461963831212, 15395.846041539666, 15396.46112386061, 15419.383224671112, 14924.47764328265, 15296.464623738117, 15354.309056092503, 15408.691826163415, 15501.689966200613, 10845.51195196218, 11087.002169804757, 11125.499352528994, 11151.163662725166, 11163.497642606102, 5974.65570246691, 6670.533062685566, 7169.641517924188, 7381.778546643609, 7405.111386633638, 12782.424790884246, 12830.807537886752, 12890.162139460905, 13085.018623603459, 13132.671683207996, 13148.211107333591, 13180.670483237996, 13252.20486770797, 13408.32958352059, 13464.865351346361, 9605.18355939753, 9652.179564737076, 9701.466419347003, 9762.36544624597, 9805.803883922139, 10911.563537458413, 11035.613753518588, 11048.944755276172, 11058.889411106107, 11059.944700276768, 9092.136247056518, 9308.301877359361, 9325.160735533835, 9367.85948210791, 9381.0, 8557.144285571392, 9181.081891811033, 9224.907750922404, 9230.446173229358, 9242.168204861595, 7543.811404714926, 7786.454948153575, 7895.0, 8467.745967620982, 8489.83020339602, 6131.264248290234, 6152.661603611666, 6258.499320054294, 6291.874162516816, 6366.490680745622, 5464.808731694372, 6309.337519560564, 6380.457661098859, 6392.840178995562, 6509.73961041572], "value": 5351.3679558549675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.2660918507873, 389.76419266343845, 394.27059939112644, 404.12708549533016, 408.75678971012246, 5133.974330128344, 5156.303898973638, 5888.175655408241, 5994.70026498675, 6005.0692142717835, 4400.229959757045, 5448.291722076125, 5885.7939972100985, 6024.69876506174, 6044.818655440333, 3223.9838800805965, 4156.376543518474, 4442.155990361837, 4456.732596044231, 4474.552544745532, 4055.5538890721996, 4203.159368126372, 4441.75570343631, 4453.48784889737, 4477.261251893434, 3532.5231093802395, 4201.537830838605, 4379.540148284432, 4418.756968366739, 4418.86743397697, 3644.908877278076, 3824.7131465139946, 4268.274393353137, 4355.477342718894, 4432.822687092496, 3646.2889736501393, 3783.735138540303, 3789.829457674411, 3807.200487897545, 3815.1606646537716, 4802.4957379475, 5729.570282228808, 5776.508996735282, 5789.594728369016, 5817.767289308423, 5089.643724939256, 5209.426963034063, 5294.602904782157, 5296.09966305729, 5318.601104917108, 6414.61512309263, 6479.7732079377465, 6488.351164883521, 6509.860774364467, 6520.380563846427, 3755.530558680165, 4303.139372125556, 4311.8706438806885, 4316.201502722007, 4331.177076355482, 6133.252023173419, 6581.26149172595, 6831.487638427088, 6882.931170688279, 6996.370326670624, 4113.424120623115, 4226.873193804191, 4433.334999750064, 4435.2016637005, 4437.334399839985, 4465.955340446619, 4466.843660471868, 4490.159034795718, 4494.685372023927, 4506.369108324837, 4089.795510224477, 4688.484266730657, 4984.2025275955675, 5118.462561430996, 5302.231176479466, 6434.871302573923, 6481.347256449575, 6505.902411463831, 6547.934520654828, 6568.405479835438, 1517.2641268984535, 1526.9465568705043, 1527.3814105287377, 1529.8240702319154, 1541.9460318888785, 866.8526350520426, 973.7516933181993, 1002.7593377589326, 1012.3571532077184, 1017.9032991865761, 4940.925886111711, 5194.792208311701, 5478.096114141122, 5693.402192769782, 6177.567570270128, 5960.523158147338, 6132.386761323832, 6399.712012959335, 6530.934690653033, 6709.36261055199, 6093.8476538086015, 6120.693965301717, 6130.938690613037, 6159.72281247336, 6226.221722284715, 3075.4156710224984, 3130.358276553301, 3321.8837340693444, 3447.1037530242315, 3489.337025965109, 6515.0553169791065, 6707.932920670828, 6762.898556521656, 6883.27725588816, 6906.792796216121, 6082.178905847654, 6359.491240700733, 6446.129772480749, 6828.351306625863, 6907.6513985244255, 5695.658260504383, 5980.491658209014, 6596.80209593713, 6768.69540870662, 6910.8617827644175, 6681.5656982296, 6714.52998290115, 6731.798046058626, 6807.426101296859, 6809.829754255985, 4774.140654682191, 6481.287058423664, 6482.740690372332, 6682.498812588978, 6721.596704197764, 5760.913586296293, 6724.8991265132045, 6849.759933650662, 6971.51199416036, 7003.6848341823725, 4293.184294983943, 4373.803178857022, 4382.758948257749, 4397.230484665235, 4407.008423104776, 6672.83317917056, 6785.59286442815, 6792.490563207878, 6792.592444453348, 6794.150731158606, 6267.561270711007, 6469.45009674183, 6544.018397240452, 6550.7707230246715, 6555.050227397594, 5920.526357891272, 6503.447206987459, 6537.248216454978, 6555.672216389066, 6555.80332590023, 4855.9074208302845, 5985.790497332572, 6434.0, 6491.610503369813, 6496.740130394731, 5710.685912274698, 6305.96847015762, 6319.462845657987, 6556.213254409311, 6688.230853451721, 6426.582272152295, 6448.387403196689, 6450.709718062604, 6456.418922296828, 6485.351464853666], "value": 4662.0368703350605, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.9962800185998, 834.5118105908043, 850.7787975126465, 874.4840544078994, 875.9211670949613, 16251.75622365664, 16748.66502669946, 16818.07500587467, 18260.908695456503, 19080.427587172406, 16586.83413165868, 16678.249478773454, 16826.24281907314, 18108.185131669074, 18616.813831861677, 996.7358649957749, 1023.825949588568, 1025.7948410317929, 1025.8563801067855, 1027.1782573940854, 980.1374790184627, 1024.651618449727, 1024.7284469615558, 1025.0108645157422, 1027.2501074215827, 1064.872215334157, 1070.7376692710259, 1073.1951036722458, 1075.0271004740723, 1075.8224892892663, 1057.4765491081887, 1068.9684454501405, 1073.1843798712957, 1075.5482697267166, 1076.682378698286, 792.1484404265381, 793.3454899707707, 795.4113955672818, 795.5107608820533, 796.0924546017544, 14093.2953352333, 16537.751933721003, 17096.57258568546, 17288.913555432267, 17577.68167387451, 16734.0, 16779.077150756704, 17034.233459494353, 17140.571485712957, 17269.136543172917, 13782.241976691277, 13854.86145138556, 13926.93036534811, 13972.371243294076, 14120.576382708536, 3112.393083348748, 3510.912227194315, 3536.7877927324444, 3545.574531056264, 3546.0957455848593, 113154.0, 114050.42974785157, 114839.0, 116137.0, 117063.41468292604, 12836.358182090858, 12889.419976101099, 13186.406611702496, 13188.0, 13235.205887646773, 24872.5025499489, 25542.48915021689, 25621.10326138576, 25680.97276108972, 25943.35141621437, 7582.696692132363, 8642.006169290486, 8642.740717778477, 8651.005134409497, 8652.783680407914, 4454.821807127679, 4525.389072475174, 4770.212914869077, 4886.731229782325, 4946.08497427977, 3405.9659403406145, 4075.4294398784195, 4105.753654780723, 4109.4246805447265, 4112.527059388149, 755.1881727143343, 760.5930827007525, 772.2277722277722, 928.2481190235931, 952.9047095290554, 660.6795704083515, 672.1161672400777, 674.6828990374614, 687.9277675844064, 689.6172624193521, 10650.840237396445, 10921.290116142425, 11165.665030049111, 11179.944100279608, 11442.313461192189, 637.4295005969724, 653.4478365780908, 663.3167837127767, 672.4788289075907, 684.1448189762797, 5316.548093412026, 5380.650257733235, 5411.675299482044, 5553.055980483327, 5680.744366503517, 94539.0, 95776.0, 96069.5589566157, 96758.0, 98435.50782246186, 67988.0, 88294.0, 89100.0, 89396.0, 89944.5502772482, 81250.59374703206, 81733.18266817255, 81975.9999999988, 82741.75887361694, 84144.0, 9439.669611563562, 11048.337099774039, 11344.546218151181, 11581.42092895335, 11601.883981160418, 40025.399619005155, 40754.0, 41095.1780964385, 41988.37017444802, 42100.0, 53689.19466207932, 53862.38412847621, 54634.90730185454, 54786.0, 54983.900321992536, 13896.861031389557, 14160.716785664023, 14408.927955360576, 14450.710985780015, 14491.0, 15601.921990389976, 15784.289706963444, 15816.920915395349, 15938.203089845692, 15950.760738588699, 14557.12657240569, 14735.189564574051, 14742.410303587736, 14767.04014239071, 14915.776263356276, 14379.78430323525, 14387.85612143907, 14682.706345873235, 14704.338304776096, 14730.55808325752, 14303.0, 14346.784798228244, 14463.85536144625, 14519.346629401913, 14570.0, 10453.954604539485, 10720.785584288114, 10864.456777160953, 10894.56421743153, 10962.123030157727, 11319.264247823865, 11728.530858765895, 11908.166428349932, 12036.03711703044, 12078.939605301917], "value": 8159.519105361469, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1634.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 123.0, 123.0, 124.0, 120.0, 118.0, 123.0, 108.0, 181.0, 179.0], "value": 131.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 139.0, 122.0, 155.0, 113.0, 119.0, 117.0, 114.0, 137.0, 116.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [220.0, 223.0, 231.0, 227.0, 222.0, 219.0, 216.0, 213.0, 213.0, 217.0], "value": 220.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 65.0, 54.0, 92.0, 85.0, 92.0, 58.0, 91.0, 108.0, 114.0], "value": 84.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 112.0, 125.0, 108.0, 75.0, 107.0, 125.0, 111.0, 106.0, 85.0], "value": 105.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 81.0, 135.0, 105.0, 107.0, 101.0, 81.0, 125.0, 81.0, 82.0], "value": 101.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 101.0, 100.0, 99.0, 100.0, 101.0, 108.0, 100.0, 99.0], "value": 100.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 149.0, 103.0, 105.0, 111.0, 166.0, 137.0, 102.0, 104.0, 101.0], "value": 118.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 51.0, 53.0, 52.0, 52.0, 62.0, 53.0, 46.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 98.0, 88.0, 69.0, 64.0, 59.0, 59.0, 56.0, 58.0, 59.0], "value": 67.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 89.0, 88.0, 88.0, 86.0, 86.0, 85.0, 92.0, 87.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 171.0, 149.0, 147.0, 159.0, 156.0, 169.0, 143.0, 193.0, 153.0], "value": 159.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 195.0, 195.0, 192.0, 231.0, 254.0, 197.0, 196.0, 190.0], "value": 204.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 71.0, 73.0, 69.0, 69.0, 73.0, 71.0, 72.0, 70.0, 72.0], "value": 71.2, "unit": "score"}]}]} 03:42:05 INFO - Return code: 0 03:42:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:42:05 ERROR - # TBPL SUCCESS # 03:42:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:42:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:42:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:42:05 INFO - 2017-04-13 03:42:05.998 screenresolution[1945:17254] starting screenresolution argv=screenresolution get 03:42:06 INFO - 2017-04-13 03:42:06.000 screenresolution[1945:17254] Display 0: 1600x1200x32@60 03:42:06 INFO - 2017-04-13 03:42:06.007 screenresolution[1946:17257] starting screenresolution argv=screenresolution list 03:42:06 INFO - Available Modes on Display 0 03:42:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:42:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:42:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:42:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:42:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:42:06 INFO - Intel Iris: 03:42:06 INFO - Chipset Model: Intel Iris 03:42:06 INFO - Type: GPU 03:42:06 INFO - Bus: Built-In 03:42:06 INFO - VRAM (Dynamic, Max): 1536 MB 03:42:06 INFO - Vendor: Intel (0x8086) 03:42:06 INFO - Device ID: 0x0a2e 03:42:06 INFO - Revision ID: 0x0009 03:42:06 INFO - Displays: 03:42:06 INFO - Display: 03:42:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:42:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:42:06 INFO - Display Serial Number: 1600x1200 60 03:42:06 INFO - Main Display: Yes 03:42:06 INFO - Mirror: Off 03:42:06 INFO - Online: Yes 03:42:06 INFO - Rotation: Supported 03:42:06 INFO - Return code: 0 03:42:06 INFO - Running post-action listener: _resource_record_post_action 03:42:06 INFO - [mozharness: 2017-04-13 10:42:06.252069Z] Finished run-tests step (success) 03:42:06 INFO - Running post-run listener: _resource_record_post_run 03:42:06 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 2106880; Write bytes: 518938624; Read time: 20; Write time: 1629 03:42:06 INFO - TinderboxPrint: CPU usage
54.2% 03:42:06 INFO - TinderboxPrint: I/O read bytes / time
2,106,880 / 20 03:42:06 INFO - TinderboxPrint: I/O write bytes / time
518,938,624 / 1,629 03:42:06 INFO - TinderboxPrint: CPU idle
2,196.8 (45.8%) 03:42:06 INFO - TinderboxPrint: CPU system
965.9 (20.1%) 03:42:06 INFO - TinderboxPrint: CPU user
1,635.0 (34.1%) 03:42:06 INFO - TinderboxPrint: Swap in / out
210,657,280 / 0 03:42:06 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208826880; Write bytes: 212031488; Read time: 16005; Write time: 462 03:42:06 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 2098688; Write bytes: 305395712; Read time: 20; Write time: 1157 03:42:06 INFO - Running post-run listener: _upload_blobber_files 03:42:06 INFO - Blob upload gear active. 03:42:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:42:06 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 03:42:06 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:42:06 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:42:07 INFO - (blobuploader) - INFO - Open directory for files ... 03:42:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:42:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:42:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:07 INFO - (blobuploader) - INFO - Done attempting. 03:42:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:42:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:42:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:08 INFO - (blobuploader) - INFO - Done attempting. 03:42:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:42:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:42:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:09 INFO - (blobuploader) - INFO - Done attempting. 03:42:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:42:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:42:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:42:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:42:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:42:12 INFO - (blobuploader) - INFO - Done attempting. 03:42:12 INFO - (blobuploader) - INFO - Iteration through files over. 03:42:12 INFO - Return code: 0 03:42:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:42:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:42:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edcd727619cd778e0d6a1b30d93df5058b42ccae47f6d4dec123bfd3030dc81e0ef1d60925513de28f57f5a5f73465f2684c38e98d99ea40281140cbaad9a8a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9f2ee3fd2388b606a9e3abdc90f24ec366958bdb422f0c1ebdc3ea13bee7e35069ff705c100425205499708e59f240098a9dad6d25c5c4b24382f49ed2f62909", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9d73f82d8e378440f50332ff7fadae51824d7cb2c75bfa9b5a58ca3603d4c81df37407195fee706ce18ff98b121ca63b1e0e21b1074428ed4159214f0b8db55a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ec3b60cbf1953fff4b644322c0daf224850b89dde63dd124071aca914ad9cc0da5ade77934ac7ce44000a69b8abac8b2d7f4d917e4a18a3886fd4798d5d42508"} 03:42:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:42:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:42:12 INFO - Contents: 03:42:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edcd727619cd778e0d6a1b30d93df5058b42ccae47f6d4dec123bfd3030dc81e0ef1d60925513de28f57f5a5f73465f2684c38e98d99ea40281140cbaad9a8a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9f2ee3fd2388b606a9e3abdc90f24ec366958bdb422f0c1ebdc3ea13bee7e35069ff705c100425205499708e59f240098a9dad6d25c5c4b24382f49ed2f62909", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9d73f82d8e378440f50332ff7fadae51824d7cb2c75bfa9b5a58ca3603d4c81df37407195fee706ce18ff98b121ca63b1e0e21b1074428ed4159214f0b8db55a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ec3b60cbf1953fff4b644322c0daf224850b89dde63dd124071aca914ad9cc0da5ade77934ac7ce44000a69b8abac8b2d7f4d917e4a18a3886fd4798d5d42508"} 03:42:12 INFO - Running post-run listener: copy_logs_to_upload_dir 03:42:12 INFO - Copying logs to upload dir... 03:42:12 INFO - mkdir: /builds/slave/test/build/upload/logs 03:42:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.474304 ========= master_lag: 0.52 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2017-04-13 03:42:12.134742) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 03:42:12.138720) ========= 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.tJidG6fSds/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.4puwtSYsHF/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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/Pine/sha512/edcd727619cd778e0d6a1b30d93df5058b42ccae47f6d4dec123bfd3030dc81e0ef1d60925513de28f57f5a5f73465f2684c38e98d99ea40281140cbaad9a8a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9f2ee3fd2388b606a9e3abdc90f24ec366958bdb422f0c1ebdc3ea13bee7e35069ff705c100425205499708e59f240098a9dad6d25c5c4b24382f49ed2f62909", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9d73f82d8e378440f50332ff7fadae51824d7cb2c75bfa9b5a58ca3603d4c81df37407195fee706ce18ff98b121ca63b1e0e21b1074428ed4159214f0b8db55a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ec3b60cbf1953fff4b644322c0daf224850b89dde63dd124071aca914ad9cc0da5ade77934ac7ce44000a69b8abac8b2d7f4d917e4a18a3886fd4798d5d42508"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011251 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1492030148/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/edcd727619cd778e0d6a1b30d93df5058b42ccae47f6d4dec123bfd3030dc81e0ef1d60925513de28f57f5a5f73465f2684c38e98d99ea40281140cbaad9a8a8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9f2ee3fd2388b606a9e3abdc90f24ec366958bdb422f0c1ebdc3ea13bee7e35069ff705c100425205499708e59f240098a9dad6d25c5c4b24382f49ed2f62909", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/9d73f82d8e378440f50332ff7fadae51824d7cb2c75bfa9b5a58ca3603d4c81df37407195fee706ce18ff98b121ca63b1e0e21b1074428ed4159214f0b8db55a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ec3b60cbf1953fff4b644322c0daf224850b89dde63dd124071aca914ad9cc0da5ade77934ac7ce44000a69b8abac8b2d7f4d917e4a18a3886fd4798d5d42508"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 03:42:12.172934) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 03:42:12.173266) ========= 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.tJidG6fSds/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.4puwtSYsHF/Listeners TMPDIR=/var/folders/vf/0yg7r78j3zz7sn50nkm35wx400000w/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.005255 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 03:42:12.222206) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 03:42:12.222528) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 03:42:12.222863) ========= ========= Total master_lag: 0.66 =========