builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0052 starttime: 1475475987.43 results: success (0) buildid: 20161002200656 builduid: bc2482c458c84ddbadee06548a288099 revision: 2a14359213efbbe5b8ea31cbba0cd8ba8fa16550 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:27.433477) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:27.433875) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:27.434143) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:27.516230) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:27.516479) ========= 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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-10-02 23:26:27-- 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% 38.6M=0s 2016-10-02 23:26:28 (38.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.537759 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:28.076941) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:28.077218) ========= 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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.020682 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:28.125608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-02 23:26:28.125904) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 2a14359213efbbe5b8ea31cbba0cd8ba8fa16550 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 2a14359213efbbe5b8ea31cbba0cd8ba8fa16550 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vWwPkFjU9o/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.dLaYGHV1gD/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-10-02 23:26:28,197 truncating revision to first 12 chars 2016-10-02 23:26:28,197 Setting DEBUG logging. 2016-10-02 23:26:28,197 attempt 1/10 2016-10-02 23:26:28,197 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/2a14359213ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-02 23:26:29,175 unpacking tar archive at: fx-team-2a14359213ef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.258529 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-02 23:26:29.405783) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:29.406111) ========= script_repo_revision: 2a14359213efbbe5b8ea31cbba0cd8ba8fa16550 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:29.406577) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:29.406893) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-02 23:26:29.431400) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-02 23:26:29.431705) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vWwPkFjU9o/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.dLaYGHV1gD/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:26:29 INFO - MultiFileLogger online at 20161002 23:26:29 in /builds/slave/test 23:26:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:26:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:26:29 INFO - {'append_to_log': False, 23:26:29 INFO - 'base_work_dir': '/builds/slave/test', 23:26:29 INFO - 'blob_upload_branch': 'Fx-Team', 23:26:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:26:29 INFO - 'branch': 'Fx-Team', 23:26:29 INFO - 'buildbot_json_path': 'buildprops.json', 23:26:29 INFO - 'config_files': ('talos/mac_config.py',), 23:26:29 INFO - 'default_actions': ('clobber', 23:26:29 INFO - 'read-buildbot-config', 23:26:29 INFO - 'download-and-extract', 23:26:29 INFO - 'populate-webroot', 23:26:29 INFO - 'create-virtualenv', 23:26:29 INFO - 'install', 23:26:29 INFO - 'run-tests'), 23:26:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:26:29 INFO - 'download_minidump_stackwalk': True, 23:26:29 INFO - 'download_symbols': 'ondemand', 23:26:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:26:29 INFO - 'tooltool.py': '/tools/tooltool.py', 23:26:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:26:29 INFO - '/tools/misc-python/virtualenv.py')}, 23:26:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:26:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:26:29 INFO - 'installer_path': 'installer.exe', 23:26:29 INFO - 'log_level': 'info', 23:26:29 INFO - 'log_name': 'talos', 23:26:29 INFO - 'log_to_console': True, 23:26:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:26:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:26:29 INFO - 'opt_config_files': (), 23:26:29 INFO - 'pip_index': False, 23:26:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:26:29 INFO - 'cmd': ('bash', 23:26:29 INFO - '-c', 23:26:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:26:29 INFO - 'enabled': True, 23:26:29 INFO - 'halt_on_failure': False, 23:26:29 INFO - 'name': 'check_screen_resolution'},), 23:26:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:26:29 INFO - 'cmd': ('bash', 23:26:29 INFO - '-c', 23:26:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:26:29 INFO - 'enabled': True, 23:26:29 INFO - 'halt_on_failure': False, 23:26:29 INFO - 'name': 'check_screen_resolution'},), 23:26:29 INFO - 'run_cmd_checks_enabled': True, 23:26:29 INFO - 'sps_profile': False, 23:26:29 INFO - 'sps_profile_interval': 0, 23:26:29 INFO - 'suite': 'dromaeojs-e10s', 23:26:29 INFO - 'system_bits': '32', 23:26:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:26:29 INFO - 'title': 't-yosemite-r7-0052', 23:26:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:26:29 INFO - 'use_talos_json': True, 23:26:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:26:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:26:29 INFO - 'work_dir': 'build'} 23:26:29 INFO - [mozharness: 2016-10-03 06:26:29.639234Z] Running clobber step. 23:26:29 INFO - Running pre-action listener: _resource_record_pre_action 23:26:29 INFO - Running main action method: clobber 23:26:29 INFO - rmtree: /builds/slave/test/build 23:26:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:26:31 INFO - Running post-action listener: _resource_record_post_action 23:26:31 INFO - [mozharness: 2016-10-03 06:26:31.121108Z] Finished clobber step (success) 23:26:31 INFO - [mozharness: 2016-10-03 06:26:31.121235Z] Running read-buildbot-config step. 23:26:31 INFO - Running pre-action listener: _resource_record_pre_action 23:26:31 INFO - Running main action method: read_buildbot_config 23:26:31 INFO - Using buildbot properties: 23:26:31 INFO - { 23:26:31 INFO - "project": "", 23:26:31 INFO - "product": "firefox", 23:26:31 INFO - "script_repo_revision": "production", 23:26:31 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 23:26:31 INFO - "repository": "", 23:26:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 23:26:31 INFO - "buildid": "20161002200656", 23:26:31 INFO - "pgo_build": "False", 23:26:31 INFO - "basedir": "/builds/slave/test", 23:26:31 INFO - "buildnumber": 5, 23:26:31 INFO - "slavename": "t-yosemite-r7-0052", 23:26:31 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 23:26:31 INFO - "platform": "yosemite_r7", 23:26:31 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 23:26:31 INFO - "branch": "fx-team", 23:26:31 INFO - "repo_path": "integration/fx-team", 23:26:31 INFO - "revision": "2a14359213efbbe5b8ea31cbba0cd8ba8fa16550", 23:26:31 INFO - "stage_platform": "macosx64", 23:26:31 INFO - "builduid": "bc2482c458c84ddbadee06548a288099", 23:26:31 INFO - "slavebuilddir": "test" 23:26:31 INFO - } 23:26:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 23:26:31 INFO - Running post-action listener: _resource_record_post_action 23:26:31 INFO - [mozharness: 2016-10-03 06:26:31.125132Z] Finished read-buildbot-config step (success) 23:26:31 INFO - [mozharness: 2016-10-03 06:26:31.125240Z] Running download-and-extract step. 23:26:31 INFO - Running pre-action listener: _resource_record_pre_action 23:26:31 INFO - Running main action method: download_and_extract 23:26:31 INFO - mkdir: /builds/slave/test/build/tests 23:26:31 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')]} 23:26:31 INFO - trying https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 23:26:31 INFO - Downloading https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 23:26:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 23:26:32 INFO - Downloaded 1199 bytes. 23:26:32 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 23:26:32 INFO - Using the following test package requirements: 23:26:32 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 23:26:32 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:26:32 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 23:26:32 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:26:32 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 23:26:32 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:26:32 INFO - u'jsshell-mac.zip'], 23:26:32 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:26:32 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 23:26:32 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 23:26:32 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:26:32 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 23:26:32 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:26:32 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 23:26:32 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:26:32 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 23:26:32 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:26:32 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 23:26:32 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:26:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 23:26:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:26:32 INFO - Fetch https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 23:26:32 INFO - Http code: 200 23:26:32 INFO - via: 1.1 41b2ee7cbe95749816a7586d9ab4629d.cloudfront.net (CloudFront) 23:26:32 INFO - x-amz-cf-id: ohz86KIQcJyt2DZ62iZxzPUs3J43m7a-5mv2gCqABNywgAWB_AWCLA== 23:26:32 INFO - x-amz-version-id: CC6PgLPxlIdCX6s0O3ASrmA6cRe79Js. 23:26:33 INFO - Expected file size: 16475786 23:26:33 INFO - Obtained file size: 16475786 23:26:39 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:26:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 23:26:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:26:39 INFO - Fetch https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 23:26:40 INFO - Http code: 200 23:26:40 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 23:26:40 INFO - x-amz-cf-id: 2si29XEvsF7mkGPSEvoDeTCzITof6qAQVdMVeQlsJgGpzfe6K1whOQ== 23:26:40 INFO - x-amz-version-id: CC6PgLPxlIdCX6s0O3ASrmA6cRe79Js. 23:26:40 INFO - Expected file size: 16475786 23:26:40 INFO - Obtained file size: 16475786 23:26:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 23:26:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:26:46 INFO - Fetch https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 23:26:48 INFO - Http code: 200 23:26:48 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 23:26:48 INFO - x-amz-cf-id: 8-c0quouPR5HJ55eMLwRVfjwThc3XwmcmHAJPcsCkv_vGafMOrFVdw== 23:26:48 INFO - x-amz-version-id: X9ew7WxWTeXOApbLVywXXehiXx6Py3Go 23:26:48 INFO - Expected file size: 14408522 23:26:48 INFO - Obtained file size: 14408522 23:26:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:26:48 INFO - trying https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 23:26:48 INFO - Downloading https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 23:26:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 23:26:52 INFO - Downloaded 111813304 bytes. 23:26:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 23:26:52 INFO - mkdir: /builds/slave/test/properties 23:26:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:26:52 INFO - Writing to file /builds/slave/test/properties/build_url 23:26:52 INFO - Contents: 23:26:52 INFO - build_url:https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 23:26:53 INFO - Running post-action listener: _resource_record_post_action 23:26:53 INFO - Running post-action listener: set_extra_try_arguments 23:26:53 INFO - [mozharness: 2016-10-03 06:26:53.073427Z] Finished download-and-extract step (success) 23:26:53 INFO - [mozharness: 2016-10-03 06:26:53.073579Z] Running populate-webroot step. 23:26:53 INFO - Running pre-action listener: _resource_record_pre_action 23:26:53 INFO - Running main action method: populate_webroot 23:26:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:26:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:26:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:26:53 INFO - '--tppagecycles', 23:26:53 INFO - '7'], 23:26:53 INFO - 'tests': ['tsvgm']}}, 23:26:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:26:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:26:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:26:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:26:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:26:53 INFO - 'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:26:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:26:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:26:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:26:53 INFO - 'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['damp', 'tps']}, 23:26:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:26:53 INFO - 'tests': ['damp', 'tps']}, 23:26:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['dromaeo_dom']}, 23:26:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:26:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['basic_compositor_video']}, 23:26:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:26:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['a11yr', 23:26:53 INFO - 'ts_paint', 23:26:53 INFO - 'tpaint', 23:26:53 INFO - 'sessionrestore', 23:26:53 INFO - 'sessionrestore_no_auto_restore', 23:26:53 INFO - 'tabpaint']}, 23:26:53 INFO - 'other-e10s': {'tests': ['a11yr', 23:26:53 INFO - 'ts_paint', 23:26:53 INFO - 'tpaint', 23:26:53 INFO - 'sessionrestore', 23:26:53 INFO - 'sessionrestore_no_auto_restore', 23:26:53 INFO - 'tabpaint']}, 23:26:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:26:53 INFO - 'ts_paint', 23:26:53 INFO - 'tpaint', 23:26:53 INFO - 'sessionrestore', 23:26:53 INFO - 'sessionrestore_no_auto_restore', 23:26:53 INFO - 'tabpaint']}, 23:26:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:26:53 INFO - 'ts_paint', 23:26:53 INFO - 'tpaint', 23:26:53 INFO - 'sessionrestore', 23:26:53 INFO - 'sessionrestore_no_auto_restore', 23:26:53 INFO - 'tabpaint']}, 23:26:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['a11yr', 23:26:53 INFO - 'ts_paint', 23:26:53 INFO - 'tpaint', 23:26:53 INFO - 'sessionrestore', 23:26:53 INFO - 'sessionrestore_no_auto_restore', 23:26:53 INFO - 'tabpaint']}, 23:26:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['a11yr', 23:26:53 INFO - 'ts_paint', 23:26:53 INFO - 'tpaint', 23:26:53 INFO - 'sessionrestore', 23:26:53 INFO - 'sessionrestore_no_auto_restore', 23:26:53 INFO - 'tabpaint']}, 23:26:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['tsvgx', 23:26:53 INFO - 'tsvgr_opacity', 23:26:53 INFO - 'tart', 23:26:53 INFO - 'tscrollx', 23:26:53 INFO - 'cart']}, 23:26:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:26:53 INFO - 'tsvgr_opacity', 23:26:53 INFO - 'tart', 23:26:53 INFO - 'tscrollx', 23:26:53 INFO - 'cart']}, 23:26:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:26:53 INFO - 'talos_options': ['--disable-e10s'], 23:26:53 INFO - 'tests': ['tp5o']}, 23:26:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:26:53 INFO - 'tests': ['tp5o']}, 23:26:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:26:53 INFO - 'talos_options': ['--disable-e10s', 23:26:53 INFO - '--xperf_path', 23:26:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:26:53 INFO - 'tests': ['tp5n']}, 23:26:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:26:53 INFO - 'talos_options': ['--xperf_path', 23:26:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:26:53 INFO - 'tests': ['tp5n']}}, 23:26:53 INFO - 'talos.zip': {'path': '', 23:26:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:26:53 INFO - Running post-action listener: _resource_record_post_action 23:26:53 INFO - [mozharness: 2016-10-03 06:26:53.091982Z] Finished populate-webroot step (success) 23:26:53 INFO - [mozharness: 2016-10-03 06:26:53.092095Z] Running create-virtualenv step. 23:26:53 INFO - Running pre-action listener: _resource_record_pre_action 23:26:53 INFO - Running main action method: create_virtualenv 23:26:53 INFO - Creating virtualenv /builds/slave/test/build/venv 23:26:53 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 23:26:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:26:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:26:53 INFO - Using real prefix '/tools/python27' 23:26:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:26:53 INFO - Installing distribute.............................................................................................................................................................................................done. 23:26:56 INFO - Installing pip.................done. 23:26:56 INFO - Return code: 0 23:26:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:26:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:26:57 INFO - Reading from file tmpfile_stdout 23:26:57 INFO - Output received: 23:26:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 23:26:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:26:57 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')]} 23:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:57 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')]} 23:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:57 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 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:26:57 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 23:26:57 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 23:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:26:57 INFO - 'HOME': '/Users/cltbld', 23:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:26:57 INFO - 'LOGNAME': 'cltbld', 23:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:57 INFO - 'MOZ_NO_REMOTE': '1', 23:26:57 INFO - 'NO_EM_RESTART': '1', 23:26:57 INFO - 'PAGER': '/bin/cat', 23:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:57 INFO - 'PWD': '/builds/slave/test', 23:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:26:57 INFO - 'SHELL': '/bin/bash', 23:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:26:57 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:26:57 INFO - 'USER': 'cltbld', 23:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:57 INFO - 'XPC_FLAGS': '0x0', 23:26:57 INFO - 'XPC_SERVICE_NAME': '0', 23:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:57 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 23:26:57 INFO - Cleaning up... 23:26:57 INFO - Return code: 0 23:26:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:26:57 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')]} 23:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:26:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:57 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')]} 23:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:26:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:26:57 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 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:26:57 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 23:26:57 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 23:26:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:26:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:26:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:26:57 INFO - 'HOME': '/Users/cltbld', 23:26:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:26:57 INFO - 'LOGNAME': 'cltbld', 23:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:26:57 INFO - 'MOZ_NO_REMOTE': '1', 23:26:57 INFO - 'NO_EM_RESTART': '1', 23:26:57 INFO - 'PAGER': '/bin/cat', 23:26:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:26:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:26:57 INFO - 'PWD': '/builds/slave/test', 23:26:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:26:57 INFO - 'SHELL': '/bin/bash', 23:26:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:26:57 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:26:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:26:57 INFO - 'USER': 'cltbld', 23:26:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:26:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:26:57 INFO - 'XPC_FLAGS': '0x0', 23:26:57 INFO - 'XPC_SERVICE_NAME': '0', 23:26:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:26:57 INFO - Downloading/unpacking psutil>=3.1.1 23:26:57 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 23:26:57 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 23:26:57 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 23:26:57 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 23:26:57 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 23:26:57 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 23:27:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:27:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:27:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:27:01 INFO - Installing collected packages: psutil 23:27:01 INFO - Running setup.py install for psutil 23:27:01 INFO - building 'psutil._psutil_osx' extension 23:27:01 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 23:27:01 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 23:27:01 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 23:27:01 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 23:27:01 INFO - building 'psutil._psutil_posix' extension 23:27:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 23:27:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:27:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:27:01 INFO - ^ 23:27:01 INFO - 1 warning generated. 23:27:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 23:27:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:27:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:27:02 INFO - Successfully installed psutil 23:27:02 INFO - Cleaning up... 23:27:02 INFO - Return code: 0 23:27:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:27:02 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')]} 23:27:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:02 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')]} 23:27:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:02 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 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:27:02 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 23:27:02 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 23:27:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:27:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:02 INFO - 'HOME': '/Users/cltbld', 23:27:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:02 INFO - 'LOGNAME': 'cltbld', 23:27:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:02 INFO - 'MOZ_NO_REMOTE': '1', 23:27:02 INFO - 'NO_EM_RESTART': '1', 23:27:02 INFO - 'PAGER': '/bin/cat', 23:27:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:02 INFO - 'PWD': '/builds/slave/test', 23:27:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:02 INFO - 'SHELL': '/bin/bash', 23:27:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:27:02 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:27:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:02 INFO - 'USER': 'cltbld', 23:27:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:02 INFO - 'XPC_FLAGS': '0x0', 23:27:02 INFO - 'XPC_SERVICE_NAME': '0', 23:27:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:27:02 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 23:27:02 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 23:27:02 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 23:27:02 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 23:27:02 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 23:27:02 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 23:27:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:27:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:27:04 INFO - Installing collected packages: mozsystemmonitor 23:27:04 INFO - Running setup.py install for mozsystemmonitor 23:27:05 INFO - Successfully installed mozsystemmonitor 23:27:05 INFO - Cleaning up... 23:27:05 INFO - Return code: 0 23:27:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:05 INFO - 'HOME': '/Users/cltbld', 23:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:05 INFO - 'LOGNAME': 'cltbld', 23:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:05 INFO - 'MOZ_NO_REMOTE': '1', 23:27:05 INFO - 'NO_EM_RESTART': '1', 23:27:05 INFO - 'PAGER': '/bin/cat', 23:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:05 INFO - 'PWD': '/builds/slave/test', 23:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:05 INFO - 'SHELL': '/bin/bash', 23:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:27:05 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:05 INFO - 'USER': 'cltbld', 23:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:05 INFO - 'XPC_FLAGS': '0x0', 23:27:05 INFO - 'XPC_SERVICE_NAME': '0', 23:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:05 INFO - Downloading/unpacking jsonschema==2.5.1 23:27:05 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 23:27:05 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 23:27:05 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 23:27:05 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 23:27:05 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 23:27:05 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 23:27:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:27:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:08 INFO - Downloading functools32-3.2.3-2.tar.gz 23:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:27:08 INFO - warning: no files found matching '*.txt' 23:27:08 INFO - no previously-included directories found matching 'build' 23:27:08 INFO - no previously-included directories found matching 'dist' 23:27:08 INFO - no previously-included directories found matching '.git*' 23:27:08 INFO - Installing collected packages: jsonschema, functools32 23:27:08 INFO - Running setup.py install for functools32 23:27:08 INFO - warning: no files found matching '*.txt' 23:27:08 INFO - no previously-included directories found matching 'build' 23:27:08 INFO - no previously-included directories found matching 'dist' 23:27:08 INFO - no previously-included directories found matching '.git*' 23:27:08 INFO - Successfully installed jsonschema functools32 23:27:08 INFO - Cleaning up... 23:27:08 INFO - Return code: 0 23:27:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:27:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:08 INFO - 'HOME': '/Users/cltbld', 23:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:08 INFO - 'LOGNAME': 'cltbld', 23:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:08 INFO - 'MOZ_NO_REMOTE': '1', 23:27:08 INFO - 'NO_EM_RESTART': '1', 23:27:08 INFO - 'PAGER': '/bin/cat', 23:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:08 INFO - 'PWD': '/builds/slave/test', 23:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:08 INFO - 'SHELL': '/bin/bash', 23:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:27:08 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:08 INFO - 'USER': 'cltbld', 23:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:08 INFO - 'XPC_FLAGS': '0x0', 23:27:08 INFO - 'XPC_SERVICE_NAME': '0', 23:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:27:08 INFO - Cleaning up... 23:27:08 INFO - Return code: 0 23:27:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:27:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:08 INFO - 'HOME': '/Users/cltbld', 23:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:08 INFO - 'LOGNAME': 'cltbld', 23:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:08 INFO - 'MOZ_NO_REMOTE': '1', 23:27:08 INFO - 'NO_EM_RESTART': '1', 23:27:08 INFO - 'PAGER': '/bin/cat', 23:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:08 INFO - 'PWD': '/builds/slave/test', 23:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:08 INFO - 'SHELL': '/bin/bash', 23:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:27:08 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:08 INFO - 'USER': 'cltbld', 23:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:08 INFO - 'XPC_FLAGS': '0x0', 23:27:08 INFO - 'XPC_SERVICE_NAME': '0', 23:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:09 INFO - Downloading/unpacking blobuploader==1.2.4 23:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:11 INFO - Downloading blobuploader-1.2.4.tar.gz 23:27:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:27:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:27:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:12 INFO - Downloading docopt-0.6.1.tar.gz 23:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:27:12 INFO - Installing collected packages: blobuploader, requests, docopt 23:27:12 INFO - Running setup.py install for blobuploader 23:27:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:27:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:27:12 INFO - Running setup.py install for requests 23:27:13 INFO - Running setup.py install for docopt 23:27:13 INFO - Successfully installed blobuploader requests docopt 23:27:13 INFO - Cleaning up... 23:27:13 INFO - Return code: 0 23:27:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:27:13 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')]} 23:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:13 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')]} 23:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:13 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 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:27:13 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 23:27:13 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 23:27:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:27:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:13 INFO - 'HOME': '/Users/cltbld', 23:27:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:13 INFO - 'LOGNAME': 'cltbld', 23:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:13 INFO - 'MOZ_NO_REMOTE': '1', 23:27:13 INFO - 'NO_EM_RESTART': '1', 23:27:13 INFO - 'PAGER': '/bin/cat', 23:27:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:13 INFO - 'PWD': '/builds/slave/test', 23:27:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:13 INFO - 'SHELL': '/bin/bash', 23:27:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:27:13 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:27:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:13 INFO - 'USER': 'cltbld', 23:27:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:13 INFO - 'XPC_FLAGS': '0x0', 23:27:13 INFO - 'XPC_SERVICE_NAME': '0', 23:27:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:27:13 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ipc3GE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:27:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-xIFP4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:27:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-d1iuaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:27:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-laLh_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:27:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-aUb4mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:27:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-KdZkXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:27:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-1fph5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:27:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-hGoiqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:27:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-WZSjPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:27:14 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-jKkUTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:27:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-aHGcI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:27:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-fiKeFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:27:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-vh3uag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:27:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-SWGmBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:27:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-c7zCoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:27:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-600MlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:27:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:27:15 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-O5XxEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:27:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:27:15 INFO - Running setup.py install for manifestparser 23:27:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:27:15 INFO - Running setup.py install for mozcrash 23:27:16 INFO - Running setup.py install for mozdebug 23:27:16 INFO - Running setup.py install for mozdevice 23:27:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:27:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:27:16 INFO - Running setup.py install for mozfile 23:27:16 INFO - Running setup.py install for mozhttpd 23:27:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:27:16 INFO - Running setup.py install for mozinfo 23:27:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:27:16 INFO - Running setup.py install for mozInstall 23:27:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:27:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:27:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:27:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:27:16 INFO - Running setup.py install for mozleak 23:27:17 INFO - Running setup.py install for mozlog 23:27:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:27:17 INFO - Running setup.py install for moznetwork 23:27:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:27:17 INFO - Running setup.py install for mozprocess 23:27:17 INFO - Running setup.py install for mozprofile 23:27:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:27:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:27:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:27:17 INFO - Running setup.py install for mozrunner 23:27:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:27:17 INFO - Running setup.py install for mozscreenshot 23:27:18 INFO - Running setup.py install for moztest 23:27:18 INFO - Running setup.py install for mozversion 23:27:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:27:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:27:18 INFO - Cleaning up... 23:27:18 INFO - Return code: 0 23:27:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:27:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:18 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 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:27:18 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 23:27:18 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 23:27:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:27:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:18 INFO - 'HOME': '/Users/cltbld', 23:27:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:18 INFO - 'LOGNAME': 'cltbld', 23:27:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:18 INFO - 'MOZ_NO_REMOTE': '1', 23:27:18 INFO - 'NO_EM_RESTART': '1', 23:27:18 INFO - 'PAGER': '/bin/cat', 23:27:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:18 INFO - 'PWD': '/builds/slave/test', 23:27:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:18 INFO - 'SHELL': '/bin/bash', 23:27:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:27:18 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:27:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:18 INFO - 'USER': 'cltbld', 23:27:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:18 INFO - 'XPC_FLAGS': '0x0', 23:27:18 INFO - 'XPC_SERVICE_NAME': '0', 23:27:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:27:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-MYIWBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:27:18 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)) 23:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:27:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-zpvPkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:27:18 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)) 23:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:27:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-e9Dony-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:27:18 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)) 23:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:27:18 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-70a8az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:27:19 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)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-do7Ldh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:27:19 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)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-j4NQFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:27:19 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)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-AFklMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:27:19 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)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-bRKFEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:27:19 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)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-3VQu7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:27:19 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)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-FWZkQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:27:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-KLusCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:27:19 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)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-3cB_Ls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:27:19 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)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-FFyaJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:27:19 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)) 23:27:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:27:19 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-0rPBry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:27:20 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)) 23:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:27:20 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-DeNjxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:27:20 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)) 23:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:27:20 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-tQlNxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:27:20 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)) 23:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:27:20 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-pvjg_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:27:20 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)) 23:27:20 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)) 23:27:20 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)) 23:27:20 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)) 23:27:20 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)) 23:27:20 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)) 23:27:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:27:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:27:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:27:22 INFO - Downloading blessings-1.6.tar.gz 23:27:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:27:22 INFO - Installing collected packages: blessings 23:27:22 INFO - Running setup.py install for blessings 23:27:23 INFO - Successfully installed blessings 23:27:23 INFO - Cleaning up... 23:27:23 INFO - Return code: 0 23:27:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:27:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:27:23 INFO - Reading from file tmpfile_stdout 23:27:23 INFO - Current package versions: 23:27:23 INFO - blessings == 1.6 23:27:23 INFO - blobuploader == 1.2.4 23:27:23 INFO - docopt == 0.6.1 23:27:23 INFO - functools32 == 3.2.3-2 23:27:23 INFO - jsonschema == 2.5.1 23:27:23 INFO - manifestparser == 1.1 23:27:23 INFO - mozInstall == 1.12 23:27:23 INFO - mozcrash == 0.17 23:27:23 INFO - mozdebug == 0.1 23:27:23 INFO - mozdevice == 0.48 23:27:23 INFO - mozfile == 1.2 23:27:23 INFO - mozhttpd == 0.7 23:27:23 INFO - mozinfo == 0.9 23:27:23 INFO - mozleak == 0.1 23:27:23 INFO - mozlog == 3.4 23:27:23 INFO - moznetwork == 0.27 23:27:23 INFO - mozprocess == 0.23 23:27:23 INFO - mozprofile == 0.28 23:27:23 INFO - mozrunner == 6.12 23:27:23 INFO - mozscreenshot == 0.1 23:27:23 INFO - mozsystemmonitor == 0.3 23:27:23 INFO - moztest == 0.7 23:27:23 INFO - mozversion == 1.4 23:27:23 INFO - psutil == 3.1.1 23:27:23 INFO - requests == 1.2.3 23:27:23 INFO - wsgiref == 0.1.2 23:27:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:23 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 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:27:23 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 23:27:23 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 23:27:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:27:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:23 INFO - 'HOME': '/Users/cltbld', 23:27:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:23 INFO - 'LOGNAME': 'cltbld', 23:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:23 INFO - 'MOZ_NO_REMOTE': '1', 23:27:23 INFO - 'NO_EM_RESTART': '1', 23:27:23 INFO - 'PAGER': '/bin/cat', 23:27:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:23 INFO - 'PWD': '/builds/slave/test', 23:27:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:23 INFO - 'SHELL': '/bin/bash', 23:27:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:27:23 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:27:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:23 INFO - 'USER': 'cltbld', 23:27:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:23 INFO - 'XPC_FLAGS': '0x0', 23:27:23 INFO - 'XPC_SERVICE_NAME': '0', 23:27:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:23 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)) 23:27:23 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)) 23:27:23 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)) 23:27:23 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)) 23:27:23 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)) 23:27:23 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)) 23:27:23 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)) 23:27:23 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)) 23:27:23 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)) 23:27:23 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)) 23:27:23 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)) 23:27:23 INFO - Cleaning up... 23:27:23 INFO - Return code: 0 23:27:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:27:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:27:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:27:23 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 0x10e0d0490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e046570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce4b4d80d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, '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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 23:27:23 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 23:27:23 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 23:27:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:27:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:23 INFO - 'HOME': '/Users/cltbld', 23:27:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:23 INFO - 'LOGNAME': 'cltbld', 23:27:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:23 INFO - 'MOZ_NO_REMOTE': '1', 23:27:23 INFO - 'NO_EM_RESTART': '1', 23:27:23 INFO - 'PAGER': '/bin/cat', 23:27:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:23 INFO - 'PWD': '/builds/slave/test', 23:27:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:23 INFO - 'SHELL': '/bin/bash', 23:27:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:27:23 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:27:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:23 INFO - 'USER': 'cltbld', 23:27:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:23 INFO - 'XPC_FLAGS': '0x0', 23:27:23 INFO - 'XPC_SERVICE_NAME': '0', 23:27:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:27:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:27:24 INFO - Cleaning up... 23:27:24 INFO - Return code: 0 23:27:24 INFO - Running post-action listener: _resource_record_post_action 23:27:24 INFO - Running post-action listener: _start_resource_monitoring 23:27:24 INFO - Starting resource monitoring. 23:27:24 INFO - [mozharness: 2016-10-03 06:27:24.057766Z] Finished create-virtualenv step (success) 23:27:24 INFO - [mozharness: 2016-10-03 06:27:24.058759Z] Running install step. 23:27:24 INFO - Running pre-action listener: _resource_record_pre_action 23:27:24 INFO - Running main action method: install 23:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:27:24 INFO - Reading from file tmpfile_stdout 23:27:24 INFO - Detecting whether we're running mozinstall >=1.0... 23:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:27:24 INFO - Reading from file tmpfile_stdout 23:27:24 INFO - Output received: 23:27:24 INFO - Usage: mozinstall [options] installer 23:27:24 INFO - Options: 23:27:24 INFO - -h, --help show this help message and exit 23:27:24 INFO - -d DEST, --destination=DEST 23:27:24 INFO - Directory to install application into. [default: 23:27:24 INFO - "/builds/slave/test"] 23:27:24 INFO - --app=APP Application being installed. [default: firefox] 23:27:24 INFO - mkdir: /builds/slave/test/build/application 23:27:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:27:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:27:58 INFO - Reading from file tmpfile_stdout 23:27:58 INFO - Output received: 23:27:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:27:58 INFO - Running post-action listener: _resource_record_post_action 23:27:58 INFO - [mozharness: 2016-10-03 06:27:58.701052Z] Finished install step (success) 23:27:58 INFO - [mozharness: 2016-10-03 06:27:58.701185Z] Running run-tests step. 23:27:58 INFO - Running pre-action listener: _resource_record_pre_action 23:27:58 INFO - Running main action method: run_tests 23:27:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:27:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:27:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:27:58 INFO - 2016-10-02 23:27:58.715 screenresolution[1934:19583] starting screenresolution argv=screenresolution get 23:27:58 INFO - 2016-10-02 23:27:58.718 screenresolution[1934:19583] Display 0: 1600x1200x32@60 23:27:58 INFO - 2016-10-02 23:27:58.724 screenresolution[1935:19585] starting screenresolution argv=screenresolution list 23:27:58 INFO - Available Modes on Display 0 23:27:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:27:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:27:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:27:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:27:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:27:58 INFO - Intel Iris: 23:27:58 INFO - Chipset Model: Intel Iris 23:27:58 INFO - Type: GPU 23:27:58 INFO - Bus: Built-In 23:27:58 INFO - VRAM (Dynamic, Max): 1536 MB 23:27:58 INFO - Vendor: Intel (0x8086) 23:27:58 INFO - Device ID: 0x0a2e 23:27:58 INFO - Revision ID: 0x0009 23:27:58 INFO - Displays: 23:27:58 INFO - Display: 23:27:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:27:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:27:58 INFO - Display Serial Number: 1600x1200 60 23:27:58 INFO - Main Display: Yes 23:27:58 INFO - Mirror: Off 23:27:58 INFO - Online: Yes 23:27:58 INFO - Rotation: Supported 23:27:58 INFO - Return code: 0 23:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:27:58 INFO - Python 2.7.3 23:27:58 INFO - Return code: 0 23:27:58 INFO - grabbing minidump binary from tooltool 23:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:27:58 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 0x10dee4d30>, '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 0x10dd0adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dedcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:27:58 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 23:27:58 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 23:27:58 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 23:27:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:27:59 INFO - Return code: 0 23:27:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:27:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:27:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:27:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:27:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 23:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 23:27:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vWwPkFjU9o/Render', 23:27:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:27:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:27:59 INFO - 'HOME': '/Users/cltbld', 23:27:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:27:59 INFO - 'LOGNAME': 'cltbld', 23:27:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:27:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:27:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:27:59 INFO - 'MOZ_NO_REMOTE': '1', 23:27:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:27:59 INFO - 'NO_EM_RESTART': '1', 23:27:59 INFO - 'PAGER': '/bin/cat', 23:27:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:27:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:27:59 INFO - 'PWD': '/builds/slave/test', 23:27:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:27:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:27:59 INFO - 'SHELL': '/bin/bash', 23:27:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dLaYGHV1gD/Listeners', 23:27:59 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 23:27:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:27:59 INFO - 'USER': 'cltbld', 23:27:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:27:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:27:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:27:59 INFO - 'XPC_FLAGS': '0x0', 23:27:59 INFO - 'XPC_SERVICE_NAME': '0', 23:27:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:27:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 23:27:59 INFO - mozversion application_buildid: 20161002200656 23:27:59 INFO - mozversion application_changeset: 2a14359213efbbe5b8ea31cbba0cd8ba8fa16550 23:27:59 INFO - mozversion application_display_name: Nightly 23:27:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:27:59 INFO - mozversion application_name: Firefox 23:27:59 INFO - mozversion application_remotingname: firefox 23:27:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:27:59 INFO - mozversion application_vendor: Mozilla 23:27:59 INFO - mozversion application_version: 52.0a1 23:27:59 INFO - mozversion platform_buildid: 20161002200656 23:27:59 INFO - mozversion platform_changeset: 2a14359213efbbe5b8ea31cbba0cd8ba8fa16550 23:27:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:27:59 INFO - mozversion platform_version: 52.0a1 23:27:59 INFO - using testdate: 1475476079 23:27:59 INFO - actual date: 1475476079 23:27:59 INFO - starting webserver on 'localhost:54341' 23:27:59 INFO - SUITE-START | Running 2 tests 23:27:59 INFO - TEST-START | dromaeo_css 23:27:59 INFO - operating with platform_type : mac_ 23:27:59 INFO - Initialising browser for dromaeo_css test... 23:27:59 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmp7w1r_o/profile http://localhost:54341/getInfo.html) 23:28:02 INFO - PROCESS | 1945 | 2016-10-02 23:28:02.260 plugin-container[1947:19735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b43, name = 'com.apple.tsm.portname' 23:28:02 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:28:02 INFO - PROCESS | 1945 | 2016-10-02 23:28:02.263 plugin-container[1947:19735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.CFPasteboardClient' 23:28:02 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:28:02 INFO - PROCESS | 1945 | 2016-10-02 23:28:02.263 plugin-container[1947:19735] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:28:02 INFO - PROCESS | 1945 | __metrics Screen width/height:1600/1200 23:28:02 INFO - PROCESS | 1945 | colorDepth:24 23:28:02 INFO - PROCESS | 1945 | Browser inner width/height: 1024/689 23:28:02 INFO - PROCESS | 1945 | __metrics 23:28:02 ERROR - PROCESS | 1945 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 23:28:03 INFO - TEST-INFO | 1945: exit 0 23:28:03 INFO - Browser initialized. 23:28:03 INFO - Running cycle 1/1 for dromaeo_css test... 23:28:03 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmp7w1r_o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:28:05 INFO - PROCESS | 1948 | 2016-10-02 23:28:05.339 plugin-container[1949:19855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.tsm.portname' 23:28:05 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:28:05 INFO - PROCESS | 1948 | 2016-10-02 23:28:05.342 plugin-container[1949:19855] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 23:28:05 INFO - PROCESS | 1948 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:28:05 INFO - PROCESS | 1948 | 2016-10-02 23:28:05.342 plugin-container[1949:19855] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:28:15 INFO - PROCESS | 1948 | RSS: Main: 240734208 23:28:15 INFO - PROCESS | 1948 | 23:29:04 INFO - PROCESS | 1948 | 1475476144689 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] 23:31:18 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54341/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54341/tests/dromaeo/cssquery-ext.html) 23:31:19 INFO - PROCESS | 1948 | RSS: Main: 252526592 23:31:19 INFO - PROCESS | 1948 | 23:34:21 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54341/tests/dromaeo/cssquery-ext.html (next: http://localhost:54341/tests/dromaeo/cssquery-jquery.html) 23:34:22 INFO - PROCESS | 1948 | RSS: Main: 265564160 23:34:22 INFO - PROCESS | 1948 | 23:37:24 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54341/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54341/tests/dromaeo/cssquery-mootools.html) 23:37:25 INFO - PROCESS | 1948 | RSS: Main: 271626240 23:37:25 INFO - PROCESS | 1948 | 23:40:27 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54341/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54341/tests/dromaeo/cssquery-prototype.html) 23:40:27 INFO - PROCESS | 1948 | RSS: Main: 271396864 23:40:27 INFO - PROCESS | 1948 | 23:43:30 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54341/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54341/tests/dromaeo/cssquery-yui.html) 23:43:31 INFO - PROCESS | 1948 | RSS: Main: 276791296 23:43:31 INFO - PROCESS | 1948 | 23:46:33 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54341/tests/dromaeo/cssquery-yui.html (next: http://localhost:54341/tests/dromaeo/cssquery-dojo.html) 23:46:34 INFO - PROCESS | 1948 | RSS: Main: 279515136 23:46:34 INFO - PROCESS | 1948 | 23:46:34 INFO - PROCESS | 1948 | __start_tp_report 23:46:34 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 23:46:34 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 23:46:34 INFO - PROCESS | 1948 | |i|pagename|runs| 23:46:34 INFO - PROCESS | 1948 | |0;dojo.html;3347.5146103814945;3410.317936412718;3453.223024819416;3463.7402194835395;3464.3417750627377;5706.885862282752;5833.5041521470675;5860.208871802311;5860.384659610738;5898.52811775058;5767.279090113736;5874.1776151338845;5877.647341159533;5971.522278217743;6056.303525094615;4341.544137865526;5130;5216.295800067;5218.608604354669;5282.418933917266;4363.869083927487;4410.029793445437;4521.344405061264;5394.622376433651;5400.567954563645;5222.895542089137;5350.5452036576935;5352.759125839327;5360.3567571891235;5386.030514507387;5236.764345604457;5258.947410525922;5271.077561426735;5281.445448227958;5318.069337865858;5164.277001219794;5169.81905633301;5185.688858668492;5281.788728450896;5298;5080.949190508085;5552.278203833516;5916.822495325147;5940;5943.3462319144855;6095.589475041383;6215.223097112861;6246;6255.374462553754;6265.404786545271;6261.843453913643;6321.020241862519;6323.588966717149;6327.27236367819;6340.144080549113;5832.766689332464;5888.411158884077;6032.66820324882;6048.304444988795;6061;5658.179563963202;6282.089097080898;6315.115883776275;6324.367563243731;6409.455196308366;6066.302375226817;6208.81373558794;6209.875802484015;6227.719752611053;6290.937090629035;5447.210154527571;6119.015143218386;6277.591956522811;6293.930031894589;6320.336364681734;6103.064938160023;6189.35011823761;6202.4417802398;6208.689565521706;6223.222097237845;6225.3774622537385;6241.656708880964;6242.625442473466;6249.593776404519;6288.496920246369;5003.949960500421;5027.748612569357;5033.345665063518;5038.521340472649;5062.962092771047;3663.7618554793853;4410.470743510799;4422.690411671152;4428.003699167662;4495.235809912323;5563.693996830132;5993.820185394445;6075.362086980892;6092.878142437126;6101.267847858286;6281.963476026496;6315.747370105236;6324.3991820776955;6326.145970293951;6355.682215889279;5873.060310350324;5944.138099975566;6033.577649564489;6057.57596968208;6086.3000754913755;5480.369757477941;5529.640573362719;5539.695316757617;5542.251796007487;5563.666180029211;6451.870962580816;6456.612603243821;6466.22405311375;6473.708683109366;6476.643784591704;6158.599691020069;6198.783042593487;6208.627482351073;6208.782692605737;6334.841628959129;6447.70985305653;6449.193850768654;6455.128557644658;6469.77355792545;6488.221413430419;5411.431799661136;6280.277768056732;6290.591111577733;6300.873982520416;6338.809835704937;5351.812686556108;5989.802039592186;6196.039613859859;6283.246010478589;6290.2766181889665;5256.842294731164;5810.244668193108;6049.485793707584;6154.815355539341;6202.937970620236;4819.734914579732;5974.522038236843;6174.197354343906;6184.938150618616;6246.125542424155;5834.912476312944;5853.9121913172185;5883.35283118848;6008.24896887889;6137.662428566293;6046.274447066205;6051.909221361595;6082.452579267887;6095.05526643371;6113.816585502442;5777.537796976316;5834.474897259267;5851.970740146442;5877.353491115889;5901.557383196192 23:46:34 INFO - PROCESS | 1948 | |1;ext.html;476.5448996208621;500.0773572758261;502.3720349563046;511.00864323212966;533.14696485623;40037.39943900844;41621;41680.95797605062;41723;41763.95590110249;42970;43298.7010389688;43449.13101737971;43487;43496.782516087456;9706.902930970706;9881.654142105028;9893.851592226123;9899.703008909744;9925.851112233322;9794.412335259907;9864.210863130967;9868.210543156567;9871.259655525828;9897.15874150698;9907.554160062815;9928.553215105338;9929;9938.006199380077;9959.053889880486;9861.802763944717;9901.257405694601;9920.057594528544;9928;9977.201823854075;9716.708498745049;9792.21662267017;9800.31397802154;9822.557984890696;9842;40622.39066414006;40873.795631021654;41008.79495602503;41215;41322;2772.8336299822076;3288.4738441849427;3299.7195238404765;3355.4938231583064;3384.1539615096226;24419;31085.689143108506;31232.687673123433;31771.887983920453;31993.840030799696;3252.5771649685626;3270.444024515838;3339.1652086978256;3361.865525378982;3383.8308084595674;433513.83243083896;494682;495706.52146739396;500425.4978725083;501606.49196754146;58287.70856145692;58304;58606.413935860095;58739.41260587425;58914.527136821926;60882.39117608767;61387.6930615353;61534.69232653898;61839.76320473565;62780.37219627837;15245.466408675644;15299.852511061716;15335.693286134216;15385.38458461649;15409.614759631098;11837.171398002058;12249.816252756216;12269.509219631114;12271.815922761165;12311.19977201479;11587.88412115868;15873.682526349408;16081;16133.354665813471;16366;2763.9723602763715;5479.561635069185;6269.373062693785;6497.577657452251;6510.316416776171;858.5921687198655;898.4654130792168;909.1590278991906;957.674390707163;1032.194887987371;29298.121056367916;29659;30042.84978575137;30789.692103078683;30813.845930770203;1015.8527013582989;1016.1362841584594;1063.2982231727024;1092.6721983405105;1105.9170562207867;14834.332455039548;15068.849311506745;15656.217189140725;15734.055956642638;15787.763183552255;660060;666939;670765;671605;672452;57597.712011439675;58145;58177.70911145417;58181.12728309079;58195;1318.8681131886735;1335.5325636027312;1337.25782190885;1340.9731805364033;1347.036868638928;29120.854395727885;29436.969706060187;29610.555841662823;30078;30301;49719.25421118614;50077.49922500874;50115;50245.74877125591;50251.74874125606;567139;567362;568183;569567;571914;24032;26390;26800.865995669898;26818.731812682403;26842;45626.54373456356;45963;46352.072958541314;46516.53483465257;47989.28016079831;37361.81319093387;38324.61675383322;38993.61006390013;39488;40401.39397909093;32283;38815.7894736852;38850;39467.605323947544;40124.19751604893;33221.667783322824;33333.66666333305;33783.66216337805;33834.8308258467;33905.83047084749;28221.871125155587;34193;34451.349873216524;34857.47713784346;35019.949401517995;25356.619650705623;25532;25539.616905746057;25579.36051598725;26402.867985660716 23:46:34 INFO - PROCESS | 1948 | |2;jquery.html;293.2712210157759;318.85492101094;336.8815532432325;377.91496913194527;394.22337994151513;13560.593182204526;17185.226664800084;17707;18637;21236.83197424141;16542.503724888273;17793.82206177941;20361.49096272594;20366.98165091747;20665.17339306426;5686.744096515657;6060.363661815512;6103.389661033883;6104.206453161083;6115.082737589352;5709.600327977043;5943.524518038546;6029.457348838604;6066;6067.544934129935;5146.614003949719;5992.730327135289;6079.240094988127;6109.755609775603;6127.417895299939;5519.724013799314;6001.579889407743;6674.965380366051;6732.057511948331;6733.293004234534;5820.068788993783;5827.125931110368;5928.911066334009;5935.990881550147;5950.315713692938;12637.2417654941;12982.221066736027;12986;13006;13935.651608709763;2205.9448513787283;2337.9649305260436;2491.862947537885;2642.220544939228;2935.941281174386;9612.759681007961;9658.806823863484;9870.802583948282;10130.594776209016;10205.2346074044;1795.3626462605848;1805.6208196278797;1823.298030258347;1929.1415320182525;1940.078462730206;118326;122701.38649306785;124381;124431;125354.99999999908;10879.83680244797;11072.280301780358;11226.831597526043;11293.774124517464;11429.142814288962;14185.290735463186;14215.786763198561;14221.217833019284;14237.145771253758;14245.85754142466;8129.349652027823;8368.58157092143;8399.832003360021;8512.40413171072;8524.914750852537;8281.917180828235;8373.706920257762;8394.370422218359;8517.48895066298;8553.74338769838;3507.3686736387695;4418.403515525363;4424.225760491901;4485.394471746337;4563.247064234364;1895.1661269041579;1911.9904400478188;1916.1377380178976;1943.7959014303578;1974.2892558678864;678.0266743436945;694.5034300475185;708.8121647763347;841.8863453433707;948.0850978805348;20346.491337716674;20784.48038799042;20981.06265122884;21028.47428814292;21119.472013199793;730.6602429870136;890.4817286526456;929.7489677786959;932.5989824375583;961.2694352292308;9103.908960910307;10433.32183408076;10853.565857365616;10896.346219226712;10899.509522071527;554220;560714;563423;592049;592618;86827;130472;136503.31748341394;138750;146865;926.1664501948301;982.9852552211865;1013.341328136717;1013.5337744637402;1013.6756238003916;17159.31362745526;17962;18896.62206755885;19275.325363612206;19357.225710971405;54051;54554.72722636361;55286.44713552813;56208.718956404955;56595;61538.692306539975;62808.68595656993;77920.61039694765;80650.5967470159;81898;7917.030394413242;7985.401094917788;8003.799905002422;8027.146969454811;8177.713780017909;8950.328725345495;9826.65606703762;12236.388180590757;12586.811197832223;12735.29955852437;9941.950290248502;10704.197185210984;10970.122390208924;11044.723881903017;11346.489407976826;10404.907484714298;10552.84170737455;11032.558697652004;11105.722356940818;11217.775644487228;11267;11567.479463423997;11662.241954272946;11685.76628467443;11691.356975366436;7768.805779855549;7917.881231781643;7928.445008849325;8000.799980000546;8104.797380065545;10648.254622176682;11122.666320010412;11157.776844462904;11296;11467 23:46:34 INFO - PROCESS | 1948 | |3;mootools.html;1518.445767294937;1550.054466775267;1780.3412737287206;1838.2555065198599;1879.774427068752;7976.561289129096;8232.341412686985;8419.452735572184;9091.909080909189;9128.406653567514;7747.380209583234;8453.57732113395;8800.207981281683;8821.955890220555;8886.20024197822;2141.9250326238584;2155.051777218027;2307.0195167053976;2340.754220806816;2348.178137651825;2315.3748487908256;2326.430024643956;2361.948932724938;2364.054378248697;2369.4431808524982;1922.1734654098732;1959.2163134746072;1963.6956271777897;1968.911398987049;1971.802819718031;2048.56980034193;2299.1952816514254;2327.3367090379247;2328.2549584133085;2348.424635964191;2315.0623997281127;2324.7210334759766;2328.429534763977;2331.5919714049974;2332.288651961151;8492.617832197566;8527.744167674917;8589.184027517376;8590.484570925764;8629.180227878342;5124.897502049938;5287.735613219362;5313.681179129226;5361;5372.650777699476;3476.217850983534;3538.832185378819;3691.6123807000145;3696.316181506403;3702.9444558331647;1063.7712891728238;1092.5902786455079;1097.9615713449991;1103.8399432082304;1110.1119104716195;11296.491657875498;11575.479103440366;11581.420928953603;11638.709032274177;11643.126765492621;4547.430701653621;4666.486686464486;4754.524547545287;4756.357891684647;4779.4264688236935;9863.901360986441;9885.654002109892;9936.205103591696;9945.45300008492;9955.601775928882;5308.336457942757;5320.813771517978;5323.920141197885;5333;5341.412444631087;4380.0582874682095;4553.544645535486;4838.371011768448;4902.676277405152;4905.460399356068;3858.5369755629504;3892.163184915257;3986.621270979252;3997.960020399817;4018.3972404139026;1264.2856785916113;1264.3804535777404;1264.5826877130526;1282.5895713371817;1290.4967062845499;866.8873046503915;881.4975463985595;882.7175303902818;913.8081002989313;934.1405906565975;4895.877603059881;5375.569954403638;5784.566157538202;5843;5869.090291004901;418.2221068812007;424.0966740841987;432.414078923061;434.7760903134888;438.37750394439973;3840.6749671363336;3861.826217820205;3882;3901.843926242975;3926.5484469285825;9182.219511341476;10595.31130476532;10821.404822734825;10948.014678678957;10952.616658417077;10507.94746026265;10565.630202943014;10884.659798143437;10887.292325998786;10891.455427228608;8162.469439486415;8233.46482478637;8296.253337199681;8318.126596707258;8387.706430274911;5587.413321601173;5745.8563535911935;5765.538756899354;5774.335951365647;5857.912131318119;9928.25538084661;9972.30193886421;10106.39361638304;10154.898451015692;10164.136048435967;10200.504382132125;10304.175665946555;10308.433036183082;10323.94838025805;10416.21878359111;6865;7081.964590177016;7180.60506672138;7465.514741541782;7539.660715267936;8226;8395.412321137463;8438.367122465963;8462.957685211535;8470.49177049379;7365.300296471826;7518.210587888193;7519.060117485315;7583.810404739926;7628.237176282328;7273.818154545967;7296.19741828409;7369.742059027908;7443;7458.254174582716;6908.3437073477935;6922.238553759184;6929.099217101744;6976.6860491277;6990.895136573057;4923.796026951124;4947.134251506044;5019.84940451787;5020.297158397754;5078.08594453002;4870.855348992913;4887.780049897834;4981.42713587942;5004.224345226496;5074.720890351066 23:46:34 INFO - PROCESS | 1948 | |4;prototype.html;285.83806825255334;306.64582407319546;306.99232519187;313.35918047592673;320.6072561112639;4443.311286750551;4635.652326075545;5017.347744793176;5101.617378696604;5418.049502179212;4444.888877778058;4712.740799256039;4719.418994636792;5611.959389239741;5661.688607126606;3775.9811200943955;4280.850170244042;4302.741835489865;4309.525952145262;4319.697621166504;3236.87052517899;4238.732278231147;4328.696991210601;4337.414449049385;4340.283853164224;3525.5593050868642;4217.1987322408795;4235.047114399251;4236.97881510592;4245.34197199433;3798.9430158547643;4213.9578604213875;4231.196072746184;4232.724872883253;4263;2950.601668774709;3612.458131280329;3656.926861462756;3658.1586235165796;3673.3755261605593;5350.384705758848;5397.353807088836;5534.335879694422;5551.861203469905;5579.214651311582;4439.955600443986;4887.657863949525;4891.853244402673;4896.2410826321975;4900.950990490085;5631.436856314336;5846.392242133405;5974.312954010301;5978.581499295051;6018.879622407571;3681.950644066428;3695.5380577427823;3976.304146774303;4021.678265738734;4024.43657887893;5827.35899051104;5987.1318658794235;6287.622742635411;6353.555251132422;6389.616623002635;3579.570451545831;4041.595840415935;4055.371417430303;4071.450354202204;4084.305668036441;3315.2540678347177;3792.27946690128;4092.4884389451317;4094;4094.806051280006;4039.6768258539246;4811.566958973709;4824.517548245148;4841.636877234221;4846.491118432514;5190.584753219733;5999.400059993965;6023.488003519662;6030.969845150747;6039.939600604026;1185.3302883870585;1217.993910030444;1251.393074358935;1340.986590134086;1354.7629164896105;816.848882956655;823.5305875650942;858.1032820702382;862.0818827948215;866.8006358537572;4385.837752995459;4615.376924115269;4788.61691064714;4812.882331774008;4846.927296090562;4557.703749256288;4915.910737936363;5173.094708426086;5224.373075230997;5233.869153271199;4200.219802178003;4325.96679560606;4483.574060464251;4788.209945359046;4820.5420485054565;2947.572601972745;3248.7329941322905;3252.593425821772;3317.855339907728;3332.566766320363;6467.417932386108;6479.8056058318325;6489.967550162219;6497.902531462032;6524.706388212542;5063.468335824759;6305.274893387227;6330.335314791972;6347.809565712943;6353.459955903708;5272.971770504715;6453.515986300952;6466.321036291216;6484.383983521558;6486.772962946275;5855.473007429352;6161.383861613803;6200.224971878515;6288.434040936155;6307.490144546671;5414.8646283841645;5642.5485961122195;5918.556108291809;6130.325664176848;6216.906746398718;5345.732713364394;5973.8207853762715;6104.11490333908;6206.875862482815;6554.377334153248;3144.701253380925;4008.977710683757;4084.7926635565414;4087.3868919662286;4124.257633625856;5028.536017351398;6023.969880150571;6099.786507472217;6182.227221597301;6283.842903927255;5909.52723782105;5909.818036392825;5976.372480889444;6027.758889644365;6067.332593414632;5275.287836142072;5442.37412697529;5615.522680572197;6003.489703375262;6061.7575296987625;5004.77478513475;5229.450907654794;5891.293044834647;5899;5954.612950158225;6028.849278768066;6039.214902062704;6048.758049678139;6086.682757896869;6152.753889844356;5733.569982251264;5938.524918006636;5957.017092179742;5966.508372906774;5983.581149319506 23:46:34 INFO - PROCESS | 1948 | |5;yui.html;742.4876834983864;756.7654027251549;758.6320634492271;791.4894892794148;834.057515008041;15057.849421505782;15652.608684782886;15892.364305427784;16087.75868361974;16142.515724528283;14662.486812961548;14696.412143514246;14834.332455039523;14854.628634284149;14857.925710371434;539.2746755613713;727.5452841973766;853.3130829682103;876.0363600039937;876.198278575103;694.7498900395854;838.7944953486405;856.6530555125184;868.50929224988;873.244643383475;880.6477409036372;891.1934699097295;893.2853717026385;896.1173244354338;898.4384759525296;875.019977625064;885.7829831691213;897.8473659477904;900.8513595256775;901.3510272603747;640.6892657061321;649.7043845050491;672.1363048482676;672.4754691340762;673.1282988529816;14450.783238251435;14487;14525;14611.634709132357;14750.262486875721;12795.680107997281;14230.786538201935;14295.928520357436;14367.640808979755;14708.33812478441;11784.882151178464;11889.464974076187;11918.642440726791;11929.701757456047;11994.70013249667;2935.6088762029417;3636.345457817592;3770.754900931458;3858.9421158682644;3913.863014794497;102353;103513;103731.48134259356;106426;107474.46262768714;10811.6215932442;10857.782844343068;11003.724906877233;11182.36234229048;11314.434278286217;20192.69710954337;20332.898335508227;20443.795562044488;20447.488812779502;20495.69256461154;7650.579218143055;7730.072391313079;7738.303552680173;7887.2112788721815;8034.879476807852;4444.444444444444;4466.977665111654;4499.122671079108;4516.683832131719;4583.931241031387;3293.226091868409;3421.8289085545625;3429.5198672185916;3445.1214940190093;3450.1202193440513;620.3375744504084;652.7650045983537;654.1169421281327;667.5994403357946;667.6828506459393;588.8555115682277;594.7115648910275;644.2508589180397;667.4260136282793;673.1316601584;6873.76272270996;8274.586270686441;8717.520536370434;8921.375503714678;10025.845946254281;564.9378568357478;618.8452886778306;622.2626187967273;645.9031145328145;676.7394553097093;4238.33412963848;4544.909101817945;4547.886302842456;4684.929726054112;4728.338032675429;114750;130781.34609326892;132241;134752.32623836817;137874;101863.98136018692;111113.33330000057;116467;121243;132948.67051329557;92051.53974230085;93914.53042734743;94434;94696.00387963133;102479.48760256301;8008.439409241297;9384.483853388128;9868.210543156387;10011.899881001096;10038.899611003797;56407;56801.295961121235;57520.4247957515;57800.42199577951;58112.70943645255;66070.66964665338;80384;82573.17426825655;83688;84274.57862710647;12632.873671263169;12858.614241572768;12882.935585322013;12918.031147663774;13098.541551045668;14313.499027533986;14404.711905761616;14575.635609109433;14946.177960212293;15103.54689359321;12417.875821241672;12457.563985260473;12520;12696.17474864131;13044.152130111512;13449.932750336186;13540.93229533846;14001.22993235382;14086.859131408555;14121.929390352983;13144.145630533985;13186.604401867959;13339.66650833737;13622.455101795817;13884.930575347058;11213.831792522955;11216;11340.773184536098;11366.488508016993;11756.81738624565;9250.907490925005;9630.159103064472;10275.894435180273;11112.662710089078;11521.88478115208 23:46:34 INFO - PROCESS | 1948 | __end_tp_report 23:46:34 INFO - PROCESS | 1948 | __start_cc_report 23:46:34 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,365 23:46:34 INFO - PROCESS | 1948 | __end_cc_report 23:46:34 INFO - PROCESS | 1948 | __startTimestamp1475477194085__endTimestamp 23:46:34 INFO - PROCESS | 1948 | 23:46:34 INFO - PROCESS | 1948 | ------- Summary: start ------- 23:46:34 INFO - PROCESS | 1948 | Number of tests: 6 23:46:34 INFO - PROCESS | 1948 | 23:46:34 INFO - PROCESS | 1948 | [#0] dojo.html Cycles:165 Average:5758.83 Median:6008.25 stddev:683.84 (11.4%) stddev-sans-first:659.25 23:46:34 INFO - PROCESS | 1948 | Values: 3347.5 3410.3 3453.2 3463.7 3464.3 5706.9 5833.5 5860.2 5860.4 5898.5 5767.3 5874.2 5877.6 5971.5 6056.3 4341.5 5130.0 5216.3 5218.6 5282.4 4363.9 4410.0 4521.3 5394.6 5400.6 5222.9 5350.5 5352.8 5360.4 5386.0 5236.8 5258.9 5271.1 5281.4 5318.1 5164.3 5169.8 5185.7 5281.8 5298.0 5080.9 5552.3 5916.8 5940.0 5943.3 6095.6 6215.2 6246.0 6255.4 6265.4 6261.8 6321.0 6323.6 6327.3 6340.1 5832.8 5888.4 6032.7 6048.3 6061.0 5658.2 6282.1 6315.1 6324.4 6409.5 6066.3 6208.8 6209.9 6227.7 6290.9 5447.2 6119.0 6277.6 6293.9 6320.3 6103.1 6189.4 6202.4 6208.7 6223.2 6225.4 6241.7 6242.6 6249.6 6288.5 5003.9 5027.7 5033.3 5038.5 5063.0 3663.8 4410.5 4422.7 4428.0 4495.2 5563.7 5993.8 6075.4 6092.9 6101.3 6282.0 6315.7 6324.4 6326.1 6355.7 5873.1 5944.1 6033.6 6057.6 6086.3 5480.4 5529.6 5539.7 5542.3 5563.7 6451.9 6456.6 6466.2 6473.7 6476.6 6158.6 6198.8 6208.6 6208.8 6334.8 6447.7 6449.2 6455.1 6469.8 6488.2 5411.4 6280.3 6290.6 6300.9 6338.8 5351.8 5989.8 6196.0 6283.2 6290.3 5256.8 5810.2 6049.5 6154.8 6202.9 4819.7 5974.5 6174.2 6184.9 6246.1 5834.9 5853.9 5883.4 6008.2 6137.7 6046.3 6051.9 6082.5 6095.1 6113.8 5777.5 5834.5 5852.0 5877.4 5901.6 23:46:34 INFO - PROCESS | 1948 | 23:46:34 INFO - PROCESS | 1948 | [#1] ext.html Cycles:180 Average:70331.95 Median:41111.90 stddev:154948.16 (376.9%) stddev-sans-first:155294.07 23:46:34 INFO - PROCESS | 1948 | Values: 476.5 500.1 502.4 511.0 533.1 40037.4 41621.0 41681.0 41723.0 41764.0 42970.0 43298.7 43449.1 43487.0 43496.8 9706.9 9881.7 9893.9 9899.7 9925.9 9794.4 9864.2 9868.2 9871.3 9897.2 9907.6 9928.6 9929.0 9938.0 9959.1 9861.8 9901.3 9920.1 9928.0 9977.2 9716.7 9792.2 9800.3 9822.6 9842.0 40622.4 40873.8 41008.8 41215.0 41322.0 2772.8 3288.5 3299.7 3355.5 3384.2 24419.0 31085.7 31232.7 31771.9 31993.8 3252.6 3270.4 3339.2 3361.9 3383.8 433513.8 494682.0 495706.5 500425.5 501606.5 58287.7 58304.0 58606.4 58739.4 58914.5 60882.4 61387.7 61534.7 61839.8 62780.4 15245.5 15299.9 15335.7 15385.4 15409.6 11837.2 12249.8 12269.5 12271.8 12311.2 11587.9 15873.7 16081.0 16133.4 16366.0 2764.0 5479.6 6269.4 6497.6 6510.3 858.6 898.5 909.2 957.7 1032.2 29298.1 29659.0 30042.8 30789.7 30813.8 1015.9 1016.1 1063.3 1092.7 1105.9 14834.3 15068.8 15656.2 15734.1 15787.8 660060.0 666939.0 670765.0 671605.0 672452.0 57597.7 58145.0 58177.7 58181.1 58195.0 1318.9 1335.5 1337.3 1341.0 1347.0 29120.9 29437.0 29610.6 30078.0 30301.0 49719.3 50077.5 50115.0 50245.7 50251.7 567139.0 567362.0 568183.0 569567.0 571914.0 24032.0 26390.0 26800.9 26818.7 26842.0 45626.5 45963.0 46352.1 46516.5 47989.3 37361.8 38324.6 38993.6 39488.0 40401.4 32283.0 38815.8 38850.0 39467.6 40124.2 33221.7 33333.7 33783.7 33834.8 33905.8 28221.9 34193.0 34451.3 34857.5 35019.9 25356.6 25532.0 25539.6 25579.4 26402.9 23:46:34 INFO - PROCESS | 1948 | 23:46:34 INFO - PROCESS | 1948 | [#2] jquery.html Cycles:180 Average:33860.25 Median:1627.40 stddev:96092.00 (5904.6%) stddev-sans-first:96328.51 23:46:34 INFO - PROCESS | 1948 | Values: 293.3 318.9 336.9 377.9 394.2 13560.6 17185.2 17707.0 18637.0 21236.8 16542.5 17793.8 20361.5 20367.0 20665.2 5686.7 6060.4 6103.4 6104.2 6115.1 5709.6 5943.5 6029.5 6066.0 6067.5 5146.6 5992.7 6079.2 6109.8 6127.4 5519.7 6001.6 6675.0 6732.1 6733.3 5820.1 5827.1 5928.9 5936.0 5950.3 12637.2 12982.2 12986.0 13006.0 13935.7 2205.9 2338.0 2491.9 2642.2 2935.9 9612.8 9658.8 9870.8 10130.6 10205.2 1795.4 1805.6 1823.3 1929.1 1940.1 118326.0 122701.4 124381.0 124431.0 125355.0 10879.8 11072.3 11226.8 11293.8 11429.1 14185.3 14215.8 14221.2 14237.1 14245.9 8129.3 8368.6 8399.8 8512.4 8524.9 8281.9 8373.7 8394.4 8517.5 8553.7 3507.4 4418.4 4424.2 4485.4 4563.2 1895.2 1912.0 1916.1 1943.8 1974.3 678.0 694.5 708.8 841.9 948.1 20346.5 20784.5 20981.1 21028.5 21119.5 730.7 890.5 929.7 932.6 961.3 9103.9 10433.3 10853.6 10896.3 10899.5 554220.0 560714.0 563423.0 592049.0 592618.0 86827.0 130472.0 136503.3 138750.0 146865.0 926.2 983.0 1013.3 1013.5 1013.7 17159.3 17962.0 18896.6 19275.3 19357.2 54051.0 54554.7 55286.4 56208.7 56595.0 61538.7 62808.7 77920.6 80650.6 81898.0 7917.0 7985.4 8003.8 8027.1 8177.7 8950.3 9826.7 12236.4 12586.8 12735.3 9942.0 10704.2 10970.1 11044.7 11346.5 10404.9 10552.8 11032.6 11105.7 11217.8 11267.0 11567.5 11662.2 11685.8 11691.4 7768.8 7917.9 7928.4 8000.8 8104.8 10648.3 11122.7 11157.8 11296.0 11467.0 23:46:34 INFO - PROCESS | 1948 | 23:46:34 INFO - PROCESS | 1948 | [#3] mootools.html Cycles:180 Average:5661.73 Median:4879.32 stddev:3224.78 (66.1%) stddev-sans-first:3218.80 23:46:34 INFO - PROCESS | 1948 | Values: 1518.4 1550.1 1780.3 1838.3 1879.8 7976.6 8232.3 8419.5 9091.9 9128.4 7747.4 8453.6 8800.2 8822.0 8886.2 2141.9 2155.1 2307.0 2340.8 2348.2 2315.4 2326.4 2361.9 2364.1 2369.4 1922.2 1959.2 1963.7 1968.9 1971.8 2048.6 2299.2 2327.3 2328.3 2348.4 2315.1 2324.7 2328.4 2331.6 2332.3 8492.6 8527.7 8589.2 8590.5 8629.2 5124.9 5287.7 5313.7 5361.0 5372.7 3476.2 3538.8 3691.6 3696.3 3702.9 1063.8 1092.6 1098.0 1103.8 1110.1 11296.5 11575.5 11581.4 11638.7 11643.1 4547.4 4666.5 4754.5 4756.4 4779.4 9863.9 9885.7 9936.2 9945.5 9955.6 5308.3 5320.8 5323.9 5333.0 5341.4 4380.1 4553.5 4838.4 4902.7 4905.5 3858.5 3892.2 3986.6 3998.0 4018.4 1264.3 1264.4 1264.6 1282.6 1290.5 866.9 881.5 882.7 913.8 934.1 4895.9 5375.6 5784.6 5843.0 5869.1 418.2 424.1 432.4 434.8 438.4 3840.7 3861.8 3882.0 3901.8 3926.5 9182.2 10595.3 10821.4 10948.0 10952.6 10507.9 10565.6 10884.7 10887.3 10891.5 8162.5 8233.5 8296.3 8318.1 8387.7 5587.4 5745.9 5765.5 5774.3 5857.9 9928.3 9972.3 10106.4 10154.9 10164.1 10200.5 10304.2 10308.4 10323.9 10416.2 6865.0 7082.0 7180.6 7465.5 7539.7 8226.0 8395.4 8438.4 8463.0 8470.5 7365.3 7518.2 7519.1 7583.8 7628.2 7273.8 7296.2 7369.7 7443.0 7458.3 6908.3 6922.2 6929.1 6976.7 6990.9 4923.8 4947.1 5019.8 5020.3 5078.1 4870.9 4887.8 4981.4 5004.2 5074.7 23:46:34 INFO - PROCESS | 1948 | 23:46:34 INFO - PROCESS | 1948 | [#4] prototype.html Cycles:180 Average:4733.25 Median:5082.54 stddev:1522.51 (30.0%) stddev-sans-first:1489.74 23:46:34 INFO - PROCESS | 1948 | Values: 285.8 306.6 307.0 313.4 320.6 4443.3 4635.7 5017.3 5101.6 5418.0 4444.9 4712.7 4719.4 5612.0 5661.7 3776.0 4280.9 4302.7 4309.5 4319.7 3236.9 4238.7 4328.7 4337.4 4340.3 3525.6 4217.2 4235.0 4237.0 4245.3 3798.9 4214.0 4231.2 4232.7 4263.0 2950.6 3612.5 3656.9 3658.2 3673.4 5350.4 5397.4 5534.3 5551.9 5579.2 4440.0 4887.7 4891.9 4896.2 4901.0 5631.4 5846.4 5974.3 5978.6 6018.9 3682.0 3695.5 3976.3 4021.7 4024.4 5827.4 5987.1 6287.6 6353.6 6389.6 3579.6 4041.6 4055.4 4071.5 4084.3 3315.3 3792.3 4092.5 4094.0 4094.8 4039.7 4811.6 4824.5 4841.6 4846.5 5190.6 5999.4 6023.5 6031.0 6039.9 1185.3 1218.0 1251.4 1341.0 1354.8 816.8 823.5 858.1 862.1 866.8 4385.8 4615.4 4788.6 4812.9 4846.9 4557.7 4915.9 5173.1 5224.4 5233.9 4200.2 4326.0 4483.6 4788.2 4820.5 2947.6 3248.7 3252.6 3317.9 3332.6 6467.4 6479.8 6490.0 6497.9 6524.7 5063.5 6305.3 6330.3 6347.8 6353.5 5273.0 6453.5 6466.3 6484.4 6486.8 5855.5 6161.4 6200.2 6288.4 6307.5 5414.9 5642.5 5918.6 6130.3 6216.9 5345.7 5973.8 6104.1 6206.9 6554.4 3144.7 4009.0 4084.8 4087.4 4124.3 5028.5 6024.0 6099.8 6182.2 6283.8 5909.5 5909.8 5976.4 6027.8 6067.3 5275.3 5442.4 5615.5 6003.5 6061.8 5004.8 5229.5 5891.3 5899.0 5954.6 6028.8 6039.2 6048.8 6086.7 6152.8 5733.6 5938.5 5957.0 5966.5 5983.6 23:46:34 INFO - PROCESS | 1948 | 23:46:34 INFO - PROCESS | 1948 | [#5] yui.html Cycles:180 Average:22891.61 Median:3437.32 stddev:35294.33 (1026.8%) stddev-sans-first:35354.16 23:46:34 INFO - PROCESS | 1948 | Values: 742.5 756.8 758.6 791.5 834.1 15057.8 15652.6 15892.4 16087.8 16142.5 14662.5 14696.4 14834.3 14854.6 14857.9 539.3 727.5 853.3 876.0 876.2 694.7 838.8 856.7 868.5 873.2 880.6 891.2 893.3 896.1 898.4 875.0 885.8 897.8 900.9 901.4 640.7 649.7 672.1 672.5 673.1 14450.8 14487.0 14525.0 14611.6 14750.3 12795.7 14230.8 14295.9 14367.6 14708.3 11784.9 11889.5 11918.6 11929.7 11994.7 2935.6 3636.3 3770.8 3858.9 3913.9 102353.0 103513.0 103731.5 106426.0 107474.5 10811.6 10857.8 11003.7 11182.4 11314.4 20192.7 20332.9 20443.8 20447.5 20495.7 7650.6 7730.1 7738.3 7887.2 8034.9 4444.4 4467.0 4499.1 4516.7 4583.9 3293.2 3421.8 3429.5 3445.1 3450.1 620.3 652.8 654.1 667.6 667.7 588.9 594.7 644.3 667.4 673.1 6873.8 8274.6 8717.5 8921.4 10025.8 564.9 618.8 622.3 645.9 676.7 4238.3 4544.9 4547.9 4684.9 4728.3 114750.0 130781.3 132241.0 134752.3 137874.0 101864.0 111113.3 116467.0 121243.0 132948.7 92051.5 93914.5 94434.0 94696.0 102479.5 8008.4 9384.5 9868.2 10011.9 10038.9 56407.0 56801.3 57520.4 57800.4 58112.7 66070.7 80384.0 82573.2 83688.0 84274.6 12632.9 12858.6 12882.9 12918.0 13098.5 14313.5 14404.7 14575.6 14946.2 15103.5 12417.9 12457.6 12520.0 12696.2 13044.2 13449.9 13540.9 14001.2 14086.9 14121.9 13144.1 13186.6 13339.7 13622.5 13884.9 11213.8 11216.0 11340.8 11366.5 11756.8 9250.9 9630.2 10275.9 11112.7 11521.9 23:46:34 INFO - PROCESS | 1948 | -------- Summary: end -------- 23:46:34 INFO - PROCESS | 1948 | 23:46:34 INFO - TEST-INFO | 1948: exit 0 23:46:34 INFO - TEST-OK | dromaeo_css | took 1115515ms 23:46:34 INFO - TEST-START | kraken 23:46:34 INFO - operating with platform_type : mac_ 23:46:34 INFO - Initialising browser for kraken test... 23:46:34 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpwsgyyN/profile http://localhost:54341/getInfo.html) 23:46:37 INFO - PROCESS | 1958 | 2016-10-02 23:46:37.737 plugin-container[1959:21832] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e2b, name = 'com.apple.tsm.portname' 23:46:37 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:46:37 INFO - PROCESS | 1958 | 2016-10-02 23:46:37.740 plugin-container[1959:21832] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 23:46:37 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:46:37 INFO - PROCESS | 1958 | 2016-10-02 23:46:37.740 plugin-container[1959:21832] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:46:37 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 23:46:37 INFO - PROCESS | 1958 | colorDepth:24 23:46:37 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 23:46:37 INFO - PROCESS | 1958 | __metrics 23:46:37 ERROR - PROCESS | 1958 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 23:46:38 INFO - TEST-INFO | 1958: exit 0 23:46:38 INFO - Browser initialized. 23:46:38 INFO - Running cycle 1/1 for kraken test... 23:46:38 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpwsgyyN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:46:41 INFO - PROCESS | 1960 | 2016-10-02 23:46:41.063 plugin-container[1961:21945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.tsm.portname' 23:46:41 INFO - PROCESS | 1960 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:46:41 INFO - PROCESS | 1960 | 2016-10-02 23:46:41.066 plugin-container[1961:21945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 23:46:41 INFO - PROCESS | 1960 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:46:41 INFO - PROCESS | 1960 | 2016-10-02 23:46:41.066 plugin-container[1961:21945] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:46:50 INFO - PROCESS | 1960 | RSS: Main: 240365568 23:46:50 INFO - PROCESS | 1960 | 23:46:53 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54341/tests/kraken/driver.html?testName=audio-beat-detection) 23:46:54 INFO - PROCESS | 1960 | RSS: Main: 253984768 23:46:54 INFO - PROCESS | 1960 | 23:46:57 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54341/tests/kraken/driver.html?testName=audio-dft) 23:46:57 INFO - PROCESS | 1960 | RSS: Main: 261451776 23:46:57 INFO - PROCESS | 1960 | 23:47:01 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54341/tests/kraken/driver.html?testName=audio-fft) 23:47:02 INFO - PROCESS | 1960 | RSS: Main: 262402048 23:47:02 INFO - PROCESS | 1960 | 23:47:04 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54341/tests/kraken/driver.html?testName=audio-oscillator) 23:47:04 INFO - PROCESS | 1960 | RSS: Main: 263503872 23:47:04 INFO - PROCESS | 1960 | 23:47:06 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54341/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:47:07 INFO - PROCESS | 1960 | RSS: Main: 263847936 23:47:07 INFO - PROCESS | 1960 | 23:47:10 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54341/tests/kraken/driver.html?testName=imaging-darkroom) 23:47:11 INFO - PROCESS | 1960 | RSS: Main: 265486336 23:47:11 INFO - PROCESS | 1960 | 23:47:14 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54341/tests/kraken/driver.html?testName=imaging-desaturate) 23:47:14 INFO - PROCESS | 1960 | RSS: Main: 265940992 23:47:14 INFO - PROCESS | 1960 | 23:47:17 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54341/tests/kraken/driver.html?testName=json-parse-financial) 23:47:18 INFO - PROCESS | 1960 | RSS: Main: 265805824 23:47:18 INFO - PROCESS | 1960 | 23:47:19 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54341/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:47:20 INFO - PROCESS | 1960 | RSS: Main: 265646080 23:47:20 INFO - PROCESS | 1960 | 23:47:22 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54341/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:47:23 INFO - PROCESS | 1960 | RSS: Main: 265658368 23:47:23 INFO - PROCESS | 1960 | 23:47:25 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54341/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:47:26 INFO - PROCESS | 1960 | RSS: Main: 265781248 23:47:26 INFO - PROCESS | 1960 | 23:47:29 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54341/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:47:29 INFO - PROCESS | 1960 | RSS: Main: 266047488 23:47:29 INFO - PROCESS | 1960 | 23:47:32 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54341/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:47:33 INFO - PROCESS | 1960 | RSS: Main: 265904128 23:47:33 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:54341/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54341/tests/kraken/driver.html?testName=ai-astar) 23:47:35 INFO - PROCESS | 1960 | RSS: Main: 267382784 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | __start_tp_report 23:47:35 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 23:47:35 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 23:47:35 INFO - PROCESS | 1960 | |i|pagename|runs| 23:47:35 INFO - PROCESS | 1960 | |0;ai-astar;134;175;119;119;117;117;117;116;118;119 23:47:35 INFO - PROCESS | 1960 | |1;audio-beat-detection;150;116;119;152;114;113;117;115;175;146 23:47:35 INFO - PROCESS | 1960 | |2;audio-dft;173;265;291;201;196;188;208;225;213;200 23:47:35 INFO - PROCESS | 1960 | |3;audio-fft;85;58;59;88;87;74;73;81;106;81 23:47:35 INFO - PROCESS | 1960 | |4;audio-oscillator;100;57;86;102;100;103;124;65;74;118 23:47:35 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;90;87;105;86;88;85;78;79;80;130 23:47:35 INFO - PROCESS | 1960 | |6;imaging-darkroom;130;96;150;94;95;109;156;95;96;95 23:47:35 INFO - PROCESS | 1960 | |7;imaging-desaturate;109;131;93;92;91;115;117;92;85;91 23:47:35 INFO - PROCESS | 1960 | |8;json-parse-financial;56;57;58;58;59;60;57;57;56;59 23:47:35 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;57;67;99;71;84;98;103;95;69;69 23:47:35 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;87;93;110;111;108;109;89;90;93;87 23:47:35 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;148;139;156;160;168;178;167;155;180;153 23:47:35 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;177;176;238;189;176;176;178;175;175;176 23:47:35 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;69;71;69;69;74;72;69;70;71;70 23:47:35 INFO - PROCESS | 1960 | __end_tp_report 23:47:35 INFO - PROCESS | 1960 | __start_cc_report 23:47:35 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,935 23:47:35 INFO - PROCESS | 1960 | __end_cc_report 23:47:35 INFO - PROCESS | 1960 | __startTimestamp1475477255405__endTimestamp 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | ------- Summary: start ------- 23:47:35 INFO - PROCESS | 1960 | Number of tests: 14 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:125.10 Median:119.00 stddev:18.29 (15.4%) stddev-sans-first:19.11 23:47:35 INFO - PROCESS | 1960 | Values: 134.0 175.0 119.0 119.0 117.0 117.0 117.0 116.0 118.0 119.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:131.70 Median:132.50 stddev:22.09 (16.7%) stddev-sans-first:22.42 23:47:35 INFO - PROCESS | 1960 | Values: 150.0 116.0 119.0 152.0 114.0 113.0 117.0 115.0 175.0 146.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:216.00 Median:210.50 stddev:36.05 (17.1%) stddev-sans-first:34.71 23:47:35 INFO - PROCESS | 1960 | Values: 173.0 265.0 291.0 201.0 196.0 188.0 208.0 225.0 213.0 200.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:79.20 Median:81.00 stddev:14.22 (17.6%) stddev-sans-first:14.93 23:47:35 INFO - PROCESS | 1960 | Values: 85.0 58.0 59.0 88.0 87.0 74.0 73.0 81.0 106.0 81.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:90.50 stddev:22.00 (24.3%) stddev-sans-first:23.18 23:47:35 INFO - PROCESS | 1960 | Values: 100.0 57.0 86.0 102.0 100.0 103.0 124.0 65.0 74.0 118.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:85.50 stddev:15.77 (18.4%) stddev-sans-first:16.72 23:47:35 INFO - PROCESS | 1960 | Values: 90.0 87.0 105.0 86.0 88.0 85.0 78.0 79.0 80.0 130.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:111.60 Median:95.00 stddev:24.53 (25.8%) stddev-sans-first:25.10 23:47:35 INFO - PROCESS | 1960 | Values: 130.0 96.0 150.0 94.0 95.0 109.0 156.0 95.0 96.0 95.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:101.60 Median:91.00 stddev:15.25 (16.8%) stddev-sans-first:15.94 23:47:35 INFO - PROCESS | 1960 | Values: 109.0 131.0 93.0 92.0 91.0 115.0 117.0 92.0 85.0 91.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:58.00 stddev:1.34 (2.3%) stddev-sans-first:1.27 23:47:35 INFO - PROCESS | 1960 | Values: 56.0 57.0 58.0 58.0 59.0 60.0 57.0 57.0 56.0 59.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:81.20 Median:77.50 stddev:16.54 (21.3%) stddev-sans-first:15.05 23:47:35 INFO - PROCESS | 1960 | Values: 57.0 67.0 99.0 71.0 84.0 98.0 103.0 95.0 69.0 69.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:97.70 Median:88.00 stddev:10.38 (11.8%) stddev-sans-first:10.26 23:47:35 INFO - PROCESS | 1960 | Values: 87.0 93.0 110.0 111.0 108.0 109.0 89.0 90.0 93.0 87.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:160.40 Median:163.50 stddev:12.95 (7.9%) stddev-sans-first:12.94 23:47:35 INFO - PROCESS | 1960 | Values: 148.0 139.0 156.0 160.0 168.0 178.0 167.0 155.0 180.0 153.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:176.50 stddev:19.56 (11.1%) stddev-sans-first:20.60 23:47:35 INFO - PROCESS | 1960 | Values: 177.0 176.0 238.0 189.0 176.0 176.0 178.0 175.0 175.0 176.0 23:47:35 INFO - PROCESS | 1960 | 23:47:35 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:70.50 stddev:1.65 (2.3%) stddev-sans-first:1.67 23:47:35 INFO - PROCESS | 1960 | Values: 69.0 71.0 69.0 69.0 74.0 72.0 69.0 70.0 71.0 70.0 23:47:35 INFO - PROCESS | 1960 | -------- Summary: end -------- 23:47:35 INFO - PROCESS | 1960 | 23:47:36 INFO - TEST-INFO | 1960: exit 0 23:47:36 INFO - TEST-OK | kraken | took 61532ms 23:47:36 INFO - SUITE-END | took 1177s 23:47:36 INFO - Completed test suite (00:19:37) 23:47:36 INFO - javascript benchmark 23:47:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7274.652305529401, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3347.5146103814945, 3410.317936412718, 3453.223024819416, 3463.7402194835395, 3464.3417750627377, 5706.885862282752, 5833.5041521470675, 5860.208871802311, 5860.384659610738, 5898.52811775058, 5767.279090113736, 5874.1776151338845, 5877.647341159533, 5971.522278217743, 6056.303525094615, 4341.544137865526, 5130.0, 5216.295800067, 5218.608604354669, 5282.418933917266, 4363.869083927487, 4410.029793445437, 4521.344405061264, 5394.622376433651, 5400.567954563645, 5222.895542089137, 5350.5452036576935, 5352.759125839327, 5360.3567571891235, 5386.030514507387, 5236.764345604457, 5258.947410525922, 5271.077561426735, 5281.445448227958, 5318.069337865858, 5164.277001219794, 5169.81905633301, 5185.688858668492, 5281.788728450896, 5298.0, 5080.949190508085, 5552.278203833516, 5916.822495325147, 5940.0, 5943.3462319144855, 6095.589475041383, 6215.223097112861, 6246.0, 6255.374462553754, 6265.404786545271, 6261.843453913643, 6321.020241862519, 6323.588966717149, 6327.27236367819, 6340.144080549113, 5832.766689332464, 5888.411158884077, 6032.66820324882, 6048.304444988795, 6061.0, 5658.179563963202, 6282.089097080898, 6315.115883776275, 6324.367563243731, 6409.455196308366, 6066.302375226817, 6208.81373558794, 6209.875802484015, 6227.719752611053, 6290.937090629035, 5447.210154527571, 6119.015143218386, 6277.591956522811, 6293.930031894589, 6320.336364681734, 6103.064938160023, 6189.35011823761, 6202.4417802398, 6208.689565521706, 6223.222097237845, 6225.3774622537385, 6241.656708880964, 6242.625442473466, 6249.593776404519, 6288.496920246369, 5003.949960500421, 5027.748612569357, 5033.345665063518, 5038.521340472649, 5062.962092771047, 3663.7618554793853, 4410.470743510799, 4422.690411671152, 4428.003699167662, 4495.235809912323, 5563.693996830132, 5993.820185394445, 6075.362086980892, 6092.878142437126, 6101.267847858286, 6281.963476026496, 6315.747370105236, 6324.3991820776955, 6326.145970293951, 6355.682215889279, 5873.060310350324, 5944.138099975566, 6033.577649564489, 6057.57596968208, 6086.3000754913755, 5480.369757477941, 5529.640573362719, 5539.695316757617, 5542.251796007487, 5563.666180029211, 6451.870962580816, 6456.612603243821, 6466.22405311375, 6473.708683109366, 6476.643784591704, 6158.599691020069, 6198.783042593487, 6208.627482351073, 6208.782692605737, 6334.841628959129, 6447.70985305653, 6449.193850768654, 6455.128557644658, 6469.77355792545, 6488.221413430419, 5411.431799661136, 6280.277768056732, 6290.591111577733, 6300.873982520416, 6338.809835704937, 5351.812686556108, 5989.802039592186, 6196.039613859859, 6283.246010478589, 6290.2766181889665, 5256.842294731164, 5810.244668193108, 6049.485793707584, 6154.815355539341, 6202.937970620236, 4819.734914579732, 5974.522038236843, 6174.197354343906, 6184.938150618616, 6246.125542424155, 5834.912476312944, 5853.9121913172185, 5883.35283118848, 6008.24896887889, 6137.662428566293, 6046.274447066205, 6051.909221361595, 6082.452579267887, 6095.05526643371, 6113.816585502442, 5777.537796976316, 5834.474897259267, 5851.970740146442, 5877.353491115889, 5901.557383196192], "value": 5715.671752664756, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.5448996208621, 500.0773572758261, 502.3720349563046, 511.00864323212966, 533.14696485623, 40037.39943900844, 41621.0, 41680.95797605062, 41723.0, 41763.95590110249, 42970.0, 43298.7010389688, 43449.13101737971, 43487.0, 43496.782516087456, 9706.902930970706, 9881.654142105028, 9893.851592226123, 9899.703008909744, 9925.851112233322, 9794.412335259907, 9864.210863130967, 9868.210543156567, 9871.259655525828, 9897.15874150698, 9907.554160062815, 9928.553215105338, 9929.0, 9938.006199380077, 9959.053889880486, 9861.802763944717, 9901.257405694601, 9920.057594528544, 9928.0, 9977.201823854075, 9716.708498745049, 9792.21662267017, 9800.31397802154, 9822.557984890696, 9842.0, 40622.39066414006, 40873.795631021654, 41008.79495602503, 41215.0, 41322.0, 2772.8336299822076, 3288.4738441849427, 3299.7195238404765, 3355.4938231583064, 3384.1539615096226, 24419.0, 31085.689143108506, 31232.687673123433, 31771.887983920453, 31993.840030799696, 3252.5771649685626, 3270.444024515838, 3339.1652086978256, 3361.865525378982, 3383.8308084595674, 433513.83243083896, 494682.0, 495706.52146739396, 500425.4978725083, 501606.49196754146, 58287.70856145692, 58304.0, 58606.413935860095, 58739.41260587425, 58914.527136821926, 60882.39117608767, 61387.6930615353, 61534.69232653898, 61839.76320473565, 62780.37219627837, 15245.466408675644, 15299.852511061716, 15335.693286134216, 15385.38458461649, 15409.614759631098, 11837.171398002058, 12249.816252756216, 12269.509219631114, 12271.815922761165, 12311.19977201479, 11587.88412115868, 15873.682526349408, 16081.0, 16133.354665813471, 16366.0, 2763.9723602763715, 5479.561635069185, 6269.373062693785, 6497.577657452251, 6510.316416776171, 858.5921687198655, 898.4654130792168, 909.1590278991906, 957.674390707163, 1032.194887987371, 29298.121056367916, 29659.0, 30042.84978575137, 30789.692103078683, 30813.845930770203, 1015.8527013582989, 1016.1362841584594, 1063.2982231727024, 1092.6721983405105, 1105.9170562207867, 14834.332455039548, 15068.849311506745, 15656.217189140725, 15734.055956642638, 15787.763183552255, 660060.0, 666939.0, 670765.0, 671605.0, 672452.0, 57597.712011439675, 58145.0, 58177.70911145417, 58181.12728309079, 58195.0, 1318.8681131886735, 1335.5325636027312, 1337.25782190885, 1340.9731805364033, 1347.036868638928, 29120.854395727885, 29436.969706060187, 29610.555841662823, 30078.0, 30301.0, 49719.25421118614, 50077.49922500874, 50115.0, 50245.74877125591, 50251.74874125606, 567139.0, 567362.0, 568183.0, 569567.0, 571914.0, 24032.0, 26390.0, 26800.865995669898, 26818.731812682403, 26842.0, 45626.54373456356, 45963.0, 46352.072958541314, 46516.53483465257, 47989.28016079831, 37361.81319093387, 38324.61675383322, 38993.61006390013, 39488.0, 40401.39397909093, 32283.0, 38815.7894736852, 38850.0, 39467.605323947544, 40124.19751604893, 33221.667783322824, 33333.66666333305, 33783.66216337805, 33834.8308258467, 33905.83047084749, 28221.871125155587, 34193.0, 34451.349873216524, 34857.47713784346, 35019.949401517995, 25356.619650705623, 25532.0, 25539.616905746057, 25579.36051598725, 26402.867985660716], "value": 19580.93645460212, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.2712210157759, 318.85492101094, 336.8815532432325, 377.91496913194527, 394.22337994151513, 13560.593182204526, 17185.226664800084, 17707.0, 18637.0, 21236.83197424141, 16542.503724888273, 17793.82206177941, 20361.49096272594, 20366.98165091747, 20665.17339306426, 5686.744096515657, 6060.363661815512, 6103.389661033883, 6104.206453161083, 6115.082737589352, 5709.600327977043, 5943.524518038546, 6029.457348838604, 6066.0, 6067.544934129935, 5146.614003949719, 5992.730327135289, 6079.240094988127, 6109.755609775603, 6127.417895299939, 5519.724013799314, 6001.579889407743, 6674.965380366051, 6732.057511948331, 6733.293004234534, 5820.068788993783, 5827.125931110368, 5928.911066334009, 5935.990881550147, 5950.315713692938, 12637.2417654941, 12982.221066736027, 12986.0, 13006.0, 13935.651608709763, 2205.9448513787283, 2337.9649305260436, 2491.862947537885, 2642.220544939228, 2935.941281174386, 9612.759681007961, 9658.806823863484, 9870.802583948282, 10130.594776209016, 10205.2346074044, 1795.3626462605848, 1805.6208196278797, 1823.298030258347, 1929.1415320182525, 1940.078462730206, 118326.0, 122701.38649306785, 124381.0, 124431.0, 125354.99999999908, 10879.83680244797, 11072.280301780358, 11226.831597526043, 11293.774124517464, 11429.142814288962, 14185.290735463186, 14215.786763198561, 14221.217833019284, 14237.145771253758, 14245.85754142466, 8129.349652027823, 8368.58157092143, 8399.832003360021, 8512.40413171072, 8524.914750852537, 8281.917180828235, 8373.706920257762, 8394.370422218359, 8517.48895066298, 8553.74338769838, 3507.3686736387695, 4418.403515525363, 4424.225760491901, 4485.394471746337, 4563.247064234364, 1895.1661269041579, 1911.9904400478188, 1916.1377380178976, 1943.7959014303578, 1974.2892558678864, 678.0266743436945, 694.5034300475185, 708.8121647763347, 841.8863453433707, 948.0850978805348, 20346.491337716674, 20784.48038799042, 20981.06265122884, 21028.47428814292, 21119.472013199793, 730.6602429870136, 890.4817286526456, 929.7489677786959, 932.5989824375583, 961.2694352292308, 9103.908960910307, 10433.32183408076, 10853.565857365616, 10896.346219226712, 10899.509522071527, 554220.0, 560714.0, 563423.0, 592049.0, 592618.0, 86827.0, 130472.0, 136503.31748341394, 138750.0, 146865.0, 926.1664501948301, 982.9852552211865, 1013.341328136717, 1013.5337744637402, 1013.6756238003916, 17159.31362745526, 17962.0, 18896.62206755885, 19275.325363612206, 19357.225710971405, 54051.0, 54554.72722636361, 55286.44713552813, 56208.718956404955, 56595.0, 61538.692306539975, 62808.68595656993, 77920.61039694765, 80650.5967470159, 81898.0, 7917.030394413242, 7985.401094917788, 8003.799905002422, 8027.146969454811, 8177.713780017909, 8950.328725345495, 9826.65606703762, 12236.388180590757, 12586.811197832223, 12735.29955852437, 9941.950290248502, 10704.197185210984, 10970.122390208924, 11044.723881903017, 11346.489407976826, 10404.907484714298, 10552.84170737455, 11032.558697652004, 11105.722356940818, 11217.775644487228, 11267.0, 11567.479463423997, 11662.241954272946, 11685.76628467443, 11691.356975366436, 7768.805779855549, 7917.881231781643, 7928.445008849325, 8000.799980000546, 8104.797380065545, 10648.254622176682, 11122.666320010412, 11157.776844462904, 11296.0, 11467.0], "value": 9233.14396448676, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1518.445767294937, 1550.054466775267, 1780.3412737287206, 1838.2555065198599, 1879.774427068752, 7976.561289129096, 8232.341412686985, 8419.452735572184, 9091.909080909189, 9128.406653567514, 7747.380209583234, 8453.57732113395, 8800.207981281683, 8821.955890220555, 8886.20024197822, 2141.9250326238584, 2155.051777218027, 2307.0195167053976, 2340.754220806816, 2348.178137651825, 2315.3748487908256, 2326.430024643956, 2361.948932724938, 2364.054378248697, 2369.4431808524982, 1922.1734654098732, 1959.2163134746072, 1963.6956271777897, 1968.911398987049, 1971.802819718031, 2048.56980034193, 2299.1952816514254, 2327.3367090379247, 2328.2549584133085, 2348.424635964191, 2315.0623997281127, 2324.7210334759766, 2328.429534763977, 2331.5919714049974, 2332.288651961151, 8492.617832197566, 8527.744167674917, 8589.184027517376, 8590.484570925764, 8629.180227878342, 5124.897502049938, 5287.735613219362, 5313.681179129226, 5361.0, 5372.650777699476, 3476.217850983534, 3538.832185378819, 3691.6123807000145, 3696.316181506403, 3702.9444558331647, 1063.7712891728238, 1092.5902786455079, 1097.9615713449991, 1103.8399432082304, 1110.1119104716195, 11296.491657875498, 11575.479103440366, 11581.420928953603, 11638.709032274177, 11643.126765492621, 4547.430701653621, 4666.486686464486, 4754.524547545287, 4756.357891684647, 4779.4264688236935, 9863.901360986441, 9885.654002109892, 9936.205103591696, 9945.45300008492, 9955.601775928882, 5308.336457942757, 5320.813771517978, 5323.920141197885, 5333.0, 5341.412444631087, 4380.0582874682095, 4553.544645535486, 4838.371011768448, 4902.676277405152, 4905.460399356068, 3858.5369755629504, 3892.163184915257, 3986.621270979252, 3997.960020399817, 4018.3972404139026, 1264.2856785916113, 1264.3804535777404, 1264.5826877130526, 1282.5895713371817, 1290.4967062845499, 866.8873046503915, 881.4975463985595, 882.7175303902818, 913.8081002989313, 934.1405906565975, 4895.877603059881, 5375.569954403638, 5784.566157538202, 5843.0, 5869.090291004901, 418.2221068812007, 424.0966740841987, 432.414078923061, 434.7760903134888, 438.37750394439973, 3840.6749671363336, 3861.826217820205, 3882.0, 3901.843926242975, 3926.5484469285825, 9182.219511341476, 10595.31130476532, 10821.404822734825, 10948.014678678957, 10952.616658417077, 10507.94746026265, 10565.630202943014, 10884.659798143437, 10887.292325998786, 10891.455427228608, 8162.469439486415, 8233.46482478637, 8296.253337199681, 8318.126596707258, 8387.706430274911, 5587.413321601173, 5745.8563535911935, 5765.538756899354, 5774.335951365647, 5857.912131318119, 9928.25538084661, 9972.30193886421, 10106.39361638304, 10154.898451015692, 10164.136048435967, 10200.504382132125, 10304.175665946555, 10308.433036183082, 10323.94838025805, 10416.21878359111, 6865.0, 7081.964590177016, 7180.60506672138, 7465.514741541782, 7539.660715267936, 8226.0, 8395.412321137463, 8438.367122465963, 8462.957685211535, 8470.49177049379, 7365.300296471826, 7518.210587888193, 7519.060117485315, 7583.810404739926, 7628.237176282328, 7273.818154545967, 7296.19741828409, 7369.742059027908, 7443.0, 7458.254174582716, 6908.3437073477935, 6922.238553759184, 6929.099217101744, 6976.6860491277, 6990.895136573057, 4923.796026951124, 4947.134251506044, 5019.84940451787, 5020.297158397754, 5078.08594453002, 4870.855348992913, 4887.780049897834, 4981.42713587942, 5004.224345226496, 5074.720890351066], "value": 4453.642385222862, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.83806825255334, 306.64582407319546, 306.99232519187, 313.35918047592673, 320.6072561112639, 4443.311286750551, 4635.652326075545, 5017.347744793176, 5101.617378696604, 5418.049502179212, 4444.888877778058, 4712.740799256039, 4719.418994636792, 5611.959389239741, 5661.688607126606, 3775.9811200943955, 4280.850170244042, 4302.741835489865, 4309.525952145262, 4319.697621166504, 3236.87052517899, 4238.732278231147, 4328.696991210601, 4337.414449049385, 4340.283853164224, 3525.5593050868642, 4217.1987322408795, 4235.047114399251, 4236.97881510592, 4245.34197199433, 3798.9430158547643, 4213.9578604213875, 4231.196072746184, 4232.724872883253, 4263.0, 2950.601668774709, 3612.458131280329, 3656.926861462756, 3658.1586235165796, 3673.3755261605593, 5350.384705758848, 5397.353807088836, 5534.335879694422, 5551.861203469905, 5579.214651311582, 4439.955600443986, 4887.657863949525, 4891.853244402673, 4896.2410826321975, 4900.950990490085, 5631.436856314336, 5846.392242133405, 5974.312954010301, 5978.581499295051, 6018.879622407571, 3681.950644066428, 3695.5380577427823, 3976.304146774303, 4021.678265738734, 4024.43657887893, 5827.35899051104, 5987.1318658794235, 6287.622742635411, 6353.555251132422, 6389.616623002635, 3579.570451545831, 4041.595840415935, 4055.371417430303, 4071.450354202204, 4084.305668036441, 3315.2540678347177, 3792.27946690128, 4092.4884389451317, 4094.0, 4094.806051280006, 4039.6768258539246, 4811.566958973709, 4824.517548245148, 4841.636877234221, 4846.491118432514, 5190.584753219733, 5999.400059993965, 6023.488003519662, 6030.969845150747, 6039.939600604026, 1185.3302883870585, 1217.993910030444, 1251.393074358935, 1340.986590134086, 1354.7629164896105, 816.848882956655, 823.5305875650942, 858.1032820702382, 862.0818827948215, 866.8006358537572, 4385.837752995459, 4615.376924115269, 4788.61691064714, 4812.882331774008, 4846.927296090562, 4557.703749256288, 4915.910737936363, 5173.094708426086, 5224.373075230997, 5233.869153271199, 4200.219802178003, 4325.96679560606, 4483.574060464251, 4788.209945359046, 4820.5420485054565, 2947.572601972745, 3248.7329941322905, 3252.593425821772, 3317.855339907728, 3332.566766320363, 6467.417932386108, 6479.8056058318325, 6489.967550162219, 6497.902531462032, 6524.706388212542, 5063.468335824759, 6305.274893387227, 6330.335314791972, 6347.809565712943, 6353.459955903708, 5272.971770504715, 6453.515986300952, 6466.321036291216, 6484.383983521558, 6486.772962946275, 5855.473007429352, 6161.383861613803, 6200.224971878515, 6288.434040936155, 6307.490144546671, 5414.8646283841645, 5642.5485961122195, 5918.556108291809, 6130.325664176848, 6216.906746398718, 5345.732713364394, 5973.8207853762715, 6104.11490333908, 6206.875862482815, 6554.377334153248, 3144.701253380925, 4008.977710683757, 4084.7926635565414, 4087.3868919662286, 4124.257633625856, 5028.536017351398, 6023.969880150571, 6099.786507472217, 6182.227221597301, 6283.842903927255, 5909.52723782105, 5909.818036392825, 5976.372480889444, 6027.758889644365, 6067.332593414632, 5275.287836142072, 5442.37412697529, 5615.522680572197, 6003.489703375262, 6061.7575296987625, 5004.77478513475, 5229.450907654794, 5891.293044834647, 5899.0, 5954.612950158225, 6028.849278768066, 6039.214902062704, 6048.758049678139, 6086.682757896869, 6152.753889844356, 5733.569982251264, 5938.524918006636, 5957.017092179742, 5966.508372906774, 5983.581149319506], "value": 4238.351562781206, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.4876834983864, 756.7654027251549, 758.6320634492271, 791.4894892794148, 834.057515008041, 15057.849421505782, 15652.608684782886, 15892.364305427784, 16087.75868361974, 16142.515724528283, 14662.486812961548, 14696.412143514246, 14834.332455039523, 14854.628634284149, 14857.925710371434, 539.2746755613713, 727.5452841973766, 853.3130829682103, 876.0363600039937, 876.198278575103, 694.7498900395854, 838.7944953486405, 856.6530555125184, 868.50929224988, 873.244643383475, 880.6477409036372, 891.1934699097295, 893.2853717026385, 896.1173244354338, 898.4384759525296, 875.019977625064, 885.7829831691213, 897.8473659477904, 900.8513595256775, 901.3510272603747, 640.6892657061321, 649.7043845050491, 672.1363048482676, 672.4754691340762, 673.1282988529816, 14450.783238251435, 14487.0, 14525.0, 14611.634709132357, 14750.262486875721, 12795.680107997281, 14230.786538201935, 14295.928520357436, 14367.640808979755, 14708.33812478441, 11784.882151178464, 11889.464974076187, 11918.642440726791, 11929.701757456047, 11994.70013249667, 2935.6088762029417, 3636.345457817592, 3770.754900931458, 3858.9421158682644, 3913.863014794497, 102353.0, 103513.0, 103731.48134259356, 106426.0, 107474.46262768714, 10811.6215932442, 10857.782844343068, 11003.724906877233, 11182.36234229048, 11314.434278286217, 20192.69710954337, 20332.898335508227, 20443.795562044488, 20447.488812779502, 20495.69256461154, 7650.579218143055, 7730.072391313079, 7738.303552680173, 7887.2112788721815, 8034.879476807852, 4444.444444444444, 4466.977665111654, 4499.122671079108, 4516.683832131719, 4583.931241031387, 3293.226091868409, 3421.8289085545625, 3429.5198672185916, 3445.1214940190093, 3450.1202193440513, 620.3375744504084, 652.7650045983537, 654.1169421281327, 667.5994403357946, 667.6828506459393, 588.8555115682277, 594.7115648910275, 644.2508589180397, 667.4260136282793, 673.1316601584, 6873.76272270996, 8274.586270686441, 8717.520536370434, 8921.375503714678, 10025.845946254281, 564.9378568357478, 618.8452886778306, 622.2626187967273, 645.9031145328145, 676.7394553097093, 4238.33412963848, 4544.909101817945, 4547.886302842456, 4684.929726054112, 4728.338032675429, 114750.0, 130781.34609326892, 132241.0, 134752.32623836817, 137874.0, 101863.98136018692, 111113.33330000057, 116467.0, 121243.0, 132948.67051329557, 92051.53974230085, 93914.53042734743, 94434.0, 94696.00387963133, 102479.48760256301, 8008.439409241297, 9384.483853388128, 9868.210543156387, 10011.899881001096, 10038.899611003797, 56407.0, 56801.295961121235, 57520.4247957515, 57800.42199577951, 58112.70943645255, 66070.66964665338, 80384.0, 82573.17426825655, 83688.0, 84274.57862710647, 12632.873671263169, 12858.614241572768, 12882.935585322013, 12918.031147663774, 13098.541551045668, 14313.499027533986, 14404.711905761616, 14575.635609109433, 14946.177960212293, 15103.54689359321, 12417.875821241672, 12457.563985260473, 12520.0, 12696.17474864131, 13044.152130111512, 13449.932750336186, 13540.93229533846, 14001.22993235382, 14086.859131408555, 14121.929390352983, 13144.145630533985, 13186.604401867959, 13339.66650833737, 13622.455101795817, 13884.930575347058, 11213.831792522955, 11216.0, 11340.773184536098, 11366.488508016993, 11756.81738624565, 9250.907490925005, 9630.159103064472, 10275.894435180273, 11112.662710089078, 11521.88478115208], "value": 7597.428942173992, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1599.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 175.0, 119.0, 119.0, 117.0, 117.0, 117.0, 116.0, 118.0, 119.0], "value": 125.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 116.0, 119.0, 152.0, 114.0, 113.0, 117.0, 115.0, 175.0, 146.0], "value": 131.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 265.0, 291.0, 201.0, 196.0, 188.0, 208.0, 225.0, 213.0, 200.0], "value": 216.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 59.0, 88.0, 87.0, 74.0, 73.0, 81.0, 106.0, 81.0], "value": 79.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 57.0, 86.0, 102.0, 100.0, 103.0, 124.0, 65.0, 74.0, 118.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 105.0, 86.0, 88.0, 85.0, 78.0, 79.0, 80.0, 130.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 96.0, 150.0, 94.0, 95.0, 109.0, 156.0, 95.0, 96.0, 95.0], "value": 111.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 131.0, 93.0, 92.0, 91.0, 115.0, 117.0, 92.0, 85.0, 91.0], "value": 101.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 58.0, 58.0, 59.0, 60.0, 57.0, 57.0, 56.0, 59.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 67.0, 99.0, 71.0, 84.0, 98.0, 103.0, 95.0, 69.0, 69.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 93.0, 110.0, 111.0, 108.0, 109.0, 89.0, 90.0, 93.0, 87.0], "value": 97.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 139.0, 156.0, 160.0, 168.0, 178.0, 167.0, 155.0, 180.0, 153.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 176.0, 238.0, 189.0, 176.0, 176.0, 178.0, 175.0, 175.0, 176.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 69.0, 69.0, 74.0, 72.0, 69.0, 70.0, 71.0, 70.0], "value": 70.4, "unit": "score"}]}]} 23:47:36 INFO - Return code: 0 23:47:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:47:36 ERROR - # TBPL SUCCESS # 23:47:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:47:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:47:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:47:36 INFO - 2016-10-02 23:47:36.705 screenresolution[1965:22327] starting screenresolution argv=screenresolution get 23:47:36 INFO - 2016-10-02 23:47:36.707 screenresolution[1965:22327] Display 0: 1600x1200x32@60 23:47:36 INFO - 2016-10-02 23:47:36.714 screenresolution[1966:22330] starting screenresolution argv=screenresolution list 23:47:36 INFO - Available Modes on Display 0 23:47:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:47:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:47:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:47:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:47:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:47:36 INFO - Intel Iris: 23:47:36 INFO - Chipset Model: Intel Iris 23:47:36 INFO - Type: GPU 23:47:36 INFO - Bus: Built-In 23:47:36 INFO - VRAM (Dynamic, Max): 1536 MB 23:47:36 INFO - Vendor: Intel (0x8086) 23:47:36 INFO - Device ID: 0x0a2e 23:47:36 INFO - Revision ID: 0x0009 23:47:36 INFO - Displays: 23:47:36 INFO - Display: 23:47:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:47:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:47:36 INFO - Display Serial Number: 1600x1200 60 23:47:36 INFO - Main Display: Yes 23:47:36 INFO - Mirror: Off 23:47:36 INFO - Online: Yes 23:47:36 INFO - Rotation: Supported 23:47:36 INFO - Return code: 0 23:47:36 INFO - Running post-action listener: _resource_record_post_action 23:47:36 INFO - [mozharness: 2016-10-03 06:47:36.952367Z] Finished run-tests step (success) 23:47:36 INFO - Running post-run listener: _resource_record_post_run 23:47:37 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1324544; Write bytes: 715970560; Read time: 16; Write time: 2261 23:47:37 INFO - TinderboxPrint: CPU usage
54.5% 23:47:37 INFO - TinderboxPrint: I/O read bytes / time
1,324,544 / 16 23:47:37 INFO - TinderboxPrint: I/O write bytes / time
715,970,560 / 2,261 23:47:37 INFO - TinderboxPrint: CPU idle
2,208.9 (45.5%) 23:47:37 INFO - TinderboxPrint: CPU system
980.4 (20.2%) 23:47:37 INFO - TinderboxPrint: CPU user
1,660.3 (34.2%) 23:47:37 INFO - TinderboxPrint: Swap in / out
408,567,808 / 0 23:47:37 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 395643392; Write bytes: 392243200; Read time: 29500; Write time: 707 23:47:37 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1324544; Write bytes: 302751744; Read time: 16; Write time: 1509 23:47:37 INFO - Running post-run listener: _upload_blobber_files 23:47:37 INFO - Blob upload gear active. 23:47:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:47:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:47:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:47:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:47:37 INFO - (blobuploader) - INFO - Open directory for files ... 23:47:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:47:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:47:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:47:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:47:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:47:38 INFO - (blobuploader) - INFO - Done attempting. 23:47:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:47:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:47:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:47:39 INFO - (blobuploader) - INFO - Done attempting. 23:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:47:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:47:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:47:42 INFO - (blobuploader) - INFO - Done attempting. 23:47:42 INFO - (blobuploader) - INFO - Iteration through files over. 23:47:42 INFO - Return code: 0 23:47:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:47:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:47:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b7311d25d136b59d28f2e614503a88158faed8caafca0937c3132d257a1fa3b81d87443ebe7cf07abd374fa032e2df4a1ee09354cbbdd2df50cadf822b49476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ffe87ec435aefb287d42cab06fb3ad7b920cd7efb6d62e37c5e1109e82a80dd3f985fbd23f435173a8e987d04d9c0142a2e39d5a8d72a5ecb4ddc45c632ce0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8050780bce3398ad47f779fa7f53d02405af2856c20919fedfa2e7fa51368466a71819b0303145db659c684b43beaa15ba2356cd0387a51b8e9c24e5ce019f22"} 23:47:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:47:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:47:42 INFO - Contents: 23:47:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b7311d25d136b59d28f2e614503a88158faed8caafca0937c3132d257a1fa3b81d87443ebe7cf07abd374fa032e2df4a1ee09354cbbdd2df50cadf822b49476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ffe87ec435aefb287d42cab06fb3ad7b920cd7efb6d62e37c5e1109e82a80dd3f985fbd23f435173a8e987d04d9c0142a2e39d5a8d72a5ecb4ddc45c632ce0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8050780bce3398ad47f779fa7f53d02405af2856c20919fedfa2e7fa51368466a71819b0303145db659c684b43beaa15ba2356cd0387a51b8e9c24e5ce019f22"} 23:47:42 INFO - Running post-run listener: copy_logs_to_upload_dir 23:47:42 INFO - Copying logs to upload dir... 23:47:42 INFO - mkdir: /builds/slave/test/build/upload/logs 23:47:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.235469 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-02 23:47:42.709015) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-02 23:47:42.713600) ========= 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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b7311d25d136b59d28f2e614503a88158faed8caafca0937c3132d257a1fa3b81d87443ebe7cf07abd374fa032e2df4a1ee09354cbbdd2df50cadf822b49476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ffe87ec435aefb287d42cab06fb3ad7b920cd7efb6d62e37c5e1109e82a80dd3f985fbd23f435173a8e987d04d9c0142a2e39d5a8d72a5ecb4ddc45c632ce0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8050780bce3398ad47f779fa7f53d02405af2856c20919fedfa2e7fa51368466a71819b0303145db659c684b43beaa15ba2356cd0387a51b8e9c24e5ce019f22"} build_url:https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010941 build_url: 'https://queue.taskcluster.net/v1/task/XDto5zwxRM6j9qpjQQ39Mg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b7311d25d136b59d28f2e614503a88158faed8caafca0937c3132d257a1fa3b81d87443ebe7cf07abd374fa032e2df4a1ee09354cbbdd2df50cadf822b49476", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57ffe87ec435aefb287d42cab06fb3ad7b920cd7efb6d62e37c5e1109e82a80dd3f985fbd23f435173a8e987d04d9c0142a2e39d5a8d72a5ecb4ddc45c632ce0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8050780bce3398ad47f779fa7f53d02405af2856c20919fedfa2e7fa51368466a71819b0303145db659c684b43beaa15ba2356cd0387a51b8e9c24e5ce019f22"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-02 23:47:42.750487) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 23:47:42.751214) ========= 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.vWwPkFjU9o/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.dLaYGHV1gD/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.005296 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-02 23:47:42.804164) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-02 23:47:42.804830) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-02 23:47:42.805547) ========= ========= Total master_lag: 0.19 =========