builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0390 starttime: 1470808056.8 results: success (0) buildid: 20160809210458 builduid: c0cce048ef7b4e618e942baafc15adc6 revision: c9bbdb627b7804fee47aa6a6708647e6e589d09c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:36.804633) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:36.805097) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:36.805369) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:36.854470) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:36.854876) ========= 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.okdtNLomZx/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.ro3yM2BMe0/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 --2016-08-09 22:47:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 37.1M=0s 2016-08-09 22:47:36 (37.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094878 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:36.972419) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:36.972742) ========= 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.okdtNLomZx/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.ro3yM2BMe0/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.025097 ========= master_lag: 0.20 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:37.197780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 22:47:37.198161) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c9bbdb627b7804fee47aa6a6708647e6e589d09c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c9bbdb627b7804fee47aa6a6708647e6e589d09c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.okdtNLomZx/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.ro3yM2BMe0/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 2016-08-09 22:47:37,416 truncating revision to first 12 chars 2016-08-09 22:47:37,417 Setting DEBUG logging. 2016-08-09 22:47:37,417 attempt 1/10 2016-08-09 22:47:37,417 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c9bbdb627b78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 22:47:38,448 unpacking tar archive at: fx-team-c9bbdb627b78/testing/mozharness/ program finished with exit code 0 elapsedTime=1.318410 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 22:47:38.674508) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:38.674833) ========= script_repo_revision: c9bbdb627b7804fee47aa6a6708647e6e589d09c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:38.675216) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:38.675479) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 22:47:38.693776) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-09 22:47:38.694220) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.okdtNLomZx/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.ro3yM2BMe0/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:47:38 INFO - MultiFileLogger online at 20160809 22:47:38 in /builds/slave/test 22:47:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:47:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:47:38 INFO - {'append_to_log': False, 22:47:38 INFO - 'base_work_dir': '/builds/slave/test', 22:47:38 INFO - 'blob_upload_branch': 'Fx-Team', 22:47:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:47:38 INFO - 'branch': 'Fx-Team', 22:47:38 INFO - 'buildbot_json_path': 'buildprops.json', 22:47:38 INFO - 'config_files': ('talos/mac_config.py',), 22:47:38 INFO - 'default_actions': ('clobber', 22:47:38 INFO - 'read-buildbot-config', 22:47:38 INFO - 'download-and-extract', 22:47:38 INFO - 'populate-webroot', 22:47:38 INFO - 'create-virtualenv', 22:47:38 INFO - 'install', 22:47:38 INFO - 'run-tests'), 22:47:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:47:38 INFO - 'download_minidump_stackwalk': True, 22:47:38 INFO - 'download_symbols': 'ondemand', 22:47:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:47:38 INFO - 'tooltool.py': '/tools/tooltool.py', 22:47:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:47:38 INFO - '/tools/misc-python/virtualenv.py')}, 22:47:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:47:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:47:38 INFO - 'installer_path': 'installer.exe', 22:47:38 INFO - 'log_level': 'info', 22:47:38 INFO - 'log_name': 'talos', 22:47:38 INFO - 'log_to_console': True, 22:47:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:47:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:47:38 INFO - 'opt_config_files': (), 22:47:38 INFO - 'pip_index': False, 22:47:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:47:38 INFO - 'cmd': ('bash', 22:47:38 INFO - '-c', 22:47:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:47:38 INFO - 'enabled': True, 22:47:38 INFO - 'halt_on_failure': False, 22:47:38 INFO - 'name': 'check_screen_resolution'},), 22:47:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:47:38 INFO - 'cmd': ('bash', 22:47:38 INFO - '-c', 22:47:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:47:38 INFO - 'enabled': True, 22:47:38 INFO - 'halt_on_failure': False, 22:47:38 INFO - 'name': 'check_screen_resolution'},), 22:47:38 INFO - 'run_cmd_checks_enabled': True, 22:47:38 INFO - 'sps_profile': False, 22:47:38 INFO - 'sps_profile_interval': 0, 22:47:38 INFO - 'suite': 'dromaeojs-e10s', 22:47:38 INFO - 'system_bits': '32', 22:47:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:47:38 INFO - 'title': 't-yosemite-r7-0390', 22:47:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:47:38 INFO - 'use_talos_json': True, 22:47:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:47:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:47:38 INFO - 'work_dir': 'build'} 22:47:38 INFO - [mozharness: 2016-08-10 05:47:38.885698Z] Running clobber step. 22:47:38 INFO - Running pre-action listener: _resource_record_pre_action 22:47:38 INFO - Running main action method: clobber 22:47:38 INFO - rmtree: /builds/slave/test/build 22:47:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:47:40 INFO - Running post-action listener: _resource_record_post_action 22:47:40 INFO - [mozharness: 2016-08-10 05:47:40.917620Z] Finished clobber step (success) 22:47:40 INFO - [mozharness: 2016-08-10 05:47:40.917849Z] Running read-buildbot-config step. 22:47:40 INFO - Running pre-action listener: _resource_record_pre_action 22:47:40 INFO - Running main action method: read_buildbot_config 22:47:40 INFO - Using buildbot properties: 22:47:40 INFO - { 22:47:40 INFO - "project": "", 22:47:40 INFO - "product": "firefox", 22:47:40 INFO - "script_repo_revision": "production", 22:47:40 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 22:47:40 INFO - "repository": "", 22:47:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 22:47:40 INFO - "buildid": "20160809210458", 22:47:40 INFO - "pgo_build": "False", 22:47:40 INFO - "basedir": "/builds/slave/test", 22:47:40 INFO - "buildnumber": 87, 22:47:40 INFO - "slavename": "t-yosemite-r7-0390", 22:47:40 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 22:47:40 INFO - "platform": "yosemite_r7", 22:47:40 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 22:47:40 INFO - "branch": "fx-team", 22:47:40 INFO - "repo_path": "integration/fx-team", 22:47:40 INFO - "revision": "c9bbdb627b7804fee47aa6a6708647e6e589d09c", 22:47:40 INFO - "stage_platform": "macosx64", 22:47:40 INFO - "builduid": "c0cce048ef7b4e618e942baafc15adc6", 22:47:40 INFO - "slavebuilddir": "test" 22:47:40 INFO - } 22:47:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 22:47:40 INFO - Running post-action listener: _resource_record_post_action 22:47:40 INFO - [mozharness: 2016-08-10 05:47:40.923378Z] Finished read-buildbot-config step (success) 22:47:40 INFO - [mozharness: 2016-08-10 05:47:40.923603Z] Running download-and-extract step. 22:47:40 INFO - Running pre-action listener: _resource_record_pre_action 22:47:40 INFO - Running main action method: download_and_extract 22:47:40 INFO - mkdir: /builds/slave/test/build/tests 22:47:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:40 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 22:47:40 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 22:47:42 INFO - Downloaded 1199 bytes. 22:47:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 22:47:42 INFO - Using the following test package requirements: 22:47:42 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:47:42 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:42 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 22:47:42 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:42 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 22:47:42 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:42 INFO - u'jsshell-mac.zip'], 22:47:42 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:42 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 22:47:42 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 22:47:42 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:42 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 22:47:42 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:42 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 22:47:42 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:42 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 22:47:42 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 22:47:42 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 22:47:42 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:47:42 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')]} 22:47:42 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:47:42 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:47:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:47:43 INFO - Downloaded 16325980 bytes. 22:47:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:47:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:47:46 INFO - Return code: 0 22:47:46 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:47: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')]} 22:47:46 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:47:46 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 22:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:47:47 INFO - Downloaded 16325980 bytes. 22:47:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:47:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:47:50 INFO - Return code: 0 22:47: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')]} 22:47:50 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:47:50 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 22:47:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:47:51 INFO - Downloaded 14413723 bytes. 22:47:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:47:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:47:51 INFO - Return code: 0 22:47:51 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')]} 22:47:51 INFO - trying https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:47:51 INFO - Downloading https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 22:47:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 22:47:54 INFO - Downloaded 110879628 bytes. 22:47:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:47:54 INFO - mkdir: /builds/slave/test/properties 22:47:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:47:54 INFO - Writing to file /builds/slave/test/properties/build_url 22:47:54 INFO - Contents: 22:47:54 INFO - build_url:https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 22:47:55 INFO - Running post-action listener: _resource_record_post_action 22:47:55 INFO - Running post-action listener: set_extra_try_arguments 22:47:55 INFO - [mozharness: 2016-08-10 05:47:55.100064Z] Finished download-and-extract step (success) 22:47:55 INFO - [mozharness: 2016-08-10 05:47:55.100204Z] Running populate-webroot step. 22:47:55 INFO - Running pre-action listener: _resource_record_pre_action 22:47:55 INFO - Running main action method: populate_webroot 22:47:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:47:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:47:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:47:55 INFO - '--tppagecycles', 22:47:55 INFO - '7'], 22:47:55 INFO - 'tests': ['tsvgm']}}, 22:47:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:47:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:47:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:47:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:47:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:55 INFO - 'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:55 INFO - 'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['damp', 'tps']}, 22:47:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:55 INFO - 'tests': ['damp', 'tps']}, 22:47:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['dromaeo_dom']}, 22:47:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:47:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['basic_compositor_video']}, 22:47:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:47:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['a11yr', 22:47:55 INFO - 'ts_paint', 22:47:55 INFO - 'tpaint', 22:47:55 INFO - 'sessionrestore', 22:47:55 INFO - 'sessionrestore_no_auto_restore', 22:47:55 INFO - 'tabpaint']}, 22:47:55 INFO - 'other-e10s': {'tests': ['a11yr', 22:47:55 INFO - 'ts_paint', 22:47:55 INFO - 'tpaint', 22:47:55 INFO - 'sessionrestore', 22:47:55 INFO - 'sessionrestore_no_auto_restore', 22:47:55 INFO - 'tabpaint']}, 22:47:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:47:55 INFO - 'ts_paint', 22:47:55 INFO - 'tpaint', 22:47:55 INFO - 'sessionrestore', 22:47:55 INFO - 'sessionrestore_no_auto_restore', 22:47:55 INFO - 'tabpaint']}, 22:47:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:47:55 INFO - 'ts_paint', 22:47:55 INFO - 'tpaint', 22:47:55 INFO - 'sessionrestore', 22:47:55 INFO - 'sessionrestore_no_auto_restore', 22:47:55 INFO - 'tabpaint']}, 22:47:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['a11yr', 22:47:55 INFO - 'ts_paint', 22:47:55 INFO - 'tpaint', 22:47:55 INFO - 'sessionrestore', 22:47:55 INFO - 'sessionrestore_no_auto_restore', 22:47:55 INFO - 'tabpaint']}, 22:47:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['a11yr', 22:47:55 INFO - 'ts_paint', 22:47:55 INFO - 'tpaint', 22:47:55 INFO - 'sessionrestore', 22:47:55 INFO - 'sessionrestore_no_auto_restore', 22:47:55 INFO - 'tabpaint']}, 22:47:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['tsvgx', 22:47:55 INFO - 'tsvgr_opacity', 22:47:55 INFO - 'tart', 22:47:55 INFO - 'tscrollx', 22:47:55 INFO - 'cart']}, 22:47:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:47:55 INFO - 'tsvgr_opacity', 22:47:55 INFO - 'tart', 22:47:55 INFO - 'tscrollx', 22:47:55 INFO - 'cart']}, 22:47:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:55 INFO - 'talos_options': ['--disable-e10s'], 22:47:55 INFO - 'tests': ['tp5o']}, 22:47:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:55 INFO - 'tests': ['tp5o']}, 22:47:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:55 INFO - 'talos_options': ['--disable-e10s', 22:47:55 INFO - '--xperf_path', 22:47:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:55 INFO - 'tests': ['tp5n']}, 22:47:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:55 INFO - 'talos_options': ['--xperf_path', 22:47:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:55 INFO - 'tests': ['tp5n']}}, 22:47:55 INFO - 'talos.zip': {'path': '', 22:47:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:47:55 INFO - Running post-action listener: _resource_record_post_action 22:47:55 INFO - [mozharness: 2016-08-10 05:47:55.119321Z] Finished populate-webroot step (success) 22:47:55 INFO - [mozharness: 2016-08-10 05:47:55.119434Z] Running create-virtualenv step. 22:47:55 INFO - Running pre-action listener: _resource_record_pre_action 22:47:55 INFO - Running main action method: create_virtualenv 22:47:55 INFO - Creating virtualenv /builds/slave/test/build/venv 22:47:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:47:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:47:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:47:55 INFO - Using real prefix '/tools/python27' 22:47:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:47:55 INFO - Installing distribute.............................................................................................................................................................................................done. 22:47:58 INFO - Installing pip.................done. 22:47:58 INFO - Return code: 0 22:47:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:47:58 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')]} 22:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:58 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')]} 22:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:58 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 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:47:58 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 22:47:58 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 22:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:47:58 INFO - 'HOME': '/Users/cltbld', 22:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:47:58 INFO - 'LOGNAME': 'cltbld', 22:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:58 INFO - 'MOZ_NO_REMOTE': '1', 22:47:58 INFO - 'NO_EM_RESTART': '1', 22:47:58 INFO - 'PAGER': '/bin/cat', 22:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:58 INFO - 'PWD': '/builds/slave/test', 22:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:47:58 INFO - 'SHELL': '/bin/bash', 22:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:47:58 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:47:58 INFO - 'USER': 'cltbld', 22:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:58 INFO - 'XPC_FLAGS': '0x0', 22:47:58 INFO - 'XPC_SERVICE_NAME': '0', 22:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:47:59 INFO - Cleaning up... 22:47:59 INFO - Return code: 0 22:47:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:47:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:47:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:47:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:47:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:47:59 INFO - 'HOME': '/Users/cltbld', 22:47:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:47:59 INFO - 'LOGNAME': 'cltbld', 22:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:59 INFO - 'MOZ_NO_REMOTE': '1', 22:47:59 INFO - 'NO_EM_RESTART': '1', 22:47:59 INFO - 'PAGER': '/bin/cat', 22:47:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:47:59 INFO - 'PWD': '/builds/slave/test', 22:47:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:47:59 INFO - 'SHELL': '/bin/bash', 22:47:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:47:59 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:47:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:47:59 INFO - 'USER': 'cltbld', 22:47:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:47:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:59 INFO - 'XPC_FLAGS': '0x0', 22:47:59 INFO - 'XPC_SERVICE_NAME': '0', 22:47:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:59 INFO - Downloading/unpacking psutil>=3.1.1 22:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:48:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:05 INFO - Installing collected packages: psutil 22:48:05 INFO - Running setup.py install for psutil 22:48:05 INFO - building 'psutil._psutil_osx' extension 22:48:05 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 22:48:06 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 22:48:06 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 22:48:06 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 22:48:06 INFO - building 'psutil._psutil_posix' extension 22:48:06 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 22:48:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:48:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:48:06 INFO - ^ 22:48:06 INFO - 1 warning generated. 22:48:06 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 22:48:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:48:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:48:06 INFO - Successfully installed psutil 22:48:06 INFO - Cleaning up... 22:48:06 INFO - Return code: 0 22:48:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:48:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:06 INFO - 'HOME': '/Users/cltbld', 22:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:06 INFO - 'LOGNAME': 'cltbld', 22:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:06 INFO - 'MOZ_NO_REMOTE': '1', 22:48:06 INFO - 'NO_EM_RESTART': '1', 22:48:06 INFO - 'PAGER': '/bin/cat', 22:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:06 INFO - 'PWD': '/builds/slave/test', 22:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:06 INFO - 'SHELL': '/bin/bash', 22:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:48:06 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:06 INFO - 'USER': 'cltbld', 22:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:06 INFO - 'XPC_FLAGS': '0x0', 22:48:06 INFO - 'XPC_SERVICE_NAME': '0', 22:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:48:11 INFO - Installing collected packages: mozsystemmonitor 22:48:11 INFO - Running setup.py install for mozsystemmonitor 22:48:11 INFO - Successfully installed mozsystemmonitor 22:48:11 INFO - Cleaning up... 22:48:11 INFO - Return code: 0 22:48:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:11 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 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:48:11 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 22:48:11 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 22:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:11 INFO - 'HOME': '/Users/cltbld', 22:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:11 INFO - 'LOGNAME': 'cltbld', 22:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:11 INFO - 'MOZ_NO_REMOTE': '1', 22:48:11 INFO - 'NO_EM_RESTART': '1', 22:48:11 INFO - 'PAGER': '/bin/cat', 22:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:11 INFO - 'PWD': '/builds/slave/test', 22:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:11 INFO - 'SHELL': '/bin/bash', 22:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:48:11 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:11 INFO - 'USER': 'cltbld', 22:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:11 INFO - 'XPC_FLAGS': '0x0', 22:48:11 INFO - 'XPC_SERVICE_NAME': '0', 22:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:11 INFO - Downloading/unpacking jsonschema==2.5.1 22:48:11 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 22:48:11 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 22:48:11 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 22:48:11 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 22:48:11 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 22:48:11 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 22:48:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:48:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:48:15 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 22:48:15 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 22:48:15 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 22:48:15 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 22:48:15 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 22:48:15 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 22:48:15 INFO - Downloading functools32-3.2.3-2.tar.gz 22:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:48:16 INFO - warning: no files found matching '*.txt' 22:48:16 INFO - no previously-included directories found matching 'build' 22:48:16 INFO - no previously-included directories found matching 'dist' 22:48:16 INFO - no previously-included directories found matching '.git*' 22:48:16 INFO - Installing collected packages: jsonschema, functools32 22:48:16 INFO - Running setup.py install for functools32 22:48:16 INFO - warning: no files found matching '*.txt' 22:48:16 INFO - no previously-included directories found matching 'build' 22:48:16 INFO - no previously-included directories found matching 'dist' 22:48:16 INFO - no previously-included directories found matching '.git*' 22:48:16 INFO - Successfully installed jsonschema functools32 22:48:16 INFO - Cleaning up... 22:48:16 INFO - Return code: 0 22:48:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:48:16 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')]} 22:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:16 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')]} 22:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:16 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 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:48:16 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 22:48:16 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 22:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:16 INFO - 'HOME': '/Users/cltbld', 22:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:16 INFO - 'LOGNAME': 'cltbld', 22:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:16 INFO - 'MOZ_NO_REMOTE': '1', 22:48:16 INFO - 'NO_EM_RESTART': '1', 22:48:16 INFO - 'PAGER': '/bin/cat', 22:48:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:16 INFO - 'PWD': '/builds/slave/test', 22:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:16 INFO - 'SHELL': '/bin/bash', 22:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:48:16 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:16 INFO - 'USER': 'cltbld', 22:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:16 INFO - 'XPC_FLAGS': '0x0', 22:48:16 INFO - 'XPC_SERVICE_NAME': '0', 22:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:48:16 INFO - Cleaning up... 22:48:16 INFO - Return code: 0 22:48:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:48:16 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')]} 22:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:16 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')]} 22:48:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:16 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 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:48:16 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 22:48:16 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 22:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:16 INFO - 'HOME': '/Users/cltbld', 22:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:16 INFO - 'LOGNAME': 'cltbld', 22:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:16 INFO - 'MOZ_NO_REMOTE': '1', 22:48:16 INFO - 'NO_EM_RESTART': '1', 22:48:16 INFO - 'PAGER': '/bin/cat', 22:48:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:16 INFO - 'PWD': '/builds/slave/test', 22:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:16 INFO - 'SHELL': '/bin/bash', 22:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:48:16 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:16 INFO - 'USER': 'cltbld', 22:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:16 INFO - 'XPC_FLAGS': '0x0', 22:48:16 INFO - 'XPC_SERVICE_NAME': '0', 22:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:16 INFO - Downloading/unpacking blobuploader==1.2.4 22:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:19 INFO - Downloading blobuploader-1.2.4.tar.gz 22:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:48:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:48:19 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 22:48:19 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 22:48:19 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 22:48:19 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 22:48:19 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 22:48:19 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 22:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:48:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:48:20 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 22:48:20 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 22:48:20 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 22:48:20 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 22:48:20 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 22:48:20 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 22:48:20 INFO - Downloading docopt-0.6.1.tar.gz 22:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:48:20 INFO - Installing collected packages: blobuploader, requests, docopt 22:48:20 INFO - Running setup.py install for blobuploader 22:48:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:48:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:48:20 INFO - Running setup.py install for requests 22:48:21 INFO - Running setup.py install for docopt 22:48:21 INFO - Successfully installed blobuploader requests docopt 22:48:21 INFO - Cleaning up... 22:48:21 INFO - Return code: 0 22:48:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:21 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')]} 22:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:21 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')]} 22:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:21 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 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:48:21 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 22:48:21 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 22:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:21 INFO - 'HOME': '/Users/cltbld', 22:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:21 INFO - 'LOGNAME': 'cltbld', 22:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:21 INFO - 'MOZ_NO_REMOTE': '1', 22:48:21 INFO - 'NO_EM_RESTART': '1', 22:48:21 INFO - 'PAGER': '/bin/cat', 22:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:21 INFO - 'PWD': '/builds/slave/test', 22:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:21 INFO - 'SHELL': '/bin/bash', 22:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:48:21 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:21 INFO - 'USER': 'cltbld', 22:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:21 INFO - 'XPC_FLAGS': '0x0', 22:48:21 INFO - 'XPC_SERVICE_NAME': '0', 22:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:48:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-jboZCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:48:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-4eWGF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:48:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-_f5sMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:48:21 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-AJ7EAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:48:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-XgZWCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:48:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-TaUnIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:48:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-igHaM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:48:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ZDAeW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:48:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-jnxhUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:48:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-uvDJbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:48:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-U2jg5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:48:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-A6P0WD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:48:22 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-XwVeB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:48:23 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-3nUiiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:23 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-cRFflL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:48:23 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-S4kHmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:48:23 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-XwtW2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:48:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:48:23 INFO - Running setup.py install for manifestparser 22:48:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:48:23 INFO - Running setup.py install for mozcrash 22:48:23 INFO - Running setup.py install for mozdebug 22:48:23 INFO - Running setup.py install for mozdevice 22:48:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Running setup.py install for mozfile 22:48:24 INFO - Running setup.py install for mozhttpd 22:48:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Running setup.py install for mozinfo 22:48:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Running setup.py install for mozInstall 22:48:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Running setup.py install for mozleak 22:48:24 INFO - Running setup.py install for mozlog 22:48:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:48:24 INFO - Running setup.py install for moznetwork 22:48:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:48:25 INFO - Running setup.py install for mozprocess 22:48:25 INFO - Running setup.py install for mozprofile 22:48:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:48:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:48:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:48:25 INFO - Running setup.py install for mozrunner 22:48:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:48:25 INFO - Running setup.py install for mozscreenshot 22:48:25 INFO - Running setup.py install for moztest 22:48:25 INFO - Running setup.py install for mozversion 22:48:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:48:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:48:25 INFO - Cleaning up... 22:48:25 INFO - Return code: 0 22:48:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48: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')]} 22:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48: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')]} 22:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:25 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 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:48:25 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 22:48:25 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 22:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:25 INFO - 'HOME': '/Users/cltbld', 22:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:25 INFO - 'LOGNAME': 'cltbld', 22:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:25 INFO - 'MOZ_NO_REMOTE': '1', 22:48:25 INFO - 'NO_EM_RESTART': '1', 22:48:25 INFO - 'PAGER': '/bin/cat', 22:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:25 INFO - 'PWD': '/builds/slave/test', 22:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:25 INFO - 'SHELL': '/bin/bash', 22:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:48:25 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:25 INFO - 'USER': 'cltbld', 22:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:25 INFO - 'XPC_FLAGS': '0x0', 22:48:25 INFO - 'XPC_SERVICE_NAME': '0', 22:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:48:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ifOvse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:48:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ijjOlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:48:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-We5WtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:48:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-yE8K6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:48:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-JtHek0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:48:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-iPaGIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:48:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-WFwaRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:48:26 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)) 22:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:48:26 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-mXWpVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:48:27 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)) 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:48:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-fdiFlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:48:27 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)) 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:48:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-CLGZR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:48:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-VRjKB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:48:27 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)) 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:48:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-2zJODk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:48:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-tC9Z11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:48:27 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)) 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:48:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ISE9Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-kxd65u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:48:27 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)) 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:48:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-XMbNJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 22:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:48:27 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-TRXyHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:48:28 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)) 22:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:48:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:48:28 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)) 22:48:28 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:48:28 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:48:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:48:28 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 22:48:28 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 22:48:28 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 22:48:28 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 22:48:28 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 22:48:28 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 22:48:32 INFO - Downloading blessings-1.6.tar.gz 22:48:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:48:32 INFO - Installing collected packages: blessings 22:48:32 INFO - Running setup.py install for blessings 22:48:32 INFO - Successfully installed blessings 22:48:32 INFO - Cleaning up... 22:48:32 INFO - Return code: 0 22:48:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:48:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:48:33 INFO - Reading from file tmpfile_stdout 22:48:33 INFO - Current package versions: 22:48:33 INFO - blessings == 1.6 22:48:33 INFO - blobuploader == 1.2.4 22:48:33 INFO - docopt == 0.6.1 22:48:33 INFO - functools32 == 3.2.3-2 22:48:33 INFO - jsonschema == 2.5.1 22:48:33 INFO - manifestparser == 1.1 22:48:33 INFO - mozInstall == 1.12 22:48:33 INFO - mozcrash == 0.17 22:48:33 INFO - mozdebug == 0.1 22:48:33 INFO - mozdevice == 0.48 22:48:33 INFO - mozfile == 1.2 22:48:33 INFO - mozhttpd == 0.7 22:48:33 INFO - mozinfo == 0.9 22:48:33 INFO - mozleak == 0.1 22:48:33 INFO - mozlog == 3.3 22:48:33 INFO - moznetwork == 0.27 22:48:33 INFO - mozprocess == 0.23 22:48:33 INFO - mozprofile == 0.28 22:48:33 INFO - mozrunner == 6.12 22:48:33 INFO - mozscreenshot == 0.1 22:48:33 INFO - mozsystemmonitor == 0.3 22:48:33 INFO - moztest == 0.7 22:48:33 INFO - mozversion == 1.4 22:48:33 INFO - psutil == 3.1.1 22:48:33 INFO - requests == 1.2.3 22:48:33 INFO - wsgiref == 0.1.2 22:48:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:48:33 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')]} 22:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:33 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')]} 22:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:33 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 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:48:33 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 22:48:33 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 22:48:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:48:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:33 INFO - 'HOME': '/Users/cltbld', 22:48:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:33 INFO - 'LOGNAME': 'cltbld', 22:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:33 INFO - 'MOZ_NO_REMOTE': '1', 22:48:33 INFO - 'NO_EM_RESTART': '1', 22:48:33 INFO - 'PAGER': '/bin/cat', 22:48:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:33 INFO - 'PWD': '/builds/slave/test', 22:48:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:33 INFO - 'SHELL': '/bin/bash', 22:48:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:48:33 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:48:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:33 INFO - 'USER': 'cltbld', 22:48:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:33 INFO - 'XPC_FLAGS': '0x0', 22:48:33 INFO - 'XPC_SERVICE_NAME': '0', 22:48:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:33 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)) 22:48:33 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)) 22:48:33 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)) 22:48:33 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)) 22:48:33 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)) 22:48:33 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)) 22:48:33 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)) 22:48:33 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)) 22:48:33 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)) 22:48:33 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)) 22:48:33 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)) 22:48:33 INFO - Cleaning up... 22:48:33 INFO - Return code: 0 22:48:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:48:33 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')]} 22:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:33 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')]} 22:48:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:33 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 0x1029a5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a3a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa173d94e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, '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.okdtNLomZx/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.ro3yM2BMe0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 22:48:33 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 22:48:33 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 22:48:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:48:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:48:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:48:33 INFO - 'HOME': '/Users/cltbld', 22:48:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:48:33 INFO - 'LOGNAME': 'cltbld', 22:48:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:33 INFO - 'MOZ_NO_REMOTE': '1', 22:48:33 INFO - 'NO_EM_RESTART': '1', 22:48:33 INFO - 'PAGER': '/bin/cat', 22:48:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:48:34 INFO - 'PWD': '/builds/slave/test', 22:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:48:34 INFO - 'SHELL': '/bin/bash', 22:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:48:34 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:48:34 INFO - 'USER': 'cltbld', 22:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:34 INFO - 'XPC_FLAGS': '0x0', 22:48:34 INFO - 'XPC_SERVICE_NAME': '0', 22:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:48:34 INFO - Cleaning up... 22:48:34 INFO - Return code: 0 22:48:34 INFO - Running post-action listener: _resource_record_post_action 22:48:34 INFO - Running post-action listener: _start_resource_monitoring 22:48:34 INFO - Starting resource monitoring. 22:48:34 INFO - [mozharness: 2016-08-10 05:48:34.282596Z] Finished create-virtualenv step (success) 22:48:34 INFO - [mozharness: 2016-08-10 05:48:34.283448Z] Running install step. 22:48:34 INFO - Running pre-action listener: _resource_record_pre_action 22:48:34 INFO - Running main action method: install 22:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:48:34 INFO - Reading from file tmpfile_stdout 22:48:34 INFO - Detecting whether we're running mozinstall >=1.0... 22:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:48:34 INFO - Reading from file tmpfile_stdout 22:48:34 INFO - Output received: 22:48:34 INFO - Usage: mozinstall [options] installer 22:48:34 INFO - Options: 22:48:34 INFO - -h, --help show this help message and exit 22:48:34 INFO - -d DEST, --destination=DEST 22:48:34 INFO - Directory to install application into. [default: 22:48:34 INFO - "/builds/slave/test"] 22:48:34 INFO - --app=APP Application being installed. [default: firefox] 22:48:34 INFO - mkdir: /builds/slave/test/build/application 22:48:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:49:08 INFO - Reading from file tmpfile_stdout 22:49:08 INFO - Output received: 22:49:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:49:08 INFO - Running post-action listener: _resource_record_post_action 22:49:08 INFO - [mozharness: 2016-08-10 05:49:08.250351Z] Finished install step (success) 22:49:08 INFO - [mozharness: 2016-08-10 05:49:08.250483Z] Running run-tests step. 22:49:08 INFO - Running pre-action listener: _resource_record_pre_action 22:49:08 INFO - Running main action method: run_tests 22:49:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:49:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:49:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:49:08 INFO - 2016-08-09 22:49:08.264 screenresolution[1649:7438] starting screenresolution argv=screenresolution get 22:49:08 INFO - 2016-08-09 22:49:08.267 screenresolution[1649:7438] Display 0: 1600x1200x32@60 22:49:08 INFO - 2016-08-09 22:49:08.273 screenresolution[1650:7440] starting screenresolution argv=screenresolution list 22:49:08 INFO - Available Modes on Display 0 22:49:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:49:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:49:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:49:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:49:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:49:08 INFO - Intel Iris: 22:49:08 INFO - Chipset Model: Intel Iris 22:49:08 INFO - Type: GPU 22:49:08 INFO - Bus: Built-In 22:49:08 INFO - VRAM (Dynamic, Max): 1536 MB 22:49:08 INFO - Vendor: Intel (0x8086) 22:49:08 INFO - Device ID: 0x0a2e 22:49:08 INFO - Revision ID: 0x0009 22:49:08 INFO - Displays: 22:49:08 INFO - Display: 22:49:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:49:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:49:08 INFO - Display Serial Number: 1600x1200 60 22:49:08 INFO - Main Display: Yes 22:49:08 INFO - Mirror: Off 22:49:08 INFO - Online: Yes 22:49:08 INFO - Rotation: Supported 22:49:08 INFO - Return code: 0 22:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:49:08 INFO - Python 2.7.3 22:49:08 INFO - Return code: 0 22:49:08 INFO - grabbing minidump binary from tooltool 22:49: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')]} 22:49:08 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 0x102909d30>, '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 0x10266edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102901b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:49:08 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 22:49:08 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 22:49:08 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 22:49:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:49:08 INFO - Return code: 0 22:49:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:49:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:49:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:49:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:49:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 22:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --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 22:49:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.okdtNLomZx/Render', 22:49:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:49:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:49:08 INFO - 'HOME': '/Users/cltbld', 22:49:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:49:08 INFO - 'LOGNAME': 'cltbld', 22:49:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:49:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:08 INFO - 'MOZ_NO_REMOTE': '1', 22:49:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:49:08 INFO - 'NO_EM_RESTART': '1', 22:49:08 INFO - 'PAGER': '/bin/cat', 22:49:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:49:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:49:08 INFO - 'PWD': '/builds/slave/test', 22:49:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:49:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:49:08 INFO - 'SHELL': '/bin/bash', 22:49:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ro3yM2BMe0/Listeners', 22:49:08 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 22:49:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:49:08 INFO - 'USER': 'cltbld', 22:49:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:49:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:08 INFO - 'XPC_FLAGS': '0x0', 22:49:08 INFO - 'XPC_SERVICE_NAME': '0', 22:49:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:49:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 22:49:08 INFO - mozversion application_buildid: 20160809210458 22:49:08 INFO - mozversion application_changeset: c9bbdb627b7804fee47aa6a6708647e6e589d09c 22:49:08 INFO - mozversion application_display_name: Nightly 22:49:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:49:08 INFO - mozversion application_name: Firefox 22:49:08 INFO - mozversion application_remotingname: firefox 22:49:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:49:08 INFO - mozversion application_vendor: Mozilla 22:49:08 INFO - mozversion application_version: 51.0a1 22:49:08 INFO - mozversion platform_buildid: 20160809210458 22:49:08 INFO - mozversion platform_changeset: c9bbdb627b7804fee47aa6a6708647e6e589d09c 22:49:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:49:08 INFO - mozversion platform_version: 51.0a1 22:49:08 INFO - using testdate: 1470808148 22:49:08 INFO - actual date: 1470808148 22:49:08 INFO - starting webserver on 'localhost:49272' 22:49:08 INFO - SUITE-START | Running 2 tests 22:49:08 INFO - TEST-START | dromaeo_css 22:49:08 INFO - operating with platform_type : mac_ 22:49:08 INFO - Initialising browser for dromaeo_css test... 22:49:08 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmphGkM9g/profile http://localhost:49272/getInfo.html) 22:49:11 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 22:49:11 INFO - PROCESS | 1660 | colorDepth:24 22:49:11 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 22:49:11 INFO - PROCESS | 1660 | __metrics 22:49:12 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:49:12 INFO - TEST-INFO | 1660: exit 0 22:49:12 INFO - Browser initialized. 22:49:12 INFO - Running cycle 1/1 for dromaeo_css test... 22:49:12 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmphGkM9g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:49:24 INFO - PROCESS | 1663 | RSS: Main: 254668800 22:49:24 INFO - PROCESS | 1663 | 22:50:14 INFO - PROCESS | 1663 | 1470808214546 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:52:27 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 22:52:28 INFO - PROCESS | 1663 | RSS: Main: 267440128 22:52:28 INFO - PROCESS | 1663 | 22:55:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 22:55:31 INFO - PROCESS | 1663 | RSS: Main: 280145920 22:55:31 INFO - PROCESS | 1663 | 22:58:33 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 22:58:34 INFO - PROCESS | 1663 | RSS: Main: 287653888 22:58:34 INFO - PROCESS | 1663 | 23:01:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 23:01:36 INFO - PROCESS | 1663 | RSS: Main: 287019008 23:01:36 INFO - PROCESS | 1663 | 23:04:39 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 23:04:40 INFO - PROCESS | 1663 | RSS: Main: 291217408 23:04:40 INFO - PROCESS | 1663 | 23:07:42 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 23:07:43 INFO - PROCESS | 1663 | RSS: Main: 293666816 23:07:43 INFO - PROCESS | 1663 | 23:07:43 INFO - PROCESS | 1663 | __start_tp_report 23:07:43 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 23:07:43 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 23:07:43 INFO - PROCESS | 1663 | |i|pagename|runs| 23:07:43 INFO - PROCESS | 1663 | |0;dojo.html;3454.205532727471;3459.944716861357;3479.6346383629716;3483.68646821786;3505.929881402372;5825.563082768788;5903.970480147598;5947.494462970647;6022.849428764283;6028.125921741344;5911.7930872419365;5918.171455996164;5935.554833387491;5998.370171132034;6137.693115344237;5281.947180528194;5376.086065368877;5393.541548968343;5449.373322067973;5476.315460567429;5261.000409922121;5306.071437498442;5425.674459532441;5439.646422982493;5454.318210223722;5281.366236051698;5304.310439642841;5333.386660534049;5348.946510534884;5426.565874730012;4422.64618830496;5376.220448035052;5379.892402151974;5382.165764306539;5383.515483606455;5233.9738301308635;5238.895222095575;5327.840164795062;5338.572914166857;5354.839354819362;5579.6652200867675;5584.357798853102;5902.704864756788;5950.226470558843;5992.640441573476;5389.110796718537;6053.092036194562;6239.844003899939;6288.245410550718;6314.86332460157;6142.232220972379;6195.101710252034;6228.657423841732;6302.747890084391;6307.18006659136;5898.941010589882;6003.3996600339615;6011.819645410645;6063.54523410746;6072.119542666289;6351.587146835441;6378.904316435257;6381.617102973836;6425;6454.870902581922;4883.536064073907;6076.969615151896;6141.170941922784;6141.9078713818435;6148.846278843064;6178.567500275028;6260.154879091265;6271.874562508724;6273.65494897785;6288.654124023131;6197.628142311476;6225.533085018551;6228.065790131516;6229.657368844757;6264.874702505925;6116.031445915052;6197.876042479215;6226.346233645492;6248.156498872594;6252.124702541648;4978.626603004716;5017.473165317662;5039.118154322979;5051.166557517969;5072.822451214263;4497.6851620386915;4509.143262780061;4536.432945881765;4541.545845415432;4578.175928332932;5877.500412464903;5913.9112913306335;6026.156338112668;6041.607295525777;6072.301685306246;6215.595986260879;6366.586171898812;6371.745130194833;6374.872502549923;6382.712777924911;6063.696815159313;6123.418275263843;6128.877422451526;6142.9864072428445;6148.661823599745;5474.562035037187;5527.53015993645;5619.381867994517;5625.409332020161;5668.319801623831;5704.372519022904;6548.672566371758;6551.246606640107;6553.803385898431;6571.310012448624;6300.21247344082;6323.019931910561;6330.082138089859;6343.873122537616;6437.903431448438;6291.685415729286;6417.454516366161;6505;6551.377619126175;6575.4410875076155;6201.10084037821;6231.781887633911;6279.748810047547;6281.026440901667;6284.874302514016;6311.116443697794;6318.273398559225;6322.304546499973;6328.46208072319;6343.921533339436;6084.361142080018;6130.061839217998;6168.599041062316;6237.844053898689;6256.843578910563;6145.907811382922;6207.224096987877;6227.221597300338;6250.937490625218;6254.2807577129215;5945.791897283574;6163.1679723238585;6166.599171054047;6171.228596425447;6207.565470417027;6114.297368146176;6166.321704612579;6223.937760622336;6243.344448832807;6264.060390941395;6126.877462450637;6131.472831077545;6148.047052706837;6161.537884658579;6198.876022479435 23:07:43 INFO - PROCESS | 1663 | |1;ext.html;482.840662581348;521.2155705662974;530.0221092085103;563.906955352367;570.2131059138388;38511.61488385115;41323.17353652929;41435;41460.58539414605;41537.16925661485;42343.78828105863;42500.149997000044;42847.57152428475;42849.57150428495;42897.78551107248;9484;9673.70978870635;9678.758031049245;9685.709428717115;9691.127798498133;9621.95189024054;9695.36980096295;9720.708378748648;9725.95137024314;9728.90271097287;9611.423314601147;9694.272929530278;9730.708078757649;9730.951345243264;9737.902620973771;8262.297704695047;9660.758481037996;9729.075737804897;9736.902630973671;9767.853482197772;8259.174082591693;9520.809898762654;9665.758356041155;9673.854892176552;9673.90326096737;40228.39657405141;40365.79817100925;40518.79740601278;40568.768717720515;40642.79678601588;3421.2473255883665;3471;3486.9651303486894;3507.57909050913;3579.6957258633047;31907.361852762813;32444;32802.17994550155;32837.83581082079;33266.6673333266;3229.8869539566003;3232.046546268856;3261.0705948804475;3343.548620936167;3343.732501399882;478518;478947;481053;481565.9999999965;484565;33583.83208083944;58354.7082264586;58479.70760146172;58574.41425585775;58611;34193.65806341905;34938;35152.82423587866;35225.82387088048;35428;14941.252937353089;15062.096274223582;15097;15102.54692359231;15119.395224191128;12646.177998430072;12693.619191424272;12814.9359253205;12826.67933301675;12856.164349317265;7821.256980586835;7829.64766585505;7842;7849.607519623996;7896.289333959971;2844.1751891951517;3219.613646362405;3242.5622540957143;3244.853981570835;3281.753868459827;985.6599473181741;1045.5713157605346;1060.7772367802847;1083.1984331594563;1087.228068071678;26322.868385658334;30953.690463095532;31090.68909310923;31108.22229444282;31165.532517012263;877.4647465046316;997.6558087459815;1020.811149937264;1078.5685725709623;1170.4908364861312;15740.763888541682;16053.43812966564;16112;16144.838551614568;16332.265048072864;708467;710066;713813;716425;718590;59744.40255597388;59783.70108149431;59794.804103917675;60223;60407.395926041056;1357.7148798752373;1376.800363947242;1397.9231142287272;1399.3772771116958;1400.0619584878157;29810.552841707824;30165.396692066475;30600.387992239586;30755.384892302478;30851.382972340878;51167.2324915134;51232;51515.48484515257;51810.96378072493;51885;615244;619429;620171;621431;623111;26308.8684556576;26831.731682682923;26872;26989.730102698722;27095;45910.77044614756;46048.309275360225;48422;48717.75641121772;50009.24986125138;35410.46884296789;37173.256534869695;39232.01919952024;40290.99272518211;40299.79850100731;39434;40276.39585406163;40618.390724139754;40771.38842917418;42057.57942420659;38210.0447488815;39161;40072.59927400688;40182;40207;29839;31737;33624;33993.47029383634;36849;23972.640410394208;24124.91437885264;24220.864512020577;24393.02427902864;26333.34166645849 23:07:43 INFO - PROCESS | 1663 | |2;jquery.html;347.9474589367164;375.59623404839806;383.9462475253464;388.8366885907918;393.77554793767564;19845;20832.85419301938;21479.785202147992;21577.784222157774;21711.131554737793;20198.29305974291;20691.896540517275;20696;20780.89609551954;21038.789612103876;6013.428724271187;6075.270967483908;6113.358097399776;6139.907901381483;6145.938540614581;4227.38702888081;6057.727402266886;6120.663363515005;6126.17296664949;6537.313582073885;6729.8317542061595;6883.174019117688;6883.8967415488805;6890.44876409886;6895.3794158525725;5681.801136960207;5913.615614985012;5950.55370847186;5993.101034844808;6876.621785801779;5192.675867653762;5778.219940308047;5845.327787304472;5871.089981052944;5921.200637913869;13407.061505694604;13603.591892243248;13613;23210.88394558033;24507.529548227165;2149.871007739541;2156.9568608627897;2157.0207643096714;2292.56441276157;2294.5755035318352;9916.057974492413;9993;10359.482025898675;10413.739656508573;15695.293711782997;1881.8118188181209;1885.415521188436;1958.9412317630495;1975.9407217783346;1987.3441764217773;115184;128505;129451.35274323568;129824.35087824595;130213.34893325473;11253.099752019818;11470.910263525118;11559.942200288944;11587.188896777145;11761.647150585497;14530.418783248762;14635.487757928635;14668.55994320172;14690.338934747962;14702.485413010494;8327.625256863455;8620.310375169971;8621.741347759576;8737.257333126629;8766.474011559327;8531.74404767858;8560.315174786003;8567.957160214284;8686.044535101133;8741.213290803858;4466.977665111654;4502.5047244803045;4541.977290113528;4592.7933243004145;4642.744649044335;1454.4763885001391;1661.4351120619046;1821.653885761701;1894.7536820213559;1974.516243520318;819.9918000819915;887.6183241206214;889.4663202078701;1007.6221416968637;1038.4963292802984;21218.893905530374;21236.25673101434;21324.89337553302;21327.36017919465;21329.933503324773;831.8419500294924;842.9799942070041;957.2724729205854;964.6928412001777;987.5901500877055;10600.363978161335;11097.945695158947;11279.32324060559;11306.038986686224;11356.716082098013;577137;581084;587925;588995;590310;89491.10508894958;124695;124863.75136248703;125775;128817;963.6386355116831;967.8403063494585;1004.4324956399611;1007.9193664506822;1016.3139880580578;14823.629409264855;18623;18741.625167496848;18750.437486875417;18922.52693682669;37072.25855482929;41245.793771030956;52840.20739688985;54399.64000899851;54568.18147727867;64961.67519162374;68374;78755;79634;80113.1988680129;7103.822404439724;7172.964135179291;7315.670794814139;7336.7432139874845;7488.737894173678;9008.729738107868;11405.714857128638;11566.363849988331;11798.233114847508;12642.620721378373;10071.244656650633;10606.045455909005;10862.94568527184;10935.507902144263;11062.889371106508;10988.560457581927;11019.559217631206;11040.944795275973;11173.273737207055;11371.43142842838;8579.613917373857;11379.886201137882;11466.197366184288;11467.541298347973;11542.884571154516;6951.9304806953305;7190.784276471714;7317.26827317264;7486.401087913063;8292.792680183044;9177.495237761987;9576.425414474876;10340.53467593945;10850.511726972463;11196.38419886914 23:07:43 INFO - PROCESS | 1663 | |3;mootools.html;1481.4888863342144;1513.1601960911694;1720.9397671081515;1851.2224865556466;1877.9906100469495;6692.765753198627;8258.710945116914;8419.368547358941;8739.825203495935;8845.027047024822;8467.322614190865;8598.1401859814;8647.740567782961;8661.870071948926;8698.91301086989;2285.0859656137554;2322.280093171114;2322.5703244899746;2326.8371214015024;2334.6614740862565;2299.1952816514254;2324.6861673674093;2351.7883390494853;2355.1403737635724;2355.187460326185;2316.5598536278053;2329.87185704787;2344.3787396339985;2350.5533948549805;2356.22244659262;2287.176616418088;2311.17953126641;2312.9190478333176;2327.290176496168;2340.648902664597;2324.8140148788057;2327.1389585853344;2329.3710698111586;2329.7320808107115;2329.7553756855455;8617.956910215471;8637.136286371313;8687.565621718888;8729.345299102593;8732.43239189457;5116.2069879168785;5197.1424714922;5265.420803711589;5279.102552566073;5289.603279753996;3421.538092357525;3468.843902024415;3538.663826936437;3551;3616.9095772605633;1024.86665530091;1043.608646757466;1060.9522571484224;1067.4929408530963;1074.607768164622;10636.521356538975;11287.37919414432;11351.82972255425;11394.202405831513;11413.657590272305;4318.568143185719;4472.262076757299;4637.118947399983;4742;4745.454272758608;9187.908120918839;9692.41845489273;9798.314118011813;9806.558704858156;9808.950955245178;5213.582913366921;5223.608229382811;5238.5547228486;5269.947300527022;5285.445028272053;4326.264535029052;4657.534246575316;4795.472498025214;4827.879303017382;4846.684965477303;3430.5368775215024;3879.3792993120965;3904.2972264992572;3936.291467535871;4023.6781057515327;1267.0560432477862;1280.065552146938;1280.4558062823262;1280.4558062823448;1290.1291628150962;831.7089018843357;912.2884150362728;921.4701546610592;933.2254228990058;970.7912798748303;5413.566914646819;5431.511163995259;5805.535557155417;5864.677442740605;5963.522918166536;422.7252286014115;434.4742861137995;441.12436812926313;444.0563801920918;444.2115245439371;3893.844246230176;3896.649301562873;3979.3633018716864;4003.279409706281;4015.417764424142;10195.643152489776;10651.360918344924;10664.626738064131;10674.466276686135;10678.985496377833;10594.576216951236;10603.151747860153;10641.627543035956;10645.627403040857;10673.89326106729;8057.6776928923355;8152.551609661407;8158.551279679556;8162.428629995843;8167.591620418955;5499.697516636464;5511.401693508998;5708.257926469699;5714;5766.99077661416;9790.06994335537;9843.114119729258;9889.456079915673;9913.256505762241;9937.453440060866;9697;9956.900430995887;10024.949875250577;10109.747256318651;10140.847887281845;6401.87196256063;6926.619035953071;7186.640667966685;7303.488755787044;7501.962490187514;8212.425130240827;8354.039285482248;8386.538740369338;8392.4544904581;8403.747887563382;7371.447141464519;7426.628668566658;7470.215627359266;7489.737859174903;7593.544387336778;7310.52481588695;7339.376153027052;7381.8523629526035;7459.552426854406;7500.099988001475;6835.58986460814;6850.314968503109;6854.485913556404;6905.723771049102;6915.446764258948;4706.223473126895;4897.975510122426;4957.801687932442;4973.303737476684;5074.11203039474;4359.738415695068;4908.214685650279;4969.105560999054;5046.899062018812;5060.721660308718 23:07:43 INFO - PROCESS | 1663 | |4;prototype.html;304.394255431691;307.1859572133845;313.2997750028686;317.896683577837;320.55763046995145;5107.23391491276;5211.5309622134;5362.544183744377;5507.339119305689;5809.315298563422;5183;5536.861578460541;5631.859203519914;5662.688552129642;5676.716164191785;4275.636570891478;4291.141771645668;4298.505671847738;4327.6754243431715;4342.326939324394;3742.195427982983;4074.9185016299657;4160.54234034256;4269.786510674454;4274.781687219143;3822.012553472193;3915.6671682906986;3917.6669983051474;4246.216589032599;4268.954106243975;3531.6468353164605;4209.5579964103945;4268.637165840907;4269.690989483144;4281.785910704452;3426.5202871597994;3555.7510974231814;3580.2839432113683;3613.5969580229116;3666.9083272918124;4980.850574482772;5305.673581604598;5524.171374293888;5546.528545073674;5563.36021357541;4281.636060934793;4818.566329030404;4829.855104346875;4836.927446088312;4837.685550439211;4834.540718631724;5668.461496157858;5706.629069110536;5948.553858460633;6001.849953751147;3496.947545786815;3792.8052663411213;3975.840966361342;3984.123492831601;3991.5409727881374;5176.611754118494;5258.421573626898;6111.449969502722;6207.503399728011;6208.472279856218;3893.53277606689;3935.2719746846924;3991.042149884007;3999.24014437256;4002.3596224604507;3713.9814300928324;3838.846446142179;4054.128362402039;4057.756534607933;4064.227796718614;4793.616510679138;4804.0872234275375;4806.404541547595;4809.711417314972;4816.373871396766;5672.830469604837;5931.3178984417345;5979.222701048836;5980;5992.880142397127;1205.8492688413949;1313.7438199550995;1380.1994842991242;1390.4716207840956;1392.1647011792843;893.620211410148;906.954652267384;914.0904799724184;932.2774849491562;934.5093825741404;4658.277966915133;4974.806046548802;5203.617648528411;5617.269754931833;5662.886742265132;4892.608591312686;5201.5838732901275;6142.078688196806;6189.616997456012;6191.226096737908;5070.8337082471235;5097.770600322994;5484.61607687458;5569.610127291132;5621.522170615462;2699.5140874642752;2738.986305068462;2740.438210166911;2747.582127727587;2770.362816552204;4749.667523273338;4774.809007639725;5327.0411325961695;5350.104654241326;5351.03681337363;4654.138984287918;4798.442453136324;4883.658143929891;4928.802847886044;5134.845954621367;4680.297955306732;4785.401824771903;5294.338207724035;5355.357357117015;5376.623636345496;4248.279446824041;5124.74376281177;5124.8718782029255;5182.378114626269;5234.450382709913;4028.6575641070835;4883.926741098958;5869.383714710065;6150.415710507495;6181.567290289817;5129.615278854176;5812.76748930055;6359.936400635935;6499.760230179081;6523.760485507738;3444.810535420576;3978.9853587335047;4033.032072302685;4038.8990275243355;4053.500204924197;5808.30300363959;5855.707214639166;6154.292256390572;6155.691615644134;6174.289956655042;5590.273264475592;6037.9094313584455;6061.181550579002;6071.817845464643;6081.695915204311;5528.474794894479;5569.526590239713;5744.5117165039765;5903.085021821625;6099.603525770811;5638.379778224474;5768.50967667753;5831.329397119385;5885.764569417175;5949.196858424058;5505.807296744595;5993.280806303272;6033.456988871023;6036.84907877289;6041.553170965766;5174.403789366354;5631.577631677723;5881.441263080001;5901.4688678017465;5945.702714864153 23:07:43 INFO - PROCESS | 1663 | |5;yui.html;657.355791324502;716.9641517924102;742.3689863615924;778.2178910195253;827.5737994932609;15454.459093931715;15776.290066947;15821.683566328666;16078;16248.75626865595;14576.271186440688;14598.562043138723;14643;14721.043132196426;14768;678.3115138134797;856.6573370651743;869.256785448442;870.0168809245544;871.9825603487926;838.3795990966671;863.6286396849353;875.8905136857893;875.9430637008605;876.9210771030606;919.793046564521;920.1166879795371;923.810618823143;924.3298608508811;926.1803304075873;901.9909800901972;910.8178364327161;913.8400779863497;915.0849150849151;916.7066538707617;654.8592052708691;666.0009985022466;685.8388278754488;686.993130068698;687.8074139241021;14714.852851471454;14957.626059348495;15031.924840375837;15041.774373384407;15121.395144194219;11885.762284754257;13061;14107.858921410758;15060.192776866737;15226.771598426032;11936.28382297065;11983.340916249603;12144.57493987715;12236.510539578407;12449.875501244962;3877.7091718121255;3899.395593682955;3910.4720862683744;3970.8411663533425;4089.9182016359805;126450.36774816067;126753;127121.72878271191;127142.36428817797;127983.72016279811;11185;11216.327020378803;11277.830832537518;11485.712857178638;11639.47622356996;23139.652905206436;23259.76740232576;23297;23305.95123219459;23338.533229335317;8154.510729356258;8283.155088223777;8287.668493260202;8348.499090054616;8494.787630309293;4483.349914262414;4513.932291015637;4522.25382811839;4530.750808705487;4698.788554515055;3615.5119058927235;3710.6474884885893;3723.832427540767;3736.5702944161226;3754.962450375516;667.2926697700453;667.4526887951832;677.8847595908708;694.3785312145601;696.095076400677;490.43844797706424;518.6680524464346;617.5059952038441;663.9103720997604;675.189772273274;8821.500344941376;8919.01959323544;9481.573329200202;9591.520423978913;9633.470159141174;514.5831876180306;517.9300794392784;538.1900240138566;614.3518587889819;660.8546119853625;4253.574642535722;4319.200947824663;4324.762138082436;4366.934495982563;4393.802278897458;97264.51367743115;101581;129299.70700293065;155472;155743;133749;134638.65361346456;136005;137111.62888371188;138415;81674;82868;85445;111677.44161279305;112143;6561.573497722633;8430.40987130895;8541.743747687578;8680.739577812676;8712.78218044554;59332;64790.35209648032;65152;65567;66582;94152;99227;99884;101139;101969;9423.858642120236;10257.87163412009;10996.23026388145;11104.65202939356;11312.321260724382;11503.367314797766;12044.614869290144;12295.32375719344;12653.620391388273;12668;10415.4820689313;10475;10615.808139679137;10719.088877445505;11035.668929932115;12153.331566763913;13313.457540420235;13793.10344827583;14001.369938352595;14567.854321457075;11428.485718142538;11681.123915706186;13615.455381785014;14332.641683957985;14380.28098595087;11149.275297105987;11469.197156198987;11514.251573647685;11529.711757206136;12088.697782555506;11561.42192890369;11586.989696685625;11594.536218551164;11647.367086467983;11820.468078936641 23:07:43 INFO - PROCESS | 1663 | __end_tp_report 23:07:43 INFO - PROCESS | 1663 | __start_cc_report 23:07:43 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,404 23:07:43 INFO - PROCESS | 1663 | __end_cc_report 23:07:43 INFO - PROCESS | 1663 | __startTimestamp1470809263242__endTimestamp 23:07:43 INFO - PROCESS | 1663 | 23:07:43 INFO - PROCESS | 1663 | ------- Summary: start ------- 23:07:43 INFO - PROCESS | 1663 | Number of tests: 6 23:07:43 INFO - PROCESS | 1663 | 23:07:43 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5858.59 Median:6130.06 stddev:640.47 (10.4%) stddev-sans-first:614.04 23:07:43 INFO - PROCESS | 1663 | Values: 3454.2 3459.9 3479.6 3483.7 3505.9 5825.6 5904.0 5947.5 6022.8 6028.1 5911.8 5918.2 5935.6 5998.4 6137.7 5281.9 5376.1 5393.5 5449.4 5476.3 5261.0 5306.1 5425.7 5439.6 5454.3 5281.4 5304.3 5333.4 5348.9 5426.6 4422.6 5376.2 5379.9 5382.2 5383.5 5234.0 5238.9 5327.8 5338.6 5354.8 5579.7 5584.4 5902.7 5950.2 5992.6 5389.1 6053.1 6239.8 6288.2 6314.9 6142.2 6195.1 6228.7 6302.7 6307.2 5898.9 6003.4 6011.8 6063.5 6072.1 6351.6 6378.9 6381.6 6425.0 6454.9 4883.5 6077.0 6141.2 6141.9 6148.8 6178.6 6260.2 6271.9 6273.7 6288.7 6197.6 6225.5 6228.1 6229.7 6264.9 6116.0 6197.9 6226.3 6248.2 6252.1 4978.6 5017.5 5039.1 5051.2 5072.8 4497.7 4509.1 4536.4 4541.5 4578.2 5877.5 5913.9 6026.2 6041.6 6072.3 6215.6 6366.6 6371.7 6374.9 6382.7 6063.7 6123.4 6128.9 6143.0 6148.7 5474.6 5527.5 5619.4 5625.4 5668.3 5704.4 6548.7 6551.2 6553.8 6571.3 6300.2 6323.0 6330.1 6343.9 6437.9 6291.7 6417.5 6505.0 6551.4 6575.4 6201.1 6231.8 6279.7 6281.0 6284.9 6311.1 6318.3 6322.3 6328.5 6343.9 6084.4 6130.1 6168.6 6237.8 6256.8 6145.9 6207.2 6227.2 6250.9 6254.3 5945.8 6163.2 6166.6 6171.2 6207.6 6114.3 6166.3 6223.9 6243.3 6264.1 6126.9 6131.5 6148.0 6161.5 6198.9 23:07:43 INFO - PROCESS | 1663 | 23:07:43 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:72116.48 Median:40295.40 stddev:164307.33 (407.8%) stddev-sans-first:164680.23 23:07:43 INFO - PROCESS | 1663 | Values: 482.8 521.2 530.0 563.9 570.2 38511.6 41323.2 41435.0 41460.6 41537.2 42343.8 42500.1 42847.6 42849.6 42897.8 9484.0 9673.7 9678.8 9685.7 9691.1 9622.0 9695.4 9720.7 9726.0 9728.9 9611.4 9694.3 9730.7 9731.0 9737.9 8262.3 9660.8 9729.1 9736.9 9767.9 8259.2 9520.8 9665.8 9673.9 9673.9 40228.4 40365.8 40518.8 40568.8 40642.8 3421.2 3471.0 3487.0 3507.6 3579.7 31907.4 32444.0 32802.2 32837.8 33266.7 3229.9 3232.0 3261.1 3343.5 3343.7 478518.0 478947.0 481053.0 481566.0 484565.0 33583.8 58354.7 58479.7 58574.4 58611.0 34193.7 34938.0 35152.8 35225.8 35428.0 14941.3 15062.1 15097.0 15102.5 15119.4 12646.2 12693.6 12814.9 12826.7 12856.2 7821.3 7829.6 7842.0 7849.6 7896.3 2844.2 3219.6 3242.6 3244.9 3281.8 985.7 1045.6 1060.8 1083.2 1087.2 26322.9 30953.7 31090.7 31108.2 31165.5 877.5 997.7 1020.8 1078.6 1170.5 15740.8 16053.4 16112.0 16144.8 16332.3 708467.0 710066.0 713813.0 716425.0 718590.0 59744.4 59783.7 59794.8 60223.0 60407.4 1357.7 1376.8 1397.9 1399.4 1400.1 29810.6 30165.4 30600.4 30755.4 30851.4 51167.2 51232.0 51515.5 51811.0 51885.0 615244.0 619429.0 620171.0 621431.0 623111.0 26308.9 26831.7 26872.0 26989.7 27095.0 45910.8 46048.3 48422.0 48717.8 50009.2 35410.5 37173.3 39232.0 40291.0 40299.8 39434.0 40276.4 40618.4 40771.4 42057.6 38210.0 39161.0 40072.6 40182.0 40207.0 29839.0 31737.0 33624.0 33993.5 36849.0 23972.6 24124.9 24220.9 24393.0 26333.3 23:07:43 INFO - PROCESS | 1663 | 23:07:43 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34182.17 Median:12752.73 stddev:97778.99 (766.7%) stddev-sans-first:98020.28 23:07:43 INFO - PROCESS | 1663 | Values: 347.9 375.6 383.9 388.8 393.8 19845.0 20832.9 21479.8 21577.8 21711.1 20198.3 20691.9 20696.0 20780.9 21038.8 6013.4 6075.3 6113.4 6139.9 6145.9 4227.4 6057.7 6120.7 6126.2 6537.3 6729.8 6883.2 6883.9 6890.4 6895.4 5681.8 5913.6 5950.6 5993.1 6876.6 5192.7 5778.2 5845.3 5871.1 5921.2 13407.1 13603.6 13613.0 23210.9 24507.5 2149.9 2157.0 2157.0 2292.6 2294.6 9916.1 9993.0 10359.5 10413.7 15695.3 1881.8 1885.4 1958.9 1975.9 1987.3 115184.0 128505.0 129451.4 129824.4 130213.3 11253.1 11470.9 11559.9 11587.2 11761.6 14530.4 14635.5 14668.6 14690.3 14702.5 8327.6 8620.3 8621.7 8737.3 8766.5 8531.7 8560.3 8568.0 8686.0 8741.2 4467.0 4502.5 4542.0 4592.8 4642.7 1454.5 1661.4 1821.7 1894.8 1974.5 820.0 887.6 889.5 1007.6 1038.5 21218.9 21236.3 21324.9 21327.4 21329.9 831.8 843.0 957.3 964.7 987.6 10600.4 11097.9 11279.3 11306.0 11356.7 577137.0 581084.0 587925.0 588995.0 590310.0 89491.1 124695.0 124863.8 125775.0 128817.0 963.6 967.8 1004.4 1007.9 1016.3 14823.6 18623.0 18741.6 18750.4 18922.5 37072.3 41245.8 52840.2 54399.6 54568.2 64961.7 68374.0 78755.0 79634.0 80113.2 7103.8 7173.0 7315.7 7336.7 7488.7 9008.7 11405.7 11566.4 11798.2 12642.6 10071.2 10606.0 10862.9 10935.5 11062.9 10988.6 11019.6 11040.9 11173.3 11371.4 8579.6 11379.9 11466.2 11467.5 11542.9 6951.9 7190.8 7317.3 7486.4 8292.8 9177.5 9576.4 10340.5 10850.5 11196.4 23:07:43 INFO - PROCESS | 1663 | 23:07:43 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5608.99 Median:4903.10 stddev:3156.57 (64.4%) stddev-sans-first:3150.18 23:07:43 INFO - PROCESS | 1663 | Values: 1481.5 1513.2 1720.9 1851.2 1878.0 6692.8 8258.7 8419.4 8739.8 8845.0 8467.3 8598.1 8647.7 8661.9 8698.9 2285.1 2322.3 2322.6 2326.8 2334.7 2299.2 2324.7 2351.8 2355.1 2355.2 2316.6 2329.9 2344.4 2350.6 2356.2 2287.2 2311.2 2312.9 2327.3 2340.6 2324.8 2327.1 2329.4 2329.7 2329.8 8618.0 8637.1 8687.6 8729.3 8732.4 5116.2 5197.1 5265.4 5279.1 5289.6 3421.5 3468.8 3538.7 3551.0 3616.9 1024.9 1043.6 1061.0 1067.5 1074.6 10636.5 11287.4 11351.8 11394.2 11413.7 4318.6 4472.3 4637.1 4742.0 4745.5 9187.9 9692.4 9798.3 9806.6 9809.0 5213.6 5223.6 5238.6 5269.9 5285.4 4326.3 4657.5 4795.5 4827.9 4846.7 3430.5 3879.4 3904.3 3936.3 4023.7 1267.1 1280.1 1280.5 1280.5 1290.1 831.7 912.3 921.5 933.2 970.8 5413.6 5431.5 5805.5 5864.7 5963.5 422.7 434.5 441.1 444.1 444.2 3893.8 3896.6 3979.4 4003.3 4015.4 10195.6 10651.4 10664.6 10674.5 10679.0 10594.6 10603.2 10641.6 10645.6 10673.9 8057.7 8152.6 8158.6 8162.4 8167.6 5499.7 5511.4 5708.3 5714.0 5767.0 9790.1 9843.1 9889.5 9913.3 9937.5 9697.0 9956.9 10024.9 10109.7 10140.8 6401.9 6926.6 7186.6 7303.5 7502.0 8212.4 8354.0 8386.5 8392.5 8403.7 7371.4 7426.6 7470.2 7489.7 7593.5 7310.5 7339.4 7381.9 7459.6 7500.1 6835.6 6850.3 6854.5 6905.7 6915.4 4706.2 4898.0 4957.8 4973.3 5074.1 4359.7 4908.2 4969.1 5046.9 5060.7 23:07:43 INFO - PROCESS | 1663 | 23:07:43 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4649.18 Median:5127.24 stddev:1444.61 (28.2%) stddev-sans-first:1411.37 23:07:43 INFO - PROCESS | 1663 | Values: 304.4 307.2 313.3 317.9 320.6 5107.2 5211.5 5362.5 5507.3 5809.3 5183.0 5536.9 5631.9 5662.7 5676.7 4275.6 4291.1 4298.5 4327.7 4342.3 3742.2 4074.9 4160.5 4269.8 4274.8 3822.0 3915.7 3917.7 4246.2 4269.0 3531.6 4209.6 4268.6 4269.7 4281.8 3426.5 3555.8 3580.3 3613.6 3666.9 4980.9 5305.7 5524.2 5546.5 5563.4 4281.6 4818.6 4829.9 4836.9 4837.7 4834.5 5668.5 5706.6 5948.6 6001.8 3496.9 3792.8 3975.8 3984.1 3991.5 5176.6 5258.4 6111.4 6207.5 6208.5 3893.5 3935.3 3991.0 3999.2 4002.4 3714.0 3838.8 4054.1 4057.8 4064.2 4793.6 4804.1 4806.4 4809.7 4816.4 5672.8 5931.3 5979.2 5980.0 5992.9 1205.8 1313.7 1380.2 1390.5 1392.2 893.6 907.0 914.1 932.3 934.5 4658.3 4974.8 5203.6 5617.3 5662.9 4892.6 5201.6 6142.1 6189.6 6191.2 5070.8 5097.8 5484.6 5569.6 5621.5 2699.5 2739.0 2740.4 2747.6 2770.4 4749.7 4774.8 5327.0 5350.1 5351.0 4654.1 4798.4 4883.7 4928.8 5134.8 4680.3 4785.4 5294.3 5355.4 5376.6 4248.3 5124.7 5124.9 5182.4 5234.5 4028.7 4883.9 5869.4 6150.4 6181.6 5129.6 5812.8 6359.9 6499.8 6523.8 3444.8 3979.0 4033.0 4038.9 4053.5 5808.3 5855.7 6154.3 6155.7 6174.3 5590.3 6037.9 6061.2 6071.8 6081.7 5528.5 5569.5 5744.5 5903.1 6099.6 5638.4 5768.5 5831.3 5885.8 5949.2 5505.8 5993.3 6033.5 6036.8 6041.6 5174.4 5631.6 5881.4 5901.5 5945.7 23:07:43 INFO - PROCESS | 1663 | 23:07:43 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:24663.79 Median:3745.77 stddev:39520.82 (1055.1%) stddev-sans-first:39590.58 23:07:43 INFO - PROCESS | 1663 | Values: 657.4 717.0 742.4 778.2 827.6 15454.5 15776.3 15821.7 16078.0 16248.8 14576.3 14598.6 14643.0 14721.0 14768.0 678.3 856.7 869.3 870.0 872.0 838.4 863.6 875.9 875.9 876.9 919.8 920.1 923.8 924.3 926.2 902.0 910.8 913.8 915.1 916.7 654.9 666.0 685.8 687.0 687.8 14714.9 14957.6 15031.9 15041.8 15121.4 11885.8 13061.0 14107.9 15060.2 15226.8 11936.3 11983.3 12144.6 12236.5 12449.9 3877.7 3899.4 3910.5 3970.8 4089.9 126450.4 126753.0 127121.7 127142.4 127983.7 11185.0 11216.3 11277.8 11485.7 11639.5 23139.7 23259.8 23297.0 23306.0 23338.5 8154.5 8283.2 8287.7 8348.5 8494.8 4483.3 4513.9 4522.3 4530.8 4698.8 3615.5 3710.6 3723.8 3736.6 3755.0 667.3 667.5 677.9 694.4 696.1 490.4 518.7 617.5 663.9 675.2 8821.5 8919.0 9481.6 9591.5 9633.5 514.6 517.9 538.2 614.4 660.9 4253.6 4319.2 4324.8 4366.9 4393.8 97264.5 101581.0 129299.7 155472.0 155743.0 133749.0 134638.7 136005.0 137111.6 138415.0 81674.0 82868.0 85445.0 111677.4 112143.0 6561.6 8430.4 8541.7 8680.7 8712.8 59332.0 64790.4 65152.0 65567.0 66582.0 94152.0 99227.0 99884.0 101139.0 101969.0 9423.9 10257.9 10996.2 11104.7 11312.3 11503.4 12044.6 12295.3 12653.6 12668.0 10415.5 10475.0 10615.8 10719.1 11035.7 12153.3 13313.5 13793.1 14001.4 14567.9 11428.5 11681.1 13615.5 14332.6 14380.3 11149.3 11469.2 11514.3 11529.7 12088.7 11561.4 11587.0 11594.5 11647.4 11820.5 23:07:43 INFO - PROCESS | 1663 | -------- Summary: end -------- 23:07:43 INFO - PROCESS | 1663 | 23:07:43 INFO - TEST-INFO | 1663: exit 0 23:07:43 INFO - TEST-OK | dromaeo_css | took 1114997ms 23:07:43 INFO - TEST-START | kraken 23:07:43 INFO - operating with platform_type : mac_ 23:07:43 INFO - Initialising browser for kraken test... 23:07:43 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpemjr5i/profile http://localhost:49272/getInfo.html) 23:07:46 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 23:07:46 INFO - PROCESS | 1674 | colorDepth:24 23:07:46 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 23:07:46 INFO - PROCESS | 1674 | __metrics 23:07:46 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 23:07:47 INFO - TEST-INFO | 1674: exit 0 23:07:47 INFO - Browser initialized. 23:07:47 INFO - Running cycle 1/1 for kraken test... 23:07:47 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpemjr5i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:59 INFO - PROCESS | 1676 | RSS: Main: 254803968 23:07:59 INFO - PROCESS | 1676 | 23:08:02 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 23:08:03 INFO - PROCESS | 1676 | RSS: Main: 267685888 23:08:03 INFO - PROCESS | 1676 | 23:08:05 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 23:08:06 INFO - PROCESS | 1676 | RSS: Main: 276295680 23:08:06 INFO - PROCESS | 1676 | 23:08:09 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 23:08:10 INFO - PROCESS | 1676 | RSS: Main: 279015424 23:08:10 INFO - PROCESS | 1676 | 23:08:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 23:08:12 INFO - PROCESS | 1676 | RSS: Main: 280473600 23:08:12 INFO - PROCESS | 1676 | 23:08:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:08:15 INFO - PROCESS | 1676 | RSS: Main: 280887296 23:08:15 INFO - PROCESS | 1676 | 23:08:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 23:08:19 INFO - PROCESS | 1676 | RSS: Main: 280870912 23:08:19 INFO - PROCESS | 1676 | 23:08:22 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 23:08:23 INFO - PROCESS | 1676 | RSS: Main: 281063424 23:08:23 INFO - PROCESS | 1676 | 23:08:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 23:08:26 INFO - PROCESS | 1676 | RSS: Main: 281395200 23:08:26 INFO - PROCESS | 1676 | 23:08:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:08:28 INFO - PROCESS | 1676 | RSS: Main: 282472448 23:08:28 INFO - PROCESS | 1676 | 23:08:30 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:08:31 INFO - PROCESS | 1676 | RSS: Main: 282849280 23:08:31 INFO - PROCESS | 1676 | 23:08:33 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:08:34 INFO - PROCESS | 1676 | RSS: Main: 282914816 23:08:34 INFO - PROCESS | 1676 | 23:08:37 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:08:37 INFO - PROCESS | 1676 | RSS: Main: 282886144 23:08:37 INFO - PROCESS | 1676 | 23:08:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:08:41 INFO - PROCESS | 1676 | RSS: Main: 283082752 23:08:41 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 23:08:43 INFO - PROCESS | 1676 | RSS: Main: 284164096 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | __start_tp_report 23:08:43 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 23:08:43 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 23:08:43 INFO - PROCESS | 1676 | |i|pagename|runs| 23:08:43 INFO - PROCESS | 1676 | |0;ai-astar;106;117;103;109;117;115;115;119;118;97 23:08:43 INFO - PROCESS | 1676 | |1;audio-beat-detection;147;109;129;157;108;129;129;110;159;110 23:08:43 INFO - PROCESS | 1676 | |2;audio-dft;193;198;200;242;273;203;196;196;196;190 23:08:43 INFO - PROCESS | 1676 | |3;audio-fft;71;111;70;158;82;82;82;60;129;58 23:08:43 INFO - PROCESS | 1676 | |4;audio-oscillator;70;77;116;96;103;108;147;96;72;113 23:08:43 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;111;86;89;115;135;84;138;130;114;87 23:08:43 INFO - PROCESS | 1676 | |6;imaging-darkroom;94;95;93;109;155;121;95;94;96;95 23:08:43 INFO - PROCESS | 1676 | |7;imaging-desaturate;87;126;90;92;91;89;91;93;93;91 23:08:43 INFO - PROCESS | 1676 | |8;json-parse-financial;58;57;67;59;55;57;56;57;55;56 23:08:43 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;68;69;69;76;74;93;63;84;100;100 23:08:43 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;87;86;83;75;73;84;104;110;109;104 23:08:43 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;157;160;151;165;133;121;152;155;159;133 23:08:43 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;165;170;166;168;171;167;176;225;197;192 23:08:43 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;68;69;66;68;67;68;67;68;66;66 23:08:43 INFO - PROCESS | 1676 | __end_tp_report 23:08:43 INFO - PROCESS | 1676 | __start_cc_report 23:08:43 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1040 23:08:43 INFO - PROCESS | 1676 | __end_cc_report 23:08:43 INFO - PROCESS | 1676 | __startTimestamp1470809323415__endTimestamp 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | ------- Summary: start ------- 23:08:43 INFO - PROCESS | 1676 | Number of tests: 14 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:111.60 Median:117.00 stddev:7.47 (6.4%) stddev-sans-first:7.64 23:08:43 INFO - PROCESS | 1676 | Values: 106.0 117.0 103.0 109.0 117.0 115.0 115.0 119.0 118.0 97.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:129.00 stddev:19.92 (15.4%) stddev-sans-first:19.99 23:08:43 INFO - PROCESS | 1676 | Values: 147.0 109.0 129.0 157.0 108.0 129.0 129.0 110.0 159.0 110.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:208.70 Median:199.00 stddev:26.97 (13.6%) stddev-sans-first:28.00 23:08:43 INFO - PROCESS | 1676 | Values: 193.0 198.0 200.0 242.0 273.0 203.0 196.0 196.0 196.0 190.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:90.30 Median:70.50 stddev:32.42 (46.0%) stddev-sans-first:33.63 23:08:43 INFO - PROCESS | 1676 | Values: 71.0 111.0 70.0 158.0 82.0 82.0 82.0 60.0 129.0 58.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:99.80 Median:71.00 stddev:23.46 (33.0%) stddev-sans-first:22.26 23:08:43 INFO - PROCESS | 1676 | Values: 70.0 77.0 116.0 96.0 103.0 108.0 147.0 96.0 72.0 113.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:111.00 stddev:21.24 (19.1%) stddev-sans-first:22.52 23:08:43 INFO - PROCESS | 1676 | Values: 111.0 86.0 89.0 115.0 135.0 84.0 138.0 130.0 114.0 87.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:104.70 Median:94.50 stddev:19.83 (21.0%) stddev-sans-first:20.65 23:08:43 INFO - PROCESS | 1676 | Values: 94.0 95.0 93.0 109.0 155.0 121.0 95.0 94.0 96.0 95.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:91.00 stddev:11.28 (12.4%) stddev-sans-first:11.66 23:08:43 INFO - PROCESS | 1676 | Values: 87.0 126.0 90.0 92.0 91.0 89.0 91.0 93.0 93.0 91.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.00 stddev:3.50 (6.1%) stddev-sans-first:3.71 23:08:43 INFO - PROCESS | 1676 | Values: 58.0 57.0 67.0 59.0 55.0 57.0 56.0 57.0 55.0 56.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:79.60 Median:71.50 stddev:13.79 (19.3%) stddev-sans-first:13.97 23:08:43 INFO - PROCESS | 1676 | Values: 68.0 69.0 69.0 76.0 74.0 93.0 63.0 84.0 100.0 100.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:79.00 stddev:13.96 (17.7%) stddev-sans-first:14.71 23:08:43 INFO - PROCESS | 1676 | Values: 87.0 86.0 83.0 75.0 73.0 84.0 104.0 110.0 109.0 104.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:156.00 stddev:14.47 (9.3%) stddev-sans-first:15.02 23:08:43 INFO - PROCESS | 1676 | Values: 157.0 160.0 151.0 165.0 133.0 121.0 152.0 155.0 159.0 133.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:173.50 stddev:19.40 (11.2%) stddev-sans-first:19.84 23:08:43 INFO - PROCESS | 1676 | Values: 165.0 170.0 166.0 168.0 171.0 167.0 176.0 225.0 197.0 192.0 23:08:43 INFO - PROCESS | 1676 | 23:08:43 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 23:08:43 INFO - PROCESS | 1676 | Values: 68.0 69.0 66.0 68.0 67.0 68.0 67.0 68.0 66.0 66.0 23:08:43 INFO - PROCESS | 1676 | -------- Summary: end -------- 23:08:43 INFO - PROCESS | 1676 | 23:08:44 INFO - TEST-INFO | 1676: exit 0 23:08:44 INFO - TEST-OK | kraken | took 60385ms 23:08:44 INFO - SUITE-END | took 1175s 23:08:44 INFO - Completed test suite (00:19:35) 23:08:44 INFO - javascript benchmark 23:08:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7279.286488913858, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3454.205532727471, 3459.944716861357, 3479.6346383629716, 3483.68646821786, 3505.929881402372, 5825.563082768788, 5903.970480147598, 5947.494462970647, 6022.849428764283, 6028.125921741344, 5911.7930872419365, 5918.171455996164, 5935.554833387491, 5998.370171132034, 6137.693115344237, 5281.947180528194, 5376.086065368877, 5393.541548968343, 5449.373322067973, 5476.315460567429, 5261.000409922121, 5306.071437498442, 5425.674459532441, 5439.646422982493, 5454.318210223722, 5281.366236051698, 5304.310439642841, 5333.386660534049, 5348.946510534884, 5426.565874730012, 4422.64618830496, 5376.220448035052, 5379.892402151974, 5382.165764306539, 5383.515483606455, 5233.9738301308635, 5238.895222095575, 5327.840164795062, 5338.572914166857, 5354.839354819362, 5579.6652200867675, 5584.357798853102, 5902.704864756788, 5950.226470558843, 5992.640441573476, 5389.110796718537, 6053.092036194562, 6239.844003899939, 6288.245410550718, 6314.86332460157, 6142.232220972379, 6195.101710252034, 6228.657423841732, 6302.747890084391, 6307.18006659136, 5898.941010589882, 6003.3996600339615, 6011.819645410645, 6063.54523410746, 6072.119542666289, 6351.587146835441, 6378.904316435257, 6381.617102973836, 6425.0, 6454.870902581922, 4883.536064073907, 6076.969615151896, 6141.170941922784, 6141.9078713818435, 6148.846278843064, 6178.567500275028, 6260.154879091265, 6271.874562508724, 6273.65494897785, 6288.654124023131, 6197.628142311476, 6225.533085018551, 6228.065790131516, 6229.657368844757, 6264.874702505925, 6116.031445915052, 6197.876042479215, 6226.346233645492, 6248.156498872594, 6252.124702541648, 4978.626603004716, 5017.473165317662, 5039.118154322979, 5051.166557517969, 5072.822451214263, 4497.6851620386915, 4509.143262780061, 4536.432945881765, 4541.545845415432, 4578.175928332932, 5877.500412464903, 5913.9112913306335, 6026.156338112668, 6041.607295525777, 6072.301685306246, 6215.595986260879, 6366.586171898812, 6371.745130194833, 6374.872502549923, 6382.712777924911, 6063.696815159313, 6123.418275263843, 6128.877422451526, 6142.9864072428445, 6148.661823599745, 5474.562035037187, 5527.53015993645, 5619.381867994517, 5625.409332020161, 5668.319801623831, 5704.372519022904, 6548.672566371758, 6551.246606640107, 6553.803385898431, 6571.310012448624, 6300.21247344082, 6323.019931910561, 6330.082138089859, 6343.873122537616, 6437.903431448438, 6291.685415729286, 6417.454516366161, 6505.0, 6551.377619126175, 6575.4410875076155, 6201.10084037821, 6231.781887633911, 6279.748810047547, 6281.026440901667, 6284.874302514016, 6311.116443697794, 6318.273398559225, 6322.304546499973, 6328.46208072319, 6343.921533339436, 6084.361142080018, 6130.061839217998, 6168.599041062316, 6237.844053898689, 6256.843578910563, 6145.907811382922, 6207.224096987877, 6227.221597300338, 6250.937490625218, 6254.2807577129215, 5945.791897283574, 6163.1679723238585, 6166.599171054047, 6171.228596425447, 6207.565470417027, 6114.297368146176, 6166.321704612579, 6223.937760622336, 6243.344448832807, 6264.060390941395, 6126.877462450637, 6131.472831077545, 6148.047052706837, 6161.537884658579, 6198.876022479435], "value": 5819.183777608382, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.840662581348, 521.2155705662974, 530.0221092085103, 563.906955352367, 570.2131059138388, 38511.61488385115, 41323.17353652929, 41435.0, 41460.58539414605, 41537.16925661485, 42343.78828105863, 42500.149997000044, 42847.57152428475, 42849.57150428495, 42897.78551107248, 9484.0, 9673.70978870635, 9678.758031049245, 9685.709428717115, 9691.127798498133, 9621.95189024054, 9695.36980096295, 9720.708378748648, 9725.95137024314, 9728.90271097287, 9611.423314601147, 9694.272929530278, 9730.708078757649, 9730.951345243264, 9737.902620973771, 8262.297704695047, 9660.758481037996, 9729.075737804897, 9736.902630973671, 9767.853482197772, 8259.174082591693, 9520.809898762654, 9665.758356041155, 9673.854892176552, 9673.90326096737, 40228.39657405141, 40365.79817100925, 40518.79740601278, 40568.768717720515, 40642.79678601588, 3421.2473255883665, 3471.0, 3486.9651303486894, 3507.57909050913, 3579.6957258633047, 31907.361852762813, 32444.0, 32802.17994550155, 32837.83581082079, 33266.6673333266, 3229.8869539566003, 3232.046546268856, 3261.0705948804475, 3343.548620936167, 3343.732501399882, 478518.0, 478947.0, 481053.0, 481565.9999999965, 484565.0, 33583.83208083944, 58354.7082264586, 58479.70760146172, 58574.41425585775, 58611.0, 34193.65806341905, 34938.0, 35152.82423587866, 35225.82387088048, 35428.0, 14941.252937353089, 15062.096274223582, 15097.0, 15102.54692359231, 15119.395224191128, 12646.177998430072, 12693.619191424272, 12814.9359253205, 12826.67933301675, 12856.164349317265, 7821.256980586835, 7829.64766585505, 7842.0, 7849.607519623996, 7896.289333959971, 2844.1751891951517, 3219.613646362405, 3242.5622540957143, 3244.853981570835, 3281.753868459827, 985.6599473181741, 1045.5713157605346, 1060.7772367802847, 1083.1984331594563, 1087.228068071678, 26322.868385658334, 30953.690463095532, 31090.68909310923, 31108.22229444282, 31165.532517012263, 877.4647465046316, 997.6558087459815, 1020.811149937264, 1078.5685725709623, 1170.4908364861312, 15740.763888541682, 16053.43812966564, 16112.0, 16144.838551614568, 16332.265048072864, 708467.0, 710066.0, 713813.0, 716425.0, 718590.0, 59744.40255597388, 59783.70108149431, 59794.804103917675, 60223.0, 60407.395926041056, 1357.7148798752373, 1376.800363947242, 1397.9231142287272, 1399.3772771116958, 1400.0619584878157, 29810.552841707824, 30165.396692066475, 30600.387992239586, 30755.384892302478, 30851.382972340878, 51167.2324915134, 51232.0, 51515.48484515257, 51810.96378072493, 51885.0, 615244.0, 619429.0, 620171.0, 621431.0, 623111.0, 26308.8684556576, 26831.731682682923, 26872.0, 26989.730102698722, 27095.0, 45910.77044614756, 46048.309275360225, 48422.0, 48717.75641121772, 50009.24986125138, 35410.46884296789, 37173.256534869695, 39232.01919952024, 40290.99272518211, 40299.79850100731, 39434.0, 40276.39585406163, 40618.390724139754, 40771.38842917418, 42057.57942420659, 38210.0447488815, 39161.0, 40072.59927400688, 40182.0, 40207.0, 29839.0, 31737.0, 33624.0, 33993.47029383634, 36849.0, 23972.640410394208, 24124.91437885264, 24220.864512020577, 24393.02427902864, 26333.34166645849], "value": 18934.785673848346, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.9474589367164, 375.59623404839806, 383.9462475253464, 388.8366885907918, 393.77554793767564, 19845.0, 20832.85419301938, 21479.785202147992, 21577.784222157774, 21711.131554737793, 20198.29305974291, 20691.896540517275, 20696.0, 20780.89609551954, 21038.789612103876, 6013.428724271187, 6075.270967483908, 6113.358097399776, 6139.907901381483, 6145.938540614581, 4227.38702888081, 6057.727402266886, 6120.663363515005, 6126.17296664949, 6537.313582073885, 6729.8317542061595, 6883.174019117688, 6883.8967415488805, 6890.44876409886, 6895.3794158525725, 5681.801136960207, 5913.615614985012, 5950.55370847186, 5993.101034844808, 6876.621785801779, 5192.675867653762, 5778.219940308047, 5845.327787304472, 5871.089981052944, 5921.200637913869, 13407.061505694604, 13603.591892243248, 13613.0, 23210.88394558033, 24507.529548227165, 2149.871007739541, 2156.9568608627897, 2157.0207643096714, 2292.56441276157, 2294.5755035318352, 9916.057974492413, 9993.0, 10359.482025898675, 10413.739656508573, 15695.293711782997, 1881.8118188181209, 1885.415521188436, 1958.9412317630495, 1975.9407217783346, 1987.3441764217773, 115184.0, 128505.0, 129451.35274323568, 129824.35087824595, 130213.34893325473, 11253.099752019818, 11470.910263525118, 11559.942200288944, 11587.188896777145, 11761.647150585497, 14530.418783248762, 14635.487757928635, 14668.55994320172, 14690.338934747962, 14702.485413010494, 8327.625256863455, 8620.310375169971, 8621.741347759576, 8737.257333126629, 8766.474011559327, 8531.74404767858, 8560.315174786003, 8567.957160214284, 8686.044535101133, 8741.213290803858, 4466.977665111654, 4502.5047244803045, 4541.977290113528, 4592.7933243004145, 4642.744649044335, 1454.4763885001391, 1661.4351120619046, 1821.653885761701, 1894.7536820213559, 1974.516243520318, 819.9918000819915, 887.6183241206214, 889.4663202078701, 1007.6221416968637, 1038.4963292802984, 21218.893905530374, 21236.25673101434, 21324.89337553302, 21327.36017919465, 21329.933503324773, 831.8419500294924, 842.9799942070041, 957.2724729205854, 964.6928412001777, 987.5901500877055, 10600.363978161335, 11097.945695158947, 11279.32324060559, 11306.038986686224, 11356.716082098013, 577137.0, 581084.0, 587925.0, 588995.0, 590310.0, 89491.10508894958, 124695.0, 124863.75136248703, 125775.0, 128817.0, 963.6386355116831, 967.8403063494585, 1004.4324956399611, 1007.9193664506822, 1016.3139880580578, 14823.629409264855, 18623.0, 18741.625167496848, 18750.437486875417, 18922.52693682669, 37072.25855482929, 41245.793771030956, 52840.20739688985, 54399.64000899851, 54568.18147727867, 64961.67519162374, 68374.0, 78755.0, 79634.0, 80113.1988680129, 7103.822404439724, 7172.964135179291, 7315.670794814139, 7336.7432139874845, 7488.737894173678, 9008.729738107868, 11405.714857128638, 11566.363849988331, 11798.233114847508, 12642.620721378373, 10071.244656650633, 10606.045455909005, 10862.94568527184, 10935.507902144263, 11062.889371106508, 10988.560457581927, 11019.559217631206, 11040.944795275973, 11173.273737207055, 11371.43142842838, 8579.613917373857, 11379.886201137882, 11466.197366184288, 11467.541298347973, 11542.884571154516, 6951.9304806953305, 7190.784276471714, 7317.26827317264, 7486.401087913063, 8292.792680183044, 9177.495237761987, 9576.425414474876, 10340.53467593945, 10850.511726972463, 11196.38419886914], "value": 9405.934605009286, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.4888863342144, 1513.1601960911694, 1720.9397671081515, 1851.2224865556466, 1877.9906100469495, 6692.765753198627, 8258.710945116914, 8419.368547358941, 8739.825203495935, 8845.027047024822, 8467.322614190865, 8598.1401859814, 8647.740567782961, 8661.870071948926, 8698.91301086989, 2285.0859656137554, 2322.280093171114, 2322.5703244899746, 2326.8371214015024, 2334.6614740862565, 2299.1952816514254, 2324.6861673674093, 2351.7883390494853, 2355.1403737635724, 2355.187460326185, 2316.5598536278053, 2329.87185704787, 2344.3787396339985, 2350.5533948549805, 2356.22244659262, 2287.176616418088, 2311.17953126641, 2312.9190478333176, 2327.290176496168, 2340.648902664597, 2324.8140148788057, 2327.1389585853344, 2329.3710698111586, 2329.7320808107115, 2329.7553756855455, 8617.956910215471, 8637.136286371313, 8687.565621718888, 8729.345299102593, 8732.43239189457, 5116.2069879168785, 5197.1424714922, 5265.420803711589, 5279.102552566073, 5289.603279753996, 3421.538092357525, 3468.843902024415, 3538.663826936437, 3551.0, 3616.9095772605633, 1024.86665530091, 1043.608646757466, 1060.9522571484224, 1067.4929408530963, 1074.607768164622, 10636.521356538975, 11287.37919414432, 11351.82972255425, 11394.202405831513, 11413.657590272305, 4318.568143185719, 4472.262076757299, 4637.118947399983, 4742.0, 4745.454272758608, 9187.908120918839, 9692.41845489273, 9798.314118011813, 9806.558704858156, 9808.950955245178, 5213.582913366921, 5223.608229382811, 5238.5547228486, 5269.947300527022, 5285.445028272053, 4326.264535029052, 4657.534246575316, 4795.472498025214, 4827.879303017382, 4846.684965477303, 3430.5368775215024, 3879.3792993120965, 3904.2972264992572, 3936.291467535871, 4023.6781057515327, 1267.0560432477862, 1280.065552146938, 1280.4558062823262, 1280.4558062823448, 1290.1291628150962, 831.7089018843357, 912.2884150362728, 921.4701546610592, 933.2254228990058, 970.7912798748303, 5413.566914646819, 5431.511163995259, 5805.535557155417, 5864.677442740605, 5963.522918166536, 422.7252286014115, 434.4742861137995, 441.12436812926313, 444.0563801920918, 444.2115245439371, 3893.844246230176, 3896.649301562873, 3979.3633018716864, 4003.279409706281, 4015.417764424142, 10195.643152489776, 10651.360918344924, 10664.626738064131, 10674.466276686135, 10678.985496377833, 10594.576216951236, 10603.151747860153, 10641.627543035956, 10645.627403040857, 10673.89326106729, 8057.6776928923355, 8152.551609661407, 8158.551279679556, 8162.428629995843, 8167.591620418955, 5499.697516636464, 5511.401693508998, 5708.257926469699, 5714.0, 5766.99077661416, 9790.06994335537, 9843.114119729258, 9889.456079915673, 9913.256505762241, 9937.453440060866, 9697.0, 9956.900430995887, 10024.949875250577, 10109.747256318651, 10140.847887281845, 6401.87196256063, 6926.619035953071, 7186.640667966685, 7303.488755787044, 7501.962490187514, 8212.425130240827, 8354.039285482248, 8386.538740369338, 8392.4544904581, 8403.747887563382, 7371.447141464519, 7426.628668566658, 7470.215627359266, 7489.737859174903, 7593.544387336778, 7310.52481588695, 7339.376153027052, 7381.8523629526035, 7459.552426854406, 7500.099988001475, 6835.58986460814, 6850.314968503109, 6854.485913556404, 6905.723771049102, 6915.446764258948, 4706.223473126895, 4897.975510122426, 4957.801687932442, 4973.303737476684, 5074.11203039474, 4359.738415695068, 4908.214685650279, 4969.105560999054, 5046.899062018812, 5060.721660308718], "value": 4440.655519026762, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.394255431691, 307.1859572133845, 313.2997750028686, 317.896683577837, 320.55763046995145, 5107.23391491276, 5211.5309622134, 5362.544183744377, 5507.339119305689, 5809.315298563422, 5183.0, 5536.861578460541, 5631.859203519914, 5662.688552129642, 5676.716164191785, 4275.636570891478, 4291.141771645668, 4298.505671847738, 4327.6754243431715, 4342.326939324394, 3742.195427982983, 4074.9185016299657, 4160.54234034256, 4269.786510674454, 4274.781687219143, 3822.012553472193, 3915.6671682906986, 3917.6669983051474, 4246.216589032599, 4268.954106243975, 3531.6468353164605, 4209.5579964103945, 4268.637165840907, 4269.690989483144, 4281.785910704452, 3426.5202871597994, 3555.7510974231814, 3580.2839432113683, 3613.5969580229116, 3666.9083272918124, 4980.850574482772, 5305.673581604598, 5524.171374293888, 5546.528545073674, 5563.36021357541, 4281.636060934793, 4818.566329030404, 4829.855104346875, 4836.927446088312, 4837.685550439211, 4834.540718631724, 5668.461496157858, 5706.629069110536, 5948.553858460633, 6001.849953751147, 3496.947545786815, 3792.8052663411213, 3975.840966361342, 3984.123492831601, 3991.5409727881374, 5176.611754118494, 5258.421573626898, 6111.449969502722, 6207.503399728011, 6208.472279856218, 3893.53277606689, 3935.2719746846924, 3991.042149884007, 3999.24014437256, 4002.3596224604507, 3713.9814300928324, 3838.846446142179, 4054.128362402039, 4057.756534607933, 4064.227796718614, 4793.616510679138, 4804.0872234275375, 4806.404541547595, 4809.711417314972, 4816.373871396766, 5672.830469604837, 5931.3178984417345, 5979.222701048836, 5980.0, 5992.880142397127, 1205.8492688413949, 1313.7438199550995, 1380.1994842991242, 1390.4716207840956, 1392.1647011792843, 893.620211410148, 906.954652267384, 914.0904799724184, 932.2774849491562, 934.5093825741404, 4658.277966915133, 4974.806046548802, 5203.617648528411, 5617.269754931833, 5662.886742265132, 4892.608591312686, 5201.5838732901275, 6142.078688196806, 6189.616997456012, 6191.226096737908, 5070.8337082471235, 5097.770600322994, 5484.61607687458, 5569.610127291132, 5621.522170615462, 2699.5140874642752, 2738.986305068462, 2740.438210166911, 2747.582127727587, 2770.362816552204, 4749.667523273338, 4774.809007639725, 5327.0411325961695, 5350.104654241326, 5351.03681337363, 4654.138984287918, 4798.442453136324, 4883.658143929891, 4928.802847886044, 5134.845954621367, 4680.297955306732, 4785.401824771903, 5294.338207724035, 5355.357357117015, 5376.623636345496, 4248.279446824041, 5124.74376281177, 5124.8718782029255, 5182.378114626269, 5234.450382709913, 4028.6575641070835, 4883.926741098958, 5869.383714710065, 6150.415710507495, 6181.567290289817, 5129.615278854176, 5812.76748930055, 6359.936400635935, 6499.760230179081, 6523.760485507738, 3444.810535420576, 3978.9853587335047, 4033.032072302685, 4038.8990275243355, 4053.500204924197, 5808.30300363959, 5855.707214639166, 6154.292256390572, 6155.691615644134, 6174.289956655042, 5590.273264475592, 6037.9094313584455, 6061.181550579002, 6071.817845464643, 6081.695915204311, 5528.474794894479, 5569.526590239713, 5744.5117165039765, 5903.085021821625, 6099.603525770811, 5638.379778224474, 5768.50967667753, 5831.329397119385, 5885.764569417175, 5949.196858424058, 5505.807296744595, 5993.280806303272, 6033.456988871023, 6036.84907877289, 6041.553170965766, 5174.403789366354, 5631.577631677723, 5881.441263080001, 5901.4688678017465, 5945.702714864153], "value": 4189.153109678544, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.355791324502, 716.9641517924102, 742.3689863615924, 778.2178910195253, 827.5737994932609, 15454.459093931715, 15776.290066947, 15821.683566328666, 16078.0, 16248.75626865595, 14576.271186440688, 14598.562043138723, 14643.0, 14721.043132196426, 14768.0, 678.3115138134797, 856.6573370651743, 869.256785448442, 870.0168809245544, 871.9825603487926, 838.3795990966671, 863.6286396849353, 875.8905136857893, 875.9430637008605, 876.9210771030606, 919.793046564521, 920.1166879795371, 923.810618823143, 924.3298608508811, 926.1803304075873, 901.9909800901972, 910.8178364327161, 913.8400779863497, 915.0849150849151, 916.7066538707617, 654.8592052708691, 666.0009985022466, 685.8388278754488, 686.993130068698, 687.8074139241021, 14714.852851471454, 14957.626059348495, 15031.924840375837, 15041.774373384407, 15121.395144194219, 11885.762284754257, 13061.0, 14107.858921410758, 15060.192776866737, 15226.771598426032, 11936.28382297065, 11983.340916249603, 12144.57493987715, 12236.510539578407, 12449.875501244962, 3877.7091718121255, 3899.395593682955, 3910.4720862683744, 3970.8411663533425, 4089.9182016359805, 126450.36774816067, 126753.0, 127121.72878271191, 127142.36428817797, 127983.72016279811, 11185.0, 11216.327020378803, 11277.830832537518, 11485.712857178638, 11639.47622356996, 23139.652905206436, 23259.76740232576, 23297.0, 23305.95123219459, 23338.533229335317, 8154.510729356258, 8283.155088223777, 8287.668493260202, 8348.499090054616, 8494.787630309293, 4483.349914262414, 4513.932291015637, 4522.25382811839, 4530.750808705487, 4698.788554515055, 3615.5119058927235, 3710.6474884885893, 3723.832427540767, 3736.5702944161226, 3754.962450375516, 667.2926697700453, 667.4526887951832, 677.8847595908708, 694.3785312145601, 696.095076400677, 490.43844797706424, 518.6680524464346, 617.5059952038441, 663.9103720997604, 675.189772273274, 8821.500344941376, 8919.01959323544, 9481.573329200202, 9591.520423978913, 9633.470159141174, 514.5831876180306, 517.9300794392784, 538.1900240138566, 614.3518587889819, 660.8546119853625, 4253.574642535722, 4319.200947824663, 4324.762138082436, 4366.934495982563, 4393.802278897458, 97264.51367743115, 101581.0, 129299.70700293065, 155472.0, 155743.0, 133749.0, 134638.65361346456, 136005.0, 137111.62888371188, 138415.0, 81674.0, 82868.0, 85445.0, 111677.44161279305, 112143.0, 6561.573497722633, 8430.40987130895, 8541.743747687578, 8680.739577812676, 8712.78218044554, 59332.0, 64790.35209648032, 65152.0, 65567.0, 66582.0, 94152.0, 99227.0, 99884.0, 101139.0, 101969.0, 9423.858642120236, 10257.87163412009, 10996.23026388145, 11104.65202939356, 11312.321260724382, 11503.367314797766, 12044.614869290144, 12295.32375719344, 12653.620391388273, 12668.0, 10415.4820689313, 10475.0, 10615.808139679137, 10719.088877445505, 11035.668929932115, 12153.331566763913, 13313.457540420235, 13793.10344827583, 14001.369938352595, 14567.854321457075, 11428.485718142538, 11681.123915706186, 13615.455381785014, 14332.641683957985, 14380.28098595087, 11149.275297105987, 11469.197156198987, 11514.251573647685, 11529.711757206136, 12088.697782555506, 11561.42192890369, 11586.989696685625, 11594.536218551164, 11647.367086467983, 11820.468078936641], "value": 7715.989340804853, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1571.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 103.0, 109.0, 117.0, 115.0, 115.0, 119.0, 118.0, 97.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 129.0, 157.0, 108.0, 129.0, 129.0, 110.0, 159.0, 110.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 198.0, 200.0, 242.0, 273.0, 203.0, 196.0, 196.0, 196.0, 190.0], "value": 208.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 111.0, 70.0, 158.0, 82.0, 82.0, 82.0, 60.0, 129.0, 58.0], "value": 90.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 77.0, 116.0, 96.0, 103.0, 108.0, 147.0, 96.0, 72.0, 113.0], "value": 99.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 86.0, 89.0, 115.0, 135.0, 84.0, 138.0, 130.0, 114.0, 87.0], "value": 108.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 93.0, 109.0, 155.0, 121.0, 95.0, 94.0, 96.0, 95.0], "value": 104.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 126.0, 90.0, 92.0, 91.0, 89.0, 91.0, 93.0, 93.0, 91.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 67.0, 59.0, 55.0, 57.0, 56.0, 57.0, 55.0, 56.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 76.0, 74.0, 93.0, 63.0, 84.0, 100.0, 100.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 83.0, 75.0, 73.0, 84.0, 104.0, 110.0, 109.0, 104.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 160.0, 151.0, 165.0, 133.0, 121.0, 152.0, 155.0, 159.0, 133.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 170.0, 166.0, 168.0, 171.0, 167.0, 176.0, 225.0, 197.0, 192.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 66.0, 68.0, 67.0, 68.0, 67.0, 68.0, 66.0, 66.0], "value": 67.3, "unit": "score"}]}]} 23:08:44 INFO - Return code: 0 23:08:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:08:44 ERROR - # TBPL SUCCESS # 23:08:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:08:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:08:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:08:44 INFO - 2016-08-09 23:08:44.711 screenresolution[1681:10223] starting screenresolution argv=screenresolution get 23:08:44 INFO - 2016-08-09 23:08:44.714 screenresolution[1681:10223] Display 0: 1600x1200x32@60 23:08:44 INFO - 2016-08-09 23:08:44.719 screenresolution[1682:10226] starting screenresolution argv=screenresolution list 23:08:44 INFO - Available Modes on Display 0 23:08:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:08:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:08:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:08:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:08:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:08:44 INFO - Intel Iris: 23:08:44 INFO - Chipset Model: Intel Iris 23:08:44 INFO - Type: GPU 23:08:44 INFO - Bus: Built-In 23:08:44 INFO - VRAM (Dynamic, Max): 1536 MB 23:08:44 INFO - Vendor: Intel (0x8086) 23:08:44 INFO - Device ID: 0x0a2e 23:08:44 INFO - Revision ID: 0x0009 23:08:44 INFO - Displays: 23:08:44 INFO - Display: 23:08:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:08:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:08:44 INFO - Display Serial Number: 1600x1200 60 23:08:44 INFO - Main Display: Yes 23:08:44 INFO - Mirror: Off 23:08:44 INFO - Online: Yes 23:08:44 INFO - Rotation: Supported 23:08:44 INFO - Return code: 0 23:08:44 INFO - Running post-action listener: _resource_record_post_action 23:08:44 INFO - [mozharness: 2016-08-10 06:08:44.953483Z] Finished run-tests step (success) 23:08:44 INFO - Running post-run listener: _resource_record_post_run 23:08:45 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 36529152; Write bytes: 702425600; Read time: 237; Write time: 1935 23:08:45 INFO - TinderboxPrint: CPU usage
54.3% 23:08:45 INFO - TinderboxPrint: I/O read bytes / time
36,529,152 / 237 23:08:45 INFO - TinderboxPrint: I/O write bytes / time
702,425,600 / 1,935 23:08:45 INFO - TinderboxPrint: CPU idle
2,210.8 (45.7%) 23:08:45 INFO - TinderboxPrint: CPU system
978.2 (20.2%) 23:08:45 INFO - TinderboxPrint: CPU user
1,652.7 (34.1%) 23:08:45 INFO - TinderboxPrint: Swap in / out
439,742,464 / 0 23:08:45 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 382317056; Write bytes: 376542720; Read time: 28302; Write time: 772 23:08:45 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 35566592; Write bytes: 296363008; Read time: 224; Write time: 1096 23:08:45 INFO - Running post-run listener: _upload_blobber_files 23:08:45 INFO - Blob upload gear active. 23:08:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:08:45 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 23:08:45 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:08:45 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:08:45 INFO - (blobuploader) - INFO - Open directory for files ... 23:08:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:08:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:08:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:46 INFO - (blobuploader) - INFO - Done attempting. 23:08:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:08:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:08:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:47 INFO - (blobuploader) - INFO - Done attempting. 23:08:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:08:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:08:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:50 INFO - (blobuploader) - INFO - Done attempting. 23:08:50 INFO - (blobuploader) - INFO - Iteration through files over. 23:08:50 INFO - Return code: 0 23:08:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:08:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:08:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/157dba19a00e16c37fb0d4207fda34a59f9edae5276a61220cd8298f3dbf1166d904d10087612e3acc9aa89ad81bec4fd1e3a6a20adc4f91e9b32689858f92f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/495982e5c36120336ac7619c34f5d3a82a0a29a468dbe83f9200e40e14ae7c6ac5f32ed582d14254da0ff2642dabb0faf3266d60fd973f4cd0fce03948a72c5c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0311ef33ebb2b3bdae89f318ba9ef44177be9afc60c646bd1a8e49d0b46bc2ee4aacf65195839632808f4befe63017cd8b4f70a11abf699b311a1f7d1e3baf10"} 23:08:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:08:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:08:50 INFO - Contents: 23:08:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/157dba19a00e16c37fb0d4207fda34a59f9edae5276a61220cd8298f3dbf1166d904d10087612e3acc9aa89ad81bec4fd1e3a6a20adc4f91e9b32689858f92f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/495982e5c36120336ac7619c34f5d3a82a0a29a468dbe83f9200e40e14ae7c6ac5f32ed582d14254da0ff2642dabb0faf3266d60fd973f4cd0fce03948a72c5c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0311ef33ebb2b3bdae89f318ba9ef44177be9afc60c646bd1a8e49d0b46bc2ee4aacf65195839632808f4befe63017cd8b4f70a11abf699b311a1f7d1e3baf10"} 23:08:50 INFO - Running post-run listener: copy_logs_to_upload_dir 23:08:50 INFO - Copying logs to upload dir... 23:08:50 INFO - mkdir: /builds/slave/test/build/upload/logs 23:08:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.590287 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-09 23:08:50.322470) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 23:08:50.326012) ========= 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.okdtNLomZx/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.ro3yM2BMe0/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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/Fx-Team/sha512/157dba19a00e16c37fb0d4207fda34a59f9edae5276a61220cd8298f3dbf1166d904d10087612e3acc9aa89ad81bec4fd1e3a6a20adc4f91e9b32689858f92f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/495982e5c36120336ac7619c34f5d3a82a0a29a468dbe83f9200e40e14ae7c6ac5f32ed582d14254da0ff2642dabb0faf3266d60fd973f4cd0fce03948a72c5c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0311ef33ebb2b3bdae89f318ba9ef44177be9afc60c646bd1a8e49d0b46bc2ee4aacf65195839632808f4befe63017cd8b4f70a11abf699b311a1f7d1e3baf10"} build_url:https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010991 build_url: 'https://queue.taskcluster.net/v1/task/SlmYjjiuQ1Olz8F89VT4Qg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/157dba19a00e16c37fb0d4207fda34a59f9edae5276a61220cd8298f3dbf1166d904d10087612e3acc9aa89ad81bec4fd1e3a6a20adc4f91e9b32689858f92f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/495982e5c36120336ac7619c34f5d3a82a0a29a468dbe83f9200e40e14ae7c6ac5f32ed582d14254da0ff2642dabb0faf3266d60fd973f4cd0fce03948a72c5c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0311ef33ebb2b3bdae89f318ba9ef44177be9afc60c646bd1a8e49d0b46bc2ee4aacf65195839632808f4befe63017cd8b4f70a11abf699b311a1f7d1e3baf10"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 23:08:50.360643) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 23:08:50.361183) ========= 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.okdtNLomZx/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.ro3yM2BMe0/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.005163 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 23:08:50.410573) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 23:08:50.410894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 23:08:50.411214) ========= ========= Total master_lag: 0.49 =========