builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0179 starttime: 1470756603.49 results: success (0) buildid: 20160809064320 builduid: 4d73ae11f4f44869bd5fe66d6e0c21c8 revision: 643a67968688ec383af3d008ef081a6939a0cb53 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:03.488610) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:03.489030) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:03.489317) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:03.515771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:03.516121) ========= 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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 218M=0s 2016-08-09 08:30:03 (218 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130823 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:03.676016) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:03.676339) ========= 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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.029370 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:03.725426) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:03.725765) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 643a67968688ec383af3d008ef081a6939a0cb53 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 643a67968688ec383af3d008ef081a6939a0cb53 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:03,804 truncating revision to first 12 chars 2016-08-09 08:30:03,804 Setting DEBUG logging. 2016-08-09 08:30:03,804 attempt 1/10 2016-08-09 08:30:03,804 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/643a67968688?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 08:30:04,516 unpacking tar archive at: mozilla-central-643a67968688/testing/mozharness/ program finished with exit code 0 elapsedTime=0.972855 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:04.723794) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:04.727144) ========= script_repo_revision: 643a67968688ec383af3d008ef081a6939a0cb53 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:04.727543) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:04.727808) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 08:30:04.746100) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-09 08:30:04.746466) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:04 INFO - MultiFileLogger online at 20160809 08:30:04 in /builds/slave/test 08:30:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:30:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:30:04 INFO - {'append_to_log': False, 08:30:04 INFO - 'base_work_dir': '/builds/slave/test', 08:30:04 INFO - 'blob_upload_branch': 'Firefox', 08:30:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:30:04 INFO - 'branch': 'Firefox', 08:30:04 INFO - 'buildbot_json_path': 'buildprops.json', 08:30:04 INFO - 'config_files': ('talos/mac_config.py',), 08:30:04 INFO - 'default_actions': ('clobber', 08:30:04 INFO - 'read-buildbot-config', 08:30:04 INFO - 'download-and-extract', 08:30:04 INFO - 'populate-webroot', 08:30:04 INFO - 'create-virtualenv', 08:30:04 INFO - 'install', 08:30:04 INFO - 'run-tests'), 08:30:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:30:04 INFO - 'download_minidump_stackwalk': True, 08:30:04 INFO - 'download_symbols': 'ondemand', 08:30:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:30:04 INFO - 'tooltool.py': '/tools/tooltool.py', 08:30:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:30:04 INFO - '/tools/misc-python/virtualenv.py')}, 08:30:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:30:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:30:04 INFO - 'installer_path': 'installer.exe', 08:30:04 INFO - 'log_level': 'info', 08:30:04 INFO - 'log_name': 'talos', 08:30:04 INFO - 'log_to_console': True, 08:30:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:30:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:30:04 INFO - 'opt_config_files': (), 08:30:04 INFO - 'pip_index': False, 08:30:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:04 INFO - 'cmd': ('bash', 08:30:04 INFO - '-c', 08:30:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:04 INFO - 'enabled': True, 08:30:04 INFO - 'halt_on_failure': False, 08:30:04 INFO - 'name': 'check_screen_resolution'},), 08:30:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:30:04 INFO - 'cmd': ('bash', 08:30:04 INFO - '-c', 08:30:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:30:04 INFO - 'enabled': True, 08:30:04 INFO - 'halt_on_failure': False, 08:30:04 INFO - 'name': 'check_screen_resolution'},), 08:30:04 INFO - 'run_cmd_checks_enabled': True, 08:30:04 INFO - 'sps_profile': False, 08:30:04 INFO - 'sps_profile_interval': 0, 08:30:04 INFO - 'suite': 'dromaeojs', 08:30:04 INFO - 'system_bits': '32', 08:30:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:30:04 INFO - 'title': 't-yosemite-r7-0179', 08:30:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:30:04 INFO - 'use_talos_json': True, 08:30:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:30:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:30:04 INFO - 'work_dir': 'build'} 08:30:04 INFO - [mozharness: 2016-08-09 15:30:04.941570Z] Running clobber step. 08:30:04 INFO - Running pre-action listener: _resource_record_pre_action 08:30:04 INFO - Running main action method: clobber 08:30:04 INFO - rmtree: /builds/slave/test/build 08:30:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:30:06 INFO - Running post-action listener: _resource_record_post_action 08:30:06 INFO - [mozharness: 2016-08-09 15:30:06.838070Z] Finished clobber step (success) 08:30:06 INFO - [mozharness: 2016-08-09 15:30:06.838240Z] Running read-buildbot-config step. 08:30:06 INFO - Running pre-action listener: _resource_record_pre_action 08:30:06 INFO - Running main action method: read_buildbot_config 08:30:06 INFO - Using buildbot properties: 08:30:06 INFO - { 08:30:06 INFO - "project": "", 08:30:06 INFO - "product": "firefox", 08:30:06 INFO - "script_repo_revision": "production", 08:30:06 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 08:30:06 INFO - "repository": "", 08:30:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 08:30:06 INFO - "buildid": "20160809064320", 08:30:06 INFO - "pgo_build": "False", 08:30:06 INFO - "basedir": "/builds/slave/test", 08:30:06 INFO - "buildnumber": 8, 08:30:06 INFO - "slavename": "t-yosemite-r7-0179", 08:30:06 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:30:06 INFO - "platform": "yosemite_r7", 08:30:06 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:30:06 INFO - "branch": "mozilla-central", 08:30:06 INFO - "repo_path": "mozilla-central", 08:30:06 INFO - "revision": "643a67968688ec383af3d008ef081a6939a0cb53", 08:30:06 INFO - "stage_platform": "macosx64", 08:30:06 INFO - "builduid": "4d73ae11f4f44869bd5fe66d6e0c21c8", 08:30:06 INFO - "slavebuilddir": "test" 08:30:06 INFO - } 08:30:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:30:06 INFO - Running post-action listener: _resource_record_post_action 08:30:06 INFO - [mozharness: 2016-08-09 15:30:06.842449Z] Finished read-buildbot-config step (success) 08:30:06 INFO - [mozharness: 2016-08-09 15:30:06.842570Z] Running download-and-extract step. 08:30:06 INFO - Running pre-action listener: _resource_record_pre_action 08:30:06 INFO - Running main action method: download_and_extract 08:30:06 INFO - mkdir: /builds/slave/test/build/tests 08:30: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')]} 08:30:06 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:30:06 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/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 08:30:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/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 08:30:07 INFO - Downloaded 1199 bytes. 08:30:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:30:07 INFO - Using the following test package requirements: 08:30:07 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:30:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:30:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:30:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:30:07 INFO - u'jsshell-mac.zip'], 08:30:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:30:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:30:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:30:07 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:30:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:30:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:30:07 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:30:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:30:07 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')]} 08:30:07 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:30:07 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/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 08:30:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/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 08:30:08 INFO - Downloaded 16324531 bytes. 08:30:08 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'] 08:30:08 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 08:30:11 INFO - Return code: 0 08:30:11 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 08:30: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')]} 08:30:11 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:30:11 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/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 08:30:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/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 08:30:12 INFO - Downloaded 16324531 bytes. 08:30:12 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'] 08:30:12 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 08:30:15 INFO - Return code: 0 08:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:15 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:30:15 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/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 08:30:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/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 08:30:16 INFO - Downloaded 14413723 bytes. 08:30:16 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'] 08:30:16 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 08:30:17 INFO - Return code: 0 08:30:17 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')]} 08:30:17 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:30:17 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:30:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/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 08:30:20 INFO - Downloaded 111951282 bytes. 08:30:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:30:20 INFO - mkdir: /builds/slave/test/properties 08:30:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:30:20 INFO - Writing to file /builds/slave/test/properties/build_url 08:30:20 INFO - Contents: 08:30:20 INFO - build_url:https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:30:21 INFO - Running post-action listener: _resource_record_post_action 08:30:21 INFO - Running post-action listener: set_extra_try_arguments 08:30:21 INFO - [mozharness: 2016-08-09 15:30:21.142747Z] Finished download-and-extract step (success) 08:30:21 INFO - [mozharness: 2016-08-09 15:30:21.142868Z] Running populate-webroot step. 08:30:21 INFO - Running pre-action listener: _resource_record_pre_action 08:30:21 INFO - Running main action method: populate_webroot 08:30:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:30:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:30:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:30:21 INFO - '--tppagecycles', 08:30:21 INFO - '7'], 08:30:21 INFO - 'tests': ['tsvgm']}}, 08:30:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:30:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:30:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:30:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:30:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:21 INFO - 'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:21 INFO - 'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['damp', 'tps']}, 08:30:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:21 INFO - 'tests': ['damp', 'tps']}, 08:30:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['dromaeo_dom']}, 08:30:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['basic_compositor_video']}, 08:30:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:30:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['a11yr', 08:30:21 INFO - 'ts_paint', 08:30:21 INFO - 'tpaint', 08:30:21 INFO - 'sessionrestore', 08:30:21 INFO - 'sessionrestore_no_auto_restore', 08:30:21 INFO - 'tabpaint']}, 08:30:21 INFO - 'other-e10s': {'tests': ['a11yr', 08:30:21 INFO - 'ts_paint', 08:30:21 INFO - 'tpaint', 08:30:21 INFO - 'sessionrestore', 08:30:21 INFO - 'sessionrestore_no_auto_restore', 08:30:21 INFO - 'tabpaint']}, 08:30:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:30:21 INFO - 'ts_paint', 08:30:21 INFO - 'tpaint', 08:30:21 INFO - 'sessionrestore', 08:30:21 INFO - 'sessionrestore_no_auto_restore', 08:30:21 INFO - 'tabpaint']}, 08:30:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:30:21 INFO - 'ts_paint', 08:30:21 INFO - 'tpaint', 08:30:21 INFO - 'sessionrestore', 08:30:21 INFO - 'sessionrestore_no_auto_restore', 08:30:21 INFO - 'tabpaint']}, 08:30:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['a11yr', 08:30:21 INFO - 'ts_paint', 08:30:21 INFO - 'tpaint', 08:30:21 INFO - 'sessionrestore', 08:30:21 INFO - 'sessionrestore_no_auto_restore', 08:30:21 INFO - 'tabpaint']}, 08:30:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['a11yr', 08:30:21 INFO - 'ts_paint', 08:30:21 INFO - 'tpaint', 08:30:21 INFO - 'sessionrestore', 08:30:21 INFO - 'sessionrestore_no_auto_restore', 08:30:21 INFO - 'tabpaint']}, 08:30:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['tsvgx', 08:30:21 INFO - 'tsvgr_opacity', 08:30:21 INFO - 'tart', 08:30:21 INFO - 'tscrollx', 08:30:21 INFO - 'cart']}, 08:30:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:30:21 INFO - 'tsvgr_opacity', 08:30:21 INFO - 'tart', 08:30:21 INFO - 'tscrollx', 08:30:21 INFO - 'cart']}, 08:30:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:21 INFO - 'talos_options': ['--disable-e10s'], 08:30:21 INFO - 'tests': ['tp5o']}, 08:30:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:21 INFO - 'tests': ['tp5o']}, 08:30:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:21 INFO - 'talos_options': ['--disable-e10s', 08:30:21 INFO - '--xperf_path', 08:30:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:21 INFO - 'tests': ['tp5n']}, 08:30:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:21 INFO - 'talos_options': ['--xperf_path', 08:30:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:21 INFO - 'tests': ['tp5n']}}, 08:30:21 INFO - 'talos.zip': {'path': '', 08:30:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:30:21 INFO - Running post-action listener: _resource_record_post_action 08:30:21 INFO - [mozharness: 2016-08-09 15:30:21.161510Z] Finished populate-webroot step (success) 08:30:21 INFO - [mozharness: 2016-08-09 15:30:21.161647Z] Running create-virtualenv step. 08:30:21 INFO - Running pre-action listener: _resource_record_pre_action 08:30:21 INFO - Running main action method: create_virtualenv 08:30:21 INFO - Creating virtualenv /builds/slave/test/build/venv 08:30:21 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 08:30:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:30:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:21 INFO - Using real prefix '/tools/python27' 08:30:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:30:22 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:24 INFO - Installing pip.................done. 08:30:24 INFO - Return code: 0 08:30:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:24 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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:24 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 08:30:24 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 08:30:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:24 INFO - 'HOME': '/Users/cltbld', 08:30:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:24 INFO - 'LOGNAME': 'cltbld', 08:30:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:24 INFO - 'MOZ_NO_REMOTE': '1', 08:30:24 INFO - 'NO_EM_RESTART': '1', 08:30:24 INFO - 'PAGER': '/bin/cat', 08:30:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:24 INFO - 'PWD': '/builds/slave/test', 08:30:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:24 INFO - 'SHELL': '/bin/bash', 08:30:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:24 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:24 INFO - 'USER': 'cltbld', 08:30:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:24 INFO - 'XPC_FLAGS': '0x0', 08:30:24 INFO - 'XPC_SERVICE_NAME': '0', 08:30:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:25 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 08:30:25 INFO - Cleaning up... 08:30:25 INFO - Return code: 0 08:30:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:30:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:25 INFO - 'HOME': '/Users/cltbld', 08:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:25 INFO - 'LOGNAME': 'cltbld', 08:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:25 INFO - 'MOZ_NO_REMOTE': '1', 08:30:25 INFO - 'NO_EM_RESTART': '1', 08:30:25 INFO - 'PAGER': '/bin/cat', 08:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:25 INFO - 'PWD': '/builds/slave/test', 08:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:25 INFO - 'SHELL': '/bin/bash', 08:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:25 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:25 INFO - 'USER': 'cltbld', 08:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:25 INFO - 'XPC_FLAGS': '0x0', 08:30:25 INFO - 'XPC_SERVICE_NAME': '0', 08:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:25 INFO - Downloading/unpacking psutil>=3.1.1 08:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:28 INFO - Installing collected packages: psutil 08:30:28 INFO - Running setup.py install for psutil 08:30:28 INFO - building 'psutil._psutil_osx' extension 08:30:28 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 08:30:29 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 08:30:29 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 08:30:29 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 08:30:29 INFO - building 'psutil._psutil_posix' extension 08:30:29 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 08:30:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:30:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:30:29 INFO - ^ 08:30:29 INFO - 1 warning generated. 08:30:29 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 08:30:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:29 INFO - Successfully installed psutil 08:30:29 INFO - Cleaning up... 08:30:29 INFO - Return code: 0 08:30:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:29 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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:29 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 08:30:29 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 08:30:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:29 INFO - 'HOME': '/Users/cltbld', 08:30:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:29 INFO - 'LOGNAME': 'cltbld', 08:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:29 INFO - 'MOZ_NO_REMOTE': '1', 08:30:29 INFO - 'NO_EM_RESTART': '1', 08:30:29 INFO - 'PAGER': '/bin/cat', 08:30:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:29 INFO - 'PWD': '/builds/slave/test', 08:30:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:29 INFO - 'SHELL': '/bin/bash', 08:30:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:29 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:29 INFO - 'USER': 'cltbld', 08:30:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:29 INFO - 'XPC_FLAGS': '0x0', 08:30:29 INFO - 'XPC_SERVICE_NAME': '0', 08:30:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:30:30 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 08:30:30 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 08:30:30 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 08:30:30 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 08:30:30 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 08:30:30 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 08:30:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:30:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:30:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:30:32 INFO - Installing collected packages: mozsystemmonitor 08:30:32 INFO - Running setup.py install for mozsystemmonitor 08:30:32 INFO - Successfully installed mozsystemmonitor 08:30:32 INFO - Cleaning up... 08:30:32 INFO - Return code: 0 08:30:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:30:32 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')]} 08:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 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')]} 08:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:32 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 08:30:32 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 08:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:32 INFO - 'HOME': '/Users/cltbld', 08:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:32 INFO - 'LOGNAME': 'cltbld', 08:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:32 INFO - 'MOZ_NO_REMOTE': '1', 08:30:32 INFO - 'NO_EM_RESTART': '1', 08:30:32 INFO - 'PAGER': '/bin/cat', 08:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:32 INFO - 'PWD': '/builds/slave/test', 08:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:32 INFO - 'SHELL': '/bin/bash', 08:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:32 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:32 INFO - 'USER': 'cltbld', 08:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:32 INFO - 'XPC_FLAGS': '0x0', 08:30:32 INFO - 'XPC_SERVICE_NAME': '0', 08:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:33 INFO - Downloading/unpacking jsonschema==2.5.1 08:30:33 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 08:30:33 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 08:30:33 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 08:30:33 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 08:30:33 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 08:30:33 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 08:30:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:30:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:35 INFO - Downloading functools32-3.2.3-2.tar.gz 08:30:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:30:36 INFO - warning: no files found matching '*.txt' 08:30:36 INFO - no previously-included directories found matching 'build' 08:30:36 INFO - no previously-included directories found matching 'dist' 08:30:36 INFO - no previously-included directories found matching '.git*' 08:30:36 INFO - Installing collected packages: jsonschema, functools32 08:30:36 INFO - Running setup.py install for functools32 08:30:36 INFO - warning: no files found matching '*.txt' 08:30:36 INFO - no previously-included directories found matching 'build' 08:30:36 INFO - no previously-included directories found matching 'dist' 08:30:36 INFO - no previously-included directories found matching '.git*' 08:30:36 INFO - Successfully installed jsonschema functools32 08:30:36 INFO - Cleaning up... 08:30:36 INFO - Return code: 0 08:30:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:30:36 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')]} 08:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:36 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')]} 08:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:36 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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:36 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 08:30:36 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 08:30:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:36 INFO - 'HOME': '/Users/cltbld', 08:30:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:36 INFO - 'LOGNAME': 'cltbld', 08:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:36 INFO - 'MOZ_NO_REMOTE': '1', 08:30:36 INFO - 'NO_EM_RESTART': '1', 08:30:36 INFO - 'PAGER': '/bin/cat', 08:30:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:36 INFO - 'PWD': '/builds/slave/test', 08:30:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:36 INFO - 'SHELL': '/bin/bash', 08:30:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:36 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:36 INFO - 'USER': 'cltbld', 08:30:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:36 INFO - 'XPC_FLAGS': '0x0', 08:30:36 INFO - 'XPC_SERVICE_NAME': '0', 08:30:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:30:36 INFO - Cleaning up... 08:30:36 INFO - Return code: 0 08:30:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:30:36 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')]} 08:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:36 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')]} 08:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:36 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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:36 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 08:30:36 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 08:30:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:36 INFO - 'HOME': '/Users/cltbld', 08:30:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:36 INFO - 'LOGNAME': 'cltbld', 08:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:36 INFO - 'MOZ_NO_REMOTE': '1', 08:30:36 INFO - 'NO_EM_RESTART': '1', 08:30:36 INFO - 'PAGER': '/bin/cat', 08:30:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:36 INFO - 'PWD': '/builds/slave/test', 08:30:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:36 INFO - 'SHELL': '/bin/bash', 08:30:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:36 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:36 INFO - 'USER': 'cltbld', 08:30:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:36 INFO - 'XPC_FLAGS': '0x0', 08:30:36 INFO - 'XPC_SERVICE_NAME': '0', 08:30:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:36 INFO - Downloading/unpacking blobuploader==1.2.4 08:30:36 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 08:30:36 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 08:30:36 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 08:30:36 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 08:30:36 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 08:30:36 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 08:30:39 INFO - Downloading blobuploader-1.2.4.tar.gz 08:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:30:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:30:39 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 08:30:39 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 08:30:39 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 08:30:39 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 08:30:39 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 08:30:39 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 08:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:30:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:40 INFO - Downloading docopt-0.6.1.tar.gz 08:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:30:40 INFO - Installing collected packages: blobuploader, requests, docopt 08:30:40 INFO - Running setup.py install for blobuploader 08:30:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:30:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:30:40 INFO - Running setup.py install for requests 08:30:41 INFO - Running setup.py install for docopt 08:30:41 INFO - Successfully installed blobuploader requests docopt 08:30:41 INFO - Cleaning up... 08:30:41 INFO - Return code: 0 08:30:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:41 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')]} 08:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:41 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')]} 08:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:41 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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:41 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 08:30:41 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 08:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:41 INFO - 'HOME': '/Users/cltbld', 08:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:41 INFO - 'LOGNAME': 'cltbld', 08:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:41 INFO - 'MOZ_NO_REMOTE': '1', 08:30:41 INFO - 'NO_EM_RESTART': '1', 08:30:41 INFO - 'PAGER': '/bin/cat', 08:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:41 INFO - 'PWD': '/builds/slave/test', 08:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:41 INFO - 'SHELL': '/bin/bash', 08:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:41 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:41 INFO - 'USER': 'cltbld', 08:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:41 INFO - 'XPC_FLAGS': '0x0', 08:30:41 INFO - 'XPC_SERVICE_NAME': '0', 08:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:41 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-hjbmVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:41 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Y0F9yA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:41 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-44a8Gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-PONcfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-bYlpcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-M_04iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-sB4dpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-I3lsO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-CmZSMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-zLn8AM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-iP0rMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Iw0KuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:42 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-lVU8nT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:43 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-6oqQUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:43 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Kl5vZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:43 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-b3GS2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:43 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-j49Pq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:30:43 INFO - Running setup.py install for manifestparser 08:30:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:30:43 INFO - Running setup.py install for mozcrash 08:30:43 INFO - Running setup.py install for mozdebug 08:30:43 INFO - Running setup.py install for mozdevice 08:30:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:30:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:30:44 INFO - Running setup.py install for mozfile 08:30:44 INFO - Running setup.py install for mozhttpd 08:30:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:30:44 INFO - Running setup.py install for mozinfo 08:30:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:30:44 INFO - Running setup.py install for mozInstall 08:30:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:30:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:30:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:30:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:30:44 INFO - Running setup.py install for mozleak 08:30:44 INFO - Running setup.py install for mozlog 08:30:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:30:44 INFO - Running setup.py install for moznetwork 08:30:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:30:45 INFO - Running setup.py install for mozprocess 08:30:45 INFO - Running setup.py install for mozprofile 08:30:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:30:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:30:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:30:45 INFO - Running setup.py install for mozrunner 08:30:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:30:45 INFO - Running setup.py install for mozscreenshot 08:30:45 INFO - Running setup.py install for moztest 08:30:45 INFO - Running setup.py install for mozversion 08:30:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:30:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:30:46 INFO - Cleaning up... 08:30:46 INFO - Return code: 0 08:30:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:46 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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:46 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 08:30:46 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 08:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:46 INFO - 'HOME': '/Users/cltbld', 08:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:46 INFO - 'LOGNAME': 'cltbld', 08:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:46 INFO - 'MOZ_NO_REMOTE': '1', 08:30:46 INFO - 'NO_EM_RESTART': '1', 08:30:46 INFO - 'PAGER': '/bin/cat', 08:30:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:46 INFO - 'PWD': '/builds/slave/test', 08:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:46 INFO - 'SHELL': '/bin/bash', 08:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:46 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:46 INFO - 'USER': 'cltbld', 08:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:46 INFO - 'XPC_FLAGS': '0x0', 08:30:46 INFO - 'XPC_SERVICE_NAME': '0', 08:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:46 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-wUtd4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:46 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)) 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:46 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-lpgDQd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:46 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)) 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:46 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-1wOeam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:46 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)) 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:46 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-qf4dKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:46 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)) 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:46 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-w8dKCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:46 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)) 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:46 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-rFycyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:46 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)) 08:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:46 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-9WbCVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-K8eiLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-qLNf0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-aWsA1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-d9LJu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-rdErpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-K3gXPE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-JcLnfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-9ROED6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-HRA82g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:47 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)) 08:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:47 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-hgnGHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:48 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)) 08:30:48 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)) 08:30:48 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)) 08:30:48 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)) 08:30:48 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)) 08:30:48 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)) 08:30:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:30:48 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 08:30:48 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 08:30:48 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 08:30:48 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 08:30:48 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 08:30:48 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 08:30:50 INFO - Downloading blessings-1.6.tar.gz 08:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:30:50 INFO - Installing collected packages: blessings 08:30:50 INFO - Running setup.py install for blessings 08:30:50 INFO - Successfully installed blessings 08:30:50 INFO - Cleaning up... 08:30:50 INFO - Return code: 0 08:30:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:51 INFO - Reading from file tmpfile_stdout 08:30:51 INFO - Current package versions: 08:30:51 INFO - blessings == 1.6 08:30:51 INFO - blobuploader == 1.2.4 08:30:51 INFO - docopt == 0.6.1 08:30:51 INFO - functools32 == 3.2.3-2 08:30:51 INFO - jsonschema == 2.5.1 08:30:51 INFO - manifestparser == 1.1 08:30:51 INFO - mozInstall == 1.12 08:30:51 INFO - mozcrash == 0.17 08:30:51 INFO - mozdebug == 0.1 08:30:51 INFO - mozdevice == 0.48 08:30:51 INFO - mozfile == 1.2 08:30:51 INFO - mozhttpd == 0.7 08:30:51 INFO - mozinfo == 0.9 08:30:51 INFO - mozleak == 0.1 08:30:51 INFO - mozlog == 3.3 08:30:51 INFO - moznetwork == 0.27 08:30:51 INFO - mozprocess == 0.23 08:30:51 INFO - mozprofile == 0.28 08:30:51 INFO - mozrunner == 6.12 08:30:51 INFO - mozscreenshot == 0.1 08:30:51 INFO - mozsystemmonitor == 0.3 08:30:51 INFO - moztest == 0.7 08:30:51 INFO - mozversion == 1.4 08:30:51 INFO - psutil == 3.1.1 08:30:51 INFO - requests == 1.2.3 08:30:51 INFO - wsgiref == 0.1.2 08:30:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:51 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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:51 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 08:30:51 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 08:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:51 INFO - 'HOME': '/Users/cltbld', 08:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:51 INFO - 'LOGNAME': 'cltbld', 08:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:51 INFO - 'MOZ_NO_REMOTE': '1', 08:30:51 INFO - 'NO_EM_RESTART': '1', 08:30:51 INFO - 'PAGER': '/bin/cat', 08:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:51 INFO - 'PWD': '/builds/slave/test', 08:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:51 INFO - 'SHELL': '/bin/bash', 08:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:51 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:51 INFO - 'USER': 'cltbld', 08:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:51 INFO - 'XPC_FLAGS': '0x0', 08:30:51 INFO - 'XPC_SERVICE_NAME': '0', 08:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:51 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)) 08:30:51 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)) 08:30:51 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)) 08:30:51 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)) 08:30:51 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)) 08:30:51 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)) 08:30:51 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)) 08:30:51 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)) 08:30:51 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)) 08:30:51 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)) 08:30:51 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)) 08:30:51 INFO - Cleaning up... 08:30:51 INFO - Return code: 0 08:30:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:30: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')]} 08:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30: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')]} 08:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:51 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 0x10e50da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5a2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb018492640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, '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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 08:30:51 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 08:30:51 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 08:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:51 INFO - 'HOME': '/Users/cltbld', 08:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:51 INFO - 'LOGNAME': 'cltbld', 08:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:51 INFO - 'MOZ_NO_REMOTE': '1', 08:30:51 INFO - 'NO_EM_RESTART': '1', 08:30:51 INFO - 'PAGER': '/bin/cat', 08:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:51 INFO - 'PWD': '/builds/slave/test', 08:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:51 INFO - 'SHELL': '/bin/bash', 08:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:30:51 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:51 INFO - 'USER': 'cltbld', 08:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:51 INFO - 'XPC_FLAGS': '0x0', 08:30:51 INFO - 'XPC_SERVICE_NAME': '0', 08:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:30:51 INFO - Cleaning up... 08:30:51 INFO - Return code: 0 08:30:51 INFO - Running post-action listener: _resource_record_post_action 08:30:51 INFO - Running post-action listener: _start_resource_monitoring 08:30:51 INFO - Starting resource monitoring. 08:30:51 INFO - [mozharness: 2016-08-09 15:30:51.668731Z] Finished create-virtualenv step (success) 08:30:51 INFO - [mozharness: 2016-08-09 15:30:51.669702Z] Running install step. 08:30:51 INFO - Running pre-action listener: _resource_record_pre_action 08:30:51 INFO - Running main action method: install 08:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:51 INFO - Reading from file tmpfile_stdout 08:30:51 INFO - Detecting whether we're running mozinstall >=1.0... 08:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:30:52 INFO - Reading from file tmpfile_stdout 08:30:52 INFO - Output received: 08:30:52 INFO - Usage: mozinstall [options] installer 08:30:52 INFO - Options: 08:30:52 INFO - -h, --help show this help message and exit 08:30:52 INFO - -d DEST, --destination=DEST 08:30:52 INFO - Directory to install application into. [default: 08:30:52 INFO - "/builds/slave/test"] 08:30:52 INFO - --app=APP Application being installed. [default: firefox] 08:30:52 INFO - mkdir: /builds/slave/test/build/application 08:30:52 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'] 08:30:52 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 08:31:26 INFO - Reading from file tmpfile_stdout 08:31:26 INFO - Output received: 08:31:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:31:26 INFO - Running post-action listener: _resource_record_post_action 08:31:26 INFO - [mozharness: 2016-08-09 15:31:26.460274Z] Finished install step (success) 08:31:26 INFO - [mozharness: 2016-08-09 15:31:26.460399Z] Running run-tests step. 08:31:26 INFO - Running pre-action listener: _resource_record_pre_action 08:31:26 INFO - Running main action method: run_tests 08:31:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:31:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:31:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:31:26 INFO - 2016-08-09 08:31:26.474 screenresolution[1647:7782] starting screenresolution argv=screenresolution get 08:31:26 INFO - 2016-08-09 08:31:26.477 screenresolution[1647:7782] Display 0: 1600x1200x32@60 08:31:26 INFO - 2016-08-09 08:31:26.483 screenresolution[1648:7784] starting screenresolution argv=screenresolution list 08:31:26 INFO - Available Modes on Display 0 08:31:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:31:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:31:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:31:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:31:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:31:26 INFO - Intel Iris: 08:31:26 INFO - Chipset Model: Intel Iris 08:31:26 INFO - Type: GPU 08:31:26 INFO - Bus: Built-In 08:31:26 INFO - VRAM (Dynamic, Max): 1536 MB 08:31:26 INFO - Vendor: Intel (0x8086) 08:31:26 INFO - Device ID: 0x0a2e 08:31:26 INFO - Revision ID: 0x0009 08:31:26 INFO - Displays: 08:31:26 INFO - Display: 08:31:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:31:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:31:26 INFO - Display Serial Number: 1600x1200 60 08:31:26 INFO - Main Display: Yes 08:31:26 INFO - Mirror: Off 08:31:26 INFO - Online: Yes 08:31:26 INFO - Rotation: Supported 08:31:26 INFO - Return code: 0 08:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:31:26 INFO - Python 2.7.3 08:31:26 INFO - Return code: 0 08:31:26 INFO - grabbing minidump binary from tooltool 08:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:26 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e471d30>, '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 0x10e1d6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e469b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:31:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:31:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:31:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:31:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:31:26 INFO - Return code: 0 08:31:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:31:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:31:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:31:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:31:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0179 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WuU4eoXoKF/Render', 08:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:26 INFO - 'HOME': '/Users/cltbld', 08:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:26 INFO - 'LOGNAME': 'cltbld', 08:31:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:31:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:26 INFO - 'MOZ_NO_REMOTE': '1', 08:31:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:31:26 INFO - 'NO_EM_RESTART': '1', 08:31:26 INFO - 'PAGER': '/bin/cat', 08:31:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:26 INFO - 'PWD': '/builds/slave/test', 08:31:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:26 INFO - 'SHELL': '/bin/bash', 08:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jkt3HDi3sW/Listeners', 08:31:26 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 08:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:26 INFO - 'USER': 'cltbld', 08:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:26 INFO - 'XPC_FLAGS': '0x0', 08:31:26 INFO - 'XPC_SERVICE_NAME': '0', 08:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:31:27 INFO - mozversion application_buildid: 20160809064320 08:31:27 INFO - mozversion application_changeset: 643a67968688ec383af3d008ef081a6939a0cb53 08:31:27 INFO - mozversion application_display_name: Nightly 08:31:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:31:27 INFO - mozversion application_name: Firefox 08:31:27 INFO - mozversion application_remotingname: firefox 08:31:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:31:27 INFO - mozversion application_vendor: Mozilla 08:31:27 INFO - mozversion application_version: 51.0a1 08:31:27 INFO - mozversion platform_buildid: 20160809064320 08:31:27 INFO - mozversion platform_changeset: 643a67968688ec383af3d008ef081a6939a0cb53 08:31:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:31:27 INFO - mozversion platform_version: 51.0a1 08:31:27 INFO - using testdate: 1470756687 08:31:27 INFO - actual date: 1470756687 08:31:27 INFO - starting webserver on 'localhost:49279' 08:31:27 INFO - SUITE-START | Running 2 tests 08:31:27 INFO - TEST-START | dromaeo_css 08:31:27 INFO - operating with platform_type : mac_ 08:31:27 INFO - Initialising browser for dromaeo_css test... 08:31:27 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp_4bzRI/profile http://localhost:49279/getInfo.html) 08:31:29 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 08:31:29 INFO - PROCESS | 1658 | colorDepth:24 08:31:29 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 08:31:29 INFO - PROCESS | 1658 | __metrics 08:31:29 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:31:29 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:31:30 INFO - TEST-INFO | 1658: exit 0 08:31:30 INFO - Browser initialized. 08:31:30 INFO - Running cycle 1/1 for dromaeo_css test... 08:31:30 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp_4bzRI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:42 INFO - PROCESS | 1660 | RSS: Main: 243720192 08:31:42 INFO - PROCESS | 1660 | 08:32:34 INFO - PROCESS | 1660 | 1470756754245 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] 08:34:46 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 08:34:47 INFO - PROCESS | 1660 | RSS: Main: 364658688 08:34:47 INFO - PROCESS | 1660 | 08:37:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 08:37:51 INFO - PROCESS | 1660 | RSS: Main: 394661888 08:37:51 INFO - PROCESS | 1660 | 08:40:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 08:40:54 INFO - PROCESS | 1660 | RSS: Main: 378712064 08:40:54 INFO - PROCESS | 1660 | 08:43:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 08:43:57 INFO - PROCESS | 1660 | RSS: Main: 351870976 08:43:57 INFO - PROCESS | 1660 | 08:47:00 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 08:47:00 INFO - PROCESS | 1660 | RSS: Main: 345313280 08:47:00 INFO - PROCESS | 1660 | 08:50:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 08:50:04 INFO - PROCESS | 1660 | RSS: Main: 542384128 08:50:04 INFO - PROCESS | 1660 | 08:50:04 INFO - PROCESS | 1660 | __start_tp_report 08:50:04 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 08:50:04 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 08:50:04 INFO - PROCESS | 1660 | |i|pagename|runs| 08:50:04 INFO - PROCESS | 1660 | |0;dojo.html;2796.3009247688083;3250.4961730931705;3404.3361544498816;3411.283630437608;3478.2869511750077;5645.237892884459;5670.829875103743;5757.107648314507;5760.913586296209;5770.798022069233;5692.459216374448;5755.971220143904;5846;5846.649201047941;5898.823035308937;5200.765965531541;5218.295530103425;5231.2153177023565;5274.235235890794;5369.463053694619;5364.168553874156;5370.758315875795;5375.892482150354;5393.379761327439;5432.837014889559;5200.947990520122;5211.24436956643;5219.295395121667;5252.395974462909;5266.894662106736;5303.337082864642;5312.362516498007;5319.335083114611;5324.787008519655;5325.680459172462;5173.534381905646;5222.294990176353;5275.60432967529;5279.5512381447625;5299.019681358961;5286.524212820865;5805.564582656318;5858.912116318259;5883.352831188566;5923.289205295349;5615.410381909923;6219.844503887393;6281.497480201573;6289.654069026202;6303.0545418187185;6287.685615719196;6327.5570710050315;6336.303006669263;6338.429541341301;6353.936460635381;5822.563307751893;5853.326867410217;5919.496842768327;5979.581429299994;5992.67040312787;6310.463610593151;6391.360863913572;6398.200224971879;6400.327965563642;6423.197100362455;6269.686515674198;6280.120783090371;6281.780137695158;6282.2147231596055;6288.685565721696;5207.2449494823795;6191.752329906843;6216.875662486816;6232.781852635136;6267.623942563369;6270.874582508324;6281.434670879643;6303.495720342362;6306.873862522724;6314.336994615591;6153.507719382439;6279.277883043417;6285;6300.3699630037545;6304.306526282106;4167.458230430025;4954.40547134339;5003.349564556657;5029.5221953914315;5077.923831142536;3866.4007078902255;4496.64026877849;4505.166544189336;4510.075434535978;4547.454305483363;5116.874287656709;5743.167240750068;5747.08046712524;5798.652080875161;5810.535157187415;6176.69116544171;6184.969075154685;6216.471599914058;6282.874342513124;6288.371162883766;5810.012297909366;5815.76736930518;5817.418258174233;5823.4467725566;5873.676947767914;5466.043442397565;5467.125259958467;5481.698506582096;5481.75332110056;5482.698451585201;6160.630362178284;6223.657698826608;6257.342978987141;6370.58591191571;6378.075179099097;6214.1921550198185;6283.057541368831;6301.4328710416285;6305.180326557518;6315.084312774714;6375.107484952203;6388.169537960035;6396.136521569715;6400.871982560229;6418.550701450854;5103.821366252312;6129.633877547049;6186.752529898934;6281.434670879643;6312.5581209314905;6235.719392627433;6269.0909818075215;6285.717142728679;6299.212598425197;6308.148399965946;6157.32269450369;6162.322144564184;6184.6598437086395;6210.192674952227;6212.72042758086;6021.277446706424;6057.606255593372;6075.331713511422;6205.069239614094;6223.844403889939;4836.274558816206;5299.940011997693;5722.971385143048;6040.818775436744;6042.093685947143;6099.420555047263;6127.632342059313;6142.293636231891;6160.537959653134;6169.475594574512;5938.317093534126;5955.196048533393;5962.5528085394635;6011.248593925759;6038.849028774316 08:50:04 INFO - PROCESS | 1660 | |1;ext.html;466.7642840365615;477.14829030181136;488.38951310861444;513.4890783670248;538.8652836790802;31240.062798116036;41728.37407438887;42803.715888523315;42807;42895.35656965148;43959.78020109895;44386.778066109626;44468.55531444684;44502.55497445024;44526.55473445264;8812.030676625565;9675.661351852668;9849.803003939916;9855.507224638775;9871.950640246789;8852.51311177885;11880;12106.576269830557;12108.515659373614;12367.319797411184;12131;12194.268343899394;12205;12212.93893530531;12228.082893782957;8854.468731876044;11841.40792960357;12014.639560813146;12068.879311206863;12092;10010.249231307682;11624;11952.64142075739;12003.63989080329;12012;40723.59276407257;41043.973900652425;41130.38304425436;41213.587864121575;41214.38178427326;3498.9650103498893;3502.176988407722;3544.2025544252597;3644.927101457956;4035.1122752994293;32452.513212301834;33205.16987075318;33462.498062529085;33680.32639347224;33684.6631533684;3217.1421750029676;3266.7386609071455;3279.5900512435946;3282.8030318180745;3378.9155271118175;454468;454472.7276363597;455188.4481155212;455782;457862;60069.498262543784;60399.698001509714;60454;60609.78780424367;60913.69543152256;56158.71920640371;62238.68880655658;62835.68582157151;63119;63191;16534;16769.580760480843;16790.244439000275;16794.99230046201;16816.24326905292;11741.589044383576;12103.515859365703;12737.044721645914;12982.415791289415;13038.217706937425;16402.425915092914;16529.6694066118;16595.336186552402;16613.3354665812;16645.16774161287;7129.964350178216;7166.211716711157;7168.426525877917;7169.749058783022;7256.6734496947765;883.6597909804643;949.4350861237691;1006.8741407324085;1049.7218237167158;1163.3078318400537;30162.69837301599;30520.847395762878;30984.53523197154;31100.68899311023;31333.843330783657;1035.063267742694;1101.9063379612662;1121.3328069798456;1162.1806626328419;1169.128998895828;13354.532591359255;15298.005629634039;15804.288807003712;15813.209339532978;16333.26500307489;580341;721729;731942;732743;972090;61358.0796288056;61889.76220475565;61913.38086619166;62000;62102;1269.257484371653;1331.1813234860685;1384.702289007868;1404.529482623312;1423.1745587559192;31215.84392078025;32743.345133097682;32919.35403229877;33032.66967330296;33867;57708;58200.4179958195;58225.708871457064;58400.123998139214;58413.70793146007;606827;612349;612746;613561;613568;23897.522049558564;23919.521609567364;24403.145889893767;24507.264782056565;26655.7334426661;39511.60488395079;40351.59648403478;46900.29649555191;47490.76254618705;49006.75496622637;36807.63192368042;39421;39869.80065099772;40306.79846600748;40743.38884916788;35863.46204806878;37373.81313093417;39467.2106557873;40260.79869600751;40991.59008409997;38221.61778382181;38691.613083868804;38954;38956;39043.804780975915;33101.33797324088;33289.00132996014;33455.49816752702;34447.96656100321;35563.644363556035;21493.892530537247;22803.657945130504;23949;25661.615075773505;25898.35254118662 08:50:04 INFO - PROCESS | 1660 | |2;jquery.html;338.61506438665884;373.43051845934946;379.70762512865093;384.7076222071226;410.29634177385776;14188.858111418882;14696.338664760086;20108.597828043432;20336.983150842472;20685.896570517165;12034.03727701784;15362.231888405562;18949.147288372023;19513.51216219595;19648.90175549124;5286.603504737141;5498.780048798043;5500.50495455409;5503.504684578387;5516.310461192351;5387.730613469331;5390.299261096072;5928.851778705523;5963.791267305645;5985.401459854002;5921.911171332434;5922.526197904157;5926.229590153274;5933.910991335112;5957.6127551709;4527.2530032544755;5424.75230696938;5440.047991601454;5450.727463626803;5525.143602741561;5243.973780131113;5272.868178295574;5277.8416647500635;5278.261043453919;5283.022640811424;19258.903705481524;22062.558748825093;22216.333509994725;23896.296074108366;25889.74110258911;2043.3052762060822;2216.013873826148;2256.6050941085246;2483.869839223149;2643;9628.66299679508;10317.896821031769;10359.378437293712;12071.879281207164;15651.530454086396;1779.7953235377968;1828.8171182881738;1907.418237437581;1924.0572119661265;1934.216642259887;100316;114509;121328.18007729891;124529.75470245272;124877.63417743705;11400.429978501043;11573.305601663928;11725.003374713075;11827.467763950643;11965.760684786255;14724.190169540563;14741.189234591986;14757.704845903021;14772.704545909022;14774.704505910036;8337.499750014897;8561;8586.570671466401;8687.131286871263;8773;8530.189631984951;8569.657213711507;8571.699990500303;8715.869261961076;8774;4472.351509031172;4535.319702044654;4558.2251017327135;4630.305454181832;4640.257558790578;1876.4933467963574;1940.2530025940114;1953.091812307284;1985.7219989201524;2001.5796682696505;838.3293365307729;896.9910300896908;916.6425094213262;938.2400255792829;1001.2290536287019;20815.37553873356;21459.57080858374;21502.247421340177;21549.461263468223;21586.56826863454;931.0875342164717;943.7216021273741;958.166395236149;975.9268054895912;1008.4554340655964;10983.286086404358;11281.71795705114;11302.773944521063;11304.660860174208;11376.374299413612;308953;310752;310938.445307772;311522;621659;101982;107032;140049;141801;147128;955.1642312976146;980.1325826643472;997.9600815967425;1003.117256813999;1006.2352612015068;14263.786043209568;17444.127793610016;18876.33932812345;18941.052947352302;19054.333147986425;44853.55146448494;49312.50687493222;49361.25958110703;50323.99352013013;50573.49426505688;70440.94338584822;70760;70966.93549596657;71385.92921106292;72408.63795680988;7864.21357864209;7933.563653999086;8129.349652027942;8202.26179643835;8294.917050829656;9158.58786354629;12843.229406235656;12965.805512917124;12996.8050479241;13229.206247625174;11010.944945275543;11274.830877537008;11283.66149015531;11303.095752339628;11320.716982075184;8033.919660803317;10618.309809862334;11086.279391839505;11174.385408802595;11225.97932372181;11480.253783504046;11802.704932376415;12196.451159697657;12198.939005305272;12251.69370765738;7942.880856787268;8077.474964127313;8114.634841432267;8510.957445212734;8543.61553730096;8417.242448179693;11167;11193.72015699614;11311.377874216998;11468.369239691898 08:50:04 INFO - PROCESS | 1660 | |3;mootools.html;1381.1160857051495;1456.6067161866292;1519.278342787176;1715.3053013529518;1922.3271854850796;7498;7980.603394405985;7983.680652773896;7993;8003.879941800863;8181.100078991306;8454.619542120605;8476.152384761535;8571.528565928871;8601.311895048397;2327.8254130940163;2361.409647588103;2365.562370961369;2366.230974933145;2369.4431808524982;2311.537692461506;2355.9646605300936;2357.068957761683;2364.1607229433475;2368.5973384524586;2328.476092879097;2349.9882500587473;2353.1293421434093;2362.4566349739484;2367.917122900699;2281.6349384098467;2303.3205204464725;2315.8378913476063;2317.4090606895134;2334.171369163956;2284.0521183708743;2284.794368506842;2285.2915596165076;2286.8399212055165;2290.667853161299;8519.616617252239;8558.144185581392;8626.956865215696;8649.221570058662;8651.913480865174;5086.415062267813;5122.5133612307145;5286.577073834084;5295.443978382253;5301.575873930076;3570.6429357064344;3657.9817100914593;3658.579263384718;3659.7621154624862;3662.487251784752;1079.5843600213896;1088.88022317545;1111.77208672222;1114.8773634900153;1116.4138827115748;11173.72065698356;11245.55017799287;11257.493412796444;11268.943655281671;11380;4398.956010439854;4622.6301895848255;4830.154722923475;4835.057163853015;4846.055019271208;8930.642774289086;8999.550022498848;9003.459792412476;9023.187913087853;9042.367034307536;5195.532402083755;5204.34945631796;5205.219217117463;5209.114450543417;5213.087709650796;3999.340108882002;4351.9564804352185;4696.131215725101;4784.569388755029;4891.510848915151;3702.333579955634;3733.51464309638;3798.2213646202463;3811.19964807388;3836.9424458633143;941.5292353823088;1098.5111625326654;1103.7406209540752;1116.6873275482878;1117.9608713694981;907.2877790934085;912.9589168487435;922;944.8299306124964;980.4950450517989;5717.199592057115;5824.854378640568;5833.241678581757;5909.674967876722;6144.078388241799;426.6416210383293;430.35876543949917;439.7537379067732;444.9288113901825;445.8550970934459;3850.595687452777;4005.899852503711;4036.9596304037173;4040.22239206343;4066.79666016698;9797.902020979698;10068.798624027624;10540.262181647211;10544.525496352682;10569.048785609179;8845.51349675774;10179.389236645826;10344.586216551405;10451.268411211142;10519.684409467729;6683.899741503881;7670.884936725954;7818.648160832776;8017.115977745225;8086.474379165218;5790.710464476843;5873.4126587340925;5955.642661440327;6007.579469437273;6061.909071363845;9533.61865525399;9854.753631158992;9873.358231714916;9887.357321774349;9916.107550320507;7662.425318101276;7981.8802717958115;9773.85339219898;9922.702318930444;9953.552090156105;7219.819504512429;7360.595167265637;7366.7053317866685;7368.521046131984;7381.557106573623;8090.271875531231;8154.1030486647605;8162.959185204036;8166.918330816616;8171.1828817120195;6316.399942005502;7047.682854271673;7320.377767889789;7377.446691498267;7456.291652293024;7336.853262934818;7338.633068346669;7363.77908662741;7389.408847292097;7428.777136685908;6682.899756503559;6890.20762612306;6916.20463646687;6994.475414343843;7069.469789765685;4977.303177555217;4986.650934434555;5044.571211447068;5054.09026375256;5140.151874940593;4944.5549900507685;4946.530079642428;5049.84850454487;5086.3896332440345;5090.134677104975 08:50:04 INFO - PROCESS | 1660 | |4;prototype.html;298.89538661468487;299.29068108582663;312.9499280115181;317.7585035373116;318.25845779334156;4831.903361932759;5070.366204224472;5166.793328266875;5244.770972336756;5366.436524164965;4155.966144714361;4485.6635752318625;5179;5261.474811876043;5297.788088476466;3812.618738126193;4061.979690101545;4065.6380112662296;4082.4692789937267;4087.6321131098193;3349.6147942986504;3787.3561494545993;4046.988252936766;4066.003829061884;4077.408775727518;3572.945980935617;3900.5904380040115;3940.014996250937;3961.7028722845757;3966.6628336591425;3395.5246265522846;3911.3937339712247;3943.112799620091;3955.4462375267485;3981.840726370942;2800.6919238883706;3359.9496007559906;3407.5399821024093;3408.5057666638445;3410.36908171989;4181.351890456954;4232.78836058199;5035.571976382012;5044.881149917043;5101.489851014869;4494.752788596626;4518.661100417482;4519.050999290153;4533.161365147458;4548.840790572314;4529.682922195448;4582.633389328846;5223.406860907422;5403.325353949781;5551;3119.407312610597;3630.2739452109417;3778.6410291022307;3790.6967442604528;3791.715621328384;4908.926366104475;5659.886802263932;5757.453041961007;5759.5968282220265;5761.127633519081;3823.1397935464297;3837.9040523987123;3844.7308688392104;3847.5575308839284;3869.0267963338338;3035.4839677254918;3253.6746325367276;3912.7456715313983;3921.1961547882615;3921.8037299600733;3779.9210434261045;4248.745075295492;4544.500104988449;4625.421822272216;4627.732411118528;4613.469451013109;5572.972135139379;5578.191162281447;5594.944050559524;5620.353659329229;1301.576987479073;1344.3815844711346;1346.414309775251;1365.3855764905832;1374.216696482995;889.2796834563894;899.4153800030033;930.2372054914905;936.3866667332771;960.5735482808025;4416.072624748838;4881.242752609058;5011.749412529359;5150.253213284052;5180.222966554971;4629.791659375336;4899;4938.913439043405;5177.016366890279;5541.501264886179;4663.603593694574;4796.592289655418;4860.465348811628;5055.494450554915;5096.133657278272;2394.3894306951615;2440.3533063738123;2470.6047032475076;2477.7893879020125;2511.062057392855;4249.575042495788;4328.307470804656;4549.522300158502;4695.577398034194;4699.647526435463;4132.946098744801;4283.978580107079;4726.809403652521;4783.851875549843;4815.686980346266;4511.390962220124;4826.493218212107;5634.183043458757;5878.764849405976;5937.317208520989;4824.95175048245;5730.369659337553;5733.770649173986;5748.39641837601;5788.479036886701;4874.074740477562;5376.462353764592;5599.468050535193;5653.943460565506;5761.423857614205;4879.463259041574;5924.674142922181;5939.732712027882;5960.523158147251;6039.809195574571;3374.797512149279;3818.809059546956;3846.2307538491855;3863.6136386362264;3895.7467764595212;4827;4888.877778055435;5594.888102238014;5753.5109515689965;5818;5517.834464966058;5583.497485226349;5612.522935550361;5622.453825198103;5629.971850140723;5559.527440167631;5578.49793518569;5596.748146333343;5614.719264036864;5637.746301416364;5087.796488140433;5364.946350536601;5441.211024401518;5488.753006114654;5506.697131657797;4701.224297990793;5273.734303766992;5544.362398324244;5581.888362232814;5598.916016259678;5194.662346947436;5472;5473.124300111963;5483.287172667528;5511.594543391409 08:50:04 INFO - PROCESS | 1660 | |5;yui.html;722.9891551626725;734.6033142103264;774.0479210570998;785.1089013969143;879.3624622148943;13694.657633559154;14524.854751452469;14543.200123993176;14547.272636368165;14598.854011459909;13771.173729576207;13827.516036938709;13854.861451385483;13900.304984750772;13926.025178237502;686.739039165119;844.9112843151473;853.6926706385711;881.4799268431624;889.5552223888056;873.7946582553095;873.9169778870997;877.1228771228771;877.7630039889226;878.5914549734372;923.866039424283;925.5788616179656;926.3608110403834;928.0626567167176;930.330162283155;719.8884172953175;748.5471289869632;749.6439191384103;754.35125791819;869.3697069624471;680.9829754256183;684.852756657316;691.029104108728;692.5740669488229;695.2595485807599;14396.136231826124;14406;14428.567142985727;14439.061461005105;14442.783358249635;13613.115147515478;13727.931360343235;13796.724065518734;13864.445422183202;13936.51222207228;10897;12592.118551701384;12790.360481975864;12902.935485322607;12908.612741617766;4567.5889169974525;4600.332951722015;4615.192341340252;4777.163996300668;4783.449903261135;117343;119055;120553.79446205602;120967;121087.39456302662;10949.288296260718;10959.178061645409;11034.779304413867;11036.227464077598;11432.371219583003;21442.035108420157;21446;21450.463738406354;21465.67801482979;21470.677939830915;7572.962135189399;7752.57075166495;7835.412344074217;7854.6858125674335;8028.558429286329;4473.709208901411;4498.797554110073;4533.365328853963;4631.675782695179;4636.142313671981;3460.4463285874644;3483.425234836224;3602.8018458985007;3614.042278796121;3618.5838628558054;668.481926506961;669.0432681265837;699.174973531238;738.7488253993668;741.3513175971025;587.5417174603816;597.310106826616;660.904168895517;661.5567569728294;692.1521136607716;9392.29557783169;9600.183984361407;9691.70924872255;9807.607695692092;9826.65606703762;578.3089208395927;636.5925807483214;662.6256165266609;668.1347654787062;678.5657179405184;3997.8600748973645;4574.542545745466;4807.978860787141;4808.158572249912;4914.434839993375;92086;102607.48696256471;113874;138462;176597;96036.51981740186;100506.99493004977;101642.4917875406;114128.42935785434;116750;82696.17303827005;86171.27657446815;89195;98000.50999744955;100941.49529252453;9521.952390238004;9724.222062235145;9997.250206234707;10017.949910250401;10072.043155900177;48934;55742;58992.705036474545;60228.79542409039;60334.39665603463;56118.719406402706;60418.395816043034;84976.4210588951;92168;93270;13178.406971686489;13383.799243011557;13492.86507134916;13493.32533373347;13919.652008699864;14349.784753228501;14550.199739014455;14598.270086495846;14645.92677036608;14751.92624036916;13948.651283717989;14017.369218385402;14104.647383815487;14487.348069337117;14808.555743327717;14075.125084011894;14148;14265.64335891611;14387.568372948828;14535.927320363331;13581.728365432438;13685.521006764715;13972.650683732989;14222.786658199928;14421;11479.966802987772;11528.884711153116;11659.591914283299;11783.941080294544;11855.34795586251;9556.992539501905;10010.978043912175;11768.94115529417;11770.823437648613;11788.646340609796 08:50:04 INFO - PROCESS | 1660 | __end_tp_report 08:50:04 INFO - PROCESS | 1660 | __start_cc_report 08:50:04 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1161 08:50:04 INFO - PROCESS | 1660 | __end_cc_report 08:50:04 INFO - PROCESS | 1660 | __startTimestamp1470757804378__endTimestamp 08:50:04 INFO - PROCESS | 1660 | 08:50:04 INFO - PROCESS | 1660 | ------- Summary: start ------- 08:50:04 INFO - PROCESS | 1660 | Number of tests: 6 08:50:04 INFO - PROCESS | 1660 | 08:50:04 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5766.21 Median:5979.58 stddev:676.97 (11.3%) stddev-sans-first:637.69 08:50:04 INFO - PROCESS | 1660 | Values: 2796.3 3250.5 3404.3 3411.3 3478.3 5645.2 5670.8 5757.1 5760.9 5770.8 5692.5 5756.0 5846.0 5846.6 5898.8 5200.8 5218.3 5231.2 5274.2 5369.5 5364.2 5370.8 5375.9 5393.4 5432.8 5200.9 5211.2 5219.3 5252.4 5266.9 5303.3 5312.4 5319.3 5324.8 5325.7 5173.5 5222.3 5275.6 5279.6 5299.0 5286.5 5805.6 5858.9 5883.4 5923.3 5615.4 6219.8 6281.5 6289.7 6303.1 6287.7 6327.6 6336.3 6338.4 6353.9 5822.6 5853.3 5919.5 5979.6 5992.7 6310.5 6391.4 6398.2 6400.3 6423.2 6269.7 6280.1 6281.8 6282.2 6288.7 5207.2 6191.8 6216.9 6232.8 6267.6 6270.9 6281.4 6303.5 6306.9 6314.3 6153.5 6279.3 6285.0 6300.4 6304.3 4167.5 4954.4 5003.3 5029.5 5077.9 3866.4 4496.6 4505.2 4510.1 4547.5 5116.9 5743.2 5747.1 5798.7 5810.5 6176.7 6185.0 6216.5 6282.9 6288.4 5810.0 5815.8 5817.4 5823.4 5873.7 5466.0 5467.1 5481.7 5481.8 5482.7 6160.6 6223.7 6257.3 6370.6 6378.1 6214.2 6283.1 6301.4 6305.2 6315.1 6375.1 6388.2 6396.1 6400.9 6418.6 5103.8 6129.6 6186.8 6281.4 6312.6 6235.7 6269.1 6285.7 6299.2 6308.1 6157.3 6162.3 6184.7 6210.2 6212.7 6021.3 6057.6 6075.3 6205.1 6223.8 4836.3 5299.9 5723.0 6040.8 6042.1 6099.4 6127.6 6142.3 6160.5 6169.5 5938.3 5955.2 5962.6 6011.2 6038.8 08:50:04 INFO - PROCESS | 1660 | 08:50:04 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:73873.14 Median:19754.19 stddev:166640.14 (843.6%) stddev-sans-first:167016.46 08:50:04 INFO - PROCESS | 1660 | Values: 466.8 477.1 488.4 513.5 538.9 31240.1 41728.4 42803.7 42807.0 42895.4 43959.8 44386.8 44468.6 44502.6 44526.6 8812.0 9675.7 9849.8 9855.5 9872.0 8852.5 11880.0 12106.6 12108.5 12367.3 12131.0 12194.3 12205.0 12212.9 12228.1 8854.5 11841.4 12014.6 12068.9 12092.0 10010.2 11624.0 11952.6 12003.6 12012.0 40723.6 41044.0 41130.4 41213.6 41214.4 3499.0 3502.2 3544.2 3644.9 4035.1 32452.5 33205.2 33462.5 33680.3 33684.7 3217.1 3266.7 3279.6 3282.8 3378.9 454468.0 454472.7 455188.4 455782.0 457862.0 60069.5 60399.7 60454.0 60609.8 60913.7 56158.7 62238.7 62835.7 63119.0 63191.0 16534.0 16769.6 16790.2 16795.0 16816.2 11741.6 12103.5 12737.0 12982.4 13038.2 16402.4 16529.7 16595.3 16613.3 16645.2 7130.0 7166.2 7168.4 7169.7 7256.7 883.7 949.4 1006.9 1049.7 1163.3 30162.7 30520.8 30984.5 31100.7 31333.8 1035.1 1101.9 1121.3 1162.2 1169.1 13354.5 15298.0 15804.3 15813.2 16333.3 580341.0 721729.0 731942.0 732743.0 972090.0 61358.1 61889.8 61913.4 62000.0 62102.0 1269.3 1331.2 1384.7 1404.5 1423.2 31215.8 32743.3 32919.4 33032.7 33867.0 57708.0 58200.4 58225.7 58400.1 58413.7 606827.0 612349.0 612746.0 613561.0 613568.0 23897.5 23919.5 24403.1 24507.3 26655.7 39511.6 40351.6 46900.3 47490.8 49006.8 36807.6 39421.0 39869.8 40306.8 40743.4 35863.5 37373.8 39467.2 40260.8 40991.6 38221.6 38691.6 38954.0 38956.0 39043.8 33101.3 33289.0 33455.5 34448.0 35563.6 21493.9 22803.7 23949.0 25661.6 25898.4 08:50:04 INFO - PROCESS | 1660 | 08:50:04 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:28208.76 Median:13076.45 stddev:68375.06 (522.9%) stddev-sans-first:68534.85 08:50:04 INFO - PROCESS | 1660 | Values: 338.6 373.4 379.7 384.7 410.3 14188.9 14696.3 20108.6 20337.0 20685.9 12034.0 15362.2 18949.1 19513.5 19648.9 5286.6 5498.8 5500.5 5503.5 5516.3 5387.7 5390.3 5928.9 5963.8 5985.4 5921.9 5922.5 5926.2 5933.9 5957.6 4527.3 5424.8 5440.0 5450.7 5525.1 5244.0 5272.9 5277.8 5278.3 5283.0 19258.9 22062.6 22216.3 23896.3 25889.7 2043.3 2216.0 2256.6 2483.9 2643.0 9628.7 10317.9 10359.4 12071.9 15651.5 1779.8 1828.8 1907.4 1924.1 1934.2 100316.0 114509.0 121328.2 124529.8 124877.6 11400.4 11573.3 11725.0 11827.5 11965.8 14724.2 14741.2 14757.7 14772.7 14774.7 8337.5 8561.0 8586.6 8687.1 8773.0 8530.2 8569.7 8571.7 8715.9 8774.0 4472.4 4535.3 4558.2 4630.3 4640.3 1876.5 1940.3 1953.1 1985.7 2001.6 838.3 897.0 916.6 938.2 1001.2 20815.4 21459.6 21502.2 21549.5 21586.6 931.1 943.7 958.2 975.9 1008.5 10983.3 11281.7 11302.8 11304.7 11376.4 308953.0 310752.0 310938.4 311522.0 621659.0 101982.0 107032.0 140049.0 141801.0 147128.0 955.2 980.1 998.0 1003.1 1006.2 14263.8 17444.1 18876.3 18941.1 19054.3 44853.6 49312.5 49361.3 50324.0 50573.5 70440.9 70760.0 70966.9 71385.9 72408.6 7864.2 7933.6 8129.3 8202.3 8294.9 9158.6 12843.2 12965.8 12996.8 13229.2 11010.9 11274.8 11283.7 11303.1 11320.7 8033.9 10618.3 11086.3 11174.4 11226.0 11480.3 11802.7 12196.5 12198.9 12251.7 7942.9 8077.5 8114.6 8511.0 8543.6 8417.2 11167.0 11193.7 11311.4 11468.4 08:50:04 INFO - PROCESS | 1660 | 08:50:04 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5521.58 Median:5088.27 stddev:3045.95 (59.9%) stddev-sans-first:3038.60 08:50:04 INFO - PROCESS | 1660 | Values: 1381.1 1456.6 1519.3 1715.3 1922.3 7498.0 7980.6 7983.7 7993.0 8003.9 8181.1 8454.6 8476.2 8571.5 8601.3 2327.8 2361.4 2365.6 2366.2 2369.4 2311.5 2356.0 2357.1 2364.2 2368.6 2328.5 2350.0 2353.1 2362.5 2367.9 2281.6 2303.3 2315.8 2317.4 2334.2 2284.1 2284.8 2285.3 2286.8 2290.7 8519.6 8558.1 8627.0 8649.2 8651.9 5086.4 5122.5 5286.6 5295.4 5301.6 3570.6 3658.0 3658.6 3659.8 3662.5 1079.6 1088.9 1111.8 1114.9 1116.4 11173.7 11245.6 11257.5 11268.9 11380.0 4399.0 4622.6 4830.2 4835.1 4846.1 8930.6 8999.6 9003.5 9023.2 9042.4 5195.5 5204.3 5205.2 5209.1 5213.1 3999.3 4352.0 4696.1 4784.6 4891.5 3702.3 3733.5 3798.2 3811.2 3836.9 941.5 1098.5 1103.7 1116.7 1118.0 907.3 913.0 922.0 944.8 980.5 5717.2 5824.9 5833.2 5909.7 6144.1 426.6 430.4 439.8 444.9 445.9 3850.6 4005.9 4037.0 4040.2 4066.8 9797.9 10068.8 10540.3 10544.5 10569.0 8845.5 10179.4 10344.6 10451.3 10519.7 6683.9 7670.9 7818.6 8017.1 8086.5 5790.7 5873.4 5955.6 6007.6 6061.9 9533.6 9854.8 9873.4 9887.4 9916.1 7662.4 7981.9 9773.9 9922.7 9953.6 7219.8 7360.6 7366.7 7368.5 7381.6 8090.3 8154.1 8163.0 8166.9 8171.2 6316.4 7047.7 7320.4 7377.4 7456.3 7336.9 7338.6 7363.8 7389.4 7428.8 6682.9 6890.2 6916.2 6994.5 7069.5 4977.3 4986.7 5044.6 5054.1 5140.2 4944.6 4946.5 5049.8 5086.4 5090.1 08:50:04 INFO - PROCESS | 1660 | 08:50:04 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4364.90 Median:4790.22 stddev:1352.84 (28.2%) stddev-sans-first:1321.77 08:50:04 INFO - PROCESS | 1660 | Values: 298.9 299.3 312.9 317.8 318.3 4831.9 5070.4 5166.8 5244.8 5366.4 4156.0 4485.7 5179.0 5261.5 5297.8 3812.6 4062.0 4065.6 4082.5 4087.6 3349.6 3787.4 4047.0 4066.0 4077.4 3572.9 3900.6 3940.0 3961.7 3966.7 3395.5 3911.4 3943.1 3955.4 3981.8 2800.7 3359.9 3407.5 3408.5 3410.4 4181.4 4232.8 5035.6 5044.9 5101.5 4494.8 4518.7 4519.1 4533.2 4548.8 4529.7 4582.6 5223.4 5403.3 5551.0 3119.4 3630.3 3778.6 3790.7 3791.7 4908.9 5659.9 5757.5 5759.6 5761.1 3823.1 3837.9 3844.7 3847.6 3869.0 3035.5 3253.7 3912.7 3921.2 3921.8 3779.9 4248.7 4544.5 4625.4 4627.7 4613.5 5573.0 5578.2 5594.9 5620.4 1301.6 1344.4 1346.4 1365.4 1374.2 889.3 899.4 930.2 936.4 960.6 4416.1 4881.2 5011.7 5150.3 5180.2 4629.8 4899.0 4938.9 5177.0 5541.5 4663.6 4796.6 4860.5 5055.5 5096.1 2394.4 2440.4 2470.6 2477.8 2511.1 4249.6 4328.3 4549.5 4695.6 4699.6 4132.9 4284.0 4726.8 4783.9 4815.7 4511.4 4826.5 5634.2 5878.8 5937.3 4825.0 5730.4 5733.8 5748.4 5788.5 4874.1 5376.5 5599.5 5653.9 5761.4 4879.5 5924.7 5939.7 5960.5 6039.8 3374.8 3818.8 3846.2 3863.6 3895.7 4827.0 4888.9 5594.9 5753.5 5818.0 5517.8 5583.5 5612.5 5622.5 5630.0 5559.5 5578.5 5596.7 5614.7 5637.7 5087.8 5364.9 5441.2 5488.8 5506.7 4701.2 5273.7 5544.4 5581.9 5598.9 5194.7 5472.0 5473.1 5483.3 5511.6 08:50:04 INFO - PROCESS | 1660 | 08:50:04 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:22839.58 Median:4235.78 stddev:35186.69 (830.7%) stddev-sans-first:35246.21 08:50:04 INFO - PROCESS | 1660 | Values: 723.0 734.6 774.0 785.1 879.4 13694.7 14524.9 14543.2 14547.3 14598.9 13771.2 13827.5 13854.9 13900.3 13926.0 686.7 844.9 853.7 881.5 889.6 873.8 873.9 877.1 877.8 878.6 923.9 925.6 926.4 928.1 930.3 719.9 748.5 749.6 754.4 869.4 681.0 684.9 691.0 692.6 695.3 14396.1 14406.0 14428.6 14439.1 14442.8 13613.1 13727.9 13796.7 13864.4 13936.5 10897.0 12592.1 12790.4 12902.9 12908.6 4567.6 4600.3 4615.2 4777.2 4783.4 117343.0 119055.0 120553.8 120967.0 121087.4 10949.3 10959.2 11034.8 11036.2 11432.4 21442.0 21446.0 21450.5 21465.7 21470.7 7573.0 7752.6 7835.4 7854.7 8028.6 4473.7 4498.8 4533.4 4631.7 4636.1 3460.4 3483.4 3602.8 3614.0 3618.6 668.5 669.0 699.2 738.7 741.4 587.5 597.3 660.9 661.6 692.2 9392.3 9600.2 9691.7 9807.6 9826.7 578.3 636.6 662.6 668.1 678.6 3997.9 4574.5 4808.0 4808.2 4914.4 92086.0 102607.5 113874.0 138462.0 176597.0 96036.5 100507.0 101642.5 114128.4 116750.0 82696.2 86171.3 89195.0 98000.5 100941.5 9522.0 9724.2 9997.3 10017.9 10072.0 48934.0 55742.0 58992.7 60228.8 60334.4 56118.7 60418.4 84976.4 92168.0 93270.0 13178.4 13383.8 13492.9 13493.3 13919.7 14349.8 14550.2 14598.3 14645.9 14751.9 13948.7 14017.4 14104.6 14487.3 14808.6 14075.1 14148.0 14265.6 14387.6 14535.9 13581.7 13685.5 13972.7 14222.8 14421.0 11480.0 11528.9 11659.6 11783.9 11855.3 9557.0 10011.0 11768.9 11770.8 11788.6 08:50:04 INFO - PROCESS | 1660 | -------- Summary: end -------- 08:50:04 INFO - PROCESS | 1660 | 08:50:04 INFO - TEST-INFO | 1660: exit 0 08:50:05 INFO - TEST-OK | dromaeo_css | took 1117953ms 08:50:05 INFO - TEST-START | kraken 08:50:05 INFO - operating with platform_type : mac_ 08:50:05 INFO - Initialising browser for kraken test... 08:50:05 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpoabTN5/profile http://localhost:49279/getInfo.html) 08:50:07 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 08:50:07 INFO - PROCESS | 1670 | colorDepth:24 08:50:07 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 08:50:07 INFO - PROCESS | 1670 | __metrics 08:50:07 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:50:07 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:50:07 INFO - TEST-INFO | 1670: exit 0 08:50:07 INFO - Browser initialized. 08:50:07 INFO - Running cycle 1/1 for kraken test... 08:50:07 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmpoabTN5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:19 INFO - PROCESS | 1671 | RSS: Main: 243548160 08:50:19 INFO - PROCESS | 1671 | 08:50:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 08:50:23 INFO - PROCESS | 1671 | RSS: Main: 294739968 08:50:23 INFO - PROCESS | 1671 | 08:50:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 08:50:26 INFO - PROCESS | 1671 | RSS: Main: 301027328 08:50:26 INFO - PROCESS | 1671 | 08:50:30 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 08:50:30 INFO - PROCESS | 1671 | RSS: Main: 397582336 08:50:30 INFO - PROCESS | 1671 | 08:50:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 08:50:33 INFO - PROCESS | 1671 | RSS: Main: 300523520 08:50:33 INFO - PROCESS | 1671 | 08:50:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:50:36 INFO - PROCESS | 1671 | RSS: Main: 303919104 08:50:36 INFO - PROCESS | 1671 | 08:50:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 08:50:39 INFO - PROCESS | 1671 | RSS: Main: 341086208 08:50:39 INFO - PROCESS | 1671 | 08:50:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 08:50:43 INFO - PROCESS | 1671 | RSS: Main: 342855680 08:50:43 INFO - PROCESS | 1671 | 08:50:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 08:50:46 INFO - PROCESS | 1671 | RSS: Main: 339988480 08:50:46 INFO - PROCESS | 1671 | 08:50:48 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:50:48 INFO - PROCESS | 1671 | RSS: Main: 315260928 08:50:48 INFO - PROCESS | 1671 | 08:50:50 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:50:51 INFO - PROCESS | 1671 | RSS: Main: 322674688 08:50:51 INFO - PROCESS | 1671 | 08:50:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:50:54 INFO - PROCESS | 1671 | RSS: Main: 325853184 08:50:54 INFO - PROCESS | 1671 | 08:50:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:50:57 INFO - PROCESS | 1671 | RSS: Main: 332767232 08:50:57 INFO - PROCESS | 1671 | 08:51:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:51:01 INFO - PROCESS | 1671 | RSS: Main: 334897152 08:51:01 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 08:51:03 INFO - PROCESS | 1671 | RSS: Main: 377737216 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | __start_tp_report 08:51:03 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 08:51:03 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 08:51:03 INFO - PROCESS | 1671 | |i|pagename|runs| 08:51:03 INFO - PROCESS | 1671 | |0;ai-astar;101;113;111;113;112;112;112;112;113;112 08:51:03 INFO - PROCESS | 1671 | |1;audio-beat-detection;142;114;106;159;106;106;128;110;172;137 08:51:03 INFO - PROCESS | 1671 | |2;audio-dft;183;185;184;182;184;181;183;251;238;184 08:51:03 INFO - PROCESS | 1671 | |3;audio-fft;81;61;67;111;81;74;68;57;99;57 08:51:03 INFO - PROCESS | 1671 | |4;audio-oscillator;89;88;92;71;72;68;96;73;67;103 08:51:03 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;86;84;87;84;87;83;87;84;87;83 08:51:03 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;103;142;92;93;93;93;93;92;92 08:51:03 INFO - PROCESS | 1671 | |7;imaging-desaturate;87;85;84;85;106;134;146;85;87;87 08:51:03 INFO - PROCESS | 1671 | |8;json-parse-financial;55;54;54;53;53;54;52;53;53;53 08:51:03 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;67;71;76;76;73;72;70;70;71;72 08:51:03 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;84;83;85;82;84;84;86;87;85;78 08:51:03 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;148;147;146;137;141;151;147;132;205;139 08:51:03 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;178;226;167;165;165;165;166;165;170;164 08:51:03 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;66;70;82;88;88;86;85;68;68;81 08:51:03 INFO - PROCESS | 1671 | __end_tp_report 08:51:03 INFO - PROCESS | 1671 | __start_cc_report 08:51:03 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2002 08:51:03 INFO - PROCESS | 1671 | __end_cc_report 08:51:03 INFO - PROCESS | 1671 | __startTimestamp1470757863529__endTimestamp 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | ------- Summary: start ------- 08:51:03 INFO - PROCESS | 1671 | Number of tests: 14 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:3.60 (3.2%) stddev-sans-first:0.67 08:51:03 INFO - PROCESS | 1671 | Values: 101.0 113.0 111.0 113.0 112.0 112.0 112.0 112.0 113.0 112.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:132.50 stddev:23.91 (18.0%) stddev-sans-first:24.82 08:51:03 INFO - PROCESS | 1671 | Values: 142.0 114.0 106.0 159.0 106.0 106.0 128.0 110.0 172.0 137.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:195.50 Median:184.00 stddev:26.03 (14.1%) stddev-sans-first:27.21 08:51:03 INFO - PROCESS | 1671 | Values: 183.0 185.0 184.0 182.0 184.0 181.0 183.0 251.0 238.0 184.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:75.60 Median:71.00 stddev:17.95 (25.3%) stddev-sans-first:18.93 08:51:03 INFO - PROCESS | 1671 | Values: 81.0 61.0 67.0 111.0 81.0 74.0 68.0 57.0 99.0 57.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:81.90 Median:80.50 stddev:13.10 (16.3%) stddev-sans-first:13.64 08:51:03 INFO - PROCESS | 1671 | Values: 89.0 88.0 92.0 71.0 72.0 68.0 96.0 73.0 67.0 103.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.50 stddev:1.75 (2.0%) stddev-sans-first:1.83 08:51:03 INFO - PROCESS | 1671 | Values: 86.0 84.0 87.0 84.0 87.0 83.0 87.0 84.0 87.0 83.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:98.60 Median:93.00 stddev:15.60 (16.8%) stddev-sans-first:16.41 08:51:03 INFO - PROCESS | 1671 | Values: 93.0 103.0 142.0 92.0 93.0 93.0 93.0 93.0 92.0 92.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:98.60 Median:85.00 stddev:22.92 (27.0%) stddev-sans-first:23.92 08:51:03 INFO - PROCESS | 1671 | Values: 87.0 85.0 84.0 85.0 106.0 134.0 146.0 85.0 87.0 87.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:53.50 stddev:0.84 (1.6%) stddev-sans-first:0.67 08:51:03 INFO - PROCESS | 1671 | Values: 55.0 54.0 54.0 53.0 53.0 54.0 52.0 53.0 53.0 53.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.00 stddev:2.74 (3.8%) stddev-sans-first:2.29 08:51:03 INFO - PROCESS | 1671 | Values: 67.0 71.0 76.0 76.0 73.0 72.0 70.0 70.0 71.0 72.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:84.50 stddev:2.49 (2.9%) stddev-sans-first:2.64 08:51:03 INFO - PROCESS | 1671 | Values: 84.0 83.0 85.0 82.0 84.0 84.0 86.0 87.0 85.0 78.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:147.00 stddev:20.42 (13.9%) stddev-sans-first:21.66 08:51:03 INFO - PROCESS | 1671 | Values: 148.0 147.0 146.0 137.0 141.0 151.0 147.0 132.0 205.0 139.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.10 Median:166.50 stddev:19.05 (11.4%) stddev-sans-first:20.12 08:51:03 INFO - PROCESS | 1671 | Values: 178.0 226.0 167.0 165.0 165.0 165.0 166.0 165.0 170.0 164.0 08:51:03 INFO - PROCESS | 1671 | 08:51:03 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.20 Median:83.50 stddev:9.10 (10.9%) stddev-sans-first:8.52 08:51:03 INFO - PROCESS | 1671 | Values: 66.0 70.0 82.0 88.0 88.0 86.0 85.0 68.0 68.0 81.0 08:51:03 INFO - PROCESS | 1671 | -------- Summary: end -------- 08:51:03 INFO - PROCESS | 1671 | 08:51:04 INFO - TEST-INFO | 1671: exit 0 08:51:04 INFO - TEST-OK | kraken | took 59377ms 08:51:04 INFO - SUITE-END | took 1177s 08:51:04 INFO - Completed test suite (00:19:37) 08:51:04 INFO - javascript benchmark 08:51:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2796.3009247688083, 3250.4961730931705, 3404.3361544498816, 3411.283630437608, 3478.2869511750077, 5645.237892884459, 5670.829875103743, 5757.107648314507, 5760.913586296209, 5770.798022069233, 5692.459216374448, 5755.971220143904, 5846.0, 5846.649201047941, 5898.823035308937, 5200.765965531541, 5218.295530103425, 5231.2153177023565, 5274.235235890794, 5369.463053694619, 5364.168553874156, 5370.758315875795, 5375.892482150354, 5393.379761327439, 5432.837014889559, 5200.947990520122, 5211.24436956643, 5219.295395121667, 5252.395974462909, 5266.894662106736, 5303.337082864642, 5312.362516498007, 5319.335083114611, 5324.787008519655, 5325.680459172462, 5173.534381905646, 5222.294990176353, 5275.60432967529, 5279.5512381447625, 5299.019681358961, 5286.524212820865, 5805.564582656318, 5858.912116318259, 5883.352831188566, 5923.289205295349, 5615.410381909923, 6219.844503887393, 6281.497480201573, 6289.654069026202, 6303.0545418187185, 6287.685615719196, 6327.5570710050315, 6336.303006669263, 6338.429541341301, 6353.936460635381, 5822.563307751893, 5853.326867410217, 5919.496842768327, 5979.581429299994, 5992.67040312787, 6310.463610593151, 6391.360863913572, 6398.200224971879, 6400.327965563642, 6423.197100362455, 6269.686515674198, 6280.120783090371, 6281.780137695158, 6282.2147231596055, 6288.685565721696, 5207.2449494823795, 6191.752329906843, 6216.875662486816, 6232.781852635136, 6267.623942563369, 6270.874582508324, 6281.434670879643, 6303.495720342362, 6306.873862522724, 6314.336994615591, 6153.507719382439, 6279.277883043417, 6285.0, 6300.3699630037545, 6304.306526282106, 4167.458230430025, 4954.40547134339, 5003.349564556657, 5029.5221953914315, 5077.923831142536, 3866.4007078902255, 4496.64026877849, 4505.166544189336, 4510.075434535978, 4547.454305483363, 5116.874287656709, 5743.167240750068, 5747.08046712524, 5798.652080875161, 5810.535157187415, 6176.69116544171, 6184.969075154685, 6216.471599914058, 6282.874342513124, 6288.371162883766, 5810.012297909366, 5815.76736930518, 5817.418258174233, 5823.4467725566, 5873.676947767914, 5466.043442397565, 5467.125259958467, 5481.698506582096, 5481.75332110056, 5482.698451585201, 6160.630362178284, 6223.657698826608, 6257.342978987141, 6370.58591191571, 6378.075179099097, 6214.1921550198185, 6283.057541368831, 6301.4328710416285, 6305.180326557518, 6315.084312774714, 6375.107484952203, 6388.169537960035, 6396.136521569715, 6400.871982560229, 6418.550701450854, 5103.821366252312, 6129.633877547049, 6186.752529898934, 6281.434670879643, 6312.5581209314905, 6235.719392627433, 6269.0909818075215, 6285.717142728679, 6299.212598425197, 6308.148399965946, 6157.32269450369, 6162.322144564184, 6184.6598437086395, 6210.192674952227, 6212.72042758086, 6021.277446706424, 6057.606255593372, 6075.331713511422, 6205.069239614094, 6223.844403889939, 4836.274558816206, 5299.940011997693, 5722.971385143048, 6040.818775436744, 6042.093685947143, 6099.420555047263, 6127.632342059313, 6142.293636231891, 6160.537959653134, 6169.475594574512, 5938.317093534126, 5955.196048533393, 5962.5528085394635, 6011.248593925759, 6038.849028774316], "value": 5721.694559196647, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.7642840365615, 477.14829030181136, 488.38951310861444, 513.4890783670248, 538.8652836790802, 31240.062798116036, 41728.37407438887, 42803.715888523315, 42807.0, 42895.35656965148, 43959.78020109895, 44386.778066109626, 44468.55531444684, 44502.55497445024, 44526.55473445264, 8812.030676625565, 9675.661351852668, 9849.803003939916, 9855.507224638775, 9871.950640246789, 8852.51311177885, 11880.0, 12106.576269830557, 12108.515659373614, 12367.319797411184, 12131.0, 12194.268343899394, 12205.0, 12212.93893530531, 12228.082893782957, 8854.468731876044, 11841.40792960357, 12014.639560813146, 12068.879311206863, 12092.0, 10010.249231307682, 11624.0, 11952.64142075739, 12003.63989080329, 12012.0, 40723.59276407257, 41043.973900652425, 41130.38304425436, 41213.587864121575, 41214.38178427326, 3498.9650103498893, 3502.176988407722, 3544.2025544252597, 3644.927101457956, 4035.1122752994293, 32452.513212301834, 33205.16987075318, 33462.498062529085, 33680.32639347224, 33684.6631533684, 3217.1421750029676, 3266.7386609071455, 3279.5900512435946, 3282.8030318180745, 3378.9155271118175, 454468.0, 454472.7276363597, 455188.4481155212, 455782.0, 457862.0, 60069.498262543784, 60399.698001509714, 60454.0, 60609.78780424367, 60913.69543152256, 56158.71920640371, 62238.68880655658, 62835.68582157151, 63119.0, 63191.0, 16534.0, 16769.580760480843, 16790.244439000275, 16794.99230046201, 16816.24326905292, 11741.589044383576, 12103.515859365703, 12737.044721645914, 12982.415791289415, 13038.217706937425, 16402.425915092914, 16529.6694066118, 16595.336186552402, 16613.3354665812, 16645.16774161287, 7129.964350178216, 7166.211716711157, 7168.426525877917, 7169.749058783022, 7256.6734496947765, 883.6597909804643, 949.4350861237691, 1006.8741407324085, 1049.7218237167158, 1163.3078318400537, 30162.69837301599, 30520.847395762878, 30984.53523197154, 31100.68899311023, 31333.843330783657, 1035.063267742694, 1101.9063379612662, 1121.3328069798456, 1162.1806626328419, 1169.128998895828, 13354.532591359255, 15298.005629634039, 15804.288807003712, 15813.209339532978, 16333.26500307489, 580341.0, 721729.0, 731942.0, 732743.0, 972090.0, 61358.0796288056, 61889.76220475565, 61913.38086619166, 62000.0, 62102.0, 1269.257484371653, 1331.1813234860685, 1384.702289007868, 1404.529482623312, 1423.1745587559192, 31215.84392078025, 32743.345133097682, 32919.35403229877, 33032.66967330296, 33867.0, 57708.0, 58200.4179958195, 58225.708871457064, 58400.123998139214, 58413.70793146007, 606827.0, 612349.0, 612746.0, 613561.0, 613568.0, 23897.522049558564, 23919.521609567364, 24403.145889893767, 24507.264782056565, 26655.7334426661, 39511.60488395079, 40351.59648403478, 46900.29649555191, 47490.76254618705, 49006.75496622637, 36807.63192368042, 39421.0, 39869.80065099772, 40306.79846600748, 40743.38884916788, 35863.46204806878, 37373.81313093417, 39467.2106557873, 40260.79869600751, 40991.59008409997, 38221.61778382181, 38691.613083868804, 38954.0, 38956.0, 39043.804780975915, 33101.33797324088, 33289.00132996014, 33455.49816752702, 34447.96656100321, 35563.644363556035, 21493.892530537247, 22803.657945130504, 23949.0, 25661.615075773505, 25898.35254118662], "value": 20673.993533307104, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.61506438665884, 373.43051845934946, 379.70762512865093, 384.7076222071226, 410.29634177385776, 14188.858111418882, 14696.338664760086, 20108.597828043432, 20336.983150842472, 20685.896570517165, 12034.03727701784, 15362.231888405562, 18949.147288372023, 19513.51216219595, 19648.90175549124, 5286.603504737141, 5498.780048798043, 5500.50495455409, 5503.504684578387, 5516.310461192351, 5387.730613469331, 5390.299261096072, 5928.851778705523, 5963.791267305645, 5985.401459854002, 5921.911171332434, 5922.526197904157, 5926.229590153274, 5933.910991335112, 5957.6127551709, 4527.2530032544755, 5424.75230696938, 5440.047991601454, 5450.727463626803, 5525.143602741561, 5243.973780131113, 5272.868178295574, 5277.8416647500635, 5278.261043453919, 5283.022640811424, 19258.903705481524, 22062.558748825093, 22216.333509994725, 23896.296074108366, 25889.74110258911, 2043.3052762060822, 2216.013873826148, 2256.6050941085246, 2483.869839223149, 2643.0, 9628.66299679508, 10317.896821031769, 10359.378437293712, 12071.879281207164, 15651.530454086396, 1779.7953235377968, 1828.8171182881738, 1907.418237437581, 1924.0572119661265, 1934.216642259887, 100316.0, 114509.0, 121328.18007729891, 124529.75470245272, 124877.63417743705, 11400.429978501043, 11573.305601663928, 11725.003374713075, 11827.467763950643, 11965.760684786255, 14724.190169540563, 14741.189234591986, 14757.704845903021, 14772.704545909022, 14774.704505910036, 8337.499750014897, 8561.0, 8586.570671466401, 8687.131286871263, 8773.0, 8530.189631984951, 8569.657213711507, 8571.699990500303, 8715.869261961076, 8774.0, 4472.351509031172, 4535.319702044654, 4558.2251017327135, 4630.305454181832, 4640.257558790578, 1876.4933467963574, 1940.2530025940114, 1953.091812307284, 1985.7219989201524, 2001.5796682696505, 838.3293365307729, 896.9910300896908, 916.6425094213262, 938.2400255792829, 1001.2290536287019, 20815.37553873356, 21459.57080858374, 21502.247421340177, 21549.461263468223, 21586.56826863454, 931.0875342164717, 943.7216021273741, 958.166395236149, 975.9268054895912, 1008.4554340655964, 10983.286086404358, 11281.71795705114, 11302.773944521063, 11304.660860174208, 11376.374299413612, 308953.0, 310752.0, 310938.445307772, 311522.0, 621659.0, 101982.0, 107032.0, 140049.0, 141801.0, 147128.0, 955.1642312976146, 980.1325826643472, 997.9600815967425, 1003.117256813999, 1006.2352612015068, 14263.786043209568, 17444.127793610016, 18876.33932812345, 18941.052947352302, 19054.333147986425, 44853.55146448494, 49312.50687493222, 49361.25958110703, 50323.99352013013, 50573.49426505688, 70440.94338584822, 70760.0, 70966.93549596657, 71385.92921106292, 72408.63795680988, 7864.21357864209, 7933.563653999086, 8129.349652027942, 8202.26179643835, 8294.917050829656, 9158.58786354629, 12843.229406235656, 12965.805512917124, 12996.8050479241, 13229.206247625174, 11010.944945275543, 11274.830877537008, 11283.66149015531, 11303.095752339628, 11320.716982075184, 8033.919660803317, 10618.309809862334, 11086.279391839505, 11174.385408802595, 11225.97932372181, 11480.253783504046, 11802.704932376415, 12196.451159697657, 12198.939005305272, 12251.69370765738, 7942.880856787268, 8077.474964127313, 8114.634841432267, 8510.957445212734, 8543.61553730096, 8417.242448179693, 11167.0, 11193.72015699614, 11311.377874216998, 11468.369239691898], "value": 9288.046149539794, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.1160857051495, 1456.6067161866292, 1519.278342787176, 1715.3053013529518, 1922.3271854850796, 7498.0, 7980.603394405985, 7983.680652773896, 7993.0, 8003.879941800863, 8181.100078991306, 8454.619542120605, 8476.152384761535, 8571.528565928871, 8601.311895048397, 2327.8254130940163, 2361.409647588103, 2365.562370961369, 2366.230974933145, 2369.4431808524982, 2311.537692461506, 2355.9646605300936, 2357.068957761683, 2364.1607229433475, 2368.5973384524586, 2328.476092879097, 2349.9882500587473, 2353.1293421434093, 2362.4566349739484, 2367.917122900699, 2281.6349384098467, 2303.3205204464725, 2315.8378913476063, 2317.4090606895134, 2334.171369163956, 2284.0521183708743, 2284.794368506842, 2285.2915596165076, 2286.8399212055165, 2290.667853161299, 8519.616617252239, 8558.144185581392, 8626.956865215696, 8649.221570058662, 8651.913480865174, 5086.415062267813, 5122.5133612307145, 5286.577073834084, 5295.443978382253, 5301.575873930076, 3570.6429357064344, 3657.9817100914593, 3658.579263384718, 3659.7621154624862, 3662.487251784752, 1079.5843600213896, 1088.88022317545, 1111.77208672222, 1114.8773634900153, 1116.4138827115748, 11173.72065698356, 11245.55017799287, 11257.493412796444, 11268.943655281671, 11380.0, 4398.956010439854, 4622.6301895848255, 4830.154722923475, 4835.057163853015, 4846.055019271208, 8930.642774289086, 8999.550022498848, 9003.459792412476, 9023.187913087853, 9042.367034307536, 5195.532402083755, 5204.34945631796, 5205.219217117463, 5209.114450543417, 5213.087709650796, 3999.340108882002, 4351.9564804352185, 4696.131215725101, 4784.569388755029, 4891.510848915151, 3702.333579955634, 3733.51464309638, 3798.2213646202463, 3811.19964807388, 3836.9424458633143, 941.5292353823088, 1098.5111625326654, 1103.7406209540752, 1116.6873275482878, 1117.9608713694981, 907.2877790934085, 912.9589168487435, 922.0, 944.8299306124964, 980.4950450517989, 5717.199592057115, 5824.854378640568, 5833.241678581757, 5909.674967876722, 6144.078388241799, 426.6416210383293, 430.35876543949917, 439.7537379067732, 444.9288113901825, 445.8550970934459, 3850.595687452777, 4005.899852503711, 4036.9596304037173, 4040.22239206343, 4066.79666016698, 9797.902020979698, 10068.798624027624, 10540.262181647211, 10544.525496352682, 10569.048785609179, 8845.51349675774, 10179.389236645826, 10344.586216551405, 10451.268411211142, 10519.684409467729, 6683.899741503881, 7670.884936725954, 7818.648160832776, 8017.115977745225, 8086.474379165218, 5790.710464476843, 5873.4126587340925, 5955.642661440327, 6007.579469437273, 6061.909071363845, 9533.61865525399, 9854.753631158992, 9873.358231714916, 9887.357321774349, 9916.107550320507, 7662.425318101276, 7981.8802717958115, 9773.85339219898, 9922.702318930444, 9953.552090156105, 7219.819504512429, 7360.595167265637, 7366.7053317866685, 7368.521046131984, 7381.557106573623, 8090.271875531231, 8154.1030486647605, 8162.959185204036, 8166.918330816616, 8171.1828817120195, 6316.399942005502, 7047.682854271673, 7320.377767889789, 7377.446691498267, 7456.291652293024, 7336.853262934818, 7338.633068346669, 7363.77908662741, 7389.408847292097, 7428.777136685908, 6682.899756503559, 6890.20762612306, 6916.20463646687, 6994.475414343843, 7069.469789765685, 4977.303177555217, 4986.650934434555, 5044.571211447068, 5054.09026375256, 5140.151874940593, 4944.5549900507685, 4946.530079642428, 5049.84850454487, 5086.3896332440345, 5090.134677104975], "value": 4390.224979589318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.89538661468487, 299.29068108582663, 312.9499280115181, 317.7585035373116, 318.25845779334156, 4831.903361932759, 5070.366204224472, 5166.793328266875, 5244.770972336756, 5366.436524164965, 4155.966144714361, 4485.6635752318625, 5179.0, 5261.474811876043, 5297.788088476466, 3812.618738126193, 4061.979690101545, 4065.6380112662296, 4082.4692789937267, 4087.6321131098193, 3349.6147942986504, 3787.3561494545993, 4046.988252936766, 4066.003829061884, 4077.408775727518, 3572.945980935617, 3900.5904380040115, 3940.014996250937, 3961.7028722845757, 3966.6628336591425, 3395.5246265522846, 3911.3937339712247, 3943.112799620091, 3955.4462375267485, 3981.840726370942, 2800.6919238883706, 3359.9496007559906, 3407.5399821024093, 3408.5057666638445, 3410.36908171989, 4181.351890456954, 4232.78836058199, 5035.571976382012, 5044.881149917043, 5101.489851014869, 4494.752788596626, 4518.661100417482, 4519.050999290153, 4533.161365147458, 4548.840790572314, 4529.682922195448, 4582.633389328846, 5223.406860907422, 5403.325353949781, 5551.0, 3119.407312610597, 3630.2739452109417, 3778.6410291022307, 3790.6967442604528, 3791.715621328384, 4908.926366104475, 5659.886802263932, 5757.453041961007, 5759.5968282220265, 5761.127633519081, 3823.1397935464297, 3837.9040523987123, 3844.7308688392104, 3847.5575308839284, 3869.0267963338338, 3035.4839677254918, 3253.6746325367276, 3912.7456715313983, 3921.1961547882615, 3921.8037299600733, 3779.9210434261045, 4248.745075295492, 4544.500104988449, 4625.421822272216, 4627.732411118528, 4613.469451013109, 5572.972135139379, 5578.191162281447, 5594.944050559524, 5620.353659329229, 1301.576987479073, 1344.3815844711346, 1346.414309775251, 1365.3855764905832, 1374.216696482995, 889.2796834563894, 899.4153800030033, 930.2372054914905, 936.3866667332771, 960.5735482808025, 4416.072624748838, 4881.242752609058, 5011.749412529359, 5150.253213284052, 5180.222966554971, 4629.791659375336, 4899.0, 4938.913439043405, 5177.016366890279, 5541.501264886179, 4663.603593694574, 4796.592289655418, 4860.465348811628, 5055.494450554915, 5096.133657278272, 2394.3894306951615, 2440.3533063738123, 2470.6047032475076, 2477.7893879020125, 2511.062057392855, 4249.575042495788, 4328.307470804656, 4549.522300158502, 4695.577398034194, 4699.647526435463, 4132.946098744801, 4283.978580107079, 4726.809403652521, 4783.851875549843, 4815.686980346266, 4511.390962220124, 4826.493218212107, 5634.183043458757, 5878.764849405976, 5937.317208520989, 4824.95175048245, 5730.369659337553, 5733.770649173986, 5748.39641837601, 5788.479036886701, 4874.074740477562, 5376.462353764592, 5599.468050535193, 5653.943460565506, 5761.423857614205, 4879.463259041574, 5924.674142922181, 5939.732712027882, 5960.523158147251, 6039.809195574571, 3374.797512149279, 3818.809059546956, 3846.2307538491855, 3863.6136386362264, 3895.7467764595212, 4827.0, 4888.877778055435, 5594.888102238014, 5753.5109515689965, 5818.0, 5517.834464966058, 5583.497485226349, 5612.522935550361, 5622.453825198103, 5629.971850140723, 5559.527440167631, 5578.49793518569, 5596.748146333343, 5614.719264036864, 5637.746301416364, 5087.796488140433, 5364.946350536601, 5441.211024401518, 5488.753006114654, 5506.697131657797, 4701.224297990793, 5273.734303766992, 5544.362398324244, 5581.888362232814, 5598.916016259678, 5194.662346947436, 5472.0, 5473.124300111963, 5483.287172667528, 5511.594543391409], "value": 3947.538311875571, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.9891551626725, 734.6033142103264, 774.0479210570998, 785.1089013969143, 879.3624622148943, 13694.657633559154, 14524.854751452469, 14543.200123993176, 14547.272636368165, 14598.854011459909, 13771.173729576207, 13827.516036938709, 13854.861451385483, 13900.304984750772, 13926.025178237502, 686.739039165119, 844.9112843151473, 853.6926706385711, 881.4799268431624, 889.5552223888056, 873.7946582553095, 873.9169778870997, 877.1228771228771, 877.7630039889226, 878.5914549734372, 923.866039424283, 925.5788616179656, 926.3608110403834, 928.0626567167176, 930.330162283155, 719.8884172953175, 748.5471289869632, 749.6439191384103, 754.35125791819, 869.3697069624471, 680.9829754256183, 684.852756657316, 691.029104108728, 692.5740669488229, 695.2595485807599, 14396.136231826124, 14406.0, 14428.567142985727, 14439.061461005105, 14442.783358249635, 13613.115147515478, 13727.931360343235, 13796.724065518734, 13864.445422183202, 13936.51222207228, 10897.0, 12592.118551701384, 12790.360481975864, 12902.935485322607, 12908.612741617766, 4567.5889169974525, 4600.332951722015, 4615.192341340252, 4777.163996300668, 4783.449903261135, 117343.0, 119055.0, 120553.79446205602, 120967.0, 121087.39456302662, 10949.288296260718, 10959.178061645409, 11034.779304413867, 11036.227464077598, 11432.371219583003, 21442.035108420157, 21446.0, 21450.463738406354, 21465.67801482979, 21470.677939830915, 7572.962135189399, 7752.57075166495, 7835.412344074217, 7854.6858125674335, 8028.558429286329, 4473.709208901411, 4498.797554110073, 4533.365328853963, 4631.675782695179, 4636.142313671981, 3460.4463285874644, 3483.425234836224, 3602.8018458985007, 3614.042278796121, 3618.5838628558054, 668.481926506961, 669.0432681265837, 699.174973531238, 738.7488253993668, 741.3513175971025, 587.5417174603816, 597.310106826616, 660.904168895517, 661.5567569728294, 692.1521136607716, 9392.29557783169, 9600.183984361407, 9691.70924872255, 9807.607695692092, 9826.65606703762, 578.3089208395927, 636.5925807483214, 662.6256165266609, 668.1347654787062, 678.5657179405184, 3997.8600748973645, 4574.542545745466, 4807.978860787141, 4808.158572249912, 4914.434839993375, 92086.0, 102607.48696256471, 113874.0, 138462.0, 176597.0, 96036.51981740186, 100506.99493004977, 101642.4917875406, 114128.42935785434, 116750.0, 82696.17303827005, 86171.27657446815, 89195.0, 98000.50999744955, 100941.49529252453, 9521.952390238004, 9724.222062235145, 9997.250206234707, 10017.949910250401, 10072.043155900177, 48934.0, 55742.0, 58992.705036474545, 60228.79542409039, 60334.39665603463, 56118.719406402706, 60418.395816043034, 84976.4210588951, 92168.0, 93270.0, 13178.406971686489, 13383.799243011557, 13492.86507134916, 13493.32533373347, 13919.652008699864, 14349.784753228501, 14550.199739014455, 14598.270086495846, 14645.92677036608, 14751.92624036916, 13948.651283717989, 14017.369218385402, 14104.647383815487, 14487.348069337117, 14808.555743327717, 14075.125084011894, 14148.0, 14265.64335891611, 14387.568372948828, 14535.927320363331, 13581.728365432438, 13685.521006764715, 13972.650683732989, 14222.786658199928, 14421.0, 11479.966802987772, 11528.884711153116, 11659.591914283299, 11783.941080294544, 11855.34795586251, 9556.992539501905, 10010.978043912175, 11768.94115529417, 11770.823437648613, 11788.646340609796], "value": 7769.6087915920325, "unit": "score"}], "name": "dromaeo_css", "value": 7272.59350998204, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 111.0, 113.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 114.0, 106.0, 159.0, 106.0, 106.0, 128.0, 110.0, 172.0, 137.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 182.0, 184.0, 181.0, 183.0, 251.0, 238.0, 184.0], "value": 195.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 67.0, 111.0, 81.0, 74.0, 68.0, 57.0, 99.0, 57.0], "value": 75.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 92.0, 71.0, 72.0, 68.0, 96.0, 73.0, 67.0, 103.0], "value": 81.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 87.0, 83.0, 87.0, 84.0, 87.0, 83.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 103.0, 142.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0, 92.0], "value": 98.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 84.0, 85.0, 106.0, 134.0, 146.0, 85.0, 87.0, 87.0], "value": 98.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 53.0, 53.0, 54.0, 52.0, 53.0, 53.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 76.0, 76.0, 73.0, 72.0, 70.0, 70.0, 71.0, 72.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 85.0, 82.0, 84.0, 84.0, 86.0, 87.0, 85.0, 78.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 137.0, 141.0, 151.0, 147.0, 132.0, 205.0, 139.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 226.0, 167.0, 165.0, 165.0, 165.0, 166.0, 165.0, 170.0, 164.0], "value": 173.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 82.0, 88.0, 88.0, 86.0, 85.0, 68.0, 68.0, 81.0], "value": 78.2, "unit": "score"}], "name": "kraken", "value": 1484.1, "alertThreshold": 2.0}]} 08:51:04 INFO - Return code: 0 08:51:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:51:04 INFO - # TBPL SUCCESS # 08:51:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:04 INFO - 2016-08-09 08:51:04.824 screenresolution[1675:10020] starting screenresolution argv=screenresolution get 08:51:04 INFO - 2016-08-09 08:51:04.826 screenresolution[1675:10020] Display 0: 1600x1200x32@60 08:51:04 INFO - 2016-08-09 08:51:04.833 screenresolution[1676:10023] starting screenresolution argv=screenresolution list 08:51:04 INFO - Available Modes on Display 0 08:51:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:05 INFO - Intel Iris: 08:51:05 INFO - Chipset Model: Intel Iris 08:51:05 INFO - Type: GPU 08:51:05 INFO - Bus: Built-In 08:51:05 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:05 INFO - Vendor: Intel (0x8086) 08:51:05 INFO - Device ID: 0x0a2e 08:51:05 INFO - Revision ID: 0x0009 08:51:05 INFO - Displays: 08:51:05 INFO - Display: 08:51:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:05 INFO - Display Serial Number: 1600x1200 60 08:51:05 INFO - Main Display: Yes 08:51:05 INFO - Mirror: Off 08:51:05 INFO - Online: Yes 08:51:05 INFO - Rotation: Supported 08:51:05 INFO - Return code: 0 08:51:05 INFO - Running post-action listener: _resource_record_post_action 08:51:05 INFO - [mozharness: 2016-08-09 15:51:05.072264Z] Finished run-tests step (success) 08:51:05 INFO - Running post-run listener: _resource_record_post_run 08:51:05 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 38069248; Write bytes: 701724672; Read time: 390; Write time: 2085 08:51:05 INFO - TinderboxPrint: CPU usage
53.3% 08:51:05 INFO - TinderboxPrint: I/O read bytes / time
38,069,248 / 390 08:51:05 INFO - TinderboxPrint: I/O write bytes / time
701,724,672 / 2,085 08:51:05 INFO - TinderboxPrint: CPU idle
2,262.7 (46.7%) 08:51:05 INFO - TinderboxPrint: CPU system
968.3 (20.0%) 08:51:05 INFO - TinderboxPrint: CPU user
1,618.7 (33.4%) 08:51:05 INFO - TinderboxPrint: Swap in / out
445,071,360 / 0 08:51:05 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392309248; Write bytes: 386156032; Read time: 29387; Write time: 748 08:51:05 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 37114880; Write bytes: 292344320; Read time: 376; Write time: 1289 08:51:05 INFO - Running post-run listener: _upload_blobber_files 08:51:05 INFO - Blob upload gear active. 08:51:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:51:05 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 08:51:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:51:05 INFO - (blobuploader) - INFO - Open directory for files ... 08:51:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:51:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:51:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:07 INFO - (blobuploader) - INFO - Done attempting. 08:51:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:51:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:51:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:08 INFO - (blobuploader) - INFO - Done attempting. 08:51:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:51:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:51:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:51:10 INFO - (blobuploader) - INFO - Done attempting. 08:51:10 INFO - (blobuploader) - INFO - Iteration through files over. 08:51:10 INFO - Return code: 0 08:51:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:51:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:51:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecc993ad4588d2d51c5b7e61c0aa3b9788418bf28c66e2f52b1a6d39ab97cf9b67cdde3bd207fc6936ce5b162f73e50a13918bc0a8bcd9f7a4e92ae0c05f8aef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4138bbeb69f5270c11ad5422c21ac18165557d9afa9f7fc3751ac2e68c5be0fc9fedca1b57935756c75e4e2f8911e2aea567b582bbe63debcccde52a9b81173f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54fdbaa7029b1b6ddf687aad05f83dd8fa9e4ddd89e26a4f15b6f557637455809d0b4d86c9d69593628ae46831909267cfc6b8fbfed096cef5cdab5c2b2ada17"} 08:51:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:51:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:51:10 INFO - Contents: 08:51:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecc993ad4588d2d51c5b7e61c0aa3b9788418bf28c66e2f52b1a6d39ab97cf9b67cdde3bd207fc6936ce5b162f73e50a13918bc0a8bcd9f7a4e92ae0c05f8aef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4138bbeb69f5270c11ad5422c21ac18165557d9afa9f7fc3751ac2e68c5be0fc9fedca1b57935756c75e4e2f8911e2aea567b582bbe63debcccde52a9b81173f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54fdbaa7029b1b6ddf687aad05f83dd8fa9e4ddd89e26a4f15b6f557637455809d0b4d86c9d69593628ae46831909267cfc6b8fbfed096cef5cdab5c2b2ada17"} 08:51:10 INFO - Running post-run listener: copy_logs_to_upload_dir 08:51:10 INFO - Copying logs to upload dir... 08:51:10 INFO - mkdir: /builds/slave/test/build/upload/logs 08:51:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.939904 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-08-09 08:51:10.725257) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 08:51:10.728319) ========= 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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecc993ad4588d2d51c5b7e61c0aa3b9788418bf28c66e2f52b1a6d39ab97cf9b67cdde3bd207fc6936ce5b162f73e50a13918bc0a8bcd9f7a4e92ae0c05f8aef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4138bbeb69f5270c11ad5422c21ac18165557d9afa9f7fc3751ac2e68c5be0fc9fedca1b57935756c75e4e2f8911e2aea567b582bbe63debcccde52a9b81173f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54fdbaa7029b1b6ddf687aad05f83dd8fa9e4ddd89e26a4f15b6f557637455809d0b4d86c9d69593628ae46831909267cfc6b8fbfed096cef5cdab5c2b2ada17"} build_url:https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecc993ad4588d2d51c5b7e61c0aa3b9788418bf28c66e2f52b1a6d39ab97cf9b67cdde3bd207fc6936ce5b162f73e50a13918bc0a8bcd9f7a4e92ae0c05f8aef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4138bbeb69f5270c11ad5422c21ac18165557d9afa9f7fc3751ac2e68c5be0fc9fedca1b57935756c75e4e2f8911e2aea567b582bbe63debcccde52a9b81173f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/54fdbaa7029b1b6ddf687aad05f83dd8fa9e4ddd89e26a4f15b6f557637455809d0b4d86c9d69593628ae46831909267cfc6b8fbfed096cef5cdab5c2b2ada17"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 08:51:10.761193) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:51:10.761527) ========= 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.WuU4eoXoKF/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.Jkt3HDi3sW/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.005161 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:51:10.815988) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 08:51:10.816299) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 08:51:10.816640) ========= ========= Total master_lag: 0.18 =========