builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0115 starttime: 1476195200.18 results: success (0) buildid: 20161011040547 builduid: 2a961b68d07c481c9177851dfcc2d183 revision: 8654fba1417d44e510b8f2791f5ccf06c0496744 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:20.182891) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:20.183418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:20.183729) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:20.208531) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:20.208985) ========= 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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-11 07:13:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 66.4M=0s 2016-10-11 07:13:20 (66.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082835 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:20.315157) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:20.315519) ========= 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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.034973 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:20.371145) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 07:13:20.371543) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8654fba1417d44e510b8f2791f5ccf06c0496744 --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 8654fba1417d44e510b8f2791f5ccf06c0496744 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9FnMuL8KmK/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.uSEmS0Kymx/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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-11 07:13:20,446 truncating revision to first 12 chars 2016-10-11 07:13:20,447 Setting DEBUG logging. 2016-10-11 07:13:20,447 attempt 1/10 2016-10-11 07:13:20,447 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8654fba1417d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-11 07:13:21,566 unpacking tar archive at: fx-team-8654fba1417d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.382849 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-11 07:13:21.776690) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:21.777067) ========= script_repo_revision: 8654fba1417d44e510b8f2791f5ccf06c0496744 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:21.777503) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:21.777799) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-11 07:13:21.797770) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-11 07:13:21.798107) ========= /tools/buildbot/bin/python -u 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', '-u', '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:13:21 INFO - MultiFileLogger online at 20161011 07:13:21 in /builds/slave/test 07:13:21 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 07:13:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:13:21 INFO - {'append_to_log': False, 07:13:21 INFO - 'base_work_dir': '/builds/slave/test', 07:13:21 INFO - 'blob_upload_branch': 'Fx-Team', 07:13:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:13:21 INFO - 'branch': 'Fx-Team', 07:13:21 INFO - 'buildbot_json_path': 'buildprops.json', 07:13:21 INFO - 'config_files': ('talos/mac_config.py',), 07:13:21 INFO - 'default_actions': ('clobber', 07:13:21 INFO - 'read-buildbot-config', 07:13:21 INFO - 'download-and-extract', 07:13:21 INFO - 'populate-webroot', 07:13:21 INFO - 'create-virtualenv', 07:13:21 INFO - 'install', 07:13:21 INFO - 'run-tests'), 07:13:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:13:21 INFO - 'download_minidump_stackwalk': True, 07:13:21 INFO - 'download_symbols': 'ondemand', 07:13:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:13:21 INFO - 'tooltool.py': '/tools/tooltool.py', 07:13:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:13:21 INFO - '/tools/misc-python/virtualenv.py')}, 07:13:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:13:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:13:21 INFO - 'installer_path': 'installer.exe', 07:13:21 INFO - 'log_level': 'info', 07:13:21 INFO - 'log_name': 'talos', 07:13:21 INFO - 'log_to_console': True, 07:13:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:13:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:13:21 INFO - 'opt_config_files': (), 07:13:21 INFO - 'pip_index': False, 07:13:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:13:21 INFO - 'cmd': ('bash', 07:13:21 INFO - '-c', 07:13:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:13:21 INFO - 'enabled': True, 07:13:21 INFO - 'halt_on_failure': False, 07:13:21 INFO - 'name': 'check_screen_resolution'},), 07:13:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:13:22 INFO - 'cmd': ('bash', 07:13:22 INFO - '-c', 07:13:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:13:22 INFO - 'enabled': True, 07:13:22 INFO - 'halt_on_failure': False, 07:13:22 INFO - 'name': 'check_screen_resolution'},), 07:13:22 INFO - 'run_cmd_checks_enabled': True, 07:13:22 INFO - 'sps_profile': False, 07:13:22 INFO - 'sps_profile_interval': 0, 07:13:22 INFO - 'suite': 'dromaeojs-e10s', 07:13:22 INFO - 'system_bits': '32', 07:13:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:13:22 INFO - 'title': 't-yosemite-r7-0115', 07:13:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:13:22 INFO - 'use_talos_json': True, 07:13:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:13:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:13:22 INFO - 'work_dir': 'build'} 07:13:22 INFO - [mozharness: 2016-10-11 14:13:22.001944Z] Running clobber step. 07:13:22 INFO - Running pre-action listener: _resource_record_pre_action 07:13:22 INFO - Running main action method: clobber 07:13:22 INFO - rmtree: /builds/slave/test/build 07:13:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:13:24 INFO - Running post-action listener: _resource_record_post_action 07:13:24 INFO - [mozharness: 2016-10-11 14:13:24.148714Z] Finished clobber step (success) 07:13:24 INFO - [mozharness: 2016-10-11 14:13:24.148840Z] Running read-buildbot-config step. 07:13:24 INFO - Running pre-action listener: _resource_record_pre_action 07:13:24 INFO - Running main action method: read_buildbot_config 07:13:24 INFO - Using buildbot properties: 07:13:24 INFO - { 07:13:24 INFO - "project": "", 07:13:24 INFO - "product": "firefox", 07:13:24 INFO - "script_repo_revision": "production", 07:13:24 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 07:13:24 INFO - "repository": "", 07:13:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 07:13:24 INFO - "buildid": "20161011040547", 07:13:24 INFO - "pgo_build": "False", 07:13:24 INFO - "basedir": "/builds/slave/test", 07:13:24 INFO - "buildnumber": 9, 07:13:24 INFO - "slavename": "t-yosemite-r7-0115", 07:13:24 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:13:24 INFO - "platform": "yosemite_r7", 07:13:24 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 07:13:24 INFO - "branch": "fx-team", 07:13:24 INFO - "repo_path": "integration/fx-team", 07:13:24 INFO - "revision": "8654fba1417d44e510b8f2791f5ccf06c0496744", 07:13:24 INFO - "stage_platform": "macosx64", 07:13:24 INFO - "builduid": "2a961b68d07c481c9177851dfcc2d183", 07:13:24 INFO - "slavebuilddir": "test" 07:13:24 INFO - } 07:13:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:13:24 INFO - Running post-action listener: _resource_record_post_action 07:13:24 INFO - [mozharness: 2016-10-11 14:13:24.152210Z] Finished read-buildbot-config step (success) 07:13:24 INFO - [mozharness: 2016-10-11 14:13:24.152325Z] Running download-and-extract step. 07:13:24 INFO - Running pre-action listener: _resource_record_pre_action 07:13:24 INFO - Running main action method: download_and_extract 07:13:24 INFO - mkdir: /builds/slave/test/build/tests 07:13: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')]} 07:13:24 INFO - trying https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:13:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/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 07:13:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/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 07:13:25 INFO - Downloaded 1199 bytes. 07:13:25 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:13:25 INFO - Using the following test package requirements: 07:13:25 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:13:25 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:13:25 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:13:25 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:13:25 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:13:25 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:13:25 INFO - u'jsshell-mac.zip'], 07:13:25 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:13:25 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:13:25 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:13:25 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:13:25 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:13:25 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:13:25 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:13:25 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:13:25 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:13:25 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:13:25 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:13:25 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:13:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:13:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:13:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:13:25 INFO - Http code: 200 07:13:25 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 07:13:25 INFO - x-amz-cf-id: BO4IFP0HDtWPn6RTLDZcLShymEASeajdtCDaCl7HG6GZKKrmnYBZOA== 07:13:25 INFO - x-amz-version-id: ZApxBX8BZj2tlwhDuGX2FOjd6JZXLz49 07:13:26 INFO - Expected file size: 16508489 07:13:26 INFO - Obtained file size: 16508489 07:13:32 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 07:13:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:13:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:13:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:13:33 INFO - Http code: 200 07:13:33 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 07:13:33 INFO - x-amz-cf-id: FDU-z9JCHx6uhON1b48ri6TelLuLi2hZw97CuLow8VTlZEGdCeU23g== 07:13:33 INFO - x-amz-version-id: ZApxBX8BZj2tlwhDuGX2FOjd6JZXLz49 07:13:33 INFO - Expected file size: 16508489 07:13:33 INFO - Obtained file size: 16508489 07:13:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:13:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:13:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:13:40 INFO - Http code: 200 07:13:40 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 07:13:40 INFO - x-amz-cf-id: sk4A8kopB4zVk93FJrFYdrajoR3ceE6wP5JviDFW067W2T7HrploRw== 07:13:40 INFO - x-amz-version-id: dmTMuwlstYVBPMRs8ZTpnLE7VmCqOQwr 07:13:41 INFO - Expected file size: 14409006 07:13:41 INFO - Obtained file size: 14409006 07:13:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:41 INFO - trying https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:13:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:13:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/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 07:13:44 INFO - Downloaded 111678090 bytes. 07:13:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:13:44 INFO - mkdir: /builds/slave/test/properties 07:13:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:13:44 INFO - Writing to file /builds/slave/test/properties/build_url 07:13:44 INFO - Contents: 07:13:44 INFO - build_url:https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:13:45 INFO - Running post-action listener: _resource_record_post_action 07:13:45 INFO - Running post-action listener: set_extra_try_arguments 07:13:45 INFO - [mozharness: 2016-10-11 14:13:45.208360Z] Finished download-and-extract step (success) 07:13:45 INFO - [mozharness: 2016-10-11 14:13:45.208515Z] Running populate-webroot step. 07:13:45 INFO - Running pre-action listener: _resource_record_pre_action 07:13:45 INFO - Running main action method: populate_webroot 07:13:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:13:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:13:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:13:45 INFO - '--tppagecycles', 07:13:45 INFO - '7'], 07:13:45 INFO - 'tests': ['tsvgm']}}, 07:13:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:13:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:13:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:13:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:13:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:45 INFO - 'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:13:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:13:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:45 INFO - 'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['damp', 'tps']}, 07:13:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:45 INFO - 'tests': ['damp', 'tps']}, 07:13:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['dromaeo_dom']}, 07:13:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:13:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['basic_compositor_video']}, 07:13:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:13:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['a11yr', 07:13:45 INFO - 'ts_paint', 07:13:45 INFO - 'tpaint', 07:13:45 INFO - 'sessionrestore', 07:13:45 INFO - 'sessionrestore_no_auto_restore', 07:13:45 INFO - 'tabpaint']}, 07:13:45 INFO - 'other-e10s': {'tests': ['a11yr', 07:13:45 INFO - 'ts_paint', 07:13:45 INFO - 'tpaint', 07:13:45 INFO - 'sessionrestore', 07:13:45 INFO - 'sessionrestore_no_auto_restore', 07:13:45 INFO - 'tabpaint']}, 07:13:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:13:45 INFO - 'ts_paint', 07:13:45 INFO - 'tpaint', 07:13:45 INFO - 'sessionrestore', 07:13:45 INFO - 'sessionrestore_no_auto_restore', 07:13:45 INFO - 'tabpaint']}, 07:13:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:13:45 INFO - 'ts_paint', 07:13:45 INFO - 'tpaint', 07:13:45 INFO - 'sessionrestore', 07:13:45 INFO - 'sessionrestore_no_auto_restore', 07:13:45 INFO - 'tabpaint']}, 07:13:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['a11yr', 07:13:45 INFO - 'ts_paint', 07:13:45 INFO - 'tpaint', 07:13:45 INFO - 'sessionrestore', 07:13:45 INFO - 'sessionrestore_no_auto_restore', 07:13:45 INFO - 'tabpaint']}, 07:13:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['a11yr', 07:13:45 INFO - 'ts_paint', 07:13:45 INFO - 'tpaint', 07:13:45 INFO - 'sessionrestore', 07:13:45 INFO - 'sessionrestore_no_auto_restore', 07:13:45 INFO - 'tabpaint']}, 07:13:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['tsvgx', 07:13:45 INFO - 'tsvgr_opacity', 07:13:45 INFO - 'tart', 07:13:45 INFO - 'tscrollx', 07:13:45 INFO - 'cart']}, 07:13:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:13:45 INFO - 'tsvgr_opacity', 07:13:45 INFO - 'tart', 07:13:45 INFO - 'tscrollx', 07:13:45 INFO - 'cart']}, 07:13:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:45 INFO - 'talos_options': ['--disable-e10s'], 07:13:45 INFO - 'tests': ['tp5o']}, 07:13:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:45 INFO - 'tests': ['tp5o']}, 07:13:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:45 INFO - 'talos_options': ['--disable-e10s', 07:13:45 INFO - '--xperf_path', 07:13:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:13:45 INFO - 'tests': ['tp5n']}, 07:13:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:13:45 INFO - 'talos_options': ['--xperf_path', 07:13:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:13:45 INFO - 'tests': ['tp5n']}}, 07:13:45 INFO - 'talos.zip': {'path': '', 07:13:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:13:45 INFO - Running post-action listener: _resource_record_post_action 07:13:45 INFO - [mozharness: 2016-10-11 14:13:45.227337Z] Finished populate-webroot step (success) 07:13:45 INFO - [mozharness: 2016-10-11 14:13:45.227461Z] Running create-virtualenv step. 07:13:45 INFO - Running pre-action listener: _resource_record_pre_action 07:13:45 INFO - Running main action method: create_virtualenv 07:13:45 INFO - Creating virtualenv /builds/slave/test/build/venv 07:13:45 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 07:13:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:13:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:13:45 INFO - Using real prefix '/tools/python27' 07:13:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:13:46 INFO - Installing distribute.............................................................................................................................................................................................done. 07:13:48 INFO - Installing pip.................done. 07:13:48 INFO - Return code: 0 07:13:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:13:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:13:49 INFO - Reading from file tmpfile_stdout 07:13:49 INFO - Output received: 07:13:49 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) 07:13:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:13:49 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')]} 07:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:49 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')]} 07:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:49 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 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:13:49 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 07:13:49 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 07:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:49 INFO - 'HOME': '/Users/cltbld', 07:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:49 INFO - 'LOGNAME': 'cltbld', 07:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:49 INFO - 'MOZ_NO_REMOTE': '1', 07:13:49 INFO - 'NO_EM_RESTART': '1', 07:13:49 INFO - 'PAGER': '/bin/cat', 07:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:49 INFO - 'PWD': '/builds/slave/test', 07:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:49 INFO - 'SHELL': '/bin/bash', 07:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:13:49 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:49 INFO - 'USER': 'cltbld', 07:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:49 INFO - 'XPC_FLAGS': '0x0', 07:13:49 INFO - 'XPC_SERVICE_NAME': '0', 07:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:49 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 07:13:49 INFO - Cleaning up... 07:13:49 INFO - Return code: 0 07:13:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:13:49 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')]} 07:13:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:49 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')]} 07:13:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:49 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 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:13:49 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 07:13:49 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 07:13:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:13:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:49 INFO - 'HOME': '/Users/cltbld', 07:13:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:49 INFO - 'LOGNAME': 'cltbld', 07:13:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:49 INFO - 'MOZ_NO_REMOTE': '1', 07:13:49 INFO - 'NO_EM_RESTART': '1', 07:13:49 INFO - 'PAGER': '/bin/cat', 07:13:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:49 INFO - 'PWD': '/builds/slave/test', 07:13:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:49 INFO - 'SHELL': '/bin/bash', 07:13:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:13:49 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:13:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:49 INFO - 'USER': 'cltbld', 07:13:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:49 INFO - 'XPC_FLAGS': '0x0', 07:13:49 INFO - 'XPC_SERVICE_NAME': '0', 07:13:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:49 INFO - Downloading/unpacking psutil>=3.1.1 07:13:49 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 07:13:49 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 07:13:49 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 07:13:49 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 07:13:49 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 07:13:49 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 07:13:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:13:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:13:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:13:53 INFO - Installing collected packages: psutil 07:13:53 INFO - Running setup.py install for psutil 07:13:53 INFO - building 'psutil._psutil_osx' extension 07:13:53 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 07:13:53 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 07:13:53 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 07:13:54 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 07:13:54 INFO - building 'psutil._psutil_posix' extension 07:13:54 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 07:13:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:13:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:13:54 INFO - ^ 07:13:54 INFO - 1 warning generated. 07:13:54 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 07:13:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:13:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:13:54 INFO - Successfully installed psutil 07:13:54 INFO - Cleaning up... 07:13:54 INFO - Return code: 0 07:13:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:13:54 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')]} 07:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:54 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')]} 07:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:54 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 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:13:54 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 07:13:54 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 07:13:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:13:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:54 INFO - 'HOME': '/Users/cltbld', 07:13:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:54 INFO - 'LOGNAME': 'cltbld', 07:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:54 INFO - 'MOZ_NO_REMOTE': '1', 07:13:54 INFO - 'NO_EM_RESTART': '1', 07:13:54 INFO - 'PAGER': '/bin/cat', 07:13:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:54 INFO - 'PWD': '/builds/slave/test', 07:13:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:54 INFO - 'SHELL': '/bin/bash', 07:13:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:13:54 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:13:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:54 INFO - 'USER': 'cltbld', 07:13:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:54 INFO - 'XPC_FLAGS': '0x0', 07:13:54 INFO - 'XPC_SERVICE_NAME': '0', 07:13:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:13:54 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 07:13:54 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 07:13:54 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 07:13:54 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 07:13:54 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 07:13:54 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 07:13:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:13:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:13:57 INFO - Installing collected packages: mozsystemmonitor 07:13:57 INFO - Running setup.py install for mozsystemmonitor 07:13:57 INFO - Successfully installed mozsystemmonitor 07:13:57 INFO - Cleaning up... 07:13:57 INFO - Return code: 0 07:13:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:13:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:13:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:13:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:13:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:13:57 INFO - 'HOME': '/Users/cltbld', 07:13:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:13:57 INFO - 'LOGNAME': 'cltbld', 07:13:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:57 INFO - 'MOZ_NO_REMOTE': '1', 07:13:57 INFO - 'NO_EM_RESTART': '1', 07:13:57 INFO - 'PAGER': '/bin/cat', 07:13:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:13:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:57 INFO - 'PWD': '/builds/slave/test', 07:13:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:13:57 INFO - 'SHELL': '/bin/bash', 07:13:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:13:57 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:13:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:13:57 INFO - 'USER': 'cltbld', 07:13:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:13:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:13:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:57 INFO - 'XPC_FLAGS': '0x0', 07:13:57 INFO - 'XPC_SERVICE_NAME': '0', 07:13:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:13:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:57 INFO - Downloading/unpacking jsonschema==2.5.1 07:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:14:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:14:00 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 07:14:00 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 07:14:00 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 07:14:00 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 07:14:00 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 07:14:00 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 07:14:00 INFO - Downloading functools32-3.2.3-2.tar.gz 07:14:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:14:00 INFO - warning: no files found matching '*.txt' 07:14:00 INFO - no previously-included directories found matching 'build' 07:14:00 INFO - no previously-included directories found matching 'dist' 07:14:00 INFO - no previously-included directories found matching '.git*' 07:14:00 INFO - Installing collected packages: jsonschema, functools32 07:14:00 INFO - Running setup.py install for functools32 07:14:00 INFO - warning: no files found matching '*.txt' 07:14:00 INFO - no previously-included directories found matching 'build' 07:14:00 INFO - no previously-included directories found matching 'dist' 07:14:00 INFO - no previously-included directories found matching '.git*' 07:14:00 INFO - Successfully installed jsonschema functools32 07:14:00 INFO - Cleaning up... 07:14:01 INFO - Return code: 0 07:14:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:01 INFO - 'HOME': '/Users/cltbld', 07:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:01 INFO - 'LOGNAME': 'cltbld', 07:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:01 INFO - 'MOZ_NO_REMOTE': '1', 07:14:01 INFO - 'NO_EM_RESTART': '1', 07:14:01 INFO - 'PAGER': '/bin/cat', 07:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:01 INFO - 'PWD': '/builds/slave/test', 07:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:01 INFO - 'SHELL': '/bin/bash', 07:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:14:01 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:01 INFO - 'USER': 'cltbld', 07:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:01 INFO - 'XPC_FLAGS': '0x0', 07:14:01 INFO - 'XPC_SERVICE_NAME': '0', 07:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:14:01 INFO - Cleaning up... 07:14:01 INFO - Return code: 0 07:14:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:14:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:14:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:14:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:01 INFO - 'HOME': '/Users/cltbld', 07:14:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:01 INFO - 'LOGNAME': 'cltbld', 07:14:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:01 INFO - 'MOZ_NO_REMOTE': '1', 07:14:01 INFO - 'NO_EM_RESTART': '1', 07:14:01 INFO - 'PAGER': '/bin/cat', 07:14:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:01 INFO - 'PWD': '/builds/slave/test', 07:14:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:01 INFO - 'SHELL': '/bin/bash', 07:14:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:14:01 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:14:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:01 INFO - 'USER': 'cltbld', 07:14:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:01 INFO - 'XPC_FLAGS': '0x0', 07:14:01 INFO - 'XPC_SERVICE_NAME': '0', 07:14:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:01 INFO - Downloading/unpacking blobuploader==1.2.4 07:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:03 INFO - Downloading blobuploader-1.2.4.tar.gz 07:14:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:14:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:14:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:05 INFO - Downloading docopt-0.6.1.tar.gz 07:14:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:14:05 INFO - Installing collected packages: blobuploader, requests, docopt 07:14:05 INFO - Running setup.py install for blobuploader 07:14:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:14:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:14:05 INFO - Running setup.py install for requests 07:14:05 INFO - Running setup.py install for docopt 07:14:06 INFO - Successfully installed blobuploader requests docopt 07:14:06 INFO - Cleaning up... 07:14:06 INFO - Return code: 0 07:14:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:14:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:06 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 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:14:06 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 07:14:06 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 07:14:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:14:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:06 INFO - 'HOME': '/Users/cltbld', 07:14:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:06 INFO - 'LOGNAME': 'cltbld', 07:14:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:06 INFO - 'MOZ_NO_REMOTE': '1', 07:14:06 INFO - 'NO_EM_RESTART': '1', 07:14:06 INFO - 'PAGER': '/bin/cat', 07:14:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:06 INFO - 'PWD': '/builds/slave/test', 07:14:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:06 INFO - 'SHELL': '/bin/bash', 07:14:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:14:06 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:14:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:06 INFO - 'USER': 'cltbld', 07:14:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:06 INFO - 'XPC_FLAGS': '0x0', 07:14:06 INFO - 'XPC_SERVICE_NAME': '0', 07:14:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:14:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-4uIqXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:14:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-aNdgKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:14:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-OAmby_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:14:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ZxoOoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:14:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:14:06 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-HjL570-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-WxEUdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-GWTpfS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-i99tEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-tsZEkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Ji9Gma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-dR8_5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-3rSwQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-RPxKnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-am0BWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:14:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:07 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-xMuvgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:14:08 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-KuYnLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:14:08 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-T65Rrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:14:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:14:08 INFO - Running setup.py install for manifestparser 07:14:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:14:08 INFO - Running setup.py install for mozcrash 07:14:08 INFO - Running setup.py install for mozdebug 07:14:08 INFO - Running setup.py install for mozdevice 07:14:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:14:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:14:08 INFO - Running setup.py install for mozfile 07:14:09 INFO - Running setup.py install for mozhttpd 07:14:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:14:09 INFO - Running setup.py install for mozinfo 07:14:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:14:09 INFO - Running setup.py install for mozInstall 07:14:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:14:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:14:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:14:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:14:09 INFO - Running setup.py install for mozleak 07:14:09 INFO - Running setup.py install for mozlog 07:14:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:14:09 INFO - Running setup.py install for moznetwork 07:14:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:14:09 INFO - Running setup.py install for mozprocess 07:14:10 INFO - Running setup.py install for mozprofile 07:14:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:14:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:14:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:14:10 INFO - Running setup.py install for mozrunner 07:14:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:14:10 INFO - Running setup.py install for mozscreenshot 07:14:10 INFO - Running setup.py install for moztest 07:14:10 INFO - Running setup.py install for mozversion 07:14:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:14:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:14:10 INFO - Cleaning up... 07:14:10 INFO - Return code: 0 07:14:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:14:10 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')]} 07:14:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:10 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')]} 07:14:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:10 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 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:14:10 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 07:14:10 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 07:14:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:14:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:10 INFO - 'HOME': '/Users/cltbld', 07:14:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:10 INFO - 'LOGNAME': 'cltbld', 07:14:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:10 INFO - 'MOZ_NO_REMOTE': '1', 07:14:10 INFO - 'NO_EM_RESTART': '1', 07:14:10 INFO - 'PAGER': '/bin/cat', 07:14:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:10 INFO - 'PWD': '/builds/slave/test', 07:14:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:10 INFO - 'SHELL': '/bin/bash', 07:14:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:14:10 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:14:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:10 INFO - 'USER': 'cltbld', 07:14:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:10 INFO - 'XPC_FLAGS': '0x0', 07:14:10 INFO - 'XPC_SERVICE_NAME': '0', 07:14:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:14:11 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-6bWMJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:14:11 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)) 07:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:14:11 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-apuUDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:14:11 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)) 07:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:14:11 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-cRnPfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:14:11 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)) 07:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:14:11 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-vyaIP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:14:11 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)) 07:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:14:11 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-uBN62V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:14:11 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)) 07:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:14:11 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-b4AUe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:14:11 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)) 07:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:14:11 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-wePjRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:14:11 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)) 07:14:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:14:11 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-Q272hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:14:12 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)) 07:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:14:12 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-WTsM78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:14:12 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)) 07:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:14:12 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-S_gcBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:14:12 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)) 07:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:14:12 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-coFvf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:14:12 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)) 07:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:14:12 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ZN4SW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:14:12 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)) 07:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:14:12 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-ihqzt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:14:12 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)) 07:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:14:12 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-XxuggA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:14:12 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)) 07:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:12 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-QV3SUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:14:12 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)) 07:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:14:12 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-zpjGMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:14:12 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)) 07:14:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:14:12 INFO - Running setup.py (path:/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/pip-VFptEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:14:13 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)) 07:14:13 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)) 07:14:13 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)) 07:14:13 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)) 07:14:13 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)) 07:14:13 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)) 07:14:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:14:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:14:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:14:15 INFO - Downloading blessings-1.6.tar.gz 07:14:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:14:15 INFO - Installing collected packages: blessings 07:14:15 INFO - Running setup.py install for blessings 07:14:15 INFO - Successfully installed blessings 07:14:15 INFO - Cleaning up... 07:14:15 INFO - Return code: 0 07:14:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:14:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:14:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:14:16 INFO - Reading from file tmpfile_stdout 07:14:16 INFO - Current package versions: 07:14:16 INFO - blessings == 1.6 07:14:16 INFO - blobuploader == 1.2.4 07:14:16 INFO - docopt == 0.6.1 07:14:16 INFO - functools32 == 3.2.3-2 07:14:16 INFO - jsonschema == 2.5.1 07:14:16 INFO - manifestparser == 1.1 07:14:16 INFO - mozInstall == 1.12 07:14:16 INFO - mozcrash == 0.17 07:14:16 INFO - mozdebug == 0.1 07:14:16 INFO - mozdevice == 0.48 07:14:16 INFO - mozfile == 1.2 07:14:16 INFO - mozhttpd == 0.7 07:14:16 INFO - mozinfo == 0.9 07:14:16 INFO - mozleak == 0.1 07:14:16 INFO - mozlog == 3.4 07:14:16 INFO - moznetwork == 0.27 07:14:16 INFO - mozprocess == 0.23 07:14:16 INFO - mozprofile == 0.28 07:14:16 INFO - mozrunner == 6.12 07:14:16 INFO - mozscreenshot == 0.1 07:14:16 INFO - mozsystemmonitor == 0.3 07:14:16 INFO - moztest == 0.7 07:14:16 INFO - mozversion == 1.4 07:14:16 INFO - psutil == 3.1.1 07:14:16 INFO - requests == 1.2.3 07:14:16 INFO - wsgiref == 0.1.2 07:14:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:14: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')]} 07:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14: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')]} 07:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14:16 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 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:14:16 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 07:14:16 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 07:14:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:14:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:16 INFO - 'HOME': '/Users/cltbld', 07:14:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:16 INFO - 'LOGNAME': 'cltbld', 07:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:16 INFO - 'MOZ_NO_REMOTE': '1', 07:14:16 INFO - 'NO_EM_RESTART': '1', 07:14:16 INFO - 'PAGER': '/bin/cat', 07:14:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:16 INFO - 'PWD': '/builds/slave/test', 07:14:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:16 INFO - 'SHELL': '/bin/bash', 07:14:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:14:16 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:14:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:16 INFO - 'USER': 'cltbld', 07:14:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:16 INFO - 'XPC_FLAGS': '0x0', 07:14:16 INFO - 'XPC_SERVICE_NAME': '0', 07:14:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:16 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)) 07:14:16 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)) 07:14:16 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)) 07:14:16 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)) 07:14:16 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)) 07:14:16 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)) 07:14:16 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)) 07:14:16 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)) 07:14:16 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)) 07:14:16 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)) 07:14:16 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)) 07:14:16 INFO - Cleaning up... 07:14:16 INFO - Return code: 0 07:14:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:14: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')]} 07:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:14:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14: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')]} 07:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:14:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:14: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108f61490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5b0568d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, '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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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 07:14: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', 'jsonschema'] in /builds/slave/test/build 07:14: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 jsonschema 07:14:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:14:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:16 INFO - 'HOME': '/Users/cltbld', 07:14:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:16 INFO - 'LOGNAME': 'cltbld', 07:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:16 INFO - 'MOZ_NO_REMOTE': '1', 07:14:16 INFO - 'NO_EM_RESTART': '1', 07:14:16 INFO - 'PAGER': '/bin/cat', 07:14:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:16 INFO - 'PWD': '/builds/slave/test', 07:14:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:16 INFO - 'SHELL': '/bin/bash', 07:14:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:14:16 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:14:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:16 INFO - 'USER': 'cltbld', 07:14:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:16 INFO - 'XPC_FLAGS': '0x0', 07:14:16 INFO - 'XPC_SERVICE_NAME': '0', 07:14:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:14:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:14:16 INFO - Cleaning up... 07:14:16 INFO - Return code: 0 07:14:16 INFO - Running post-action listener: _resource_record_post_action 07:14:16 INFO - Running post-action listener: _start_resource_monitoring 07:14:16 INFO - Starting resource monitoring. 07:14:16 INFO - [mozharness: 2016-10-11 14:14:16.758618Z] Finished create-virtualenv step (success) 07:14:16 INFO - [mozharness: 2016-10-11 14:14:16.759590Z] Running install step. 07:14:16 INFO - Running pre-action listener: _resource_record_pre_action 07:14:16 INFO - Running main action method: install 07:14:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:14:17 INFO - Reading from file tmpfile_stdout 07:14:17 INFO - Detecting whether we're running mozinstall >=1.0... 07:14:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:14:17 INFO - Reading from file tmpfile_stdout 07:14:17 INFO - Output received: 07:14:17 INFO - Usage: mozinstall [options] installer 07:14:17 INFO - Options: 07:14:17 INFO - -h, --help show this help message and exit 07:14:17 INFO - -d DEST, --destination=DEST 07:14:17 INFO - Directory to install application into. [default: 07:14:17 INFO - "/builds/slave/test"] 07:14:17 INFO - --app=APP Application being installed. [default: firefox] 07:14:17 INFO - mkdir: /builds/slave/test/build/application 07:14:17 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'] 07:14:17 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 07:14:51 INFO - Reading from file tmpfile_stdout 07:14:51 INFO - Output received: 07:14:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:14:51 INFO - Running post-action listener: _resource_record_post_action 07:14:51 INFO - [mozharness: 2016-10-11 14:14:51.529420Z] Finished install step (success) 07:14:51 INFO - [mozharness: 2016-10-11 14:14:51.529549Z] Running run-tests step. 07:14:51 INFO - Running pre-action listener: _resource_record_pre_action 07:14:51 INFO - Running main action method: run_tests 07:14:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:14:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:14:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:14:51 INFO - 2016-10-11 07:14:51.544 screenresolution[1637:7488] starting screenresolution argv=screenresolution get 07:14:51 INFO - 2016-10-11 07:14:51.546 screenresolution[1637:7488] Display 0: 1600x1200x32@60 07:14:51 INFO - 2016-10-11 07:14:51.552 screenresolution[1638:7490] starting screenresolution argv=screenresolution list 07:14:51 INFO - Available Modes on Display 0 07:14:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:14:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:14:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:14:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:14:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:14:51 INFO - Intel Iris: 07:14:51 INFO - Chipset Model: Intel Iris 07:14:51 INFO - Type: GPU 07:14:51 INFO - Bus: Built-In 07:14:51 INFO - VRAM (Dynamic, Max): 1536 MB 07:14:51 INFO - Vendor: Intel (0x8086) 07:14:51 INFO - Device ID: 0x0a2e 07:14:51 INFO - Revision ID: 0x0009 07:14:51 INFO - Displays: 07:14:51 INFO - Display: 07:14:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:14:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:14:51 INFO - Display Serial Number: 1600x1200 60 07:14:51 INFO - Main Display: Yes 07:14:51 INFO - Mirror: Off 07:14:51 INFO - Online: Yes 07:14:51 INFO - Rotation: Supported 07:14:51 INFO - Return code: 0 07:14:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:14:51 INFO - Python 2.7.3 07:14:51 INFO - Return code: 0 07:14:51 INFO - grabbing minidump binary from tooltool 07:14: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')]} 07:14:51 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 0x108d75d30>, '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 0x108b9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d6db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:14:51 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 07:14:51 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 07:14:51 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 07:14:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:14:51 INFO - Return code: 0 07:14:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:14:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:14:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:14:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:14:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:14:51 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/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 07:14:51 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/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0115 --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 07:14:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9FnMuL8KmK/Render', 07:14:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:14:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:14:51 INFO - 'HOME': '/Users/cltbld', 07:14:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:14:51 INFO - 'LOGNAME': 'cltbld', 07:14:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:14:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:14:51 INFO - 'MOZ_NO_REMOTE': '1', 07:14:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:14:51 INFO - 'NO_EM_RESTART': '1', 07:14:51 INFO - 'PAGER': '/bin/cat', 07:14:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:14:51 INFO - 'PWD': '/builds/slave/test', 07:14:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:14:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:14:51 INFO - 'SHELL': '/bin/bash', 07:14:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uSEmS0Kymx/Listeners', 07:14:51 INFO - 'TMPDIR': '/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/', 07:14:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:14:51 INFO - 'USER': 'cltbld', 07:14:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:14:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:14:51 INFO - 'XPC_FLAGS': '0x0', 07:14:51 INFO - 'XPC_SERVICE_NAME': '0', 07:14:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:14:51 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/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0115', '--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 07:14:52 INFO - mozversion application_buildid: 20161011040547 07:14:52 INFO - mozversion application_changeset: 8654fba1417d44e510b8f2791f5ccf06c0496744 07:14:52 INFO - mozversion application_display_name: Nightly 07:14:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:14:52 INFO - mozversion application_name: Firefox 07:14:52 INFO - mozversion application_remotingname: firefox 07:14:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:14:52 INFO - mozversion application_vendor: Mozilla 07:14:52 INFO - mozversion application_version: 52.0a1 07:14:52 INFO - mozversion platform_buildid: 20161011040547 07:14:52 INFO - mozversion platform_changeset: 8654fba1417d44e510b8f2791f5ccf06c0496744 07:14:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:14:52 INFO - mozversion platform_version: 52.0a1 07:14:52 INFO - using testdate: 1476195292 07:14:52 INFO - actual date: 1476195292 07:14:52 INFO - starting webserver on 'localhost:49272' 07:14:52 INFO - SUITE-START | Running 2 tests 07:14:52 INFO - TEST-START | dromaeo_css 07:14:52 INFO - operating with platform_type : mac_ 07:14:52 INFO - Initialising browser for dromaeo_css test... 07:14:52 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpQ12SZI/profile http://localhost:49272/getInfo.html) 07:14:54 INFO - PROCESS | 1648 | 2016-10-11 07:14:54.921 plugin-container[1650:7653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 07:14:54 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:14:54 INFO - PROCESS | 1648 | 2016-10-11 07:14:54.924 plugin-container[1650:7653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d17, name = 'com.apple.CFPasteboardClient' 07:14:54 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:14:54 INFO - PROCESS | 1648 | 2016-10-11 07:14:54.924 plugin-container[1650:7653] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:14:54 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 07:14:54 INFO - PROCESS | 1648 | colorDepth:24 07:14:54 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 07:14:54 INFO - PROCESS | 1648 | __metrics 07:14:55 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 07:14:55 INFO - TEST-INFO | 1648: exit 0 07:14:55 INFO - Browser initialized. 07:14:55 INFO - Running cycle 1/1 for dromaeo_css test... 07:14:55 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpQ12SZI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:14:58 INFO - PROCESS | 1651 | 2016-10-11 07:14:58.339 plugin-container[1655:7828] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 07:14:58 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:14:58 INFO - PROCESS | 1651 | 2016-10-11 07:14:58.340 plugin-container[1655:7828] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 07:14:58 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:14:58 INFO - PROCESS | 1651 | 2016-10-11 07:14:58.340 plugin-container[1655:7828] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:15:07 INFO - PROCESS | 1651 | RSS: Main: 239030272 07:15:07 INFO - PROCESS | 1651 | 07:15:57 INFO - PROCESS | 1651 | 1476195357557 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] 07:18:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 07:18:13 INFO - PROCESS | 1651 | RSS: Main: 250732544 07:18:13 INFO - PROCESS | 1651 | 07:21:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 07:21:16 INFO - PROCESS | 1651 | RSS: Main: 264847360 07:21:16 INFO - PROCESS | 1651 | 07:24:18 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 07:24:19 INFO - PROCESS | 1651 | RSS: Main: 270856192 07:24:19 INFO - PROCESS | 1651 | 07:27:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 07:27:21 INFO - PROCESS | 1651 | RSS: Main: 270520320 07:27:21 INFO - PROCESS | 1651 | 07:30:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 07:30:25 INFO - PROCESS | 1651 | RSS: Main: 275873792 07:30:25 INFO - PROCESS | 1651 | 07:33:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 07:33:28 INFO - PROCESS | 1651 | RSS: Main: 278802432 07:33:28 INFO - PROCESS | 1651 | 07:33:28 INFO - PROCESS | 1651 | __start_tp_report 07:33:28 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 07:33:28 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 07:33:28 INFO - PROCESS | 1651 | |i|pagename|runs| 07:33:28 INFO - PROCESS | 1651 | |0;dojo.html;3343.8662453501875;3369.7978121312717;3394.4738565522352;3397.0997685613333;3410.6930376266137;4733.721895088324;5490.286262785832;5568.888622227563;5682.346530149034;5698.430156984305;5589.636673616223;5721.113227449741;5747.2528571285775;5761.884762304751;5889.146073819294;5149.304843846089;5164.9483505165035;5238.7642556084875;5246.422893481714;5345.973270133644;5295.682259064449;5301.151815709487;5314.335708036496;5320.707361095138;5326.440723724011;5265.341832270966;5274.789008439658;5276.366835979668;5311.787528498855;5329.653572517794;5124.256982737482;5185.170372740344;5237.973810130963;5251.579873610102;5320.068987927136;5144.099782538041;5168.147255702805;5178.766955487012;5199.948000520022;5282.81510147143;4449.9110017799785;4783.736894470802;5566.443355664442;5741.799037033684;5757.424257574251;4614.653900957442;5031.974840125813;6029.939700602981;6154.7230374633245;6227.595206311576;6247.219097612799;6257.217847769029;6258.249010118815;6265.530085243579;6278.623282603058;5797.768089276424;5823.526768551747;5862.062070068854;5891.175235467038;5912.290525137011;6101.3079194897555;6137.989923150331;6162.568620196633;6169.2288463942;6206.875862482725;6131.381032942549;6137.71108067302;6154.046122850876;6155.599886007395;6182.474489668429;6100.206973093558;6205.596636218631;6210.31686514483;6228.813135605939;6276.592021518587;5095.261187127846;6093.817185484442;6114.2357205349335;6140.166841500503;6239.500839932794;6200.81397558074;6240.126382306481;6250.499960003189;6263.561550691499;6285.088662143963;4927.507249275115;4928.556429921253;4945.307656928033;4955.8017679292425;4956.132676781549;4322.956770432255;4344.782760862007;4376.321670141133;4419.248727716296;4458.487273963536;5664.2636457261115;5672.319321681342;5720.970225359475;5736;5741.598088133791;6190;6213.347598502092;6216.067589861466;6230.750769969241;6239.750409983641;4382.9342559861625;5648.322201335866;5745.9425405745405;5772.7690892363835;5777.826665199967;5378.058839703036;5380.488853558905;5385.461453854583;5395.082835917903;5438.646487978269;5616.848546047969;6074.696265186812;6210.161628180228;6242.344553821874;6243.125962365272;6157.876842463036;6177.96911015442;6192.164057852132;6236.501079913687;6260.624362538262;6306.243250809932;6308.873822523616;6309.684515774101;6310.5267104968225;6324.272708638566;6037.09443583466;6131.509479241739;6155.784547540815;6216.813495595139;6226.25284965807;6140.197513349842;6163.599366041193;6184.381561843779;6264.686765661608;6267.655278959701;6087.8128764890425;6093.9390606093375;6098.237720284965;6129.724162412613;6132.724027418688;5843.678597677169;5963.463288304073;6112.786052488142;6148.9692551536955;6150.938490615215;5728.398518155534;5777.884442311046;5881.588288819741;5903.734331954986;5917.911231331447;5977.850553736192;6112.541559383153;6114.724837382418;6120.051392034242;6121.051237058263;5879.412058794086;5944.286685597583;5952.315483719428;5956.583039187215;5963.2844058713235 07:33:28 INFO - PROCESS | 1651 | |1;ext.html;461.7898856020511;479.47258016182184;482.0407389295302;483.4198961246504;587.8471597384679;35884;38203.426948595756;38208;38267.23465530688;38535;38534.61465385345;39040.80479597605;39099;39182.21635567287;39246;7665.5783931883725;8870.06864279251;9187.632494700203;9359.502479603265;9398.436093834358;6868.450523958071;9223.538823058854;9274.48990305533;9319.860202096974;9337.953310233474;9285.814283714322;9308.767280818;9310.25517958565;9329.72010839676;9344.159025687686;9108.908910910872;9219.032001639798;9264.72205833826;9266.444013359152;9290.1174388433;9185.908140918638;9199.126083022102;9254.259659227246;9258.259339252843;9284.72145835626;37670.81164594187;37845.432318515246;37881.81059094714;38097.047573810596;38127;3177.7140057394713;3222.242772948355;3288.161518812711;3309.7848639838335;3314.2377253231653;29109.981150659627;29682.25794355137;30014.69985300141;30738.538921916188;30762.53856192159;3144.2925341797913;3207.935841283185;3216;3217.951730724041;3252.3007553376137;472373;473292;477520;477668;478320;54133.188002179995;54564;54769.1784623231;54973;55002.44997549973;56134.71932640392;56457.71771141118;56513;56655.43344566584;56682.43317566854;15647;15689.058656480647;15856.365745370287;15908.76136857948;15922.840771592368;12369.010479161645;12415.379231038592;12471.376431178405;12474.189177703602;12497.56258530947;11066.114710823114;14433.783493247609;14594.562163135122;14601.561953141423;14629.634259143606;5052;5951.613145145551;5992.910106348409;6049.606775559662;6052.061930400795;740.3854800515559;917.7705573606598;923.8937522185034;980.0757647222351;1002.929794914349;23022.76977230206;27656.74521840844;28819.56770648442;30365.089047328616;31212.219694507367;885.420049867338;950.4344914775697;989.9752506187259;999.575180548264;1095.8630171228597;14179.361928713233;14680.853191468163;14717;14971;15025.924870375578;554979;643882;660559;661777;664418;50610.49389506058;50929.23606145911;51128.48871511237;51221.743891280305;51440.742796286526;1302.3358087375507;1308.4242933109401;1338.598420473854;1353.472145863114;1356.071091302461;24597.877010614833;24618.630720539564;27686.86156569204;27821.30446738767;28378;37310;45737.542624573325;45855.165793367894;45924.540754592024;45971;507582.24177581945;512294.33203845576;515600;516630;519372;23963.52072958566;24107;24453.087505998956;24474.021039158946;24553.263402097964;41579.96050098772;42427;42983.78508107439;43233.7838310819;43406.34890476582;35359.82320088486;35431;36374;36600;36621.633783661826;32774;33867.98396048122;35396.115097122776;35841;36332.81833590815;34233.97298081061;34610.134746631535;35168.12079698028;35249.471257930636;35339.646603533634;28974.27564310909;30992.53511197379;31828.840855795574;31999.880004200673;32116.518252226702;18874.622507550048;23862.88068559646;24198;24378.634320484853;24431.75568244295 07:33:28 INFO - PROCESS | 1651 | |2;jquery.html;360.6825993126049;361.416312655062;363.87810083622;372.89186136020555;388.87750358637027;19091.331803386885;20285.39143825686;20497.38507844767;21095.367138985817;21255.25606603769;19908.70136947947;20059.498512537193;20255.59488810223;20559.177632894705;20571.897140514277;5544.279243698314;5601.551875849943;5687.744051517671;5896.557758168155;6008.75964961401;5855.61938474;5985.221921150244;5996.880062398749;5998.4001599839885;6014.488768454687;5659.349174844904;5805.941940580604;5913.674747888865;5921.763129474816;5964.194833697438;5055.6713813602;5849.61977471463;5896.380880007623;5908.05471124618;5915.526757859382;4591.173588754023;5368.382635996872;5398;5409.486098820606;5476.397596264408;12902.612921612366;13146.408411621404;14770.892183086311;21018.474538136517;25061.49877002468;1987.6919077543005;2040.3470889315424;2080.833533317331;2099.88450635215;2147.6026935016916;9278.44329340235;9316.69566260724;9719.514024298756;9736.659216927374;9813;1663.8835281530298;1669.273865868339;1782.322717367392;1815.5733402650367;1844.7786265648074;127867.36066319702;130347;130863;131088.34455827755;131525.3423732875;11051.502682379312;11377.431128443544;11470.54117835294;11554.422278886022;11745.647630571097;14162.575122746333;14263.714725705428;14370.784438233435;14423.206723630301;14491.20298383599;8179.386546009073;8466;8495.787605309917;8501.19238672325;8534.359923005799;8429.367797415096;8430.789230269293;8437.451565648336;8561.78595535104;8605.741827745178;4392.275274579659;4460.50934397216;4477.462704475483;4507.639388848885;4562.247229207143;1838.6322735452964;1898.3958555020986;1902.1060101752155;1906.36136894142;1913.8468922486168;750.2759836757555;774.6436639146056;798.4211446701212;813.3127507256359;869.6347534035721;20274.986250687405;21025.474363140736;21050;21112.261070862445;21158.57682846365;832.9333653307721;847.3433089355675;862.844687956174;865.6277800545826;905.8958219804675;10544.472776361305;11016.06363459099;11047.668569942914;11109.611163609397;11122.721931951766;603867;607977;612628.8737112661;628551.2869227862;629192;128598;137628;138313;139322.99999999796;141667;946.6308139825474;948.3835506920557;970.0978090376018;973.9805203896024;974.1281553010158;18084.547886302422;18237.726434103766;18890.811091889456;18971.715424268372;19074.52313692169;55161;55366.44633553613;56341.71829140828;57363;57682.55793605193;76551.23448765441;78325;78660.60669696843;80571;82566;6822.522423430511;7068.929310706827;7215.67529461186;7894.881576776238;8045;11797.233179843282;12080.637580872588;12113.939430302793;12670.303133327756;12747.362631868184;11062.668119956414;11196.66410007701;11371.20401571882;11506.906843849822;11538.94230528842;10647.201459890694;10823.783524329627;11019.944900275448;11073.667789966314;11163.051140653135;11000.889991099986;11016.394098324668;11576.305421674726;11607;11674.357910315015;6838.965805171141;7171.784846454615;7251.23861994483;7445.404367650467;8197.385196110195;9669.9033009669;10940.781184376427;11273.71815704614;11334.979851813376;11384.37385943781 07:33:28 INFO - PROCESS | 1651 | |3;mootools.html;1287.4914408808525;1338.5181334719503;1603.2624992503447;1773.7605423267871;1848.704207326828;7629.542227466342;8039.075506316775;8242.258196762297;8807.471551706894;8836;8268.462549934266;8628;8666.610002549885;8778.91221087789;8781.604827782749;1943.6209939061819;2274.0789980058103;2309.1109922679807;2313.6413855852284;2315.189683610731;2278.851874628152;2307.9884600576975;2316.9652455213186;2323.384303159664;2332.1837356925025;2299.7700229976954;2309.3187509684685;2324.883755812211;2325.2326732178426;2328.976710232893;2272.5682120397073;2284.4859906520983;2292.6446400807818;2296.2651951375565;2301.7928386445215;2249.179049646867;2286.7827556382117;2293.334932869466;2300.5973954557885;2310.0413328468667;8597;8740.257078148365;8764;8791.60437780295;8800.603972821174;5100.336956195708;5214.6871187728475;5260.8684782880355;5265.078611243055;5284.81503147388;3467.271872906693;3568.7145028397667;3634.491171236029;3658.506101676266;3688.2254726507467;868.7828042989253;1067.8558394616782;1075.0432115417284;1089.145021158387;1090.2259395828971;11408;11464.25482343645;11551.422428878523;11574.131940104442;11599.304041757438;4449.710768800018;4603.585677288993;4685.906281874343;4699.835505757282;4748.833790817304;9723.65967191145;9741.269404794528;9811.460369679737;9844.852327215098;9846.310758246997;5212.921806172911;5273.868153296198;5278.155495120839;5287.4448182941005;5298.09932311508;4216.2199993001395;4453.888652783641;4765.309030190603;4848.587870031087;4855.708657480563;3798.0315019669806;3823.197128602969;3884.3008258513173;3901.7463864848696;3917.2557214129224;1273.1024627637494;1276.3235485192677;1279.6480967733949;1285.2417073926429;1286.8777466140702;752.6688257166829;898.9706785730302;904.1094521895927;920.7744102694749;943.2689665509148;5148.613853960893;5350.197470379474;5569.331680198321;5775.8556036099435;6092.3603021682975;717.7129148340706;971.0580736685404;972.1250874213265;976.5703090640095;976.6288810252058;3598.784072955631;3865.690744740414;3939.9606003940166;3950.762954222756;3971.6425521703186;10301.381917084998;10615.734606634896;10645.467726613793;10753.032227099598;10761.408122553177;9938.403695778277;10529.84205236922;10537.367757934548;10705.892941070646;10738.409387483764;7951.045874495097;8139.79650508742;8142.51144931306;8164.836703265902;8175.509469431853;5567.387587365468;5763.567732420169;5873.177755114202;6051.939480605138;6105.938940610537;8527.403081784216;9901.405915645084;9923.603055877684;9937.751556211153;9990.500474976077;8105.75682729519;8147.796305092183;8647.308215342631;9903.009699030039;9920.00799920031;6653.600783952978;7001.929980700128;7115.324044215791;7349.448791340564;7502.887456688263;7967.163447837894;8233.876491852507;8236.382271329554;8237.299829514559;8308.83382332362;7053.682584283617;7286.162091359562;7427.740029098955;7507.286807753255;7548.962255188689;6035.969820150871;7156.964215178891;7275;7285.251539630556;7355.043844300207;6618.966905165636;6822.147231596051;6860.485463590151;6968.79093627192;6982.755603553851;4853.6602437829015;4977.975110124571;5002.799888004439;5003.974980125222;5045.9243111352625;4452.998075433002;4731.432228132646;4961.181405068123;4962.6774259673;5078.390593128848 07:33:28 INFO - PROCESS | 1651 | |4;prototype.html;293.19371727748677;296.68847709904594;302.9699023320709;305.23843011685847;307.6246978686002;4994.550490455863;5120;5193.896122077556;5359.330083739533;5526.309211348576;4176.540580536139;4738.28925661151;5160.587153027758;5293.020791153639;5379.919301210475;4102.958970410295;4140.627343539081;4162.583741625828;4169.249535083683;4182.916341673164;3227.967050543827;4028.436018957348;4147.958520414787;4177.663147792708;4191.266528357525;3854.9784307158484;3942.245700663206;4090.406891000803;4105.302098643223;4138.503379594438;3185.761067919915;3562.750607457479;3859.957811390937;4091.4272001919753;4171.666266698657;2819.774418046572;3550.467429885512;3609.4946707460977;3612.8916132516065;3628.129248980226;4140.668746500272;4604.654650901196;5016.692588886796;5217.373915130209;5277.920831187535;3878.5927477614728;4712.246040633482;4717.976410117962;4726.991028812741;4741.790843334928;4483.439570053743;5688.715564221772;5691.702764412883;5725.513331366838;5745.827625171251;3505.8597656093725;3804.087019115392;3814.275287695329;3987.501562304712;3993.7004724645476;5114.2584325273;5173.948260517384;5750.424957504258;5959.463648271676;5982.8504287392725;3899.732586909266;3902.70729695274;3916.804159791999;3925.21495700861;3926.784026878492;3307.536944827726;3650.9229777215787;3978.3435733103715;3979.2240513100246;3997.380406037069;3680.77915325076;4627.838025669085;4631.374764406829;4643.674942753974;4674.789634466457;5249.737513124405;5707.71461426927;5759.7408116634015;5778.219940308089;5814.76740930358;1061.3631820907394;1134.9262297950772;1301.4078594239597;1314.6384744195423;1317.9588125380842;887.624182517096;895.3956079646213;898.2589363774781;914.492456686532;920.7375897869209;4609.303557418947;4625.583697467244;4981.701097934063;5272.420033796279;5289.206619007103;4500.77496125199;4767.094252092092;5190.740462976836;5393.5954803389905;5818.8254352369495;4861.538153875376;5011.699298042129;5291.153415453586;5291.3385826771655;5299.311089558333;3036.8633411496535;3097.829619370956;3104.580881581003;3123.9531407028912;3151.5115157150676;4944.901101978012;5594.559346002179;6158.784442544579;6161.921663708923;6181.258249010148;5758.827235182951;6044;6056.9697151513965;6102.206713127265;6156.415140561639;6146.631202127708;6153.569250152446;6166.568340216231;6166.876662466726;6171.259448866164;4882.437619961578;5007.123753343223;5037.974810125926;5062.848114556422;5924.370798030403;4801.447833499193;5091.414487334004;5803.8549036274435;5805.82582522525;5878.3239927408895;5542.8337149885565;5914.521369657586;5917.112433135064;6129.785457509144;6199;3250.8049517029053;3709.0727318170457;3864.14988702485;3891.708121890926;3922.7057970651745;4857.295692124693;5108.9233661495855;5541.5289700375915;5925.970370148294;5941.643501389931;5222.321098257202;5346;5461.972690136683;5619.578531610064;5783.652980821164;4757.286407038833;4999.725015124203;5076.923846142237;5250.818565822659;5775.566832487496;5399.541039011728;5413.810516631899;5558.888822223452;5574.888502230014;5678.488935995781;4933.284673722362;5300.390455097713;5708.914366284426;5740.024195886625;5779.942200577941;5313.707746074003;5619.241402410622;5630.466290169522;5639.6334238274385;5659.434056594308 07:33:28 INFO - PROCESS | 1651 | |5;yui.html;628.0937355127185;669.8526324208673;689.6379400814573;773.8839174123881;776.3905334312562;15068.32192551335;15875;15978.76031859523;16015.439459618916;16019.679606407879;14135;14347.282635868218;14453.710925781477;14456.132632042085;14991.100533967943;793.761871438569;809.5709274084744;820.1675299570941;827.6937533112757;828.3869936247194;815.355868863597;821.7616891101574;823.7858156879224;824.8432797768435;825.4799476329904;673.6867356679144;693.4902846407887;732.9010583571229;869.8129902071054;872.3893274707698;825.8430898129336;838.9580520973957;851.8083431227986;851.8594431918788;852.6035393541971;604.4137186928672;623.7972658885835;631.5074242091176;633.6578247746212;635.9077933699634;14537.200453975027;14646.560603181922;14767.630809229748;14783.334749936279;14991.625209369744;9952.203823694088;13313.933430332883;13649.931750341284;13821.378037988214;13976.02167848251;12483.126181167321;12693.555725549653;12712.173708708904;12751.043671724658;13229;4317.655363019098;4333.241682705546;4387.802548885308;4434.645228381754;4452.265376212921;123463.382683086;123557;125538.74461255454;126578.36710816479;127077.72922270751;10695.518701658444;10748.623798167027;10757.30077544957;10809.513571889283;11170.553177872956;17317.56706082358;17364.565885852953;17548.736768948474;21218;21456.678149827763;7195.560887822457;7442.590657513889;7616.961915190389;7755.689772409153;7828.138904720477;4198.790060496963;4272.380504826782;4311.590398912098;4346.565343465628;4444.711093778894;3333.4166520858753;3441.4149594568985;3443.070370999816;3443.6212016678146;3443.6728510791436;617.2284644194757;629.6002038705456;646.4763541531281;647.2685864972551;649.0523835200565;591.361329763854;591.9585629005928;617.5522746009108;622.6015350175891;631.722042301386;6959.930400695928;8121.025476942805;8350.872632194698;8398.916010839936;8989.730308090768;505.4945054945055;564.5765675743193;571.7141429285357;575.9222504961776;619.3620570812071;4146.724127658739;4189.895252618709;4348.499922508889;4356.477222733292;4463.977680111579;107390;131038;152059;170779;172712;104556;121600;124192;131078.34460827825;134045;88238;112127;112797.43601282105;116239.2564111539;119535.80464195421;9316.720498384788;9737.367071140354;9817.410955342702;9912.35569687968;10089.243306751876;54022.72988635032;65052.67473662791;65124.02313965389;65517.672411637635;65700.67149664222;88587.671184931;98558.01441985775;99556.55986442027;100042;101735;13038.47846086146;13098.541551046048;13312.46750129984;13320.067595268625;13430.664233394244;12334.629961101182;14502.782458262924;14632.853671463148;15083.54749357521;15392.230388480308;13982;14084.15495070299;14177.787333189804;14197.432102715775;14368.784468232776;13830.515931942333;13930.094543854617;14292.928535357256;14427.3507692152;14551.126932384092;13237.440170535901;13303.933480332536;13592.592222233348;13959.581212563638;14110.717785644434;9142.3600347975;10257.128144107834;10382.377057376581;10487.002841965757;10975.61585344541;9293.807943229898;9767.804643907224;9888.159506441745;10070.496475176065;10891.69299684043 07:33:28 INFO - PROCESS | 1651 | __end_tp_report 07:33:28 INFO - PROCESS | 1651 | __start_cc_report 07:33:28 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,369 07:33:28 INFO - PROCESS | 1651 | __end_cc_report 07:33:28 INFO - PROCESS | 1651 | __startTimestamp1476196408188__endTimestamp 07:33:28 INFO - PROCESS | 1651 | 07:33:28 INFO - PROCESS | 1651 | ------- Summary: start ------- 07:33:28 INFO - PROCESS | 1651 | Number of tests: 6 07:33:28 INFO - PROCESS | 1651 | 07:33:28 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5684.41 Median:5891.18 stddev:651.88 (11.1%) stddev-sans-first:627.49 07:33:28 INFO - PROCESS | 1651 | Values: 3343.9 3369.8 3394.5 3397.1 3410.7 4733.7 5490.3 5568.9 5682.3 5698.4 5589.6 5721.1 5747.3 5761.9 5889.1 5149.3 5164.9 5238.8 5246.4 5346.0 5295.7 5301.2 5314.3 5320.7 5326.4 5265.3 5274.8 5276.4 5311.8 5329.7 5124.3 5185.2 5238.0 5251.6 5320.1 5144.1 5168.1 5178.8 5199.9 5282.8 4449.9 4783.7 5566.4 5741.8 5757.4 4614.7 5032.0 6029.9 6154.7 6227.6 6247.2 6257.2 6258.2 6265.5 6278.6 5797.8 5823.5 5862.1 5891.2 5912.3 6101.3 6138.0 6162.6 6169.2 6206.9 6131.4 6137.7 6154.0 6155.6 6182.5 6100.2 6205.6 6210.3 6228.8 6276.6 5095.3 6093.8 6114.2 6140.2 6239.5 6200.8 6240.1 6250.5 6263.6 6285.1 4927.5 4928.6 4945.3 4955.8 4956.1 4323.0 4344.8 4376.3 4419.2 4458.5 5664.3 5672.3 5721.0 5736.0 5741.6 6190.0 6213.3 6216.1 6230.8 6239.8 4382.9 5648.3 5745.9 5772.8 5777.8 5378.1 5380.5 5385.5 5395.1 5438.6 5616.8 6074.7 6210.2 6242.3 6243.1 6157.9 6178.0 6192.2 6236.5 6260.6 6306.2 6308.9 6309.7 6310.5 6324.3 6037.1 6131.5 6155.8 6216.8 6226.3 6140.2 6163.6 6184.4 6264.7 6267.7 6087.8 6093.9 6098.2 6129.7 6132.7 5843.7 5963.5 6112.8 6149.0 6150.9 5728.4 5777.9 5881.6 5903.7 5917.9 5977.9 6112.5 6114.7 6120.1 6121.1 5879.4 5944.3 5952.3 5956.6 5963.3 07:33:28 INFO - PROCESS | 1651 | 07:33:28 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:66079.36 Median:38237.62 stddev:146460.51 (383.0%) stddev-sans-first:146788.51 07:33:28 INFO - PROCESS | 1651 | Values: 461.8 479.5 482.0 483.4 587.8 35884.0 38203.4 38208.0 38267.2 38535.0 38534.6 39040.8 39099.0 39182.2 39246.0 7665.6 8870.1 9187.6 9359.5 9398.4 6868.5 9223.5 9274.5 9319.9 9338.0 9285.8 9308.8 9310.3 9329.7 9344.2 9108.9 9219.0 9264.7 9266.4 9290.1 9185.9 9199.1 9254.3 9258.3 9284.7 37670.8 37845.4 37881.8 38097.0 38127.0 3177.7 3222.2 3288.2 3309.8 3314.2 29110.0 29682.3 30014.7 30738.5 30762.5 3144.3 3207.9 3216.0 3218.0 3252.3 472373.0 473292.0 477520.0 477668.0 478320.0 54133.2 54564.0 54769.2 54973.0 55002.4 56134.7 56457.7 56513.0 56655.4 56682.4 15647.0 15689.1 15856.4 15908.8 15922.8 12369.0 12415.4 12471.4 12474.2 12497.6 11066.1 14433.8 14594.6 14601.6 14629.6 5052.0 5951.6 5992.9 6049.6 6052.1 740.4 917.8 923.9 980.1 1002.9 23022.8 27656.7 28819.6 30365.1 31212.2 885.4 950.4 990.0 999.6 1095.9 14179.4 14680.9 14717.0 14971.0 15025.9 554979.0 643882.0 660559.0 661777.0 664418.0 50610.5 50929.2 51128.5 51221.7 51440.7 1302.3 1308.4 1338.6 1353.5 1356.1 24597.9 24618.6 27686.9 27821.3 28378.0 37310.0 45737.5 45855.2 45924.5 45971.0 507582.2 512294.3 515600.0 516630.0 519372.0 23963.5 24107.0 24453.1 24474.0 24553.3 41580.0 42427.0 42983.8 43233.8 43406.3 35359.8 35431.0 36374.0 36600.0 36621.6 32774.0 33868.0 35396.1 35841.0 36332.8 34234.0 34610.1 35168.1 35249.5 35339.6 28974.3 30992.5 31828.8 31999.9 32116.5 18874.6 23862.9 24198.0 24378.6 24431.8 07:33:28 INFO - PROCESS | 1651 | 07:33:28 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:35942.13 Median:362.65 stddev:103362.62 (28502.3%) stddev-sans-first:103618.05 07:33:28 INFO - PROCESS | 1651 | Values: 360.7 361.4 363.9 372.9 388.9 19091.3 20285.4 20497.4 21095.4 21255.3 19908.7 20059.5 20255.6 20559.2 20571.9 5544.3 5601.6 5687.7 5896.6 6008.8 5855.6 5985.2 5996.9 5998.4 6014.5 5659.3 5805.9 5913.7 5921.8 5964.2 5055.7 5849.6 5896.4 5908.1 5915.5 4591.2 5368.4 5398.0 5409.5 5476.4 12902.6 13146.4 14770.9 21018.5 25061.5 1987.7 2040.3 2080.8 2099.9 2147.6 9278.4 9316.7 9719.5 9736.7 9813.0 1663.9 1669.3 1782.3 1815.6 1844.8 127867.4 130347.0 130863.0 131088.3 131525.3 11051.5 11377.4 11470.5 11554.4 11745.6 14162.6 14263.7 14370.8 14423.2 14491.2 8179.4 8466.0 8495.8 8501.2 8534.4 8429.4 8430.8 8437.5 8561.8 8605.7 4392.3 4460.5 4477.5 4507.6 4562.2 1838.6 1898.4 1902.1 1906.4 1913.8 750.3 774.6 798.4 813.3 869.6 20275.0 21025.5 21050.0 21112.3 21158.6 832.9 847.3 862.8 865.6 905.9 10544.5 11016.1 11047.7 11109.6 11122.7 603867.0 607977.0 612628.9 628551.3 629192.0 128598.0 137628.0 138313.0 139323.0 141667.0 946.6 948.4 970.1 974.0 974.1 18084.5 18237.7 18890.8 18971.7 19074.5 55161.0 55366.4 56341.7 57363.0 57682.6 76551.2 78325.0 78660.6 80571.0 82566.0 6822.5 7068.9 7215.7 7894.9 8045.0 11797.2 12080.6 12113.9 12670.3 12747.4 11062.7 11196.7 11371.2 11506.9 11538.9 10647.2 10823.8 11019.9 11073.7 11163.1 11000.9 11016.4 11576.3 11607.0 11674.4 6839.0 7171.8 7251.2 7445.4 8197.4 9669.9 10940.8 11273.7 11335.0 11384.4 07:33:28 INFO - PROCESS | 1651 | 07:33:28 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5579.69 Median:5213.80 stddev:3116.78 (59.8%) stddev-sans-first:3108.83 07:33:28 INFO - PROCESS | 1651 | Values: 1287.5 1338.5 1603.3 1773.8 1848.7 7629.5 8039.1 8242.3 8807.5 8836.0 8268.5 8628.0 8666.6 8778.9 8781.6 1943.6 2274.1 2309.1 2313.6 2315.2 2278.9 2308.0 2317.0 2323.4 2332.2 2299.8 2309.3 2324.9 2325.2 2329.0 2272.6 2284.5 2292.6 2296.3 2301.8 2249.2 2286.8 2293.3 2300.6 2310.0 8597.0 8740.3 8764.0 8791.6 8800.6 5100.3 5214.7 5260.9 5265.1 5284.8 3467.3 3568.7 3634.5 3658.5 3688.2 868.8 1067.9 1075.0 1089.1 1090.2 11408.0 11464.3 11551.4 11574.1 11599.3 4449.7 4603.6 4685.9 4699.8 4748.8 9723.7 9741.3 9811.5 9844.9 9846.3 5212.9 5273.9 5278.2 5287.4 5298.1 4216.2 4453.9 4765.3 4848.6 4855.7 3798.0 3823.2 3884.3 3901.7 3917.3 1273.1 1276.3 1279.6 1285.2 1286.9 752.7 899.0 904.1 920.8 943.3 5148.6 5350.2 5569.3 5775.9 6092.4 717.7 971.1 972.1 976.6 976.6 3598.8 3865.7 3940.0 3950.8 3971.6 10301.4 10615.7 10645.5 10753.0 10761.4 9938.4 10529.8 10537.4 10705.9 10738.4 7951.0 8139.8 8142.5 8164.8 8175.5 5567.4 5763.6 5873.2 6051.9 6105.9 8527.4 9901.4 9923.6 9937.8 9990.5 8105.8 8147.8 8647.3 9903.0 9920.0 6653.6 7001.9 7115.3 7349.4 7502.9 7967.2 8233.9 8236.4 8237.3 8308.8 7053.7 7286.2 7427.7 7507.3 7549.0 6036.0 7157.0 7275.0 7285.3 7355.0 6619.0 6822.1 6860.5 6968.8 6982.8 4853.7 4978.0 5002.8 5004.0 5045.9 4453.0 4731.4 4961.2 4962.7 5078.4 07:33:28 INFO - PROCESS | 1651 | 07:33:28 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4540.56 Median:5014.20 stddev:1415.69 (28.2%) stddev-sans-first:1383.30 07:33:28 INFO - PROCESS | 1651 | Values: 293.2 296.7 303.0 305.2 307.6 4994.6 5120.0 5193.9 5359.3 5526.3 4176.5 4738.3 5160.6 5293.0 5379.9 4103.0 4140.6 4162.6 4169.2 4182.9 3228.0 4028.4 4148.0 4177.7 4191.3 3855.0 3942.2 4090.4 4105.3 4138.5 3185.8 3562.8 3860.0 4091.4 4171.7 2819.8 3550.5 3609.5 3612.9 3628.1 4140.7 4604.7 5016.7 5217.4 5277.9 3878.6 4712.2 4718.0 4727.0 4741.8 4483.4 5688.7 5691.7 5725.5 5745.8 3505.9 3804.1 3814.3 3987.5 3993.7 5114.3 5173.9 5750.4 5959.5 5982.9 3899.7 3902.7 3916.8 3925.2 3926.8 3307.5 3650.9 3978.3 3979.2 3997.4 3680.8 4627.8 4631.4 4643.7 4674.8 5249.7 5707.7 5759.7 5778.2 5814.8 1061.4 1134.9 1301.4 1314.6 1318.0 887.6 895.4 898.3 914.5 920.7 4609.3 4625.6 4981.7 5272.4 5289.2 4500.8 4767.1 5190.7 5393.6 5818.8 4861.5 5011.7 5291.2 5291.3 5299.3 3036.9 3097.8 3104.6 3124.0 3151.5 4944.9 5594.6 6158.8 6161.9 6181.3 5758.8 6044.0 6057.0 6102.2 6156.4 6146.6 6153.6 6166.6 6166.9 6171.3 4882.4 5007.1 5038.0 5062.8 5924.4 4801.4 5091.4 5803.9 5805.8 5878.3 5542.8 5914.5 5917.1 6129.8 6199.0 3250.8 3709.1 3864.1 3891.7 3922.7 4857.3 5108.9 5541.5 5926.0 5941.6 5222.3 5346.0 5462.0 5619.6 5783.7 4757.3 4999.7 5076.9 5250.8 5775.6 5399.5 5413.8 5558.9 5574.9 5678.5 4933.3 5300.4 5708.9 5740.0 5779.9 5313.7 5619.2 5630.5 5639.6 5659.4 07:33:28 INFO - PROCESS | 1651 | 07:33:28 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:25123.50 Median:3443.65 stddev:40627.69 (1179.8%) stddev-sans-first:40700.03 07:33:28 INFO - PROCESS | 1651 | Values: 628.1 669.9 689.6 773.9 776.4 15068.3 15875.0 15978.8 16015.4 16019.7 14135.0 14347.3 14453.7 14456.1 14991.1 793.8 809.6 820.2 827.7 828.4 815.4 821.8 823.8 824.8 825.5 673.7 693.5 732.9 869.8 872.4 825.8 839.0 851.8 851.9 852.6 604.4 623.8 631.5 633.7 635.9 14537.2 14646.6 14767.6 14783.3 14991.6 9952.2 13313.9 13649.9 13821.4 13976.0 12483.1 12693.6 12712.2 12751.0 13229.0 4317.7 4333.2 4387.8 4434.6 4452.3 123463.4 123557.0 125538.7 126578.4 127077.7 10695.5 10748.6 10757.3 10809.5 11170.6 17317.6 17364.6 17548.7 21218.0 21456.7 7195.6 7442.6 7617.0 7755.7 7828.1 4198.8 4272.4 4311.6 4346.6 4444.7 3333.4 3441.4 3443.1 3443.6 3443.7 617.2 629.6 646.5 647.3 649.1 591.4 592.0 617.6 622.6 631.7 6959.9 8121.0 8350.9 8398.9 8989.7 505.5 564.6 571.7 575.9 619.4 4146.7 4189.9 4348.5 4356.5 4464.0 107390.0 131038.0 152059.0 170779.0 172712.0 104556.0 121600.0 124192.0 131078.3 134045.0 88238.0 112127.0 112797.4 116239.3 119535.8 9316.7 9737.4 9817.4 9912.4 10089.2 54022.7 65052.7 65124.0 65517.7 65700.7 88587.7 98558.0 99556.6 100042.0 101735.0 13038.5 13098.5 13312.5 13320.1 13430.7 12334.6 14502.8 14632.9 15083.5 15392.2 13982.0 14084.2 14177.8 14197.4 14368.8 13830.5 13930.1 14292.9 14427.4 14551.1 13237.4 13303.9 13592.6 13959.6 14110.7 9142.4 10257.1 10382.4 10487.0 10975.6 9293.8 9767.8 9888.2 10070.5 10891.7 07:33:28 INFO - PROCESS | 1651 | -------- Summary: end -------- 07:33:28 INFO - PROCESS | 1651 | 07:33:28 INFO - TEST-INFO | 1651: exit 0 07:33:28 INFO - TEST-OK | dromaeo_css | took 1116796ms 07:33:28 INFO - TEST-START | kraken 07:33:28 INFO - operating with platform_type : mac_ 07:33:28 INFO - Initialising browser for kraken test... 07:33:28 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpobfZGU/profile http://localhost:49272/getInfo.html) 07:33:31 INFO - PROCESS | 1664 | 2016-10-11 07:33:31.441 plugin-container[1665:9929] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4b, name = 'com.apple.tsm.portname' 07:33:31 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:31 INFO - PROCESS | 1664 | 2016-10-11 07:33:31.444 plugin-container[1665:9929] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 07:33:31 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:31 INFO - PROCESS | 1664 | 2016-10-11 07:33:31.444 plugin-container[1665:9929] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:33:31 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 07:33:31 INFO - PROCESS | 1664 | colorDepth:24 07:33:31 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 07:33:31 INFO - PROCESS | 1664 | __metrics 07:33:31 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 07:33:32 INFO - TEST-INFO | 1664: exit 0 07:33:32 INFO - Browser initialized. 07:33:32 INFO - Running cycle 1/1 for kraken test... 07:33:32 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/T/tmpobfZGU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:33:34 INFO - PROCESS | 1666 | 2016-10-11 07:33:34.719 plugin-container[1667:10046] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 07:33:34 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:34 INFO - PROCESS | 1666 | 2016-10-11 07:33:34.719 plugin-container[1667:10046] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.CFPasteboardClient' 07:33:34 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:33:34 INFO - PROCESS | 1666 | 2016-10-11 07:33:34.719 plugin-container[1667:10046] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:33:44 INFO - PROCESS | 1666 | RSS: Main: 239165440 07:33:44 INFO - PROCESS | 1666 | 07:33:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 07:33:48 INFO - PROCESS | 1666 | RSS: Main: 252510208 07:33:48 INFO - PROCESS | 1666 | 07:33:50 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 07:33:51 INFO - PROCESS | 1666 | RSS: Main: 259948544 07:33:51 INFO - PROCESS | 1666 | 07:33:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 07:33:55 INFO - PROCESS | 1666 | RSS: Main: 260583424 07:33:55 INFO - PROCESS | 1666 | 07:33:57 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 07:33:57 INFO - PROCESS | 1666 | RSS: Main: 262909952 07:33:57 INFO - PROCESS | 1666 | 07:33:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:34:00 INFO - PROCESS | 1666 | RSS: Main: 263528448 07:34:00 INFO - PROCESS | 1666 | 07:34:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 07:34:04 INFO - PROCESS | 1666 | RSS: Main: 264769536 07:34:04 INFO - PROCESS | 1666 | 07:34:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 07:34:07 INFO - PROCESS | 1666 | RSS: Main: 264364032 07:34:07 INFO - PROCESS | 1666 | 07:34:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 07:34:11 INFO - PROCESS | 1666 | RSS: Main: 264560640 07:34:11 INFO - PROCESS | 1666 | 07:34:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:34:13 INFO - PROCESS | 1666 | RSS: Main: 264245248 07:34:13 INFO - PROCESS | 1666 | 07:34:15 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:34:16 INFO - PROCESS | 1666 | RSS: Main: 264310784 07:34:16 INFO - PROCESS | 1666 | 07:34:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:34:19 INFO - PROCESS | 1666 | RSS: Main: 264351744 07:34:19 INFO - PROCESS | 1666 | 07:34:22 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:34:23 INFO - PROCESS | 1666 | RSS: Main: 264642560 07:34:23 INFO - PROCESS | 1666 | 07:34:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:34:26 INFO - PROCESS | 1666 | RSS: Main: 264749056 07:34:26 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 07:34:28 INFO - PROCESS | 1666 | RSS: Main: 266412032 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | __start_tp_report 07:34:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 07:34:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 07:34:28 INFO - PROCESS | 1666 | |i|pagename|runs| 07:34:28 INFO - PROCESS | 1666 | |0;ai-astar;106;118;119;120;117;119;118;119;118;118 07:34:28 INFO - PROCESS | 1666 | |1;audio-beat-detection;146;117;116;151;117;119;119;117;144;117 07:34:28 INFO - PROCESS | 1666 | |2;audio-dft;197;207;207;243;201;201;202;199;201;196 07:34:28 INFO - PROCESS | 1666 | |3;audio-fft;83;59;60;86;69;72;57;58;75;75 07:34:28 INFO - PROCESS | 1666 | |4;audio-oscillator;87;79;87;61;73;95;127;102;83;87 07:34:28 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;92;90;92;75;136;91;99;90;132;80 07:34:28 INFO - PROCESS | 1666 | |6;imaging-darkroom;97;101;90;141;152;98;98;97;97;101 07:34:28 INFO - PROCESS | 1666 | |7;imaging-desaturate;95;135;89;140;144;114;96;97;112;145 07:34:28 INFO - PROCESS | 1666 | |8;json-parse-financial;49;75;59;57;57;59;57;58;65;88 07:34:28 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;73;68;70;76;75;72;58;92;93;60 07:34:28 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;85;84;91;88;89;89;88;90;87;89 07:34:28 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;153;163;161;145;155;163;160;147;220;156 07:34:28 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;174;175;181;230;216;179;177;178;175;178 07:34:28 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;65;67;76;66;64;60;59;62;67;86 07:34:28 INFO - PROCESS | 1666 | __end_tp_report 07:34:28 INFO - PROCESS | 1666 | __start_cc_report 07:34:28 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,976 07:34:28 INFO - PROCESS | 1666 | __end_cc_report 07:34:28 INFO - PROCESS | 1666 | __startTimestamp1476196468585__endTimestamp 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | ------- Summary: start ------- 07:34:28 INFO - PROCESS | 1666 | Number of tests: 14 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:117.20 Median:118.50 stddev:4.02 (3.4%) stddev-sans-first:0.88 07:34:28 INFO - PROCESS | 1666 | Values: 106.0 118.0 119.0 120.0 117.0 119.0 118.0 119.0 118.0 118.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:119.00 stddev:14.41 (12.1%) stddev-sans-first:13.41 07:34:28 INFO - PROCESS | 1666 | Values: 146.0 117.0 116.0 151.0 117.0 119.0 119.0 117.0 144.0 117.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:205.40 Median:201.50 stddev:13.70 (6.8%) stddev-sans-first:14.19 07:34:28 INFO - PROCESS | 1666 | Values: 197.0 207.0 207.0 243.0 201.0 201.0 202.0 199.0 201.0 196.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:69.40 Median:73.50 stddev:10.60 (14.4%) stddev-sans-first:10.03 07:34:28 INFO - PROCESS | 1666 | Values: 83.0 59.0 60.0 86.0 69.0 72.0 57.0 58.0 75.0 75.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:85.00 stddev:17.73 (20.9%) stddev-sans-first:18.80 07:34:28 INFO - PROCESS | 1666 | Values: 87.0 79.0 87.0 61.0 73.0 95.0 127.0 102.0 83.0 87.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:97.70 Median:90.50 stddev:20.28 (22.4%) stddev-sans-first:21.41 07:34:28 INFO - PROCESS | 1666 | Values: 92.0 90.0 92.0 75.0 136.0 91.0 99.0 90.0 132.0 80.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:107.20 Median:97.00 stddev:21.09 (21.7%) stddev-sans-first:22.05 07:34:28 INFO - PROCESS | 1666 | Values: 97.0 101.0 90.0 141.0 152.0 98.0 98.0 97.0 97.0 101.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:116.70 Median:117.00 stddev:22.38 (19.1%) stddev-sans-first:22.32 07:34:28 INFO - PROCESS | 1666 | Values: 95.0 135.0 89.0 140.0 144.0 114.0 96.0 97.0 112.0 145.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:62.40 Median:59.00 stddev:11.21 (19.0%) stddev-sans-first:10.79 07:34:28 INFO - PROCESS | 1666 | Values: 49.0 75.0 59.0 57.0 57.0 59.0 57.0 58.0 65.0 88.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:74.00 stddev:11.54 (15.6%) stddev-sans-first:12.23 07:34:28 INFO - PROCESS | 1666 | Values: 73.0 68.0 70.0 76.0 75.0 72.0 58.0 92.0 93.0 60.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:89.00 stddev:2.16 (2.4%) stddev-sans-first:2.00 07:34:28 INFO - PROCESS | 1666 | Values: 85.0 84.0 91.0 88.0 89.0 89.0 88.0 90.0 87.0 89.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:162.30 Median:160.50 stddev:21.21 (13.2%) stddev-sans-first:22.23 07:34:28 INFO - PROCESS | 1666 | Values: 153.0 163.0 161.0 145.0 155.0 163.0 160.0 147.0 220.0 156.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.30 Median:178.50 stddev:19.73 (11.1%) stddev-sans-first:20.42 07:34:28 INFO - PROCESS | 1666 | Values: 174.0 175.0 181.0 230.0 216.0 179.0 177.0 178.0 175.0 178.0 07:34:28 INFO - PROCESS | 1666 | 07:34:28 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:66.50 stddev:8.12 (12.2%) stddev-sans-first:8.57 07:34:28 INFO - PROCESS | 1666 | Values: 65.0 67.0 76.0 66.0 64.0 60.0 59.0 62.0 67.0 86.0 07:34:28 INFO - PROCESS | 1666 | -------- Summary: end -------- 07:34:28 INFO - PROCESS | 1666 | 07:34:29 INFO - TEST-INFO | 1666: exit 0 07:34:29 INFO - TEST-OK | kraken | took 60478ms 07:34:29 INFO - SUITE-END | took 1177s 07:34:29 INFO - Completed test suite (00:19:37) 07:34:29 INFO - javascript benchmark 07:34:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7180.133884446325, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3343.8662453501875, 3369.7978121312717, 3394.4738565522352, 3397.0997685613333, 3410.6930376266137, 4733.721895088324, 5490.286262785832, 5568.888622227563, 5682.346530149034, 5698.430156984305, 5589.636673616223, 5721.113227449741, 5747.2528571285775, 5761.884762304751, 5889.146073819294, 5149.304843846089, 5164.9483505165035, 5238.7642556084875, 5246.422893481714, 5345.973270133644, 5295.682259064449, 5301.151815709487, 5314.335708036496, 5320.707361095138, 5326.440723724011, 5265.341832270966, 5274.789008439658, 5276.366835979668, 5311.787528498855, 5329.653572517794, 5124.256982737482, 5185.170372740344, 5237.973810130963, 5251.579873610102, 5320.068987927136, 5144.099782538041, 5168.147255702805, 5178.766955487012, 5199.948000520022, 5282.81510147143, 4449.9110017799785, 4783.736894470802, 5566.443355664442, 5741.799037033684, 5757.424257574251, 4614.653900957442, 5031.974840125813, 6029.939700602981, 6154.7230374633245, 6227.595206311576, 6247.219097612799, 6257.217847769029, 6258.249010118815, 6265.530085243579, 6278.623282603058, 5797.768089276424, 5823.526768551747, 5862.062070068854, 5891.175235467038, 5912.290525137011, 6101.3079194897555, 6137.989923150331, 6162.568620196633, 6169.2288463942, 6206.875862482725, 6131.381032942549, 6137.71108067302, 6154.046122850876, 6155.599886007395, 6182.474489668429, 6100.206973093558, 6205.596636218631, 6210.31686514483, 6228.813135605939, 6276.592021518587, 5095.261187127846, 6093.817185484442, 6114.2357205349335, 6140.166841500503, 6239.500839932794, 6200.81397558074, 6240.126382306481, 6250.499960003189, 6263.561550691499, 6285.088662143963, 4927.507249275115, 4928.556429921253, 4945.307656928033, 4955.8017679292425, 4956.132676781549, 4322.956770432255, 4344.782760862007, 4376.321670141133, 4419.248727716296, 4458.487273963536, 5664.2636457261115, 5672.319321681342, 5720.970225359475, 5736.0, 5741.598088133791, 6190.0, 6213.347598502092, 6216.067589861466, 6230.750769969241, 6239.750409983641, 4382.9342559861625, 5648.322201335866, 5745.9425405745405, 5772.7690892363835, 5777.826665199967, 5378.058839703036, 5380.488853558905, 5385.461453854583, 5395.082835917903, 5438.646487978269, 5616.848546047969, 6074.696265186812, 6210.161628180228, 6242.344553821874, 6243.125962365272, 6157.876842463036, 6177.96911015442, 6192.164057852132, 6236.501079913687, 6260.624362538262, 6306.243250809932, 6308.873822523616, 6309.684515774101, 6310.5267104968225, 6324.272708638566, 6037.09443583466, 6131.509479241739, 6155.784547540815, 6216.813495595139, 6226.25284965807, 6140.197513349842, 6163.599366041193, 6184.381561843779, 6264.686765661608, 6267.655278959701, 6087.8128764890425, 6093.9390606093375, 6098.237720284965, 6129.724162412613, 6132.724027418688, 5843.678597677169, 5963.463288304073, 6112.786052488142, 6148.9692551536955, 6150.938490615215, 5728.398518155534, 5777.884442311046, 5881.588288819741, 5903.734331954986, 5917.911231331447, 5977.850553736192, 6112.541559383153, 6114.724837382418, 6120.051392034242, 6121.051237058263, 5879.412058794086, 5944.286685597583, 5952.315483719428, 5956.583039187215, 5963.2844058713235], "value": 5645.119749393259, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.7898856020511, 479.47258016182184, 482.0407389295302, 483.4198961246504, 587.8471597384679, 35884.0, 38203.426948595756, 38208.0, 38267.23465530688, 38535.0, 38534.61465385345, 39040.80479597605, 39099.0, 39182.21635567287, 39246.0, 7665.5783931883725, 8870.06864279251, 9187.632494700203, 9359.502479603265, 9398.436093834358, 6868.450523958071, 9223.538823058854, 9274.48990305533, 9319.860202096974, 9337.953310233474, 9285.814283714322, 9308.767280818, 9310.25517958565, 9329.72010839676, 9344.159025687686, 9108.908910910872, 9219.032001639798, 9264.72205833826, 9266.444013359152, 9290.1174388433, 9185.908140918638, 9199.126083022102, 9254.259659227246, 9258.259339252843, 9284.72145835626, 37670.81164594187, 37845.432318515246, 37881.81059094714, 38097.047573810596, 38127.0, 3177.7140057394713, 3222.242772948355, 3288.161518812711, 3309.7848639838335, 3314.2377253231653, 29109.981150659627, 29682.25794355137, 30014.69985300141, 30738.538921916188, 30762.53856192159, 3144.2925341797913, 3207.935841283185, 3216.0, 3217.951730724041, 3252.3007553376137, 472373.0, 473292.0, 477520.0, 477668.0, 478320.0, 54133.188002179995, 54564.0, 54769.1784623231, 54973.0, 55002.44997549973, 56134.71932640392, 56457.71771141118, 56513.0, 56655.43344566584, 56682.43317566854, 15647.0, 15689.058656480647, 15856.365745370287, 15908.76136857948, 15922.840771592368, 12369.010479161645, 12415.379231038592, 12471.376431178405, 12474.189177703602, 12497.56258530947, 11066.114710823114, 14433.783493247609, 14594.562163135122, 14601.561953141423, 14629.634259143606, 5052.0, 5951.613145145551, 5992.910106348409, 6049.606775559662, 6052.061930400795, 740.3854800515559, 917.7705573606598, 923.8937522185034, 980.0757647222351, 1002.929794914349, 23022.76977230206, 27656.74521840844, 28819.56770648442, 30365.089047328616, 31212.219694507367, 885.420049867338, 950.4344914775697, 989.9752506187259, 999.575180548264, 1095.8630171228597, 14179.361928713233, 14680.853191468163, 14717.0, 14971.0, 15025.924870375578, 554979.0, 643882.0, 660559.0, 661777.0, 664418.0, 50610.49389506058, 50929.23606145911, 51128.48871511237, 51221.743891280305, 51440.742796286526, 1302.3358087375507, 1308.4242933109401, 1338.598420473854, 1353.472145863114, 1356.071091302461, 24597.877010614833, 24618.630720539564, 27686.86156569204, 27821.30446738767, 28378.0, 37310.0, 45737.542624573325, 45855.165793367894, 45924.540754592024, 45971.0, 507582.24177581945, 512294.33203845576, 515600.0, 516630.0, 519372.0, 23963.52072958566, 24107.0, 24453.087505998956, 24474.021039158946, 24553.263402097964, 41579.96050098772, 42427.0, 42983.78508107439, 43233.7838310819, 43406.34890476582, 35359.82320088486, 35431.0, 36374.0, 36600.0, 36621.633783661826, 32774.0, 33867.98396048122, 35396.115097122776, 35841.0, 36332.81833590815, 34233.97298081061, 34610.134746631535, 35168.12079698028, 35249.471257930636, 35339.646603533634, 28974.27564310909, 30992.53511197379, 31828.840855795574, 31999.880004200673, 32116.518252226702, 18874.622507550048, 23862.88068559646, 24198.0, 24378.634320484853, 24431.75568244295], "value": 18496.329795568974, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.6825993126049, 361.416312655062, 363.87810083622, 372.89186136020555, 388.87750358637027, 19091.331803386885, 20285.39143825686, 20497.38507844767, 21095.367138985817, 21255.25606603769, 19908.70136947947, 20059.498512537193, 20255.59488810223, 20559.177632894705, 20571.897140514277, 5544.279243698314, 5601.551875849943, 5687.744051517671, 5896.557758168155, 6008.75964961401, 5855.61938474, 5985.221921150244, 5996.880062398749, 5998.4001599839885, 6014.488768454687, 5659.349174844904, 5805.941940580604, 5913.674747888865, 5921.763129474816, 5964.194833697438, 5055.6713813602, 5849.61977471463, 5896.380880007623, 5908.05471124618, 5915.526757859382, 4591.173588754023, 5368.382635996872, 5398.0, 5409.486098820606, 5476.397596264408, 12902.612921612366, 13146.408411621404, 14770.892183086311, 21018.474538136517, 25061.49877002468, 1987.6919077543005, 2040.3470889315424, 2080.833533317331, 2099.88450635215, 2147.6026935016916, 9278.44329340235, 9316.69566260724, 9719.514024298756, 9736.659216927374, 9813.0, 1663.8835281530298, 1669.273865868339, 1782.322717367392, 1815.5733402650367, 1844.7786265648074, 127867.36066319702, 130347.0, 130863.0, 131088.34455827755, 131525.3423732875, 11051.502682379312, 11377.431128443544, 11470.54117835294, 11554.422278886022, 11745.647630571097, 14162.575122746333, 14263.714725705428, 14370.784438233435, 14423.206723630301, 14491.20298383599, 8179.386546009073, 8466.0, 8495.787605309917, 8501.19238672325, 8534.359923005799, 8429.367797415096, 8430.789230269293, 8437.451565648336, 8561.78595535104, 8605.741827745178, 4392.275274579659, 4460.50934397216, 4477.462704475483, 4507.639388848885, 4562.247229207143, 1838.6322735452964, 1898.3958555020986, 1902.1060101752155, 1906.36136894142, 1913.8468922486168, 750.2759836757555, 774.6436639146056, 798.4211446701212, 813.3127507256359, 869.6347534035721, 20274.986250687405, 21025.474363140736, 21050.0, 21112.261070862445, 21158.57682846365, 832.9333653307721, 847.3433089355675, 862.844687956174, 865.6277800545826, 905.8958219804675, 10544.472776361305, 11016.06363459099, 11047.668569942914, 11109.611163609397, 11122.721931951766, 603867.0, 607977.0, 612628.8737112661, 628551.2869227862, 629192.0, 128598.0, 137628.0, 138313.0, 139322.99999999796, 141667.0, 946.6308139825474, 948.3835506920557, 970.0978090376018, 973.9805203896024, 974.1281553010158, 18084.547886302422, 18237.726434103766, 18890.811091889456, 18971.715424268372, 19074.52313692169, 55161.0, 55366.44633553613, 56341.71829140828, 57363.0, 57682.55793605193, 76551.23448765441, 78325.0, 78660.60669696843, 80571.0, 82566.0, 6822.522423430511, 7068.929310706827, 7215.67529461186, 7894.881576776238, 8045.0, 11797.233179843282, 12080.637580872588, 12113.939430302793, 12670.303133327756, 12747.362631868184, 11062.668119956414, 11196.66410007701, 11371.20401571882, 11506.906843849822, 11538.94230528842, 10647.201459890694, 10823.783524329627, 11019.944900275448, 11073.667789966314, 11163.051140653135, 11000.889991099986, 11016.394098324668, 11576.305421674726, 11607.0, 11674.357910315015, 6838.965805171141, 7171.784846454615, 7251.23861994483, 7445.404367650467, 8197.385196110195, 9669.9033009669, 10940.781184376427, 11273.71815704614, 11334.979851813376, 11384.37385943781], "value": 9327.22310703145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1287.4914408808525, 1338.5181334719503, 1603.2624992503447, 1773.7605423267871, 1848.704207326828, 7629.542227466342, 8039.075506316775, 8242.258196762297, 8807.471551706894, 8836.0, 8268.462549934266, 8628.0, 8666.610002549885, 8778.91221087789, 8781.604827782749, 1943.6209939061819, 2274.0789980058103, 2309.1109922679807, 2313.6413855852284, 2315.189683610731, 2278.851874628152, 2307.9884600576975, 2316.9652455213186, 2323.384303159664, 2332.1837356925025, 2299.7700229976954, 2309.3187509684685, 2324.883755812211, 2325.2326732178426, 2328.976710232893, 2272.5682120397073, 2284.4859906520983, 2292.6446400807818, 2296.2651951375565, 2301.7928386445215, 2249.179049646867, 2286.7827556382117, 2293.334932869466, 2300.5973954557885, 2310.0413328468667, 8597.0, 8740.257078148365, 8764.0, 8791.60437780295, 8800.603972821174, 5100.336956195708, 5214.6871187728475, 5260.8684782880355, 5265.078611243055, 5284.81503147388, 3467.271872906693, 3568.7145028397667, 3634.491171236029, 3658.506101676266, 3688.2254726507467, 868.7828042989253, 1067.8558394616782, 1075.0432115417284, 1089.145021158387, 1090.2259395828971, 11408.0, 11464.25482343645, 11551.422428878523, 11574.131940104442, 11599.304041757438, 4449.710768800018, 4603.585677288993, 4685.906281874343, 4699.835505757282, 4748.833790817304, 9723.65967191145, 9741.269404794528, 9811.460369679737, 9844.852327215098, 9846.310758246997, 5212.921806172911, 5273.868153296198, 5278.155495120839, 5287.4448182941005, 5298.09932311508, 4216.2199993001395, 4453.888652783641, 4765.309030190603, 4848.587870031087, 4855.708657480563, 3798.0315019669806, 3823.197128602969, 3884.3008258513173, 3901.7463864848696, 3917.2557214129224, 1273.1024627637494, 1276.3235485192677, 1279.6480967733949, 1285.2417073926429, 1286.8777466140702, 752.6688257166829, 898.9706785730302, 904.1094521895927, 920.7744102694749, 943.2689665509148, 5148.613853960893, 5350.197470379474, 5569.331680198321, 5775.8556036099435, 6092.3603021682975, 717.7129148340706, 971.0580736685404, 972.1250874213265, 976.5703090640095, 976.6288810252058, 3598.784072955631, 3865.690744740414, 3939.9606003940166, 3950.762954222756, 3971.6425521703186, 10301.381917084998, 10615.734606634896, 10645.467726613793, 10753.032227099598, 10761.408122553177, 9938.403695778277, 10529.84205236922, 10537.367757934548, 10705.892941070646, 10738.409387483764, 7951.045874495097, 8139.79650508742, 8142.51144931306, 8164.836703265902, 8175.509469431853, 5567.387587365468, 5763.567732420169, 5873.177755114202, 6051.939480605138, 6105.938940610537, 8527.403081784216, 9901.405915645084, 9923.603055877684, 9937.751556211153, 9990.500474976077, 8105.75682729519, 8147.796305092183, 8647.308215342631, 9903.009699030039, 9920.00799920031, 6653.600783952978, 7001.929980700128, 7115.324044215791, 7349.448791340564, 7502.887456688263, 7967.163447837894, 8233.876491852507, 8236.382271329554, 8237.299829514559, 8308.83382332362, 7053.682584283617, 7286.162091359562, 7427.740029098955, 7507.286807753255, 7548.962255188689, 6035.969820150871, 7156.964215178891, 7275.0, 7285.251539630556, 7355.043844300207, 6618.966905165636, 6822.147231596051, 6860.485463590151, 6968.79093627192, 6982.755603553851, 4853.6602437829015, 4977.975110124571, 5002.799888004439, 5003.974980125222, 5045.9243111352625, 4452.998075433002, 4731.432228132646, 4961.181405068123, 4962.6774259673, 5078.390593128848], "value": 4491.6549174925985, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.19371727748677, 296.68847709904594, 302.9699023320709, 305.23843011685847, 307.6246978686002, 4994.550490455863, 5120.0, 5193.896122077556, 5359.330083739533, 5526.309211348576, 4176.540580536139, 4738.28925661151, 5160.587153027758, 5293.020791153639, 5379.919301210475, 4102.958970410295, 4140.627343539081, 4162.583741625828, 4169.249535083683, 4182.916341673164, 3227.967050543827, 4028.436018957348, 4147.958520414787, 4177.663147792708, 4191.266528357525, 3854.9784307158484, 3942.245700663206, 4090.406891000803, 4105.302098643223, 4138.503379594438, 3185.761067919915, 3562.750607457479, 3859.957811390937, 4091.4272001919753, 4171.666266698657, 2819.774418046572, 3550.467429885512, 3609.4946707460977, 3612.8916132516065, 3628.129248980226, 4140.668746500272, 4604.654650901196, 5016.692588886796, 5217.373915130209, 5277.920831187535, 3878.5927477614728, 4712.246040633482, 4717.976410117962, 4726.991028812741, 4741.790843334928, 4483.439570053743, 5688.715564221772, 5691.702764412883, 5725.513331366838, 5745.827625171251, 3505.8597656093725, 3804.087019115392, 3814.275287695329, 3987.501562304712, 3993.7004724645476, 5114.2584325273, 5173.948260517384, 5750.424957504258, 5959.463648271676, 5982.8504287392725, 3899.732586909266, 3902.70729695274, 3916.804159791999, 3925.21495700861, 3926.784026878492, 3307.536944827726, 3650.9229777215787, 3978.3435733103715, 3979.2240513100246, 3997.380406037069, 3680.77915325076, 4627.838025669085, 4631.374764406829, 4643.674942753974, 4674.789634466457, 5249.737513124405, 5707.71461426927, 5759.7408116634015, 5778.219940308089, 5814.76740930358, 1061.3631820907394, 1134.9262297950772, 1301.4078594239597, 1314.6384744195423, 1317.9588125380842, 887.624182517096, 895.3956079646213, 898.2589363774781, 914.492456686532, 920.7375897869209, 4609.303557418947, 4625.583697467244, 4981.701097934063, 5272.420033796279, 5289.206619007103, 4500.77496125199, 4767.094252092092, 5190.740462976836, 5393.5954803389905, 5818.8254352369495, 4861.538153875376, 5011.699298042129, 5291.153415453586, 5291.3385826771655, 5299.311089558333, 3036.8633411496535, 3097.829619370956, 3104.580881581003, 3123.9531407028912, 3151.5115157150676, 4944.901101978012, 5594.559346002179, 6158.784442544579, 6161.921663708923, 6181.258249010148, 5758.827235182951, 6044.0, 6056.9697151513965, 6102.206713127265, 6156.415140561639, 6146.631202127708, 6153.569250152446, 6166.568340216231, 6166.876662466726, 6171.259448866164, 4882.437619961578, 5007.123753343223, 5037.974810125926, 5062.848114556422, 5924.370798030403, 4801.447833499193, 5091.414487334004, 5803.8549036274435, 5805.82582522525, 5878.3239927408895, 5542.8337149885565, 5914.521369657586, 5917.112433135064, 6129.785457509144, 6199.0, 3250.8049517029053, 3709.0727318170457, 3864.14988702485, 3891.708121890926, 3922.7057970651745, 4857.295692124693, 5108.9233661495855, 5541.5289700375915, 5925.970370148294, 5941.643501389931, 5222.321098257202, 5346.0, 5461.972690136683, 5619.578531610064, 5783.652980821164, 4757.286407038833, 4999.725015124203, 5076.923846142237, 5250.818565822659, 5775.566832487496, 5399.541039011728, 5413.810516631899, 5558.888822223452, 5574.888502230014, 5678.488935995781, 4933.284673722362, 5300.390455097713, 5708.914366284426, 5740.024195886625, 5779.942200577941, 5313.707746074003, 5619.241402410622, 5630.466290169522, 5639.6334238274385, 5659.434056594308], "value": 4088.789660749426, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [628.0937355127185, 669.8526324208673, 689.6379400814573, 773.8839174123881, 776.3905334312562, 15068.32192551335, 15875.0, 15978.76031859523, 16015.439459618916, 16019.679606407879, 14135.0, 14347.282635868218, 14453.710925781477, 14456.132632042085, 14991.100533967943, 793.761871438569, 809.5709274084744, 820.1675299570941, 827.6937533112757, 828.3869936247194, 815.355868863597, 821.7616891101574, 823.7858156879224, 824.8432797768435, 825.4799476329904, 673.6867356679144, 693.4902846407887, 732.9010583571229, 869.8129902071054, 872.3893274707698, 825.8430898129336, 838.9580520973957, 851.8083431227986, 851.8594431918788, 852.6035393541971, 604.4137186928672, 623.7972658885835, 631.5074242091176, 633.6578247746212, 635.9077933699634, 14537.200453975027, 14646.560603181922, 14767.630809229748, 14783.334749936279, 14991.625209369744, 9952.203823694088, 13313.933430332883, 13649.931750341284, 13821.378037988214, 13976.02167848251, 12483.126181167321, 12693.555725549653, 12712.173708708904, 12751.043671724658, 13229.0, 4317.655363019098, 4333.241682705546, 4387.802548885308, 4434.645228381754, 4452.265376212921, 123463.382683086, 123557.0, 125538.74461255454, 126578.36710816479, 127077.72922270751, 10695.518701658444, 10748.623798167027, 10757.30077544957, 10809.513571889283, 11170.553177872956, 17317.56706082358, 17364.565885852953, 17548.736768948474, 21218.0, 21456.678149827763, 7195.560887822457, 7442.590657513889, 7616.961915190389, 7755.689772409153, 7828.138904720477, 4198.790060496963, 4272.380504826782, 4311.590398912098, 4346.565343465628, 4444.711093778894, 3333.4166520858753, 3441.4149594568985, 3443.070370999816, 3443.6212016678146, 3443.6728510791436, 617.2284644194757, 629.6002038705456, 646.4763541531281, 647.2685864972551, 649.0523835200565, 591.361329763854, 591.9585629005928, 617.5522746009108, 622.6015350175891, 631.722042301386, 6959.930400695928, 8121.025476942805, 8350.872632194698, 8398.916010839936, 8989.730308090768, 505.4945054945055, 564.5765675743193, 571.7141429285357, 575.9222504961776, 619.3620570812071, 4146.724127658739, 4189.895252618709, 4348.499922508889, 4356.477222733292, 4463.977680111579, 107390.0, 131038.0, 152059.0, 170779.0, 172712.0, 104556.0, 121600.0, 124192.0, 131078.34460827825, 134045.0, 88238.0, 112127.0, 112797.43601282105, 116239.2564111539, 119535.80464195421, 9316.720498384788, 9737.367071140354, 9817.410955342702, 9912.35569687968, 10089.243306751876, 54022.72988635032, 65052.67473662791, 65124.02313965389, 65517.672411637635, 65700.67149664222, 88587.671184931, 98558.01441985775, 99556.55986442027, 100042.0, 101735.0, 13038.47846086146, 13098.541551046048, 13312.46750129984, 13320.067595268625, 13430.664233394244, 12334.629961101182, 14502.782458262924, 14632.853671463148, 15083.54749357521, 15392.230388480308, 13982.0, 14084.15495070299, 14177.787333189804, 14197.432102715775, 14368.784468232776, 13830.515931942333, 13930.094543854617, 14292.928535357256, 14427.3507692152, 14551.126932384092, 13237.440170535901, 13303.933480332536, 13592.592222233348, 13959.581212563638, 14110.717785644434, 9142.3600347975, 10257.128144107834, 10382.377057376581, 10487.002841965757, 10975.61585344541, 9293.807943229898, 9767.804643907224, 9888.159506441745, 10070.496475176065, 10891.69299684043], "value": 7660.186924912226, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1567.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 119.0, 120.0, 117.0, 119.0, 118.0, 119.0, 118.0, 118.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 116.0, 151.0, 117.0, 119.0, 119.0, 117.0, 144.0, 117.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 207.0, 207.0, 243.0, 201.0, 201.0, 202.0, 199.0, 201.0, 196.0], "value": 205.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 60.0, 86.0, 69.0, 72.0, 57.0, 58.0, 75.0, 75.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 79.0, 87.0, 61.0, 73.0, 95.0, 127.0, 102.0, 83.0, 87.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 92.0, 75.0, 136.0, 91.0, 99.0, 90.0, 132.0, 80.0], "value": 97.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 101.0, 90.0, 141.0, 152.0, 98.0, 98.0, 97.0, 97.0, 101.0], "value": 107.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 135.0, 89.0, 140.0, 144.0, 114.0, 96.0, 97.0, 112.0, 145.0], "value": 116.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 75.0, 59.0, 57.0, 57.0, 59.0, 57.0, 58.0, 65.0, 88.0], "value": 62.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 68.0, 70.0, 76.0, 75.0, 72.0, 58.0, 92.0, 93.0, 60.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 91.0, 88.0, 89.0, 89.0, 88.0, 90.0, 87.0, 89.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 163.0, 161.0, 145.0, 155.0, 163.0, 160.0, 147.0, 220.0, 156.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 175.0, 181.0, 230.0, 216.0, 179.0, 177.0, 178.0, 175.0, 178.0], "value": 186.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 76.0, 66.0, 64.0, 60.0, 59.0, 62.0, 67.0, 86.0], "value": 67.2, "unit": "score"}]}]} 07:34:29 INFO - Return code: 0 07:34:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:34:29 ERROR - # TBPL SUCCESS # 07:34:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:34:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:34:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:34:29 INFO - 2016-10-11 07:34:29.888 screenresolution[1671:10429] starting screenresolution argv=screenresolution get 07:34:29 INFO - 2016-10-11 07:34:29.890 screenresolution[1671:10429] Display 0: 1600x1200x32@60 07:34:29 INFO - 2016-10-11 07:34:29.896 screenresolution[1672:10432] starting screenresolution argv=screenresolution list 07:34:29 INFO - Available Modes on Display 0 07:34:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:34:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:34:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:34:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:34:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:34:30 INFO - Intel Iris: 07:34:30 INFO - Chipset Model: Intel Iris 07:34:30 INFO - Type: GPU 07:34:30 INFO - Bus: Built-In 07:34:30 INFO - VRAM (Dynamic, Max): 1536 MB 07:34:30 INFO - Vendor: Intel (0x8086) 07:34:30 INFO - Device ID: 0x0a2e 07:34:30 INFO - Revision ID: 0x0009 07:34:30 INFO - Displays: 07:34:30 INFO - Display: 07:34:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:34:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:34:30 INFO - Display Serial Number: 1600x1200 60 07:34:30 INFO - Main Display: Yes 07:34:30 INFO - Mirror: Off 07:34:30 INFO - Online: Yes 07:34:30 INFO - Rotation: Supported 07:34:30 INFO - Return code: 0 07:34:30 INFO - Running post-action listener: _resource_record_post_action 07:34:30 INFO - [mozharness: 2016-10-11 14:34:30.132022Z] Finished run-tests step (success) 07:34:30 INFO - Running post-run listener: _resource_record_post_run 07:34:30 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 37680640; Write bytes: 707604992; Read time: 362; Write time: 1862 07:34:30 INFO - TinderboxPrint: CPU usage
54.6% 07:34:30 INFO - TinderboxPrint: I/O read bytes / time
37,680,640 / 362 07:34:30 INFO - TinderboxPrint: I/O write bytes / time
707,604,992 / 1,862 07:34:30 INFO - TinderboxPrint: CPU idle
2,204.1 (45.4%) 07:34:30 INFO - TinderboxPrint: CPU system
986.7 (20.3%) 07:34:30 INFO - TinderboxPrint: CPU user
1,658.9 (34.2%) 07:34:30 INFO - TinderboxPrint: Swap in / out
447,287,296 / 0 07:34:30 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 391129600; Write bytes: 384738816; Read time: 29349; Write time: 682 07:34:30 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 36709888; Write bytes: 298466304; Read time: 347; Write time: 1129 07:34:30 INFO - Running post-run listener: _upload_blobber_files 07:34:30 INFO - Blob upload gear active. 07:34:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:34:30 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 07:34:30 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'] 07:34:30 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 07:34:31 INFO - (blobuploader) - INFO - Open directory for files ... 07:34:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:34:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:34:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:31 INFO - (blobuploader) - INFO - Done attempting. 07:34:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:34:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:34:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:32 INFO - (blobuploader) - INFO - Done attempting. 07:34:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:34:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:34:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:34:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:34:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:34:35 INFO - (blobuploader) - INFO - Done attempting. 07:34:35 INFO - (blobuploader) - INFO - Iteration through files over. 07:34:35 INFO - Return code: 0 07:34:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:34:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:34:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33e35b9902014585023dbaafe56962468b09f412ce2776e8002c5da5b31e056b138f6a07b8a5413ed267a607dedb0cea89dee66080630b8685751e479367d632", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/89744ef20625a0e726bb22a2db5460e1d948a1952f4eb9adc3e9f1a7f79b52fbe737333afe3ad7b88765900fba6d7b390ed48cb12554f472ccfaaf02ad906dd8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d55150081c3963f253fb1552ae3229769ffeac26c853977ad4aa8663508a07c86f95ff9c167792091c3a68a4460a5397df03ae5976da674090c6eff29f6e6347"} 07:34:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:34:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:34:35 INFO - Contents: 07:34:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33e35b9902014585023dbaafe56962468b09f412ce2776e8002c5da5b31e056b138f6a07b8a5413ed267a607dedb0cea89dee66080630b8685751e479367d632", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/89744ef20625a0e726bb22a2db5460e1d948a1952f4eb9adc3e9f1a7f79b52fbe737333afe3ad7b88765900fba6d7b390ed48cb12554f472ccfaaf02ad906dd8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d55150081c3963f253fb1552ae3229769ffeac26c853977ad4aa8663508a07c86f95ff9c167792091c3a68a4460a5397df03ae5976da674090c6eff29f6e6347"} 07:34:35 INFO - Running post-run listener: copy_logs_to_upload_dir 07:34:35 INFO - Copying logs to upload dir... 07:34:35 INFO - mkdir: /builds/slave/test/build/upload/logs 07:34:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.499285 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-11 07:34:35.334225) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 07:34:35.337320) ========= 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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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/33e35b9902014585023dbaafe56962468b09f412ce2776e8002c5da5b31e056b138f6a07b8a5413ed267a607dedb0cea89dee66080630b8685751e479367d632", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/89744ef20625a0e726bb22a2db5460e1d948a1952f4eb9adc3e9f1a7f79b52fbe737333afe3ad7b88765900fba6d7b390ed48cb12554f472ccfaaf02ad906dd8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d55150081c3963f253fb1552ae3229769ffeac26c853977ad4aa8663508a07c86f95ff9c167792091c3a68a4460a5397df03ae5976da674090c6eff29f6e6347"} build_url:https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010947 build_url: 'https://queue.taskcluster.net/v1/task/Z-KQj4akRW6q_oj4Dhcwog/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/33e35b9902014585023dbaafe56962468b09f412ce2776e8002c5da5b31e056b138f6a07b8a5413ed267a607dedb0cea89dee66080630b8685751e479367d632", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/89744ef20625a0e726bb22a2db5460e1d948a1952f4eb9adc3e9f1a7f79b52fbe737333afe3ad7b88765900fba6d7b390ed48cb12554f472ccfaaf02ad906dd8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d55150081c3963f253fb1552ae3229769ffeac26c853977ad4aa8663508a07c86f95ff9c167792091c3a68a4460a5397df03ae5976da674090c6eff29f6e6347"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-11 07:34:35.369323) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 07:34:35.369790) ========= 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.9FnMuL8KmK/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.uSEmS0Kymx/Listeners TMPDIR=/var/folders/vs/05tgb2r939n1hg2_z6mc78kw00000w/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.004976 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-11 07:34:35.420935) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 07:34:35.421753) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-11 07:34:35.422530) ========= ========= Total master_lag: 0.17 =========