builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0143 starttime: 1492042504.09 results: success (0) revision: c7159022ffd95b4faa3a19a1796bc782312bc045 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:04.093850) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:04.094379) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:04.094738) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:04.121434) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 17:15:04.121884) ========= 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.LyAhhoR1J5/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.wTHx733P2M/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 --2017-04-12 17:15:04-- 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% 24.2M=0s 2017-04-12 17:15:06 (24.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=2.036199 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 17:15:06.182667) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:06.183047) ========= 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.LyAhhoR1J5/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.wTHx733P2M/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.029090 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:06.233792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 17:15:06.234158) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev c7159022ffd95b4faa3a19a1796bc782312bc045 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/pine --rev c7159022ffd95b4faa3a19a1796bc782312bc045 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LyAhhoR1J5/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.wTHx733P2M/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 2017-04-12 17:15:06,322 truncating revision to first 12 chars 2017-04-12 17:15:06,322 Setting DEBUG logging. 2017-04-12 17:15:06,322 attempt 1/10 2017-04-12 17:15:06,322 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/c7159022ffd9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 17:15:07,183 unpacking tar archive at: pine-c7159022ffd9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.122920 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-12 17:15:07.380112) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:07.380532) ========= script_repo_revision: c7159022ffd95b4faa3a19a1796bc782312bc045 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:07.380967) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:07.381289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 17:15:07.401304) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-12 17:15:07.401634) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LyAhhoR1J5/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.wTHx733P2M/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:15:07 INFO - MultiFileLogger online at 20170412 17:15:07 in /builds/slave/test 17:15:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 17:15:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:15:07 INFO - {'append_to_log': False, 17:15:07 INFO - 'base_work_dir': '/builds/slave/test', 17:15:07 INFO - 'blob_upload_branch': 'Pine', 17:15:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:15:07 INFO - 'branch': 'Pine', 17:15:07 INFO - 'buildbot_json_path': 'buildprops.json', 17:15:07 INFO - 'config_files': ('talos/mac_config.py',), 17:15:07 INFO - 'default_actions': ('clobber', 17:15:07 INFO - 'read-buildbot-config', 17:15:07 INFO - 'download-and-extract', 17:15:07 INFO - 'populate-webroot', 17:15:07 INFO - 'create-virtualenv', 17:15:07 INFO - 'install', 17:15:07 INFO - 'run-tests'), 17:15:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:15:07 INFO - 'download_minidump_stackwalk': True, 17:15:07 INFO - 'download_symbols': 'ondemand', 17:15:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:15:07 INFO - 'tooltool.py': '/tools/tooltool.py', 17:15:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:15:07 INFO - '/tools/misc-python/virtualenv.py')}, 17:15:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:15:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:15:07 INFO - 'gecko_profile': False, 17:15:07 INFO - 'gecko_profile_interval': 0, 17:15:07 INFO - 'installer_path': 'installer.exe', 17:15:07 INFO - 'log_level': 'info', 17:15:07 INFO - 'log_name': 'talos', 17:15:07 INFO - 'log_to_console': True, 17:15:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:15:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:15:07 INFO - 'opt_config_files': (), 17:15:07 INFO - 'pip_index': False, 17:15:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:15:07 INFO - 'cmd': ('bash', 17:15:07 INFO - '-c', 17:15:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:15:07 INFO - 'enabled': True, 17:15:07 INFO - 'halt_on_failure': False, 17:15:07 INFO - 'name': 'check_screen_resolution'},), 17:15:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:15:07 INFO - 'cmd': ('bash', 17:15:07 INFO - '-c', 17:15:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:15:07 INFO - 'enabled': True, 17:15:07 INFO - 'halt_on_failure': False, 17:15:07 INFO - 'name': 'check_screen_resolution'},), 17:15:07 INFO - 'run_cmd_checks_enabled': True, 17:15:07 INFO - 'suite': 'dromaeojs-e10s', 17:15:07 INFO - 'system_bits': '32', 17:15:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:15:07 INFO - 'title': 't-yosemite-r7-0143', 17:15:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:15:07 INFO - 'use_talos_json': True, 17:15:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:15:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:15:07 INFO - 'work_dir': 'build'} 17:15:07 INFO - [mozharness: 2017-04-13 00:15:07.604776Z] Running clobber step. 17:15:07 INFO - Running pre-action listener: _resource_record_pre_action 17:15:07 INFO - Running main action method: clobber 17:15:07 INFO - rmtree: /builds/slave/test/build 17:15:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:15:10 INFO - Running post-action listener: _resource_record_post_action 17:15:10 INFO - [mozharness: 2017-04-13 00:15:10.294175Z] Finished clobber step (success) 17:15:10 INFO - [mozharness: 2017-04-13 00:15:10.294311Z] Running read-buildbot-config step. 17:15:10 INFO - Running pre-action listener: _resource_record_pre_action 17:15:10 INFO - Running main action method: read_buildbot_config 17:15:10 INFO - Using buildbot properties: 17:15:10 INFO - { 17:15:10 INFO - "upload_to_task_id": "JzR2VD0mRpSRoNsAgq68Pg", 17:15:10 INFO - "project": "", 17:15:10 INFO - "product": "firefox", 17:15:10 INFO - "who": "dmosedale@mozilla.com", 17:15:10 INFO - "script_repo_revision": "default", 17:15:10 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 17:15:10 INFO - "repository": "", 17:15:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 17:15:10 INFO - "stage_platform": "macosx64", 17:15:10 INFO - "basedir": "/builds/slave/test", 17:15:10 INFO - "buildnumber": 1, 17:15:10 INFO - "platform": "yosemite_r7", 17:15:10 INFO - "parent_task_id": "UBENQhR2SsmnEw96TJndJQ", 17:15:10 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:15:10 INFO - "slavebuilddir": "test", 17:15:10 INFO - "taskId": "AgAXgaaSQ6SY0RvKaVM7yA", 17:15:10 INFO - "branch": "pine", 17:15:10 INFO - "revision": "c7159022ffd95b4faa3a19a1796bc782312bc045", 17:15:10 INFO - "slavename": "t-yosemite-r7-0143", 17:15:10 INFO - "repo_path": "projects/pine" 17:15:10 INFO - } 17:15:10 INFO - Finding installer, test and symbols from parent task. 17:15:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AgAXgaaSQ6SY0RvKaVM7yA'}, attempt #1 17:15:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AgAXgaaSQ6SY0RvKaVM7yA'}, attempt #1 17:15:11 INFO - Task dependencies: AgAXgaaSQ6SY0RvKaVM7yA 17:15:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ'}, attempt #1 17:15:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/buildbot_properties.json'}, attempt #1 17:15:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:15:12 INFO - Set test_url: https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:15:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip 17:15:12 INFO - Running post-action listener: _resource_record_post_action 17:15:12 INFO - [mozharness: 2017-04-13 00:15:12.633240Z] Finished read-buildbot-config step (success) 17:15:12 INFO - [mozharness: 2017-04-13 00:15:12.633334Z] Running download-and-extract step. 17:15:12 INFO - Running pre-action listener: _resource_record_pre_action 17:15:12 INFO - Running main action method: download_and_extract 17:15:12 INFO - mkdir: /builds/slave/test/build/tests 17:15:12 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')]} 17:15:12 INFO - trying https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:15:12 INFO - Downloading https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:15:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:15:13 INFO - Downloaded 1325 bytes. 17:15:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:15:13 INFO - Using the following test package requirements: 17:15:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:15:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:15:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:15:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:15:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:15:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:15:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:15:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:15:13 INFO - u'jsshell-mac.zip'], 17:15:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:15:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:15:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:15:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:15:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:15:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:15:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:15:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:15:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:15:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:15:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:15:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:15:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:15:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:15:13 INFO - Fetch https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:15:14 INFO - Expected file size: 52582213 17:15:14 INFO - Obtained file size: 52582213 17:15:31 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:15:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:15:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:15:31 INFO - Fetch https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:15:33 INFO - Expected file size: 52582213 17:15:33 INFO - Obtained file size: 52582213 17:15:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:15:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:15:49 INFO - Fetch https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:15:50 INFO - Expected file size: 14405596 17:15:50 INFO - Obtained file size: 14405596 17:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:15:50 INFO - trying https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:15:50 INFO - Downloading https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:15:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:15:52 INFO - Downloaded 70998209 bytes. 17:15:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:15:52 INFO - mkdir: /builds/slave/test/properties 17:15:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:15:52 INFO - Writing to file /builds/slave/test/properties/build_url 17:15:52 INFO - Contents: 17:15:52 INFO - build_url:https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:15:52 INFO - Running post-action listener: _resource_record_post_action 17:15:52 INFO - Running post-action listener: set_extra_try_arguments 17:15:52 INFO - [mozharness: 2017-04-13 00:15:52.461805Z] Finished download-and-extract step (success) 17:15:52 INFO - [mozharness: 2017-04-13 00:15:52.461976Z] Running populate-webroot step. 17:15:52 INFO - Running pre-action listener: _resource_record_pre_action 17:15:52 INFO - Running main action method: populate_webroot 17:15:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:15:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:15:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:15:52 INFO - '--tppagecycles', 17:15:52 INFO - '7'], 17:15:52 INFO - 'tests': ['tsvgm']}}, 17:15:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:15:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:15:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:15:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:15:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:15:52 INFO - 'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:15:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:15:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:15:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:15:52 INFO - 'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['damp', 'tps']}, 17:15:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:15:52 INFO - 'tests': ['damp', 'tps']}, 17:15:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['dromaeo_dom']}, 17:15:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:15:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['basic_compositor_video']}, 17:15:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:15:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['a11yr', 17:15:52 INFO - 'ts_paint', 17:15:52 INFO - 'tpaint', 17:15:52 INFO - 'sessionrestore', 17:15:52 INFO - 'sessionrestore_no_auto_restore', 17:15:52 INFO - 'tabpaint']}, 17:15:52 INFO - 'other-e10s': {'tests': ['a11yr', 17:15:52 INFO - 'ts_paint', 17:15:52 INFO - 'tpaint', 17:15:52 INFO - 'sessionrestore', 17:15:52 INFO - 'sessionrestore_no_auto_restore', 17:15:52 INFO - 'tabpaint']}, 17:15:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:15:52 INFO - 'ts_paint', 17:15:52 INFO - 'tpaint', 17:15:52 INFO - 'sessionrestore', 17:15:52 INFO - 'sessionrestore_no_auto_restore', 17:15:52 INFO - 'tabpaint']}, 17:15:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:15:52 INFO - 'ts_paint', 17:15:52 INFO - 'tpaint', 17:15:52 INFO - 'sessionrestore', 17:15:52 INFO - 'sessionrestore_no_auto_restore', 17:15:52 INFO - 'tabpaint']}, 17:15:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['a11yr', 17:15:52 INFO - 'ts_paint', 17:15:52 INFO - 'tpaint', 17:15:52 INFO - 'sessionrestore', 17:15:52 INFO - 'sessionrestore_no_auto_restore', 17:15:52 INFO - 'tabpaint']}, 17:15:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['a11yr', 17:15:52 INFO - 'ts_paint', 17:15:52 INFO - 'tpaint', 17:15:52 INFO - 'sessionrestore', 17:15:52 INFO - 'sessionrestore_no_auto_restore', 17:15:52 INFO - 'tabpaint']}, 17:15:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['tsvgx', 17:15:52 INFO - 'tsvgr_opacity', 17:15:52 INFO - 'tart', 17:15:52 INFO - 'tscrollx', 17:15:52 INFO - 'cart', 17:15:52 INFO - 'tsvg_static']}, 17:15:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:15:52 INFO - 'tsvgr_opacity', 17:15:52 INFO - 'tart', 17:15:52 INFO - 'tscrollx', 17:15:52 INFO - 'cart', 17:15:52 INFO - 'tsvg_static']}, 17:15:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:15:52 INFO - 'talos_options': ['--disable-e10s'], 17:15:52 INFO - 'tests': ['tp5o']}, 17:15:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:15:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:15:52 INFO - 'talos_options': ['--disable-e10s', 17:15:52 INFO - '--xperf_path', 17:15:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:15:52 INFO - 'tests': ['tp5n']}, 17:15:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:15:52 INFO - 'talos_options': ['--xperf_path', 17:15:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:15:52 INFO - 'tests': ['tp5n']}}, 17:15:52 INFO - 'talos.zip': {'path': '', 17:15:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:15:52 INFO - Running post-action listener: _resource_record_post_action 17:15:52 INFO - [mozharness: 2017-04-13 00:15:52.481988Z] Finished populate-webroot step (success) 17:15:52 INFO - [mozharness: 2017-04-13 00:15:52.482124Z] Running create-virtualenv step. 17:15:52 INFO - Running pre-action listener: _resource_record_pre_action 17:15:52 INFO - Running main action method: create_virtualenv 17:15:52 INFO - Creating virtualenv /builds/slave/test/build/venv 17:15:52 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 17:15:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:15:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:15:52 INFO - Using real prefix '/tools/python27' 17:15:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:15:53 INFO - Installing distribute.............................................................................................................................................................................................done. 17:15:56 INFO - Installing pip.................done. 17:15:56 INFO - Return code: 0 17:15:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:15:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:15:56 INFO - Reading from file tmpfile_stdout 17:15:56 INFO - Output received: 17:15:56 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) 17:15:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:15:56 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')]} 17:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:15:56 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')]} 17:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:15:56 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 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:15:56 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 17:15:56 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 17:15:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:15:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:15:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:15:56 INFO - 'HOME': '/Users/cltbld', 17:15:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:15:56 INFO - 'LOGNAME': 'cltbld', 17:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:56 INFO - 'MOZ_NO_REMOTE': '1', 17:15:56 INFO - 'NO_EM_RESTART': '1', 17:15:56 INFO - 'PAGER': '/bin/cat', 17:15:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:56 INFO - 'PWD': '/builds/slave/test', 17:15:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:15:56 INFO - 'SHELL': '/bin/bash', 17:15:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:15:56 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:15:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:15:56 INFO - 'USER': 'cltbld', 17:15:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:15:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:56 INFO - 'XPC_FLAGS': '0x0', 17:15:56 INFO - 'XPC_SERVICE_NAME': '0', 17:15:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:56 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 17:15:56 INFO - Cleaning up... 17:15:56 INFO - Return code: 0 17:15:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:15:56 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')]} 17:15:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:15:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:15:56 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')]} 17:15:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:15:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:15:56 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 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:15:56 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 17:15:56 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 17:15:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:15:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:15:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:15:56 INFO - 'HOME': '/Users/cltbld', 17:15:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:15:56 INFO - 'LOGNAME': 'cltbld', 17:15:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:56 INFO - 'MOZ_NO_REMOTE': '1', 17:15:56 INFO - 'NO_EM_RESTART': '1', 17:15:56 INFO - 'PAGER': '/bin/cat', 17:15:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:15:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:56 INFO - 'PWD': '/builds/slave/test', 17:15:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:15:56 INFO - 'SHELL': '/bin/bash', 17:15:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:15:56 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:15:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:15:56 INFO - 'USER': 'cltbld', 17:15:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:15:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:15:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:56 INFO - 'XPC_FLAGS': '0x0', 17:15:56 INFO - 'XPC_SERVICE_NAME': '0', 17:15:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:15:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:15:57 INFO - Downloading/unpacking psutil>=3.1.1 17:15: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 17:15: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 17:15: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 17:15: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 17:15: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 17:15: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 17:16:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:16:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:00 INFO - Installing collected packages: psutil 17:16:00 INFO - Running setup.py install for psutil 17:16:00 INFO - building 'psutil._psutil_osx' extension 17:16:00 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 17:16:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:16:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:16:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:16:01 INFO - building 'psutil._psutil_posix' extension 17:16:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:16:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:16:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:16:01 INFO - ^ 17:16:01 INFO - 1 warning generated. 17:16:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:16:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:16:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:16:01 INFO - Successfully installed psutil 17:16:01 INFO - Cleaning up... 17:16:01 INFO - Return code: 0 17:16:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:16:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:16:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:16:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:02 INFO - 'HOME': '/Users/cltbld', 17:16:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:02 INFO - 'LOGNAME': 'cltbld', 17:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:02 INFO - 'MOZ_NO_REMOTE': '1', 17:16:02 INFO - 'NO_EM_RESTART': '1', 17:16:02 INFO - 'PAGER': '/bin/cat', 17:16:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:02 INFO - 'PWD': '/builds/slave/test', 17:16:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:02 INFO - 'SHELL': '/bin/bash', 17:16:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:16:02 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:16:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:02 INFO - 'USER': 'cltbld', 17:16:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:02 INFO - 'XPC_FLAGS': '0x0', 17:16:02 INFO - 'XPC_SERVICE_NAME': '0', 17:16:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:16:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:16:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:16:05 INFO - Installing collected packages: mozsystemmonitor 17:16:05 INFO - Running setup.py install for mozsystemmonitor 17:16:05 INFO - Successfully installed mozsystemmonitor 17:16:05 INFO - Cleaning up... 17:16:06 INFO - Return code: 0 17:16:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:16:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:16:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:16:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:06 INFO - 'HOME': '/Users/cltbld', 17:16:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:06 INFO - 'LOGNAME': 'cltbld', 17:16:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:06 INFO - 'MOZ_NO_REMOTE': '1', 17:16:06 INFO - 'NO_EM_RESTART': '1', 17:16:06 INFO - 'PAGER': '/bin/cat', 17:16:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:06 INFO - 'PWD': '/builds/slave/test', 17:16:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:06 INFO - 'SHELL': '/bin/bash', 17:16:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:16:06 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:16:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:06 INFO - 'USER': 'cltbld', 17:16:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:06 INFO - 'XPC_FLAGS': '0x0', 17:16:06 INFO - 'XPC_SERVICE_NAME': '0', 17:16:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:06 INFO - Downloading/unpacking jsonschema==2.5.1 17:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:16:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:16:08 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 17:16:08 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 17:16:08 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 17:16:08 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 17:16:08 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 17:16:08 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 17:16:09 INFO - Downloading functools32-3.2.3-2.tar.gz 17:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:16:09 INFO - warning: no files found matching '*.txt' 17:16:09 INFO - no previously-included directories found matching 'build' 17:16:09 INFO - no previously-included directories found matching 'dist' 17:16:09 INFO - no previously-included directories found matching '.git*' 17:16:09 INFO - Installing collected packages: jsonschema, functools32 17:16:09 INFO - Running setup.py install for functools32 17:16:09 INFO - warning: no files found matching '*.txt' 17:16:09 INFO - no previously-included directories found matching 'build' 17:16:09 INFO - no previously-included directories found matching 'dist' 17:16:09 INFO - no previously-included directories found matching '.git*' 17:16:09 INFO - Successfully installed jsonschema functools32 17:16:09 INFO - Cleaning up... 17:16:09 INFO - Return code: 0 17:16:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:16:09 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')]} 17:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:09 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')]} 17:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:09 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 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:16:09 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 17:16:09 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 17:16:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:16:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:09 INFO - 'HOME': '/Users/cltbld', 17:16:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:09 INFO - 'LOGNAME': 'cltbld', 17:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:09 INFO - 'MOZ_NO_REMOTE': '1', 17:16:09 INFO - 'NO_EM_RESTART': '1', 17:16:09 INFO - 'PAGER': '/bin/cat', 17:16:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:09 INFO - 'PWD': '/builds/slave/test', 17:16:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:09 INFO - 'SHELL': '/bin/bash', 17:16:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:16:09 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:16:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:09 INFO - 'USER': 'cltbld', 17:16:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:09 INFO - 'XPC_FLAGS': '0x0', 17:16:09 INFO - 'XPC_SERVICE_NAME': '0', 17:16:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:16:09 INFO - Cleaning up... 17:16:10 INFO - Return code: 0 17:16:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:10 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 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:16:10 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 17:16:10 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 17:16:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:16:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:10 INFO - 'HOME': '/Users/cltbld', 17:16:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:10 INFO - 'LOGNAME': 'cltbld', 17:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:10 INFO - 'MOZ_NO_REMOTE': '1', 17:16:10 INFO - 'NO_EM_RESTART': '1', 17:16:10 INFO - 'PAGER': '/bin/cat', 17:16:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:10 INFO - 'PWD': '/builds/slave/test', 17:16:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:10 INFO - 'SHELL': '/bin/bash', 17:16:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:16:10 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:16:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:10 INFO - 'USER': 'cltbld', 17:16:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:10 INFO - 'XPC_FLAGS': '0x0', 17:16:10 INFO - 'XPC_SERVICE_NAME': '0', 17:16:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:10 INFO - Downloading/unpacking blobuploader==1.2.4 17:16:10 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 17:16:10 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 17:16:10 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 17:16:10 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 17:16:10 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 17:16:10 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 17:16:12 INFO - Downloading blobuploader-1.2.4.tar.gz 17:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:16:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:16:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16: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 17:16:14 INFO - Downloading docopt-0.6.1.tar.gz 17:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:16:14 INFO - Installing collected packages: blobuploader, requests, docopt 17:16:14 INFO - Running setup.py install for blobuploader 17:16:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:16:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:16:14 INFO - Running setup.py install for requests 17:16:14 INFO - Running setup.py install for docopt 17:16:14 INFO - Successfully installed blobuploader requests docopt 17:16:14 INFO - Cleaning up... 17:16:15 INFO - Return code: 0 17:16:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:15 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 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:16:15 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 17:16:15 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 17:16:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:16:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:15 INFO - 'HOME': '/Users/cltbld', 17:16:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:15 INFO - 'LOGNAME': 'cltbld', 17:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:15 INFO - 'MOZ_NO_REMOTE': '1', 17:16:15 INFO - 'NO_EM_RESTART': '1', 17:16:15 INFO - 'PAGER': '/bin/cat', 17:16:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:15 INFO - 'PWD': '/builds/slave/test', 17:16:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:15 INFO - 'SHELL': '/bin/bash', 17:16:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:16:15 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:16:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:15 INFO - 'USER': 'cltbld', 17:16:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:15 INFO - 'XPC_FLAGS': '0x0', 17:16:15 INFO - 'XPC_SERVICE_NAME': '0', 17:16:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:15 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-u_PVqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:15 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-hJSGZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:15 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-MLI05p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:15 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-rPcfSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:15 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-tu_7OB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:15 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-6g_lVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:15 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-uXUqFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-toq5wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-9M6dVw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-6zgm7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-bcmXMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-d0eHDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-o4i31W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-dOcvlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-76qlot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ekzcnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:16 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-n_uOLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:16:17 INFO - Running setup.py install for manifestparser 17:16:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for mozcrash 17:16:17 INFO - Running setup.py install for mozdebug 17:16:17 INFO - Running setup.py install for mozdevice 17:16:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for mozfile 17:16:17 INFO - Running setup.py install for mozhttpd 17:16:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:16:17 INFO - Running setup.py install for mozinfo 17:16:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Running setup.py install for mozInstall 17:16:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Running setup.py install for mozleak 17:16:18 INFO - Running setup.py install for mozlog 17:16:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Running setup.py install for moznetwork 17:16:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Running setup.py install for mozprocess 17:16:18 INFO - Running setup.py install for mozprofile 17:16:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:16:18 INFO - Running setup.py install for mozrunner 17:16:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:16:19 INFO - Running setup.py install for mozscreenshot 17:16:19 INFO - Running setup.py install for moztest 17:16:19 INFO - Running setup.py install for mozversion 17:16:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:16:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:16:19 INFO - Cleaning up... 17:16:19 INFO - Return code: 0 17:16:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16:19 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')]} 17:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:19 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')]} 17:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:19 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 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:16:19 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 17:16:19 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 17:16:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:16:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:19 INFO - 'HOME': '/Users/cltbld', 17:16:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:19 INFO - 'LOGNAME': 'cltbld', 17:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:19 INFO - 'MOZ_NO_REMOTE': '1', 17:16:19 INFO - 'NO_EM_RESTART': '1', 17:16:19 INFO - 'PAGER': '/bin/cat', 17:16:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:19 INFO - 'PWD': '/builds/slave/test', 17:16:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:19 INFO - 'SHELL': '/bin/bash', 17:16:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:16:19 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:16:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:19 INFO - 'USER': 'cltbld', 17:16:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:19 INFO - 'XPC_FLAGS': '0x0', 17:16:19 INFO - 'XPC_SERVICE_NAME': '0', 17:16:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:16:19 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-fSqocq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:16:20 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-vOr7zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-6QlWS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:16:20 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-44zvcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-6ERDu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:16:20 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ujdoIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:16:20 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-m_HVn1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:16:20 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-LwXUaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:16:20 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-_v9zHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:16:20 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-1pyRQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:16:20 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)) 17:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:16:20 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-Ss1ujD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:16:21 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)) 17:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:16:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-ks3Lks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 17:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:16:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-9oI0g9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:16:21 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)) 17:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:16:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-pl8rfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 17:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-P3PW1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:16:21 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)) 17:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:16:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-lOv3VI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 17:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:16:21 INFO - Running setup.py (path:/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/pip-OFIBTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:16:21 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)) 17:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:16:21 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)) 17:16:21 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:16:21 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:16:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:16:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:24 INFO - Downloading blessings-1.6.tar.gz 17:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:16:24 INFO - Installing collected packages: blessings 17:16:24 INFO - Running setup.py install for blessings 17:16:24 INFO - Successfully installed blessings 17:16:24 INFO - Cleaning up... 17:16:24 INFO - Return code: 0 17:16:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:24 INFO - Reading from file tmpfile_stdout 17:16:24 INFO - Current package versions: 17:16:24 INFO - blessings == 1.6 17:16:24 INFO - blobuploader == 1.2.4 17:16:24 INFO - docopt == 0.6.1 17:16:24 INFO - functools32 == 3.2.3-2 17:16:24 INFO - jsonschema == 2.5.1 17:16:24 INFO - manifestparser == 1.1 17:16:24 INFO - mozInstall == 1.12 17:16:24 INFO - mozcrash == 1.0 17:16:24 INFO - mozdebug == 0.1 17:16:24 INFO - mozdevice == 0.50 17:16:24 INFO - mozfile == 1.2 17:16:24 INFO - mozhttpd == 0.7 17:16:24 INFO - mozinfo == 0.9 17:16:24 INFO - mozleak == 0.1 17:16:24 INFO - mozlog == 3.4 17:16:24 INFO - moznetwork == 0.27 17:16:24 INFO - mozprocess == 0.25 17:16:24 INFO - mozprofile == 0.28 17:16:24 INFO - mozrunner == 6.13 17:16:24 INFO - mozscreenshot == 0.1 17:16:24 INFO - mozsystemmonitor == 0.3 17:16:24 INFO - moztest == 0.8 17:16:24 INFO - mozversion == 1.4 17:16:24 INFO - psutil == 3.1.1 17:16:24 INFO - requests == 1.2.3 17:16:24 INFO - wsgiref == 0.1.2 17:16:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:16: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')]} 17:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16: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')]} 17:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:24 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 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:16:24 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 17:16:24 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 17:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:24 INFO - 'HOME': '/Users/cltbld', 17:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:24 INFO - 'LOGNAME': 'cltbld', 17:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:24 INFO - 'MOZ_NO_REMOTE': '1', 17:16:24 INFO - 'NO_EM_RESTART': '1', 17:16:24 INFO - 'PAGER': '/bin/cat', 17:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:24 INFO - 'PWD': '/builds/slave/test', 17:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:24 INFO - 'SHELL': '/bin/bash', 17:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:16:24 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:24 INFO - 'USER': 'cltbld', 17:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:24 INFO - 'XPC_FLAGS': '0x0', 17:16:24 INFO - 'XPC_SERVICE_NAME': '0', 17:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:25 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)) 17:16:25 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)) 17:16:25 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)) 17:16:25 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)) 17:16:25 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)) 17:16:25 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)) 17:16:25 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)) 17:16:25 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)) 17:16:25 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)) 17:16:25 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)) 17:16:25 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)) 17:16:25 INFO - Cleaning up... 17:16:25 INFO - Return code: 0 17:16:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d42fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d432768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb8095a1a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, '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.LyAhhoR1J5/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.wTHx733P2M/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/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 17:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:25 INFO - 'HOME': '/Users/cltbld', 17:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:25 INFO - 'LOGNAME': 'cltbld', 17:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:25 INFO - 'MOZ_NO_REMOTE': '1', 17:16:25 INFO - 'NO_EM_RESTART': '1', 17:16:25 INFO - 'PAGER': '/bin/cat', 17:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:25 INFO - 'PWD': '/builds/slave/test', 17:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:25 INFO - 'SHELL': '/bin/bash', 17:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:16:25 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:25 INFO - 'USER': 'cltbld', 17:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:25 INFO - 'XPC_FLAGS': '0x0', 17:16:25 INFO - 'XPC_SERVICE_NAME': '0', 17:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:16:25 INFO - Cleaning up... 17:16:25 INFO - Return code: 0 17:16:25 INFO - Running post-action listener: _resource_record_post_action 17:16:25 INFO - Running post-action listener: _start_resource_monitoring 17:16:25 INFO - Starting resource monitoring. 17:16:25 INFO - [mozharness: 2017-04-13 00:16:25.545655Z] Finished create-virtualenv step (success) 17:16:25 INFO - [mozharness: 2017-04-13 00:16:25.546595Z] Running install step. 17:16:25 INFO - Running pre-action listener: _resource_record_pre_action 17:16:25 INFO - Running main action method: install 17:16:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:16:25 INFO - Reading from file tmpfile_stdout 17:16:25 INFO - Detecting whether we're running mozinstall >=1.0... 17:16:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:16:25 INFO - Reading from file tmpfile_stdout 17:16:25 INFO - Output received: 17:16:25 INFO - Usage: mozinstall [options] installer 17:16:25 INFO - Options: 17:16:25 INFO - -h, --help show this help message and exit 17:16:25 INFO - -d DEST, --destination=DEST 17:16:25 INFO - Directory to install application into. [default: 17:16:25 INFO - "/builds/slave/test"] 17:16:25 INFO - --app=APP Application being installed. [default: firefox] 17:16:25 INFO - mkdir: /builds/slave/test/build/application 17:16:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:16:45 INFO - Reading from file tmpfile_stdout 17:16:45 INFO - Output received: 17:16:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:16:45 INFO - Running post-action listener: _resource_record_post_action 17:16:45 INFO - [mozharness: 2017-04-13 00:16:45.864854Z] Finished install step (success) 17:16:45 INFO - [mozharness: 2017-04-13 00:16:45.865007Z] Running run-tests step. 17:16:45 INFO - Running pre-action listener: _resource_record_pre_action 17:16:45 INFO - Running main action method: run_tests 17:16:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:16:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:16:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:16:45 INFO - 2017-04-12 17:16:45.879 screenresolution[1624:7389] starting screenresolution argv=screenresolution get 17:16:45 INFO - 2017-04-12 17:16:45.882 screenresolution[1624:7389] Display 0: 1600x1200x32@60 17:16:45 INFO - 2017-04-12 17:16:45.888 screenresolution[1625:7391] starting screenresolution argv=screenresolution list 17:16:45 INFO - Available Modes on Display 0 17:16:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:16:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:16:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:16:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:16:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:16:46 INFO - Intel Iris: 17:16:46 INFO - Chipset Model: Intel Iris 17:16:46 INFO - Type: GPU 17:16:46 INFO - Bus: Built-In 17:16:46 INFO - VRAM (Dynamic, Max): 1536 MB 17:16:46 INFO - Vendor: Intel (0x8086) 17:16:46 INFO - Device ID: 0x0a2e 17:16:46 INFO - Revision ID: 0x0009 17:16:46 INFO - Displays: 17:16:46 INFO - Display: 17:16:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:16:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:16:46 INFO - Display Serial Number: 1600x1200 60 17:16:46 INFO - Main Display: Yes 17:16:46 INFO - Mirror: Off 17:16:46 INFO - Online: Yes 17:16:46 INFO - Rotation: Supported 17:16:46 INFO - Return code: 0 17:16:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:16:46 INFO - Python 2.7.3 17:16:46 INFO - Return code: 0 17:16:46 INFO - grabbing minidump binary from tooltool 17:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:16:46 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 0x10d20dd30>, '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 0x10d134db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d202be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:16:46 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 17:16:46 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 17:16:46 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 17:16:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:16:46 INFO - Return code: 0 17:16:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:16:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:16:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:16:46 INFO - ENV: RUST_BACKTRACE is now 1 17:16:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:16:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:16:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip', '--title', 't-yosemite-r7-0143', '--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 17:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip --title t-yosemite-r7-0143 --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 17:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LyAhhoR1J5/Render', 17:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:46 INFO - 'HOME': '/Users/cltbld', 17:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:46 INFO - 'LOGNAME': 'cltbld', 17:16:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:16:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:46 INFO - 'MOZ_NO_REMOTE': '1', 17:16:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:16:46 INFO - 'NO_EM_RESTART': '1', 17:16:46 INFO - 'PAGER': '/bin/cat', 17:16:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:46 INFO - 'PWD': '/builds/slave/test', 17:16:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:46 INFO - 'RUST_BACKTRACE': '1', 17:16:46 INFO - 'SHELL': '/bin/bash', 17:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wTHx733P2M/Listeners', 17:16:46 INFO - 'TMPDIR': '/var/folders/8l/2lld364j46g___08n54mb94h00000w/T/', 17:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:46 INFO - 'USER': 'cltbld', 17:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:46 INFO - 'XPC_FLAGS': '0x0', 17:16:46 INFO - 'XPC_SERVICE_NAME': '0', 17:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip', '--title', 't-yosemite-r7-0143', '--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 17:16:46 INFO - mozversion application_buildid: 20170412155613 17:16:46 INFO - mozversion application_changeset: c7159022ffd95b4faa3a19a1796bc782312bc045 17:16:46 INFO - mozversion application_display_name: Nightly 17:16:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:16:46 INFO - mozversion application_name: Firefox 17:16:46 INFO - mozversion application_remotingname: firefox 17:16:46 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 17:16:46 INFO - mozversion application_vendor: Mozilla 17:16:46 INFO - mozversion application_version: 55.0a1 17:16:46 INFO - mozversion platform_buildid: 20170412155613 17:16:46 INFO - mozversion platform_changeset: c7159022ffd95b4faa3a19a1796bc782312bc045 17:16:46 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 17:16:46 INFO - mozversion platform_version: 55.0a1 17:16:46 INFO - using testdate: 1492042606 17:16:46 INFO - actual date: 1492042606 17:16:46 INFO - starting webserver on 'localhost:49238' 17:16:46 INFO - SUITE-START | Running 2 tests 17:16:46 INFO - TEST-START | dromaeo_css 17:16:46 INFO - operating with platform_type : mac_ 17:16:46 INFO - Initialising browser for dromaeo_css test... 17:16:46 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmp4pyG30/profile http://localhost:49238/getInfo.html) 17:16:49 INFO - PID 1635 | 2017-04-12 17:16:49.719 plugin-container[1637:7559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 17:16:49 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:16:49 INFO - PID 1635 | 2017-04-12 17:16:49.722 plugin-container[1637:7559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 17:16:49 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:16:49 INFO - PID 1635 | 2017-04-12 17:16:49.722 plugin-container[1637:7559] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:16:49 INFO - PID 1635 | __metrics Screen width/height:1600/1200 17:16:49 INFO - PID 1635 | colorDepth:24 17:16:49 INFO - PID 1635 | Browser inner width/height: 1024/689 17:16:49 INFO - PID 1635 | __metrics 17:16:50 ERROR - PID 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 17:16:50 INFO - TEST-INFO | 1635: exit 0 17:16:50 INFO - Browser initialized. 17:16:50 INFO - Running cycle 1/1 for dromaeo_css test... 17:16:50 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmp4pyG30/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:16:53 INFO - PID 1638 | 2017-04-12 17:16:53.775 plugin-container[1639:7698] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 17:16:53 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:16:53 INFO - PID 1638 | 2017-04-12 17:16:53.776 plugin-container[1639:7698] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 17:16:53 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:16:53 INFO - PID 1638 | 2017-04-12 17:16:53.776 plugin-container[1639:7698] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:17:03 INFO - PID 1638 | RSS: Main: 235741184 17:17:03 INFO - PID 1638 | 17:20:07 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49238/tests/dromaeo/cssquery-ext.html) 17:20:09 INFO - PID 1638 | RSS: Main: 253857792 17:20:09 INFO - PID 1638 | 17:21:51 INFO - PID 1638 | ************************* 17:21:51 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 17:21:51 INFO - PID 1638 | 17:21:51 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:21:51 INFO - PID 1638 | Full stack: 17:21:51 INFO - PID 1638 | ************************* 17:21:51 INFO - PID 1638 | ************************* 17:21:51 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 17:21:51 INFO - PID 1638 | 17:21:51 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:21:51 INFO - PID 1638 | Full stack: 17:21:51 INFO - PID 1638 | ************************* 17:21:51 INFO - PID 1638 | ************************* 17:21:51 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 17:21:51 INFO - PID 1638 | 17:21:51 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:21:51 INFO - PID 1638 | Full stack: 17:21:51 INFO - PID 1638 | ************************* 17:21:51 INFO - PID 1638 | ************************* 17:21:51 INFO - PID 1638 | A coding exception was thrown and uncaught in a Task. 17:21:51 INFO - PID 1638 | 17:21:51 ERROR - PID 1638 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:21:51 INFO - PID 1638 | Full stack: 17:21:51 INFO - PID 1638 | ************************* 17:23:11 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-ext.html (next: http://localhost:49238/tests/dromaeo/cssquery-jquery.html) 17:23:11 INFO - PID 1638 | RSS: Main: 261140480 17:23:11 INFO - PID 1638 | 17:26:13 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49238/tests/dromaeo/cssquery-mootools.html) 17:26:14 INFO - PID 1638 | RSS: Main: 273928192 17:26:14 INFO - PID 1638 | 17:29:16 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49238/tests/dromaeo/cssquery-prototype.html) 17:29:16 INFO - PID 1638 | RSS: Main: 266211328 17:29:16 INFO - PID 1638 | 17:32:19 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49238/tests/dromaeo/cssquery-yui.html) 17:32:20 INFO - PID 1638 | RSS: Main: 276557824 17:32:20 INFO - PID 1638 | 17:35:21 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49238/tests/dromaeo/cssquery-yui.html (next: http://localhost:49238/tests/dromaeo/cssquery-dojo.html) 17:35:22 INFO - PID 1638 | RSS: Main: 270241792 17:35:22 INFO - PID 1638 | 17:35:22 INFO - PID 1638 | __start_tp_report 17:35:22 INFO - PID 1638 | _x_x_mozilla_page_load 17:35:22 INFO - PID 1638 | _x_x_mozilla_page_load_details 17:35:22 INFO - PID 1638 | |i|pagename|runs| 17:35:22 INFO - PID 1638 | |0;dojo.html;3367.040393487856;3586.892393228204;3596.694280986116;3619.2580520993197;3626.4922910792493;5263.6052296077805;6194.7212375443105;6234.251889773228;6304.432601065903;6353.3646635336445;6161.291451483081;6253.906191407121;6258.62448253104;6300.960341543651;6323.873522529547;5467.753951072192;5512.228288039678;5539.584531160159;5586.24585680932;5634.4928956393915;5462.617616766828;5572.108462645978;5617.045102332593;5628.183913332565;5709.543236541067;4251.511076226243;5306.151015837486;5474.753636086346;5541.33503979521;5546.584006199551;5519.254900588449;5523.447655234485;5529.364123125812;5553.694546799924;5565.026120428949;5457.181422786574;5460.836174914759;5488.91766623501;5503.11950087984;5537.944620553783;4762.2380419133115;6179.351168127327;6243;6292.119103325538;6359.713812878432;6683.832904177434;6778.118844550227;6780;6783.694733736945;6802.863942721118;6886.827829304308;6899.241083480813;6903.1371078615175;6909.067275917737;6910.48171387143;6202.472789812917;6326.905096423558;6424.196975378078;6429.38920802523;6439.420452159328;6921.826954326182;6943.618101004493;6943.756968506078;6949.44404447643;6995.79012629622;5121.692698438105;6436.195475565554;6511.576747511396;6652.634105124264;6665.466762658975;6596.175478065242;6750.966245168743;6775.593464392152;6792.490563207779;6795.150606174228;6465.806025819233;6515;6728.730850766013;6730.226024007302;6747.831304217434;6585.6377899216;6663.267040625527;6762.492813039042;6768.966155169291;6780.254172041072;5374.54316383104;5383.246345511631;5386.353637563518;5423.132298832168;5431.864203394946;4765;4816.084943860656;4822.180229361017;4859.149648811514;4898.657094003457;6100.944524685232;6331.303556608769;6355.841103972345;6372.6495042773095;6419.935800641933;6883.862322753517;6884.449244060464;6892.069570608004;6899.586024838526;6900.9654951725925;6273.843153921189;6385.297617262097;6394.328595497499;6409.807705768834;6436.581622194542;5833.270841144857;5894.734736936848;5905.291365036137;5922.437368449991;5974.880502389928;6141.969290153699;6936.445084393236;6985.615791131537;6986.231514533499;6997.895031574429;6816.420604248496;6831.009503621847;6852.486063545154;6861.313868613099;6864.107666003587;6719.731210751515;6839.62382068991;6890.827729306808;6921.550099243735;6937.271586483346;5593;6653.600783952978;6717.093192419156;6718.193816742022;6732.865342693217;6338.366163383625;6590.275069742421;6857.902735562486;6872.862542749017;6879.552829066095;6479.481641468766;6527.804165875032;6624.6025238485845;6627.469802415699;6634.469242460688;6327.525435592257;6622.403983641495;6627.900581491378;6628.966855165693;6680.6993685283305;6225.346338634378;6428.646424446701;6438.6136831790245;6450.387213214737;6519.152510173647;6632.801015969529;6649.867002659823;6667.066610674412;6668.299828517936;6698.83252918681;6459.160309159779;6464.256610489854;6468;6485.675716214265;6489.578177418642 17:35:22 INFO - PID 1638 | |1;ext.html;741.7070257248387;800.683729926679;826.5123577089516;1130.5025788652997;1266.385802885601;37294.69468568601;38357.80821095894;38384.616153838455;38470.03824904372;38610.22779544407;38012.049698757546;38300.61699383005;38383.42424863622;38392.42411363832;38419;8040.075391330013;8192.795180120485;8539.402241843043;8795.912040879606;8799;8658.567071646425;8748.99386570546;8768.298536117096;8772.69295574655;8787.604557794883;8759.124087591254;8762.86855697165;8817.559122043873;8819.250363719091;8842.823143537125;8741.082186370466;8743.562821858914;8789.64841406343;8790.033096359395;8821.294296456268;8512.74461766148;8531.4454560454;8561.48631082137;8571.957140214321;8572.185642363966;37274.813625931696;37274.813625931965;37290;37488.25023499515;37496.81251593724;3610.3862343401684;3620.4931309616672;3661.3226553087766;3662.029562166028;3665.9185540265685;30619;30647.38705225883;30676;30682.386352272828;30721.692783072107;2708.2281549758422;2967.3620171663197;3167.524871269305;3182.411253918033;3182.742816587435;201140.99999999854;222776;227474;255520;255969;40121.39817902734;50192.24711629256;50263.24605130926;50273.24590131151;50304.74847625739;53055.73472132692;53073;53138.46861531412;53193;53453.732731336095;14663.706725865422;14683.339249733788;14805.555833325017;14825.851741482662;14846.257687115602;9507.619695212114;12083.45624446902;12117.515299387926;12200.389980500939;12222.572209972608;11952.701182470508;12543.749125017448;13875.236861972688;13875.306234688225;13879.375428105759;5129.333186685707;6039;6073.787417440369;6122.632642041492;6148.200733904564;896.7489103051156;998.8801343838641;1024.4263212600968;1032.210359075308;1065.3607835298758;20711.792882071288;23717.16989905345;24171.879140604186;24289.514209715708;24506;1046.842973553973;1132.841402203692;1135.3188087147646;1169.152364535705;1179.2924245452832;11927.940360298144;12399.318037508023;12426.254424734545;12460.376981150906;12462.376881155906;347638;349565;349991.2500437482;350416;351632;53920.73039634777;54349.45650543523;54395.72802135964;54478;54636.90726185454;1500.407339101049;1549.566121485963;1558.3922270314586;1567.1537369820169;1573.968520629604;24684;29309.85345073261;29427.55858662079;30132.397352052398;30286;38960.41559376555;51883.74058129685;52370;52630;52667.209991849384;306180;326395;327566;328043;328068;35682.10794730153;35778.64221357753;36288.4556731644;36369.636303637686;36405.08987275339;44684.55315446804;45443.772781135885;45602.77198614118;45647.54352456433;45678.54321456743;30131.397372052874;30722;31132.844335778176;31316.53025204578;31567.684323156474;29467.673954671664;30488.695113049474;30792;31401.685983139876;31656.36687266288;36540.26919461649;37106;37396.06509837276;37426.81286593658;37502.62497374992;29855.253618659706;30354.84822575873;30375.240618984702;31471.84264078665;31842.885499007425;23507.529849402574;24204.15285465;24408.145714899892;24531.38671533226;24695.62956555689 17:35:22 INFO - PID 1638 | |2;jquery.html;396.46675221826644;423.2170484603482;430.3286872478935;440.8501109622729;450.51119535304196;16173.676526469464;18202.08989550522;19198.424047278568;21267.787322126773;21411;19811.108500117494;21185.894070529663;21577.568448631057;22385.888070559624;22426;6030.185924900126;6340.429361357477;6347.746090156411;6358.332375100617;6365.52258580606;5855.79504717335;6060.303065147498;6210.161628180205;6263.373662633746;6283.717232724538;6207.844803879894;6220.813375598739;6225.906611400833;6227.252729672446;6265.310815810257;6074.665893375863;6146.938530614703;6189.473894718954;6246.187995560571;6249.250089989185;4412.183746006999;5583.218349431083;5595.356533998602;5605.467480589337;5623.0721930881355;20757.792422075738;25773.484530309477;25981.22056338313;26458.470830583472;26548;2259.051198496635;2269.6595510673365;2284.497410569672;2305.6656784766283;2309.0719249426747;16016.279267432994;16500;17208.483745487654;17995.910020449814;18077.734558580905;2126.447123747821;2170.945726356854;2219;2219.6781466687403;2223.132978138527;143415.28292358576;145208;145582;145652.2717386417;146602.26698866545;10910.181736369805;11708.941455292668;12694.301813400352;12751.553695620794;12922.095453318365;16308;16419.83580164183;16445.506634800975;16448.83551164473;16500.75248871268;7571.46999710026;8972;9208.401453905608;9227.815443691223;9241.844769403824;8979.742836002964;8981.281497480186;8982.101789820965;9151.679691210775;9170;7817.960910195527;7938.960305198437;7982.880256796153;7983.920160798434;7993.880091798627;1888.7261347104593;2187.1688758271753;2189.700814145141;2205.38249290199;2383.1778036577352;927.2674587076269;965.3435663748718;967.2842096848282;969.743018100204;971.8532132084067;16559.6688066238;16738.414155504743;19876.60246795056;20659.793402066087;21068.894655526932;900.7162743735702;935.6631612619451;966.9419834809934;992.3103443106971;1025.092792878308;10625.521851516545;10929.726756830984;12763.617091487271;13517.459301628021;13663.043586948817;674905;683135.5843220851;684728;689532;690979;122541;129305.35347323392;161973;162752.1862390704;164264;1176.1590462819127;1199.8500187476566;1242.9627111186498;1244.2721008210117;1247.8065899785547;15715.292811823267;16601.833981660027;18603.813961860207;19593.216271348985;20431.8978405107;57330;64695;65685;66681.33318666751;67040.3295967034;77919;81333.59333203296;85200.57399712961;85803.14196857951;86218;8017.358611311198;8425.915740842513;8805.295576354005;9029.864552031593;9055.547222638974;13467.191968481922;13827.86172138306;13885;14056.578302650936;14104.294785260901;11755.941220293844;11790.292582445081;11983.880161198276;12002.63992080239;12005.879941200476;9846.966068562699;10501.9474902625;11248.887511124783;11383.658490245305;11450.541978320774;11663.76672466563;11855.58505452305;12093.213941093802;12163.270203787448;12262.325572093621;8711.21599056088;8745.038045815083;8925.776855578662;9005.729828105168;9045.77385565366;11713.765724685629;11963.70538953111;12070.879291207328;12126;12226.510939562319 17:35:22 INFO - PID 1638 | |3;mootools.html;1359.1369480379958;1398.9860101398983;1409.5278081842582;1410.8572056634127;1435.454527279634;11553.133514986379;11719.179657423962;11983.340916249614;12013.159078864483;12045.51817927284;10529.841717411078;11057.447127643605;11542.36516991565;11844.348560829172;11848.703782405444;2465.790407815338;2515.1071369663687;2515.610080437535;2518.26970178648;2518.672572565573;2435.4520232947534;2449.7917676997477;2484.291976786616;2542.224621490445;2543.4913017396593;2527.519771243467;2553.0298486575075;2554.8339357941672;2558.1685952065654;2560.590305551112;2452.4236804350962;2473.5547601431736;2488.8631125288102;2489.8132640052067;2500.574902266619;2447.730749617541;2460.1758410932357;2464.6303054541963;2465.272744540347;2467.5188338274043;11769.411529423494;11850.644480665507;12014.939925300405;12024.639260822189;12027.158098933078;6629.93370066298;6822.215445223764;6822.976553517012;6829.760958366433;6857.348551887562;4006.419069234974;4151.564085771011;4217.810198541073;4246.5541118182455;4309.978450107761;1208.196549294718;1233.6360773571728;1247.7192631657895;1253.931033793141;1258.087886282447;14937.17845518496;16618.25217865199;16661.750073748903;16666.333346666117;16710.832891671173;5310.601704872072;5453.563714902798;5683.232763576864;5746.367899531048;5796.594238403356;11789.056875449944;11862.881371186179;11901.642950711492;11905.464254108585;11912.404379780975;6611.33886611335;6613.437857782143;6655.667216639245;6658.933410665832;6672.666366681647;5283.418823929364;5998.160257563857;6041;6094.360092190344;6199.721012554446;4416.33754936762;4487.394201782783;4514.503404625489;4519;4562.794674239667;1185.733210027737;1215.6535387414544;1281.3465132782349;1292.5088466382902;1313.7109835836102;591.2638764737928;674.0251064381409;726.5858460677468;744.5272252119835;750.107372227048;5481.643693159932;5723.6851973141875;5758.769649213985;5816.1566572846705;5822.359540450547;722.692855536395;881.9647214111363;886.5257087458178;895.1451363947415;896.8027034052498;3434.5363375944426;3466.2374277658873;3485.9477107843404;3534.346145962954;3534.699550538233;14519.34662940149;15155.848441515443;15395.153266570445;15520.611984700472;15529.223538823013;15263;15327.386904523917;15442.691146177014;15466.458673946358;15517.224138793015;11050.281731687415;11107.278026928223;11143.832842507369;11245.550177992789;11272.718182045515;6606.636634985122;6754.763583274562;6793;6841.418479429304;6962.3037696231995;13615.863841361856;13650;13683.384247708678;13893.235872027135;13920.72158556803;10312.793744124925;12872.871271287542;13960.162390256617;14063.156210627396;14110.788338175142;8435.072142064246;8626.78433039179;8650.745641882018;8659.610317535851;8668.739937801876;10874.130069594572;10914.781704366027;10985.890141098806;10987.505562249558;11018.11855051578;8893.73318800437;8907.421017633833;8923.464592124494;8986.550672466481;9000.144986226298;9026.864597030919;9116.954415227881;9145.63417463294;9168.44989300644;9169.083091690778;8087.919120808952;8195.34437245007;8247.793805154679;8282.627281772215;8285.461445006054;5928.13263821912;5950.583459157818;6048.425399587032;6129.693515324127;6174.907376389448;5938.940610593839;6045.395460454095;6118.908216376669;6140.478059365004;6152.39466693157 17:35:22 INFO - PID 1638 | |4;prototype.html;383.7237189223758;394.38869751884573;404.90687141957346;411.1673860432622;412.9545749967504;5860.355360910296;6035.849103772408;6127.988881834497;6279.591826531284;6296.559240853142;5970.223870896777;6170.382961703827;6184.176342452894;6184.845378865531;6190.1643278157435;4730.053989202156;4734.147853386389;4738.834140805073;4746.881327966811;4772.80908763649;4628.375169352144;4733.408323959505;4743.1225223333795;4744.501827308135;4752.5247475252545;4256.744595324275;4261.169072030955;4680.882977925545;4691.624670026406;4720.150372932871;3714.8142592870518;4010.8769544527577;4539.751568318706;4559.612432943204;4699.979009026118;3584.982075089608;3812.218495208476;4085.346344584852;4127.669786417079;4181.247375472413;5288.338957630296;5900.675462849542;5964.821055368346;6024.397560243984;6048.546359023091;5635.098384258499;5636.436356364372;5642.887142257132;5653.886922261532;5659.887062115861;6264.9686751566405;6545.869082618321;6640.535162538624;6690.13028306317;6732.394084532416;4059.878203653895;4347.152305300468;4410.2282100632265;4739.573438390561;4813.566778989873;5456.672599644034;6201.906971395432;6820.624865632438;6963.40811031063;7089.291070892921;4840.419149701988;4862.41651001882;4868.342773725572;4868.853934381974;4879.39007624047;4960.553550180501;4965.602751779849;4971.254311853251;4978.377702787152;4996.550310472075;5130.692158470503;5352.946470535245;5509.5041446270025;5563.833085007456;5563.916541251884;6535.738570457129;6591.373819487141;6608.801735947929;6616.603003819786;6616.669166541654;1622.1240530113841;1658.8507034366964;1702.3105642214923;1705.1474262868567;1706.0701917455017;1080.6811990462832;1124.145649306533;1169.6724917023248;1189.6550000499758;1192.7197108679454;6234.9688251558455;6237.754408412968;6239.532035097386;6259.436650701458;6272.466840318532;5058.204337460223;6455.9677201613695;6496.805095847132;6759.966200168968;6867.690953907087;6232.439080482778;6265.843353916189;6304.527160462983;6308.003698243389;6318.303792281475;2949.73452389286;3531.9646803531637;3574.857005719794;3583.211693427442;3590;5353.4646535346155;6659.901116315754;6704.530682852153;7028.648567571703;7083.114610673666;6391.749486591365;6746.099177353785;6993.580385176906;7097.680604372712;7155.964220178866;5758.395368486333;6050.757969681164;6186.350433204449;7050.682719277543;7076.787696369117;6746.662666866735;6850.486213533905;6878.38094571471;6900.482463815334;6913.654317284217;5852.473277405054;6706.765263215764;6791.796246112624;6807.319268073153;6816.079829222991;6745.763898263537;6815.454763618998;7091.60996145217;7095.9645201773665;7110.804625271115;4413.7793110345;4460;4461.26010855773;4476.350929115195;4487.169873573399;6802.6258555779905;6859.5541289816;6867.896981545181;6909.585424874524;6916.066331045446;5085.92371114441;6331.398517140864;6466.191726034246;6657.600543967377;6663.800085997428;6265.086734668401;6587.80236592903;6593.80218593443;6629.834254143492;6630.248446894485;6249.593776404519;6385.936140638534;6460;6476.31998640136;6487.318831522811;5150.690958542499;6518.608883467007;6620.3710647488415;6622.4702023838945;6623.834404139742;6124.938750612437;6200.81397558074;6461.644609546332;6468.223813142265;6496.415322620986 17:35:22 INFO - PID 1638 | |5;yui.html;746.8692978728722;837.2339309531778;848.6393282854785;877.4077497689058;877.7235170921159;14478.638034049154;15216.467423640148;16481.011139331633;16559.503214903543;16825.83174168258;13969.79045314321;16499.83500164998;17131.571710707238;17254.56863578411;17483.300667973297;962.8122516109344;987.3532835992437;990.5740531571421;991.4002028772597;996.5565323431076;1009.5154325923561;1012.7012531303283;1012.9566546457161;1014.6803756816616;1017.1354348803532;1055.3034996902047;1065.0893486069415;1069.1446842525947;1069.8074346617645;1069.9304545204575;1062.3360399750156;1062.4103622489515;1062.718379629395;1062.946852657364;1063.500154927187;775.3642013548892;787.5156778581188;788;788.1487992967582;789.9888143176743;13108.147970381895;16811.243494042796;17018.404355847484;17230.483085507454;17251.827481725148;16349;16998.490045298662;17019.48941531756;17061.48815535536;17109.657806843792;13483.86516134836;13814.240216788072;13972.650683732887;13982.510612128628;13996.580102596836;3352.916177095568;3369.831508424569;3380.222548813762;3393.3043726036103;3407.522946787452;113828;114507;114704;116741;117341;10998.725031874106;11434.371109589052;12247.755044899051;12263.816042759367;12279.815802762965;17463.65072698539;20048.799512004985;20101.698474522895;20339.18643254283;20441.693374599392;7626.4661473697415;7863.174366691529;8497.405181637227;8856.3800533962;8857.46855188678;4808.08646357191;4892.938860745573;4909.680870743462;4929.728864912393;4936.925946110811;4243.066525364415;4321.48142222929;4331.480222373272;4343.2399330117105;4355.956440435555;874.8818909447143;908.5003248213457;911.9133682300171;913.5614904845711;914.6021480655936;608.716946619824;646.2148489585177;652.9444997175199;659.7394029358377;691.3155975584135;10832.566697332019;10927.398993055458;10948;11134;12009.759804803854;635.5963962883602;658.5850913924197;670.9906071270349;671.8118926700531;683.3132701634826;5067.606408237764;5259.263703081571;5485.9725701371235;5501.972490137524;5554.77780888768;97467;98658.04025879235;98866;99890;111013.88986110035;65668.67165664141;87857;88744.5562772195;89939.55030224807;91775.54112229396;80136;80866.19133808822;81316;81456;82027.5898620515;11952.402379881145;12248.75502489963;12305.692357691129;12326.137170398344;12351.876481235073;33545.66454335425;36857.604255723294;38928;40418.1916361677;41637.375439367825;49493.02027918987;67829.98255026278;71890.28109718836;71983;73567;16309.429169978994;16575.00549967006;16736.414225502536;17070.89039212447;17134;18275.725864111784;18467;18502.44492665222;18616.789908655894;18739.812601873808;16458.588535286715;16547.007179569264;17271;17291.740623890902;17407.129643518027;13205.801912971505;16244.02535847853;16549;16832.663346733243;16913.32346706118;15522.84477155214;15526.378944842532;15644.92177539105;15664;16313;11602.723700393119;11996.820047699466;12060.698482538006;12094.334811585095;12111.273323600612;13024.934875325942;13176.538821141214;13193.20840749558;13351.933240333736;13430 17:35:22 INFO - PID 1638 | __end_tp_report 17:35:22 INFO - PID 1638 | __start_cc_report 17:35:22 INFO - PID 1638 | _x_x_mozilla_cycle_collect,438 17:35:22 INFO - PID 1638 | __end_cc_report 17:35:22 INFO - PID 1638 | __startTimestamp1492043722197__endTimestamp 17:35:22 INFO - PID 1638 | 17:35:22 INFO - PID 1638 | ------- Summary: start ------- 17:35:22 INFO - PID 1638 | Number of tests: 6 17:35:22 INFO - PID 1638 | 17:35:22 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:6204.40 Median:6438.61 stddev:756.78 (11.8%) stddev-sans-first:725.63 17:35:22 INFO - PID 1638 | Values: 3367.0 3586.9 3596.7 3619.3 3626.5 5263.6 6194.7 6234.3 6304.4 6353.4 6161.3 6253.9 6258.6 6301.0 6323.9 5467.8 5512.2 5539.6 5586.2 5634.5 5462.6 5572.1 5617.0 5628.2 5709.5 4251.5 5306.2 5474.8 5541.3 5546.6 5519.3 5523.4 5529.4 5553.7 5565.0 5457.2 5460.8 5488.9 5503.1 5537.9 4762.2 6179.4 6243.0 6292.1 6359.7 6683.8 6778.1 6780.0 6783.7 6802.9 6886.8 6899.2 6903.1 6909.1 6910.5 6202.5 6326.9 6424.2 6429.4 6439.4 6921.8 6943.6 6943.8 6949.4 6995.8 5121.7 6436.2 6511.6 6652.6 6665.5 6596.2 6751.0 6775.6 6792.5 6795.2 6465.8 6515.0 6728.7 6730.2 6747.8 6585.6 6663.3 6762.5 6769.0 6780.3 5374.5 5383.2 5386.4 5423.1 5431.9 4765.0 4816.1 4822.2 4859.1 4898.7 6100.9 6331.3 6355.8 6372.6 6419.9 6883.9 6884.4 6892.1 6899.6 6901.0 6273.8 6385.3 6394.3 6409.8 6436.6 5833.3 5894.7 5905.3 5922.4 5974.9 6142.0 6936.4 6985.6 6986.2 6997.9 6816.4 6831.0 6852.5 6861.3 6864.1 6719.7 6839.6 6890.8 6921.6 6937.3 5593.0 6653.6 6717.1 6718.2 6732.9 6338.4 6590.3 6857.9 6872.9 6879.6 6479.5 6527.8 6624.6 6627.5 6634.5 6327.5 6622.4 6627.9 6629.0 6680.7 6225.3 6428.6 6438.6 6450.4 6519.2 6632.8 6649.9 6667.1 6668.3 6698.8 6459.2 6464.3 6468.0 6485.7 6489.6 17:35:22 INFO - PID 1638 | 17:35:22 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:45999.34 Median:19694.51 stddev:80484.55 (408.7%) stddev-sans-first:80638.60 17:35:22 INFO - PID 1638 | Values: 741.7 800.7 826.5 1130.5 1266.4 37294.7 38357.8 38384.6 38470.0 38610.2 38012.0 38300.6 38383.4 38392.4 38419.0 8040.1 8192.8 8539.4 8795.9 8799.0 8658.6 8749.0 8768.3 8772.7 8787.6 8759.1 8762.9 8817.6 8819.3 8842.8 8741.1 8743.6 8789.6 8790.0 8821.3 8512.7 8531.4 8561.5 8572.0 8572.2 37274.8 37274.8 37290.0 37488.3 37496.8 3610.4 3620.5 3661.3 3662.0 3665.9 30619.0 30647.4 30676.0 30682.4 30721.7 2708.2 2967.4 3167.5 3182.4 3182.7 201141.0 222776.0 227474.0 255520.0 255969.0 40121.4 50192.2 50263.2 50273.2 50304.7 53055.7 53073.0 53138.5 53193.0 53453.7 14663.7 14683.3 14805.6 14825.9 14846.3 9507.6 12083.5 12117.5 12200.4 12222.6 11952.7 12543.7 13875.2 13875.3 13879.4 5129.3 6039.0 6073.8 6122.6 6148.2 896.7 998.9 1024.4 1032.2 1065.4 20711.8 23717.2 24171.9 24289.5 24506.0 1046.8 1132.8 1135.3 1169.2 1179.3 11927.9 12399.3 12426.3 12460.4 12462.4 347638.0 349565.0 349991.3 350416.0 351632.0 53920.7 54349.5 54395.7 54478.0 54636.9 1500.4 1549.6 1558.4 1567.2 1574.0 24684.0 29309.9 29427.6 30132.4 30286.0 38960.4 51883.7 52370.0 52630.0 52667.2 306180.0 326395.0 327566.0 328043.0 328068.0 35682.1 35778.6 36288.5 36369.6 36405.1 44684.6 45443.8 45602.8 45647.5 45678.5 30131.4 30722.0 31132.8 31316.5 31567.7 29467.7 30488.7 30792.0 31401.7 31656.4 36540.3 37106.0 37396.1 37426.8 37502.6 29855.3 30354.8 30375.2 31471.8 31842.9 23507.5 24204.2 24408.1 24531.4 24695.6 17:35:22 INFO - PID 1638 | 17:35:22 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:39757.91 Median:2264.36 stddev:114668.87 (5064.1%) stddev-sans-first:114952.46 17:35:22 INFO - PID 1638 | Values: 396.5 423.2 430.3 440.9 450.5 16173.7 18202.1 19198.4 21267.8 21411.0 19811.1 21185.9 21577.6 22385.9 22426.0 6030.2 6340.4 6347.7 6358.3 6365.5 5855.8 6060.3 6210.2 6263.4 6283.7 6207.8 6220.8 6225.9 6227.3 6265.3 6074.7 6146.9 6189.5 6246.2 6249.3 4412.2 5583.2 5595.4 5605.5 5623.1 20757.8 25773.5 25981.2 26458.5 26548.0 2259.1 2269.7 2284.5 2305.7 2309.1 16016.3 16500.0 17208.5 17995.9 18077.7 2126.4 2170.9 2219.0 2219.7 2223.1 143415.3 145208.0 145582.0 145652.3 146602.3 10910.2 11708.9 12694.3 12751.6 12922.1 16308.0 16419.8 16445.5 16448.8 16500.8 7571.5 8972.0 9208.4 9227.8 9241.8 8979.7 8981.3 8982.1 9151.7 9170.0 7818.0 7939.0 7982.9 7983.9 7993.9 1888.7 2187.2 2189.7 2205.4 2383.2 927.3 965.3 967.3 969.7 971.9 16559.7 16738.4 19876.6 20659.8 21068.9 900.7 935.7 966.9 992.3 1025.1 10625.5 10929.7 12763.6 13517.5 13663.0 674905.0 683135.6 684728.0 689532.0 690979.0 122541.0 129305.4 161973.0 162752.2 164264.0 1176.2 1199.9 1243.0 1244.3 1247.8 15715.3 16601.8 18603.8 19593.2 20431.9 57330.0 64695.0 65685.0 66681.3 67040.3 77919.0 81333.6 85200.6 85803.1 86218.0 8017.4 8425.9 8805.3 9029.9 9055.5 13467.2 13827.9 13885.0 14056.6 14104.3 11755.9 11790.3 11983.9 12002.6 12005.9 9847.0 10501.9 11248.9 11383.7 11450.5 11663.8 11855.6 12093.2 12163.3 12262.3 8711.2 8745.0 8925.8 9005.7 9045.8 11713.8 11963.7 12070.9 12126.0 12226.5 17:35:22 INFO - PID 1638 | 17:35:22 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:7153.74 Median:2548.26 stddev:4594.12 (180.3%) stddev-sans-first:4586.37 17:35:22 INFO - PID 1638 | Values: 1359.1 1399.0 1409.5 1410.9 1435.5 11553.1 11719.2 11983.3 12013.2 12045.5 10529.8 11057.4 11542.4 11844.3 11848.7 2465.8 2515.1 2515.6 2518.3 2518.7 2435.5 2449.8 2484.3 2542.2 2543.5 2527.5 2553.0 2554.8 2558.2 2560.6 2452.4 2473.6 2488.9 2489.8 2500.6 2447.7 2460.2 2464.6 2465.3 2467.5 11769.4 11850.6 12014.9 12024.6 12027.2 6629.9 6822.2 6823.0 6829.8 6857.3 4006.4 4151.6 4217.8 4246.6 4310.0 1208.2 1233.6 1247.7 1253.9 1258.1 14937.2 16618.3 16661.8 16666.3 16710.8 5310.6 5453.6 5683.2 5746.4 5796.6 11789.1 11862.9 11901.6 11905.5 11912.4 6611.3 6613.4 6655.7 6658.9 6672.7 5283.4 5998.2 6041.0 6094.4 6199.7 4416.3 4487.4 4514.5 4519.0 4562.8 1185.7 1215.7 1281.3 1292.5 1313.7 591.3 674.0 726.6 744.5 750.1 5481.6 5723.7 5758.8 5816.2 5822.4 722.7 882.0 886.5 895.1 896.8 3434.5 3466.2 3485.9 3534.3 3534.7 14519.3 15155.8 15395.2 15520.6 15529.2 15263.0 15327.4 15442.7 15466.5 15517.2 11050.3 11107.3 11143.8 11245.6 11272.7 6606.6 6754.8 6793.0 6841.4 6962.3 13615.9 13650.0 13683.4 13893.2 13920.7 10312.8 12872.9 13960.2 14063.2 14110.8 8435.1 8626.8 8650.7 8659.6 8668.7 10874.1 10914.8 10985.9 10987.5 11018.1 8893.7 8907.4 8923.5 8986.6 9000.1 9026.9 9117.0 9145.6 9168.4 9169.1 8087.9 8195.3 8247.8 8282.6 8285.5 5928.1 5950.6 6048.4 6129.7 6174.9 5938.9 6045.4 6118.9 6140.5 6152.4 17:35:22 INFO - PID 1638 | 17:35:22 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:5400.78 Median:6087.85 stddev:1638.68 (26.9%) stddev-sans-first:1599.43 17:35:22 INFO - PID 1638 | Values: 383.7 394.4 404.9 411.2 413.0 5860.4 6035.8 6128.0 6279.6 6296.6 5970.2 6170.4 6184.2 6184.8 6190.2 4730.1 4734.1 4738.8 4746.9 4772.8 4628.4 4733.4 4743.1 4744.5 4752.5 4256.7 4261.2 4680.9 4691.6 4720.2 3714.8 4010.9 4539.8 4559.6 4700.0 3585.0 3812.2 4085.3 4127.7 4181.2 5288.3 5900.7 5964.8 6024.4 6048.5 5635.1 5636.4 5642.9 5653.9 5659.9 6265.0 6545.9 6640.5 6690.1 6732.4 4059.9 4347.2 4410.2 4739.6 4813.6 5456.7 6201.9 6820.6 6963.4 7089.3 4840.4 4862.4 4868.3 4868.9 4879.4 4960.6 4965.6 4971.3 4978.4 4996.6 5130.7 5352.9 5509.5 5563.8 5563.9 6535.7 6591.4 6608.8 6616.6 6616.7 1622.1 1658.9 1702.3 1705.1 1706.1 1080.7 1124.1 1169.7 1189.7 1192.7 6235.0 6237.8 6239.5 6259.4 6272.5 5058.2 6456.0 6496.8 6760.0 6867.7 6232.4 6265.8 6304.5 6308.0 6318.3 2949.7 3532.0 3574.9 3583.2 3590.0 5353.5 6659.9 6704.5 7028.6 7083.1 6391.7 6746.1 6993.6 7097.7 7156.0 5758.4 6050.8 6186.4 7050.7 7076.8 6746.7 6850.5 6878.4 6900.5 6913.7 5852.5 6706.8 6791.8 6807.3 6816.1 6745.8 6815.5 7091.6 7096.0 7110.8 4413.8 4460.0 4461.3 4476.4 4487.2 6802.6 6859.6 6867.9 6909.6 6916.1 5085.9 6331.4 6466.2 6657.6 6663.8 6265.1 6587.8 6593.8 6629.8 6630.2 6249.6 6385.9 6460.0 6476.3 6487.3 5150.7 6518.6 6620.4 6622.5 6623.8 6124.9 6200.8 6461.6 6468.2 6496.4 17:35:22 INFO - PID 1638 | 17:35:22 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:21266.58 Median:17473.48 stddev:29513.69 (168.9%) stddev-sans-first:29556.26 17:35:22 INFO - PID 1638 | Values: 746.9 837.2 848.6 877.4 877.7 14478.6 15216.5 16481.0 16559.5 16825.8 13969.8 16499.8 17131.6 17254.6 17483.3 962.8 987.4 990.6 991.4 996.6 1009.5 1012.7 1013.0 1014.7 1017.1 1055.3 1065.1 1069.1 1069.8 1069.9 1062.3 1062.4 1062.7 1062.9 1063.5 775.4 787.5 788.0 788.1 790.0 13108.1 16811.2 17018.4 17230.5 17251.8 16349.0 16998.5 17019.5 17061.5 17109.7 13483.9 13814.2 13972.7 13982.5 13996.6 3352.9 3369.8 3380.2 3393.3 3407.5 113828.0 114507.0 114704.0 116741.0 117341.0 10998.7 11434.4 12247.8 12263.8 12279.8 17463.7 20048.8 20101.7 20339.2 20441.7 7626.5 7863.2 8497.4 8856.4 8857.5 4808.1 4892.9 4909.7 4929.7 4936.9 4243.1 4321.5 4331.5 4343.2 4356.0 874.9 908.5 911.9 913.6 914.6 608.7 646.2 652.9 659.7 691.3 10832.6 10927.4 10948.0 11134.0 12009.8 635.6 658.6 671.0 671.8 683.3 5067.6 5259.3 5486.0 5502.0 5554.8 97467.0 98658.0 98866.0 99890.0 111013.9 65668.7 87857.0 88744.6 89939.6 91775.5 80136.0 80866.2 81316.0 81456.0 82027.6 11952.4 12248.8 12305.7 12326.1 12351.9 33545.7 36857.6 38928.0 40418.2 41637.4 49493.0 67830.0 71890.3 71983.0 73567.0 16309.4 16575.0 16736.4 17070.9 17134.0 18275.7 18467.0 18502.4 18616.8 18739.8 16458.6 16547.0 17271.0 17291.7 17407.1 13205.8 16244.0 16549.0 16832.7 16913.3 15522.8 15526.4 15644.9 15664.0 16313.0 11602.7 11996.8 12060.7 12094.3 12111.3 13024.9 13176.5 13193.2 13351.9 13430.0 17:35:22 INFO - PID 1638 | -------- Summary: end -------- 17:35:22 INFO - PID 1638 | 17:35:22 INFO - TEST-INFO | 1638: exit 0 17:35:22 INFO - TEST-OK | dromaeo_css | took 1116365ms 17:35:22 INFO - TEST-START | kraken 17:35:22 INFO - operating with platform_type : mac_ 17:35:22 INFO - Initialising browser for kraken test... 17:35:22 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpdOzjMp/profile http://localhost:49238/getInfo.html) 17:35:25 INFO - PID 1650 | 2017-04-12 17:35:25.730 plugin-container[1651:10076] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 17:35:25 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:35:25 INFO - PID 1650 | 2017-04-12 17:35:25.732 plugin-container[1651:10076] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 17:35:25 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:35:25 INFO - PID 1650 | 2017-04-12 17:35:25.732 plugin-container[1651:10076] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:35:25 INFO - PID 1650 | __metrics Screen width/height:1600/1200 17:35:25 INFO - PID 1650 | colorDepth:24 17:35:25 INFO - PID 1650 | Browser inner width/height: 1024/689 17:35:25 INFO - PID 1650 | __metrics 17:35:25 ERROR - PID 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 17:35:26 INFO - TEST-INFO | 1650: exit 0 17:35:26 INFO - Browser initialized. 17:35:26 INFO - Running cycle 1/1 for kraken test... 17:35:26 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/2lld364j46g___08n54mb94h00000w/T/tmpdOzjMp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:29 INFO - PID 1652 | 2017-04-12 17:35:29.207 plugin-container[1653:10199] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 17:35:29 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:35:29 INFO - PID 1652 | 2017-04-12 17:35:29.207 plugin-container[1653:10199] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8927, name = 'com.apple.CFPasteboardClient' 17:35:29 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:35:29 INFO - PID 1652 | 2017-04-12 17:35:29.208 plugin-container[1653:10199] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:35:39 INFO - PID 1652 | RSS: Main: 235421696 17:35:39 INFO - PID 1652 | 17:35:41 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection) 17:35:42 INFO - PID 1652 | RSS: Main: 250605568 17:35:42 INFO - PID 1652 | 17:35:45 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-dft) 17:35:45 INFO - PID 1652 | RSS: Main: 259112960 17:35:45 INFO - PID 1652 | 17:35:49 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-fft) 17:35:50 INFO - PID 1652 | RSS: Main: 261156864 17:35:50 INFO - PID 1652 | 17:35:52 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator) 17:35:52 INFO - PID 1652 | RSS: Main: 263311360 17:35:52 INFO - PID 1652 | 17:35:54 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:35:55 INFO - PID 1652 | RSS: Main: 263634944 17:35:55 INFO - PID 1652 | 17:35:58 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom) 17:35:59 INFO - PID 1652 | RSS: Main: 270008320 17:35:59 INFO - PID 1652 | 17:36:02 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate) 17:36:03 INFO - PID 1652 | RSS: Main: 269860864 17:36:03 INFO - PID 1652 | 17:36:06 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial) 17:36:06 INFO - PID 1652 | RSS: Main: 269807616 17:36:06 INFO - PID 1652 | 17:36:08 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:36:09 INFO - PID 1652 | RSS: Main: 269537280 17:36:09 INFO - PID 1652 | 17:36:11 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:36:11 INFO - PID 1652 | RSS: Main: 269557760 17:36:11 INFO - PID 1652 | 17:36:13 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:36:14 INFO - PID 1652 | RSS: Main: 269553664 17:36:14 INFO - PID 1652 | 17:36:17 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:36:18 INFO - PID 1652 | RSS: Main: 270082048 17:36:18 INFO - PID 1652 | 17:36:21 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:36:22 INFO - PID 1652 | RSS: Main: 269983744 17:36:22 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49238/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49238/tests/kraken/driver.html?testName=ai-astar) 17:36:24 INFO - PID 1652 | RSS: Main: 272576512 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | __start_tp_report 17:36:24 INFO - PID 1652 | _x_x_mozilla_page_load 17:36:24 INFO - PID 1652 | _x_x_mozilla_page_load_details 17:36:24 INFO - PID 1652 | |i|pagename|runs| 17:36:24 INFO - PID 1652 | |0;ai-astar;108;121;122;122;121;121;124;131;167;124 17:36:24 INFO - PID 1652 | |1;audio-beat-detection;152;100;114;157;112;115;115;115;139;117 17:36:24 INFO - PID 1652 | |2;audio-dft;215;216;225;232;226;254;217;300;217;302 17:36:24 INFO - PID 1652 | |3;audio-fft;84;59;58;84;61;62;60;61;73;64 17:36:24 INFO - PID 1652 | |4;audio-oscillator;86;69;90;71;73;78;70;102;110;121 17:36:24 INFO - PID 1652 | |5;imaging-gaussian-blur;93;92;94;92;107;92;98;93;93;92 17:36:24 INFO - PID 1652 | |6;imaging-darkroom;101;101;101;100;155;159;101;100;101;113 17:36:24 INFO - PID 1652 | |7;imaging-desaturate;102;101;99;102;103;100;101;147;164;101 17:36:24 INFO - PID 1652 | |8;json-parse-financial;52;52;52;53;53;57;82;84;48;46 17:36:24 INFO - PID 1652 | |9;json-stringify-tinderbox;63;68;60;74;69;68;52;88;57;55 17:36:24 INFO - PID 1652 | |10;stanford-crypto-aes;87;88;85;84;84;76;109;79;76;79 17:36:24 INFO - PID 1652 | |11;stanford-crypto-ccm;149;150;176;168;171;161;158;154;215;172 17:36:24 INFO - PID 1652 | |12;stanford-crypto-pbkdf2;194;239;259;200;195;194;195;195;207;194 17:36:24 INFO - PID 1652 | |13;stanford-crypto-sha256-iterative;71;71;70;79;92;97;64;70;102;105 17:36:24 INFO - PID 1652 | __end_tp_report 17:36:24 INFO - PID 1652 | __start_cc_report 17:36:24 INFO - PID 1652 | _x_x_mozilla_cycle_collect,1029 17:36:24 INFO - PID 1652 | __end_cc_report 17:36:24 INFO - PID 1652 | __startTimestamp1492043784544__endTimestamp 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | ------- Summary: start ------- 17:36:24 INFO - PID 1652 | Number of tests: 14 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#0] ai-astar Cycles:10 Average:126.10 Median:123.00 stddev:15.44 (12.6%) stddev-sans-first:14.92 17:36:24 INFO - PID 1652 | Values: 108.0 121.0 122.0 122.0 121.0 121.0 124.0 131.0 167.0 124.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:116.00 stddev:18.88 (16.3%) stddev-sans-first:17.00 17:36:24 INFO - PID 1652 | Values: 152.0 100.0 114.0 157.0 112.0 115.0 115.0 115.0 139.0 117.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#2] audio-dft Cycles:10 Average:240.40 Median:229.00 stddev:33.96 (14.8%) stddev-sans-first:34.76 17:36:24 INFO - PID 1652 | Values: 215.0 216.0 225.0 232.0 226.0 254.0 217.0 300.0 217.0 302.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#3] audio-fft Cycles:10 Average:66.60 Median:63.00 stddev:10.07 (16.0%) stddev-sans-first:8.49 17:36:24 INFO - PID 1652 | Values: 84.0 59.0 58.0 84.0 61.0 62.0 60.0 61.0 73.0 64.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#4] audio-oscillator Cycles:10 Average:87.00 Median:72.00 stddev:18.46 (25.6%) stddev-sans-first:19.57 17:36:24 INFO - PID 1652 | Values: 86.0 69.0 90.0 71.0 73.0 78.0 70.0 102.0 110.0 121.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:94.60 Median:93.00 stddev:4.72 (5.1%) stddev-sans-first:4.97 17:36:24 INFO - PID 1652 | Values: 93.0 92.0 94.0 92.0 107.0 92.0 98.0 93.0 93.0 92.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#6] imaging-darkroom Cycles:10 Average:113.20 Median:101.00 stddev:23.42 (23.2%) stddev-sans-first:24.42 17:36:24 INFO - PID 1652 | Values: 101.0 101.0 101.0 100.0 155.0 159.0 101.0 100.0 101.0 113.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#7] imaging-desaturate Cycles:10 Average:112.00 Median:102.50 stddev:23.30 (22.7%) stddev-sans-first:24.43 17:36:24 INFO - PID 1652 | Values: 102.0 101.0 99.0 102.0 103.0 100.0 101.0 147.0 164.0 101.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:53.00 stddev:13.56 (25.6%) stddev-sans-first:14.21 17:36:24 INFO - PID 1652 | Values: 52.0 52.0 52.0 53.0 53.0 57.0 82.0 84.0 48.0 46.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:68.00 stddev:10.56 (15.5%) stddev-sans-first:11.17 17:36:24 INFO - PID 1652 | Values: 63.0 68.0 60.0 74.0 69.0 68.0 52.0 88.0 57.0 55.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.00 stddev:9.57 (11.4%) stddev-sans-first:10.11 17:36:24 INFO - PID 1652 | Values: 87.0 88.0 85.0 84.0 84.0 76.0 109.0 79.0 76.0 79.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:167.40 Median:169.50 stddev:19.22 (11.3%) stddev-sans-first:19.20 17:36:24 INFO - PID 1652 | Values: 149.0 150.0 176.0 168.0 171.0 161.0 158.0 154.0 215.0 172.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:207.20 Median:197.50 stddev:22.89 (11.6%) stddev-sans-first:23.77 17:36:24 INFO - PID 1652 | Values: 194.0 239.0 259.0 200.0 195.0 194.0 195.0 195.0 207.0 194.0 17:36:24 INFO - PID 1652 | 17:36:24 INFO - PID 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.10 Median:71.00 stddev:15.34 (21.6%) stddev-sans-first:15.73 17:36:24 INFO - PID 1652 | Values: 71.0 71.0 70.0 79.0 92.0 97.0 64.0 70.0 102.0 105.0 17:36:24 INFO - PID 1652 | -------- Summary: end -------- 17:36:24 INFO - PID 1652 | 17:36:25 INFO - TEST-INFO | 1652: exit 0 17:36:25 INFO - TEST-OK | kraken | took 62791ms 17:36:25 INFO - SUITE-END | took 1179s 17:36:25 INFO - Completed test suite (00:19:39) 17:36:25 INFO - javascript benchmark 17:36:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7954.011475195501, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3367.040393487856, 3586.892393228204, 3596.694280986116, 3619.2580520993197, 3626.4922910792493, 5263.6052296077805, 6194.7212375443105, 6234.251889773228, 6304.432601065903, 6353.3646635336445, 6161.291451483081, 6253.906191407121, 6258.62448253104, 6300.960341543651, 6323.873522529547, 5467.753951072192, 5512.228288039678, 5539.584531160159, 5586.24585680932, 5634.4928956393915, 5462.617616766828, 5572.108462645978, 5617.045102332593, 5628.183913332565, 5709.543236541067, 4251.511076226243, 5306.151015837486, 5474.753636086346, 5541.33503979521, 5546.584006199551, 5519.254900588449, 5523.447655234485, 5529.364123125812, 5553.694546799924, 5565.026120428949, 5457.181422786574, 5460.836174914759, 5488.91766623501, 5503.11950087984, 5537.944620553783, 4762.2380419133115, 6179.351168127327, 6243.0, 6292.119103325538, 6359.713812878432, 6683.832904177434, 6778.118844550227, 6780.0, 6783.694733736945, 6802.863942721118, 6886.827829304308, 6899.241083480813, 6903.1371078615175, 6909.067275917737, 6910.48171387143, 6202.472789812917, 6326.905096423558, 6424.196975378078, 6429.38920802523, 6439.420452159328, 6921.826954326182, 6943.618101004493, 6943.756968506078, 6949.44404447643, 6995.79012629622, 5121.692698438105, 6436.195475565554, 6511.576747511396, 6652.634105124264, 6665.466762658975, 6596.175478065242, 6750.966245168743, 6775.593464392152, 6792.490563207779, 6795.150606174228, 6465.806025819233, 6515.0, 6728.730850766013, 6730.226024007302, 6747.831304217434, 6585.6377899216, 6663.267040625527, 6762.492813039042, 6768.966155169291, 6780.254172041072, 5374.54316383104, 5383.246345511631, 5386.353637563518, 5423.132298832168, 5431.864203394946, 4765.0, 4816.084943860656, 4822.180229361017, 4859.149648811514, 4898.657094003457, 6100.944524685232, 6331.303556608769, 6355.841103972345, 6372.6495042773095, 6419.935800641933, 6883.862322753517, 6884.449244060464, 6892.069570608004, 6899.586024838526, 6900.9654951725925, 6273.843153921189, 6385.297617262097, 6394.328595497499, 6409.807705768834, 6436.581622194542, 5833.270841144857, 5894.734736936848, 5905.291365036137, 5922.437368449991, 5974.880502389928, 6141.969290153699, 6936.445084393236, 6985.615791131537, 6986.231514533499, 6997.895031574429, 6816.420604248496, 6831.009503621847, 6852.486063545154, 6861.313868613099, 6864.107666003587, 6719.731210751515, 6839.62382068991, 6890.827729306808, 6921.550099243735, 6937.271586483346, 5593.0, 6653.600783952978, 6717.093192419156, 6718.193816742022, 6732.865342693217, 6338.366163383625, 6590.275069742421, 6857.902735562486, 6872.862542749017, 6879.552829066095, 6479.481641468766, 6527.804165875032, 6624.6025238485845, 6627.469802415699, 6634.469242460688, 6327.525435592257, 6622.403983641495, 6627.900581491378, 6628.966855165693, 6680.6993685283305, 6225.346338634378, 6428.646424446701, 6438.6136831790245, 6450.387213214737, 6519.152510173647, 6632.801015969529, 6649.867002659823, 6667.066610674412, 6668.299828517936, 6698.83252918681, 6459.160309159779, 6464.256610489854, 6468.0, 6485.675716214265, 6489.578177418642], "value": 6154.478615868539, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.7070257248387, 800.683729926679, 826.5123577089516, 1130.5025788652997, 1266.385802885601, 37294.69468568601, 38357.80821095894, 38384.616153838455, 38470.03824904372, 38610.22779544407, 38012.049698757546, 38300.61699383005, 38383.42424863622, 38392.42411363832, 38419.0, 8040.075391330013, 8192.795180120485, 8539.402241843043, 8795.912040879606, 8799.0, 8658.567071646425, 8748.99386570546, 8768.298536117096, 8772.69295574655, 8787.604557794883, 8759.124087591254, 8762.86855697165, 8817.559122043873, 8819.250363719091, 8842.823143537125, 8741.082186370466, 8743.562821858914, 8789.64841406343, 8790.033096359395, 8821.294296456268, 8512.74461766148, 8531.4454560454, 8561.48631082137, 8571.957140214321, 8572.185642363966, 37274.813625931696, 37274.813625931965, 37290.0, 37488.25023499515, 37496.81251593724, 3610.3862343401684, 3620.4931309616672, 3661.3226553087766, 3662.029562166028, 3665.9185540265685, 30619.0, 30647.38705225883, 30676.0, 30682.386352272828, 30721.692783072107, 2708.2281549758422, 2967.3620171663197, 3167.524871269305, 3182.411253918033, 3182.742816587435, 201140.99999999854, 222776.0, 227474.0, 255520.0, 255969.0, 40121.39817902734, 50192.24711629256, 50263.24605130926, 50273.24590131151, 50304.74847625739, 53055.73472132692, 53073.0, 53138.46861531412, 53193.0, 53453.732731336095, 14663.706725865422, 14683.339249733788, 14805.555833325017, 14825.851741482662, 14846.257687115602, 9507.619695212114, 12083.45624446902, 12117.515299387926, 12200.389980500939, 12222.572209972608, 11952.701182470508, 12543.749125017448, 13875.236861972688, 13875.306234688225, 13879.375428105759, 5129.333186685707, 6039.0, 6073.787417440369, 6122.632642041492, 6148.200733904564, 896.7489103051156, 998.8801343838641, 1024.4263212600968, 1032.210359075308, 1065.3607835298758, 20711.792882071288, 23717.16989905345, 24171.879140604186, 24289.514209715708, 24506.0, 1046.842973553973, 1132.841402203692, 1135.3188087147646, 1169.152364535705, 1179.2924245452832, 11927.940360298144, 12399.318037508023, 12426.254424734545, 12460.376981150906, 12462.376881155906, 347638.0, 349565.0, 349991.2500437482, 350416.0, 351632.0, 53920.73039634777, 54349.45650543523, 54395.72802135964, 54478.0, 54636.90726185454, 1500.407339101049, 1549.566121485963, 1558.3922270314586, 1567.1537369820169, 1573.968520629604, 24684.0, 29309.85345073261, 29427.55858662079, 30132.397352052398, 30286.0, 38960.41559376555, 51883.74058129685, 52370.0, 52630.0, 52667.209991849384, 306180.0, 326395.0, 327566.0, 328043.0, 328068.0, 35682.10794730153, 35778.64221357753, 36288.4556731644, 36369.636303637686, 36405.08987275339, 44684.55315446804, 45443.772781135885, 45602.77198614118, 45647.54352456433, 45678.54321456743, 30131.397372052874, 30722.0, 31132.844335778176, 31316.53025204578, 31567.684323156474, 29467.673954671664, 30488.695113049474, 30792.0, 31401.685983139876, 31656.36687266288, 36540.26919461649, 37106.0, 37396.06509837276, 37426.81286593658, 37502.62497374992, 29855.253618659706, 30354.84822575873, 30375.240618984702, 31471.84264078665, 31842.885499007425, 23507.529849402574, 24204.15285465, 24408.145714899892, 24531.38671533226, 24695.62956555689], "value": 17933.54238938152, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.46675221826644, 423.2170484603482, 430.3286872478935, 440.8501109622729, 450.51119535304196, 16173.676526469464, 18202.08989550522, 19198.424047278568, 21267.787322126773, 21411.0, 19811.108500117494, 21185.894070529663, 21577.568448631057, 22385.888070559624, 22426.0, 6030.185924900126, 6340.429361357477, 6347.746090156411, 6358.332375100617, 6365.52258580606, 5855.79504717335, 6060.303065147498, 6210.161628180205, 6263.373662633746, 6283.717232724538, 6207.844803879894, 6220.813375598739, 6225.906611400833, 6227.252729672446, 6265.310815810257, 6074.665893375863, 6146.938530614703, 6189.473894718954, 6246.187995560571, 6249.250089989185, 4412.183746006999, 5583.218349431083, 5595.356533998602, 5605.467480589337, 5623.0721930881355, 20757.792422075738, 25773.484530309477, 25981.22056338313, 26458.470830583472, 26548.0, 2259.051198496635, 2269.6595510673365, 2284.497410569672, 2305.6656784766283, 2309.0719249426747, 16016.279267432994, 16500.0, 17208.483745487654, 17995.910020449814, 18077.734558580905, 2126.447123747821, 2170.945726356854, 2219.0, 2219.6781466687403, 2223.132978138527, 143415.28292358576, 145208.0, 145582.0, 145652.2717386417, 146602.26698866545, 10910.181736369805, 11708.941455292668, 12694.301813400352, 12751.553695620794, 12922.095453318365, 16308.0, 16419.83580164183, 16445.506634800975, 16448.83551164473, 16500.75248871268, 7571.46999710026, 8972.0, 9208.401453905608, 9227.815443691223, 9241.844769403824, 8979.742836002964, 8981.281497480186, 8982.101789820965, 9151.679691210775, 9170.0, 7817.960910195527, 7938.960305198437, 7982.880256796153, 7983.920160798434, 7993.880091798627, 1888.7261347104593, 2187.1688758271753, 2189.700814145141, 2205.38249290199, 2383.1778036577352, 927.2674587076269, 965.3435663748718, 967.2842096848282, 969.743018100204, 971.8532132084067, 16559.6688066238, 16738.414155504743, 19876.60246795056, 20659.793402066087, 21068.894655526932, 900.7162743735702, 935.6631612619451, 966.9419834809934, 992.3103443106971, 1025.092792878308, 10625.521851516545, 10929.726756830984, 12763.617091487271, 13517.459301628021, 13663.043586948817, 674905.0, 683135.5843220851, 684728.0, 689532.0, 690979.0, 122541.0, 129305.35347323392, 161973.0, 162752.1862390704, 164264.0, 1176.1590462819127, 1199.8500187476566, 1242.9627111186498, 1244.2721008210117, 1247.8065899785547, 15715.292811823267, 16601.833981660027, 18603.813961860207, 19593.216271348985, 20431.8978405107, 57330.0, 64695.0, 65685.0, 66681.33318666751, 67040.3295967034, 77919.0, 81333.59333203296, 85200.57399712961, 85803.14196857951, 86218.0, 8017.358611311198, 8425.915740842513, 8805.295576354005, 9029.864552031593, 9055.547222638974, 13467.191968481922, 13827.86172138306, 13885.0, 14056.578302650936, 14104.294785260901, 11755.941220293844, 11790.292582445081, 11983.880161198276, 12002.63992080239, 12005.879941200476, 9846.966068562699, 10501.9474902625, 11248.887511124783, 11383.658490245305, 11450.541978320774, 11663.76672466563, 11855.58505452305, 12093.213941093802, 12163.270203787448, 12262.325572093621, 8711.21599056088, 8745.038045815083, 8925.776855578662, 9005.729828105168, 9045.77385565366, 11713.765724685629, 11963.70538953111, 12070.879291207328, 12126.0, 12226.510939562319], "value": 10530.008562910218, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.1369480379958, 1398.9860101398983, 1409.5278081842582, 1410.8572056634127, 1435.454527279634, 11553.133514986379, 11719.179657423962, 11983.340916249614, 12013.159078864483, 12045.51817927284, 10529.841717411078, 11057.447127643605, 11542.36516991565, 11844.348560829172, 11848.703782405444, 2465.790407815338, 2515.1071369663687, 2515.610080437535, 2518.26970178648, 2518.672572565573, 2435.4520232947534, 2449.7917676997477, 2484.291976786616, 2542.224621490445, 2543.4913017396593, 2527.519771243467, 2553.0298486575075, 2554.8339357941672, 2558.1685952065654, 2560.590305551112, 2452.4236804350962, 2473.5547601431736, 2488.8631125288102, 2489.8132640052067, 2500.574902266619, 2447.730749617541, 2460.1758410932357, 2464.6303054541963, 2465.272744540347, 2467.5188338274043, 11769.411529423494, 11850.644480665507, 12014.939925300405, 12024.639260822189, 12027.158098933078, 6629.93370066298, 6822.215445223764, 6822.976553517012, 6829.760958366433, 6857.348551887562, 4006.419069234974, 4151.564085771011, 4217.810198541073, 4246.5541118182455, 4309.978450107761, 1208.196549294718, 1233.6360773571728, 1247.7192631657895, 1253.931033793141, 1258.087886282447, 14937.17845518496, 16618.25217865199, 16661.750073748903, 16666.333346666117, 16710.832891671173, 5310.601704872072, 5453.563714902798, 5683.232763576864, 5746.367899531048, 5796.594238403356, 11789.056875449944, 11862.881371186179, 11901.642950711492, 11905.464254108585, 11912.404379780975, 6611.33886611335, 6613.437857782143, 6655.667216639245, 6658.933410665832, 6672.666366681647, 5283.418823929364, 5998.160257563857, 6041.0, 6094.360092190344, 6199.721012554446, 4416.33754936762, 4487.394201782783, 4514.503404625489, 4519.0, 4562.794674239667, 1185.733210027737, 1215.6535387414544, 1281.3465132782349, 1292.5088466382902, 1313.7109835836102, 591.2638764737928, 674.0251064381409, 726.5858460677468, 744.5272252119835, 750.107372227048, 5481.643693159932, 5723.6851973141875, 5758.769649213985, 5816.1566572846705, 5822.359540450547, 722.692855536395, 881.9647214111363, 886.5257087458178, 895.1451363947415, 896.8027034052498, 3434.5363375944426, 3466.2374277658873, 3485.9477107843404, 3534.346145962954, 3534.699550538233, 14519.34662940149, 15155.848441515443, 15395.153266570445, 15520.611984700472, 15529.223538823013, 15263.0, 15327.386904523917, 15442.691146177014, 15466.458673946358, 15517.224138793015, 11050.281731687415, 11107.278026928223, 11143.832842507369, 11245.550177992789, 11272.718182045515, 6606.636634985122, 6754.763583274562, 6793.0, 6841.418479429304, 6962.3037696231995, 13615.863841361856, 13650.0, 13683.384247708678, 13893.235872027135, 13920.72158556803, 10312.793744124925, 12872.871271287542, 13960.162390256617, 14063.156210627396, 14110.788338175142, 8435.072142064246, 8626.78433039179, 8650.745641882018, 8659.610317535851, 8668.739937801876, 10874.130069594572, 10914.781704366027, 10985.890141098806, 10987.505562249558, 11018.11855051578, 8893.73318800437, 8907.421017633833, 8923.464592124494, 8986.550672466481, 9000.144986226298, 9026.864597030919, 9116.954415227881, 9145.63417463294, 9168.44989300644, 9169.083091690778, 8087.919120808952, 8195.34437245007, 8247.793805154679, 8282.627281772215, 8285.461445006054, 5928.13263821912, 5950.583459157818, 6048.425399587032, 6129.693515324127, 6174.907376389448, 5938.940610593839, 6045.395460454095, 6118.908216376669, 6140.478059365004, 6152.39466693157], "value": 5350.106429044684, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.7237189223758, 394.38869751884573, 404.90687141957346, 411.1673860432622, 412.9545749967504, 5860.355360910296, 6035.849103772408, 6127.988881834497, 6279.591826531284, 6296.559240853142, 5970.223870896777, 6170.382961703827, 6184.176342452894, 6184.845378865531, 6190.1643278157435, 4730.053989202156, 4734.147853386389, 4738.834140805073, 4746.881327966811, 4772.80908763649, 4628.375169352144, 4733.408323959505, 4743.1225223333795, 4744.501827308135, 4752.5247475252545, 4256.744595324275, 4261.169072030955, 4680.882977925545, 4691.624670026406, 4720.150372932871, 3714.8142592870518, 4010.8769544527577, 4539.751568318706, 4559.612432943204, 4699.979009026118, 3584.982075089608, 3812.218495208476, 4085.346344584852, 4127.669786417079, 4181.247375472413, 5288.338957630296, 5900.675462849542, 5964.821055368346, 6024.397560243984, 6048.546359023091, 5635.098384258499, 5636.436356364372, 5642.887142257132, 5653.886922261532, 5659.887062115861, 6264.9686751566405, 6545.869082618321, 6640.535162538624, 6690.13028306317, 6732.394084532416, 4059.878203653895, 4347.152305300468, 4410.2282100632265, 4739.573438390561, 4813.566778989873, 5456.672599644034, 6201.906971395432, 6820.624865632438, 6963.40811031063, 7089.291070892921, 4840.419149701988, 4862.41651001882, 4868.342773725572, 4868.853934381974, 4879.39007624047, 4960.553550180501, 4965.602751779849, 4971.254311853251, 4978.377702787152, 4996.550310472075, 5130.692158470503, 5352.946470535245, 5509.5041446270025, 5563.833085007456, 5563.916541251884, 6535.738570457129, 6591.373819487141, 6608.801735947929, 6616.603003819786, 6616.669166541654, 1622.1240530113841, 1658.8507034366964, 1702.3105642214923, 1705.1474262868567, 1706.0701917455017, 1080.6811990462832, 1124.145649306533, 1169.6724917023248, 1189.6550000499758, 1192.7197108679454, 6234.9688251558455, 6237.754408412968, 6239.532035097386, 6259.436650701458, 6272.466840318532, 5058.204337460223, 6455.9677201613695, 6496.805095847132, 6759.966200168968, 6867.690953907087, 6232.439080482778, 6265.843353916189, 6304.527160462983, 6308.003698243389, 6318.303792281475, 2949.73452389286, 3531.9646803531637, 3574.857005719794, 3583.211693427442, 3590.0, 5353.4646535346155, 6659.901116315754, 6704.530682852153, 7028.648567571703, 7083.114610673666, 6391.749486591365, 6746.099177353785, 6993.580385176906, 7097.680604372712, 7155.964220178866, 5758.395368486333, 6050.757969681164, 6186.350433204449, 7050.682719277543, 7076.787696369117, 6746.662666866735, 6850.486213533905, 6878.38094571471, 6900.482463815334, 6913.654317284217, 5852.473277405054, 6706.765263215764, 6791.796246112624, 6807.319268073153, 6816.079829222991, 6745.763898263537, 6815.454763618998, 7091.60996145217, 7095.9645201773665, 7110.804625271115, 4413.7793110345, 4460.0, 4461.26010855773, 4476.350929115195, 4487.169873573399, 6802.6258555779905, 6859.5541289816, 6867.896981545181, 6909.585424874524, 6916.066331045446, 5085.92371114441, 6331.398517140864, 6466.191726034246, 6657.600543967377, 6663.800085997428, 6265.086734668401, 6587.80236592903, 6593.80218593443, 6629.834254143492, 6630.248446894485, 6249.593776404519, 6385.936140638534, 6460.0, 6476.31998640136, 6487.318831522811, 5150.690958542499, 6518.608883467007, 6620.3710647488415, 6622.4702023838945, 6623.834404139742, 6124.938750612437, 6200.81397558074, 6461.644609546332, 6468.223813142265, 6496.415322620986], "value": 4898.223768915603, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.8692978728722, 837.2339309531778, 848.6393282854785, 877.4077497689058, 877.7235170921159, 14478.638034049154, 15216.467423640148, 16481.011139331633, 16559.503214903543, 16825.83174168258, 13969.79045314321, 16499.83500164998, 17131.571710707238, 17254.56863578411, 17483.300667973297, 962.8122516109344, 987.3532835992437, 990.5740531571421, 991.4002028772597, 996.5565323431076, 1009.5154325923561, 1012.7012531303283, 1012.9566546457161, 1014.6803756816616, 1017.1354348803532, 1055.3034996902047, 1065.0893486069415, 1069.1446842525947, 1069.8074346617645, 1069.9304545204575, 1062.3360399750156, 1062.4103622489515, 1062.718379629395, 1062.946852657364, 1063.500154927187, 775.3642013548892, 787.5156778581188, 788.0, 788.1487992967582, 789.9888143176743, 13108.147970381895, 16811.243494042796, 17018.404355847484, 17230.483085507454, 17251.827481725148, 16349.0, 16998.490045298662, 17019.48941531756, 17061.48815535536, 17109.657806843792, 13483.86516134836, 13814.240216788072, 13972.650683732887, 13982.510612128628, 13996.580102596836, 3352.916177095568, 3369.831508424569, 3380.222548813762, 3393.3043726036103, 3407.522946787452, 113828.0, 114507.0, 114704.0, 116741.0, 117341.0, 10998.725031874106, 11434.371109589052, 12247.755044899051, 12263.816042759367, 12279.815802762965, 17463.65072698539, 20048.799512004985, 20101.698474522895, 20339.18643254283, 20441.693374599392, 7626.4661473697415, 7863.174366691529, 8497.405181637227, 8856.3800533962, 8857.46855188678, 4808.08646357191, 4892.938860745573, 4909.680870743462, 4929.728864912393, 4936.925946110811, 4243.066525364415, 4321.48142222929, 4331.480222373272, 4343.2399330117105, 4355.956440435555, 874.8818909447143, 908.5003248213457, 911.9133682300171, 913.5614904845711, 914.6021480655936, 608.716946619824, 646.2148489585177, 652.9444997175199, 659.7394029358377, 691.3155975584135, 10832.566697332019, 10927.398993055458, 10948.0, 11134.0, 12009.759804803854, 635.5963962883602, 658.5850913924197, 670.9906071270349, 671.8118926700531, 683.3132701634826, 5067.606408237764, 5259.263703081571, 5485.9725701371235, 5501.972490137524, 5554.77780888768, 97467.0, 98658.04025879235, 98866.0, 99890.0, 111013.88986110035, 65668.67165664141, 87857.0, 88744.5562772195, 89939.55030224807, 91775.54112229396, 80136.0, 80866.19133808822, 81316.0, 81456.0, 82027.5898620515, 11952.402379881145, 12248.75502489963, 12305.692357691129, 12326.137170398344, 12351.876481235073, 33545.66454335425, 36857.604255723294, 38928.0, 40418.1916361677, 41637.375439367825, 49493.02027918987, 67829.98255026278, 71890.28109718836, 71983.0, 73567.0, 16309.429169978994, 16575.00549967006, 16736.414225502536, 17070.89039212447, 17134.0, 18275.725864111784, 18467.0, 18502.44492665222, 18616.789908655894, 18739.812601873808, 16458.588535286715, 16547.007179569264, 17271.0, 17291.740623890902, 17407.129643518027, 13205.801912971505, 16244.02535847853, 16549.0, 16832.663346733243, 16913.32346706118, 15522.84477155214, 15526.378944842532, 15644.92177539105, 15664.0, 16313.0, 11602.723700393119, 11996.820047699466, 12060.698482538006, 12094.334811585095, 12111.273323600612, 13024.934875325942, 13176.538821141214, 13193.20840749558, 13351.933240333736, 13430.0], "value": 8313.801873216264, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1628.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 122.0, 122.0, 121.0, 121.0, 124.0, 131.0, 167.0, 124.0], "value": 126.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 100.0, 114.0, 157.0, 112.0, 115.0, 115.0, 115.0, 139.0, 117.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 216.0, 225.0, 232.0, 226.0, 254.0, 217.0, 300.0, 217.0, 302.0], "value": 240.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 58.0, 84.0, 61.0, 62.0, 60.0, 61.0, 73.0, 64.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 90.0, 71.0, 73.0, 78.0, 70.0, 102.0, 110.0, 121.0], "value": 87.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 94.0, 92.0, 107.0, 92.0, 98.0, 93.0, 93.0, 92.0], "value": 94.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 101.0, 100.0, 155.0, 159.0, 101.0, 100.0, 101.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 99.0, 102.0, 103.0, 100.0, 101.0, 147.0, 164.0, 101.0], "value": 112.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 53.0, 53.0, 57.0, 82.0, 84.0, 48.0, 46.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 60.0, 74.0, 69.0, 68.0, 52.0, 88.0, 57.0, 55.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 85.0, 84.0, 84.0, 76.0, 109.0, 79.0, 76.0, 79.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 176.0, 168.0, 171.0, 161.0, 158.0, 154.0, 215.0, 172.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 239.0, 259.0, 200.0, 195.0, 194.0, 195.0, 195.0, 207.0, 194.0], "value": 207.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 70.0, 79.0, 92.0, 97.0, 64.0, 70.0, 102.0, 105.0], "value": 82.1, "unit": "score"}]}]} 17:36:25 INFO - Return code: 0 17:36:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:36:25 ERROR - # TBPL SUCCESS # 17:36:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:36:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:36:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:36:25 INFO - 2017-04-12 17:36:25.836 screenresolution[1657:10592] starting screenresolution argv=screenresolution get 17:36:25 INFO - 2017-04-12 17:36:25.838 screenresolution[1657:10592] Display 0: 1600x1200x32@60 17:36:25 INFO - 2017-04-12 17:36:25.844 screenresolution[1658:10595] starting screenresolution argv=screenresolution list 17:36:25 INFO - Available Modes on Display 0 17:36:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:36:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:36:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:36:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:36:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:36:26 INFO - Intel Iris: 17:36:26 INFO - Chipset Model: Intel Iris 17:36:26 INFO - Type: GPU 17:36:26 INFO - Bus: Built-In 17:36:26 INFO - VRAM (Dynamic, Max): 1536 MB 17:36:26 INFO - Vendor: Intel (0x8086) 17:36:26 INFO - Device ID: 0x0a2e 17:36:26 INFO - Revision ID: 0x0009 17:36:26 INFO - Displays: 17:36:26 INFO - Display: 17:36:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:36:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:36:26 INFO - Display Serial Number: 1600x1200 60 17:36:26 INFO - Main Display: Yes 17:36:26 INFO - Mirror: Off 17:36:26 INFO - Online: Yes 17:36:26 INFO - Rotation: Supported 17:36:26 INFO - Return code: 0 17:36:26 INFO - Running post-action listener: _resource_record_post_action 17:36:26 INFO - [mozharness: 2017-04-13 00:36:26.094824Z] Finished run-tests step (success) 17:36:26 INFO - Running post-run listener: _resource_record_post_run 17:36:26 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 40299008; Write bytes: 522438144; Read time: 390; Write time: 1686 17:36:26 INFO - TinderboxPrint: CPU usage
54.1% 17:36:26 INFO - TinderboxPrint: I/O read bytes / time
40,299,008 / 390 17:36:26 INFO - TinderboxPrint: I/O write bytes / time
522,438,144 / 1,686 17:36:26 INFO - TinderboxPrint: CPU idle
2,203.1 (45.9%) 17:36:26 INFO - TinderboxPrint: CPU system
965.6 (20.1%) 17:36:26 INFO - TinderboxPrint: CPU user
1,633.0 (34.0%) 17:36:26 INFO - TinderboxPrint: Swap in / out
247,951,360 / 0 17:36:26 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205306368; Write bytes: 204289536; Read time: 15899; Write time: 421 17:36:26 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 38181376; Write bytes: 311042048; Read time: 366; Write time: 1236 17:36:26 INFO - Running post-run listener: _upload_blobber_files 17:36:26 INFO - Blob upload gear active. 17:36:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:36:26 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 17:36:26 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:36:26 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:36:27 INFO - (blobuploader) - INFO - Open directory for files ... 17:36:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:36:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:36:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:28 INFO - (blobuploader) - INFO - Done attempting. 17:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:36:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:36:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:29 INFO - (blobuploader) - INFO - Done attempting. 17:36:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:36:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:36:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:30 INFO - (blobuploader) - INFO - Done attempting. 17:36:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:36:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:36:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:36:32 INFO - (blobuploader) - INFO - Done attempting. 17:36:32 INFO - (blobuploader) - INFO - Iteration through files over. 17:36:32 INFO - Return code: 0 17:36:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:36:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:36:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8e4feb70f280d369889d4165a03d161aba795b6b0c5618bdd176fa86b14e49560fdb5196805bfed70281399337bbcf93a8f4259cceb39173fa6bb22ac6fb4230", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/46336e6389b8e306a1216574c45b67fdd95c4847910c11914f691eea0a5865f5bb6ffc71419adf3ff7a68ba57973f58b83b3681aa4bde2bad4c17ec6bd0b68a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a31cfb274c38823d59d5acc3eae334e9a32012ca965e230a84803ef70c88ac10b705ee768245bcdd44cf81a2e15072bac3e99a8ff24754ab043942b623b994e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/911d75a966ffacba904f0aa5b29eb9c64032ff45c146c6d32db3b8da57f394cbb5886ea0d596d345df32a85e53aa301f6e0743b423d8f71f97a3c84427b56686"} 17:36:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:36:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:36:32 INFO - Contents: 17:36:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8e4feb70f280d369889d4165a03d161aba795b6b0c5618bdd176fa86b14e49560fdb5196805bfed70281399337bbcf93a8f4259cceb39173fa6bb22ac6fb4230", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/46336e6389b8e306a1216574c45b67fdd95c4847910c11914f691eea0a5865f5bb6ffc71419adf3ff7a68ba57973f58b83b3681aa4bde2bad4c17ec6bd0b68a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a31cfb274c38823d59d5acc3eae334e9a32012ca965e230a84803ef70c88ac10b705ee768245bcdd44cf81a2e15072bac3e99a8ff24754ab043942b623b994e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/911d75a966ffacba904f0aa5b29eb9c64032ff45c146c6d32db3b8da57f394cbb5886ea0d596d345df32a85e53aa301f6e0743b423d8f71f97a3c84427b56686"} 17:36:32 INFO - Running post-run listener: copy_logs_to_upload_dir 17:36:32 INFO - Copying logs to upload dir... 17:36:32 INFO - mkdir: /builds/slave/test/build/upload/logs 17:36:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.795753 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-12 17:36:32.232626) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 17:36:32.235927) ========= 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.LyAhhoR1J5/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.wTHx733P2M/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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/Pine/sha512/8e4feb70f280d369889d4165a03d161aba795b6b0c5618bdd176fa86b14e49560fdb5196805bfed70281399337bbcf93a8f4259cceb39173fa6bb22ac6fb4230", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/46336e6389b8e306a1216574c45b67fdd95c4847910c11914f691eea0a5865f5bb6ffc71419adf3ff7a68ba57973f58b83b3681aa4bde2bad4c17ec6bd0b68a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a31cfb274c38823d59d5acc3eae334e9a32012ca965e230a84803ef70c88ac10b705ee768245bcdd44cf81a2e15072bac3e99a8ff24754ab043942b623b994e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/911d75a966ffacba904f0aa5b29eb9c64032ff45c146c6d32db3b8da57f394cbb5886ea0d596d345df32a85e53aa301f6e0743b423d8f71f97a3c84427b56686"} build_url:https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/UBENQhR2SsmnEw96TJndJQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/8e4feb70f280d369889d4165a03d161aba795b6b0c5618bdd176fa86b14e49560fdb5196805bfed70281399337bbcf93a8f4259cceb39173fa6bb22ac6fb4230", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/46336e6389b8e306a1216574c45b67fdd95c4847910c11914f691eea0a5865f5bb6ffc71419adf3ff7a68ba57973f58b83b3681aa4bde2bad4c17ec6bd0b68a4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a31cfb274c38823d59d5acc3eae334e9a32012ca965e230a84803ef70c88ac10b705ee768245bcdd44cf81a2e15072bac3e99a8ff24754ab043942b623b994e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/911d75a966ffacba904f0aa5b29eb9c64032ff45c146c6d32db3b8da57f394cbb5886ea0d596d345df32a85e53aa301f6e0743b423d8f71f97a3c84427b56686"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 17:36:32.269833) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:36:32.270144) ========= 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.LyAhhoR1J5/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.wTHx733P2M/Listeners TMPDIR=/var/folders/8l/2lld364j46g___08n54mb94h00000w/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.005165 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:36:32.318286) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 17:36:32.318639) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 17:36:32.318946) ========= ========= Total master_lag: 0.17 =========