builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0250 starttime: 1492033031.34 results: success (0) buildid: 20170412103546 builduid: e4fa8c2f8dcf42ec80d5c3befbb24f00 revision: f976772df8b6880145f272387478c2439f4f2234 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:11.343371) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:11.343969) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:11.344300) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:11.371191) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:11.371562) ========= 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.rx56ZCi90w/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.LEXc1mnbVN/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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-12 14:37:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2017-04-12 14:37:11 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145955 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:11.542174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:11.542536) ========= 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.rx56ZCi90w/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.LEXc1mnbVN/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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.040613 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:11.605856) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 14:37:11.606530) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev f976772df8b6880145f272387478c2439f4f2234 --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 f976772df8b6880145f272387478c2439f4f2234 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rx56ZCi90w/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.LEXc1mnbVN/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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-12 14:37:11,911 truncating revision to first 12 chars 2017-04-12 14:37:11,912 Setting DEBUG logging. 2017-04-12 14:37:11,912 attempt 1/10 2017-04-12 14:37:11,912 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/f976772df8b6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 14:37:12,920 unpacking tar archive at: pine-f976772df8b6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.292800 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 14:37:12.923285) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:12.923651) ========= script_repo_revision: f976772df8b6880145f272387478c2439f4f2234 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:12.924101) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:12.924401) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 14:37:12.944381) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-12 14:37:12.944705) ========= /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.rx56ZCi90w/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.LEXc1mnbVN/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:37:13 INFO - MultiFileLogger online at 20170412 14:37:13 in /builds/slave/test 14:37:13 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 14:37:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:37:13 INFO - {'append_to_log': False, 14:37:13 INFO - 'base_work_dir': '/builds/slave/test', 14:37:13 INFO - 'blob_upload_branch': 'Pine', 14:37:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:37:13 INFO - 'branch': 'Pine', 14:37:13 INFO - 'buildbot_json_path': 'buildprops.json', 14:37:13 INFO - 'config_files': ('talos/mac_config.py',), 14:37:13 INFO - 'default_actions': ('clobber', 14:37:13 INFO - 'read-buildbot-config', 14:37:13 INFO - 'download-and-extract', 14:37:13 INFO - 'populate-webroot', 14:37:13 INFO - 'create-virtualenv', 14:37:13 INFO - 'install', 14:37:13 INFO - 'run-tests'), 14:37:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:37:13 INFO - 'download_minidump_stackwalk': True, 14:37:13 INFO - 'download_symbols': 'ondemand', 14:37:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:37:13 INFO - 'tooltool.py': '/tools/tooltool.py', 14:37:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:37:13 INFO - '/tools/misc-python/virtualenv.py')}, 14:37:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:37:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:37:13 INFO - 'gecko_profile': False, 14:37:13 INFO - 'gecko_profile_interval': 0, 14:37:13 INFO - 'installer_path': 'installer.exe', 14:37:13 INFO - 'log_level': 'info', 14:37:13 INFO - 'log_name': 'talos', 14:37:13 INFO - 'log_to_console': True, 14:37:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:37:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:37:13 INFO - 'opt_config_files': (), 14:37:13 INFO - 'pip_index': False, 14:37:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:37:13 INFO - 'cmd': ('bash', 14:37:13 INFO - '-c', 14:37:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:37:13 INFO - 'enabled': True, 14:37:13 INFO - 'halt_on_failure': False, 14:37:13 INFO - 'name': 'check_screen_resolution'},), 14:37:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:37:13 INFO - 'cmd': ('bash', 14:37:13 INFO - '-c', 14:37:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:37:13 INFO - 'enabled': True, 14:37:13 INFO - 'halt_on_failure': False, 14:37:13 INFO - 'name': 'check_screen_resolution'},), 14:37:13 INFO - 'run_cmd_checks_enabled': True, 14:37:13 INFO - 'suite': 'dromaeojs-e10s', 14:37:13 INFO - 'system_bits': '32', 14:37:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:37:13 INFO - 'title': 't-yosemite-r7-0250', 14:37:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:37:13 INFO - 'use_talos_json': True, 14:37:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:37:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:37:13 INFO - 'work_dir': 'build'} 14:37:13 INFO - [mozharness: 2017-04-12 21:37:13.353400Z] Running clobber step. 14:37:13 INFO - Running pre-action listener: _resource_record_pre_action 14:37:13 INFO - Running main action method: clobber 14:37:13 INFO - rmtree: /builds/slave/test/build 14:37:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:37:15 INFO - Running post-action listener: _resource_record_post_action 14:37:15 INFO - [mozharness: 2017-04-12 21:37:15.851315Z] Finished clobber step (success) 14:37:15 INFO - [mozharness: 2017-04-12 21:37:15.851448Z] Running read-buildbot-config step. 14:37:15 INFO - Running pre-action listener: _resource_record_pre_action 14:37:15 INFO - Running main action method: read_buildbot_config 14:37:15 INFO - Using buildbot properties: 14:37:15 INFO - { 14:37:15 INFO - "project": "", 14:37:15 INFO - "product": "firefox", 14:37:15 INFO - "script_repo_revision": "default", 14:37:15 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 14:37:15 INFO - "repository": "", 14:37:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 14:37:15 INFO - "buildid": "20170412103546", 14:37:15 INFO - "stage_platform": "macosx64", 14:37:15 INFO - "basedir": "/builds/slave/test", 14:37:15 INFO - "buildnumber": 0, 14:37:15 INFO - "platform": "yosemite_r7", 14:37:15 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 14:37:15 INFO - "slavebuilddir": "test", 14:37:15 INFO - "branch": "pine", 14:37:15 INFO - "revision": "f976772df8b6880145f272387478c2439f4f2234", 14:37:15 INFO - "slavename": "t-yosemite-r7-0250", 14:37:15 INFO - "builduid": "e4fa8c2f8dcf42ec80d5c3befbb24f00", 14:37:15 INFO - "repo_path": "projects/pine" 14:37:15 INFO - } 14:37:15 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.dmg. 14:37:15 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.test_packages.json. 14:37:15 INFO - Running post-action listener: _resource_record_post_action 14:37:15 INFO - [mozharness: 2017-04-12 21:37:15.854728Z] Finished read-buildbot-config step (success) 14:37:15 INFO - [mozharness: 2017-04-12 21:37:15.854884Z] Running download-and-extract step. 14:37:15 INFO - Running pre-action listener: _resource_record_pre_action 14:37:15 INFO - Running main action method: download_and_extract 14:37:15 INFO - mkdir: /builds/slave/test/build/tests 14:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:15 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.test_packages.json 14:37:15 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:37:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 14:37:16 INFO - Downloaded 1325 bytes. 14:37:16 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:37:16 INFO - Using the following test package requirements: 14:37:16 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:16 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:37:16 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:37:16 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:16 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:37:16 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:16 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:37:16 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:16 INFO - u'jsshell-mac.zip'], 14:37:16 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:16 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:37:16 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:37:16 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:16 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:37:16 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:16 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:37:16 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:16 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:37:16 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:16 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:37:16 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:37:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.common.tests.zip 14:37:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:37:16 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:37:17 INFO - Expected file size: 52582964 14:37:17 INFO - Obtained file size: 52582964 14:37:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:37:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.common.tests.zip 14:37:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:37:33 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:37:34 INFO - Expected file size: 52582964 14:37:34 INFO - Obtained file size: 52582964 14:37:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.talos.tests.zip 14:37:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:37:49 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:37:50 INFO - Expected file size: 14405596 14:37:50 INFO - Obtained file size: 14405596 14:37:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:50 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.dmg 14:37:50 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:37:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 14:37:51 INFO - Downloaded 70998782 bytes. 14:37:51 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.dmg 14:37:51 INFO - mkdir: /builds/slave/test/properties 14:37:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:37:51 INFO - Writing to file /builds/slave/test/properties/build_url 14:37:51 INFO - Contents: 14:37:51 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.dmg 14:37:52 INFO - Running post-action listener: _resource_record_post_action 14:37:52 INFO - Running post-action listener: set_extra_try_arguments 14:37:52 INFO - [mozharness: 2017-04-12 21:37:52.155671Z] Finished download-and-extract step (success) 14:37:52 INFO - [mozharness: 2017-04-12 21:37:52.155794Z] Running populate-webroot step. 14:37:52 INFO - Running pre-action listener: _resource_record_pre_action 14:37:52 INFO - Running main action method: populate_webroot 14:37:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:37:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:37:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:37:52 INFO - '--tppagecycles', 14:37:52 INFO - '7'], 14:37:52 INFO - 'tests': ['tsvgm']}}, 14:37:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:37:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:37:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:37:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:37:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:37:52 INFO - 'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:37:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:37:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:37:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:37:52 INFO - 'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['damp', 'tps']}, 14:37:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:37:52 INFO - 'tests': ['damp', 'tps']}, 14:37:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['dromaeo_dom']}, 14:37:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:37:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['basic_compositor_video']}, 14:37:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:37:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['a11yr', 14:37:52 INFO - 'ts_paint', 14:37:52 INFO - 'tpaint', 14:37:52 INFO - 'sessionrestore', 14:37:52 INFO - 'sessionrestore_no_auto_restore', 14:37:52 INFO - 'tabpaint']}, 14:37:52 INFO - 'other-e10s': {'tests': ['a11yr', 14:37:52 INFO - 'ts_paint', 14:37:52 INFO - 'tpaint', 14:37:52 INFO - 'sessionrestore', 14:37:52 INFO - 'sessionrestore_no_auto_restore', 14:37:52 INFO - 'tabpaint']}, 14:37:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:37:52 INFO - 'ts_paint', 14:37:52 INFO - 'tpaint', 14:37:52 INFO - 'sessionrestore', 14:37:52 INFO - 'sessionrestore_no_auto_restore', 14:37:52 INFO - 'tabpaint']}, 14:37:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:37:52 INFO - 'ts_paint', 14:37:52 INFO - 'tpaint', 14:37:52 INFO - 'sessionrestore', 14:37:52 INFO - 'sessionrestore_no_auto_restore', 14:37:52 INFO - 'tabpaint']}, 14:37:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['a11yr', 14:37:52 INFO - 'ts_paint', 14:37:52 INFO - 'tpaint', 14:37:52 INFO - 'sessionrestore', 14:37:52 INFO - 'sessionrestore_no_auto_restore', 14:37:52 INFO - 'tabpaint']}, 14:37:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['a11yr', 14:37:52 INFO - 'ts_paint', 14:37:52 INFO - 'tpaint', 14:37:52 INFO - 'sessionrestore', 14:37:52 INFO - 'sessionrestore_no_auto_restore', 14:37:52 INFO - 'tabpaint']}, 14:37:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['tsvgx', 14:37:52 INFO - 'tsvgr_opacity', 14:37:52 INFO - 'tart', 14:37:52 INFO - 'tscrollx', 14:37:52 INFO - 'cart', 14:37:52 INFO - 'tsvg_static']}, 14:37:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:37:52 INFO - 'tsvgr_opacity', 14:37:52 INFO - 'tart', 14:37:52 INFO - 'tscrollx', 14:37:52 INFO - 'cart', 14:37:52 INFO - 'tsvg_static']}, 14:37:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:37:52 INFO - 'talos_options': ['--disable-e10s'], 14:37:52 INFO - 'tests': ['tp5o']}, 14:37:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:37:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:37:52 INFO - 'talos_options': ['--disable-e10s', 14:37:52 INFO - '--xperf_path', 14:37:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:37:52 INFO - 'tests': ['tp5n']}, 14:37:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:37:52 INFO - 'talos_options': ['--xperf_path', 14:37:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:37:52 INFO - 'tests': ['tp5n']}}, 14:37:52 INFO - 'talos.zip': {'path': '', 14:37:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:37:52 INFO - Running post-action listener: _resource_record_post_action 14:37:52 INFO - [mozharness: 2017-04-12 21:37:52.173827Z] Finished populate-webroot step (success) 14:37:52 INFO - [mozharness: 2017-04-12 21:37:52.173949Z] Running create-virtualenv step. 14:37:52 INFO - Running pre-action listener: _resource_record_pre_action 14:37:52 INFO - Running main action method: create_virtualenv 14:37:52 INFO - Creating virtualenv /builds/slave/test/build/venv 14:37:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:37:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:37:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:37:52 INFO - Using real prefix '/tools/python27' 14:37:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:37:53 INFO - Installing distribute.............................................................................................................................................................................................done. 14:37:55 INFO - Installing pip.................done. 14:37:55 INFO - Return code: 0 14:37:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:37:56 INFO - Reading from file tmpfile_stdout 14:37:56 INFO - Output received: 14:37:56 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:37:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:37:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:37:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:56 INFO - 'HOME': '/Users/cltbld', 14:37:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:56 INFO - 'LOGNAME': 'cltbld', 14:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:56 INFO - 'MOZ_NO_REMOTE': '1', 14:37:56 INFO - 'NO_EM_RESTART': '1', 14:37:56 INFO - 'PAGER': '/bin/cat', 14:37:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:56 INFO - 'PWD': '/builds/slave/test', 14:37:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:56 INFO - 'SHELL': '/bin/bash', 14:37:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:37:56 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:37:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:56 INFO - 'USER': 'cltbld', 14:37:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:56 INFO - 'XPC_FLAGS': '0x0', 14:37:56 INFO - 'XPC_SERVICE_NAME': '0', 14:37:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:37:56 INFO - Cleaning up... 14:37:56 INFO - Return code: 0 14:37:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:37:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:37:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:37:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:37:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:37:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:37:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:37:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:37:56 INFO - 'HOME': '/Users/cltbld', 14:37:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:37:56 INFO - 'LOGNAME': 'cltbld', 14:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:37:56 INFO - 'MOZ_NO_REMOTE': '1', 14:37:56 INFO - 'NO_EM_RESTART': '1', 14:37:56 INFO - 'PAGER': '/bin/cat', 14:37:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:37:56 INFO - 'PWD': '/builds/slave/test', 14:37:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:37:56 INFO - 'SHELL': '/bin/bash', 14:37:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:37:56 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:37:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:37:56 INFO - 'USER': 'cltbld', 14:37:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:37:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:37:56 INFO - 'XPC_FLAGS': '0x0', 14:37:56 INFO - 'XPC_SERVICE_NAME': '0', 14:37:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:37:56 INFO - Downloading/unpacking psutil>=3.1.1 14:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:37:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:38:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:38:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:38:00 INFO - Installing collected packages: psutil 14:38:00 INFO - Running setup.py install for psutil 14:38:00 INFO - building 'psutil._psutil_osx' extension 14:38:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:38:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:38:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:38:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:38:01 INFO - building 'psutil._psutil_posix' extension 14:38:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:38:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:38:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:38:01 INFO - ^ 14:38:01 INFO - 1 warning generated. 14:38:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:38:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:38:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:38:01 INFO - Successfully installed psutil 14:38:01 INFO - Cleaning up... 14:38:01 INFO - Return code: 0 14:38:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:01 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 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:38:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:38:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:38:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:01 INFO - 'HOME': '/Users/cltbld', 14:38:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:01 INFO - 'LOGNAME': 'cltbld', 14:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:01 INFO - 'MOZ_NO_REMOTE': '1', 14:38:01 INFO - 'NO_EM_RESTART': '1', 14:38:01 INFO - 'PAGER': '/bin/cat', 14:38:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:01 INFO - 'PWD': '/builds/slave/test', 14:38:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:01 INFO - 'SHELL': '/bin/bash', 14:38:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:38:01 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:38:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:01 INFO - 'USER': 'cltbld', 14:38:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:01 INFO - 'XPC_FLAGS': '0x0', 14:38:01 INFO - 'XPC_SERVICE_NAME': '0', 14:38:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:38: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 14:38: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 14:38: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 14:38: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 14:38: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 14:38: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 14:38:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:38:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:38:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:38:04 INFO - Installing collected packages: mozsystemmonitor 14:38:04 INFO - Running setup.py install for mozsystemmonitor 14:38:04 INFO - Successfully installed mozsystemmonitor 14:38:04 INFO - Cleaning up... 14:38:04 INFO - Return code: 0 14:38:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:38:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:38:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:38:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:38:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:04 INFO - 'HOME': '/Users/cltbld', 14:38:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:04 INFO - 'LOGNAME': 'cltbld', 14:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:04 INFO - 'MOZ_NO_REMOTE': '1', 14:38:04 INFO - 'NO_EM_RESTART': '1', 14:38:04 INFO - 'PAGER': '/bin/cat', 14:38:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:04 INFO - 'PWD': '/builds/slave/test', 14:38:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:04 INFO - 'SHELL': '/bin/bash', 14:38:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:38:04 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:38:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:04 INFO - 'USER': 'cltbld', 14:38:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:04 INFO - 'XPC_FLAGS': '0x0', 14:38:04 INFO - 'XPC_SERVICE_NAME': '0', 14:38:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:04 INFO - Downloading/unpacking jsonschema==2.5.1 14:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:38:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:07 INFO - Downloading functools32-3.2.3-2.tar.gz 14:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:38:07 INFO - warning: no files found matching '*.txt' 14:38:07 INFO - no previously-included directories found matching 'build' 14:38:07 INFO - no previously-included directories found matching 'dist' 14:38:07 INFO - no previously-included directories found matching '.git*' 14:38:07 INFO - Installing collected packages: jsonschema, functools32 14:38:07 INFO - Running setup.py install for functools32 14:38:08 INFO - warning: no files found matching '*.txt' 14:38:08 INFO - no previously-included directories found matching 'build' 14:38:08 INFO - no previously-included directories found matching 'dist' 14:38:08 INFO - no previously-included directories found matching '.git*' 14:38:08 INFO - Successfully installed jsonschema functools32 14:38:08 INFO - Cleaning up... 14:38:08 INFO - Return code: 0 14:38:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:38:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:38:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:38:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:08 INFO - 'HOME': '/Users/cltbld', 14:38:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:08 INFO - 'LOGNAME': 'cltbld', 14:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:08 INFO - 'MOZ_NO_REMOTE': '1', 14:38:08 INFO - 'NO_EM_RESTART': '1', 14:38:08 INFO - 'PAGER': '/bin/cat', 14:38:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:08 INFO - 'PWD': '/builds/slave/test', 14:38:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:08 INFO - 'SHELL': '/bin/bash', 14:38:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:38:08 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:38:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:08 INFO - 'USER': 'cltbld', 14:38:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:08 INFO - 'XPC_FLAGS': '0x0', 14:38:08 INFO - 'XPC_SERVICE_NAME': '0', 14:38:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:38:09 INFO - Cleaning up... 14:38:09 INFO - Return code: 0 14:38:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:38:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:09 INFO - 'HOME': '/Users/cltbld', 14:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:09 INFO - 'LOGNAME': 'cltbld', 14:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:09 INFO - 'MOZ_NO_REMOTE': '1', 14:38:09 INFO - 'NO_EM_RESTART': '1', 14:38:09 INFO - 'PAGER': '/bin/cat', 14:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:09 INFO - 'PWD': '/builds/slave/test', 14:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:09 INFO - 'SHELL': '/bin/bash', 14:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:38:09 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:09 INFO - 'USER': 'cltbld', 14:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:09 INFO - 'XPC_FLAGS': '0x0', 14:38:09 INFO - 'XPC_SERVICE_NAME': '0', 14:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:09 INFO - Downloading/unpacking blobuploader==1.2.4 14:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:12 INFO - Downloading blobuploader-1.2.4.tar.gz 14:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:38:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:38:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:13 INFO - Downloading docopt-0.6.1.tar.gz 14:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:38:14 INFO - Installing collected packages: blobuploader, requests, docopt 14:38:14 INFO - Running setup.py install for blobuploader 14:38:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:38:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:38:14 INFO - Running setup.py install for requests 14:38:14 INFO - Running setup.py install for docopt 14:38:14 INFO - Successfully installed blobuploader requests docopt 14:38:14 INFO - Cleaning up... 14:38:14 INFO - Return code: 0 14:38:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:14 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 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:38:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:38:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:38:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:38:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:14 INFO - 'HOME': '/Users/cltbld', 14:38:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:14 INFO - 'LOGNAME': 'cltbld', 14:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:14 INFO - 'MOZ_NO_REMOTE': '1', 14:38:14 INFO - 'NO_EM_RESTART': '1', 14:38:14 INFO - 'PAGER': '/bin/cat', 14:38:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:14 INFO - 'PWD': '/builds/slave/test', 14:38:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:14 INFO - 'SHELL': '/bin/bash', 14:38:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:38:14 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:38:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:14 INFO - 'USER': 'cltbld', 14:38:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:14 INFO - 'XPC_FLAGS': '0x0', 14:38:14 INFO - 'XPC_SERVICE_NAME': '0', 14:38:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:38:15 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-ip6pwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:38:15 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-XR4X3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:38:15 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-eqytYk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:38:15 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-2TPLv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:38:15 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-VmlF40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:38:15 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-2Hinlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:38:15 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-sHy51m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:38:15 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-axQEeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:38:16 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-gTQwZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:38:16 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-9fr6AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:38:16 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-IpqUp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:38:16 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-6wK8CX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:38:16 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-vR4rhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:38:16 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-k34GC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:38:16 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Li0xEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:38:16 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-3CgONU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:38:16 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-rVxV13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:38:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:38:16 INFO - Running setup.py install for manifestparser 14:38:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:38:17 INFO - Running setup.py install for mozcrash 14:38:17 INFO - Running setup.py install for mozdebug 14:38:17 INFO - Running setup.py install for mozdevice 14:38:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:38:17 INFO - Running setup.py install for mozfile 14:38:17 INFO - Running setup.py install for mozhttpd 14:38:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:38:17 INFO - Running setup.py install for mozinfo 14:38:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:38:17 INFO - Running setup.py install for mozInstall 14:38:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:38:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:38:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:38:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:38:18 INFO - Running setup.py install for mozleak 14:38:18 INFO - Running setup.py install for mozlog 14:38:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:38:18 INFO - Running setup.py install for moznetwork 14:38:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:38:18 INFO - Running setup.py install for mozprocess 14:38:18 INFO - Running setup.py install for mozprofile 14:38:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:38:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:38:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:38:18 INFO - Running setup.py install for mozrunner 14:38:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:38:19 INFO - Running setup.py install for mozscreenshot 14:38:19 INFO - Running setup.py install for moztest 14:38:19 INFO - Running setup.py install for mozversion 14:38:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:38:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:38:19 INFO - Cleaning up... 14:38:19 INFO - Return code: 0 14:38:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:38:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:38:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:19 INFO - 'HOME': '/Users/cltbld', 14:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:19 INFO - 'LOGNAME': 'cltbld', 14:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:19 INFO - 'MOZ_NO_REMOTE': '1', 14:38:19 INFO - 'NO_EM_RESTART': '1', 14:38:19 INFO - 'PAGER': '/bin/cat', 14:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:19 INFO - 'PWD': '/builds/slave/test', 14:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:19 INFO - 'SHELL': '/bin/bash', 14:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:38:19 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:19 INFO - 'USER': 'cltbld', 14:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:19 INFO - 'XPC_FLAGS': '0x0', 14:38:19 INFO - 'XPC_SERVICE_NAME': '0', 14:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:38:19 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-1eaMWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:38:19 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-ceSH6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:38:19 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-bsECPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:38:20 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-BR8C6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:38:20 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-ktH1ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:38:20 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Qmi8T2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:38:20 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-YFrzPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:38:20 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-TMELTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:38:20 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-9uRFYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:38:20 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-0CokAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:38:20 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-8ueJxt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:38:20 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-hxGGZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:38:21 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-_Gjae8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:38:21 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-6vVUnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:38:21 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-MvVSu9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:38:21 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-7Quasq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:38:21 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-ZoV0qn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:38:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:38:24 INFO - Downloading blessings-1.6.tar.gz 14:38:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:38:24 INFO - Installing collected packages: blessings 14:38:24 INFO - Running setup.py install for blessings 14:38:24 INFO - Successfully installed blessings 14:38:24 INFO - Cleaning up... 14:38:24 INFO - Return code: 0 14:38:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:38:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:38:24 INFO - Reading from file tmpfile_stdout 14:38:24 INFO - Current package versions: 14:38:24 INFO - blessings == 1.6 14:38:24 INFO - blobuploader == 1.2.4 14:38:24 INFO - docopt == 0.6.1 14:38:24 INFO - functools32 == 3.2.3-2 14:38:24 INFO - jsonschema == 2.5.1 14:38:24 INFO - manifestparser == 1.1 14:38:24 INFO - mozInstall == 1.12 14:38:24 INFO - mozcrash == 1.0 14:38:24 INFO - mozdebug == 0.1 14:38:24 INFO - mozdevice == 0.50 14:38:24 INFO - mozfile == 1.2 14:38:24 INFO - mozhttpd == 0.7 14:38:24 INFO - mozinfo == 0.9 14:38:24 INFO - mozleak == 0.1 14:38:24 INFO - mozlog == 3.4 14:38:24 INFO - moznetwork == 0.27 14:38:24 INFO - mozprocess == 0.25 14:38:24 INFO - mozprofile == 0.28 14:38:24 INFO - mozrunner == 6.13 14:38:24 INFO - mozscreenshot == 0.1 14:38:24 INFO - mozsystemmonitor == 0.3 14:38:24 INFO - moztest == 0.8 14:38:24 INFO - mozversion == 1.4 14:38:24 INFO - psutil == 3.1.1 14:38:24 INFO - requests == 1.2.3 14:38:24 INFO - wsgiref == 0.1.2 14:38:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:24 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 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:38:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:38:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:24 INFO - 'HOME': '/Users/cltbld', 14:38:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:24 INFO - 'LOGNAME': 'cltbld', 14:38:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:24 INFO - 'MOZ_NO_REMOTE': '1', 14:38:24 INFO - 'NO_EM_RESTART': '1', 14:38:24 INFO - 'PAGER': '/bin/cat', 14:38:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:24 INFO - 'PWD': '/builds/slave/test', 14:38:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:24 INFO - 'SHELL': '/bin/bash', 14:38:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:38:24 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:38:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:24 INFO - 'USER': 'cltbld', 14:38:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:24 INFO - 'XPC_FLAGS': '0x0', 14:38:24 INFO - 'XPC_SERVICE_NAME': '0', 14:38:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:38:25 INFO - Cleaning up... 14:38:25 INFO - Return code: 0 14:38:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:38:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:38:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10de89b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de8c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6e35ae3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, '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.rx56ZCi90w/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.LEXc1mnbVN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:25 INFO - 'HOME': '/Users/cltbld', 14:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:25 INFO - 'LOGNAME': 'cltbld', 14:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:25 INFO - 'MOZ_NO_REMOTE': '1', 14:38:25 INFO - 'NO_EM_RESTART': '1', 14:38:25 INFO - 'PAGER': '/bin/cat', 14:38:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:25 INFO - 'PWD': '/builds/slave/test', 14:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:25 INFO - 'SHELL': '/bin/bash', 14:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:38:25 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:25 INFO - 'USER': 'cltbld', 14:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:25 INFO - 'XPC_FLAGS': '0x0', 14:38:25 INFO - 'XPC_SERVICE_NAME': '0', 14:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:38:25 INFO - Cleaning up... 14:38:25 INFO - Return code: 0 14:38:25 INFO - Running post-action listener: _resource_record_post_action 14:38:25 INFO - Running post-action listener: _start_resource_monitoring 14:38:25 INFO - Starting resource monitoring. 14:38:25 INFO - [mozharness: 2017-04-12 21:38:25.464730Z] Finished create-virtualenv step (success) 14:38:25 INFO - [mozharness: 2017-04-12 21:38:25.465664Z] Running install step. 14:38:25 INFO - Running pre-action listener: _resource_record_pre_action 14:38:25 INFO - Running main action method: install 14:38:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:38:25 INFO - Reading from file tmpfile_stdout 14:38:25 INFO - Detecting whether we're running mozinstall >=1.0... 14:38:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:38:25 INFO - Reading from file tmpfile_stdout 14:38:25 INFO - Output received: 14:38:25 INFO - Usage: mozinstall [options] installer 14:38:25 INFO - Options: 14:38:25 INFO - -h, --help show this help message and exit 14:38:25 INFO - -d DEST, --destination=DEST 14:38:25 INFO - Directory to install application into. [default: 14:38:25 INFO - "/builds/slave/test"] 14:38:25 INFO - --app=APP Application being installed. [default: firefox] 14:38:25 INFO - mkdir: /builds/slave/test/build/application 14:38:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:38:45 INFO - Reading from file tmpfile_stdout 14:38:45 INFO - Output received: 14:38:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:38:45 INFO - Running post-action listener: _resource_record_post_action 14:38:45 INFO - [mozharness: 2017-04-12 21:38:45.798191Z] Finished install step (success) 14:38:45 INFO - [mozharness: 2017-04-12 21:38:45.798317Z] Running run-tests step. 14:38:45 INFO - Running pre-action listener: _resource_record_pre_action 14:38:45 INFO - Running main action method: run_tests 14:38:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:38:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:38:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:38:45 INFO - 2017-04-12 14:38:45.812 screenresolution[1622:7230] starting screenresolution argv=screenresolution get 14:38:45 INFO - 2017-04-12 14:38:45.815 screenresolution[1622:7230] Display 0: 1600x1200x32@60 14:38:45 INFO - 2017-04-12 14:38:45.821 screenresolution[1623:7233] starting screenresolution argv=screenresolution list 14:38:45 INFO - Available Modes on Display 0 14:38:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:38:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:38:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:38:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:38:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:38:46 INFO - Intel Iris: 14:38:46 INFO - Chipset Model: Intel Iris 14:38:46 INFO - Type: GPU 14:38:46 INFO - Bus: Built-In 14:38:46 INFO - VRAM (Dynamic, Max): 1536 MB 14:38:46 INFO - Vendor: Intel (0x8086) 14:38:46 INFO - Device ID: 0x0a2e 14:38:46 INFO - Revision ID: 0x0009 14:38:46 INFO - Displays: 14:38:46 INFO - Display: 14:38:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:38:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:38:46 INFO - Display Serial Number: 1600x1200 60 14:38:46 INFO - Main Display: Yes 14:38:46 INFO - Mirror: Off 14:38:46 INFO - Online: Yes 14:38:46 INFO - Rotation: Supported 14:38:46 INFO - Return code: 0 14:38:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:38:46 INFO - Python 2.7.3 14:38:46 INFO - Return code: 0 14:38:46 INFO - grabbing minidump binary from tooltool 14:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:46 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dc67d30>, '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 0x10db8edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dc5cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:38:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:38:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:38:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:38:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:38:46 INFO - Return code: 0 14:38:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:38:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:38:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:38:46 INFO - ENV: RUST_BACKTRACE is now 1 14:38:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:38:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:38:46 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/1491932768/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0250', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:38:46 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/1491932768/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0250 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:38:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rx56ZCi90w/Render', 14:38:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:46 INFO - 'HOME': '/Users/cltbld', 14:38:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:46 INFO - 'LOGNAME': 'cltbld', 14:38:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:38:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:38:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:46 INFO - 'MOZ_NO_REMOTE': '1', 14:38:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:38:46 INFO - 'NO_EM_RESTART': '1', 14:38:46 INFO - 'PAGER': '/bin/cat', 14:38:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:46 INFO - 'PWD': '/builds/slave/test', 14:38:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:38:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:46 INFO - 'RUST_BACKTRACE': '1', 14:38:46 INFO - 'SHELL': '/bin/bash', 14:38:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LEXc1mnbVN/Listeners', 14:38:46 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 14:38:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:46 INFO - 'USER': 'cltbld', 14:38:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:46 INFO - 'XPC_FLAGS': '0x0', 14:38:46 INFO - 'XPC_SERVICE_NAME': '0', 14:38:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:46 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/1491932768/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0250', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:38:46 INFO - mozversion application_buildid: 20170411104608 14:38:46 INFO - mozversion application_changeset: f976772df8b6880145f272387478c2439f4f2234 14:38:46 INFO - mozversion application_display_name: Nightly 14:38:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:38:46 INFO - mozversion application_name: Firefox 14:38:46 INFO - mozversion application_remotingname: firefox 14:38:46 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 14:38:46 INFO - mozversion application_vendor: Mozilla 14:38:46 INFO - mozversion application_version: 55.0a1 14:38:46 INFO - mozversion platform_buildid: 20170411104608 14:38:46 INFO - mozversion platform_changeset: f976772df8b6880145f272387478c2439f4f2234 14:38:46 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 14:38:46 INFO - mozversion platform_version: 55.0a1 14:38:46 INFO - using testdate: 1492033126 14:38:46 INFO - actual date: 1492033126 14:38:46 INFO - starting webserver on 'localhost:49242' 14:38:46 INFO - SUITE-START | Running 2 tests 14:38:46 INFO - TEST-START | dromaeo_css 14:38:46 INFO - operating with platform_type : mac_ 14:38:46 INFO - Initialising browser for dromaeo_css test... 14:38:46 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpl6wcEw/profile http://localhost:49242/getInfo.html) 14:38:49 INFO - PID 1633 | 2017-04-12 14:38:49.395 plugin-container[1635:7422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903b, name = 'com.apple.tsm.portname' 14:38:49 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:49 INFO - PID 1633 | 2017-04-12 14:38:49.397 plugin-container[1635:7422] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1b, name = 'com.apple.CFPasteboardClient' 14:38:49 INFO - PID 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:49 INFO - PID 1633 | 2017-04-12 14:38:49.397 plugin-container[1635:7422] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:38:49 INFO - PID 1633 | __metrics Screen width/height:1600/1200 14:38:49 INFO - PID 1633 | colorDepth:24 14:38:49 INFO - PID 1633 | Browser inner width/height: 1024/689 14:38:49 INFO - PID 1633 | __metrics 14:38:49 ERROR - PID 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 14:38:50 INFO - TEST-INFO | 1633: exit 0 14:38:50 INFO - Browser initialized. 14:38:50 INFO - Running cycle 1/1 for dromaeo_css test... 14:38:50 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpl6wcEw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:53 INFO - PID 1636 | 2017-04-12 14:38:53.180 plugin-container[1637:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 14:38:53 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:53 INFO - PID 1636 | 2017-04-12 14:38:53.181 plugin-container[1637:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d13, name = 'com.apple.CFPasteboardClient' 14:38:53 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:38:53 INFO - PID 1636 | 2017-04-12 14:38:53.181 plugin-container[1637:7547] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:39:02 INFO - PID 1636 | RSS: Main: 235462656 14:39:02 INFO - PID 1636 | 14:42:06 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 14:42:08 INFO - PID 1636 | RSS: Main: 253440000 14:42:08 INFO - PID 1636 | 14:43:51 INFO - PID 1636 | ************************* 14:43:51 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 14:43:51 INFO - PID 1636 | 14:43:51 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:43:51 INFO - PID 1636 | Full stack: 14:43:51 INFO - PID 1636 | ************************* 14:43:51 INFO - PID 1636 | ************************* 14:43:51 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 14:43:51 INFO - PID 1636 | 14:43:51 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:43:51 INFO - PID 1636 | Full stack: 14:43:51 INFO - PID 1636 | ************************* 14:43:51 INFO - PID 1636 | ************************* 14:43:51 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 14:43:51 INFO - PID 1636 | 14:43:51 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:43:51 INFO - PID 1636 | Full stack: 14:43:51 INFO - PID 1636 | ************************* 14:43:51 INFO - PID 1636 | ************************* 14:43:51 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 14:43:51 INFO - PID 1636 | 14:43:51 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:43:51 INFO - PID 1636 | Full stack: 14:43:51 INFO - PID 1636 | ************************* 14:45:10 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 14:45:11 INFO - PID 1636 | RSS: Main: 261738496 14:45:11 INFO - PID 1636 | 14:48:12 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 14:48:13 INFO - PID 1636 | RSS: Main: 273666048 14:48:13 INFO - PID 1636 | 14:51:15 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 14:51:15 INFO - PID 1636 | RSS: Main: 266297344 14:51:15 INFO - PID 1636 | 14:54:18 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 14:54:19 INFO - PID 1636 | RSS: Main: 276643840 14:54:19 INFO - PID 1636 | 14:57:21 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 14:57:21 INFO - PID 1636 | RSS: Main: 273395712 14:57:21 INFO - PID 1636 | 14:57:21 INFO - PID 1636 | __start_tp_report 14:57:21 INFO - PID 1636 | _x_x_mozilla_page_load 14:57:21 INFO - PID 1636 | _x_x_mozilla_page_load_details 14:57:21 INFO - PID 1636 | |i|pagename|runs| 14:57:21 INFO - PID 1636 | |0;dojo.html;3261.4781634938413;3525.541679581654;3530.1351168963606;3564.3584154852115;3578.9821050894743;5247.86880327992;5744.741486633101;6181.320054793975;6211.440970312671;6251.406116418944;6131.040261761299;6146.692665366736;6190.504759619231;6245.125682404456;6250.124982502453;5473.987312347209;5483.232347471368;5509.47659972302;5539.806106786264;5612.07400778871;4742.2886567014875;5407.5133238008575;5557.166425036236;5594.804181853636;5624.184493248476;5416.702081385522;5487.835364939058;5516.365617953927;5518.668879867221;5524.861878453031;5471.452854714496;5474.808381706621;5496.0931446311315;5504.972475137599;5521.309836270466;5433.565314774848;5455.863603409907;5467.644603100785;5487.42382049887;5505.642133261325;6010.338862725097;6108.144859719643;6149.954507733696;6169.475594574466;6219.968900155471;6727.629980351078;6729.798106056826;6734.797956061326;6755.054292399019;6756.054152418666;6825.795226143224;6835.179778426571;6835.350641689031;6844.555103918229;6849.075374824434;5147.279380886679;6189.442950134509;6194.4115309045565;6261.530385221035;6369.267534233576;5883.441073098049;6891.414229790424;6937.653117344113;6951.65241737911;6984.965075174591;4876.780544875418;6330.335314791972;6404;6420;6639.734410623521;6681.7327306908155;6746.359095885883;6791.728330866808;6798.5241033128195;6812.897806532906;6432.678366081677;6518.706658200393;6597.241317248578;6667.766628167991;6703.095082163943;6654.26803051664;6688.665566721645;6766.18805743314;6769.898451523231;6774.254831968479;4576.519465456146;5344.866378340573;5366.543843773323;5401.4868587484125;5421.945780542144;3790.753601015925;4706.576408123286;4768.928466073012;4825.276208568743;4829;4967.379077615298;6148.569600128038;6212.06818977157;6258.468030217391;6290.591111577733;5427.565794736412;5815.883682326415;6777.051212830208;6826.419754320938;6932.410745086724;6229.595076320024;6240.188775459252;6244.781432649836;6317.399847014526;6325.936740632535;5961.5230781537375;5969.462748352669;6013.428724271187;6062.757489700362;6086.513078953762;6813.080234168324;6892.345227203407;6905.482088843457;6905.75829845963;6910.619915904473;6398.200224971879;6784.185897692111;6822.965885170543;6824.727010919508;6842.418394436329;6691.230508491586;6811.93188068113;6860.03959445668;6907.585544867324;6908.792736217922;6668.199816022108;6671.666416679243;6691.5650482718465;6700.162479690039;6717.05961165447;6467.741290348334;6555.147830781968;6622.701978410887;6738.685956238486;6819.420349270367;6401.583897046677;6510.609363438209;6567.573107748172;6576.736930522726;6589.505787065893;6526.477881769352;6557.770478033246;6567.704453299518;6569.835754105994;6569.9343006571235;5922.940770592239;6280.811575652555;6412.711427985659;6425;6428.907085795332;6332.52506062038;6387.169667943138;6445.4199122079235;6534.738610455529;6586.4401525870835;6416.935830641821;6430;6436.742530298736;6448.451881590117;6456.418922297016 14:57:21 INFO - PID 1636 | |1;ext.html;791.0309870408748;810.5136917849289;1273.3760457375888;1277.5911708253363;1293.6119164250722;38127.80936095319;38768.418473722915;38782;38797.80601096994;38811;38705.806470967604;39055.21889562207;39129;39209.2355843987;39237.21525569494;8009.559474228915;8609.096044915288;8806.031336552975;8818.823623527525;8819.867701984474;8613;8678.609462574168;8685.392022558424;8693.95653021734;8712.172343627346;6967.512274140812;7272.9636351818435;8646.827063458728;8688.522131282776;8717.084706105863;8658.913410865905;8669.869951950726;8688.609012594448;8700.26047785939;8701.521416322099;8474.491530508127;8493.193146651058;8565;8578.313734901192;8579.914200858037;37835.054123646856;38112.80943595292;38135.04662383435;38146.427803582694;38298.468061277796;3623.9637603624155;3635.1457407509215;3660.5973342932257;3663.065918190871;3679.22736225393;30518.237044073852;30666.54000189999;30960.225994350098;30965.380692386025;30966;3127.0618814355603;3216.8069915805127;3223.693749093832;3235.88674396395;3248.3503299340227;298715;299371;299947;300307;300522.9999999978;39842;40857.387139192935;47896;49095.26357104646;49162.508374916506;50428.74785626048;50472.49527504751;50473.49526504688;50553.98892022139;50608.746956264986;14387.208703521197;14497.637559060897;14605.488807891885;14622.341994610268;14624.926875365554;11684.591039313753;11727.35499547516;11743.471543780379;11869.050475961902;11899.583514576949;11761.411929403495;12753.788390102925;13393.66515837112;13413.262270575213;13418.597442076752;5317.601179911522;5620.751699320304;5845.561582881216;5904.704764761745;5909.940900591025;1040.328987802863;1052.1109662335314;1055.5355643516828;1075.6074032978063;1079.1259080145107;21146.15415383398;23410.64884026707;24432.51134977326;24674.753252467606;24689.62965555518;1052.7052425320921;1067.9412632305298;1128.9209755317213;1131.8059447283194;1155.1913660437726;12535.059870509749;12581.622551323475;12583.993280537536;12586.37068146589;12676.683082923;360206;361761;362174;362920;364096;43822.78088609537;54625.72687136539;54708.63228419242;54975.450245497035;55047.89904201894;1540.4839378808224;1590.6262028423544;1593.2909855114594;1600.3998500562288;1611.1702473226298;21845.016974236514;24246.63630045586;24252.029918803757;24279.271621851374;24447.38881527905;38363.42454863235;38730.80634596922;38739;38753;38977.6102238974;327282;327919.72080279846;328234;328387;328439;34299.485507716905;34686.65313346834;34754;35089.47365789464;35163.82418087893;44353.11293774171;45570.08859822719;45967.310490342;46487.53512464967;46617.300740488245;24811.379715507257;28259.858700706365;31800.045998620077;31910.840445798553;32185.67814321827;30709.078727637312;30945;31709;31838;32000.959908808625;37230;37464;37625.62374376221;37683.81158094192;37907.43138852864;26311.185335401224;30063.098107056823;30912;31632.841835791594;32408.189795255308;20131.496712582302;20247.392578222676;23671.526569468857;24840.37899052538;25332.620010699484 14:57:21 INFO - PID 1636 | |2;jquery.html;426.91239837196133;427.6550249465431;432.82686925229916;448.8339314453652;455.84670782919204;19887.701684474745;21303.828289444074;22200.000999955002;22293.665595016046;22405.10379584817;19524;21972.670409943825;22840;23222.303330900057;23443.296701098952;6035.577509574332;6175.691215439232;6213.25440947087;6256.436920677138;6267.279262884758;5722;5943.138244954501;5979.671118088503;6034.275886893579;6039.1243269725865;5928.258967629046;5960.79137230197;5976.342602313742;5983.85040373992;5996;5961.970190149065;5982.820515384524;6060.272767267934;6063.423974722438;6072.05883088122;5235.319408476874;5325.494078062617;5326.041312563737;5385.707430216719;5388.299521062276;22901.770982290298;27710.445791084065;28030;28129.57805632917;28276.01033963822;2227.265002549171;2290.1526435218907;2293.059845463352;2293.2317673579373;2298.6092364297942;17692.203850826743;17787.288508459646;18345.633087338178;18412.98728569928;18624.44126676188;2113.5244569971787;2150.946036442148;2183.192218879009;2187.048633844283;2197.7142971413773;145677.27161364126;147521.71793060208;147902;148397;149477.50522494855;12356.073294502768;12743.808842867178;12888.226706397458;12911.22532648044;12950.287734174712;15901.840981590267;16164.949278296874;16221.351145954266;16250.75623865643;16251.10618915971;8743.300535957109;8994.010658827523;9086.591103400362;9188;9239.505333560035;7823.904653348536;8268.875966860502;8745.300375969908;8749.781255468537;8756.299496040301;6529.7388104475285;7718.845623087507;7801.609919504002;7844.176361482077;7939.920600793918;2111.9155233790784;2127.319257837477;2134.1463414634272;2146.3239079689847;2385.8449200801892;956.8756061711933;971.3928794503436;981.676046904534;1003.8193125237527;1028.0490546244844;20164.983501649716;20801.687974680393;20805.687914681293;20954.265064792424;21068.367948961557;982.9655962041437;1000.1898462245459;1001.154024849016;1024.1039090795555;1030.9742256443649;9914.157296629723;10710.143188544898;10859.457027148768;10878;10944.507497162487;706359;712702;715060;730680;734484;123808;139127;150612.2469387646;157134;168720;1024.8257796174669;1033.1631378583372;1135.8296255561568;1137.7212582917073;1185.5435657272012;21576.244831431453;22600.773992259867;22944;22946.770532294464;22968.966396511863;60593.69703151456;61975.07037394353;64460.785538963464;64562.35437645563;64672.05983820493;88538;91272;93328.06671933194;93747.53126234325;94396.5840512379;7406.14829294638;8659.567021649018;8681.045085040514;8985.415947963362;9193.034731353111;13668.316584170552;14213.147211167363;14434.278286085864;14444.277786110863;14594.227096851564;11070.612528561462;11870.228435151688;11955.820662690241;12183.208091474025;12277.263364198177;11183.664490065312;11186.776264474827;11430.714232144262;11574.247673901169;11668.124890633067;12065.457054432396;12086.939565302117;12231.938840305742;12250.87749122533;12386.504539818307;8859.512726800089;8895.644174232959;8968.282537396863;8970.820583588162;8981.820363592822;10123.493825308853;11604.187706860777;11933.52265909354;12024.519019239133;12200 14:57:21 INFO - PID 1636 | |3;mootools.html;1359.7076628524865;1411.6259191314298;1413.0108923753369;1420.7016526529428;1443.898927075105;11599;11804.704882377933;11970.999999999978;11994.820077698841;12335.691607709812;12050.51797928084;12161.27032378058;12168.51325946961;12176.93911530441;12189;2537.3529749913746;2582.3156863431204;2583.4962182374443;2586.6249393837884;2587.3272949033294;2138.540213854021;2222.799948004679;2468.5821349199236;2470.258922323305;2547.7579629935126;2547.3758929062406;2558.7569180927876;2569.550328692481;2571.292894454031;2575.1373289947796;2494.887730052143;2502.874856257189;2535.1253817432957;2554.7189809120982;2558.145344625132;2485.3413845331;2493.675822143128;2495.189063554334;2506.9373265668505;2509.962350564743;9669.371490853142;12031.759364812655;12244.387780611023;12251.142420030601;12251.509939602405;6569.93430065698;6742.629155396451;6826.795196144124;6830.351116643911;6832.282610325944;3650.6349365063547;4169.97915010426;4409.889752756175;4483.641308695296;4483.641308695296;1222.2727477151082;1234.6913271682079;1280.615815255429;1291.070429290909;1296.9559504598888;16593.41923032688;16709.83290167107;16844.66310673792;16857.409990650267;17477.038762868036;4990.600751939836;5657.51911087554;5856.560757943172;5866.911996320058;6000.609960352563;12352.876471235173;12436.067294952914;12473;12556.309402982924;12593.181443206162;6667.899981500282;6710.396064354134;6742.123523941954;6756.662166891734;6765.1205343305055;6042.516598672096;6098.573099883054;6204.162438070893;6270;6275;4362.411074504965;4562.749048802348;4623.630109591225;4673.345731597579;4673.415823022122;1182.2256422043574;1215.611004478576;1293.8447386313514;1310.8164856920039;1331.2877610478347;781.5193655901693;793.7656943452871;801.7755028592004;809.1908091908092;829.307528213936;6124.234470691164;6151.446369826737;6305.936940630627;6361.427471527492;6384.201974753156;907.7563737679421;911.7401540560908;916.5554705967601;930.6230976454547;939.112538650982;3368.7305015598695;3780.2628487445236;3791.696664266852;3878.0886491674432;3885.436611691289;14892.329845156994;15496.845031549765;15534;15542.689146217239;15556.766648500281;15296.464623738117;15437.9228103861;15462.768058479132;15480.071195728293;15487.070775753491;10610.99195576419;10923;10989.890101098887;11090.33457992507;11119.944400277947;5880.676562789087;6608.074869518367;6638.468922486093;6752.257251702211;6769.356911093439;13511.932440338129;13946.814520765849;13981.091229070078;14001.789973150208;14018.859811401755;13328.80040796333;13849.238291894042;13893.95795315335;14217.289135542975;14343.995920285897;8758.343124265579;8888.111188881061;8932.151445612657;8977.685780997896;8991.595378208127;9025.729228123168;10788.730281743019;10820.729481763019;10921.180911431515;11020.5040773167;9352.205062569758;9362.157405833235;9398.01320861327;9398.34211605181;9431.858522122036;7222;8977.416467929565;9166.954165229132;9221.677241296522;9238.445693258425;8158.224968627971;8293.709720159766;8297;8297;8309.00291964968;5029;5377.919331209957;6018.7291571878495;6117.3576774439825;6227.314995350598;6007.729652165575;6090.269167699905;6104;6253.718582663882;6356.3325850785 14:57:21 INFO - PID 1636 | |4;prototype.html;336.5759143479215;387.9892879050075;388.2700523016728;410.1980627872509;417.58658927661617;6120.969395153029;6328.398802113792;6440.452561532264;6451.387188188779;6473.579217350874;5635.7724714190845;6034.637921724689;6163.305091099938;6271.843203919904;6305.274893387262;3554.3520077542266;4136.420901073852;4399.30626708717;4762.309465127554;4775.164346239411;4708.599769019637;4788.6887352322165;4797.880052998686;4801.8079276828885;4812.205986012304;3707.555093388797;4723.338732577425;4726.645501587377;4739.530745468898;4753.833615823447;4167.937480937789;4739.71561706297;4762.57136857681;4765.833195838146;4778.378810754614;3738.420544815558;3881.3789793633164;3931.174453364797;4230.555791641895;4251.319788833772;5207.505286997729;5571.693556854371;5791.73937172824;6117.602355846856;6122.632642041492;5377.435369286208;5413.323334583177;5422.6475279106735;5428.9185662215095;5429.321334833146;6529.510286728514;6571.605703657796;6603.735850565971;6605.801825945229;6681.365270299314;3898.2203559287973;4404.933925991113;4414.161309351213;4415.889602759925;4422.71252368595;6055.790431071495;6386.329435409308;6615.106960560311;6733.2057196263495;6868.587884726932;4278.957210427918;4480.260756975063;4487.304467807495;4500.932486012713;4511.909761804811;4076.5719599442223;4412.6690498212765;4604.355390245369;4610.97694511532;4625;5240.790368385298;5405.351357837006;5410.8917821643345;5411.323584551931;5454.53636440899;6060.2727672678675;6671.899921501182;6680.229739119137;6725.377102929861;6726.831829204309;1425.4440768100242;1535.9769603455957;1558.8230885681237;1559.1736379718768;1563.7576175692786;930.8929473110679;1026.6150193677372;1029.459533744775;1029.902159294866;1036.9481525923673;5786.392428794916;6217.96891015542;6242.376981984717;6330.436173349801;6366.9681651591445;6542.771003014872;6828.453723702092;6861.725530978705;6932.410745086724;6933.9945707872075;5326.36083669962;6231.594946328474;6232.220972378453;6267.373262673787;6283.214598175228;3409.5226668266464;3498.650134986481;3582.390993531106;3582.4805403216387;3590.263995880838;6983.336583024605;7041.8239544010785;7115.359617634438;7125.572465652078;7144.571085782864;6181.567290289637;6916.619585922722;7049.859002820018;7074.054634975423;7144.642767861586;6654.26803051664;6922.7923162305215;6944.444444444332;6967.93032069683;6978.755743549154;5672.290963629546;6078.513718902389;6631.3700198481065;6856.811582614108;6870.965645171942;6539.803805885831;6643.169603799525;6756.513567015249;6858.417034552119;6925.58446493212;7111.466640001917;7147.641278378227;7157.964210178916;7174.202061196033;7187.604681742554;4483.14820184164;4505.61702255312;4512.548745125461;4543.409356783597;4550.203714350042;6577.473802095725;6929.930700692928;6933.1333583302085;6979.336962988508;7010.509264351446;5518.420565840529;5561.582881283839;5656.321241451053;6145.019665462158;6348.333699846168;5326.946730532645;6342.507340865014;6640.335966403321;6693.2972037935315;6720.361565651256;5268.4994925482015;6474.352564743488;6532.967335163294;6575.967120164369;6588.967055164693;5370.704611246419;6260.530460215593;6645.60126392418;6683.799486015428;6775.356341147583;6419.197600299963;6472.676366181766;6496.805095847132;6534.509911756732;6559.934400656123 14:57:21 INFO - PID 1636 | |5;yui.html;810.4488947515695;836.8284501677153;852.3820230333009;863.8056437301608;877.2806298834956;16552.089635070082;16986.320547178115;16990.490285291446;18106;18811.90594047028;17698.20358083886;18090.00504972226;18139.093045347712;18200.72698909514;18250.452486425427;990.3166814897734;994.4331730913382;1004.5228516454698;1004.9346792458504;1009.5406590001569;1019.2101121630722;1022.8005538919876;1028.4240825137908;1029.4080903480492;1033.0444338986408;1071.8874518175596;1074.274864466486;1076.3272954403499;1076.48328802175;1077.0898590690892;1066.4880857188593;1068.9679309620685;1069.0058245831374;1071.0307172009343;1072.5870539842217;757.4735558786646;773.4547144263281;777.9222077792232;778.6379333609842;779.6257796257771;16608.66782664352;16900.07049612271;17035.914820425944;17045.318187272493;17070.91464542682;17132.486025419257;17335.21991510385;17465.214065366963;17705.38031168903;17743.29026838925;13547.848432883216;13754.31228438584;14082.507112251022;14088.15471071739;14117.64705882351;3309.25541753106;3376.2403459221723;3378.6114596821435;3378.645242249553;3390.1016230698638;106206.93793062048;120622.39688801499;121097.39451302776;121416.39291803572;121892.1716174258;13524.729505409836;13588.388522516312;13817.792733109012;13836.377363018688;13870.543592922799;24968.12611558623;25442.49115017689;25497.872510637328;26153.215403537924;26193.95224191011;9092.545372731336;9202.769930751785;9237.353385262966;9245.676401325922;9282;3899.66852817508;4855.150348689036;4943.703377797344;4965.0069986002945;5112.718800465936;4412.294032954712;4434.068845542408;4455.75493347869;4458.375827384169;4459.639809858025;778.2373274191324;932.2541966426832;934.0472717827778;939.8402271613842;942.40157499988;557.9972100139473;637.8022812928007;657.6185812228991;658.9736410543525;687.7970998555438;10963.397013164353;11216.831747523795;11247.606333778278;11532.769344613229;11930.821037684442;645.815942456407;653.5555822040963;653.9673016349163;656.3469347998772;687.0175648822133;4598.402207712976;5512.5589952803675;5568.136938774496;5626.915596266059;5636.830895073154;105245.47377263065;105785.47107264414;111657.44171279254;112437;115520;84019;96696.51651741697;97257.51371243098;98635;98732;81252;82540.58729706432;83414.58292708619;85064;85186.72219916707;10828.18788590843;11068.833967490333;11182.496787644413;11329.943350283525;11340.432978351215;37135;40779;41249.1750164989;42046;42653.57346426496;41512.79243603763;53680.46319536755;54140;54196;54563;14131.222782747047;14247.145171289756;14337.928310358382;14388.20864852443;14418.206998615176;15686.764698529303;18174.72737908959;18622.255109795457;18757.437276881716;18779.342723005175;16230.675386491968;16636.16819159013;16921.576960576083;17152.742708859627;17354.913225433793;16429.260683269044;16864.747028794824;17132;17350.826491734922;17361.30554777795;16184.607080203488;16342.591435214215;16625.334986600883;16761.497155085366;16824;11782.469788859351;11902.583409580624;11936.045116390842;12005.819912701141;12178.269303841798;13098.607041788762;13457.730845383234;13526.661833454242;13537.864621353661;13628.386722597705 14:57:21 INFO - PID 1636 | __end_tp_report 14:57:21 INFO - PID 1636 | __start_cc_report 14:57:21 INFO - PID 1636 | _x_x_mozilla_cycle_collect,418 14:57:21 INFO - PID 1636 | __end_cc_report 14:57:21 INFO - PID 1636 | __startTimestamp1492034241418__endTimestamp 14:57:21 INFO - PID 1636 | 14:57:21 INFO - PID 1636 | ------- Summary: start ------- 14:57:21 INFO - PID 1636 | Number of tests: 6 14:57:21 INFO - PID 1636 | 14:57:21 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:6125.94 Median:6369.27 stddev:768.64 (12.1%) stddev-sans-first:737.42 14:57:21 INFO - PID 1636 | Values: 3261.5 3525.5 3530.1 3564.4 3579.0 5247.9 5744.7 6181.3 6211.4 6251.4 6131.0 6146.7 6190.5 6245.1 6250.1 5474.0 5483.2 5509.5 5539.8 5612.1 4742.3 5407.5 5557.2 5594.8 5624.2 5416.7 5487.8 5516.4 5518.7 5524.9 5471.5 5474.8 5496.1 5505.0 5521.3 5433.6 5455.9 5467.6 5487.4 5505.6 6010.3 6108.1 6150.0 6169.5 6220.0 6727.6 6729.8 6734.8 6755.1 6756.1 6825.8 6835.2 6835.4 6844.6 6849.1 5147.3 6189.4 6194.4 6261.5 6369.3 5883.4 6891.4 6937.7 6951.7 6985.0 4876.8 6330.3 6404.0 6420.0 6639.7 6681.7 6746.4 6791.7 6798.5 6812.9 6432.7 6518.7 6597.2 6667.8 6703.1 6654.3 6688.7 6766.2 6769.9 6774.3 4576.5 5344.9 5366.5 5401.5 5421.9 3790.8 4706.6 4768.9 4825.3 4829.0 4967.4 6148.6 6212.1 6258.5 6290.6 5427.6 5815.9 6777.1 6826.4 6932.4 6229.6 6240.2 6244.8 6317.4 6325.9 5961.5 5969.5 6013.4 6062.8 6086.5 6813.1 6892.3 6905.5 6905.8 6910.6 6398.2 6784.2 6823.0 6824.7 6842.4 6691.2 6811.9 6860.0 6907.6 6908.8 6668.2 6671.7 6691.6 6700.2 6717.1 6467.7 6555.1 6622.7 6738.7 6819.4 6401.6 6510.6 6567.6 6576.7 6589.5 6526.5 6557.8 6567.7 6569.8 6569.9 5922.9 6280.8 6412.7 6425.0 6428.9 6332.5 6387.2 6445.4 6534.7 6586.4 6416.9 6430.0 6436.7 6448.5 6456.4 14:57:21 INFO - PID 1636 | 14:57:21 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:47661.32 Median:34921.74 stddev:87091.91 (249.4%) stddev-sans-first:87265.13 14:57:21 INFO - PID 1636 | Values: 791.0 810.5 1273.4 1277.6 1293.6 38127.8 38768.4 38782.0 38797.8 38811.0 38705.8 39055.2 39129.0 39209.2 39237.2 8009.6 8609.1 8806.0 8818.8 8819.9 8613.0 8678.6 8685.4 8694.0 8712.2 6967.5 7273.0 8646.8 8688.5 8717.1 8658.9 8669.9 8688.6 8700.3 8701.5 8474.5 8493.2 8565.0 8578.3 8579.9 37835.1 38112.8 38135.0 38146.4 38298.5 3624.0 3635.1 3660.6 3663.1 3679.2 30518.2 30666.5 30960.2 30965.4 30966.0 3127.1 3216.8 3223.7 3235.9 3248.4 298715.0 299371.0 299947.0 300307.0 300523.0 39842.0 40857.4 47896.0 49095.3 49162.5 50428.7 50472.5 50473.5 50554.0 50608.7 14387.2 14497.6 14605.5 14622.3 14624.9 11684.6 11727.4 11743.5 11869.1 11899.6 11761.4 12753.8 13393.7 13413.3 13418.6 5317.6 5620.8 5845.6 5904.7 5909.9 1040.3 1052.1 1055.5 1075.6 1079.1 21146.2 23410.6 24432.5 24674.8 24689.6 1052.7 1067.9 1128.9 1131.8 1155.2 12535.1 12581.6 12584.0 12586.4 12676.7 360206.0 361761.0 362174.0 362920.0 364096.0 43822.8 54625.7 54708.6 54975.5 55047.9 1540.5 1590.6 1593.3 1600.4 1611.2 21845.0 24246.6 24252.0 24279.3 24447.4 38363.4 38730.8 38739.0 38753.0 38977.6 327282.0 327919.7 328234.0 328387.0 328439.0 34299.5 34686.7 34754.0 35089.5 35163.8 44353.1 45570.1 45967.3 46487.5 46617.3 24811.4 28259.9 31800.0 31910.8 32185.7 30709.1 30945.0 31709.0 31838.0 32001.0 37230.0 37464.0 37625.6 37683.8 37907.4 26311.2 30063.1 30912.0 31632.8 32408.2 20131.5 20247.4 23671.5 24840.4 25332.6 14:57:21 INFO - PID 1636 | 14:57:21 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:41293.48 Median:12595.96 stddev:120313.79 (955.2%) stddev-sans-first:120612.17 14:57:21 INFO - PID 1636 | Values: 426.9 427.7 432.8 448.8 455.8 19887.7 21303.8 22200.0 22293.7 22405.1 19524.0 21972.7 22840.0 23222.3 23443.3 6035.6 6175.7 6213.3 6256.4 6267.3 5722.0 5943.1 5979.7 6034.3 6039.1 5928.3 5960.8 5976.3 5983.9 5996.0 5962.0 5982.8 6060.3 6063.4 6072.1 5235.3 5325.5 5326.0 5385.7 5388.3 22901.8 27710.4 28030.0 28129.6 28276.0 2227.3 2290.2 2293.1 2293.2 2298.6 17692.2 17787.3 18345.6 18413.0 18624.4 2113.5 2150.9 2183.2 2187.0 2197.7 145677.3 147521.7 147902.0 148397.0 149477.5 12356.1 12743.8 12888.2 12911.2 12950.3 15901.8 16164.9 16221.4 16250.8 16251.1 8743.3 8994.0 9086.6 9188.0 9239.5 7823.9 8268.9 8745.3 8749.8 8756.3 6529.7 7718.8 7801.6 7844.2 7939.9 2111.9 2127.3 2134.1 2146.3 2385.8 956.9 971.4 981.7 1003.8 1028.0 20165.0 20801.7 20805.7 20954.3 21068.4 983.0 1000.2 1001.2 1024.1 1031.0 9914.2 10710.1 10859.5 10878.0 10944.5 706359.0 712702.0 715060.0 730680.0 734484.0 123808.0 139127.0 150612.2 157134.0 168720.0 1024.8 1033.2 1135.8 1137.7 1185.5 21576.2 22600.8 22944.0 22946.8 22969.0 60593.7 61975.1 64460.8 64562.4 64672.1 88538.0 91272.0 93328.1 93747.5 94396.6 7406.1 8659.6 8681.0 8985.4 9193.0 13668.3 14213.1 14434.3 14444.3 14594.2 11070.6 11870.2 11955.8 12183.2 12277.3 11183.7 11186.8 11430.7 11574.2 11668.1 12065.5 12086.9 12231.9 12250.9 12386.5 8859.5 8895.6 8968.3 8970.8 8981.8 10123.5 11604.2 11933.5 12024.5 12200.0 14:57:21 INFO - PID 1636 | 14:57:21 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7261.85 Median:2585.06 stddev:4679.70 (181.0%) stddev-sans-first:4671.81 14:57:21 INFO - PID 1636 | Values: 1359.7 1411.6 1413.0 1420.7 1443.9 11599.0 11804.7 11971.0 11994.8 12335.7 12050.5 12161.3 12168.5 12176.9 12189.0 2537.4 2582.3 2583.5 2586.6 2587.3 2138.5 2222.8 2468.6 2470.3 2547.8 2547.4 2558.8 2569.6 2571.3 2575.1 2494.9 2502.9 2535.1 2554.7 2558.1 2485.3 2493.7 2495.2 2506.9 2510.0 9669.4 12031.8 12244.4 12251.1 12251.5 6569.9 6742.6 6826.8 6830.4 6832.3 3650.6 4170.0 4409.9 4483.6 4483.6 1222.3 1234.7 1280.6 1291.1 1297.0 16593.4 16709.8 16844.7 16857.4 17477.0 4990.6 5657.5 5856.6 5866.9 6000.6 12352.9 12436.1 12473.0 12556.3 12593.2 6667.9 6710.4 6742.1 6756.7 6765.1 6042.5 6098.6 6204.2 6270.0 6275.0 4362.4 4562.7 4623.6 4673.3 4673.4 1182.2 1215.6 1293.8 1310.8 1331.3 781.5 793.8 801.8 809.2 829.3 6124.2 6151.4 6305.9 6361.4 6384.2 907.8 911.7 916.6 930.6 939.1 3368.7 3780.3 3791.7 3878.1 3885.4 14892.3 15496.8 15534.0 15542.7 15556.8 15296.5 15437.9 15462.8 15480.1 15487.1 10611.0 10923.0 10989.9 11090.3 11119.9 5880.7 6608.1 6638.5 6752.3 6769.4 13511.9 13946.8 13981.1 14001.8 14018.9 13328.8 13849.2 13894.0 14217.3 14344.0 8758.3 8888.1 8932.2 8977.7 8991.6 9025.7 10788.7 10820.7 10921.2 11020.5 9352.2 9362.2 9398.0 9398.3 9431.9 7222.0 8977.4 9167.0 9221.7 9238.4 8158.2 8293.7 8297.0 8297.0 8309.0 5029.0 5377.9 6018.7 6117.4 6227.3 6007.7 6090.3 6104.0 6253.7 6356.3 14:57:21 INFO - PID 1636 | 14:57:21 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5379.49 Median:6098.06 stddev:1687.20 (27.7%) stddev-sans-first:1648.93 14:57:21 INFO - PID 1636 | Values: 336.6 388.0 388.3 410.2 417.6 6121.0 6328.4 6440.5 6451.4 6473.6 5635.8 6034.6 6163.3 6271.8 6305.3 3554.4 4136.4 4399.3 4762.3 4775.2 4708.6 4788.7 4797.9 4801.8 4812.2 3707.6 4723.3 4726.6 4739.5 4753.8 4167.9 4739.7 4762.6 4765.8 4778.4 3738.4 3881.4 3931.2 4230.6 4251.3 5207.5 5571.7 5791.7 6117.6 6122.6 5377.4 5413.3 5422.6 5428.9 5429.3 6529.5 6571.6 6603.7 6605.8 6681.4 3898.2 4404.9 4414.2 4415.9 4422.7 6055.8 6386.3 6615.1 6733.2 6868.6 4279.0 4480.3 4487.3 4500.9 4511.9 4076.6 4412.7 4604.4 4611.0 4625.0 5240.8 5405.4 5410.9 5411.3 5454.5 6060.3 6671.9 6680.2 6725.4 6726.8 1425.4 1536.0 1558.8 1559.2 1563.8 930.9 1026.6 1029.5 1029.9 1036.9 5786.4 6218.0 6242.4 6330.4 6367.0 6542.8 6828.5 6861.7 6932.4 6934.0 5326.4 6231.6 6232.2 6267.4 6283.2 3409.5 3498.7 3582.4 3582.5 3590.3 6983.3 7041.8 7115.4 7125.6 7144.6 6181.6 6916.6 7049.9 7074.1 7144.6 6654.3 6922.8 6944.4 6967.9 6978.8 5672.3 6078.5 6631.4 6856.8 6871.0 6539.8 6643.2 6756.5 6858.4 6925.6 7111.5 7147.6 7158.0 7174.2 7187.6 4483.1 4505.6 4512.5 4543.4 4550.2 6577.5 6929.9 6933.1 6979.3 7010.5 5518.4 5561.6 5656.3 6145.0 6348.3 5326.9 6342.5 6640.3 6693.3 6720.4 5268.5 6474.4 6533.0 6576.0 6589.0 5370.7 6260.5 6645.6 6683.8 6775.4 6419.2 6472.7 6496.8 6534.5 6559.9 14:57:21 INFO - PID 1636 | 14:57:21 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:21901.23 Median:17701.79 stddev:30677.30 (173.3%) stddev-sans-first:30722.48 14:57:21 INFO - PID 1636 | Values: 810.4 836.8 852.4 863.8 877.3 16552.1 16986.3 16990.5 18106.0 18811.9 17698.2 18090.0 18139.1 18200.7 18250.5 990.3 994.4 1004.5 1004.9 1009.5 1019.2 1022.8 1028.4 1029.4 1033.0 1071.9 1074.3 1076.3 1076.5 1077.1 1066.5 1069.0 1069.0 1071.0 1072.6 757.5 773.5 777.9 778.6 779.6 16608.7 16900.1 17035.9 17045.3 17070.9 17132.5 17335.2 17465.2 17705.4 17743.3 13547.8 13754.3 14082.5 14088.2 14117.6 3309.3 3376.2 3378.6 3378.6 3390.1 106206.9 120622.4 121097.4 121416.4 121892.2 13524.7 13588.4 13817.8 13836.4 13870.5 24968.1 25442.5 25497.9 26153.2 26194.0 9092.5 9202.8 9237.4 9245.7 9282.0 3899.7 4855.2 4943.7 4965.0 5112.7 4412.3 4434.1 4455.8 4458.4 4459.6 778.2 932.3 934.0 939.8 942.4 558.0 637.8 657.6 659.0 687.8 10963.4 11216.8 11247.6 11532.8 11930.8 645.8 653.6 654.0 656.3 687.0 4598.4 5512.6 5568.1 5626.9 5636.8 105245.5 105785.5 111657.4 112437.0 115520.0 84019.0 96696.5 97257.5 98635.0 98732.0 81252.0 82540.6 83414.6 85064.0 85186.7 10828.2 11068.8 11182.5 11329.9 11340.4 37135.0 40779.0 41249.2 42046.0 42653.6 41512.8 53680.5 54140.0 54196.0 54563.0 14131.2 14247.1 14337.9 14388.2 14418.2 15686.8 18174.7 18622.3 18757.4 18779.3 16230.7 16636.2 16921.6 17152.7 17354.9 16429.3 16864.7 17132.0 17350.8 17361.3 16184.6 16342.6 16625.3 16761.5 16824.0 11782.5 11902.6 11936.0 12005.8 12178.3 13098.6 13457.7 13526.7 13537.9 13628.4 14:57:21 INFO - PID 1636 | -------- Summary: end -------- 14:57:21 INFO - PID 1636 | 14:57:22 INFO - TEST-INFO | 1636: exit 0 14:57:22 INFO - TEST-OK | dromaeo_css | took 1115661ms 14:57:22 INFO - TEST-START | kraken 14:57:22 INFO - operating with platform_type : mac_ 14:57:22 INFO - Initialising browser for kraken test... 14:57:22 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpBOD1y3/profile http://localhost:49242/getInfo.html) 14:57:25 INFO - PID 1648 | 2017-04-12 14:57:25.106 plugin-container[1649:9999] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 14:57:25 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:57:25 INFO - PID 1648 | 2017-04-12 14:57:25.111 plugin-container[1649:9999] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 14:57:25 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:57:25 INFO - PID 1648 | 2017-04-12 14:57:25.111 plugin-container[1649:9999] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:57:25 INFO - PID 1648 | __metrics Screen width/height:1600/1200 14:57:25 INFO - PID 1648 | colorDepth:24 14:57:25 INFO - PID 1648 | Browser inner width/height: 1024/689 14:57:25 INFO - PID 1648 | __metrics 14:57:25 ERROR - PID 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 14:57:26 INFO - TEST-INFO | 1648: exit 0 14:57:26 INFO - Browser initialized. 14:57:26 INFO - Running cycle 1/1 for kraken test... 14:57:26 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpBOD1y3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:57:29 INFO - PID 1650 | 2017-04-12 14:57:29.033 plugin-container[1651:10126] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 14:57:29 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:57:29 INFO - PID 1650 | 2017-04-12 14:57:29.033 plugin-container[1651:10126] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 14:57:29 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:57:29 INFO - PID 1650 | 2017-04-12 14:57:29.033 plugin-container[1651:10126] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:57:38 INFO - PID 1650 | RSS: Main: 235716608 14:57:38 INFO - PID 1650 | 14:57:41 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 14:57:42 INFO - PID 1650 | RSS: Main: 250404864 14:57:42 INFO - PID 1650 | 14:57:45 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 14:57:46 INFO - PID 1650 | RSS: Main: 259112960 14:57:46 INFO - PID 1650 | 14:57:49 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 14:57:50 INFO - PID 1650 | RSS: Main: 261414912 14:57:50 INFO - PID 1650 | 14:57:52 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 14:57:52 INFO - PID 1650 | RSS: Main: 263409664 14:57:52 INFO - PID 1650 | 14:57:55 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:57:55 INFO - PID 1650 | RSS: Main: 264019968 14:57:55 INFO - PID 1650 | 14:57:59 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 14:57:59 INFO - PID 1650 | RSS: Main: 269611008 14:57:59 INFO - PID 1650 | 14:58:02 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 14:58:03 INFO - PID 1650 | RSS: Main: 270286848 14:58:03 INFO - PID 1650 | 14:58:07 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 14:58:07 INFO - PID 1650 | RSS: Main: 267112448 14:58:07 INFO - PID 1650 | 14:58:09 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:58:09 INFO - PID 1650 | RSS: Main: 268025856 14:58:09 INFO - PID 1650 | 14:58:11 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:58:12 INFO - PID 1650 | RSS: Main: 268410880 14:58:12 INFO - PID 1650 | 14:58:14 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:58:15 INFO - PID 1650 | RSS: Main: 268480512 14:58:15 INFO - PID 1650 | 14:58:18 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:58:19 INFO - PID 1650 | RSS: Main: 268787712 14:58:19 INFO - PID 1650 | 14:58:22 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:58:23 INFO - PID 1650 | RSS: Main: 266989568 14:58:23 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 14:58:25 INFO - PID 1650 | RSS: Main: 269991936 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | __start_tp_report 14:58:25 INFO - PID 1650 | _x_x_mozilla_page_load 14:58:25 INFO - PID 1650 | _x_x_mozilla_page_load_details 14:58:25 INFO - PID 1650 | |i|pagename|runs| 14:58:25 INFO - PID 1650 | |0;ai-astar;110;123;155;183;185;120;125;124;122;123 14:58:25 INFO - PID 1650 | |1;audio-beat-detection;153;128;159;193;99;150;157;155;142;116 14:58:25 INFO - PID 1650 | |2;audio-dft;219;232;216;231;212;215;211;216;210;213 14:58:25 INFO - PID 1650 | |3;audio-fft;84;64;90;107;58;61;60;60;71;61 14:58:25 INFO - PID 1650 | |4;audio-oscillator;93;97;97;59;60;99;125;109;109;85 14:58:25 INFO - PID 1650 | |5;imaging-gaussian-blur;94;97;140;140;111;97;142;142;94;95 14:58:25 INFO - PID 1650 | |6;imaging-darkroom;101;101;103;103;99;133;162;124;100;102 14:58:25 INFO - PID 1650 | |7;imaging-desaturate;159;154;102;148;164;114;110;125;103;102 14:58:25 INFO - PID 1650 | |8;json-parse-financial;52;54;51;55;52;53;56;52;47;64 14:58:25 INFO - PID 1650 | |9;json-stringify-tinderbox;65;54;51;57;60;93;85;55;81;78 14:58:25 INFO - PID 1650 | |10;stanford-crypto-aes;89;94;90;91;86;88;88;88;89;89 14:58:25 INFO - PID 1650 | |11;stanford-crypto-ccm;138;157;138;160;145;182;171;150;198;142 14:58:25 INFO - PID 1650 | |12;stanford-crypto-pbkdf2;204;221;243;256;192;196;193;189;193;193 14:58:25 INFO - PID 1650 | |13;stanford-crypto-sha256-iterative;71;74;97;60;62;70;84;84;61;102 14:58:25 INFO - PID 1650 | __end_tp_report 14:58:25 INFO - PID 1650 | __start_cc_report 14:58:25 INFO - PID 1650 | _x_x_mozilla_cycle_collect,1024 14:58:25 INFO - PID 1650 | __end_cc_report 14:58:25 INFO - PID 1650 | __startTimestamp1492034305306__endTimestamp 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | ------- Summary: start ------- 14:58:25 INFO - PID 1650 | Number of tests: 14 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#0] ai-astar Cycles:10 Average:137.00 Median:124.50 stddev:27.27 (21.9%) stddev-sans-first:27.12 14:58:25 INFO - PID 1650 | Values: 110.0 123.0 155.0 183.0 185.0 120.0 125.0 124.0 122.0 123.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:156.00 stddev:26.01 (16.7%) stddev-sans-first:27.43 14:58:25 INFO - PID 1650 | Values: 153.0 128.0 159.0 193.0 99.0 150.0 157.0 155.0 142.0 116.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#2] audio-dft Cycles:10 Average:217.50 Median:216.00 stddev:7.85 (3.6%) stddev-sans-first:8.31 14:58:25 INFO - PID 1650 | Values: 219.0 232.0 216.0 231.0 212.0 215.0 211.0 216.0 210.0 213.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#3] audio-fft Cycles:10 Average:71.60 Median:62.50 stddev:16.61 (26.6%) stddev-sans-first:17.00 14:58:25 INFO - PID 1650 | Values: 84.0 64.0 90.0 107.0 58.0 61.0 60.0 60.0 71.0 61.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#4] audio-oscillator Cycles:10 Average:93.30 Median:89.00 stddev:20.85 (23.4%) stddev-sans-first:22.11 14:58:25 INFO - PID 1650 | Values: 93.0 97.0 97.0 59.0 60.0 99.0 125.0 109.0 109.0 85.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:115.20 Median:94.00 stddev:22.74 (24.2%) stddev-sans-first:22.79 14:58:25 INFO - PID 1650 | Values: 94.0 97.0 140.0 140.0 111.0 97.0 142.0 142.0 94.0 95.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#6] imaging-darkroom Cycles:10 Average:112.80 Median:113.50 stddev:20.80 (18.3%) stddev-sans-first:21.62 14:58:25 INFO - PID 1650 | Values: 101.0 101.0 103.0 103.0 99.0 133.0 162.0 124.0 100.0 102.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#7] imaging-desaturate Cycles:10 Average:128.10 Median:136.50 stddev:25.47 (18.7%) stddev-sans-first:24.44 14:58:25 INFO - PID 1650 | Values: 159.0 154.0 102.0 148.0 164.0 114.0 110.0 125.0 103.0 102.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:53.50 stddev:4.40 (8.2%) stddev-sans-first:4.63 14:58:25 INFO - PID 1650 | Values: 52.0 54.0 51.0 55.0 52.0 53.0 56.0 52.0 47.0 64.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:71.50 stddev:15.02 (21.0%) stddev-sans-first:15.90 14:58:25 INFO - PID 1650 | Values: 65.0 54.0 51.0 57.0 60.0 93.0 85.0 55.0 81.0 78.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:89.00 stddev:2.15 (2.4%) stddev-sans-first:2.28 14:58:25 INFO - PID 1650 | Values: 89.0 94.0 90.0 91.0 86.0 88.0 88.0 88.0 89.0 89.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:158.50 stddev:20.11 (12.7%) stddev-sans-first:19.97 14:58:25 INFO - PID 1650 | Values: 138.0 157.0 138.0 160.0 145.0 182.0 171.0 150.0 198.0 142.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:208.00 Median:200.00 stddev:23.92 (12.0%) stddev-sans-first:25.33 14:58:25 INFO - PID 1650 | Values: 204.0 221.0 243.0 256.0 192.0 196.0 193.0 189.0 193.0 193.0 14:58:25 INFO - PID 1650 | 14:58:25 INFO - PID 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.50 Median:72.50 stddev:14.85 (20.5%) stddev-sans-first:15.62 14:58:25 INFO - PID 1650 | Values: 71.0 74.0 97.0 60.0 62.0 70.0 84.0 84.0 61.0 102.0 14:58:25 INFO - PID 1650 | -------- Summary: end -------- 14:58:25 INFO - PID 1650 | 14:58:26 INFO - TEST-INFO | 1650: exit 0 14:58:26 INFO - TEST-OK | kraken | took 64302ms 14:58:26 INFO - SUITE-END | took 1179s 14:58:26 INFO - Completed test suite (00:19:40) 14:58:26 INFO - javascript benchmark 14:58:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7980.857015558657, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3261.4781634938413, 3525.541679581654, 3530.1351168963606, 3564.3584154852115, 3578.9821050894743, 5247.86880327992, 5744.741486633101, 6181.320054793975, 6211.440970312671, 6251.406116418944, 6131.040261761299, 6146.692665366736, 6190.504759619231, 6245.125682404456, 6250.124982502453, 5473.987312347209, 5483.232347471368, 5509.47659972302, 5539.806106786264, 5612.07400778871, 4742.2886567014875, 5407.5133238008575, 5557.166425036236, 5594.804181853636, 5624.184493248476, 5416.702081385522, 5487.835364939058, 5516.365617953927, 5518.668879867221, 5524.861878453031, 5471.452854714496, 5474.808381706621, 5496.0931446311315, 5504.972475137599, 5521.309836270466, 5433.565314774848, 5455.863603409907, 5467.644603100785, 5487.42382049887, 5505.642133261325, 6010.338862725097, 6108.144859719643, 6149.954507733696, 6169.475594574466, 6219.968900155471, 6727.629980351078, 6729.798106056826, 6734.797956061326, 6755.054292399019, 6756.054152418666, 6825.795226143224, 6835.179778426571, 6835.350641689031, 6844.555103918229, 6849.075374824434, 5147.279380886679, 6189.442950134509, 6194.4115309045565, 6261.530385221035, 6369.267534233576, 5883.441073098049, 6891.414229790424, 6937.653117344113, 6951.65241737911, 6984.965075174591, 4876.780544875418, 6330.335314791972, 6404.0, 6420.0, 6639.734410623521, 6681.7327306908155, 6746.359095885883, 6791.728330866808, 6798.5241033128195, 6812.897806532906, 6432.678366081677, 6518.706658200393, 6597.241317248578, 6667.766628167991, 6703.095082163943, 6654.26803051664, 6688.665566721645, 6766.18805743314, 6769.898451523231, 6774.254831968479, 4576.519465456146, 5344.866378340573, 5366.543843773323, 5401.4868587484125, 5421.945780542144, 3790.753601015925, 4706.576408123286, 4768.928466073012, 4825.276208568743, 4829.0, 4967.379077615298, 6148.569600128038, 6212.06818977157, 6258.468030217391, 6290.591111577733, 5427.565794736412, 5815.883682326415, 6777.051212830208, 6826.419754320938, 6932.410745086724, 6229.595076320024, 6240.188775459252, 6244.781432649836, 6317.399847014526, 6325.936740632535, 5961.5230781537375, 5969.462748352669, 6013.428724271187, 6062.757489700362, 6086.513078953762, 6813.080234168324, 6892.345227203407, 6905.482088843457, 6905.75829845963, 6910.619915904473, 6398.200224971879, 6784.185897692111, 6822.965885170543, 6824.727010919508, 6842.418394436329, 6691.230508491586, 6811.93188068113, 6860.03959445668, 6907.585544867324, 6908.792736217922, 6668.199816022108, 6671.666416679243, 6691.5650482718465, 6700.162479690039, 6717.05961165447, 6467.741290348334, 6555.147830781968, 6622.701978410887, 6738.685956238486, 6819.420349270367, 6401.583897046677, 6510.609363438209, 6567.573107748172, 6576.736930522726, 6589.505787065893, 6526.477881769352, 6557.770478033246, 6567.704453299518, 6569.835754105994, 6569.9343006571235, 5922.940770592239, 6280.811575652555, 6412.711427985659, 6425.0, 6428.907085795332, 6332.52506062038, 6387.169667943138, 6445.4199122079235, 6534.738610455529, 6586.4401525870835, 6416.935830641821, 6430.0, 6436.742530298736, 6448.451881590117, 6456.418922297016], "value": 6074.92366311888, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.0309870408748, 810.5136917849289, 1273.3760457375888, 1277.5911708253363, 1293.6119164250722, 38127.80936095319, 38768.418473722915, 38782.0, 38797.80601096994, 38811.0, 38705.806470967604, 39055.21889562207, 39129.0, 39209.2355843987, 39237.21525569494, 8009.559474228915, 8609.096044915288, 8806.031336552975, 8818.823623527525, 8819.867701984474, 8613.0, 8678.609462574168, 8685.392022558424, 8693.95653021734, 8712.172343627346, 6967.512274140812, 7272.9636351818435, 8646.827063458728, 8688.522131282776, 8717.084706105863, 8658.913410865905, 8669.869951950726, 8688.609012594448, 8700.26047785939, 8701.521416322099, 8474.491530508127, 8493.193146651058, 8565.0, 8578.313734901192, 8579.914200858037, 37835.054123646856, 38112.80943595292, 38135.04662383435, 38146.427803582694, 38298.468061277796, 3623.9637603624155, 3635.1457407509215, 3660.5973342932257, 3663.065918190871, 3679.22736225393, 30518.237044073852, 30666.54000189999, 30960.225994350098, 30965.380692386025, 30966.0, 3127.0618814355603, 3216.8069915805127, 3223.693749093832, 3235.88674396395, 3248.3503299340227, 298715.0, 299371.0, 299947.0, 300307.0, 300522.9999999978, 39842.0, 40857.387139192935, 47896.0, 49095.26357104646, 49162.508374916506, 50428.74785626048, 50472.49527504751, 50473.49526504688, 50553.98892022139, 50608.746956264986, 14387.208703521197, 14497.637559060897, 14605.488807891885, 14622.341994610268, 14624.926875365554, 11684.591039313753, 11727.35499547516, 11743.471543780379, 11869.050475961902, 11899.583514576949, 11761.411929403495, 12753.788390102925, 13393.66515837112, 13413.262270575213, 13418.597442076752, 5317.601179911522, 5620.751699320304, 5845.561582881216, 5904.704764761745, 5909.940900591025, 1040.328987802863, 1052.1109662335314, 1055.5355643516828, 1075.6074032978063, 1079.1259080145107, 21146.15415383398, 23410.64884026707, 24432.51134977326, 24674.753252467606, 24689.62965555518, 1052.7052425320921, 1067.9412632305298, 1128.9209755317213, 1131.8059447283194, 1155.1913660437726, 12535.059870509749, 12581.622551323475, 12583.993280537536, 12586.37068146589, 12676.683082923, 360206.0, 361761.0, 362174.0, 362920.0, 364096.0, 43822.78088609537, 54625.72687136539, 54708.63228419242, 54975.450245497035, 55047.89904201894, 1540.4839378808224, 1590.6262028423544, 1593.2909855114594, 1600.3998500562288, 1611.1702473226298, 21845.016974236514, 24246.63630045586, 24252.029918803757, 24279.271621851374, 24447.38881527905, 38363.42454863235, 38730.80634596922, 38739.0, 38753.0, 38977.6102238974, 327282.0, 327919.72080279846, 328234.0, 328387.0, 328439.0, 34299.485507716905, 34686.65313346834, 34754.0, 35089.47365789464, 35163.82418087893, 44353.11293774171, 45570.08859822719, 45967.310490342, 46487.53512464967, 46617.300740488245, 24811.379715507257, 28259.858700706365, 31800.045998620077, 31910.840445798553, 32185.67814321827, 30709.078727637312, 30945.0, 31709.0, 31838.0, 32000.959908808625, 37230.0, 37464.0, 37625.62374376221, 37683.81158094192, 37907.43138852864, 26311.185335401224, 30063.098107056823, 30912.0, 31632.841835791594, 32408.189795255308, 20131.496712582302, 20247.392578222676, 23671.526569468857, 24840.37899052538, 25332.620010699484], "value": 17829.983805053416, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.91239837196133, 427.6550249465431, 432.82686925229916, 448.8339314453652, 455.84670782919204, 19887.701684474745, 21303.828289444074, 22200.000999955002, 22293.665595016046, 22405.10379584817, 19524.0, 21972.670409943825, 22840.0, 23222.303330900057, 23443.296701098952, 6035.577509574332, 6175.691215439232, 6213.25440947087, 6256.436920677138, 6267.279262884758, 5722.0, 5943.138244954501, 5979.671118088503, 6034.275886893579, 6039.1243269725865, 5928.258967629046, 5960.79137230197, 5976.342602313742, 5983.85040373992, 5996.0, 5961.970190149065, 5982.820515384524, 6060.272767267934, 6063.423974722438, 6072.05883088122, 5235.319408476874, 5325.494078062617, 5326.041312563737, 5385.707430216719, 5388.299521062276, 22901.770982290298, 27710.445791084065, 28030.0, 28129.57805632917, 28276.01033963822, 2227.265002549171, 2290.1526435218907, 2293.059845463352, 2293.2317673579373, 2298.6092364297942, 17692.203850826743, 17787.288508459646, 18345.633087338178, 18412.98728569928, 18624.44126676188, 2113.5244569971787, 2150.946036442148, 2183.192218879009, 2187.048633844283, 2197.7142971413773, 145677.27161364126, 147521.71793060208, 147902.0, 148397.0, 149477.50522494855, 12356.073294502768, 12743.808842867178, 12888.226706397458, 12911.22532648044, 12950.287734174712, 15901.840981590267, 16164.949278296874, 16221.351145954266, 16250.75623865643, 16251.10618915971, 8743.300535957109, 8994.010658827523, 9086.591103400362, 9188.0, 9239.505333560035, 7823.904653348536, 8268.875966860502, 8745.300375969908, 8749.781255468537, 8756.299496040301, 6529.7388104475285, 7718.845623087507, 7801.609919504002, 7844.176361482077, 7939.920600793918, 2111.9155233790784, 2127.319257837477, 2134.1463414634272, 2146.3239079689847, 2385.8449200801892, 956.8756061711933, 971.3928794503436, 981.676046904534, 1003.8193125237527, 1028.0490546244844, 20164.983501649716, 20801.687974680393, 20805.687914681293, 20954.265064792424, 21068.367948961557, 982.9655962041437, 1000.1898462245459, 1001.154024849016, 1024.1039090795555, 1030.9742256443649, 9914.157296629723, 10710.143188544898, 10859.457027148768, 10878.0, 10944.507497162487, 706359.0, 712702.0, 715060.0, 730680.0, 734484.0, 123808.0, 139127.0, 150612.2469387646, 157134.0, 168720.0, 1024.8257796174669, 1033.1631378583372, 1135.8296255561568, 1137.7212582917073, 1185.5435657272012, 21576.244831431453, 22600.773992259867, 22944.0, 22946.770532294464, 22968.966396511863, 60593.69703151456, 61975.07037394353, 64460.785538963464, 64562.35437645563, 64672.05983820493, 88538.0, 91272.0, 93328.06671933194, 93747.53126234325, 94396.5840512379, 7406.14829294638, 8659.567021649018, 8681.045085040514, 8985.415947963362, 9193.034731353111, 13668.316584170552, 14213.147211167363, 14434.278286085864, 14444.277786110863, 14594.227096851564, 11070.612528561462, 11870.228435151688, 11955.820662690241, 12183.208091474025, 12277.263364198177, 11183.664490065312, 11186.776264474827, 11430.714232144262, 11574.247673901169, 11668.124890633067, 12065.457054432396, 12086.939565302117, 12231.938840305742, 12250.87749122533, 12386.504539818307, 8859.512726800089, 8895.644174232959, 8968.282537396863, 8970.820583588162, 8981.820363592822, 10123.493825308853, 11604.187706860777, 11933.52265909354, 12024.519019239133, 12200.0], "value": 10660.458129121702, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.7076628524865, 1411.6259191314298, 1413.0108923753369, 1420.7016526529428, 1443.898927075105, 11599.0, 11804.704882377933, 11970.999999999978, 11994.820077698841, 12335.691607709812, 12050.51797928084, 12161.27032378058, 12168.51325946961, 12176.93911530441, 12189.0, 2537.3529749913746, 2582.3156863431204, 2583.4962182374443, 2586.6249393837884, 2587.3272949033294, 2138.540213854021, 2222.799948004679, 2468.5821349199236, 2470.258922323305, 2547.7579629935126, 2547.3758929062406, 2558.7569180927876, 2569.550328692481, 2571.292894454031, 2575.1373289947796, 2494.887730052143, 2502.874856257189, 2535.1253817432957, 2554.7189809120982, 2558.145344625132, 2485.3413845331, 2493.675822143128, 2495.189063554334, 2506.9373265668505, 2509.962350564743, 9669.371490853142, 12031.759364812655, 12244.387780611023, 12251.142420030601, 12251.509939602405, 6569.93430065698, 6742.629155396451, 6826.795196144124, 6830.351116643911, 6832.282610325944, 3650.6349365063547, 4169.97915010426, 4409.889752756175, 4483.641308695296, 4483.641308695296, 1222.2727477151082, 1234.6913271682079, 1280.615815255429, 1291.070429290909, 1296.9559504598888, 16593.41923032688, 16709.83290167107, 16844.66310673792, 16857.409990650267, 17477.038762868036, 4990.600751939836, 5657.51911087554, 5856.560757943172, 5866.911996320058, 6000.609960352563, 12352.876471235173, 12436.067294952914, 12473.0, 12556.309402982924, 12593.181443206162, 6667.899981500282, 6710.396064354134, 6742.123523941954, 6756.662166891734, 6765.1205343305055, 6042.516598672096, 6098.573099883054, 6204.162438070893, 6270.0, 6275.0, 4362.411074504965, 4562.749048802348, 4623.630109591225, 4673.345731597579, 4673.415823022122, 1182.2256422043574, 1215.611004478576, 1293.8447386313514, 1310.8164856920039, 1331.2877610478347, 781.5193655901693, 793.7656943452871, 801.7755028592004, 809.1908091908092, 829.307528213936, 6124.234470691164, 6151.446369826737, 6305.936940630627, 6361.427471527492, 6384.201974753156, 907.7563737679421, 911.7401540560908, 916.5554705967601, 930.6230976454547, 939.112538650982, 3368.7305015598695, 3780.2628487445236, 3791.696664266852, 3878.0886491674432, 3885.436611691289, 14892.329845156994, 15496.845031549765, 15534.0, 15542.689146217239, 15556.766648500281, 15296.464623738117, 15437.9228103861, 15462.768058479132, 15480.071195728293, 15487.070775753491, 10610.99195576419, 10923.0, 10989.890101098887, 11090.33457992507, 11119.944400277947, 5880.676562789087, 6608.074869518367, 6638.468922486093, 6752.257251702211, 6769.356911093439, 13511.932440338129, 13946.814520765849, 13981.091229070078, 14001.789973150208, 14018.859811401755, 13328.80040796333, 13849.238291894042, 13893.95795315335, 14217.289135542975, 14343.995920285897, 8758.343124265579, 8888.111188881061, 8932.151445612657, 8977.685780997896, 8991.595378208127, 9025.729228123168, 10788.730281743019, 10820.729481763019, 10921.180911431515, 11020.5040773167, 9352.205062569758, 9362.157405833235, 9398.01320861327, 9398.34211605181, 9431.858522122036, 7222.0, 8977.416467929565, 9166.954165229132, 9221.677241296522, 9238.445693258425, 8158.224968627971, 8293.709720159766, 8297.0, 8297.0, 8309.00291964968, 5029.0, 5377.919331209957, 6018.7291571878495, 6117.3576774439825, 6227.314995350598, 6007.729652165575, 6090.269167699905, 6104.0, 6253.718582663882, 6356.3325850785], "value": 5448.174774909471, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.5759143479215, 387.9892879050075, 388.2700523016728, 410.1980627872509, 417.58658927661617, 6120.969395153029, 6328.398802113792, 6440.452561532264, 6451.387188188779, 6473.579217350874, 5635.7724714190845, 6034.637921724689, 6163.305091099938, 6271.843203919904, 6305.274893387262, 3554.3520077542266, 4136.420901073852, 4399.30626708717, 4762.309465127554, 4775.164346239411, 4708.599769019637, 4788.6887352322165, 4797.880052998686, 4801.8079276828885, 4812.205986012304, 3707.555093388797, 4723.338732577425, 4726.645501587377, 4739.530745468898, 4753.833615823447, 4167.937480937789, 4739.71561706297, 4762.57136857681, 4765.833195838146, 4778.378810754614, 3738.420544815558, 3881.3789793633164, 3931.174453364797, 4230.555791641895, 4251.319788833772, 5207.505286997729, 5571.693556854371, 5791.73937172824, 6117.602355846856, 6122.632642041492, 5377.435369286208, 5413.323334583177, 5422.6475279106735, 5428.9185662215095, 5429.321334833146, 6529.510286728514, 6571.605703657796, 6603.735850565971, 6605.801825945229, 6681.365270299314, 3898.2203559287973, 4404.933925991113, 4414.161309351213, 4415.889602759925, 4422.71252368595, 6055.790431071495, 6386.329435409308, 6615.106960560311, 6733.2057196263495, 6868.587884726932, 4278.957210427918, 4480.260756975063, 4487.304467807495, 4500.932486012713, 4511.909761804811, 4076.5719599442223, 4412.6690498212765, 4604.355390245369, 4610.97694511532, 4625.0, 5240.790368385298, 5405.351357837006, 5410.8917821643345, 5411.323584551931, 5454.53636440899, 6060.2727672678675, 6671.899921501182, 6680.229739119137, 6725.377102929861, 6726.831829204309, 1425.4440768100242, 1535.9769603455957, 1558.8230885681237, 1559.1736379718768, 1563.7576175692786, 930.8929473110679, 1026.6150193677372, 1029.459533744775, 1029.902159294866, 1036.9481525923673, 5786.392428794916, 6217.96891015542, 6242.376981984717, 6330.436173349801, 6366.9681651591445, 6542.771003014872, 6828.453723702092, 6861.725530978705, 6932.410745086724, 6933.9945707872075, 5326.36083669962, 6231.594946328474, 6232.220972378453, 6267.373262673787, 6283.214598175228, 3409.5226668266464, 3498.650134986481, 3582.390993531106, 3582.4805403216387, 3590.263995880838, 6983.336583024605, 7041.8239544010785, 7115.359617634438, 7125.572465652078, 7144.571085782864, 6181.567290289637, 6916.619585922722, 7049.859002820018, 7074.054634975423, 7144.642767861586, 6654.26803051664, 6922.7923162305215, 6944.444444444332, 6967.93032069683, 6978.755743549154, 5672.290963629546, 6078.513718902389, 6631.3700198481065, 6856.811582614108, 6870.965645171942, 6539.803805885831, 6643.169603799525, 6756.513567015249, 6858.417034552119, 6925.58446493212, 7111.466640001917, 7147.641278378227, 7157.964210178916, 7174.202061196033, 7187.604681742554, 4483.14820184164, 4505.61702255312, 4512.548745125461, 4543.409356783597, 4550.203714350042, 6577.473802095725, 6929.930700692928, 6933.1333583302085, 6979.336962988508, 7010.509264351446, 5518.420565840529, 5561.582881283839, 5656.321241451053, 6145.019665462158, 6348.333699846168, 5326.946730532645, 6342.507340865014, 6640.335966403321, 6693.2972037935315, 6720.361565651256, 5268.4994925482015, 6474.352564743488, 6532.967335163294, 6575.967120164369, 6588.967055164693, 5370.704611246419, 6260.530460215593, 6645.60126392418, 6683.799486015428, 6775.356341147583, 6419.197600299963, 6472.676366181766, 6496.805095847132, 6534.509911756732, 6559.934400656123], "value": 4844.318742681555, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.4488947515695, 836.8284501677153, 852.3820230333009, 863.8056437301608, 877.2806298834956, 16552.089635070082, 16986.320547178115, 16990.490285291446, 18106.0, 18811.90594047028, 17698.20358083886, 18090.00504972226, 18139.093045347712, 18200.72698909514, 18250.452486425427, 990.3166814897734, 994.4331730913382, 1004.5228516454698, 1004.9346792458504, 1009.5406590001569, 1019.2101121630722, 1022.8005538919876, 1028.4240825137908, 1029.4080903480492, 1033.0444338986408, 1071.8874518175596, 1074.274864466486, 1076.3272954403499, 1076.48328802175, 1077.0898590690892, 1066.4880857188593, 1068.9679309620685, 1069.0058245831374, 1071.0307172009343, 1072.5870539842217, 757.4735558786646, 773.4547144263281, 777.9222077792232, 778.6379333609842, 779.6257796257771, 16608.66782664352, 16900.07049612271, 17035.914820425944, 17045.318187272493, 17070.91464542682, 17132.486025419257, 17335.21991510385, 17465.214065366963, 17705.38031168903, 17743.29026838925, 13547.848432883216, 13754.31228438584, 14082.507112251022, 14088.15471071739, 14117.64705882351, 3309.25541753106, 3376.2403459221723, 3378.6114596821435, 3378.645242249553, 3390.1016230698638, 106206.93793062048, 120622.39688801499, 121097.39451302776, 121416.39291803572, 121892.1716174258, 13524.729505409836, 13588.388522516312, 13817.792733109012, 13836.377363018688, 13870.543592922799, 24968.12611558623, 25442.49115017689, 25497.872510637328, 26153.215403537924, 26193.95224191011, 9092.545372731336, 9202.769930751785, 9237.353385262966, 9245.676401325922, 9282.0, 3899.66852817508, 4855.150348689036, 4943.703377797344, 4965.0069986002945, 5112.718800465936, 4412.294032954712, 4434.068845542408, 4455.75493347869, 4458.375827384169, 4459.639809858025, 778.2373274191324, 932.2541966426832, 934.0472717827778, 939.8402271613842, 942.40157499988, 557.9972100139473, 637.8022812928007, 657.6185812228991, 658.9736410543525, 687.7970998555438, 10963.397013164353, 11216.831747523795, 11247.606333778278, 11532.769344613229, 11930.821037684442, 645.815942456407, 653.5555822040963, 653.9673016349163, 656.3469347998772, 687.0175648822133, 4598.402207712976, 5512.5589952803675, 5568.136938774496, 5626.915596266059, 5636.830895073154, 105245.47377263065, 105785.47107264414, 111657.44171279254, 112437.0, 115520.0, 84019.0, 96696.51651741697, 97257.51371243098, 98635.0, 98732.0, 81252.0, 82540.58729706432, 83414.58292708619, 85064.0, 85186.72219916707, 10828.18788590843, 11068.833967490333, 11182.496787644413, 11329.943350283525, 11340.432978351215, 37135.0, 40779.0, 41249.1750164989, 42046.0, 42653.57346426496, 41512.79243603763, 53680.46319536755, 54140.0, 54196.0, 54563.0, 14131.222782747047, 14247.145171289756, 14337.928310358382, 14388.20864852443, 14418.206998615176, 15686.764698529303, 18174.72737908959, 18622.255109795457, 18757.437276881716, 18779.342723005175, 16230.675386491968, 16636.16819159013, 16921.576960576083, 17152.742708859627, 17354.913225433793, 16429.260683269044, 16864.747028794824, 17132.0, 17350.826491734922, 17361.30554777795, 16184.607080203488, 16342.591435214215, 16625.334986600883, 16761.497155085366, 16824.0, 11782.469788859351, 11902.583409580624, 11936.045116390842, 12005.819912701141, 12178.269303841798, 13098.607041788762, 13457.730845383234, 13526.661833454242, 13537.864621353661, 13628.386722597705], "value": 8478.424016933897, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1674.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 123.0, 155.0, 183.0, 185.0, 120.0, 125.0, 124.0, 122.0, 123.0], "value": 137.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 128.0, 159.0, 193.0, 99.0, 150.0, 157.0, 155.0, 142.0, 116.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 232.0, 216.0, 231.0, 212.0, 215.0, 211.0, 216.0, 210.0, 213.0], "value": 217.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 64.0, 90.0, 107.0, 58.0, 61.0, 60.0, 60.0, 71.0, 61.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 97.0, 97.0, 59.0, 60.0, 99.0, 125.0, 109.0, 109.0, 85.0], "value": 93.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 140.0, 140.0, 111.0, 97.0, 142.0, 142.0, 94.0, 95.0], "value": 115.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 103.0, 103.0, 99.0, 133.0, 162.0, 124.0, 100.0, 102.0], "value": 112.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 154.0, 102.0, 148.0, 164.0, 114.0, 110.0, 125.0, 103.0, 102.0], "value": 128.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 51.0, 55.0, 52.0, 53.0, 56.0, 52.0, 47.0, 64.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 54.0, 51.0, 57.0, 60.0, 93.0, 85.0, 55.0, 81.0, 78.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 90.0, 91.0, 86.0, 88.0, 88.0, 88.0, 89.0, 89.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 157.0, 138.0, 160.0, 145.0, 182.0, 171.0, 150.0, 198.0, 142.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 221.0, 243.0, 256.0, 192.0, 196.0, 193.0, 189.0, 193.0, 193.0], "value": 208.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 97.0, 60.0, 62.0, 70.0, 84.0, 84.0, 61.0, 102.0], "value": 76.5, "unit": "score"}]}]} 14:58:26 INFO - Return code: 0 14:58:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:58:26 ERROR - # TBPL SUCCESS # 14:58:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:58:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:58:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:58:26 INFO - 2017-04-12 14:58:26.624 screenresolution[1655:10515] starting screenresolution argv=screenresolution get 14:58:26 INFO - 2017-04-12 14:58:26.627 screenresolution[1655:10515] Display 0: 1600x1200x32@60 14:58:26 INFO - 2017-04-12 14:58:26.634 screenresolution[1656:10518] starting screenresolution argv=screenresolution list 14:58:26 INFO - Available Modes on Display 0 14:58:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:58:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:58:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:58:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:58:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:58:26 INFO - Intel Iris: 14:58:26 INFO - Chipset Model: Intel Iris 14:58:26 INFO - Type: GPU 14:58:26 INFO - Bus: Built-In 14:58:26 INFO - VRAM (Dynamic, Max): 1536 MB 14:58:26 INFO - Vendor: Intel (0x8086) 14:58:26 INFO - Device ID: 0x0a2e 14:58:26 INFO - Revision ID: 0x0009 14:58:26 INFO - Displays: 14:58:26 INFO - Display: 14:58:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:58:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:58:26 INFO - Display Serial Number: 1600x1200 60 14:58:26 INFO - Main Display: Yes 14:58:26 INFO - Mirror: Off 14:58:26 INFO - Online: Yes 14:58:26 INFO - Rotation: Supported 14:58:26 INFO - Return code: 0 14:58:26 INFO - Running post-action listener: _resource_record_post_action 14:58:26 INFO - [mozharness: 2017-04-12 21:58:26.879345Z] Finished run-tests step (success) 14:58:26 INFO - Running post-run listener: _resource_record_post_run 14:58:26 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 39250432; Write bytes: 517304320; Read time: 252; Write time: 1639 14:58:26 INFO - TinderboxPrint: CPU usage
54.2% 14:58:26 INFO - TinderboxPrint: I/O read bytes / time
39,250,432 / 252 14:58:26 INFO - TinderboxPrint: I/O write bytes / time
517,304,320 / 1,639 14:58:26 INFO - TinderboxPrint: CPU idle
2,199.2 (45.8%) 14:58:26 INFO - TinderboxPrint: CPU system
964.7 (20.1%) 14:58:26 INFO - TinderboxPrint: CPU user
1,637.8 (34.1%) 14:58:26 INFO - TinderboxPrint: Swap in / out
247,443,456 / 0 14:58:26 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205263360; Write bytes: 204752896; Read time: 15817; Write time: 435 14:58:26 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 37169664; Write bytes: 305473536; Read time: 228; Write time: 1176 14:58:27 INFO - Running post-run listener: _upload_blobber_files 14:58:27 INFO - Blob upload gear active. 14:58:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:58:27 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:58:27 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'] 14:58:27 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 14:58:27 INFO - (blobuploader) - INFO - Open directory for files ... 14:58:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:58:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:58:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:58:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:58:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:58:28 INFO - (blobuploader) - INFO - Done attempting. 14:58:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:58:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:58:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:58:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:58:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:58:29 INFO - (blobuploader) - INFO - Done attempting. 14:58:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:58:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:58:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:58:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:58:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:58:30 INFO - (blobuploader) - INFO - Done attempting. 14:58:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:58:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:58:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:58:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:58:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:58:32 INFO - (blobuploader) - INFO - Done attempting. 14:58:32 INFO - (blobuploader) - INFO - Iteration through files over. 14:58:32 INFO - Return code: 0 14:58:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:58:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:58:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/349b9c2787fbd837f963553ee2357bafebf71cd1751d4aa9ec4e89e9b9839db78866e8b96316cb218d81d4e852a682d054d4808c52b6f1125bd3d91637b96ec0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/399f9206b26561637f3cd776dd3683084486b5ce209ed12d1b30ff5970958dc70ec983d859bfb7683acdd5e92dd5e8ffcffd6639bee0d9c2d2bd43154db9da71", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bfada69b2e69c3dfb82403cb880c486992b10e622cb18ca8e8d75222496c139aeb32b069f457ac11e9c466d3a7b2fcb08c5085d08e68cf01d364d6ccdd6eeffc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/938de9f31d78a5d6e19e19a9c1e23b20f1148f99d86c99edf9000174a333349301344234011f9902edfa1eb7820f3dcf12c8610440c939145b70d473e7705262"} 14:58:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:58:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:58:32 INFO - Contents: 14:58:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/349b9c2787fbd837f963553ee2357bafebf71cd1751d4aa9ec4e89e9b9839db78866e8b96316cb218d81d4e852a682d054d4808c52b6f1125bd3d91637b96ec0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/399f9206b26561637f3cd776dd3683084486b5ce209ed12d1b30ff5970958dc70ec983d859bfb7683acdd5e92dd5e8ffcffd6639bee0d9c2d2bd43154db9da71", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bfada69b2e69c3dfb82403cb880c486992b10e622cb18ca8e8d75222496c139aeb32b069f457ac11e9c466d3a7b2fcb08c5085d08e68cf01d364d6ccdd6eeffc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/938de9f31d78a5d6e19e19a9c1e23b20f1148f99d86c99edf9000174a333349301344234011f9902edfa1eb7820f3dcf12c8610440c939145b70d473e7705262"} 14:58:32 INFO - Running post-run listener: copy_logs_to_upload_dir 14:58:32 INFO - Copying logs to upload dir... 14:58:32 INFO - mkdir: /builds/slave/test/build/upload/logs 14:58:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.578283 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-04-12 14:58:32.534725) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 14:58:32.538398) ========= 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.rx56ZCi90w/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.LEXc1mnbVN/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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/349b9c2787fbd837f963553ee2357bafebf71cd1751d4aa9ec4e89e9b9839db78866e8b96316cb218d81d4e852a682d054d4808c52b6f1125bd3d91637b96ec0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/399f9206b26561637f3cd776dd3683084486b5ce209ed12d1b30ff5970958dc70ec983d859bfb7683acdd5e92dd5e8ffcffd6639bee0d9c2d2bd43154db9da71", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bfada69b2e69c3dfb82403cb880c486992b10e622cb18ca8e8d75222496c139aeb32b069f457ac11e9c466d3a7b2fcb08c5085d08e68cf01d364d6ccdd6eeffc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/938de9f31d78a5d6e19e19a9c1e23b20f1148f99d86c99edf9000174a333349301344234011f9902edfa1eb7820f3dcf12c8610440c939145b70d473e7705262"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/pine-macosx64/1491932768/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/349b9c2787fbd837f963553ee2357bafebf71cd1751d4aa9ec4e89e9b9839db78866e8b96316cb218d81d4e852a682d054d4808c52b6f1125bd3d91637b96ec0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/399f9206b26561637f3cd776dd3683084486b5ce209ed12d1b30ff5970958dc70ec983d859bfb7683acdd5e92dd5e8ffcffd6639bee0d9c2d2bd43154db9da71", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/bfada69b2e69c3dfb82403cb880c486992b10e622cb18ca8e8d75222496c139aeb32b069f457ac11e9c466d3a7b2fcb08c5085d08e68cf01d364d6ccdd6eeffc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/938de9f31d78a5d6e19e19a9c1e23b20f1148f99d86c99edf9000174a333349301344234011f9902edfa1eb7820f3dcf12c8610440c939145b70d473e7705262"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 14:58:32.572978) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 14:58:32.573320) ========= 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.rx56ZCi90w/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.LEXc1mnbVN/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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.005124 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 14:58:32.621682) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 14:58:32.622015) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 14:58:32.622329) ========= ========= Total master_lag: 0.15 =========