builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0326 starttime: 1475563127.98 results: success (0) buildid: 20161003205303 builduid: 8f492a072f05464a8bce2f994a02de8c revision: 96c39d552134572a439c998a2f9577b6d8652c9a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:47.976017) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:47.976505) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:47.976792) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:48.004738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:48.005195) ========= 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.w9Tih7urp5/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.5B5FuBPMdS/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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-03 23:38:48-- 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% 18.8M=0.001s 2016-10-03 23:38:48 (18.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125108 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:48.161523) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:48.165202) ========= 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.w9Tih7urp5/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.5B5FuBPMdS/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.039421 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:48.225481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-03 23:38:48.225842) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 96c39d552134572a439c998a2f9577b6d8652c9a --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 96c39d552134572a439c998a2f9577b6d8652c9a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.w9Tih7urp5/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.5B5FuBPMdS/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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-03 23:38:48,320 truncating revision to first 12 chars 2016-10-03 23:38:48,320 Setting DEBUG logging. 2016-10-03 23:38:48,320 attempt 1/10 2016-10-03 23:38:48,320 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/96c39d552134?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-03 23:38:49,009 unpacking tar archive at: fx-team-96c39d552134/testing/mozharness/ program finished with exit code 0 elapsedTime=0.974679 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-03 23:38:49.228089) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:49.228431) ========= script_repo_revision: 96c39d552134572a439c998a2f9577b6d8652c9a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:49.228827) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:49.229116) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-03 23:38:49.247253) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-03 23:38:49.247644) ========= /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.w9Tih7urp5/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.5B5FuBPMdS/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:38:49 INFO - MultiFileLogger online at 20161003 23:38:49 in /builds/slave/test 23:38:49 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:38:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:38:49 INFO - {'append_to_log': False, 23:38:49 INFO - 'base_work_dir': '/builds/slave/test', 23:38:49 INFO - 'blob_upload_branch': 'Fx-Team', 23:38:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:38:49 INFO - 'branch': 'Fx-Team', 23:38:49 INFO - 'buildbot_json_path': 'buildprops.json', 23:38:49 INFO - 'config_files': ('talos/mac_config.py',), 23:38:49 INFO - 'default_actions': ('clobber', 23:38:49 INFO - 'read-buildbot-config', 23:38:49 INFO - 'download-and-extract', 23:38:49 INFO - 'populate-webroot', 23:38:49 INFO - 'create-virtualenv', 23:38:49 INFO - 'install', 23:38:49 INFO - 'run-tests'), 23:38:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:38:49 INFO - 'download_minidump_stackwalk': True, 23:38:49 INFO - 'download_symbols': 'ondemand', 23:38:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:38:49 INFO - 'tooltool.py': '/tools/tooltool.py', 23:38:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:38:49 INFO - '/tools/misc-python/virtualenv.py')}, 23:38:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:38:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:38:49 INFO - 'installer_path': 'installer.exe', 23:38:49 INFO - 'log_level': 'info', 23:38:49 INFO - 'log_name': 'talos', 23:38:49 INFO - 'log_to_console': True, 23:38:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:38:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:38:49 INFO - 'opt_config_files': (), 23:38:49 INFO - 'pip_index': False, 23:38:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:38:49 INFO - 'cmd': ('bash', 23:38:49 INFO - '-c', 23:38:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:38:49 INFO - 'enabled': True, 23:38:49 INFO - 'halt_on_failure': False, 23:38:49 INFO - 'name': 'check_screen_resolution'},), 23:38:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:38:49 INFO - 'cmd': ('bash', 23:38:49 INFO - '-c', 23:38:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:38:49 INFO - 'enabled': True, 23:38:49 INFO - 'halt_on_failure': False, 23:38:49 INFO - 'name': 'check_screen_resolution'},), 23:38:49 INFO - 'run_cmd_checks_enabled': True, 23:38:49 INFO - 'sps_profile': False, 23:38:49 INFO - 'sps_profile_interval': 0, 23:38:49 INFO - 'suite': 'dromaeojs-e10s', 23:38:49 INFO - 'system_bits': '32', 23:38:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:38:49 INFO - 'title': 't-yosemite-r7-0326', 23:38:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:38:49 INFO - 'use_talos_json': True, 23:38:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:38:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:38:49 INFO - 'work_dir': 'build'} 23:38:49 INFO - [mozharness: 2016-10-04 06:38:49.461485Z] Running clobber step. 23:38:49 INFO - Running pre-action listener: _resource_record_pre_action 23:38:49 INFO - Running main action method: clobber 23:38:49 INFO - rmtree: /builds/slave/test/build 23:38:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:38:51 INFO - Running post-action listener: _resource_record_post_action 23:38:51 INFO - [mozharness: 2016-10-04 06:38:51.491207Z] Finished clobber step (success) 23:38:51 INFO - [mozharness: 2016-10-04 06:38:51.491341Z] Running read-buildbot-config step. 23:38:51 INFO - Running pre-action listener: _resource_record_pre_action 23:38:51 INFO - Running main action method: read_buildbot_config 23:38:51 INFO - Using buildbot properties: 23:38:51 INFO - { 23:38:51 INFO - "project": "", 23:38:51 INFO - "product": "firefox", 23:38:51 INFO - "script_repo_revision": "production", 23:38:51 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 23:38:51 INFO - "repository": "", 23:38:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 23:38:51 INFO - "buildid": "20161003205303", 23:38:51 INFO - "pgo_build": "False", 23:38:51 INFO - "basedir": "/builds/slave/test", 23:38:51 INFO - "buildnumber": 30, 23:38:51 INFO - "slavename": "t-yosemite-r7-0326", 23:38:51 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 23:38:51 INFO - "platform": "yosemite_r7", 23:38:51 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 23:38:51 INFO - "branch": "fx-team", 23:38:51 INFO - "repo_path": "integration/fx-team", 23:38:51 INFO - "revision": "96c39d552134572a439c998a2f9577b6d8652c9a", 23:38:51 INFO - "stage_platform": "macosx64", 23:38:51 INFO - "builduid": "8f492a072f05464a8bce2f994a02de8c", 23:38:51 INFO - "slavebuilddir": "test" 23:38:51 INFO - } 23:38:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 23:38:51 INFO - Running post-action listener: _resource_record_post_action 23:38:51 INFO - [mozharness: 2016-10-04 06:38:51.494748Z] Finished read-buildbot-config step (success) 23:38:51 INFO - [mozharness: 2016-10-04 06:38:51.494882Z] Running download-and-extract step. 23:38:51 INFO - Running pre-action listener: _resource_record_pre_action 23:38:51 INFO - Running main action method: download_and_extract 23:38:51 INFO - mkdir: /builds/slave/test/build/tests 23:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:38:51 INFO - trying https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 23:38:51 INFO - Downloading https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/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:38:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/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:38:52 INFO - Downloaded 1199 bytes. 23:38:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 23:38:52 INFO - Using the following test package requirements: 23:38:52 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 23:38:52 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:38:52 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 23:38:52 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:38:52 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 23:38:52 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:38:52 INFO - u'jsshell-mac.zip'], 23:38:52 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:38:52 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 23:38:52 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 23:38:52 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:38:52 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 23:38:52 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:38:52 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 23:38:52 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:38:52 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 23:38:52 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 23:38:52 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 23:38:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:38:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 23:38:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:38:52 INFO - Fetch https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 23:38:53 INFO - Http code: 200 23:38:53 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 23:38:53 INFO - x-amz-cf-id: BPl_HAlvRdoS6EczlQlNGZExfdvDcnis8ALUttN_3Lah7kq_XaV3gw== 23:38:53 INFO - x-amz-version-id: c0ySaBRCF6TQVMEUTpHHe_k1SbYn8cH_ 23:38:53 INFO - Expected file size: 16476618 23:38:53 INFO - Obtained file size: 16476618 23:39:00 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:39:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 23:39:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:39:00 INFO - Fetch https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 23:39:00 INFO - Http code: 200 23:39:00 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 23:39:00 INFO - x-amz-cf-id: 0BDw_jIhfwE9oM-r30VDk13NQkNt_w6VLS_zmX8ln1h7Q-70mqJf5g== 23:39:00 INFO - x-amz-version-id: c0ySaBRCF6TQVMEUTpHHe_k1SbYn8cH_ 23:39:01 INFO - Expected file size: 16476618 23:39:01 INFO - Obtained file size: 16476618 23:39:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 23:39:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:39:07 INFO - Fetch https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 23:39:07 INFO - Http code: 200 23:39:08 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 23:39:08 INFO - x-amz-cf-id: Gk_zBizVJdla5t8O89T6rKdn-ufeS8KERbHmAufcGPE5ce8K8HQWJg== 23:39:08 INFO - x-amz-version-id: hMBG04CmKbq9QA3lxvfIZF0LHJmaZ_sC 23:39:08 INFO - Expected file size: 14409006 23:39:08 INFO - Obtained file size: 14409006 23:39: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:39:08 INFO - trying https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 23:39:08 INFO - Downloading https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 23:39:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/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:39:11 INFO - Downloaded 111652867 bytes. 23:39:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 23:39:11 INFO - mkdir: /builds/slave/test/properties 23:39:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:39:11 INFO - Writing to file /builds/slave/test/properties/build_url 23:39:11 INFO - Contents: 23:39:11 INFO - build_url:https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 23:39:12 INFO - Running post-action listener: _resource_record_post_action 23:39:12 INFO - Running post-action listener: set_extra_try_arguments 23:39:12 INFO - [mozharness: 2016-10-04 06:39:12.489003Z] Finished download-and-extract step (success) 23:39:12 INFO - [mozharness: 2016-10-04 06:39:12.489128Z] Running populate-webroot step. 23:39:12 INFO - Running pre-action listener: _resource_record_pre_action 23:39:12 INFO - Running main action method: populate_webroot 23:39:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:39:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:39:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:39:12 INFO - '--tppagecycles', 23:39:12 INFO - '7'], 23:39:12 INFO - 'tests': ['tsvgm']}}, 23:39:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:39:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:39:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:39:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:39:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:12 INFO - 'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:39:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:39:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:12 INFO - 'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['damp', 'tps']}, 23:39:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:12 INFO - 'tests': ['damp', 'tps']}, 23:39:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['dromaeo_dom']}, 23:39:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:39:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['basic_compositor_video']}, 23:39:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:39:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['a11yr', 23:39:12 INFO - 'ts_paint', 23:39:12 INFO - 'tpaint', 23:39:12 INFO - 'sessionrestore', 23:39:12 INFO - 'sessionrestore_no_auto_restore', 23:39:12 INFO - 'tabpaint']}, 23:39:12 INFO - 'other-e10s': {'tests': ['a11yr', 23:39:12 INFO - 'ts_paint', 23:39:12 INFO - 'tpaint', 23:39:12 INFO - 'sessionrestore', 23:39:12 INFO - 'sessionrestore_no_auto_restore', 23:39:12 INFO - 'tabpaint']}, 23:39:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:39:12 INFO - 'ts_paint', 23:39:12 INFO - 'tpaint', 23:39:12 INFO - 'sessionrestore', 23:39:12 INFO - 'sessionrestore_no_auto_restore', 23:39:12 INFO - 'tabpaint']}, 23:39:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:39:12 INFO - 'ts_paint', 23:39:12 INFO - 'tpaint', 23:39:12 INFO - 'sessionrestore', 23:39:12 INFO - 'sessionrestore_no_auto_restore', 23:39:12 INFO - 'tabpaint']}, 23:39:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['a11yr', 23:39:12 INFO - 'ts_paint', 23:39:12 INFO - 'tpaint', 23:39:12 INFO - 'sessionrestore', 23:39:12 INFO - 'sessionrestore_no_auto_restore', 23:39:12 INFO - 'tabpaint']}, 23:39:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['a11yr', 23:39:12 INFO - 'ts_paint', 23:39:12 INFO - 'tpaint', 23:39:12 INFO - 'sessionrestore', 23:39:12 INFO - 'sessionrestore_no_auto_restore', 23:39:12 INFO - 'tabpaint']}, 23:39:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['tsvgx', 23:39:12 INFO - 'tsvgr_opacity', 23:39:12 INFO - 'tart', 23:39:12 INFO - 'tscrollx', 23:39:12 INFO - 'cart']}, 23:39:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:39:12 INFO - 'tsvgr_opacity', 23:39:12 INFO - 'tart', 23:39:12 INFO - 'tscrollx', 23:39:12 INFO - 'cart']}, 23:39:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:12 INFO - 'talos_options': ['--disable-e10s'], 23:39:12 INFO - 'tests': ['tp5o']}, 23:39:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:12 INFO - 'tests': ['tp5o']}, 23:39:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:12 INFO - 'talos_options': ['--disable-e10s', 23:39:12 INFO - '--xperf_path', 23:39:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:39:12 INFO - 'tests': ['tp5n']}, 23:39:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:12 INFO - 'talos_options': ['--xperf_path', 23:39:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:39:12 INFO - 'tests': ['tp5n']}}, 23:39:12 INFO - 'talos.zip': {'path': '', 23:39:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:39:12 INFO - Running post-action listener: _resource_record_post_action 23:39:12 INFO - [mozharness: 2016-10-04 06:39:12.508322Z] Finished populate-webroot step (success) 23:39:12 INFO - [mozharness: 2016-10-04 06:39:12.508441Z] Running create-virtualenv step. 23:39:12 INFO - Running pre-action listener: _resource_record_pre_action 23:39:12 INFO - Running main action method: create_virtualenv 23:39:12 INFO - Creating virtualenv /builds/slave/test/build/venv 23:39:12 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:39:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:39:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:39:12 INFO - Using real prefix '/tools/python27' 23:39:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:39:13 INFO - Installing distribute.............................................................................................................................................................................................done. 23:39:16 INFO - Installing pip.................done. 23:39:16 INFO - Return code: 0 23:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:39:16 INFO - Reading from file tmpfile_stdout 23:39:16 INFO - Output received: 23:39:16 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:39:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:16 INFO - 'HOME': '/Users/cltbld', 23:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:16 INFO - 'LOGNAME': 'cltbld', 23:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:16 INFO - 'MOZ_NO_REMOTE': '1', 23:39:16 INFO - 'NO_EM_RESTART': '1', 23:39:16 INFO - 'PAGER': '/bin/cat', 23:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:16 INFO - 'PWD': '/builds/slave/test', 23:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:16 INFO - 'SHELL': '/bin/bash', 23:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:16 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:16 INFO - 'USER': 'cltbld', 23:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:16 INFO - 'XPC_FLAGS': '0x0', 23:39:16 INFO - 'XPC_SERVICE_NAME': '0', 23:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:16 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:39:16 INFO - Cleaning up... 23:39:16 INFO - Return code: 0 23:39:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:16 INFO - 'HOME': '/Users/cltbld', 23:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:16 INFO - 'LOGNAME': 'cltbld', 23:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:16 INFO - 'MOZ_NO_REMOTE': '1', 23:39:16 INFO - 'NO_EM_RESTART': '1', 23:39:16 INFO - 'PAGER': '/bin/cat', 23:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:16 INFO - 'PWD': '/builds/slave/test', 23:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:16 INFO - 'SHELL': '/bin/bash', 23:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:16 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:16 INFO - 'USER': 'cltbld', 23:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:16 INFO - 'XPC_FLAGS': '0x0', 23:39:16 INFO - 'XPC_SERVICE_NAME': '0', 23:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:17 INFO - Downloading/unpacking psutil>=3.1.1 23:39:17 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:39:17 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:39:17 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:39:17 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:39:17 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:39:17 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:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:39:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:39:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:39:20 INFO - Installing collected packages: psutil 23:39:20 INFO - Running setup.py install for psutil 23:39:20 INFO - building 'psutil._psutil_osx' extension 23:39:20 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:39:20 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:39:20 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:39:21 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:39:21 INFO - building 'psutil._psutil_posix' extension 23:39:21 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:39:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:39:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:39:21 INFO - ^ 23:39:21 INFO - 1 warning generated. 23:39:21 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:39:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:39:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:39:21 INFO - Successfully installed psutil 23:39:21 INFO - Cleaning up... 23:39:21 INFO - Return code: 0 23:39:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:21 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 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:21 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:39:21 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:39:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:21 INFO - 'HOME': '/Users/cltbld', 23:39:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:21 INFO - 'LOGNAME': 'cltbld', 23:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:21 INFO - 'MOZ_NO_REMOTE': '1', 23:39:21 INFO - 'NO_EM_RESTART': '1', 23:39:21 INFO - 'PAGER': '/bin/cat', 23:39:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:21 INFO - 'PWD': '/builds/slave/test', 23:39:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:21 INFO - 'SHELL': '/bin/bash', 23:39:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:21 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:21 INFO - 'USER': 'cltbld', 23:39:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:21 INFO - 'XPC_FLAGS': '0x0', 23:39:21 INFO - 'XPC_SERVICE_NAME': '0', 23:39:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:39:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:39:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:39:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:39:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:39:24 INFO - Installing collected packages: mozsystemmonitor 23:39:24 INFO - Running setup.py install for mozsystemmonitor 23:39:24 INFO - Successfully installed mozsystemmonitor 23:39:24 INFO - Cleaning up... 23:39:24 INFO - Return code: 0 23:39:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:24 INFO - 'HOME': '/Users/cltbld', 23:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:24 INFO - 'LOGNAME': 'cltbld', 23:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:24 INFO - 'MOZ_NO_REMOTE': '1', 23:39:24 INFO - 'NO_EM_RESTART': '1', 23:39:24 INFO - 'PAGER': '/bin/cat', 23:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:24 INFO - 'PWD': '/builds/slave/test', 23:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:24 INFO - 'SHELL': '/bin/bash', 23:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:24 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:24 INFO - 'USER': 'cltbld', 23:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:24 INFO - 'XPC_FLAGS': '0x0', 23:39:24 INFO - 'XPC_SERVICE_NAME': '0', 23:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:24 INFO - Downloading/unpacking jsonschema==2.5.1 23:39:24 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:39:24 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:39:24 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:39:24 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:39:24 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:39:24 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:39:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:39:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:39:27 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:39:27 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:39:27 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:39:27 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:39:27 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:39:27 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:39:27 INFO - Downloading functools32-3.2.3-2.tar.gz 23:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:39:27 INFO - warning: no files found matching '*.txt' 23:39:27 INFO - no previously-included directories found matching 'build' 23:39:27 INFO - no previously-included directories found matching 'dist' 23:39:27 INFO - no previously-included directories found matching '.git*' 23:39:27 INFO - Installing collected packages: jsonschema, functools32 23:39:27 INFO - Running setup.py install for functools32 23:39:27 INFO - warning: no files found matching '*.txt' 23:39:27 INFO - no previously-included directories found matching 'build' 23:39:27 INFO - no previously-included directories found matching 'dist' 23:39:27 INFO - no previously-included directories found matching '.git*' 23:39:27 INFO - Successfully installed jsonschema functools32 23:39:27 INFO - Cleaning up... 23:39:27 INFO - Return code: 0 23:39:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:39:27 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:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:27 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:39:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:27 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 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:27 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:39:27 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:39:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:27 INFO - 'HOME': '/Users/cltbld', 23:39:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:27 INFO - 'LOGNAME': 'cltbld', 23:39:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:27 INFO - 'MOZ_NO_REMOTE': '1', 23:39:27 INFO - 'NO_EM_RESTART': '1', 23:39:27 INFO - 'PAGER': '/bin/cat', 23:39:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:27 INFO - 'PWD': '/builds/slave/test', 23:39:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:27 INFO - 'SHELL': '/bin/bash', 23:39:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:27 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:27 INFO - 'USER': 'cltbld', 23:39:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:27 INFO - 'XPC_FLAGS': '0x0', 23:39:27 INFO - 'XPC_SERVICE_NAME': '0', 23:39:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:39:28 INFO - Cleaning up... 23:39:28 INFO - Return code: 0 23:39:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:39:28 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:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:28 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:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:28 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 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:28 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:39:28 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:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:28 INFO - 'HOME': '/Users/cltbld', 23:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:28 INFO - 'LOGNAME': 'cltbld', 23:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:28 INFO - 'MOZ_NO_REMOTE': '1', 23:39:28 INFO - 'NO_EM_RESTART': '1', 23:39:28 INFO - 'PAGER': '/bin/cat', 23:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:28 INFO - 'PWD': '/builds/slave/test', 23:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:28 INFO - 'SHELL': '/bin/bash', 23:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:28 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:28 INFO - 'USER': 'cltbld', 23:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:28 INFO - 'XPC_FLAGS': '0x0', 23:39:28 INFO - 'XPC_SERVICE_NAME': '0', 23:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:28 INFO - Downloading/unpacking blobuploader==1.2.4 23:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:39:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:39:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:39:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:39:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:39:30 INFO - Downloading blobuploader-1.2.4.tar.gz 23:39:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:39:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:39:31 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:39:31 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:39:31 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:39:31 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:39:31 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:39:31 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:39:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:39:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:39:31 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:39:31 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:39:31 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:39:31 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:39:31 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:39:31 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:39:32 INFO - Downloading docopt-0.6.1.tar.gz 23:39:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:39:32 INFO - Installing collected packages: blobuploader, requests, docopt 23:39:32 INFO - Running setup.py install for blobuploader 23:39:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:39:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:39:32 INFO - Running setup.py install for requests 23:39:32 INFO - Running setup.py install for docopt 23:39:33 INFO - Successfully installed blobuploader requests docopt 23:39:33 INFO - Cleaning up... 23:39:33 INFO - Return code: 0 23:39:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:33 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 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:33 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:39:33 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:39:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:33 INFO - 'HOME': '/Users/cltbld', 23:39:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:33 INFO - 'LOGNAME': 'cltbld', 23:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:33 INFO - 'MOZ_NO_REMOTE': '1', 23:39:33 INFO - 'NO_EM_RESTART': '1', 23:39:33 INFO - 'PAGER': '/bin/cat', 23:39:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:33 INFO - 'PWD': '/builds/slave/test', 23:39:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:33 INFO - 'SHELL': '/bin/bash', 23:39:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:33 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:33 INFO - 'USER': 'cltbld', 23:39:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:33 INFO - 'XPC_FLAGS': '0x0', 23:39:33 INFO - 'XPC_SERVICE_NAME': '0', 23:39:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:39:33 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-q4gNrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:39:33 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-NZ_eXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:39:33 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-py8aQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:39:33 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-GnygAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:39:33 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-OIbWGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:39:33 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Hp8HzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:39:33 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-f1XUgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:39:34 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-xZskKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:39:34 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-2Dj6vB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:39:34 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-xZoLaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:39:34 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-93S965-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:39:34 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-djlj2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:39:34 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-4iJ4Md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:39:34 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-l9sIa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:39:34 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-gD3ZBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:39:34 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-MMAGkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:39:35 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-fIuelh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:39:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:39:35 INFO - Running setup.py install for manifestparser 23:39:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:39:35 INFO - Running setup.py install for mozcrash 23:39:35 INFO - Running setup.py install for mozdebug 23:39:35 INFO - Running setup.py install for mozdevice 23:39:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:39:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:39:35 INFO - Running setup.py install for mozfile 23:39:35 INFO - Running setup.py install for mozhttpd 23:39:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:39:36 INFO - Running setup.py install for mozinfo 23:39:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:39:36 INFO - Running setup.py install for mozInstall 23:39:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:39:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:39:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:39:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:39:36 INFO - Running setup.py install for mozleak 23:39:36 INFO - Running setup.py install for mozlog 23:39:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:39:36 INFO - Running setup.py install for moznetwork 23:39:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:39:36 INFO - Running setup.py install for mozprocess 23:39:36 INFO - Running setup.py install for mozprofile 23:39:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:39:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:39:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:39:37 INFO - Running setup.py install for mozrunner 23:39:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:39:37 INFO - Running setup.py install for mozscreenshot 23:39:37 INFO - Running setup.py install for moztest 23:39:37 INFO - Running setup.py install for mozversion 23:39:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:39:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:39:37 INFO - Cleaning up... 23:39:37 INFO - Return code: 0 23:39:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:39:37 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:39:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:37 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:39:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:37 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 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:37 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:39:37 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:39:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:37 INFO - 'HOME': '/Users/cltbld', 23:39:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:37 INFO - 'LOGNAME': 'cltbld', 23:39:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:37 INFO - 'MOZ_NO_REMOTE': '1', 23:39:37 INFO - 'NO_EM_RESTART': '1', 23:39:37 INFO - 'PAGER': '/bin/cat', 23:39:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:37 INFO - 'PWD': '/builds/slave/test', 23:39:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:37 INFO - 'SHELL': '/bin/bash', 23:39:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:37 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:37 INFO - 'USER': 'cltbld', 23:39:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:37 INFO - 'XPC_FLAGS': '0x0', 23:39:37 INFO - 'XPC_SERVICE_NAME': '0', 23:39:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:39:37 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-_gCCUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:39:38 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:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:39:38 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-USeINx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:39:38 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:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:39:38 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-LZ48iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:39:38 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:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:39:38 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-202uAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:39:38 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:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:39:38 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-xG14BV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:39:38 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:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:39:38 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-pBIDGQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:39:38 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:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:39:38 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-UYshvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:39:38 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:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:39:38 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-prk31W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:39:38 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:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:39:38 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-DcEJG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:39:38 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:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:39:38 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-UY3Bww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:39:39 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:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:39:39 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-YS5hpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:39:39 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:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:39:39 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-e5aY54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:39:39 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:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:39:39 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-Rxp_ZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:39:39 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:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:39:39 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-IlDIaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:39:39 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:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:39:39 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-LUEVSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:39:39 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:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:39:39 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-zc097w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:39:39 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:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:39:39 INFO - Running setup.py (path:/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/pip-toD0p6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:39:39 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:39:39 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:39:39 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:39:39 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:39:39 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:39:39 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:39:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:39:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:39:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:39:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:39:42 INFO - Downloading blessings-1.6.tar.gz 23:39:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:39:42 INFO - Installing collected packages: blessings 23:39:42 INFO - Running setup.py install for blessings 23:39:42 INFO - Successfully installed blessings 23:39:42 INFO - Cleaning up... 23:39:42 INFO - Return code: 0 23:39:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:39:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:39:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:39:43 INFO - Reading from file tmpfile_stdout 23:39:43 INFO - Current package versions: 23:39:43 INFO - blessings == 1.6 23:39:43 INFO - blobuploader == 1.2.4 23:39:43 INFO - docopt == 0.6.1 23:39:43 INFO - functools32 == 3.2.3-2 23:39:43 INFO - jsonschema == 2.5.1 23:39:43 INFO - manifestparser == 1.1 23:39:43 INFO - mozInstall == 1.12 23:39:43 INFO - mozcrash == 0.17 23:39:43 INFO - mozdebug == 0.1 23:39:43 INFO - mozdevice == 0.48 23:39:43 INFO - mozfile == 1.2 23:39:43 INFO - mozhttpd == 0.7 23:39:43 INFO - mozinfo == 0.9 23:39:43 INFO - mozleak == 0.1 23:39:43 INFO - mozlog == 3.4 23:39:43 INFO - moznetwork == 0.27 23:39:43 INFO - mozprocess == 0.23 23:39:43 INFO - mozprofile == 0.28 23:39:43 INFO - mozrunner == 6.12 23:39:43 INFO - mozscreenshot == 0.1 23:39:43 INFO - mozsystemmonitor == 0.3 23:39:43 INFO - moztest == 0.7 23:39:43 INFO - mozversion == 1.4 23:39:43 INFO - psutil == 3.1.1 23:39:43 INFO - requests == 1.2.3 23:39:43 INFO - wsgiref == 0.1.2 23:39:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:43 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 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:43 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:39:43 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:39:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:43 INFO - 'HOME': '/Users/cltbld', 23:39:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:43 INFO - 'LOGNAME': 'cltbld', 23:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:43 INFO - 'MOZ_NO_REMOTE': '1', 23:39:43 INFO - 'NO_EM_RESTART': '1', 23:39:43 INFO - 'PAGER': '/bin/cat', 23:39:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:43 INFO - 'PWD': '/builds/slave/test', 23:39:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:43 INFO - 'SHELL': '/bin/bash', 23:39:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:43 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:43 INFO - 'USER': 'cltbld', 23:39:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:43 INFO - 'XPC_FLAGS': '0x0', 23:39:43 INFO - 'XPC_SERVICE_NAME': '0', 23:39:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:43 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:39:43 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:39:43 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:39:43 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:39:43 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:39:43 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:39:43 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:39:43 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:39:43 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:39:43 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:39:43 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:39:43 INFO - Cleaning up... 23:39:43 INFO - Return code: 0 23:39:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:39:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:39:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:39:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:39:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b290490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b206570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff230cbe430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, '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.w9Tih7urp5/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.5B5FuBPMdS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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:39:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:39:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:39:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:39:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:39:43 INFO - 'HOME': '/Users/cltbld', 23:39:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:39:43 INFO - 'LOGNAME': 'cltbld', 23:39:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:39:43 INFO - 'MOZ_NO_REMOTE': '1', 23:39:43 INFO - 'NO_EM_RESTART': '1', 23:39:43 INFO - 'PAGER': '/bin/cat', 23:39:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:39:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:39:43 INFO - 'PWD': '/builds/slave/test', 23:39:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:39:43 INFO - 'SHELL': '/bin/bash', 23:39:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:39:43 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:39:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:39:43 INFO - 'USER': 'cltbld', 23:39:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:39:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:39:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:39:43 INFO - 'XPC_FLAGS': '0x0', 23:39:43 INFO - 'XPC_SERVICE_NAME': '0', 23:39:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:39:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:39:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:39:43 INFO - Cleaning up... 23:39:43 INFO - Return code: 0 23:39:43 INFO - Running post-action listener: _resource_record_post_action 23:39:43 INFO - Running post-action listener: _start_resource_monitoring 23:39:43 INFO - Starting resource monitoring. 23:39:43 INFO - [mozharness: 2016-10-04 06:39:43.597660Z] Finished create-virtualenv step (success) 23:39:43 INFO - [mozharness: 2016-10-04 06:39:43.598711Z] Running install step. 23:39:43 INFO - Running pre-action listener: _resource_record_pre_action 23:39:43 INFO - Running main action method: install 23:39:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:39:43 INFO - Reading from file tmpfile_stdout 23:39:43 INFO - Detecting whether we're running mozinstall >=1.0... 23:39:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:39:43 INFO - Reading from file tmpfile_stdout 23:39:43 INFO - Output received: 23:39:43 INFO - Usage: mozinstall [options] installer 23:39:43 INFO - Options: 23:39:43 INFO - -h, --help show this help message and exit 23:39:43 INFO - -d DEST, --destination=DEST 23:39:43 INFO - Directory to install application into. [default: 23:39:43 INFO - "/builds/slave/test"] 23:39:43 INFO - --app=APP Application being installed. [default: firefox] 23:39:43 INFO - mkdir: /builds/slave/test/build/application 23:39:43 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:39:43 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:40:18 INFO - Reading from file tmpfile_stdout 23:40:18 INFO - Output received: 23:40:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:40:18 INFO - Running post-action listener: _resource_record_post_action 23:40:18 INFO - [mozharness: 2016-10-04 06:40:18.175928Z] Finished install step (success) 23:40:18 INFO - [mozharness: 2016-10-04 06:40:18.176075Z] Running run-tests step. 23:40:18 INFO - Running pre-action listener: _resource_record_pre_action 23:40:18 INFO - Running main action method: run_tests 23:40:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:40:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:40:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:40:18 INFO - 2016-10-03 23:40:18.190 screenresolution[1639:7388] starting screenresolution argv=screenresolution get 23:40:18 INFO - 2016-10-03 23:40:18.193 screenresolution[1639:7388] Display 0: 1600x1200x32@60 23:40:18 INFO - 2016-10-03 23:40:18.199 screenresolution[1640:7390] starting screenresolution argv=screenresolution list 23:40:18 INFO - Available Modes on Display 0 23:40:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:40:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:40:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:40:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:40:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:40:18 INFO - Intel Iris: 23:40:18 INFO - Chipset Model: Intel Iris 23:40:18 INFO - Type: GPU 23:40:18 INFO - Bus: Built-In 23:40:18 INFO - VRAM (Dynamic, Max): 1536 MB 23:40:18 INFO - Vendor: Intel (0x8086) 23:40:18 INFO - Device ID: 0x0a2e 23:40:18 INFO - Revision ID: 0x0009 23:40:18 INFO - Displays: 23:40:18 INFO - Display: 23:40:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:40:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:40:18 INFO - Display Serial Number: 1600x1200 60 23:40:18 INFO - Main Display: Yes 23:40:18 INFO - Mirror: Off 23:40:18 INFO - Online: Yes 23:40:18 INFO - Rotation: Supported 23:40:18 INFO - Return code: 0 23:40:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:40:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:40:18 INFO - Python 2.7.3 23:40:18 INFO - Return code: 0 23:40:18 INFO - grabbing minidump binary from tooltool 23:40: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:40:18 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 0x10b0a4d30>, '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 0x10aecadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b09cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:40:18 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:40:18 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:40:18 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:40:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:40:18 INFO - Return code: 0 23:40:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:40:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:40:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:40:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:40:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:40:18 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/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--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:40:18 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/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0326 --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:40:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.w9Tih7urp5/Render', 23:40:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:40:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:40:18 INFO - 'HOME': '/Users/cltbld', 23:40:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:40:18 INFO - 'LOGNAME': 'cltbld', 23:40:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:40:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:40:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:18 INFO - 'MOZ_NO_REMOTE': '1', 23:40:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:40:18 INFO - 'NO_EM_RESTART': '1', 23:40:18 INFO - 'PAGER': '/bin/cat', 23:40:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:40:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:18 INFO - 'PWD': '/builds/slave/test', 23:40:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:40:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:40:18 INFO - 'SHELL': '/bin/bash', 23:40:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5B5FuBPMdS/Listeners', 23:40:18 INFO - 'TMPDIR': '/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/', 23:40:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:40:18 INFO - 'USER': 'cltbld', 23:40:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:40:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:40:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:18 INFO - 'XPC_FLAGS': '0x0', 23:40:18 INFO - 'XPC_SERVICE_NAME': '0', 23:40:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:40:18 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/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0326', '--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:40:18 INFO - mozversion application_buildid: 20161003205303 23:40:18 INFO - mozversion application_changeset: 96c39d552134572a439c998a2f9577b6d8652c9a 23:40:18 INFO - mozversion application_display_name: Nightly 23:40:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:40:18 INFO - mozversion application_name: Firefox 23:40:18 INFO - mozversion application_remotingname: firefox 23:40:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:40:18 INFO - mozversion application_vendor: Mozilla 23:40:18 INFO - mozversion application_version: 52.0a1 23:40:18 INFO - mozversion platform_buildid: 20161003205303 23:40:18 INFO - mozversion platform_changeset: 96c39d552134572a439c998a2f9577b6d8652c9a 23:40:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:40:18 INFO - mozversion platform_version: 52.0a1 23:40:18 INFO - using testdate: 1475563218 23:40:18 INFO - actual date: 1475563218 23:40:18 INFO - starting webserver on 'localhost:49266' 23:40:18 INFO - SUITE-START | Running 2 tests 23:40:18 INFO - TEST-START | dromaeo_css 23:40:18 INFO - operating with platform_type : mac_ 23:40:18 INFO - Initialising browser for dromaeo_css test... 23:40:18 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmp3O6zxy/profile http://localhost:49266/getInfo.html) 23:40:21 INFO - PROCESS | 1650 | 2016-10-03 23:40:21.428 plugin-container[1652:7549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 23:40:21 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:40:21 INFO - PROCESS | 1650 | 2016-10-03 23:40:21.431 plugin-container[1652:7549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 23:40:21 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:40:21 INFO - PROCESS | 1650 | 2016-10-03 23:40:21.431 plugin-container[1652:7549] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:40:21 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 23:40:21 INFO - PROCESS | 1650 | colorDepth:24 23:40:21 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 23:40:21 INFO - PROCESS | 1650 | __metrics 23:40:21 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 23:40:22 INFO - TEST-INFO | 1650: exit 0 23:40:22 INFO - Browser initialized. 23:40:22 INFO - Running cycle 1/1 for dromaeo_css test... 23:40:22 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmp3O6zxy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:40:24 INFO - PROCESS | 1653 | 2016-10-03 23:40:24.797 plugin-container[1657:7729] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a2f, name = 'com.apple.tsm.portname' 23:40:24 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:40:24 INFO - PROCESS | 1653 | 2016-10-03 23:40:24.800 plugin-container[1657:7729] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 23:40:24 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:40:24 INFO - PROCESS | 1653 | 2016-10-03 23:40:24.800 plugin-container[1657:7729] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:40:34 INFO - PROCESS | 1653 | RSS: Main: 240402432 23:40:34 INFO - PROCESS | 1653 | 23:41:24 INFO - PROCESS | 1653 | 1475563284144 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:43:38 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 23:43:39 INFO - PROCESS | 1653 | RSS: Main: 253874176 23:43:39 INFO - PROCESS | 1653 | 23:46:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 23:46:42 INFO - PROCESS | 1653 | RSS: Main: 266088448 23:46:42 INFO - PROCESS | 1653 | 23:49:44 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 23:49:45 INFO - PROCESS | 1653 | RSS: Main: 271351808 23:49:45 INFO - PROCESS | 1653 | 23:52:47 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 23:52:47 INFO - PROCESS | 1653 | RSS: Main: 271093760 23:52:47 INFO - PROCESS | 1653 | 23:55:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 23:55:51 INFO - PROCESS | 1653 | RSS: Main: 276475904 23:55:51 INFO - PROCESS | 1653 | 23:58:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 23:58:54 INFO - PROCESS | 1653 | RSS: Main: 278843392 23:58:54 INFO - PROCESS | 1653 | 23:58:54 INFO - PROCESS | 1653 | __start_tp_report 23:58:54 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 23:58:54 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 23:58:54 INFO - PROCESS | 1653 | |i|pagename|runs| 23:58:54 INFO - PROCESS | 1653 | |0;dojo.html;3045.4213699397114;3411.624721280659;3420.956608234489;3451.0682115828713;3469.583649962004;5620.550355971523;5722.7424765885535;5734.254546908906;5778.739956701953;5829.300483941933;5685.8578535536635;5726.971365143168;5775.162601422791;5799.594028418012;5853.356130825606;5171;5189.117849965496;5237.10969135246;5309.73451327434;5345.973270133663;3898.6491215790575;4179.749215047092;5301.416844147141;5336.332958380202;5368.221607866857;5130.461301563357;5162.457941916082;5281.339832520935;5296.285001524802;5323.520883120499;5105.948940510584;5142.794288228466;5226.55574276187;5306.7611957462;5356.009138309425;5198.6880787152895;5200.557952574036;5215.348081489814;5239.580833533309;5244.160934250502;5529.281193444866;5584.246126772874;5746.454086861741;5767.567432442584;5785.537157027427;6105.004892149239;6108.736706092843;6128.662923539204;6176.351483094255;6204.720787564571;6203.4416902479;6247.125402443661;6255.687215639246;6266.404691554295;6269.028300613412;5939.762409503658;5940.079287710412;5944.762209511571;5959.582829201914;6002.639841609517;6141.977005061692;6150.862090513269;6205.81382558524;6208.410201030895;6252.343503932113;6168.414000669929;6199.721012554446;6208.410201030895;6211.9068213976825;6212.06818977157;5804.577878419815;6103.034636161374;6265.843353916098;6267.843303917439;6292.968535157386;5812.157237200582;6189.102580125857;6198.380161983856;6270.811875643738;6279.183706118175;6205.968970155211;6255;6266.843328916813;6267.9373206267355;6270.216222972129;4847.394075740533;4973.776180071905;4976.776045077981;4982.402111746614;5023.874403139951;4416.580424859633;4423.579759922803;4442;4519.7740112994215;4583.862484125481;5844.47399734026;5855.970720146457;5931.881362372728;5941.910871336933;5944.881102377928;6168.352323006109;6215.440610345091;6244.687765611701;6249.812505624838;6250.781222657276;5685.857853553694;5748.913766293509;5843.503302219359;5873.148393483007;5928.970355148197;5428;5447.972760136174;5450.400455949843;5454.481824226692;5505.119180931032;6247.187865577446;6277.843053923597;6286.088517165077;6309.526785491014;6316.968415157895;6109.040306147876;6177.351378105234;6186.1030150627;6186.319504854552;6248.8752024636005;6245.406686364788;6256.562040657111;6260.843478913063;6276.403741644536;6283.994560870239;6192.442680158807;6200.56596038273;6212.409821066992;6278.309385967631;6286.748530058747;6030;6128.442013630826;6161.353057928853;6237.345078766845;6264.718087685974;6101.481374083252;6120.17377654011;6125.173101631401;6184.690765461799;6195.690215489298;6116.357782432959;6166.4758495526585;6180;6182.28903676083;6184.165137706352;5724.998125328134;5788.739506722123;5866.208061911588;5992.0712289595185;6016.969915150396;4506.211413002646;4902.509749025069;6165.075238714228;6178.752849886134;6189;4692.296155576554;5890.381509941394;5942.078977758454;6043.667598282137;6083.300420451705 23:58:54 INFO - PROCESS | 1653 | |1;ext.html;508.0676957782469;523.8166641675414;529.8304542546384;529.9549538289245;533.514501803359;40611.18777624446;42213.78893105538;42341.788291058576;42409.99999999992;42421.99999999996;43380.78309608455;43831.78084109583;43966.340504892374;43977;44184.3372349415;9808.803823923536;9915.008499150063;9927.453990030546;9966.053224943618;9975.800483990315;9826.312158148932;9895.356801807895;9926.305158638897;9950.253730970206;9997.750056248578;9876.654317098903;9904.306698531105;9958.402495850236;9991.850122248172;10003.74990625233;9912;9945.950270248675;9968;9968.053034961667;9991.650292239809;9720.347540918061;9729.805403891953;9734.805303893883;9827.557759900821;9835.754106147331;40906.79546602248;41013.589864101574;41249.79375103105;41284.174316513505;41371.37942930858;3244.6430892601793;3261.756452864723;3309.9879110009774;3339.2152844081866;3421.7775844570274;30002.84998575015;31299.53050704241;31605;31681.36637267242;31712.524312135338;2943.8969636062634;3143.3241853001714;3178.8728450861936;3246.3507298540385;3400.914977125567;355298;421212;422041;424026;425100.9999999969;65107.02339464912;65241.347586524476;65963;65997;66024.33975660279;68259.31740682629;68427;69152.6542367285;69483.30516694768;69719.3028069723;16021.198940052951;16504.33982640681;16700.582485437717;16756.91621541909;16775.161241937854;12221.205621634565;12527.624171274876;12640.620781376574;12668.80996785049;12781.744365112645;19198.928171492495;19992.60014799696;20048.799512004985;20160.395188144084;20304.08631611581;7084.43324534036;8558.572071396406;8671.56642167889;8677;8799.868001979976;676.3844901139939;875.0811647769943;887.9422837515541;921.5806807902388;1012.9010024123761;27081.593776093374;29449.96925107611;29537.26156846053;30112;30319.545206821917;791.1218547412368;943.5518128888723;1011.2718842433436;1084.9403282819362;1102.6416414665266;15101.3204405802;15512.534623961299;15606.531804045897;15784.052956822627;15892;670805.645971767;727013;740939;982209;986147;59068.704656476446;59113.70443147843;59399;59963.40036599578;60088.69955650194;1171.5923776638917;1323.848252020748;1335.779596366608;1371.7736573465265;1373.0731756064815;25976.74023259819;30338.938137165092;31118.844405777825;31308;31902.52146217855;50567.74716126396;50656.746716266185;51277.666951648025;51390;51655;616839;619751;622028;623389;623825;27233.455330892873;27714.3071423208;27742.306442339104;27777.305567360978;27814.44371112607;47068.7646561765;47152.52847471481;47254.291185631555;48409.27386089282;48728;33408.1647958803;39390.21219575535;40028.799856000536;40117.398239027025;40996.59003410047;36102.27795444129;39041.2191756169;39127;39168.6083139165;40095.99760005906;37910.052248694;38185.85442436732;38901.2219755609;39301.80349098236;41155;33593.66406335905;33744.662553375136;35065;35167;35256.64743352636;24589.50820983606;25666.743332566435;25761;26253.34366640849;26485 23:58:54 INFO - PROCESS | 1653 | |2;jquery.html;332.2640351621159;366.15966357210203;374.8669222426039;385.2160852664824;402.8831638824739;17609.55976100598;19421.805781942177;20391.38825835227;20670.483237919023;20675.379738607866;19462.610747785035;20483.283085092025;20719.274825381115;20873.478163045933;21200;4732.455767586728;5817.447342502455;5983.940160598382;6026.337102918676;6065.393460653943;5908.143319218732;6023.789167379143;6027.849303767397;6040.879182416371;6052;5468.945310546903;5901.262342207224;5969.731362088716;5978.983572792614;5979.641221526701;5866.76532938682;5897.292324921037;5947;5947.95910715623;5963.165156878041;4470.329450582406;4564.2012647786505;4869.63477739171;4869.732164730938;5121.453754818484;25125.49749005028;25471.61792573113;25701.742982570122;25812.870935645202;25969.350766230807;2168.663857102136;2193.3529608765452;2212.2588932707563;2281.589313923493;2360.775726305998;14781.482648107372;15453.613659658486;16079.11564863932;16104.11427371494;16210.918945405316;1811.8822276552114;1850.0379802502757;1864.3288416170167;1869.2522990803786;1881.9529511762178;113883;131185;133677.6632233675;133785.99321010194;134251.32874335663;10168.186545076376;10763.408012559385;11138.27601205919;11473.368964706855;11615.303081815118;12306.569270075683;14105.717885642434;14578.343974521173;14579.271036448346;14595.124292542481;8187.590620468953;8467.068622451525;8473.067962524117;8550.059493455716;8564.74305770828;8258.958705206436;8301.958490207631;8419.789505262417;8529.445586036887;8575.142485751376;4436.5785250401805;4531.864044078683;4543.227651299287;4596.17268891596;4631.675782695246;1656.8674506039488;1917.664408728467;1936.2255097960929;1937.5737337785947;1941.3787587971713;811.3711873298123;879.1252703559871;885.3758100539104;950.9191718703987;983.1053741095569;20566.79433205649;21296.361109166748;21447.820369879493;21468.033938472807;21653;857.0743596915316;888.3603805260202;905.1627244798535;948.4688574398359;979.8432250839832;10862.565497380174;11363.488643011084;11375.715607109889;11377.146713996483;11390.544378224778;602889;602955;606754.9324506698;606911;616948;125281;125580.37209814075;128766;132090;133530;928.758522784081;939.8261321655652;968.9806203875883;971.145392054987;971.771633666088;19387.61224775525;19503.707444388063;19761.20955161835;19765.40703778889;19797.802021979594;50254.74872625613;54707.905841882144;54774;55093.460513620455;55213.619659507225;78935.60532197302;80144;80406;81310;81987;6694.866102677822;7941.960290198743;8162.428629995843;8285.50286982783;8446.619902104543;12401.13192076546;12920.35398230066;13256.53602123921;13315.334233288104;13441.327933603476;10723.924314527647;11156.442177891235;11249.987501124939;11621.24461909973;11652.184347095623;9807.558659860466;11324.37715925599;11477.08183345347;11729.41352932367;11855.34795586251;8192.631331589973;8665.696700615448;10186;11534.769304613694;11858.16992810495;6915.861682766417;8178.5092894426525;8199.836003280021;8329.583520823813;8361.916380836114;8809.691660791841;11305.26515776472;11570.479328430072;11584;11821.763564728486 23:58:54 INFO - PROCESS | 1653 | |3;mootools.html;1455.5633310007;1547.4583895636524;1790.8656850736197;1796.6586348593773;1899.2877670873422;8222.34221262299;8503.91496085039;8764.737057888266;9268.397554158983;9363.90636093639;8717.343704696103;8997.055309192523;9038.59326330315;9089.772755681111;9123.589438475268;2056.0953180600486;2305.13557415969;2322.9651555226687;2350.576896158691;2351.929442116739;2288.199130304397;2346.683197768305;2402.978734038036;2407.205622144697;2408.650745641881;2380.7262164851004;2389.498205376873;2398.7481314461993;2402.5915594349003;2407.5305315463493;2001.829844463215;2347.5657003454417;2365.5150694107665;2371.95256094878;2378.369732021016;2351.6472529120597;2354.6468029795496;2357;2358.693369861924;2362.433016076129;9051.321150913642;9059.094090590954;9128.497932613704;9175.541222938826;9183.357164998453;4823.011282687075;4872.171730805771;4979.253112033224;5001.374828146481;5024.246363045536;3456.8098754568487;3471.861125554975;3568;3660.6705396514176;3678.834452449646;1055.820510513207;1071.8959471744115;1085.2728671789919;1097.846301517788;1110.9777804443947;11749.823752643717;12184.81722774159;12212.57255996036;12314.445849936774;12337.259764414164;4605.539446055434;4678.251479763221;4847.418309802776;4869.245266983623;4869.269609558594;10331.94834025825;10401.94799026;10417.166626669848;10445.268831181897;10456.902025287387;5358.2498450217;5422.53908417781;5427.213054107132;5434.755436005389;5443.863903402446;4394.560543945644;4575.679702420865;4796.56830885215;4956.231784073474;4970.204767237297;3944.7435916665327;3972.4835771350117;4064.085580744309;4064.5122585289955;4067.9389809152294;1203.9164751723345;1292.8965682745359;1303.4395210059765;1306.0596370613143;1313.3302015971733;741.3216906530464;898.1512470715242;926.754410081329;940.8635747816528;951.2722767083187;5332.9733351333;5804.82585522435;5993.280806303184;6086.087086936995;6212.099245609361;441.76144881763486;442.6879050269552;453.4581175495251;993.4883534979491;1038.6157121864958;3889.53325600924;3979.5622481527007;4037.8384864605086;4053.412255222976;4173.353130264813;8619.051904290523;9710.689056977359;10946.014858662758;10974.231803773659;11252.66242012741;8626.870596941051;9335.673251436167;11221.775564488828;11233.157513186381;11253.887461125447;8158.918410815934;8265.504069755834;8346;8410.831783364298;8472.068072511895;5829.242198514166;5928.851778705567;5959.940400596112;6002.33974262822;6015.187949626745;10282.920293369387;10313.22650801178;10329.380237185793;10354;10354.741131471774;10145.03622155894;10357;10420.32267902584;10488.737781555523;10603.257771955889;5878.529717622666;7283.708651653875;7492.887606685795;7551.697932082875;7796.4932279401655;8460.619272132646;8480.279176270085;8491.787705307417;8661.826763464569;8702.521361325187;7529.510581812165;7635.083789945242;7682.577458239627;7732;7781.377489800916;6488.805335839932;7334.009908662262;7423.591702456417;7465.104187497535;7650.617469126632;6991.965040174767;7056.6118863463;7126.5011449198055;7134.607596582237;7142.857142857009;4958.603311734981;5036.874078148076;5090.592752579859;5162.9741851292;5166.4058633257655;5107.438181799925;5131.409887862795;5207.192885102815;5213.791448342024;5265.52610265078 23:58:54 INFO - PROCESS | 1653 | |4;prototype.html;294.6523102738769;297.72906654944;305.4593369735569;309.16062889255693;312.3861611932552;5354.41101478837;5553.4724201200825;5563.496426608028;5586.3464854194235;5679.602427830054;4673.485916549177;5019.7992080316835;5278.604104692154;5489.204065410513;5538.47384498473;4104.24071546764;4208.368744688306;4233.364995250721;4236.639885609727;4244.596763307481;4070;4136.8138433770555;4203.414899836594;4237.131388065454;4257.168114190401;4149.8006056789945;4152.211079894825;4154.563770804068;4178.770167640779;4191;4168.978600242945;4178.782337600046;4188.2879910414995;4194.51094861326;4206.453161089039;3662.963370366289;3675.5956844747056;3677.4370892370903;3689.760165589255;3699.2971335446177;4310.008697999478;4539;4882.5605695487575;5004.77287607015;5044.672096313728;3967.3850553164307;4306.418633484471;4844.346013288196;4846.781894814707;4851.951480485185;4761.761911904426;4992.350994370745;5774.393688662672;5815.302163740336;5877.941220587825;3849.2109117630816;4000.879973600797;4005.6595189408936;4043.4945631796027;4054.432379466877;4958.454569997298;5165;5768.336641286264;6141.140240366352;6159.846003849895;3262.2546937388383;3430.8456119474686;4107.014316564003;4108.4864391951005;4142.937855932163;3619.819009049537;3996.480457540501;4114.238865809834;4156.771377574262;4158.251514727378;3893.9221215576094;4724.858254252378;4804.25534042217;4827.444843843003;4847.224444088929;5056.359421216718;5258.763355649005;5549.250851135045;5825.883482330414;5835.912461313083;1312.2716892124772;1334.3661760663608;1338.788396501168;1370.9520166794114;1431.9498817541337;919.7562645898843;933.2627224492575;946.9195118415012;962.3456049886005;964.4743614729849;4514.277715565494;4762.476127625959;4769.546893045155;4801.831935882227;5127.9230811537855;4695.389599352063;5947.721239933348;5978.940210597925;5982.547155666156;5992.670403127782;5093.261477085802;5136.864823816789;5459.781608735686;5462.180672899097;5473.644213126134;2368.7986521145895;2539.3748001279196;2655.7650692428115;2668.7064422913463;2672.7728143107665;4742.264948932921;4926.842192084857;4974.726390048582;4994.450610432849;5160.174372100446;4062.0048088217986;4291.485021797404;4745.383100196952;5125;5134.689389774617;4213.5154457237195;4421.955780442218;4647.419072615923;4757.096151731298;4943.1596628573225;4111.342185250465;4638.698484598491;4896.4124305083615;4950.579200767975;5061.342025536656;4033.136473104129;4275.46556680415;4326.9663258268565;4704.882377940579;4957.876053098701;4367.58507941745;4925.300771233919;4957.752112394438;5085.703145697823;5115.92326115116;3286.6287837176974;3325.783824051429;3478.113081164287;3542.9645703542633;3558.6193116538875;4291.270484017662;4321.222180007629;4661.347411362344;5004.824831130893;5635.33757541518;4786;5065.898682026413;5132.692038477703;5812.21535092774;5965.403459654;4747.480806142002;5076.492350764894;5133.0237858424025;5851.531877449709;5888.764449421975;5006.848424862229;5309.6106751961;5406.756695948613;5736.68448235351;5754.769809207753;4626.37543931565;5338.946610534001;5772.451617096369;5806.332271788629;5896.231130660871;5080.5520426679695;5183.611229157904;5689.943100568941;5721.399253078367;5756.798512052058 23:58:54 INFO - PROCESS | 1653 | |5;yui.html;644.9322821103781;660.5013215022659;727;736.1681300130851;740.8590770213871;15108.47120350788;15577;15897.602559936007;15974.96162749422;15987;13662.316884155802;13905.513307034256;13946.093503922264;14018.929905350458;14153.787693184611;655.7803135949439;680.6324584724243;818.2594751749676;824.7113510271402;838.2497664590188;841.8484672758901;850.0776657326808;863.7020228021322;863.753830158405;866.1425189062845;876.6887754847002;897.8832751742265;910.1171863292595;910.7723069232692;913.5934509143434;894.8165626046645;901.6078006067349;905.8052518708444;906.5829718329543;907.1155623267327;666.040901102411;668.762589280808;669.6183175589915;670.4033410264868;670.8893032649607;15152.545423637308;15233.466828660943;15310.617234569114;15427.845721542753;15428.537143885702;14418.927905360511;14534.418623255056;14539.054961427579;14595.197264150467;14701.705965880623;11703;11789.233699809485;11881.34652594107;11936.940315298369;11963.760724785543;3737.4206997915367;3757.455169000507;3792.1655051919047;3812.275667623143;3922.195949830278;72272;101748;105040.99999999923;106196.46901765441;106310;11102.722431939266;11193.66419007431;11277.774444511064;11347.37589432589;11349.886501135048;22473.988670509865;22774.65838012431;22788.544229115323;22804.771952280596;22805.543889122455;6794.704493609762;7853.960730196427;7904.604769761489;8028.718994835152;8100.473469224482;4255.638270747021;4298;4379.978100109479;4398.3402489626815;4473.753943533072;3543.2382037861985;3650.4341827016856;3651.1967367179177;3652.2695460907926;3659.9451008234896;632.8797528469576;635.5487603801353;648.1023782061878;656.0421784195033;666.6666666666666;582.2721598002497;640.9455196308272;651.7686221391433;662.8475450646282;666.1240468783534;7199.668061408656;8521;8747.556653152285;8907.73276801697;8938;600.9939900601025;622.4522420270133;647;672.1060988884772;682.0553533356338;4404.801783919731;4606.608438282784;4619.455532134344;4661.930071048937;4667.486576476585;91492;110020;138005;155355;169056.85658860506;108376;129886;130711;134141.220229168;136745;71303;97865.85213439702;102376.46435303477;119158.40420797841;119458.40270798591;8869;9719.611215551298;9805.2646051545;9831.655892043744;10011.949940250252;49258.26112608385;62220.37779622146;64416.67791661012;64514.677426612565;64645.67677161584;68600.6569967147;84617;91178.08821911697;91669;94657;12735.04487163485;12975.870241297465;13093.672658183621;13130;13163.868361316265;14634.92682536623;14649;14809.259537023321;15009.774853377427;15294.158821264493;13227.80158297607;13370.933145334211;13882.750552450329;14024.368903399167;14145;11167.553297868319;13607;13676.384562694902;13717.862821372059;14167;13045.152065115737;13522.25627590492;13648.86351136476;14006.299685015505;14127.858721413066;11015.394153321324;11101.666949991513;11161.497732602214;11278.830817537579;11363.636363636417;9483.857742134012;10804;11157.824224276188;11520.596779112691;11527.538898444303 23:58:54 INFO - PROCESS | 1653 | __end_tp_report 23:58:54 INFO - PROCESS | 1653 | __start_cc_report 23:58:54 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,356 23:58:54 INFO - PROCESS | 1653 | __end_cc_report 23:58:54 INFO - PROCESS | 1653 | __startTimestamp1475564334166__endTimestamp 23:58:54 INFO - PROCESS | 1653 | 23:58:54 INFO - PROCESS | 1653 | ------- Summary: start ------- 23:58:54 INFO - PROCESS | 1653 | Number of tests: 6 23:58:54 INFO - PROCESS | 1653 | 23:58:54 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5729.53 Median:5944.88 stddev:666.21 (11.2%) stddev-sans-first:634.10 23:58:54 INFO - PROCESS | 1653 | Values: 3045.4 3411.6 3421.0 3451.1 3469.6 5620.6 5722.7 5734.3 5778.7 5829.3 5685.9 5727.0 5775.2 5799.6 5853.4 5171.0 5189.1 5237.1 5309.7 5346.0 3898.6 4179.7 5301.4 5336.3 5368.2 5130.5 5162.5 5281.3 5296.3 5323.5 5105.9 5142.8 5226.6 5306.8 5356.0 5198.7 5200.6 5215.3 5239.6 5244.2 5529.3 5584.2 5746.5 5767.6 5785.5 6105.0 6108.7 6128.7 6176.4 6204.7 6203.4 6247.1 6255.7 6266.4 6269.0 5939.8 5940.1 5944.8 5959.6 6002.6 6142.0 6150.9 6205.8 6208.4 6252.3 6168.4 6199.7 6208.4 6211.9 6212.1 5804.6 6103.0 6265.8 6267.8 6293.0 5812.2 6189.1 6198.4 6270.8 6279.2 6206.0 6255.0 6266.8 6267.9 6270.2 4847.4 4973.8 4976.8 4982.4 5023.9 4416.6 4423.6 4442.0 4519.8 4583.9 5844.5 5856.0 5931.9 5941.9 5944.9 6168.4 6215.4 6244.7 6249.8 6250.8 5685.9 5748.9 5843.5 5873.1 5929.0 5428.0 5448.0 5450.4 5454.5 5505.1 6247.2 6277.8 6286.1 6309.5 6317.0 6109.0 6177.4 6186.1 6186.3 6248.9 6245.4 6256.6 6260.8 6276.4 6284.0 6192.4 6200.6 6212.4 6278.3 6286.7 6030.0 6128.4 6161.4 6237.3 6264.7 6101.5 6120.2 6125.2 6184.7 6195.7 6116.4 6166.5 6180.0 6182.3 6184.2 5725.0 5788.7 5866.2 5992.1 6017.0 4506.2 4902.5 6165.1 6178.8 6189.0 4692.3 5890.4 5942.1 6043.7 6083.3 23:58:54 INFO - PROCESS | 1653 | 23:58:54 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:75004.14 Median:41327.78 stddev:173624.04 (420.1%) stddev-sans-first:174021.00 23:58:54 INFO - PROCESS | 1653 | Values: 508.1 523.8 529.8 530.0 533.5 40611.2 42213.8 42341.8 42410.0 42422.0 43380.8 43831.8 43966.3 43977.0 44184.3 9808.8 9915.0 9927.5 9966.1 9975.8 9826.3 9895.4 9926.3 9950.3 9997.8 9876.7 9904.3 9958.4 9991.9 10003.7 9912.0 9946.0 9968.0 9968.1 9991.7 9720.3 9729.8 9734.8 9827.6 9835.8 40906.8 41013.6 41249.8 41284.2 41371.4 3244.6 3261.8 3310.0 3339.2 3421.8 30002.8 31299.5 31605.0 31681.4 31712.5 2943.9 3143.3 3178.9 3246.4 3400.9 355298.0 421212.0 422041.0 424026.0 425101.0 65107.0 65241.3 65963.0 65997.0 66024.3 68259.3 68427.0 69152.7 69483.3 69719.3 16021.2 16504.3 16700.6 16756.9 16775.2 12221.2 12527.6 12640.6 12668.8 12781.7 19198.9 19992.6 20048.8 20160.4 20304.1 7084.4 8558.6 8671.6 8677.0 8799.9 676.4 875.1 887.9 921.6 1012.9 27081.6 29450.0 29537.3 30112.0 30319.5 791.1 943.6 1011.3 1084.9 1102.6 15101.3 15512.5 15606.5 15784.1 15892.0 670805.6 727013.0 740939.0 982209.0 986147.0 59068.7 59113.7 59399.0 59963.4 60088.7 1171.6 1323.8 1335.8 1371.8 1373.1 25976.7 30338.9 31118.8 31308.0 31902.5 50567.7 50656.7 51277.7 51390.0 51655.0 616839.0 619751.0 622028.0 623389.0 623825.0 27233.5 27714.3 27742.3 27777.3 27814.4 47068.8 47152.5 47254.3 48409.3 48728.0 33408.2 39390.2 40028.8 40117.4 40996.6 36102.3 39041.2 39127.0 39168.6 40096.0 37910.1 38185.9 38901.2 39301.8 41155.0 33593.7 33744.7 35065.0 35167.0 35256.6 24589.5 25666.7 25761.0 26253.3 26485.0 23:58:54 INFO - PROCESS | 1653 | 23:58:54 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:35850.15 Median:13150.81 stddev:101600.74 (772.6%) stddev-sans-first:101850.75 23:58:54 INFO - PROCESS | 1653 | Values: 332.3 366.2 374.9 385.2 402.9 17609.6 19421.8 20391.4 20670.5 20675.4 19462.6 20483.3 20719.3 20873.5 21200.0 4732.5 5817.4 5983.9 6026.3 6065.4 5908.1 6023.8 6027.8 6040.9 6052.0 5468.9 5901.3 5969.7 5979.0 5979.6 5866.8 5897.3 5947.0 5948.0 5963.2 4470.3 4564.2 4869.6 4869.7 5121.5 25125.5 25471.6 25701.7 25812.9 25969.4 2168.7 2193.4 2212.3 2281.6 2360.8 14781.5 15453.6 16079.1 16104.1 16210.9 1811.9 1850.0 1864.3 1869.3 1882.0 113883.0 131185.0 133677.7 133786.0 134251.3 10168.2 10763.4 11138.3 11473.4 11615.3 12306.6 14105.7 14578.3 14579.3 14595.1 8187.6 8467.1 8473.1 8550.1 8564.7 8259.0 8302.0 8419.8 8529.4 8575.1 4436.6 4531.9 4543.2 4596.2 4631.7 1656.9 1917.7 1936.2 1937.6 1941.4 811.4 879.1 885.4 950.9 983.1 20566.8 21296.4 21447.8 21468.0 21653.0 857.1 888.4 905.2 948.5 979.8 10862.6 11363.5 11375.7 11377.1 11390.5 602889.0 602955.0 606754.9 606911.0 616948.0 125281.0 125580.4 128766.0 132090.0 133530.0 928.8 939.8 969.0 971.1 971.8 19387.6 19503.7 19761.2 19765.4 19797.8 50254.7 54707.9 54774.0 55093.5 55213.6 78935.6 80144.0 80406.0 81310.0 81987.0 6694.9 7942.0 8162.4 8285.5 8446.6 12401.1 12920.4 13256.5 13315.3 13441.3 10723.9 11156.4 11250.0 11621.2 11652.2 9807.6 11324.4 11477.1 11729.4 11855.3 8192.6 8665.7 10186.0 11534.8 11858.2 6915.9 8178.5 8199.8 8329.6 8361.9 8809.7 11305.3 11570.5 11584.0 11821.8 23:58:54 INFO - PROCESS | 1653 | 23:58:54 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5775.33 Median:4835.21 stddev:3285.44 (67.9%) stddev-sans-first:3278.62 23:58:54 INFO - PROCESS | 1653 | Values: 1455.6 1547.5 1790.9 1796.7 1899.3 8222.3 8503.9 8764.7 9268.4 9363.9 8717.3 8997.1 9038.6 9089.8 9123.6 2056.1 2305.1 2323.0 2350.6 2351.9 2288.2 2346.7 2403.0 2407.2 2408.7 2380.7 2389.5 2398.7 2402.6 2407.5 2001.8 2347.6 2365.5 2372.0 2378.4 2351.6 2354.6 2357.0 2358.7 2362.4 9051.3 9059.1 9128.5 9175.5 9183.4 4823.0 4872.2 4979.3 5001.4 5024.2 3456.8 3471.9 3568.0 3660.7 3678.8 1055.8 1071.9 1085.3 1097.8 1111.0 11749.8 12184.8 12212.6 12314.4 12337.3 4605.5 4678.3 4847.4 4869.2 4869.3 10331.9 10401.9 10417.2 10445.3 10456.9 5358.2 5422.5 5427.2 5434.8 5443.9 4394.6 4575.7 4796.6 4956.2 4970.2 3944.7 3972.5 4064.1 4064.5 4067.9 1203.9 1292.9 1303.4 1306.1 1313.3 741.3 898.2 926.8 940.9 951.3 5333.0 5804.8 5993.3 6086.1 6212.1 441.8 442.7 453.5 993.5 1038.6 3889.5 3979.6 4037.8 4053.4 4173.4 8619.1 9710.7 10946.0 10974.2 11252.7 8626.9 9335.7 11221.8 11233.2 11253.9 8158.9 8265.5 8346.0 8410.8 8472.1 5829.2 5928.9 5959.9 6002.3 6015.2 10282.9 10313.2 10329.4 10354.0 10354.7 10145.0 10357.0 10420.3 10488.7 10603.3 5878.5 7283.7 7492.9 7551.7 7796.5 8460.6 8480.3 8491.8 8661.8 8702.5 7529.5 7635.1 7682.6 7732.0 7781.4 6488.8 7334.0 7423.6 7465.1 7650.6 6992.0 7056.6 7126.5 7134.6 7142.9 4958.6 5036.9 5090.6 5163.0 5166.4 5107.4 5131.4 5207.2 5213.8 5265.5 23:58:54 INFO - PROCESS | 1653 | 23:58:54 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4393.70 Median:4766.01 stddev:1312.78 (27.5%) stddev-sans-first:1279.91 23:58:54 INFO - PROCESS | 1653 | Values: 294.7 297.7 305.5 309.2 312.4 5354.4 5553.5 5563.5 5586.3 5679.6 4673.5 5019.8 5278.6 5489.2 5538.5 4104.2 4208.4 4233.4 4236.6 4244.6 4070.0 4136.8 4203.4 4237.1 4257.2 4149.8 4152.2 4154.6 4178.8 4191.0 4169.0 4178.8 4188.3 4194.5 4206.5 3663.0 3675.6 3677.4 3689.8 3699.3 4310.0 4539.0 4882.6 5004.8 5044.7 3967.4 4306.4 4844.3 4846.8 4852.0 4761.8 4992.4 5774.4 5815.3 5877.9 3849.2 4000.9 4005.7 4043.5 4054.4 4958.5 5165.0 5768.3 6141.1 6159.8 3262.3 3430.8 4107.0 4108.5 4142.9 3619.8 3996.5 4114.2 4156.8 4158.3 3893.9 4724.9 4804.3 4827.4 4847.2 5056.4 5258.8 5549.3 5825.9 5835.9 1312.3 1334.4 1338.8 1371.0 1431.9 919.8 933.3 946.9 962.3 964.5 4514.3 4762.5 4769.5 4801.8 5127.9 4695.4 5947.7 5978.9 5982.5 5992.7 5093.3 5136.9 5459.8 5462.2 5473.6 2368.8 2539.4 2655.8 2668.7 2672.8 4742.3 4926.8 4974.7 4994.5 5160.2 4062.0 4291.5 4745.4 5125.0 5134.7 4213.5 4422.0 4647.4 4757.1 4943.2 4111.3 4638.7 4896.4 4950.6 5061.3 4033.1 4275.5 4327.0 4704.9 4957.9 4367.6 4925.3 4957.8 5085.7 5115.9 3286.6 3325.8 3478.1 3543.0 3558.6 4291.3 4321.2 4661.3 5004.8 5635.3 4786.0 5065.9 5132.7 5812.2 5965.4 4747.5 5076.5 5133.0 5851.5 5888.8 5006.8 5309.6 5406.8 5736.7 5754.8 4626.4 5338.9 5772.5 5806.3 5896.2 5080.6 5183.6 5689.9 5721.4 5756.8 23:58:54 INFO - PROCESS | 1653 | 23:58:54 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:23700.77 Median:3698.68 stddev:37255.05 (1007.3%) stddev-sans-first:37319.34 23:58:54 INFO - PROCESS | 1653 | Values: 644.9 660.5 727.0 736.2 740.9 15108.5 15577.0 15897.6 15975.0 15987.0 13662.3 13905.5 13946.1 14018.9 14153.8 655.8 680.6 818.3 824.7 838.2 841.8 850.1 863.7 863.8 866.1 876.7 897.9 910.1 910.8 913.6 894.8 901.6 905.8 906.6 907.1 666.0 668.8 669.6 670.4 670.9 15152.5 15233.5 15310.6 15427.8 15428.5 14418.9 14534.4 14539.1 14595.2 14701.7 11703.0 11789.2 11881.3 11936.9 11963.8 3737.4 3757.5 3792.2 3812.3 3922.2 72272.0 101748.0 105041.0 106196.5 106310.0 11102.7 11193.7 11277.8 11347.4 11349.9 22474.0 22774.7 22788.5 22804.8 22805.5 6794.7 7854.0 7904.6 8028.7 8100.5 4255.6 4298.0 4380.0 4398.3 4473.8 3543.2 3650.4 3651.2 3652.3 3659.9 632.9 635.5 648.1 656.0 666.7 582.3 640.9 651.8 662.8 666.1 7199.7 8521.0 8747.6 8907.7 8938.0 601.0 622.5 647.0 672.1 682.1 4404.8 4606.6 4619.5 4661.9 4667.5 91492.0 110020.0 138005.0 155355.0 169056.9 108376.0 129886.0 130711.0 134141.2 136745.0 71303.0 97865.9 102376.5 119158.4 119458.4 8869.0 9719.6 9805.3 9831.7 10011.9 49258.3 62220.4 64416.7 64514.7 64645.7 68600.7 84617.0 91178.1 91669.0 94657.0 12735.0 12975.9 13093.7 13130.0 13163.9 14634.9 14649.0 14809.3 15009.8 15294.2 13227.8 13370.9 13882.8 14024.4 14145.0 11167.6 13607.0 13676.4 13717.9 14167.0 13045.2 13522.3 13648.9 14006.3 14127.9 11015.4 11101.7 11161.5 11278.8 11363.6 9483.9 10804.0 11157.8 11520.6 11527.5 23:58:54 INFO - PROCESS | 1653 | -------- Summary: end -------- 23:58:54 INFO - PROCESS | 1653 | 23:58:54 INFO - TEST-INFO | 1653: exit 0 23:58:54 INFO - TEST-OK | dromaeo_css | took 1116027ms 23:58:54 INFO - TEST-START | kraken 23:58:54 INFO - operating with platform_type : mac_ 23:58:54 INFO - Initialising browser for kraken test... 23:58:54 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpUPTeM0/profile http://localhost:49266/getInfo.html) 23:58:57 INFO - PROCESS | 1665 | 2016-10-03 23:58:57.576 plugin-container[1666:9907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e2b, name = 'com.apple.tsm.portname' 23:58:57 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:58:57 INFO - PROCESS | 1665 | 2016-10-03 23:58:57.579 plugin-container[1666:9907] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 23:58:57 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:58:57 INFO - PROCESS | 1665 | 2016-10-03 23:58:57.579 plugin-container[1666:9907] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:58:57 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 23:58:57 INFO - PROCESS | 1665 | colorDepth:24 23:58:57 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 23:58:57 INFO - PROCESS | 1665 | __metrics 23:58:57 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 23:58:58 INFO - TEST-INFO | 1665: exit 0 23:58:58 INFO - Browser initialized. 23:58:58 INFO - Running cycle 1/1 for kraken test... 23:58:58 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/T/tmpUPTeM0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:59:00 INFO - PROCESS | 1667 | 2016-10-03 23:59:00.632 plugin-container[1668:10018] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.tsm.portname' 23:59:00 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:59:00 INFO - PROCESS | 1667 | 2016-10-03 23:59:00.637 plugin-container[1668:10018] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 23:59:00 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:59:00 INFO - PROCESS | 1667 | 2016-10-03 23:59:00.637 plugin-container[1668:10018] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:59:10 INFO - PROCESS | 1667 | RSS: Main: 240340992 23:59:10 INFO - PROCESS | 1667 | 23:59:13 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 23:59:14 INFO - PROCESS | 1667 | RSS: Main: 254197760 23:59:14 INFO - PROCESS | 1667 | 23:59:17 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 23:59:17 INFO - PROCESS | 1667 | RSS: Main: 262586368 23:59:17 INFO - PROCESS | 1667 | 23:59:21 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 23:59:21 INFO - PROCESS | 1667 | RSS: Main: 262639616 23:59:21 INFO - PROCESS | 1667 | 23:59:23 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 23:59:24 INFO - PROCESS | 1667 | RSS: Main: 263630848 23:59:24 INFO - PROCESS | 1667 | 23:59:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:59:26 INFO - PROCESS | 1667 | RSS: Main: 263876608 23:59:26 INFO - PROCESS | 1667 | 23:59:29 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 23:59:30 INFO - PROCESS | 1667 | RSS: Main: 264912896 23:59:30 INFO - PROCESS | 1667 | 23:59:32 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 23:59:33 INFO - PROCESS | 1667 | RSS: Main: 266010624 23:59:33 INFO - PROCESS | 1667 | 23:59:36 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 23:59:37 INFO - PROCESS | 1667 | RSS: Main: 266665984 23:59:37 INFO - PROCESS | 1667 | 23:59:38 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:59:39 INFO - PROCESS | 1667 | RSS: Main: 266170368 23:59:39 INFO - PROCESS | 1667 | 23:59:41 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:59:41 INFO - PROCESS | 1667 | RSS: Main: 266297344 23:59:41 INFO - PROCESS | 1667 | 23:59:44 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:59:44 INFO - PROCESS | 1667 | RSS: Main: 266313728 23:59:44 INFO - PROCESS | 1667 | 23:59:47 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:59:48 INFO - PROCESS | 1667 | RSS: Main: 266342400 23:59:48 INFO - PROCESS | 1667 | 23:59:51 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:59:52 INFO - PROCESS | 1667 | RSS: Main: 266661888 23:59:52 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 23:59:54 INFO - PROCESS | 1667 | RSS: Main: 268365824 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | __start_tp_report 23:59:54 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 23:59:54 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 23:59:54 INFO - PROCESS | 1667 | |i|pagename|runs| 23:59:54 INFO - PROCESS | 1667 | |0;ai-astar;147;175;166;117;117;138;156;118;114;116 23:59:54 INFO - PROCESS | 1667 | |1;audio-beat-detection;145;117;116;150;114;121;111;116;143;114 23:59:54 INFO - PROCESS | 1667 | |2;audio-dft;196;207;214;246;251;201;195;194;194;196 23:59:54 INFO - PROCESS | 1667 | |3;audio-fft;78;59;60;86;60;60;58;57;78;58 23:59:54 INFO - PROCESS | 1667 | |4;audio-oscillator;88;70;86;73;70;69;89;70;71;87 23:59:54 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;89;87;92;86;89;87;89;87;89;86 23:59:54 INFO - PROCESS | 1667 | |6;imaging-darkroom;96;97;100;95;96;94;97;94;153;121 23:59:54 INFO - PROCESS | 1667 | |7;imaging-desaturate;92;95;92;96;95;151;144;89;93;91 23:59:54 INFO - PROCESS | 1667 | |8;json-parse-financial;59;55;56;59;57;58;58;58;57;56 23:59:54 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;68;68;78;81;75;70;66;88;101;102 23:59:54 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;78;102;110;90;90;91;106;89;93;93 23:59:54 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;162;162;147;151;158;158;156;148;182;145 23:59:54 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;177;231;176;181;154;235;251;229;176;175 23:59:54 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;71;77;88;91;91;87;92;70;68;68 23:59:54 INFO - PROCESS | 1667 | __end_tp_report 23:59:54 INFO - PROCESS | 1667 | __start_cc_report 23:59:54 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,943 23:59:54 INFO - PROCESS | 1667 | __end_cc_report 23:59:54 INFO - PROCESS | 1667 | __startTimestamp1475564394436__endTimestamp 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | ------- Summary: start ------- 23:59:54 INFO - PROCESS | 1667 | Number of tests: 14 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:136.40 Median:142.50 stddev:23.27 (16.3%) stddev-sans-first:24.37 23:59:54 INFO - PROCESS | 1667 | Values: 147.0 175.0 166.0 117.0 117.0 138.0 156.0 118.0 114.0 116.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:119.00 stddev:15.01 (12.6%) stddev-sans-first:14.01 23:59:54 INFO - PROCESS | 1667 | Values: 145.0 117.0 116.0 150.0 114.0 121.0 111.0 116.0 143.0 114.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:209.40 Median:204.00 stddev:21.62 (10.6%) stddev-sans-first:22.39 23:59:54 INFO - PROCESS | 1667 | Values: 196.0 207.0 214.0 246.0 251.0 201.0 195.0 194.0 194.0 196.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:65.40 Median:60.00 stddev:10.80 (18.0%) stddev-sans-first:10.45 23:59:54 INFO - PROCESS | 1667 | Values: 78.0 59.0 60.0 86.0 60.0 60.0 58.0 57.0 78.0 58.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:79.50 stddev:8.87 (11.2%) stddev-sans-first:8.52 23:59:54 INFO - PROCESS | 1667 | Values: 88.0 70.0 86.0 73.0 70.0 69.0 89.0 70.0 71.0 87.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:89.00 stddev:1.85 (2.1%) stddev-sans-first:1.94 23:59:54 INFO - PROCESS | 1667 | Values: 89.0 87.0 92.0 86.0 89.0 87.0 89.0 87.0 89.0 86.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:104.30 Median:95.50 stddev:18.89 (19.8%) stddev-sans-first:19.80 23:59:54 INFO - PROCESS | 1667 | Values: 96.0 97.0 100.0 95.0 96.0 94.0 97.0 94.0 153.0 121.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:103.80 Median:92.50 stddev:23.18 (25.1%) stddev-sans-first:24.19 23:59:54 INFO - PROCESS | 1667 | Values: 92.0 95.0 92.0 96.0 95.0 151.0 144.0 89.0 93.0 91.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:1.34 (2.3%) stddev-sans-first:1.27 23:59:54 INFO - PROCESS | 1667 | Values: 59.0 55.0 56.0 59.0 57.0 58.0 58.0 58.0 57.0 56.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:79.70 Median:72.50 stddev:13.34 (18.4%) stddev-sans-first:13.46 23:59:54 INFO - PROCESS | 1667 | Values: 68.0 68.0 78.0 81.0 75.0 70.0 66.0 88.0 101.0 102.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:94.20 Median:90.00 stddev:9.35 (10.4%) stddev-sans-first:7.87 23:59:54 INFO - PROCESS | 1667 | Values: 78.0 102.0 110.0 90.0 90.0 91.0 106.0 89.0 93.0 93.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:156.90 Median:158.00 stddev:10.74 (6.8%) stddev-sans-first:11.24 23:59:54 INFO - PROCESS | 1667 | Values: 162.0 162.0 147.0 151.0 158.0 158.0 156.0 148.0 182.0 145.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.50 Median:205.00 stddev:33.97 (16.6%) stddev-sans-first:35.13 23:59:54 INFO - PROCESS | 1667 | Values: 177.0 231.0 176.0 181.0 154.0 235.0 251.0 229.0 176.0 175.0 23:59:54 INFO - PROCESS | 1667 | 23:59:54 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.30 Median:87.50 stddev:10.41 (11.9%) stddev-sans-first:10.49 23:59:54 INFO - PROCESS | 1667 | Values: 71.0 77.0 88.0 91.0 91.0 87.0 92.0 70.0 68.0 68.0 23:59:54 INFO - PROCESS | 1667 | -------- Summary: end -------- 23:59:54 INFO - PROCESS | 1667 | 23:59:55 INFO - TEST-INFO | 1667: exit 0 23:59:55 INFO - TEST-OK | kraken | took 60462ms 23:59:55 INFO - SUITE-END | took 1176s 23:59:55 INFO - Completed test suite (00:19:36) 23:59:55 INFO - javascript benchmark 23:59:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7352.685005918484, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3045.4213699397114, 3411.624721280659, 3420.956608234489, 3451.0682115828713, 3469.583649962004, 5620.550355971523, 5722.7424765885535, 5734.254546908906, 5778.739956701953, 5829.300483941933, 5685.8578535536635, 5726.971365143168, 5775.162601422791, 5799.594028418012, 5853.356130825606, 5171.0, 5189.117849965496, 5237.10969135246, 5309.73451327434, 5345.973270133663, 3898.6491215790575, 4179.749215047092, 5301.416844147141, 5336.332958380202, 5368.221607866857, 5130.461301563357, 5162.457941916082, 5281.339832520935, 5296.285001524802, 5323.520883120499, 5105.948940510584, 5142.794288228466, 5226.55574276187, 5306.7611957462, 5356.009138309425, 5198.6880787152895, 5200.557952574036, 5215.348081489814, 5239.580833533309, 5244.160934250502, 5529.281193444866, 5584.246126772874, 5746.454086861741, 5767.567432442584, 5785.537157027427, 6105.004892149239, 6108.736706092843, 6128.662923539204, 6176.351483094255, 6204.720787564571, 6203.4416902479, 6247.125402443661, 6255.687215639246, 6266.404691554295, 6269.028300613412, 5939.762409503658, 5940.079287710412, 5944.762209511571, 5959.582829201914, 6002.639841609517, 6141.977005061692, 6150.862090513269, 6205.81382558524, 6208.410201030895, 6252.343503932113, 6168.414000669929, 6199.721012554446, 6208.410201030895, 6211.9068213976825, 6212.06818977157, 5804.577878419815, 6103.034636161374, 6265.843353916098, 6267.843303917439, 6292.968535157386, 5812.157237200582, 6189.102580125857, 6198.380161983856, 6270.811875643738, 6279.183706118175, 6205.968970155211, 6255.0, 6266.843328916813, 6267.9373206267355, 6270.216222972129, 4847.394075740533, 4973.776180071905, 4976.776045077981, 4982.402111746614, 5023.874403139951, 4416.580424859633, 4423.579759922803, 4442.0, 4519.7740112994215, 4583.862484125481, 5844.47399734026, 5855.970720146457, 5931.881362372728, 5941.910871336933, 5944.881102377928, 6168.352323006109, 6215.440610345091, 6244.687765611701, 6249.812505624838, 6250.781222657276, 5685.857853553694, 5748.913766293509, 5843.503302219359, 5873.148393483007, 5928.970355148197, 5428.0, 5447.972760136174, 5450.400455949843, 5454.481824226692, 5505.119180931032, 6247.187865577446, 6277.843053923597, 6286.088517165077, 6309.526785491014, 6316.968415157895, 6109.040306147876, 6177.351378105234, 6186.1030150627, 6186.319504854552, 6248.8752024636005, 6245.406686364788, 6256.562040657111, 6260.843478913063, 6276.403741644536, 6283.994560870239, 6192.442680158807, 6200.56596038273, 6212.409821066992, 6278.309385967631, 6286.748530058747, 6030.0, 6128.442013630826, 6161.353057928853, 6237.345078766845, 6264.718087685974, 6101.481374083252, 6120.17377654011, 6125.173101631401, 6184.690765461799, 6195.690215489298, 6116.357782432959, 6166.4758495526585, 6180.0, 6182.28903676083, 6184.165137706352, 5724.998125328134, 5788.739506722123, 5866.208061911588, 5992.0712289595185, 6016.969915150396, 4506.211413002646, 4902.509749025069, 6165.075238714228, 6178.752849886134, 6189.0, 4692.296155576554, 5890.381509941394, 5942.078977758454, 6043.667598282137, 6083.300420451705], "value": 5688.0414496662215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.0676957782469, 523.8166641675414, 529.8304542546384, 529.9549538289245, 533.514501803359, 40611.18777624446, 42213.78893105538, 42341.788291058576, 42409.99999999992, 42421.99999999996, 43380.78309608455, 43831.78084109583, 43966.340504892374, 43977.0, 44184.3372349415, 9808.803823923536, 9915.008499150063, 9927.453990030546, 9966.053224943618, 9975.800483990315, 9826.312158148932, 9895.356801807895, 9926.305158638897, 9950.253730970206, 9997.750056248578, 9876.654317098903, 9904.306698531105, 9958.402495850236, 9991.850122248172, 10003.74990625233, 9912.0, 9945.950270248675, 9968.0, 9968.053034961667, 9991.650292239809, 9720.347540918061, 9729.805403891953, 9734.805303893883, 9827.557759900821, 9835.754106147331, 40906.79546602248, 41013.589864101574, 41249.79375103105, 41284.174316513505, 41371.37942930858, 3244.6430892601793, 3261.756452864723, 3309.9879110009774, 3339.2152844081866, 3421.7775844570274, 30002.84998575015, 31299.53050704241, 31605.0, 31681.36637267242, 31712.524312135338, 2943.8969636062634, 3143.3241853001714, 3178.8728450861936, 3246.3507298540385, 3400.914977125567, 355298.0, 421212.0, 422041.0, 424026.0, 425100.9999999969, 65107.02339464912, 65241.347586524476, 65963.0, 65997.0, 66024.33975660279, 68259.31740682629, 68427.0, 69152.6542367285, 69483.30516694768, 69719.3028069723, 16021.198940052951, 16504.33982640681, 16700.582485437717, 16756.91621541909, 16775.161241937854, 12221.205621634565, 12527.624171274876, 12640.620781376574, 12668.80996785049, 12781.744365112645, 19198.928171492495, 19992.60014799696, 20048.799512004985, 20160.395188144084, 20304.08631611581, 7084.43324534036, 8558.572071396406, 8671.56642167889, 8677.0, 8799.868001979976, 676.3844901139939, 875.0811647769943, 887.9422837515541, 921.5806807902388, 1012.9010024123761, 27081.593776093374, 29449.96925107611, 29537.26156846053, 30112.0, 30319.545206821917, 791.1218547412368, 943.5518128888723, 1011.2718842433436, 1084.9403282819362, 1102.6416414665266, 15101.3204405802, 15512.534623961299, 15606.531804045897, 15784.052956822627, 15892.0, 670805.645971767, 727013.0, 740939.0, 982209.0, 986147.0, 59068.704656476446, 59113.70443147843, 59399.0, 59963.40036599578, 60088.69955650194, 1171.5923776638917, 1323.848252020748, 1335.779596366608, 1371.7736573465265, 1373.0731756064815, 25976.74023259819, 30338.938137165092, 31118.844405777825, 31308.0, 31902.52146217855, 50567.74716126396, 50656.746716266185, 51277.666951648025, 51390.0, 51655.0, 616839.0, 619751.0, 622028.0, 623389.0, 623825.0, 27233.455330892873, 27714.3071423208, 27742.306442339104, 27777.305567360978, 27814.44371112607, 47068.7646561765, 47152.52847471481, 47254.291185631555, 48409.27386089282, 48728.0, 33408.1647958803, 39390.21219575535, 40028.799856000536, 40117.398239027025, 40996.59003410047, 36102.27795444129, 39041.2191756169, 39127.0, 39168.6083139165, 40095.99760005906, 37910.052248694, 38185.85442436732, 38901.2219755609, 39301.80349098236, 41155.0, 33593.66406335905, 33744.662553375136, 35065.0, 35167.0, 35256.64743352636, 24589.50820983606, 25666.743332566435, 25761.0, 26253.34366640849, 26485.0], "value": 20379.209368326385, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.2640351621159, 366.15966357210203, 374.8669222426039, 385.2160852664824, 402.8831638824739, 17609.55976100598, 19421.805781942177, 20391.38825835227, 20670.483237919023, 20675.379738607866, 19462.610747785035, 20483.283085092025, 20719.274825381115, 20873.478163045933, 21200.0, 4732.455767586728, 5817.447342502455, 5983.940160598382, 6026.337102918676, 6065.393460653943, 5908.143319218732, 6023.789167379143, 6027.849303767397, 6040.879182416371, 6052.0, 5468.945310546903, 5901.262342207224, 5969.731362088716, 5978.983572792614, 5979.641221526701, 5866.76532938682, 5897.292324921037, 5947.0, 5947.95910715623, 5963.165156878041, 4470.329450582406, 4564.2012647786505, 4869.63477739171, 4869.732164730938, 5121.453754818484, 25125.49749005028, 25471.61792573113, 25701.742982570122, 25812.870935645202, 25969.350766230807, 2168.663857102136, 2193.3529608765452, 2212.2588932707563, 2281.589313923493, 2360.775726305998, 14781.482648107372, 15453.613659658486, 16079.11564863932, 16104.11427371494, 16210.918945405316, 1811.8822276552114, 1850.0379802502757, 1864.3288416170167, 1869.2522990803786, 1881.9529511762178, 113883.0, 131185.0, 133677.6632233675, 133785.99321010194, 134251.32874335663, 10168.186545076376, 10763.408012559385, 11138.27601205919, 11473.368964706855, 11615.303081815118, 12306.569270075683, 14105.717885642434, 14578.343974521173, 14579.271036448346, 14595.124292542481, 8187.590620468953, 8467.068622451525, 8473.067962524117, 8550.059493455716, 8564.74305770828, 8258.958705206436, 8301.958490207631, 8419.789505262417, 8529.445586036887, 8575.142485751376, 4436.5785250401805, 4531.864044078683, 4543.227651299287, 4596.17268891596, 4631.675782695246, 1656.8674506039488, 1917.664408728467, 1936.2255097960929, 1937.5737337785947, 1941.3787587971713, 811.3711873298123, 879.1252703559871, 885.3758100539104, 950.9191718703987, 983.1053741095569, 20566.79433205649, 21296.361109166748, 21447.820369879493, 21468.033938472807, 21653.0, 857.0743596915316, 888.3603805260202, 905.1627244798535, 948.4688574398359, 979.8432250839832, 10862.565497380174, 11363.488643011084, 11375.715607109889, 11377.146713996483, 11390.544378224778, 602889.0, 602955.0, 606754.9324506698, 606911.0, 616948.0, 125281.0, 125580.37209814075, 128766.0, 132090.0, 133530.0, 928.758522784081, 939.8261321655652, 968.9806203875883, 971.145392054987, 971.771633666088, 19387.61224775525, 19503.707444388063, 19761.20955161835, 19765.40703778889, 19797.802021979594, 50254.74872625613, 54707.905841882144, 54774.0, 55093.460513620455, 55213.619659507225, 78935.60532197302, 80144.0, 80406.0, 81310.0, 81987.0, 6694.866102677822, 7941.960290198743, 8162.428629995843, 8285.50286982783, 8446.619902104543, 12401.13192076546, 12920.35398230066, 13256.53602123921, 13315.334233288104, 13441.327933603476, 10723.924314527647, 11156.442177891235, 11249.987501124939, 11621.24461909973, 11652.184347095623, 9807.558659860466, 11324.37715925599, 11477.08183345347, 11729.41352932367, 11855.34795586251, 8192.631331589973, 8665.696700615448, 10186.0, 11534.769304613694, 11858.16992810495, 6915.861682766417, 8178.5092894426525, 8199.836003280021, 8329.583520823813, 8361.916380836114, 8809.691660791841, 11305.26515776472, 11570.479328430072, 11584.0, 11821.763564728486], "value": 9634.422194386187, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1455.5633310007, 1547.4583895636524, 1790.8656850736197, 1796.6586348593773, 1899.2877670873422, 8222.34221262299, 8503.91496085039, 8764.737057888266, 9268.397554158983, 9363.90636093639, 8717.343704696103, 8997.055309192523, 9038.59326330315, 9089.772755681111, 9123.589438475268, 2056.0953180600486, 2305.13557415969, 2322.9651555226687, 2350.576896158691, 2351.929442116739, 2288.199130304397, 2346.683197768305, 2402.978734038036, 2407.205622144697, 2408.650745641881, 2380.7262164851004, 2389.498205376873, 2398.7481314461993, 2402.5915594349003, 2407.5305315463493, 2001.829844463215, 2347.5657003454417, 2365.5150694107665, 2371.95256094878, 2378.369732021016, 2351.6472529120597, 2354.6468029795496, 2357.0, 2358.693369861924, 2362.433016076129, 9051.321150913642, 9059.094090590954, 9128.497932613704, 9175.541222938826, 9183.357164998453, 4823.011282687075, 4872.171730805771, 4979.253112033224, 5001.374828146481, 5024.246363045536, 3456.8098754568487, 3471.861125554975, 3568.0, 3660.6705396514176, 3678.834452449646, 1055.820510513207, 1071.8959471744115, 1085.2728671789919, 1097.846301517788, 1110.9777804443947, 11749.823752643717, 12184.81722774159, 12212.57255996036, 12314.445849936774, 12337.259764414164, 4605.539446055434, 4678.251479763221, 4847.418309802776, 4869.245266983623, 4869.269609558594, 10331.94834025825, 10401.94799026, 10417.166626669848, 10445.268831181897, 10456.902025287387, 5358.2498450217, 5422.53908417781, 5427.213054107132, 5434.755436005389, 5443.863903402446, 4394.560543945644, 4575.679702420865, 4796.56830885215, 4956.231784073474, 4970.204767237297, 3944.7435916665327, 3972.4835771350117, 4064.085580744309, 4064.5122585289955, 4067.9389809152294, 1203.9164751723345, 1292.8965682745359, 1303.4395210059765, 1306.0596370613143, 1313.3302015971733, 741.3216906530464, 898.1512470715242, 926.754410081329, 940.8635747816528, 951.2722767083187, 5332.9733351333, 5804.82585522435, 5993.280806303184, 6086.087086936995, 6212.099245609361, 441.76144881763486, 442.6879050269552, 453.4581175495251, 993.4883534979491, 1038.6157121864958, 3889.53325600924, 3979.5622481527007, 4037.8384864605086, 4053.412255222976, 4173.353130264813, 8619.051904290523, 9710.689056977359, 10946.014858662758, 10974.231803773659, 11252.66242012741, 8626.870596941051, 9335.673251436167, 11221.775564488828, 11233.157513186381, 11253.887461125447, 8158.918410815934, 8265.504069755834, 8346.0, 8410.831783364298, 8472.068072511895, 5829.242198514166, 5928.851778705567, 5959.940400596112, 6002.33974262822, 6015.187949626745, 10282.920293369387, 10313.22650801178, 10329.380237185793, 10354.0, 10354.741131471774, 10145.03622155894, 10357.0, 10420.32267902584, 10488.737781555523, 10603.257771955889, 5878.529717622666, 7283.708651653875, 7492.887606685795, 7551.697932082875, 7796.4932279401655, 8460.619272132646, 8480.279176270085, 8491.787705307417, 8661.826763464569, 8702.521361325187, 7529.510581812165, 7635.083789945242, 7682.577458239627, 7732.0, 7781.377489800916, 6488.805335839932, 7334.009908662262, 7423.591702456417, 7465.104187497535, 7650.617469126632, 6991.965040174767, 7056.6118863463, 7126.5011449198055, 7134.607596582237, 7142.857142857009, 4958.603311734981, 5036.874078148076, 5090.592752579859, 5162.9741851292, 5166.4058633257655, 5107.438181799925, 5131.409887862795, 5207.192885102815, 5213.791448342024, 5265.52610265078], "value": 4592.945639773152, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.6523102738769, 297.72906654944, 305.4593369735569, 309.16062889255693, 312.3861611932552, 5354.41101478837, 5553.4724201200825, 5563.496426608028, 5586.3464854194235, 5679.602427830054, 4673.485916549177, 5019.7992080316835, 5278.604104692154, 5489.204065410513, 5538.47384498473, 4104.24071546764, 4208.368744688306, 4233.364995250721, 4236.639885609727, 4244.596763307481, 4070.0, 4136.8138433770555, 4203.414899836594, 4237.131388065454, 4257.168114190401, 4149.8006056789945, 4152.211079894825, 4154.563770804068, 4178.770167640779, 4191.0, 4168.978600242945, 4178.782337600046, 4188.2879910414995, 4194.51094861326, 4206.453161089039, 3662.963370366289, 3675.5956844747056, 3677.4370892370903, 3689.760165589255, 3699.2971335446177, 4310.008697999478, 4539.0, 4882.5605695487575, 5004.77287607015, 5044.672096313728, 3967.3850553164307, 4306.418633484471, 4844.346013288196, 4846.781894814707, 4851.951480485185, 4761.761911904426, 4992.350994370745, 5774.393688662672, 5815.302163740336, 5877.941220587825, 3849.2109117630816, 4000.879973600797, 4005.6595189408936, 4043.4945631796027, 4054.432379466877, 4958.454569997298, 5165.0, 5768.336641286264, 6141.140240366352, 6159.846003849895, 3262.2546937388383, 3430.8456119474686, 4107.014316564003, 4108.4864391951005, 4142.937855932163, 3619.819009049537, 3996.480457540501, 4114.238865809834, 4156.771377574262, 4158.251514727378, 3893.9221215576094, 4724.858254252378, 4804.25534042217, 4827.444843843003, 4847.224444088929, 5056.359421216718, 5258.763355649005, 5549.250851135045, 5825.883482330414, 5835.912461313083, 1312.2716892124772, 1334.3661760663608, 1338.788396501168, 1370.9520166794114, 1431.9498817541337, 919.7562645898843, 933.2627224492575, 946.9195118415012, 962.3456049886005, 964.4743614729849, 4514.277715565494, 4762.476127625959, 4769.546893045155, 4801.831935882227, 5127.9230811537855, 4695.389599352063, 5947.721239933348, 5978.940210597925, 5982.547155666156, 5992.670403127782, 5093.261477085802, 5136.864823816789, 5459.781608735686, 5462.180672899097, 5473.644213126134, 2368.7986521145895, 2539.3748001279196, 2655.7650692428115, 2668.7064422913463, 2672.7728143107665, 4742.264948932921, 4926.842192084857, 4974.726390048582, 4994.450610432849, 5160.174372100446, 4062.0048088217986, 4291.485021797404, 4745.383100196952, 5125.0, 5134.689389774617, 4213.5154457237195, 4421.955780442218, 4647.419072615923, 4757.096151731298, 4943.1596628573225, 4111.342185250465, 4638.698484598491, 4896.4124305083615, 4950.579200767975, 5061.342025536656, 4033.136473104129, 4275.46556680415, 4326.9663258268565, 4704.882377940579, 4957.876053098701, 4367.58507941745, 4925.300771233919, 4957.752112394438, 5085.703145697823, 5115.92326115116, 3286.6287837176974, 3325.783824051429, 3478.113081164287, 3542.9645703542633, 3558.6193116538875, 4291.270484017662, 4321.222180007629, 4661.347411362344, 5004.824831130893, 5635.33757541518, 4786.0, 5065.898682026413, 5132.692038477703, 5812.21535092774, 5965.403459654, 4747.480806142002, 5076.492350764894, 5133.0237858424025, 5851.531877449709, 5888.764449421975, 5006.848424862229, 5309.6106751961, 5406.756695948613, 5736.68448235351, 5754.769809207753, 4626.37543931565, 5338.946610534001, 5772.451617096369, 5806.332271788629, 5896.231130660871, 5080.5520426679695, 5183.611229157904, 5689.943100568941, 5721.399253078367, 5756.798512052058], "value": 3987.0065917350785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [644.9322821103781, 660.5013215022659, 727.0, 736.1681300130851, 740.8590770213871, 15108.47120350788, 15577.0, 15897.602559936007, 15974.96162749422, 15987.0, 13662.316884155802, 13905.513307034256, 13946.093503922264, 14018.929905350458, 14153.787693184611, 655.7803135949439, 680.6324584724243, 818.2594751749676, 824.7113510271402, 838.2497664590188, 841.8484672758901, 850.0776657326808, 863.7020228021322, 863.753830158405, 866.1425189062845, 876.6887754847002, 897.8832751742265, 910.1171863292595, 910.7723069232692, 913.5934509143434, 894.8165626046645, 901.6078006067349, 905.8052518708444, 906.5829718329543, 907.1155623267327, 666.040901102411, 668.762589280808, 669.6183175589915, 670.4033410264868, 670.8893032649607, 15152.545423637308, 15233.466828660943, 15310.617234569114, 15427.845721542753, 15428.537143885702, 14418.927905360511, 14534.418623255056, 14539.054961427579, 14595.197264150467, 14701.705965880623, 11703.0, 11789.233699809485, 11881.34652594107, 11936.940315298369, 11963.760724785543, 3737.4206997915367, 3757.455169000507, 3792.1655051919047, 3812.275667623143, 3922.195949830278, 72272.0, 101748.0, 105040.99999999923, 106196.46901765441, 106310.0, 11102.722431939266, 11193.66419007431, 11277.774444511064, 11347.37589432589, 11349.886501135048, 22473.988670509865, 22774.65838012431, 22788.544229115323, 22804.771952280596, 22805.543889122455, 6794.704493609762, 7853.960730196427, 7904.604769761489, 8028.718994835152, 8100.473469224482, 4255.638270747021, 4298.0, 4379.978100109479, 4398.3402489626815, 4473.753943533072, 3543.2382037861985, 3650.4341827016856, 3651.1967367179177, 3652.2695460907926, 3659.9451008234896, 632.8797528469576, 635.5487603801353, 648.1023782061878, 656.0421784195033, 666.6666666666666, 582.2721598002497, 640.9455196308272, 651.7686221391433, 662.8475450646282, 666.1240468783534, 7199.668061408656, 8521.0, 8747.556653152285, 8907.73276801697, 8938.0, 600.9939900601025, 622.4522420270133, 647.0, 672.1060988884772, 682.0553533356338, 4404.801783919731, 4606.608438282784, 4619.455532134344, 4661.930071048937, 4667.486576476585, 91492.0, 110020.0, 138005.0, 155355.0, 169056.85658860506, 108376.0, 129886.0, 130711.0, 134141.220229168, 136745.0, 71303.0, 97865.85213439702, 102376.46435303477, 119158.40420797841, 119458.40270798591, 8869.0, 9719.611215551298, 9805.2646051545, 9831.655892043744, 10011.949940250252, 49258.26112608385, 62220.37779622146, 64416.67791661012, 64514.677426612565, 64645.67677161584, 68600.6569967147, 84617.0, 91178.08821911697, 91669.0, 94657.0, 12735.04487163485, 12975.870241297465, 13093.672658183621, 13130.0, 13163.868361316265, 14634.92682536623, 14649.0, 14809.259537023321, 15009.774853377427, 15294.158821264493, 13227.80158297607, 13370.933145334211, 13882.750552450329, 14024.368903399167, 14145.0, 11167.553297868319, 13607.0, 13676.384562694902, 13717.862821372059, 14167.0, 13045.152065115737, 13522.25627590492, 13648.86351136476, 14006.299685015505, 14127.858721413066, 11015.394153321324, 11101.666949991513, 11161.497732602214, 11278.830817537579, 11363.636363636417, 9483.857742134012, 10804.0, 11157.824224276188, 11520.596779112691, 11527.538898444303], "value": 7725.2708835464855, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1576.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 175.0, 166.0, 117.0, 117.0, 138.0, 156.0, 118.0, 114.0, 116.0], "value": 136.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 117.0, 116.0, 150.0, 114.0, 121.0, 111.0, 116.0, 143.0, 114.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 207.0, 214.0, 246.0, 251.0, 201.0, 195.0, 194.0, 194.0, 196.0], "value": 209.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 60.0, 86.0, 60.0, 60.0, 58.0, 57.0, 78.0, 58.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 86.0, 73.0, 70.0, 69.0, 89.0, 70.0, 71.0, 87.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 92.0, 86.0, 89.0, 87.0, 89.0, 87.0, 89.0, 86.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 100.0, 95.0, 96.0, 94.0, 97.0, 94.0, 153.0, 121.0], "value": 104.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 92.0, 96.0, 95.0, 151.0, 144.0, 89.0, 93.0, 91.0], "value": 103.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 55.0, 56.0, 59.0, 57.0, 58.0, 58.0, 58.0, 57.0, 56.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 78.0, 81.0, 75.0, 70.0, 66.0, 88.0, 101.0, 102.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 102.0, 110.0, 90.0, 90.0, 91.0, 106.0, 89.0, 93.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 147.0, 151.0, 158.0, 158.0, 156.0, 148.0, 182.0, 145.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 231.0, 176.0, 181.0, 154.0, 235.0, 251.0, 229.0, 176.0, 175.0], "value": 198.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 88.0, 91.0, 91.0, 87.0, 92.0, 70.0, 68.0, 68.0], "value": 80.3, "unit": "score"}]}]} 23:59:55 INFO - Return code: 0 23:59:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:59:55 ERROR - # TBPL SUCCESS # 23:59:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:59:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:59:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:59:55 INFO - 2016-10-03 23:59:55.760 screenresolution[1672:10400] starting screenresolution argv=screenresolution get 23:59:55 INFO - 2016-10-03 23:59:55.763 screenresolution[1672:10400] Display 0: 1600x1200x32@60 23:59:55 INFO - 2016-10-03 23:59:55.769 screenresolution[1673:10403] starting screenresolution argv=screenresolution list 23:59:55 INFO - Available Modes on Display 0 23:59:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:59:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:59:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:59:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:59:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:59:56 INFO - Intel Iris: 23:59:56 INFO - Chipset Model: Intel Iris 23:59:56 INFO - Type: GPU 23:59:56 INFO - Bus: Built-In 23:59:56 INFO - VRAM (Dynamic, Max): 1536 MB 23:59:56 INFO - Vendor: Intel (0x8086) 23:59:56 INFO - Device ID: 0x0a2e 23:59:56 INFO - Revision ID: 0x0009 23:59:56 INFO - Displays: 23:59:56 INFO - Display: 23:59:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:59:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:59:56 INFO - Display Serial Number: 1600x1200 60 23:59:56 INFO - Main Display: Yes 23:59:56 INFO - Mirror: Off 23:59:56 INFO - Online: Yes 23:59:56 INFO - Rotation: Supported 23:59:56 INFO - Return code: 0 23:59:56 INFO - Running post-action listener: _resource_record_post_action 23:59:56 INFO - [mozharness: 2016-10-04 06:59:56.011356Z] Finished run-tests step (success) 23:59:56 INFO - Running post-run listener: _resource_record_post_run 23:59:56 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 38238720; Write bytes: 708214272; Read time: 246; Write time: 1975 23:59:56 INFO - TinderboxPrint: CPU usage
54.6% 23:59:56 INFO - TinderboxPrint: I/O read bytes / time
38,238,720 / 246 23:59:56 INFO - TinderboxPrint: I/O write bytes / time
708,214,272 / 1,975 23:59:56 INFO - TinderboxPrint: CPU idle
2,201.5 (45.4%) 23:59:56 INFO - TinderboxPrint: CPU system
978.7 (20.2%) 23:59:56 INFO - TinderboxPrint: CPU user
1,665.5 (34.4%) 23:59:56 INFO - TinderboxPrint: Swap in / out
446,001,152 / 0 23:59:56 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 397734400; Write bytes: 390334464; Read time: 29748; Write time: 778 23:59:56 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 36284928; Write bytes: 296904192; Read time: 227; Write time: 1146 23:59:56 INFO - Running post-run listener: _upload_blobber_files 23:59:56 INFO - Blob upload gear active. 23:59:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:59:56 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:59:56 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:59:56 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:59:56 INFO - (blobuploader) - INFO - Open directory for files ... 23:59:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:59:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:59:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:59:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:59:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:59:57 INFO - (blobuploader) - INFO - Done attempting. 23:59:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:59:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:59:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:59:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:59:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:59:58 INFO - (blobuploader) - INFO - Done attempting. 23:59:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:59:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:59:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:00:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:00:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:00:00 INFO - (blobuploader) - INFO - Done attempting. 00:00:00 INFO - (blobuploader) - INFO - Iteration through files over. 00:00:01 INFO - Return code: 0 00:00:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:00:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:00:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e0d06b6ab678e243d785d04e4acce7704185f7df4cb6173c74f6a8308fa8cd9da36a0c6b6bfd87f9f9d3adb422a3dfc6f8b32ed0a2bbfcb06699948898873f24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d398208951c794a6e90c06e71d20633b5121618035fa020a582c90fb06cf0bbf70340a53512cfe5cb754c97146c13e0936da86bc756ebad868f7046c5a05124", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ba7272795edba69b96965454b62df27859e66e4d2deeb94ba9a8e8d0d9e309ec8c30b75bd9146ce9eed1c602fe44a550e1677be525bbd663e12298b3aa32b79"} 00:00:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:00:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:00:01 INFO - Contents: 00:00:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e0d06b6ab678e243d785d04e4acce7704185f7df4cb6173c74f6a8308fa8cd9da36a0c6b6bfd87f9f9d3adb422a3dfc6f8b32ed0a2bbfcb06699948898873f24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d398208951c794a6e90c06e71d20633b5121618035fa020a582c90fb06cf0bbf70340a53512cfe5cb754c97146c13e0936da86bc756ebad868f7046c5a05124", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ba7272795edba69b96965454b62df27859e66e4d2deeb94ba9a8e8d0d9e309ec8c30b75bd9146ce9eed1c602fe44a550e1677be525bbd663e12298b3aa32b79"} 00:00:01 INFO - Running post-run listener: copy_logs_to_upload_dir 00:00:01 INFO - Copying logs to upload dir... 00:00:01 INFO - mkdir: /builds/slave/test/build/upload/logs 00:00:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.780968 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-04 00:00:01.168924) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 00:00:01.173420) ========= 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.w9Tih7urp5/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.5B5FuBPMdS/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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/e0d06b6ab678e243d785d04e4acce7704185f7df4cb6173c74f6a8308fa8cd9da36a0c6b6bfd87f9f9d3adb422a3dfc6f8b32ed0a2bbfcb06699948898873f24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d398208951c794a6e90c06e71d20633b5121618035fa020a582c90fb06cf0bbf70340a53512cfe5cb754c97146c13e0936da86bc756ebad868f7046c5a05124", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ba7272795edba69b96965454b62df27859e66e4d2deeb94ba9a8e8d0d9e309ec8c30b75bd9146ce9eed1c602fe44a550e1677be525bbd663e12298b3aa32b79"} build_url:https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011028 build_url: 'https://queue.taskcluster.net/v1/task/JCfsIFaMRIypt0OVa4npLg/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/e0d06b6ab678e243d785d04e4acce7704185f7df4cb6173c74f6a8308fa8cd9da36a0c6b6bfd87f9f9d3adb422a3dfc6f8b32ed0a2bbfcb06699948898873f24", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d398208951c794a6e90c06e71d20633b5121618035fa020a582c90fb06cf0bbf70340a53512cfe5cb754c97146c13e0936da86bc756ebad868f7046c5a05124", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ba7272795edba69b96965454b62df27859e66e4d2deeb94ba9a8e8d0d9e309ec8c30b75bd9146ce9eed1c602fe44a550e1677be525bbd663e12298b3aa32b79"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 00:00:01.217683) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 00:00:01.227157) ========= 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.w9Tih7urp5/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.5B5FuBPMdS/Listeners TMPDIR=/var/folders/qd/d6crkgfx1f96h1r6sc2yq68h00000w/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.005186 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 00:00:01.280807) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 00:00:01.281134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 00:00:01.281501) ========= ========= Total master_lag: 0.30 =========