builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0196 starttime: 1492720235.39 results: success (0) buildid: 20170420115229 builduid: f8075253126b4866a51d0f9e83a91734 revision: 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:35.394891) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:35.395307) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:35.395602) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:35.421604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:35.421961) ========= 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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-20 13:30:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.6M=0.001s 2017-04-20 13:30:35 (13.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111856 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:35.558218) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:35.558539) ========= 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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.027926 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:35.608365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-20 13:30:35.608733) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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-20 13:30:35,679 truncating revision to first 12 chars 2017-04-20 13:30:35,680 Setting DEBUG logging. 2017-04-20 13:30:35,680 attempt 1/10 2017-04-20 13:30:35,680 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/5d6e88c784ca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-20 13:30:37,275 unpacking tar archive at: pine-5d6e88c784ca/testing/mozharness/ program finished with exit code 0 elapsedTime=1.867854 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-20 13:30:37.500187) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:37.500518) ========= script_repo_revision: 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:37.500940) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:37.501212) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-20 13:30:37.521161) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-20 13:30:37.521564) ========= /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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:30:37 INFO - MultiFileLogger online at 20170420 13:30:37 in /builds/slave/test 13:30:37 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 13:30:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:30:37 INFO - {'append_to_log': False, 13:30:37 INFO - 'base_work_dir': '/builds/slave/test', 13:30:37 INFO - 'blob_upload_branch': 'Pine', 13:30:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:30:37 INFO - 'branch': 'Pine', 13:30:37 INFO - 'buildbot_json_path': 'buildprops.json', 13:30:37 INFO - 'config_files': ('talos/mac_config.py',), 13:30:37 INFO - 'default_actions': ('clobber', 13:30:37 INFO - 'read-buildbot-config', 13:30:37 INFO - 'download-and-extract', 13:30:37 INFO - 'populate-webroot', 13:30:37 INFO - 'create-virtualenv', 13:30:37 INFO - 'install', 13:30:37 INFO - 'run-tests'), 13:30:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:30:37 INFO - 'download_minidump_stackwalk': True, 13:30:37 INFO - 'download_symbols': 'ondemand', 13:30:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:30:37 INFO - 'tooltool.py': '/tools/tooltool.py', 13:30:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:30:37 INFO - '/tools/misc-python/virtualenv.py')}, 13:30:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:30:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:30:37 INFO - 'gecko_profile': False, 13:30:37 INFO - 'gecko_profile_interval': 0, 13:30:37 INFO - 'installer_path': 'installer.exe', 13:30:37 INFO - 'log_level': 'info', 13:30:37 INFO - 'log_name': 'talos', 13:30:37 INFO - 'log_to_console': True, 13:30:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:30:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:30:37 INFO - 'opt_config_files': (), 13:30:37 INFO - 'pip_index': False, 13:30:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:37 INFO - 'cmd': ('bash', 13:30:37 INFO - '-c', 13:30:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:37 INFO - 'enabled': True, 13:30:37 INFO - 'halt_on_failure': False, 13:30:37 INFO - 'name': 'check_screen_resolution'},), 13:30:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:37 INFO - 'cmd': ('bash', 13:30:37 INFO - '-c', 13:30:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:37 INFO - 'enabled': True, 13:30:37 INFO - 'halt_on_failure': False, 13:30:37 INFO - 'name': 'check_screen_resolution'},), 13:30:37 INFO - 'run_cmd_checks_enabled': True, 13:30:37 INFO - 'suite': 'dromaeojs-e10s', 13:30:37 INFO - 'system_bits': '32', 13:30:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:30:37 INFO - 'title': 't-yosemite-r7-0196', 13:30:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:30:37 INFO - 'use_talos_json': True, 13:30:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:30:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:30:37 INFO - 'work_dir': 'build'} 13:30:37 INFO - [mozharness: 2017-04-20 20:30:37.706032Z] Running clobber step. 13:30:37 INFO - Running pre-action listener: _resource_record_pre_action 13:30:37 INFO - Running main action method: clobber 13:30:37 INFO - rmtree: /builds/slave/test/build 13:30:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:30:40 INFO - Running post-action listener: _resource_record_post_action 13:30:40 INFO - [mozharness: 2017-04-20 20:30:40.347578Z] Finished clobber step (success) 13:30:40 INFO - [mozharness: 2017-04-20 20:30:40.347747Z] Running read-buildbot-config step. 13:30:40 INFO - Running pre-action listener: _resource_record_pre_action 13:30:40 INFO - Running main action method: read_buildbot_config 13:30:40 INFO - Using buildbot properties: 13:30:40 INFO - { 13:30:40 INFO - "project": "", 13:30:40 INFO - "product": "firefox", 13:30:40 INFO - "script_repo_revision": "default", 13:30:40 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 13:30:40 INFO - "repository": "", 13:30:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 13:30:40 INFO - "buildid": "20170420115229", 13:30:40 INFO - "pgo_build": "False", 13:30:40 INFO - "basedir": "/builds/slave/test", 13:30:40 INFO - "buildnumber": 14, 13:30:40 INFO - "slavename": "t-yosemite-r7-0196", 13:30:40 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 13:30:40 INFO - "platform": "yosemite_r7", 13:30:40 INFO - "scheduler": "tests-pine-macosx64-talos", 13:30:40 INFO - "branch": "pine", 13:30:40 INFO - "repo_path": "projects/pine", 13:30:40 INFO - "revision": "5d6e88c784ca72dd35b6f0a15701d867f8bd0061", 13:30:40 INFO - "stage_platform": "macosx64", 13:30:40 INFO - "builduid": "f8075253126b4866a51d0f9e83a91734", 13:30:40 INFO - "slavebuilddir": "test" 13:30:40 INFO - } 13:30:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 13:30:40 INFO - Running post-action listener: _resource_record_post_action 13:30:40 INFO - [mozharness: 2017-04-20 20:30:40.353535Z] Finished read-buildbot-config step (success) 13:30:40 INFO - [mozharness: 2017-04-20 20:30:40.353765Z] Running download-and-extract step. 13:30:40 INFO - Running pre-action listener: _resource_record_pre_action 13:30:40 INFO - Running main action method: download_and_extract 13:30:40 INFO - mkdir: /builds/slave/test/build/tests 13:30:40 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')]} 13:30:40 INFO - trying https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 13:30:40 INFO - Downloading https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/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 13:30:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/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 13:30:41 INFO - Downloaded 1325 bytes. 13:30:41 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 13:30:41 INFO - Using the following test package requirements: 13:30:41 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:41 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 13:30:41 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:30:41 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:41 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 13:30:41 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:41 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 13:30:41 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:41 INFO - u'jsshell-mac.zip'], 13:30:41 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:41 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 13:30:41 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:30:41 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:41 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 13:30:41 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:41 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 13:30:41 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:41 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 13:30:41 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:30:41 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 13:30:41 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:30:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:30:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:30:41 INFO - Fetch https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:30:44 INFO - Content-Length response header: 52521679 13:30:44 INFO - Bytes received: 52521679 13:31:00 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 13:31:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:31:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:31:00 INFO - Fetch https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:31:01 INFO - Content-Length response header: 52521679 13:31:01 INFO - Bytes received: 52521679 13:31:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 13:31:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:31:17 INFO - Fetch https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 13:31:19 INFO - Content-Length response header: 14408811 13:31:19 INFO - Bytes received: 14408811 13:31: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')]} 13:31:19 INFO - trying https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:31:19 INFO - Downloading https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 13:31:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/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 13:31:21 INFO - Downloaded 71093256 bytes. 13:31:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:31:21 INFO - mkdir: /builds/slave/test/properties 13:31:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:31:21 INFO - Writing to file /builds/slave/test/properties/build_url 13:31:21 INFO - Contents: 13:31:21 INFO - build_url:https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:31:22 INFO - Running post-action listener: _resource_record_post_action 13:31:22 INFO - Running post-action listener: set_extra_try_arguments 13:31:22 INFO - [mozharness: 2017-04-20 20:31:22.106603Z] Finished download-and-extract step (success) 13:31:22 INFO - [mozharness: 2017-04-20 20:31:22.106755Z] Running populate-webroot step. 13:31:22 INFO - Running pre-action listener: _resource_record_pre_action 13:31:22 INFO - Running main action method: populate_webroot 13:31:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:31:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:31:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:31:22 INFO - '--tppagecycles', 13:31:22 INFO - '7'], 13:31:22 INFO - 'tests': ['tsvgm']}}, 13:31:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:31:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:31:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:31:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:31:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:31:22 INFO - 'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:31:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:31:22 INFO - 'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['damp', 'tps']}, 13:31:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:31:22 INFO - 'tests': ['damp', 'tps']}, 13:31:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['dromaeo_dom']}, 13:31:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:31:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 13:31:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 13:31:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['a11yr', 13:31:22 INFO - 'ts_paint', 13:31:22 INFO - 'tpaint', 13:31:22 INFO - 'sessionrestore', 13:31:22 INFO - 'sessionrestore_no_auto_restore', 13:31:22 INFO - 'tabpaint']}, 13:31:22 INFO - 'other-e10s': {'tests': ['a11yr', 13:31:22 INFO - 'ts_paint', 13:31:22 INFO - 'tpaint', 13:31:22 INFO - 'sessionrestore', 13:31:22 INFO - 'sessionrestore_no_auto_restore', 13:31:22 INFO - 'tabpaint']}, 13:31:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:31:22 INFO - 'ts_paint', 13:31:22 INFO - 'tpaint', 13:31:22 INFO - 'sessionrestore', 13:31:22 INFO - 'sessionrestore_no_auto_restore', 13:31:22 INFO - 'tabpaint']}, 13:31:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:31:22 INFO - 'ts_paint', 13:31:22 INFO - 'tpaint', 13:31:22 INFO - 'sessionrestore', 13:31:22 INFO - 'sessionrestore_no_auto_restore', 13:31:22 INFO - 'tabpaint']}, 13:31:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['a11yr', 13:31:22 INFO - 'ts_paint', 13:31:22 INFO - 'tpaint', 13:31:22 INFO - 'sessionrestore', 13:31:22 INFO - 'sessionrestore_no_auto_restore', 13:31:22 INFO - 'tabpaint']}, 13:31:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['a11yr', 13:31:22 INFO - 'ts_paint', 13:31:22 INFO - 'tpaint', 13:31:22 INFO - 'sessionrestore', 13:31:22 INFO - 'sessionrestore_no_auto_restore', 13:31:22 INFO - 'tabpaint']}, 13:31:22 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 13:31:22 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 13:31:22 INFO - 'bloom_basic_ref']}, 13:31:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['tsvgx', 13:31:22 INFO - 'tsvgr_opacity', 13:31:22 INFO - 'tart', 13:31:22 INFO - 'tscrollx', 13:31:22 INFO - 'cart', 13:31:22 INFO - 'tsvg_static']}, 13:31:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:31:22 INFO - 'tsvgr_opacity', 13:31:22 INFO - 'tart', 13:31:22 INFO - 'tscrollx', 13:31:22 INFO - 'cart', 13:31:22 INFO - 'tsvg_static']}, 13:31:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:31:22 INFO - 'talos_options': ['--disable-e10s'], 13:31:22 INFO - 'tests': ['tp5o']}, 13:31:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:31:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:31:22 INFO - 'talos_options': ['--disable-e10s', 13:31:22 INFO - '--xperf_path', 13:31:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:22 INFO - 'tests': ['tp5n']}, 13:31:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:31:22 INFO - 'talos_options': ['--xperf_path', 13:31:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:22 INFO - 'tests': ['tp5n']}}, 13:31:22 INFO - 'talos.zip': {'path': '', 13:31:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:31:22 INFO - Running post-action listener: _resource_record_post_action 13:31:22 INFO - [mozharness: 2017-04-20 20:31:22.126011Z] Finished populate-webroot step (success) 13:31:22 INFO - [mozharness: 2017-04-20 20:31:22.126145Z] Running create-virtualenv step. 13:31:22 INFO - Running pre-action listener: _resource_record_pre_action 13:31:22 INFO - Running main action method: create_virtualenv 13:31:22 INFO - Creating virtualenv /builds/slave/test/build/venv 13:31:22 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 13:31:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:31:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:31:22 INFO - Using real prefix '/tools/python27' 13:31:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:31:22 INFO - Installing distribute.............................................................................................................................................................................................done. 13:31:25 INFO - Installing pip.................done. 13:31:25 INFO - Return code: 0 13:31:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:31:26 INFO - Reading from file tmpfile_stdout 13:31:26 INFO - Output received: 13:31:26 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) 13:31:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:26 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 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:26 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 13:31:26 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 13:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:26 INFO - 'HOME': '/Users/cltbld', 13:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:26 INFO - 'LOGNAME': 'cltbld', 13:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:26 INFO - 'MOZ_NO_REMOTE': '1', 13:31:26 INFO - 'NO_EM_RESTART': '1', 13:31:26 INFO - 'PAGER': '/bin/cat', 13:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:26 INFO - 'PWD': '/builds/slave/test', 13:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:26 INFO - 'SHELL': '/bin/bash', 13:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:26 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:26 INFO - 'USER': 'cltbld', 13:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:26 INFO - 'XPC_FLAGS': '0x0', 13:31:26 INFO - 'XPC_SERVICE_NAME': '0', 13:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:26 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 13:31:26 INFO - Cleaning up... 13:31:26 INFO - Return code: 0 13:31:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:26 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 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:26 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 13:31:26 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 13:31:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:26 INFO - 'HOME': '/Users/cltbld', 13:31:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:26 INFO - 'LOGNAME': 'cltbld', 13:31:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:26 INFO - 'MOZ_NO_REMOTE': '1', 13:31:26 INFO - 'NO_EM_RESTART': '1', 13:31:26 INFO - 'PAGER': '/bin/cat', 13:31:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:26 INFO - 'PWD': '/builds/slave/test', 13:31:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:26 INFO - 'SHELL': '/bin/bash', 13:31:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:26 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:26 INFO - 'USER': 'cltbld', 13:31:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:26 INFO - 'XPC_FLAGS': '0x0', 13:31:26 INFO - 'XPC_SERVICE_NAME': '0', 13:31:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:26 INFO - Downloading/unpacking psutil>=3.1.1 13:31:26 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 13:31:26 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 13:31:26 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 13:31:26 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 13:31:26 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 13:31:26 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 13:31:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:31:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:30 INFO - Installing collected packages: psutil 13:31:30 INFO - Running setup.py install for psutil 13:31:30 INFO - building 'psutil._psutil_osx' extension 13:31:30 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 13:31:30 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 13:31:30 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 13:31:30 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 13:31:31 INFO - building 'psutil._psutil_posix' extension 13:31:31 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 13:31:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:31:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:31:31 INFO - ^ 13:31:31 INFO - 1 warning generated. 13:31:31 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 13:31:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:31 INFO - Successfully installed psutil 13:31:31 INFO - Cleaning up... 13:31:31 INFO - Return code: 0 13:31:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:31:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:31:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:32 INFO - 'HOME': '/Users/cltbld', 13:31:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:32 INFO - 'LOGNAME': 'cltbld', 13:31:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:32 INFO - 'MOZ_NO_REMOTE': '1', 13:31:32 INFO - 'NO_EM_RESTART': '1', 13:31:32 INFO - 'PAGER': '/bin/cat', 13:31:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:32 INFO - 'PWD': '/builds/slave/test', 13:31:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:32 INFO - 'SHELL': '/bin/bash', 13:31:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:32 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:32 INFO - 'USER': 'cltbld', 13:31:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:32 INFO - 'XPC_FLAGS': '0x0', 13:31:32 INFO - 'XPC_SERVICE_NAME': '0', 13:31:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:31:32 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 13:31:32 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 13:31:32 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 13:31:32 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 13:31:32 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 13:31:32 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 13:31:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:31:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:31:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:31:35 INFO - Installing collected packages: mozsystemmonitor 13:31:35 INFO - Running setup.py install for mozsystemmonitor 13:31:35 INFO - Successfully installed mozsystemmonitor 13:31:35 INFO - Cleaning up... 13:31:35 INFO - Return code: 0 13:31:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:31:35 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')]} 13:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:35 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')]} 13:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:35 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 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:35 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 13:31:35 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 13:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:35 INFO - 'HOME': '/Users/cltbld', 13:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:35 INFO - 'LOGNAME': 'cltbld', 13:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:35 INFO - 'MOZ_NO_REMOTE': '1', 13:31:35 INFO - 'NO_EM_RESTART': '1', 13:31:35 INFO - 'PAGER': '/bin/cat', 13:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:35 INFO - 'PWD': '/builds/slave/test', 13:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:35 INFO - 'SHELL': '/bin/bash', 13:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:35 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:35 INFO - 'USER': 'cltbld', 13:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:35 INFO - 'XPC_FLAGS': '0x0', 13:31:35 INFO - 'XPC_SERVICE_NAME': '0', 13:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:36 INFO - Downloading/unpacking jsonschema==2.5.1 13:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:31:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:31:38 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 13:31:38 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 13:31:38 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 13:31:38 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 13:31:38 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 13:31:38 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 13:31:39 INFO - Downloading functools32-3.2.3-2.tar.gz 13:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:31:39 INFO - warning: no files found matching '*.txt' 13:31:39 INFO - no previously-included directories found matching 'build' 13:31:39 INFO - no previously-included directories found matching 'dist' 13:31:39 INFO - no previously-included directories found matching '.git*' 13:31:39 INFO - Installing collected packages: jsonschema, functools32 13:31:39 INFO - Running setup.py install for functools32 13:31:39 INFO - warning: no files found matching '*.txt' 13:31:39 INFO - no previously-included directories found matching 'build' 13:31:39 INFO - no previously-included directories found matching 'dist' 13:31:39 INFO - no previously-included directories found matching '.git*' 13:31:39 INFO - Successfully installed jsonschema functools32 13:31:39 INFO - Cleaning up... 13:31:39 INFO - Return code: 0 13:31:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:31:39 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')]} 13:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:39 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')]} 13:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:39 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 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:39 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 13:31:39 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 13:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:39 INFO - 'HOME': '/Users/cltbld', 13:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:39 INFO - 'LOGNAME': 'cltbld', 13:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:39 INFO - 'MOZ_NO_REMOTE': '1', 13:31:39 INFO - 'NO_EM_RESTART': '1', 13:31:39 INFO - 'PAGER': '/bin/cat', 13:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:39 INFO - 'PWD': '/builds/slave/test', 13:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:39 INFO - 'SHELL': '/bin/bash', 13:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:39 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:39 INFO - 'USER': 'cltbld', 13:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:39 INFO - 'XPC_FLAGS': '0x0', 13:31:39 INFO - 'XPC_SERVICE_NAME': '0', 13:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:31:39 INFO - Cleaning up... 13:31:39 INFO - Return code: 0 13:31:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:31:39 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')]} 13:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:39 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')]} 13:31:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:39 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 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:39 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 13:31:39 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 13:31:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:39 INFO - 'HOME': '/Users/cltbld', 13:31:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:39 INFO - 'LOGNAME': 'cltbld', 13:31:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:39 INFO - 'MOZ_NO_REMOTE': '1', 13:31:39 INFO - 'NO_EM_RESTART': '1', 13:31:39 INFO - 'PAGER': '/bin/cat', 13:31:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:39 INFO - 'PWD': '/builds/slave/test', 13:31:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:39 INFO - 'SHELL': '/bin/bash', 13:31:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:39 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:39 INFO - 'USER': 'cltbld', 13:31:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:39 INFO - 'XPC_FLAGS': '0x0', 13:31:39 INFO - 'XPC_SERVICE_NAME': '0', 13:31:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:40 INFO - Downloading/unpacking blobuploader==1.2.4 13:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:42 INFO - Downloading blobuploader-1.2.4.tar.gz 13:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:31:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:31:42 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 13:31:42 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 13:31:42 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 13:31:42 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 13:31:42 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 13:31:42 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 13:31:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:31:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:31:43 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 13:31:43 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 13:31:43 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 13:31:43 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 13:31:43 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 13:31:43 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 13:31:44 INFO - Downloading docopt-0.6.1.tar.gz 13:31:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:31:44 INFO - Installing collected packages: blobuploader, requests, docopt 13:31:44 INFO - Running setup.py install for blobuploader 13:31:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:31:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:31:44 INFO - Running setup.py install for requests 13:31:44 INFO - Running setup.py install for docopt 13:31:44 INFO - Successfully installed blobuploader requests docopt 13:31:44 INFO - Cleaning up... 13:31:44 INFO - Return code: 0 13:31:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:44 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')]} 13:31:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:44 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')]} 13:31:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:44 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 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:44 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 13:31:44 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 13:31:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:44 INFO - 'HOME': '/Users/cltbld', 13:31:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:44 INFO - 'LOGNAME': 'cltbld', 13:31:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:44 INFO - 'MOZ_NO_REMOTE': '1', 13:31:44 INFO - 'NO_EM_RESTART': '1', 13:31:44 INFO - 'PAGER': '/bin/cat', 13:31:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:44 INFO - 'PWD': '/builds/slave/test', 13:31:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:44 INFO - 'SHELL': '/bin/bash', 13:31:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:44 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:44 INFO - 'USER': 'cltbld', 13:31:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:44 INFO - 'XPC_FLAGS': '0x0', 13:31:44 INFO - 'XPC_SERVICE_NAME': '0', 13:31:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:45 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-C_uFOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:45 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-D7YyGn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:45 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-R4bOQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:45 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-KxK5sN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:45 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-DQwMtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:45 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-1NvX7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:45 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-a07bFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:45 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-JApN67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:46 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-94wRe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:46 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-eoiXDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:46 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-V8Oh9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:46 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-HUfXfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:46 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-ZzuhS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:46 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-EVvpKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:46 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Wu79t1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:46 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-DZsYgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:46 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-bvBHHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:31:47 INFO - Running setup.py install for manifestparser 13:31:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:31:47 INFO - Running setup.py install for mozcrash 13:31:47 INFO - Running setup.py install for mozdebug 13:31:47 INFO - Running setup.py install for mozdevice 13:31:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:31:47 INFO - Running setup.py install for mozfile 13:31:47 INFO - Running setup.py install for mozhttpd 13:31:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:31:47 INFO - Running setup.py install for mozinfo 13:31:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Running setup.py install for mozInstall 13:31:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Running setup.py install for mozleak 13:31:48 INFO - Running setup.py install for mozlog 13:31:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Running setup.py install for moznetwork 13:31:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Running setup.py install for mozprocess 13:31:48 INFO - Running setup.py install for mozprofile 13:31:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:31:48 INFO - Running setup.py install for mozrunner 13:31:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:31:49 INFO - Running setup.py install for mozscreenshot 13:31:49 INFO - Running setup.py install for moztest 13:31:49 INFO - Running setup.py install for mozversion 13:31:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:31:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:31:49 INFO - Cleaning up... 13:31:49 INFO - Return code: 0 13:31:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:49 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 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:49 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 13:31:49 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 13:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:49 INFO - 'HOME': '/Users/cltbld', 13:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:49 INFO - 'LOGNAME': 'cltbld', 13:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:49 INFO - 'MOZ_NO_REMOTE': '1', 13:31:49 INFO - 'NO_EM_RESTART': '1', 13:31:49 INFO - 'PAGER': '/bin/cat', 13:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:49 INFO - 'PWD': '/builds/slave/test', 13:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:49 INFO - 'SHELL': '/bin/bash', 13:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:49 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:49 INFO - 'USER': 'cltbld', 13:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:49 INFO - 'XPC_FLAGS': '0x0', 13:31:49 INFO - 'XPC_SERVICE_NAME': '0', 13:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:31:49 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-3MHGtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:31:49 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)) 13:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:31:49 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-hkiDVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:31:50 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)) 13:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:31:50 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-_Os6sH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:31:50 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)) 13:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:31:50 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-RtckVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:31:50 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)) 13:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:31:50 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-E_yPmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:31:50 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)) 13:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:31:50 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-UT4r7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:31:50 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)) 13:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:31:50 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-aQBxNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:31:50 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)) 13:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:31:50 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-Huuhp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:31:50 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)) 13:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:31:50 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-NcaZee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:31:50 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)) 13:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:31:50 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-qvrzcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:31:50 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)) 13:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:31:50 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-YIdyAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:31:51 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)) 13:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:31:51 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-w7Yl1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:31:51 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)) 13:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:31:51 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-lGWW4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:31:51 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)) 13:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:31:51 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-35pru_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:31:51 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)) 13:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:51 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-0ws8wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:31:51 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)) 13:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:31:51 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-QuAACs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:31:51 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)) 13:31:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:31:51 INFO - Running setup.py (path:/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/pip-hDVpMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:31:51 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)) 13:31:51 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)) 13:31:51 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)) 13:31:51 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)) 13:31:51 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)) 13:31:51 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)) 13:31:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:31:51 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 13:31:51 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 13:31:51 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 13:31:51 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 13:31:51 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 13:31:51 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 13:31:54 INFO - Downloading blessings-1.6.tar.gz 13:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:31:54 INFO - Installing collected packages: blessings 13:31:54 INFO - Running setup.py install for blessings 13:31:54 INFO - Successfully installed blessings 13:31:54 INFO - Cleaning up... 13:31:54 INFO - Return code: 0 13:31:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:31:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:54 INFO - Reading from file tmpfile_stdout 13:31:54 INFO - Current package versions: 13:31:54 INFO - blessings == 1.6 13:31:54 INFO - blobuploader == 1.2.4 13:31:54 INFO - docopt == 0.6.1 13:31:54 INFO - functools32 == 3.2.3-2 13:31:54 INFO - jsonschema == 2.5.1 13:31:54 INFO - manifestparser == 1.1 13:31:54 INFO - mozInstall == 1.12 13:31:54 INFO - mozcrash == 1.0 13:31:54 INFO - mozdebug == 0.1 13:31:54 INFO - mozdevice == 0.50 13:31:54 INFO - mozfile == 1.2 13:31:54 INFO - mozhttpd == 0.7 13:31:54 INFO - mozinfo == 0.9 13:31:54 INFO - mozleak == 0.1 13:31:54 INFO - mozlog == 3.4 13:31:54 INFO - moznetwork == 0.27 13:31:54 INFO - mozprocess == 0.25 13:31:54 INFO - mozprofile == 0.28 13:31:54 INFO - mozrunner == 6.13 13:31:54 INFO - mozscreenshot == 0.1 13:31:54 INFO - mozsystemmonitor == 0.3 13:31:54 INFO - moztest == 0.8 13:31:54 INFO - mozversion == 1.4 13:31:54 INFO - psutil == 3.1.1 13:31:54 INFO - requests == 1.2.3 13:31:54 INFO - wsgiref == 0.1.2 13:31:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:54 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 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:54 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 13:31:54 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 13:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:54 INFO - 'HOME': '/Users/cltbld', 13:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:54 INFO - 'LOGNAME': 'cltbld', 13:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:54 INFO - 'MOZ_NO_REMOTE': '1', 13:31:54 INFO - 'NO_EM_RESTART': '1', 13:31:54 INFO - 'PAGER': '/bin/cat', 13:31:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:54 INFO - 'PWD': '/builds/slave/test', 13:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:54 INFO - 'SHELL': '/bin/bash', 13:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:54 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:54 INFO - 'USER': 'cltbld', 13:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:54 INFO - 'XPC_FLAGS': '0x0', 13:31:54 INFO - 'XPC_SERVICE_NAME': '0', 13:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:55 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)) 13:31:55 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)) 13:31:55 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)) 13:31:55 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)) 13:31:55 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)) 13:31:55 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)) 13:31:55 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)) 13:31:55 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)) 13:31:55 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)) 13:31:55 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)) 13:31:55 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)) 13:31:55 INFO - Cleaning up... 13:31:55 INFO - Return code: 0 13:31:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:31:55 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')]} 13:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:55 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')]} 13:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:55 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 0x10cdf4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdf7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa1c2d7ef40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, '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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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 13:31:55 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 13:31:55 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 13:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:55 INFO - 'HOME': '/Users/cltbld', 13:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:55 INFO - 'LOGNAME': 'cltbld', 13:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:55 INFO - 'MOZ_NO_REMOTE': '1', 13:31:55 INFO - 'NO_EM_RESTART': '1', 13:31:55 INFO - 'PAGER': '/bin/cat', 13:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:55 INFO - 'PWD': '/builds/slave/test', 13:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:55 INFO - 'SHELL': '/bin/bash', 13:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:31:55 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:55 INFO - 'USER': 'cltbld', 13:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:55 INFO - 'XPC_FLAGS': '0x0', 13:31:55 INFO - 'XPC_SERVICE_NAME': '0', 13:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:31:55 INFO - Cleaning up... 13:31:55 INFO - Return code: 0 13:31:55 INFO - Running post-action listener: _resource_record_post_action 13:31:55 INFO - Running post-action listener: _start_resource_monitoring 13:31:55 INFO - Starting resource monitoring. 13:31:55 INFO - [mozharness: 2017-04-20 20:31:55.542409Z] Finished create-virtualenv step (success) 13:31:55 INFO - [mozharness: 2017-04-20 20:31:55.543344Z] Running install step. 13:31:55 INFO - Running pre-action listener: _resource_record_pre_action 13:31:55 INFO - Running main action method: install 13:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:55 INFO - Reading from file tmpfile_stdout 13:31:55 INFO - Detecting whether we're running mozinstall >=1.0... 13:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:31:55 INFO - Reading from file tmpfile_stdout 13:31:55 INFO - Output received: 13:31:55 INFO - Usage: mozinstall [options] installer 13:31:55 INFO - Options: 13:31:55 INFO - -h, --help show this help message and exit 13:31:55 INFO - -d DEST, --destination=DEST 13:31:55 INFO - Directory to install application into. [default: 13:31:55 INFO - "/builds/slave/test"] 13:31:55 INFO - --app=APP Application being installed. [default: firefox] 13:31:55 INFO - mkdir: /builds/slave/test/build/application 13:31:55 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'] 13:31:55 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 13:32:15 INFO - Reading from file tmpfile_stdout 13:32:15 INFO - Output received: 13:32:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:32:15 INFO - Running post-action listener: _resource_record_post_action 13:32:15 INFO - [mozharness: 2017-04-20 20:32:15.368567Z] Finished install step (success) 13:32:15 INFO - [mozharness: 2017-04-20 20:32:15.368710Z] Running run-tests step. 13:32:15 INFO - Running pre-action listener: _resource_record_pre_action 13:32:15 INFO - Running main action method: run_tests 13:32:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:32:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:32:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:32:15 INFO - 2017-04-20 13:32:15.383 screenresolution[1621:7462] starting screenresolution argv=screenresolution get 13:32:15 INFO - 2017-04-20 13:32:15.385 screenresolution[1621:7462] Display 0: 1600x1200x32@60 13:32:15 INFO - 2017-04-20 13:32:15.391 screenresolution[1622:7464] starting screenresolution argv=screenresolution list 13:32:15 INFO - Available Modes on Display 0 13:32:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:32:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:32:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:32:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:32:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:32:15 INFO - Intel Iris: 13:32:15 INFO - Chipset Model: Intel Iris 13:32:15 INFO - Type: GPU 13:32:15 INFO - Bus: Built-In 13:32:15 INFO - VRAM (Dynamic, Max): 1536 MB 13:32:15 INFO - Vendor: Intel (0x8086) 13:32:15 INFO - Device ID: 0x0a2e 13:32:15 INFO - Revision ID: 0x0009 13:32:15 INFO - Displays: 13:32:15 INFO - Display: 13:32:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:32:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:32:15 INFO - Display Serial Number: 1600x1200 60 13:32:15 INFO - Main Display: Yes 13:32:15 INFO - Mirror: Off 13:32:15 INFO - Online: Yes 13:32:15 INFO - Rotation: Supported 13:32:15 INFO - Return code: 0 13:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:32:15 INFO - Python 2.7.3 13:32:15 INFO - Return code: 0 13:32:15 INFO - grabbing minidump binary from tooltool 13:32: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')]} 13:32:15 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 0x10cbd2d30>, '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 0x10caf8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cbc7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:32:15 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 13:32:15 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 13:32:15 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 13:32:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:32:15 INFO - Return code: 0 13:32:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:32:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:32:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:32:15 INFO - ENV: RUST_BACKTRACE is now 1 13:32:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:32:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:32:15 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/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--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 13:32:15 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/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0196 --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 13:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fGv5aldgcD/Render', 13:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:32:15 INFO - 'HOME': '/Users/cltbld', 13:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:32:15 INFO - 'LOGNAME': 'cltbld', 13:32:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:32:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:15 INFO - 'MOZ_NO_REMOTE': '1', 13:32:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:32:15 INFO - 'NO_EM_RESTART': '1', 13:32:15 INFO - 'PAGER': '/bin/cat', 13:32:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:15 INFO - 'PWD': '/builds/slave/test', 13:32:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:32:15 INFO - 'RUST_BACKTRACE': '1', 13:32:15 INFO - 'SHELL': '/bin/bash', 13:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sWDEZ2Jqo4/Listeners', 13:32:15 INFO - 'TMPDIR': '/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/', 13:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:32:15 INFO - 'USER': 'cltbld', 13:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:15 INFO - 'XPC_FLAGS': '0x0', 13:32:15 INFO - 'XPC_SERVICE_NAME': '0', 13:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:32:15 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/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0196', '--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 13:32:15 INFO - mozversion application_buildid: 20170420115229 13:32:15 INFO - mozversion application_changeset: 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 13:32:15 INFO - mozversion application_display_name: Nightly 13:32:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:32:15 INFO - mozversion application_name: Firefox 13:32:15 INFO - mozversion application_remotingname: firefox 13:32:15 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 13:32:15 INFO - mozversion application_vendor: Mozilla 13:32:15 INFO - mozversion application_version: 55.0a1 13:32:15 INFO - mozversion platform_buildid: 20170420115229 13:32:15 INFO - mozversion platform_changeset: 5d6e88c784ca72dd35b6f0a15701d867f8bd0061 13:32:15 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 13:32:15 INFO - mozversion platform_version: 55.0a1 13:32:15 INFO - using testdate: 1492720335 13:32:15 INFO - actual date: 1492720335 13:32:15 INFO - starting webserver on 'localhost:49241' 13:32:15 INFO - SUITE-START | Running 2 tests 13:32:15 INFO - TEST-START | dromaeo_css 13:32:15 INFO - operating with platform_type : mac_ 13:32:15 INFO - Initialising browser for dromaeo_css test... 13:32:15 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmp5jWEU1/profile http://localhost:49241/getInfo.html) 13:32:18 INFO - PID 1632 | 2017-04-20 13:32:18.754 plugin-container[1634:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x904b, name = 'com.apple.tsm.portname' 13:32:18 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:32:18 INFO - PID 1632 | 2017-04-20 13:32:18.756 plugin-container[1634:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 13:32:18 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:32:18 INFO - PID 1632 | 2017-04-20 13:32:18.756 plugin-container[1634:7645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:32:18 INFO - PID 1632 | __metrics Screen width/height:1600/1200 13:32:18 INFO - PID 1632 | colorDepth:24 13:32:18 INFO - PID 1632 | Browser inner width/height: 1024/689 13:32:18 INFO - PID 1632 | __metrics 13:32:18 ERROR - PID 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 13:32:19 INFO - TEST-INFO | 1632: exit 0 13:32:19 INFO - Browser initialized. 13:32:19 INFO - Running cycle 1/1 for dromaeo_css test... 13:32:19 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmp5jWEU1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:32:22 INFO - PID 1635 | 2017-04-20 13:32:22.118 plugin-container[1636:7786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 13:32:22 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:32:22 INFO - PID 1635 | 2017-04-20 13:32:22.118 plugin-container[1636:7786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 13:32:22 INFO - PID 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:32:22 INFO - PID 1635 | 2017-04-20 13:32:22.118 plugin-container[1636:7786] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:32:31 INFO - PID 1635 | RSS: Main: 235900928 13:32:31 INFO - PID 1635 | 13:35:35 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 13:35:37 INFO - PID 1635 | RSS: Main: 251899904 13:35:37 INFO - PID 1635 | 13:37:20 INFO - PID 1635 | ************************* 13:37:20 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 13:37:20 INFO - PID 1635 | 13:37:20 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:37:20 INFO - PID 1635 | Full stack: 13:37:20 INFO - PID 1635 | ************************* 13:37:20 INFO - PID 1635 | ************************* 13:37:20 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 13:37:20 INFO - PID 1635 | 13:37:20 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:37:20 INFO - PID 1635 | Full stack: 13:37:20 INFO - PID 1635 | ************************* 13:37:20 INFO - PID 1635 | ************************* 13:37:20 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 13:37:20 INFO - PID 1635 | 13:37:20 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:37:20 INFO - PID 1635 | Full stack: 13:37:20 INFO - PID 1635 | ************************* 13:37:20 INFO - PID 1635 | ************************* 13:37:20 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 13:37:20 INFO - PID 1635 | 13:37:20 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:37:20 INFO - PID 1635 | Full stack: 13:37:20 INFO - PID 1635 | ************************* 13:38:39 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 13:38:39 INFO - PID 1635 | RSS: Main: 255168512 13:38:39 INFO - PID 1635 | 13:41:41 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 13:41:42 INFO - PID 1635 | RSS: Main: 267169792 13:41:42 INFO - PID 1635 | 13:44:43 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 13:44:44 INFO - PID 1635 | RSS: Main: 260358144 13:44:44 INFO - PID 1635 | 13:47:47 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 13:47:48 INFO - PID 1635 | RSS: Main: 269557760 13:47:48 INFO - PID 1635 | 13:50:49 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 13:50:49 INFO - PID 1635 | RSS: Main: 264142848 13:50:49 INFO - PID 1635 | 13:50:49 INFO - PID 1635 | __start_tp_report 13:50:49 INFO - PID 1635 | _x_x_mozilla_page_load 13:50:49 INFO - PID 1635 | _x_x_mozilla_page_load_details 13:50:49 INFO - PID 1635 | |i|pagename|runs| 13:50:49 INFO - PID 1635 | |0;dojo.html;3158.952615710764;3566.9643303566963;3577.7674451160688;3600.3519366514015;3608.2963822054694;6091.390860913906;6123.3264340922515;6131.8773624527485;6214.254289485262;6236.12694222808;6146.524834039832;6149.800788846177;6188.628682279066;6194.628322300665;6200.255969283692;5399.757010934498;5459.12653975364;5460.34475862897;5471.316085489314;5550.611457197998;4530.456345238559;5458.344998600173;5462.508374246317;5470.617056806025;5551.084071128279;4698.248280275139;5485.561155107582;5521.723913804314;5539.584531160159;5549.694766787845;5317.335333083364;5363.919541206885;5364.785408583652;5396.676199428047;5402.540784033362;5398.757055932492;5432.076546987022;5437.483439073281;5444.373897001856;5488.588355873326;5969.582129250911;6178.62928224308;6223.751049957997;6236.407541283571;6261.5929964552615;6356.936430635681;6446.516511261627;6669.499787515956;6679.76620818269;6697.129373181504;5700.315962084576;6663.300353462865;6740.764073257462;6766.966165169192;6797.456203503708;6300.527460440394;6302.968485157545;6303.779367722108;6305.3379395163765;6328.335524769833;6590.077389165521;6796.388325050769;6964.686589103502;6965.616891070938;7001.579905205704;5010;6280.937190628035;6354.586951848115;6497.18785151856;6597.406233439014;6643.302453242437;6718.193816742022;6720.731170753213;6745.93254067453;6746.494012949049;5960.880782384328;6230.813075607648;6565.376289252514;6606.306337834554;6640.369164929421;5626.381098079208;6327.810165695037;6380.55336126476;6675.132232809704;6684.16447944007;5341.252224688546;5342.732863356894;5348.224507446398;5353.2237825515085;5374.892502150014;4728.3143944127905;4736.478987311393;4750.976245118753;4755.548222918817;4760.428748550196;5313.814016509481;6015.6691381974415;6274.372562743689;6286.371362863677;6373.808785736435;6722.193336799615;6728.6635668216395;6762.154730658668;6788.355106264897;6804.55770374924;6343.9682801585695;6347.016212486979;6364.968175159094;6365.681715914278;6369;5698.572607054488;5755.712214389264;5779.537636989031;5979.1629171915965;6013.579049466583;6885;6964.756233532004;6981.720731170696;6990.860182796418;6991.335823096798;6411.102445657519;6531.9020214695865;6622.569373482859;6704.128463299739;6726.932730672826;6860.416864566568;6877.89683154763;6967.65161741921;6972.44220462372;6982.895256571257;6383.39357761012;6422.293547709842;6673.899891501728;6678.964760462136;6700.832479187864;5650.7568334966245;6415.454686351713;6706.966465167643;6729.461643068641;6783.18601767791;6493.090967264681;6564.179477565304;6570.5729127606555;6574.112494813139;6587;6288.182536270256;6531.379518945692;6547.672616369067;6552.213734351908;6565.343465653396;6180.103884936569;6255.781047663492;6431.228252609717;6442.194725659293;6453.548251622341;6315.673708521262;6554.606723596599;6558.114654521579;6579.276279609335;6585.275619681735;4715.552022557851;6374.426301632875;6380.521460890544;6407.199100112486;6422.582532135583 13:50:49 INFO - PID 1635 | |1;ext.html;741.8961345411643;790.1624278265037;807.4993504027502;1253.1666441816199;1262.3498898067492;29562.852185739095;36215.63784362155;37357;37368.813155934215;37600.43599346009;37567.87296381113;37934;37948.81025594868;38002.619973800254;38158.809205954;7771.184025677293;8309.376796740238;8455.027671817728;8562.100979397166;8562.871556926651;6530.934690653104;7345.485815992883;8482.194191551793;8556.144385561456;8556.288742251543;7404.555726656391;8476.957615211915;8567.143285671445;8579.442336248125;8589.699360522383;8530.061693213773;8550.187732165497;8577.699780507683;8580.270676992463;8589.570521473965;7676.769696909102;8411.074781774061;8427.367947403969;8473.49159050459;8489.151084891524;37081.81459092714;37114.814425927965;37132.814335928146;37246.81376593127;37486.62513374886;3149.4173577888164;3605.4231322988194;3639.508666330038;3651.72612054097;3654.9217980695757;28896;29103.56344654832;29456.82172713089;29560.96536621208;29595.260118496994;3054.923626909323;3066.340736741611;3077.3999070181326;3092.78350515464;3104.4877595196767;297265;298349;300663;305509;305772.47113764513;47079.76460117677;47131.52868471271;47186.76406617945;47206.527934720216;47241.05517889692;49277.75361123243;49407;54002.45997540053;54803.72598137063;54984.17523737147;14211.715765684628;14293.356798944073;14301.499447519494;14304.85695143056;14319.427222910967;11327.603533876274;11368.886311136783;11394.658160255205;11404.65786026404;11415.828762568568;13898;13940.651483712785;14003.649908752363;14054.367553460119;14096;6226.62640241587;6251.562390632703;6272.153259310026;6273.937260627426;6280.685965701697;975.6682727872558;998.3211416236884;1017.4759998600726;1056.424248784399;1065.248999455382;24288.63567046496;24318.87840560786;24476.877615611807;24717;24770.752292477206;984.5077461269366;1085.159001773616;1104.6520346090956;1130.9773804523863;1135.0975974100609;11101.611443599435;11423.371714555613;12107.031437485157;12425;12476.81284780729;315789;316709;316844;317012;317333;47363.5263647366;47841;47855.76072119617;47869.9999999993;47941;1474.2112969561447;1479.3712672114523;1511.5238699809745;1524.4131009561177;1538.9461368852037;24066.87966560156;24147.879260603586;24211.75788242095;24244.908979096337;24279.271621851374;29888;30788.23029424282;37041.07397315089;37449.81275093607;37507.624923750416;235700.82149589143;259286.40713592622;285863;286560;288423;34973;34983.825080874434;35045.29909401849;35384.64615353814;35450.645493545766;43474.34788478112;44141.77929110463;44278.55721442744;44311.33532997072;44530.886727830766;29706.851465742533;30065.398692026476;30704;31019.84490077535;31092.378152437275;30274.4102065514;30718.84640576783;30912.381752365276;31052.29693212533;31122;32916.01251962445;34687;36671.89984300475;36711.63288367082;36750.44874326941;28984.42031159323;29995.850020749756;30758.38483230278;31214.21964450907;31561.52657710182;16322;20774.54578179513;21265.89367053155;22469.876506174955;26034 13:50:49 INFO - PID 1635 | |2;jquery.html;398.3765407137829;425.83392476933983;426.8058033594715;430.4705212588516;448.564892054707;20006.29977950774;21364.57270854582;21929;22112.88943555284;22168.556628867434;21929;22577.75822329771;22727;22833.771662283372;23052.654210186818;6155.538334624899;6316.431521163117;6338;6349.904751428732;6357.3006969233575;6092.32984371721;6113.449789518942;6221.59559628625;6286.46565041972;6326.430621244087;4709.364235828154;6253.874922501547;6265.091561723548;6268.059791031336;6275.560710750249;6162.876742465148;6174.938250617526;6222.595531290452;6227.71975261112;6230.283517395512;5448.509634132907;5526.143447765603;5549.1676248562635;5551.750171242304;5596.3284405871555;25855.70721463919;27257;27731.44537109267;27931.72068279332;28544.429111417656;2168.5988092202992;2209.0942713487393;2224.077008041661;2227.465408301996;2227.4765430123907;17654.205560749797;17745.378911738026;17963;18305.633887322314;18571.535711607183;2006.8193862552441;2122.980969134824;2185.530111026137;2208.5251670890834;2225.899834507451;121459;154013.45986540103;158429;158793;161216;12214;12745.808812867814;12821.551245706356;12890.226586404846;12926.095173337964;16573.917130414513;16587;16649.500514984327;16667;16675.583110422092;8047.678092876219;8794.252488538417;8963.462192268486;8980.550972451481;9056.683016094537;8499.235068843835;8512.829743405096;8551.957240213758;8695.999960004681;8699.521526315986;6748.291429399842;7955.363570914313;8051.5169089853625;8062.637181326854;8108.43240973138;2077.896105194734;2189.6168170570086;2194.714687090668;2197.549502352014;2197.9450513737283;940.8965013848472;957.5971202188827;970.335320305593;986.5757724178673;1003.40798928631;21461.89269053676;21692.674609880865;21730.348089557338;21807.127714891543;21831;970.9902900971041;1004.6232662751469;1010.6361709784471;1027.445179603021;1029.156092004564;10672.092872105957;10993.230473866752;11321.094312455149;11378.886211137948;11432.314061156358;454712;463163;513919.4304028456;520022;530349;120251.79748202581;124056;125208;126989;130552.3472382632;1171.1275100050516;1179.9823002654966;1226.0620625221713;1226.337777600086;1227.1348699167065;19879.527106400943;20752;20768.79231207729;22969.196078137185;23091.307260782203;55693;58205;58353.4164658348;58773;58927.410725892194;48973.020539588295;73899.63050184715;74812;75418;75710.62144689242;8814.955925220333;9154.450732956044;9301.162895339452;9364.20404265645;9422.152006319464;14263.07290026145;14518.274086295854;14706.13053254391;14763;14852;12097.274163550215;12144.574939877062;12206.511739530675;12399.760023997527;12505.374731263219;10222.948885255824;11616.070714343;11683.474243659226;11820.704482387666;11939.76120477603;12361.925031099625;12567.68580785451;12576.87423125757;12722.61832145037;12784.936075319563;8916.687915922912;8974.596143173443;9189.77025574366;9237.122473365289;9430.764230894283;9754.80490390174;11893.405329733305;12002.459889305177;12039.277643341427;12046.096542759504 13:50:49 INFO - PID 1635 | |3;mootools.html;1433.2045714628382;1435.6195608163832;1466.5527014260647;1501.579557723837;1512.0020786281054;9996.400215987027;10321.587136514549;11562.710932226688;11871;12051.337176455281;11327.886721132787;11788.941055294712;11811.70470738232;11815;11825.586104486345;2530.3421110511217;2557.577999630059;2565.679290088739;2572.3183356410473;2575.7681808637217;2500.6624105745673;2558.9360265993405;2567.833090849098;2568.434944312258;2569.871506424681;2517.546841568517;2526.06535581835;2536.6702328697243;2538.6318983747346;2543.160756950211;2081.5420607466335;2465.580851255291;2496.588062969617;2508.385445565839;2515.4466017476125;2170.316350349635;2470.1354525915967;2491.700995880506;2501.2871331670567;2511.5228106659674;11712.297262164297;11752.764944701059;11791.528338866436;11836.585719499777;11865.169438139334;6602.40578347951;6773.627450490221;6794.116764820542;6872.862542749117;6876;4037.8290295814177;4173.707840451169;4390.8902277443;4465.977670111661;4496.437945256843;1114.554178328667;1269.2574843716436;1298.4741179822188;1307.274462673216;1311.6458556189775;16617.41839035628;16743.581410464714;16892.746608800753;16901.070441125732;17106.230219640147;5589.329280486368;5611.551075913917;5900.822975310747;6018.518518518508;6325.177726895474;13708.725825483434;14094.929525352307;14161.716765664834;14170.929145354206;14218.786718199235;6270.902592046465;6597.30728273534;6937.445004399636;6938.340857618518;6940.41006514452;6124.418180272867;6268.811935641938;6304.684765761694;6346;6356.2054743157105;4427.380166776654;4581.931271030937;4611.262198048296;4619.769011549409;4665.2768820832825;1333.733253349334;1335.2255691698792;1341.2489006156584;1349.0691422918155;1352.7226918481688;792.37798328312;802.9357651387875;831.5717405536033;832.196929962589;857.1043259793531;6229.96885015572;6255.530835187379;6378.107065010902;6431.485481161496;6482.967585162044;596.9850753731105;600.9699515024231;605.1588292273743;617.8177437655902;622.2222222222222;4170.249355116108;4353.434053573079;4359.888228501712;4452.154090722753;4482.708623939434;14808.777868331983;15327.616809579627;15484.30320635574;15486.22568871552;15514;12417.75164496705;14913.403463861541;15334.156621385931;15409.460668876536;15411.152386618844;8425.578721063923;8586.65653373858;8673.956630216935;8684.60919258635;8691.695790647296;6840.2817704142335;6873.072135261876;7036.964815175891;7065.470089743392;7104.822379440556;13269.071165018742;13514.391852366864;13759.793603096161;13783.724325513635;13825;13674.863251367358;13796.724065518834;13846.93076534611;14039.683452230267;14109.647258818612;9454.574544145393;9731.805363892825;9741.561629726537;9754.024597540472;9756.121949024622;11080.501377437873;11098.778024439305;11170.329780213213;11197.992180703775;11200.047995920113;8952.641894324153;9358.251339892928;9388.859167112636;9467.905320946978;9625.614975400906;8809.735707928772;9207;9256.305777066613;9295.72112836616;9768.413895166314;8405.705800297204;8517;8520.14798520143;8520.3183745299;8534.91465085366;6130.846728831636;6132.9080063799975;6260.68696565179;6277.5605707600025;6337.429631333202;5670.8015219467125;6153.200083989232;6205.410486003822;6219.751209951733;6436.581622194542 13:50:49 INFO - PID 1635 | |4;prototype.html;366.8660938757354;384.1912773611548;408.0573874350249;412.56062293657254;412.9380592911063;4664.930026049612;5184.144616138337;5378.596605254602;5797.797077102292;6194.535409844263;5345.812896548621;5479.972600137004;5808.838232353535;5881.235439392884;5969.641821490703;4426.937534991611;4436.955630443703;4444.755538445384;4446.7554284514335;4478.708883922551;3895.454636350899;3906.726529142961;4316.223079845627;4429.114177164563;4457.551295828853;3821.445890345898;4392.099619577994;4406.272964960777;4418.911621767563;4423.358613001113;4432.933505997396;4436.378906953029;4463.888402789924;4473.642108631334;4477.888052798689;3075.938801113612;3669.1194113412857;3681.852725890961;3842.9231415371814;3847.1920896611746;5610.523105536035;5841.474267315962;5866.296044474648;5900.291964964232;5920.763169473216;5286.233496143076;5294.385212510183;5296.152615581527;5320.733963301819;5342.225377320306;6151.477124444427;6286.277078135981;6390.041493775924;6424.068510066014;6430.324815894357;3948.3682610782425;4253.638440732541;4327.3725309830215;4354.499232588261;4379.671524635665;4970.87572810679;6404.903926441154;6650.339864054368;6721.7647382341875;6768.357006084414;3980.6616437602547;4107.507099148062;4192.203481338536;4391.956080439155;4405.7797110144365;3772.8490860365278;4184.644305234028;4490.955090449119;4498.8425405110665;4500.254844544825;5023.045621331935;5074.858644580724;5230.607704422184;5264.525672933715;5292.338457692788;5548.195511650788;5560.388357280696;5617.466340697627;6494.188226471691;6502.959526475835;1502.646877983673;1526.6641338905424;1527.4730218074747;1532.8773698104126;1539.4227164813194;807.8828569857337;985.280745056113;1010.5099026971913;1020.4897551224387;1024.1192574386023;5329.626926115135;6141.385861413823;6176.197094377792;6189.257289125334;6189.566730328834;5585.659441733955;6705;6722.831929201809;6723.193216813916;6738.292479289702;5825.679587622722;6124.295705993779;6141.5260337518675;6159.815205543751;6177.2511232502;3319.9834000830324;3440.415129428003;3500.544929159193;3506.474028895686;3534.6111927687934;6280.717367718464;6689.5986240825705;6876.449884009267;6934.503578711659;6974;5462.999999999921;5722.055860782924;6844.965775171092;6857.588544687334;6968.547044442095;5534.806281780118;5837.328707198726;6474.061261117112;6702.597844129368;6963.442924566022;5377.784888604412;6615.735370585316;6621.602703837785;6640.203175618956;6653.933460665331;5777.884442311214;6021.518278537619;6592.637404942774;6594.10979517759;6618.63597502142;5476.370217424887;6282.780102696383;6782.084418603623;6883.931160688329;6893;3879.224155169034;4271.786410679391;4385.407969924019;4388.376300768736;4390.07808360248;5630.60585758993;6270.0908368287255;6570.244421891352;6668.766593169215;6709.329067093447;5970.3432622412365;6176.258848938156;6421.422072013354;6439.645819479786;6480.967595161994;6206.6451255262655;6323.4625056868845;6385.201849768779;6450.806475805545;6496.805095846943;6270.905936411049;6288.622682639057;6320.557560970872;6326.9683651581445;6329.8734025320155;5860.0037993542055;5921.3190483094995;6423.807285781434;6454.257760357619;6480.935190648033;5015.322931404214;5961.672108033927;5995.400459953969;6248.687565621792;6262.279837818709 13:50:49 INFO - PID 1635 | |5;yui.html;820.7783898347443;830.0744669693294;843.4517563583672;856.2037305306067;866.4281574161051;13754.931225343884;16570.33718651254;16962.236699348545;17049;18723.719144212846;17846.28614855404;17931.462056138305;18081;18110.63778724428;18296.634067318646;955.928305377096;976.5215044628152;978.0366339155926;983.1151963233111;984.0208992052882;971.5239532628996;981.2346369831542;994.0953732103787;995.5470261031213;995.5918073589829;1025.5282570017764;1031.1389989358856;1037.704254287528;1039.7192757955347;1042.6246551241547;1018.4907546226887;1026.9383836969805;1034.1726618705043;1035.6427032673716;1035.6737627647267;766.2414209932183;770.730244414456;771.2634434115463;773.6441237030916;774.5855967057597;16768.58078548034;17073.82926170735;17272.827271727372;17351.82648173515;17444.040577768217;13991.440342386293;17218.741718874226;17247.913760431242;17426.65146697059;17427.73858392125;13649.658758531017;13873;13926.234057126854;13994.790078148835;14047.578572642837;3070.2784845561273;3289.7663376233913;3489.5638045244345;3507.877224297137;3509.3858574749315;92073;102902.97097029009;108836.36745448825;113886.86113138846;119023.4048829759;12892.935535322264;13125.474981000652;13186;13259.403326850315;13260.336983150804;24480.755192447847;24990.125345612818;25016.749832501442;25197.496050078895;25211.621825672628;7737;7897.881531777028;7904.6442910068035;7905.881411778828;7912.367010639135;3840.251151025579;3858.3633700439673;3868.207017561393;3910.569837317893;3924.6467817895955;4300.580808333242;4342.08816148606;4350.21696094706;4363.345498175299;4392.714473559208;929.3912487320681;963.629002833914;977.4086677560116;993.1657407777501;995.1392045880261;654.6432194454034;654.8002859128012;661.1438187547138;665.890128128863;677.8237658208901;11337.206395552232;11617.593384231512;11969.222000569935;12107.818382724266;12183.939080304543;630.338144947803;660.5607271164622;662.3608218069586;676.4689718570899;686.8797960356918;5086.415062267813;5524.005678977823;5557.138643510262;5675.1770993205755;5743.2246646703;105617;106839;107256;107348.46325768478;109259;94893;95212;96822;97388.51305743426;99073;85215;85806.57096714477;86644;87064;88247.11752882517;8820.11798820113;10640.84038739404;10969.945150274198;11072.778544428906;11293;40392.3941140889;40629.796851015555;41089.58910410858;41515.7924210377;41750.58249417467;49318.50681493139;49318.75340623274;49544.75227623839;49657.26199582997;50021.49978500168;14172.574822755334;14208.57374278732;14397.64005899819;14491.565253042425;14535.491257805927;15163.620909477351;15329.38682452734;15372.846271537588;15541.766873497132;15622.062676239462;12167.391630418266;14593.124412535282;14654.413823446943;14725.55823325302;14821.703565928405;14391.208483533088;14404.495842645458;14449.422023119378;14603.415863365346;14723.705525889636;14288.214148221947;14289.857101428852;14307.499237526637;14372.568822935329;14460.783088253474;10441;10456.895431045896;10480.842787358348;11037.448127593749;11053.447327633425;11406.771864562828;14004.229767362893;14013.229272390117;14047.508337208148;14102.78845817293 13:50:49 INFO - PID 1635 | __end_tp_report 13:50:49 INFO - PID 1635 | __start_cc_report 13:50:49 INFO - PID 1635 | _x_x_mozilla_cycle_collect,433 13:50:49 INFO - PID 1635 | __end_cc_report 13:50:49 INFO - PID 1635 | __startTimestamp1492721449965__endTimestamp 13:50:49 INFO - PID 1635 | 13:50:49 INFO - PID 1635 | ------- Summary: start ------- 13:50:49 INFO - PID 1635 | Number of tests: 6 13:50:49 INFO - PID 1635 | 13:50:49 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:6104.43 Median:6343.97 stddev:750.01 (11.8%) stddev-sans-first:715.83 13:50:49 INFO - PID 1635 | Values: 3159.0 3567.0 3577.8 3600.4 3608.3 6091.4 6123.3 6131.9 6214.3 6236.1 6146.5 6149.8 6188.6 6194.6 6200.3 5399.8 5459.1 5460.3 5471.3 5550.6 4530.5 5458.3 5462.5 5470.6 5551.1 4698.2 5485.6 5521.7 5539.6 5549.7 5317.3 5363.9 5364.8 5396.7 5402.5 5398.8 5432.1 5437.5 5444.4 5488.6 5969.6 6178.6 6223.8 6236.4 6261.6 6356.9 6446.5 6669.5 6679.8 6697.1 5700.3 6663.3 6740.8 6767.0 6797.5 6300.5 6303.0 6303.8 6305.3 6328.3 6590.1 6796.4 6964.7 6965.6 7001.6 5010.0 6280.9 6354.6 6497.2 6597.4 6643.3 6718.2 6720.7 6745.9 6746.5 5960.9 6230.8 6565.4 6606.3 6640.4 5626.4 6327.8 6380.6 6675.1 6684.2 5341.3 5342.7 5348.2 5353.2 5374.9 4728.3 4736.5 4751.0 4755.5 4760.4 5313.8 6015.7 6274.4 6286.4 6373.8 6722.2 6728.7 6762.2 6788.4 6804.6 6344.0 6347.0 6365.0 6365.7 6369.0 5698.6 5755.7 5779.5 5979.2 6013.6 6885.0 6964.8 6981.7 6990.9 6991.3 6411.1 6531.9 6622.6 6704.1 6726.9 6860.4 6877.9 6967.7 6972.4 6982.9 6383.4 6422.3 6673.9 6679.0 6700.8 5650.8 6415.5 6707.0 6729.5 6783.2 6493.1 6564.2 6570.6 6574.1 6587.0 6288.2 6531.4 6547.7 6552.2 6565.3 6180.1 6255.8 6431.2 6442.2 6453.5 6315.7 6554.6 6558.1 6579.3 6585.3 4715.6 6374.4 6380.5 6407.2 6422.6 13:50:49 INFO - PID 1635 | 13:50:49 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:44408.25 Median:316928.00 stddev:77971.15 (24.6%) stddev-sans-first:78120.95 13:50:49 INFO - PID 1635 | Values: 741.9 790.2 807.5 1253.2 1262.3 29562.9 36215.6 37357.0 37368.8 37600.4 37567.9 37934.0 37948.8 38002.6 38158.8 7771.2 8309.4 8455.0 8562.1 8562.9 6530.9 7345.5 8482.2 8556.1 8556.3 7404.6 8477.0 8567.1 8579.4 8589.7 8530.1 8550.2 8577.7 8580.3 8589.6 7676.8 8411.1 8427.4 8473.5 8489.2 37081.8 37114.8 37132.8 37246.8 37486.6 3149.4 3605.4 3639.5 3651.7 3654.9 28896.0 29103.6 29456.8 29561.0 29595.3 3054.9 3066.3 3077.4 3092.8 3104.5 297265.0 298349.0 300663.0 305509.0 305772.5 47079.8 47131.5 47186.8 47206.5 47241.1 49277.8 49407.0 54002.5 54803.7 54984.2 14211.7 14293.4 14301.5 14304.9 14319.4 11327.6 11368.9 11394.7 11404.7 11415.8 13898.0 13940.7 14003.6 14054.4 14096.0 6226.6 6251.6 6272.2 6273.9 6280.7 975.7 998.3 1017.5 1056.4 1065.2 24288.6 24318.9 24476.9 24717.0 24770.8 984.5 1085.2 1104.7 1131.0 1135.1 11101.6 11423.4 12107.0 12425.0 12476.8 315789.0 316709.0 316844.0 317012.0 317333.0 47363.5 47841.0 47855.8 47870.0 47941.0 1474.2 1479.4 1511.5 1524.4 1538.9 24066.9 24147.9 24211.8 24244.9 24279.3 29888.0 30788.2 37041.1 37449.8 37507.6 235700.8 259286.4 285863.0 286560.0 288423.0 34973.0 34983.8 35045.3 35384.6 35450.6 43474.3 44141.8 44278.6 44311.3 44530.9 29706.9 30065.4 30704.0 31019.8 31092.4 30274.4 30718.8 30912.4 31052.3 31122.0 32916.0 34687.0 36671.9 36711.6 36750.4 28984.4 29995.9 30758.4 31214.2 31561.5 16322.0 20774.5 21265.9 22469.9 26034.0 13:50:49 INFO - PID 1635 | 13:50:49 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:33891.45 Median:2226.68 stddev:84896.68 (3812.7%) stddev-sans-first:85097.59 13:50:49 INFO - PID 1635 | Values: 398.4 425.8 426.8 430.5 448.6 20006.3 21364.6 21929.0 22112.9 22168.6 21929.0 22577.8 22727.0 22833.8 23052.7 6155.5 6316.4 6338.0 6349.9 6357.3 6092.3 6113.4 6221.6 6286.5 6326.4 4709.4 6253.9 6265.1 6268.1 6275.6 6162.9 6174.9 6222.6 6227.7 6230.3 5448.5 5526.1 5549.2 5551.8 5596.3 25855.7 27257.0 27731.4 27931.7 28544.4 2168.6 2209.1 2224.1 2227.5 2227.5 17654.2 17745.4 17963.0 18305.6 18571.5 2006.8 2123.0 2185.5 2208.5 2225.9 121459.0 154013.5 158429.0 158793.0 161216.0 12214.0 12745.8 12821.6 12890.2 12926.1 16573.9 16587.0 16649.5 16667.0 16675.6 8047.7 8794.3 8963.5 8980.6 9056.7 8499.2 8512.8 8552.0 8696.0 8699.5 6748.3 7955.4 8051.5 8062.6 8108.4 2077.9 2189.6 2194.7 2197.5 2197.9 940.9 957.6 970.3 986.6 1003.4 21461.9 21692.7 21730.3 21807.1 21831.0 971.0 1004.6 1010.6 1027.4 1029.2 10672.1 10993.2 11321.1 11378.9 11432.3 454712.0 463163.0 513919.4 520022.0 530349.0 120251.8 124056.0 125208.0 126989.0 130552.3 1171.1 1180.0 1226.1 1226.3 1227.1 19879.5 20752.0 20768.8 22969.2 23091.3 55693.0 58205.0 58353.4 58773.0 58927.4 48973.0 73899.6 74812.0 75418.0 75710.6 8815.0 9154.5 9301.2 9364.2 9422.2 14263.1 14518.3 14706.1 14763.0 14852.0 12097.3 12144.6 12206.5 12399.8 12505.4 10222.9 11616.1 11683.5 11820.7 11939.8 12361.9 12567.7 12576.9 12722.6 12784.9 8916.7 8974.6 9189.8 9237.1 9430.8 9754.8 11893.4 12002.5 12039.3 12046.1 13:50:49 INFO - PID 1635 | 13:50:49 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:7287.64 Median:4171.98 stddev:4634.59 (111.1%) stddev-sans-first:4626.71 13:50:49 INFO - PID 1635 | Values: 1433.2 1435.6 1466.6 1501.6 1512.0 9996.4 10321.6 11562.7 11871.0 12051.3 11327.9 11788.9 11811.7 11815.0 11825.6 2530.3 2557.6 2565.7 2572.3 2575.8 2500.7 2558.9 2567.8 2568.4 2569.9 2517.5 2526.1 2536.7 2538.6 2543.2 2081.5 2465.6 2496.6 2508.4 2515.4 2170.3 2470.1 2491.7 2501.3 2511.5 11712.3 11752.8 11791.5 11836.6 11865.2 6602.4 6773.6 6794.1 6872.9 6876.0 4037.8 4173.7 4390.9 4466.0 4496.4 1114.6 1269.3 1298.5 1307.3 1311.6 16617.4 16743.6 16892.7 16901.1 17106.2 5589.3 5611.6 5900.8 6018.5 6325.2 13708.7 14094.9 14161.7 14170.9 14218.8 6270.9 6597.3 6937.4 6938.3 6940.4 6124.4 6268.8 6304.7 6346.0 6356.2 4427.4 4581.9 4611.3 4619.8 4665.3 1333.7 1335.2 1341.2 1349.1 1352.7 792.4 802.9 831.6 832.2 857.1 6230.0 6255.5 6378.1 6431.5 6483.0 597.0 601.0 605.2 617.8 622.2 4170.2 4353.4 4359.9 4452.2 4482.7 14808.8 15327.6 15484.3 15486.2 15514.0 12417.8 14913.4 15334.2 15409.5 15411.2 8425.6 8586.7 8674.0 8684.6 8691.7 6840.3 6873.1 7037.0 7065.5 7104.8 13269.1 13514.4 13759.8 13783.7 13825.0 13674.9 13796.7 13846.9 14039.7 14109.6 9454.6 9731.8 9741.6 9754.0 9756.1 11080.5 11098.8 11170.3 11198.0 11200.0 8952.6 9358.3 9388.9 9467.9 9625.6 8809.7 9207.0 9256.3 9295.7 9768.4 8405.7 8517.0 8520.1 8520.3 8534.9 6130.8 6132.9 6260.7 6277.6 6337.4 5670.8 6153.2 6205.4 6219.8 6436.6 13:50:49 INFO - PID 1635 | 13:50:49 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:5141.16 Median:5676.33 stddev:1610.87 (28.4%) stddev-sans-first:1575.03 13:50:49 INFO - PID 1635 | Values: 366.9 384.2 408.1 412.6 412.9 4664.9 5184.1 5378.6 5797.8 6194.5 5345.8 5480.0 5808.8 5881.2 5969.6 4426.9 4437.0 4444.8 4446.8 4478.7 3895.5 3906.7 4316.2 4429.1 4457.6 3821.4 4392.1 4406.3 4418.9 4423.4 4432.9 4436.4 4463.9 4473.6 4477.9 3075.9 3669.1 3681.9 3842.9 3847.2 5610.5 5841.5 5866.3 5900.3 5920.8 5286.2 5294.4 5296.2 5320.7 5342.2 6151.5 6286.3 6390.0 6424.1 6430.3 3948.4 4253.6 4327.4 4354.5 4379.7 4970.9 6404.9 6650.3 6721.8 6768.4 3980.7 4107.5 4192.2 4392.0 4405.8 3772.8 4184.6 4491.0 4498.8 4500.3 5023.0 5074.9 5230.6 5264.5 5292.3 5548.2 5560.4 5617.5 6494.2 6503.0 1502.6 1526.7 1527.5 1532.9 1539.4 807.9 985.3 1010.5 1020.5 1024.1 5329.6 6141.4 6176.2 6189.3 6189.6 5585.7 6705.0 6722.8 6723.2 6738.3 5825.7 6124.3 6141.5 6159.8 6177.3 3320.0 3440.4 3500.5 3506.5 3534.6 6280.7 6689.6 6876.4 6934.5 6974.0 5463.0 5722.1 6845.0 6857.6 6968.5 5534.8 5837.3 6474.1 6702.6 6963.4 5377.8 6615.7 6621.6 6640.2 6653.9 5777.9 6021.5 6592.6 6594.1 6618.6 5476.4 6282.8 6782.1 6883.9 6893.0 3879.2 4271.8 4385.4 4388.4 4390.1 5630.6 6270.1 6570.2 6668.8 6709.3 5970.3 6176.3 6421.4 6439.6 6481.0 6206.6 6323.5 6385.2 6450.8 6496.8 6270.9 6288.6 6320.6 6327.0 6329.9 5860.0 5921.3 6423.8 6454.3 6480.9 5015.3 5961.7 5995.4 6248.7 6262.3 13:50:49 INFO - PID 1635 | 13:50:49 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:21150.68 Median:18203.64 stddev:29866.25 (164.1%) stddev-sans-first:29911.02 13:50:49 INFO - PID 1635 | Values: 820.8 830.1 843.5 856.2 866.4 13754.9 16570.3 16962.2 17049.0 18723.7 17846.3 17931.5 18081.0 18110.6 18296.6 955.9 976.5 978.0 983.1 984.0 971.5 981.2 994.1 995.5 995.6 1025.5 1031.1 1037.7 1039.7 1042.6 1018.5 1026.9 1034.2 1035.6 1035.7 766.2 770.7 771.3 773.6 774.6 16768.6 17073.8 17272.8 17351.8 17444.0 13991.4 17218.7 17247.9 17426.7 17427.7 13649.7 13873.0 13926.2 13994.8 14047.6 3070.3 3289.8 3489.6 3507.9 3509.4 92073.0 102903.0 108836.4 113886.9 119023.4 12892.9 13125.5 13186.0 13259.4 13260.3 24480.8 24990.1 25016.7 25197.5 25211.6 7737.0 7897.9 7904.6 7905.9 7912.4 3840.3 3858.4 3868.2 3910.6 3924.6 4300.6 4342.1 4350.2 4363.3 4392.7 929.4 963.6 977.4 993.2 995.1 654.6 654.8 661.1 665.9 677.8 11337.2 11617.6 11969.2 12107.8 12183.9 630.3 660.6 662.4 676.5 686.9 5086.4 5524.0 5557.1 5675.2 5743.2 105617.0 106839.0 107256.0 107348.5 109259.0 94893.0 95212.0 96822.0 97388.5 99073.0 85215.0 85806.6 86644.0 87064.0 88247.1 8820.1 10640.8 10969.9 11072.8 11293.0 40392.4 40629.8 41089.6 41515.8 41750.6 49318.5 49318.8 49544.8 49657.3 50021.5 14172.6 14208.6 14397.6 14491.6 14535.5 15163.6 15329.4 15372.8 15541.8 15622.1 12167.4 14593.1 14654.4 14725.6 14821.7 14391.2 14404.5 14449.4 14603.4 14723.7 14288.2 14289.9 14307.5 14372.6 14460.8 10441.0 10456.9 10480.8 11037.4 11053.4 11406.8 14004.2 14013.2 14047.5 14102.8 13:50:49 INFO - PID 1635 | -------- Summary: end -------- 13:50:49 INFO - PID 1635 | 13:50:50 INFO - TEST-INFO | 1635: exit 0 13:50:50 INFO - TEST-OK | dromaeo_css | took 1114754ms 13:50:50 INFO - TEST-START | kraken 13:50:50 INFO - operating with platform_type : mac_ 13:50:50 INFO - Initialising browser for kraken test... 13:50:50 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpQiXmmb/profile http://localhost:49241/getInfo.html) 13:50:53 INFO - PID 1646 | 2017-04-20 13:50:53.656 plugin-container[1647:10155] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b33, name = 'com.apple.tsm.portname' 13:50:53 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:50:53 INFO - PID 1646 | 2017-04-20 13:50:53.661 plugin-container[1647:10155] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 13:50:53 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:50:53 INFO - PID 1646 | 2017-04-20 13:50:53.661 plugin-container[1647:10155] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:50:53 INFO - PID 1646 | __metrics Screen width/height:1600/1200 13:50:53 INFO - PID 1646 | colorDepth:24 13:50:53 INFO - PID 1646 | Browser inner width/height: 1024/689 13:50:53 INFO - PID 1646 | __metrics 13:50:53 ERROR - PID 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 13:50:54 INFO - TEST-INFO | 1646: exit 0 13:50:54 INFO - Browser initialized. 13:50:54 INFO - Running cycle 1/1 for kraken test... 13:50:54 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/T/tmpQiXmmb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:50:57 INFO - PID 1648 | 2017-04-20 13:50:57.061 plugin-container[1649:10277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x882b, name = 'com.apple.tsm.portname' 13:50:57 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:50:57 INFO - PID 1648 | 2017-04-20 13:50:57.061 plugin-container[1649:10277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d1f, name = 'com.apple.CFPasteboardClient' 13:50:57 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:50:57 INFO - PID 1648 | 2017-04-20 13:50:57.061 plugin-container[1649:10277] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:51:06 INFO - PID 1648 | RSS: Main: 237539328 13:51:06 INFO - PID 1648 | 13:51:09 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 13:51:10 INFO - PID 1648 | RSS: Main: 249753600 13:51:10 INFO - PID 1648 | 13:51:13 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 13:51:13 INFO - PID 1648 | RSS: Main: 257855488 13:51:13 INFO - PID 1648 | 13:51:17 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 13:51:17 INFO - PID 1648 | RSS: Main: 260284416 13:51:17 INFO - PID 1648 | 13:51:19 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 13:51:20 INFO - PID 1648 | RSS: Main: 261345280 13:51:20 INFO - PID 1648 | 13:51:22 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:51:23 INFO - PID 1648 | RSS: Main: 263118848 13:51:23 INFO - PID 1648 | 13:51:26 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 13:51:26 INFO - PID 1648 | RSS: Main: 268333056 13:51:26 INFO - PID 1648 | 13:51:29 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 13:51:30 INFO - PID 1648 | RSS: Main: 269639680 13:51:30 INFO - PID 1648 | 13:51:33 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 13:51:34 INFO - PID 1648 | RSS: Main: 269406208 13:51:34 INFO - PID 1648 | 13:51:35 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:51:36 INFO - PID 1648 | RSS: Main: 269344768 13:51:36 INFO - PID 1648 | 13:51:38 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:51:38 INFO - PID 1648 | RSS: Main: 269205504 13:51:38 INFO - PID 1648 | 13:51:41 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:51:41 INFO - PID 1648 | RSS: Main: 268861440 13:51:41 INFO - PID 1648 | 13:51:44 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:51:45 INFO - PID 1648 | RSS: Main: 269275136 13:51:45 INFO - PID 1648 | 13:51:48 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:51:49 INFO - PID 1648 | RSS: Main: 269258752 13:51:49 INFO - PID 1648 | 13:51:50 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 13:51:51 INFO - PID 1648 | RSS: Main: 271007744 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | __start_tp_report 13:51:51 INFO - PID 1648 | _x_x_mozilla_page_load 13:51:51 INFO - PID 1648 | _x_x_mozilla_page_load_details 13:51:51 INFO - PID 1648 | |i|pagename|runs| 13:51:51 INFO - PID 1648 | |0;ai-astar;134;167;115;116;118;115;115;117;118;116 13:51:51 INFO - PID 1648 | |1;audio-beat-detection;146;110;112;154;111;110;113;114;136;126 13:51:51 INFO - PID 1648 | |2;audio-dft;198;224;188;201;213;265;277;222;244;268 13:51:51 INFO - PID 1648 | |3;audio-fft;79;58;74;89;59;57;49;70;94;81 13:51:51 INFO - PID 1648 | |4;audio-oscillator;82;68;86;70;69;68;87;71;74;107 13:51:51 INFO - PID 1648 | |5;imaging-gaussian-blur;90;90;91;135;98;89;90;107;89;90 13:51:51 INFO - PID 1648 | |6;imaging-darkroom;96;96;97;118;96;152;150;117;105;150 13:51:51 INFO - PID 1648 | |7;imaging-desaturate;87;95;90;92;107;145;141;93;93;99 13:51:51 INFO - PID 1648 | |8;json-parse-financial;49;52;51;54;54;69;49;56;61;72 13:51:51 INFO - PID 1648 | |9;json-stringify-tinderbox;57;69;74;62;65;61;55;57;57;57 13:51:51 INFO - PID 1648 | |10;stanford-crypto-aes;82;90;104;106;84;83;84;105;91;85 13:51:51 INFO - PID 1648 | |11;stanford-crypto-ccm;139;152;141;135;137;149;156;144;196;153 13:51:51 INFO - PID 1648 | |12;stanford-crypto-pbkdf2;184;189;185;185;183;208;181;163;228;227 13:51:51 INFO - PID 1648 | |13;stanford-crypto-sha256-iterative;69;69;70;69;69;68;69;67;73;66 13:51:51 INFO - PID 1648 | __end_tp_report 13:51:51 INFO - PID 1648 | __start_cc_report 13:51:51 INFO - PID 1648 | _x_x_mozilla_cycle_collect,1001 13:51:51 INFO - PID 1648 | __end_cc_report 13:51:51 INFO - PID 1648 | __startTimestamp1492721511063__endTimestamp 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | ------- Summary: start ------- 13:51:51 INFO - PID 1648 | Number of tests: 14 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#0] ai-astar Cycles:10 Average:123.10 Median:117.50 stddev:16.44 (14.0%) stddev-sans-first:16.96 13:51:51 INFO - PID 1648 | Values: 134.0 167.0 115.0 116.0 118.0 115.0 115.0 117.0 118.0 116.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:120.00 stddev:16.50 (13.8%) stddev-sans-first:15.31 13:51:51 INFO - PID 1648 | Values: 146.0 110.0 112.0 154.0 111.0 110.0 113.0 114.0 136.0 126.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#2] audio-dft Cycles:10 Average:230.00 Median:234.00 stddev:31.78 (13.6%) stddev-sans-first:31.53 13:51:51 INFO - PID 1648 | Values: 198.0 224.0 188.0 201.0 213.0 265.0 277.0 222.0 244.0 268.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#3] audio-fft Cycles:10 Average:71.00 Median:76.50 stddev:14.98 (19.6%) stddev-sans-first:15.61 13:51:51 INFO - PID 1648 | Values: 79.0 58.0 74.0 89.0 59.0 57.0 49.0 70.0 94.0 81.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:72.50 stddev:12.52 (17.3%) stddev-sans-first:13.21 13:51:51 INFO - PID 1648 | Values: 82.0 68.0 86.0 70.0 69.0 68.0 87.0 71.0 74.0 107.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:90.00 stddev:14.55 (16.2%) stddev-sans-first:15.22 13:51:51 INFO - PID 1648 | Values: 90.0 90.0 91.0 135.0 98.0 89.0 90.0 107.0 89.0 90.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#6] imaging-darkroom Cycles:10 Average:117.70 Median:124.00 stddev:24.19 (19.5%) stddev-sans-first:24.35 13:51:51 INFO - PID 1648 | Values: 96.0 96.0 97.0 118.0 96.0 152.0 150.0 117.0 105.0 150.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#7] imaging-desaturate Cycles:10 Average:104.20 Median:92.50 stddev:21.18 (22.9%) stddev-sans-first:21.53 13:51:51 INFO - PID 1648 | Values: 87.0 95.0 90.0 92.0 107.0 145.0 141.0 93.0 93.0 99.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:55.00 stddev:8.11 (14.7%) stddev-sans-first:8.11 13:51:51 INFO - PID 1648 | Values: 49.0 52.0 51.0 54.0 54.0 69.0 49.0 56.0 61.0 72.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:61.50 stddev:6.22 (10.1%) stddev-sans-first:6.39 13:51:51 INFO - PID 1648 | Values: 57.0 69.0 74.0 62.0 65.0 61.0 55.0 57.0 57.0 57.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#10] stanford-crypto-aes Cycles:10 Average:91.40 Median:84.00 stddev:9.82 (11.7%) stddev-sans-first:9.81 13:51:51 INFO - PID 1648 | Values: 82.0 90.0 104.0 106.0 84.0 83.0 84.0 105.0 91.0 85.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:150.50 stddev:17.63 (11.7%) stddev-sans-first:18.23 13:51:51 INFO - PID 1648 | Values: 139.0 152.0 141.0 135.0 137.0 149.0 156.0 144.0 196.0 153.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.30 Median:187.00 stddev:21.01 (11.2%) stddev-sans-first:22.02 13:51:51 INFO - PID 1648 | Values: 184.0 189.0 185.0 185.0 183.0 208.0 181.0 163.0 228.0 227.0 13:51:51 INFO - PID 1648 | 13:51:51 INFO - PID 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.85 (2.7%) stddev-sans-first:1.96 13:51:51 INFO - PID 1648 | Values: 69.0 69.0 70.0 69.0 69.0 68.0 69.0 67.0 73.0 66.0 13:51:51 INFO - PID 1648 | -------- Summary: end -------- 13:51:51 INFO - PID 1648 | 13:51:52 INFO - TEST-INFO | 1648: exit 0 13:51:52 INFO - TEST-OK | kraken | took 61375ms 13:51:52 INFO - SUITE-END | took 1176s 13:51:52 INFO - Completed test suite (00:19:36) 13:51:52 INFO - javascript benchmark 13:51:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7812.525674736941, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3158.952615710764, 3566.9643303566963, 3577.7674451160688, 3600.3519366514015, 3608.2963822054694, 6091.390860913906, 6123.3264340922515, 6131.8773624527485, 6214.254289485262, 6236.12694222808, 6146.524834039832, 6149.800788846177, 6188.628682279066, 6194.628322300665, 6200.255969283692, 5399.757010934498, 5459.12653975364, 5460.34475862897, 5471.316085489314, 5550.611457197998, 4530.456345238559, 5458.344998600173, 5462.508374246317, 5470.617056806025, 5551.084071128279, 4698.248280275139, 5485.561155107582, 5521.723913804314, 5539.584531160159, 5549.694766787845, 5317.335333083364, 5363.919541206885, 5364.785408583652, 5396.676199428047, 5402.540784033362, 5398.757055932492, 5432.076546987022, 5437.483439073281, 5444.373897001856, 5488.588355873326, 5969.582129250911, 6178.62928224308, 6223.751049957997, 6236.407541283571, 6261.5929964552615, 6356.936430635681, 6446.516511261627, 6669.499787515956, 6679.76620818269, 6697.129373181504, 5700.315962084576, 6663.300353462865, 6740.764073257462, 6766.966165169192, 6797.456203503708, 6300.527460440394, 6302.968485157545, 6303.779367722108, 6305.3379395163765, 6328.335524769833, 6590.077389165521, 6796.388325050769, 6964.686589103502, 6965.616891070938, 7001.579905205704, 5010.0, 6280.937190628035, 6354.586951848115, 6497.18785151856, 6597.406233439014, 6643.302453242437, 6718.193816742022, 6720.731170753213, 6745.93254067453, 6746.494012949049, 5960.880782384328, 6230.813075607648, 6565.376289252514, 6606.306337834554, 6640.369164929421, 5626.381098079208, 6327.810165695037, 6380.55336126476, 6675.132232809704, 6684.16447944007, 5341.252224688546, 5342.732863356894, 5348.224507446398, 5353.2237825515085, 5374.892502150014, 4728.3143944127905, 4736.478987311393, 4750.976245118753, 4755.548222918817, 4760.428748550196, 5313.814016509481, 6015.6691381974415, 6274.372562743689, 6286.371362863677, 6373.808785736435, 6722.193336799615, 6728.6635668216395, 6762.154730658668, 6788.355106264897, 6804.55770374924, 6343.9682801585695, 6347.016212486979, 6364.968175159094, 6365.681715914278, 6369.0, 5698.572607054488, 5755.712214389264, 5779.537636989031, 5979.1629171915965, 6013.579049466583, 6885.0, 6964.756233532004, 6981.720731170696, 6990.860182796418, 6991.335823096798, 6411.102445657519, 6531.9020214695865, 6622.569373482859, 6704.128463299739, 6726.932730672826, 6860.416864566568, 6877.89683154763, 6967.65161741921, 6972.44220462372, 6982.895256571257, 6383.39357761012, 6422.293547709842, 6673.899891501728, 6678.964760462136, 6700.832479187864, 5650.7568334966245, 6415.454686351713, 6706.966465167643, 6729.461643068641, 6783.18601767791, 6493.090967264681, 6564.179477565304, 6570.5729127606555, 6574.112494813139, 6587.0, 6288.182536270256, 6531.379518945692, 6547.672616369067, 6552.213734351908, 6565.343465653396, 6180.103884936569, 6255.781047663492, 6431.228252609717, 6442.194725659293, 6453.548251622341, 6315.673708521262, 6554.606723596599, 6558.114654521579, 6579.276279609335, 6585.275619681735, 4715.552022557851, 6374.426301632875, 6380.521460890544, 6407.199100112486, 6422.582532135583], "value": 6055.7822701917785, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.8961345411643, 790.1624278265037, 807.4993504027502, 1253.1666441816199, 1262.3498898067492, 29562.852185739095, 36215.63784362155, 37357.0, 37368.813155934215, 37600.43599346009, 37567.87296381113, 37934.0, 37948.81025594868, 38002.619973800254, 38158.809205954, 7771.184025677293, 8309.376796740238, 8455.027671817728, 8562.100979397166, 8562.871556926651, 6530.934690653104, 7345.485815992883, 8482.194191551793, 8556.144385561456, 8556.288742251543, 7404.555726656391, 8476.957615211915, 8567.143285671445, 8579.442336248125, 8589.699360522383, 8530.061693213773, 8550.187732165497, 8577.699780507683, 8580.270676992463, 8589.570521473965, 7676.769696909102, 8411.074781774061, 8427.367947403969, 8473.49159050459, 8489.151084891524, 37081.81459092714, 37114.814425927965, 37132.814335928146, 37246.81376593127, 37486.62513374886, 3149.4173577888164, 3605.4231322988194, 3639.508666330038, 3651.72612054097, 3654.9217980695757, 28896.0, 29103.56344654832, 29456.82172713089, 29560.96536621208, 29595.260118496994, 3054.923626909323, 3066.340736741611, 3077.3999070181326, 3092.78350515464, 3104.4877595196767, 297265.0, 298349.0, 300663.0, 305509.0, 305772.47113764513, 47079.76460117677, 47131.52868471271, 47186.76406617945, 47206.527934720216, 47241.05517889692, 49277.75361123243, 49407.0, 54002.45997540053, 54803.72598137063, 54984.17523737147, 14211.715765684628, 14293.356798944073, 14301.499447519494, 14304.85695143056, 14319.427222910967, 11327.603533876274, 11368.886311136783, 11394.658160255205, 11404.65786026404, 11415.828762568568, 13898.0, 13940.651483712785, 14003.649908752363, 14054.367553460119, 14096.0, 6226.62640241587, 6251.562390632703, 6272.153259310026, 6273.937260627426, 6280.685965701697, 975.6682727872558, 998.3211416236884, 1017.4759998600726, 1056.424248784399, 1065.248999455382, 24288.63567046496, 24318.87840560786, 24476.877615611807, 24717.0, 24770.752292477206, 984.5077461269366, 1085.159001773616, 1104.6520346090956, 1130.9773804523863, 1135.0975974100609, 11101.611443599435, 11423.371714555613, 12107.031437485157, 12425.0, 12476.81284780729, 315789.0, 316709.0, 316844.0, 317012.0, 317333.0, 47363.5263647366, 47841.0, 47855.76072119617, 47869.9999999993, 47941.0, 1474.2112969561447, 1479.3712672114523, 1511.5238699809745, 1524.4131009561177, 1538.9461368852037, 24066.87966560156, 24147.879260603586, 24211.75788242095, 24244.908979096337, 24279.271621851374, 29888.0, 30788.23029424282, 37041.07397315089, 37449.81275093607, 37507.624923750416, 235700.82149589143, 259286.40713592622, 285863.0, 286560.0, 288423.0, 34973.0, 34983.825080874434, 35045.29909401849, 35384.64615353814, 35450.645493545766, 43474.34788478112, 44141.77929110463, 44278.55721442744, 44311.33532997072, 44530.886727830766, 29706.851465742533, 30065.398692026476, 30704.0, 31019.84490077535, 31092.378152437275, 30274.4102065514, 30718.84640576783, 30912.381752365276, 31052.29693212533, 31122.0, 32916.01251962445, 34687.0, 36671.89984300475, 36711.63288367082, 36750.44874326941, 28984.42031159323, 29995.850020749756, 30758.38483230278, 31214.21964450907, 31561.52657710182, 16322.0, 20774.54578179513, 21265.89367053155, 22469.876506174955, 26034.0], "value": 17295.36804251488, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.3765407137829, 425.83392476933983, 426.8058033594715, 430.4705212588516, 448.564892054707, 20006.29977950774, 21364.57270854582, 21929.0, 22112.88943555284, 22168.556628867434, 21929.0, 22577.75822329771, 22727.0, 22833.771662283372, 23052.654210186818, 6155.538334624899, 6316.431521163117, 6338.0, 6349.904751428732, 6357.3006969233575, 6092.32984371721, 6113.449789518942, 6221.59559628625, 6286.46565041972, 6326.430621244087, 4709.364235828154, 6253.874922501547, 6265.091561723548, 6268.059791031336, 6275.560710750249, 6162.876742465148, 6174.938250617526, 6222.595531290452, 6227.71975261112, 6230.283517395512, 5448.509634132907, 5526.143447765603, 5549.1676248562635, 5551.750171242304, 5596.3284405871555, 25855.70721463919, 27257.0, 27731.44537109267, 27931.72068279332, 28544.429111417656, 2168.5988092202992, 2209.0942713487393, 2224.077008041661, 2227.465408301996, 2227.4765430123907, 17654.205560749797, 17745.378911738026, 17963.0, 18305.633887322314, 18571.535711607183, 2006.8193862552441, 2122.980969134824, 2185.530111026137, 2208.5251670890834, 2225.899834507451, 121459.0, 154013.45986540103, 158429.0, 158793.0, 161216.0, 12214.0, 12745.808812867814, 12821.551245706356, 12890.226586404846, 12926.095173337964, 16573.917130414513, 16587.0, 16649.500514984327, 16667.0, 16675.583110422092, 8047.678092876219, 8794.252488538417, 8963.462192268486, 8980.550972451481, 9056.683016094537, 8499.235068843835, 8512.829743405096, 8551.957240213758, 8695.999960004681, 8699.521526315986, 6748.291429399842, 7955.363570914313, 8051.5169089853625, 8062.637181326854, 8108.43240973138, 2077.896105194734, 2189.6168170570086, 2194.714687090668, 2197.549502352014, 2197.9450513737283, 940.8965013848472, 957.5971202188827, 970.335320305593, 986.5757724178673, 1003.40798928631, 21461.89269053676, 21692.674609880865, 21730.348089557338, 21807.127714891543, 21831.0, 970.9902900971041, 1004.6232662751469, 1010.6361709784471, 1027.445179603021, 1029.156092004564, 10672.092872105957, 10993.230473866752, 11321.094312455149, 11378.886211137948, 11432.314061156358, 454712.0, 463163.0, 513919.4304028456, 520022.0, 530349.0, 120251.79748202581, 124056.0, 125208.0, 126989.0, 130552.3472382632, 1171.1275100050516, 1179.9823002654966, 1226.0620625221713, 1226.337777600086, 1227.1348699167065, 19879.527106400943, 20752.0, 20768.79231207729, 22969.196078137185, 23091.307260782203, 55693.0, 58205.0, 58353.4164658348, 58773.0, 58927.410725892194, 48973.020539588295, 73899.63050184715, 74812.0, 75418.0, 75710.62144689242, 8814.955925220333, 9154.450732956044, 9301.162895339452, 9364.20404265645, 9422.152006319464, 14263.07290026145, 14518.274086295854, 14706.13053254391, 14763.0, 14852.0, 12097.274163550215, 12144.574939877062, 12206.511739530675, 12399.760023997527, 12505.374731263219, 10222.948885255824, 11616.070714343, 11683.474243659226, 11820.704482387666, 11939.76120477603, 12361.925031099625, 12567.68580785451, 12576.87423125757, 12722.61832145037, 12784.936075319563, 8916.687915922912, 8974.596143173443, 9189.77025574366, 9237.122473365289, 9430.764230894283, 9754.80490390174, 11893.405329733305, 12002.459889305177, 12039.277643341427, 12046.096542759504], "value": 10521.761232279747, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.2045714628382, 1435.6195608163832, 1466.5527014260647, 1501.579557723837, 1512.0020786281054, 9996.400215987027, 10321.587136514549, 11562.710932226688, 11871.0, 12051.337176455281, 11327.886721132787, 11788.941055294712, 11811.70470738232, 11815.0, 11825.586104486345, 2530.3421110511217, 2557.577999630059, 2565.679290088739, 2572.3183356410473, 2575.7681808637217, 2500.6624105745673, 2558.9360265993405, 2567.833090849098, 2568.434944312258, 2569.871506424681, 2517.546841568517, 2526.06535581835, 2536.6702328697243, 2538.6318983747346, 2543.160756950211, 2081.5420607466335, 2465.580851255291, 2496.588062969617, 2508.385445565839, 2515.4466017476125, 2170.316350349635, 2470.1354525915967, 2491.700995880506, 2501.2871331670567, 2511.5228106659674, 11712.297262164297, 11752.764944701059, 11791.528338866436, 11836.585719499777, 11865.169438139334, 6602.40578347951, 6773.627450490221, 6794.116764820542, 6872.862542749117, 6876.0, 4037.8290295814177, 4173.707840451169, 4390.8902277443, 4465.977670111661, 4496.437945256843, 1114.554178328667, 1269.2574843716436, 1298.4741179822188, 1307.274462673216, 1311.6458556189775, 16617.41839035628, 16743.581410464714, 16892.746608800753, 16901.070441125732, 17106.230219640147, 5589.329280486368, 5611.551075913917, 5900.822975310747, 6018.518518518508, 6325.177726895474, 13708.725825483434, 14094.929525352307, 14161.716765664834, 14170.929145354206, 14218.786718199235, 6270.902592046465, 6597.30728273534, 6937.445004399636, 6938.340857618518, 6940.41006514452, 6124.418180272867, 6268.811935641938, 6304.684765761694, 6346.0, 6356.2054743157105, 4427.380166776654, 4581.931271030937, 4611.262198048296, 4619.769011549409, 4665.2768820832825, 1333.733253349334, 1335.2255691698792, 1341.2489006156584, 1349.0691422918155, 1352.7226918481688, 792.37798328312, 802.9357651387875, 831.5717405536033, 832.196929962589, 857.1043259793531, 6229.96885015572, 6255.530835187379, 6378.107065010902, 6431.485481161496, 6482.967585162044, 596.9850753731105, 600.9699515024231, 605.1588292273743, 617.8177437655902, 622.2222222222222, 4170.249355116108, 4353.434053573079, 4359.888228501712, 4452.154090722753, 4482.708623939434, 14808.777868331983, 15327.616809579627, 15484.30320635574, 15486.22568871552, 15514.0, 12417.75164496705, 14913.403463861541, 15334.156621385931, 15409.460668876536, 15411.152386618844, 8425.578721063923, 8586.65653373858, 8673.956630216935, 8684.60919258635, 8691.695790647296, 6840.2817704142335, 6873.072135261876, 7036.964815175891, 7065.470089743392, 7104.822379440556, 13269.071165018742, 13514.391852366864, 13759.793603096161, 13783.724325513635, 13825.0, 13674.863251367358, 13796.724065518834, 13846.93076534611, 14039.683452230267, 14109.647258818612, 9454.574544145393, 9731.805363892825, 9741.561629726537, 9754.024597540472, 9756.121949024622, 11080.501377437873, 11098.778024439305, 11170.329780213213, 11197.992180703775, 11200.047995920113, 8952.641894324153, 9358.251339892928, 9388.859167112636, 9467.905320946978, 9625.614975400906, 8809.735707928772, 9207.0, 9256.305777066613, 9295.72112836616, 9768.413895166314, 8405.705800297204, 8517.0, 8520.14798520143, 8520.3183745299, 8534.91465085366, 6130.846728831636, 6132.9080063799975, 6260.68696565179, 6277.5605707600025, 6337.429631333202, 5670.8015219467125, 6153.200083989232, 6205.410486003822, 6219.751209951733, 6436.581622194542], "value": 5448.592611636716, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.8660938757354, 384.1912773611548, 408.0573874350249, 412.56062293657254, 412.9380592911063, 4664.930026049612, 5184.144616138337, 5378.596605254602, 5797.797077102292, 6194.535409844263, 5345.812896548621, 5479.972600137004, 5808.838232353535, 5881.235439392884, 5969.641821490703, 4426.937534991611, 4436.955630443703, 4444.755538445384, 4446.7554284514335, 4478.708883922551, 3895.454636350899, 3906.726529142961, 4316.223079845627, 4429.114177164563, 4457.551295828853, 3821.445890345898, 4392.099619577994, 4406.272964960777, 4418.911621767563, 4423.358613001113, 4432.933505997396, 4436.378906953029, 4463.888402789924, 4473.642108631334, 4477.888052798689, 3075.938801113612, 3669.1194113412857, 3681.852725890961, 3842.9231415371814, 3847.1920896611746, 5610.523105536035, 5841.474267315962, 5866.296044474648, 5900.291964964232, 5920.763169473216, 5286.233496143076, 5294.385212510183, 5296.152615581527, 5320.733963301819, 5342.225377320306, 6151.477124444427, 6286.277078135981, 6390.041493775924, 6424.068510066014, 6430.324815894357, 3948.3682610782425, 4253.638440732541, 4327.3725309830215, 4354.499232588261, 4379.671524635665, 4970.87572810679, 6404.903926441154, 6650.339864054368, 6721.7647382341875, 6768.357006084414, 3980.6616437602547, 4107.507099148062, 4192.203481338536, 4391.956080439155, 4405.7797110144365, 3772.8490860365278, 4184.644305234028, 4490.955090449119, 4498.8425405110665, 4500.254844544825, 5023.045621331935, 5074.858644580724, 5230.607704422184, 5264.525672933715, 5292.338457692788, 5548.195511650788, 5560.388357280696, 5617.466340697627, 6494.188226471691, 6502.959526475835, 1502.646877983673, 1526.6641338905424, 1527.4730218074747, 1532.8773698104126, 1539.4227164813194, 807.8828569857337, 985.280745056113, 1010.5099026971913, 1020.4897551224387, 1024.1192574386023, 5329.626926115135, 6141.385861413823, 6176.197094377792, 6189.257289125334, 6189.566730328834, 5585.659441733955, 6705.0, 6722.831929201809, 6723.193216813916, 6738.292479289702, 5825.679587622722, 6124.295705993779, 6141.5260337518675, 6159.815205543751, 6177.2511232502, 3319.9834000830324, 3440.415129428003, 3500.544929159193, 3506.474028895686, 3534.6111927687934, 6280.717367718464, 6689.5986240825705, 6876.449884009267, 6934.503578711659, 6974.0, 5462.999999999921, 5722.055860782924, 6844.965775171092, 6857.588544687334, 6968.547044442095, 5534.806281780118, 5837.328707198726, 6474.061261117112, 6702.597844129368, 6963.442924566022, 5377.784888604412, 6615.735370585316, 6621.602703837785, 6640.203175618956, 6653.933460665331, 5777.884442311214, 6021.518278537619, 6592.637404942774, 6594.10979517759, 6618.63597502142, 5476.370217424887, 6282.780102696383, 6782.084418603623, 6883.931160688329, 6893.0, 3879.224155169034, 4271.786410679391, 4385.407969924019, 4388.376300768736, 4390.07808360248, 5630.60585758993, 6270.0908368287255, 6570.244421891352, 6668.766593169215, 6709.329067093447, 5970.3432622412365, 6176.258848938156, 6421.422072013354, 6439.645819479786, 6480.967595161994, 6206.6451255262655, 6323.4625056868845, 6385.201849768779, 6450.806475805545, 6496.805095846943, 6270.905936411049, 6288.622682639057, 6320.557560970872, 6326.9683651581445, 6329.8734025320155, 5860.0037993542055, 5921.3190483094995, 6423.807285781434, 6454.257760357619, 6480.935190648033, 5015.322931404214, 5961.672108033927, 5995.400459953969, 6248.687565621792, 6262.279837818709], "value": 4643.312417420933, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [820.7783898347443, 830.0744669693294, 843.4517563583672, 856.2037305306067, 866.4281574161051, 13754.931225343884, 16570.33718651254, 16962.236699348545, 17049.0, 18723.719144212846, 17846.28614855404, 17931.462056138305, 18081.0, 18110.63778724428, 18296.634067318646, 955.928305377096, 976.5215044628152, 978.0366339155926, 983.1151963233111, 984.0208992052882, 971.5239532628996, 981.2346369831542, 994.0953732103787, 995.5470261031213, 995.5918073589829, 1025.5282570017764, 1031.1389989358856, 1037.704254287528, 1039.7192757955347, 1042.6246551241547, 1018.4907546226887, 1026.9383836969805, 1034.1726618705043, 1035.6427032673716, 1035.6737627647267, 766.2414209932183, 770.730244414456, 771.2634434115463, 773.6441237030916, 774.5855967057597, 16768.58078548034, 17073.82926170735, 17272.827271727372, 17351.82648173515, 17444.040577768217, 13991.440342386293, 17218.741718874226, 17247.913760431242, 17426.65146697059, 17427.73858392125, 13649.658758531017, 13873.0, 13926.234057126854, 13994.790078148835, 14047.578572642837, 3070.2784845561273, 3289.7663376233913, 3489.5638045244345, 3507.877224297137, 3509.3858574749315, 92073.0, 102902.97097029009, 108836.36745448825, 113886.86113138846, 119023.4048829759, 12892.935535322264, 13125.474981000652, 13186.0, 13259.403326850315, 13260.336983150804, 24480.755192447847, 24990.125345612818, 25016.749832501442, 25197.496050078895, 25211.621825672628, 7737.0, 7897.881531777028, 7904.6442910068035, 7905.881411778828, 7912.367010639135, 3840.251151025579, 3858.3633700439673, 3868.207017561393, 3910.569837317893, 3924.6467817895955, 4300.580808333242, 4342.08816148606, 4350.21696094706, 4363.345498175299, 4392.714473559208, 929.3912487320681, 963.629002833914, 977.4086677560116, 993.1657407777501, 995.1392045880261, 654.6432194454034, 654.8002859128012, 661.1438187547138, 665.890128128863, 677.8237658208901, 11337.206395552232, 11617.593384231512, 11969.222000569935, 12107.818382724266, 12183.939080304543, 630.338144947803, 660.5607271164622, 662.3608218069586, 676.4689718570899, 686.8797960356918, 5086.415062267813, 5524.005678977823, 5557.138643510262, 5675.1770993205755, 5743.2246646703, 105617.0, 106839.0, 107256.0, 107348.46325768478, 109259.0, 94893.0, 95212.0, 96822.0, 97388.51305743426, 99073.0, 85215.0, 85806.57096714477, 86644.0, 87064.0, 88247.11752882517, 8820.11798820113, 10640.84038739404, 10969.945150274198, 11072.778544428906, 11293.0, 40392.3941140889, 40629.796851015555, 41089.58910410858, 41515.7924210377, 41750.58249417467, 49318.50681493139, 49318.75340623274, 49544.75227623839, 49657.26199582997, 50021.49978500168, 14172.574822755334, 14208.57374278732, 14397.64005899819, 14491.565253042425, 14535.491257805927, 15163.620909477351, 15329.38682452734, 15372.846271537588, 15541.766873497132, 15622.062676239462, 12167.391630418266, 14593.124412535282, 14654.413823446943, 14725.55823325302, 14821.703565928405, 14391.208483533088, 14404.495842645458, 14449.422023119378, 14603.415863365346, 14723.705525889636, 14288.214148221947, 14289.857101428852, 14307.499237526637, 14372.568822935329, 14460.783088253474, 10441.0, 10456.895431045896, 10480.842787358348, 11037.448127593749, 11053.447327633425, 11406.771864562828, 14004.229767362893, 14013.229272390117, 14047.508337208148, 14102.78845817293], "value": 8154.868247606224, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 167.0, 115.0, 116.0, 118.0, 115.0, 115.0, 117.0, 118.0, 116.0], "value": 123.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 110.0, 112.0, 154.0, 111.0, 110.0, 113.0, 114.0, 136.0, 126.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 224.0, 188.0, 201.0, 213.0, 265.0, 277.0, 222.0, 244.0, 268.0], "value": 230.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 74.0, 89.0, 59.0, 57.0, 49.0, 70.0, 94.0, 81.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 68.0, 86.0, 70.0, 69.0, 68.0, 87.0, 71.0, 74.0, 107.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 91.0, 135.0, 98.0, 89.0, 90.0, 107.0, 89.0, 90.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 118.0, 96.0, 152.0, 150.0, 117.0, 105.0, 150.0], "value": 117.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 95.0, 90.0, 92.0, 107.0, 145.0, 141.0, 93.0, 93.0, 99.0], "value": 104.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 52.0, 51.0, 54.0, 54.0, 69.0, 49.0, 56.0, 61.0, 72.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 69.0, 74.0, 62.0, 65.0, 61.0, 55.0, 57.0, 57.0, 57.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 90.0, 104.0, 106.0, 84.0, 83.0, 84.0, 105.0, 91.0, 85.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 152.0, 141.0, 135.0, 137.0, 149.0, 156.0, 144.0, 196.0, 153.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 189.0, 185.0, 185.0, 183.0, 208.0, 181.0, 163.0, 228.0, 227.0], "value": 193.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 69.0, 69.0, 68.0, 69.0, 67.0, 73.0, 66.0], "value": 68.9, "unit": "score"}]}]} 13:51:52 INFO - Return code: 0 13:51:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:51:52 ERROR - # TBPL SUCCESS # 13:51:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:51:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:51:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:51:52 INFO - 2017-04-20 13:51:52.420 screenresolution[1653:10667] starting screenresolution argv=screenresolution get 13:51:52 INFO - 2017-04-20 13:51:52.423 screenresolution[1653:10667] Display 0: 1600x1200x32@60 13:51:52 INFO - 2017-04-20 13:51:52.430 screenresolution[1654:10670] starting screenresolution argv=screenresolution list 13:51:52 INFO - Available Modes on Display 0 13:51:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:51:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:51:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:51:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:51:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:51:52 INFO - Intel Iris: 13:51:52 INFO - Chipset Model: Intel Iris 13:51:52 INFO - Type: GPU 13:51:52 INFO - Bus: Built-In 13:51:52 INFO - VRAM (Dynamic, Max): 1536 MB 13:51:52 INFO - Vendor: Intel (0x8086) 13:51:52 INFO - Device ID: 0x0a2e 13:51:52 INFO - Revision ID: 0x0009 13:51:52 INFO - Displays: 13:51:52 INFO - Display: 13:51:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:51:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:51:52 INFO - Display Serial Number: 1600x1200 60 13:51:52 INFO - Main Display: Yes 13:51:52 INFO - Mirror: Off 13:51:52 INFO - Online: Yes 13:51:52 INFO - Rotation: Supported 13:51:52 INFO - Return code: 0 13:51:52 INFO - Running post-action listener: _resource_record_post_action 13:51:52 INFO - [mozharness: 2017-04-20 20:51:52.674780Z] Finished run-tests step (success) 13:51:52 INFO - Running post-run listener: _resource_record_post_run 13:51:52 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 40090112; Write bytes: 517263360; Read time: 392; Write time: 1587 13:51:52 INFO - TinderboxPrint: CPU usage
54.1% 13:51:52 INFO - TinderboxPrint: I/O read bytes / time
40,090,112 / 392 13:51:52 INFO - TinderboxPrint: I/O write bytes / time
517,263,360 / 1,587 13:51:52 INFO - TinderboxPrint: CPU idle
2,198.3 (45.9%) 13:51:52 INFO - TinderboxPrint: CPU system
958.6 (20.0%) 13:51:52 INFO - TinderboxPrint: CPU user
1,628.8 (34.0%) 13:51:52 INFO - TinderboxPrint: Swap in / out
247,894,016 / 0 13:51:52 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 196694528; Write bytes: 193459712; Read time: 14897; Write time: 392 13:51:52 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 39127552; Write bytes: 306977280; Read time: 374; Write time: 1146 13:51:53 INFO - Running post-run listener: _upload_blobber_files 13:51:53 INFO - Blob upload gear active. 13:51:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:51:53 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 13:51:53 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'] 13:51:53 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 13:51:53 INFO - (blobuploader) - INFO - Open directory for files ... 13:51:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:51:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:51:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:54 INFO - (blobuploader) - INFO - Done attempting. 13:51:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:51:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:51:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:55 INFO - (blobuploader) - INFO - Done attempting. 13:51:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:51:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:51:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:56 INFO - (blobuploader) - INFO - Done attempting. 13:51:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:51:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:51:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:59 INFO - (blobuploader) - INFO - Done attempting. 13:51:59 INFO - (blobuploader) - INFO - Iteration through files over. 13:51:59 INFO - Return code: 0 13:51:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:51:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:51:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/16508bf00470c51c55ea868ed8faad8d30ea8d4dc701f62be99fb79f6c74f4e5a8f169ccf27003fbe593fb51f63f6d47c89483afb39898044751c4ded7c8f7af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e748d7e1464d79d8acfada929229e4ca922dca15de994be0b87687a1f2d2e53fc490be4a886ca0c8f3ec93656a1ab7d2ddcf8f5cb316330ba08a68e08bd9e151", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/36c92bde60625f0e768d6959335feca1a9589a0e5b3092b1d8c5fbfeb10a8c0b895d9ac988e5e01e140ad08e2b910e137a89f4bd6f0514692f06468a7cb90883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4bcd459a274f5e591dc13fc576ede4276fa3b513bd108fe81ff5ec89d662928bfcd233d89f81aa9d4228abef16493ecf27e1734f59e9766af7743e73ff947d00"} 13:51:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:51:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:51:59 INFO - Contents: 13:51:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/16508bf00470c51c55ea868ed8faad8d30ea8d4dc701f62be99fb79f6c74f4e5a8f169ccf27003fbe593fb51f63f6d47c89483afb39898044751c4ded7c8f7af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e748d7e1464d79d8acfada929229e4ca922dca15de994be0b87687a1f2d2e53fc490be4a886ca0c8f3ec93656a1ab7d2ddcf8f5cb316330ba08a68e08bd9e151", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/36c92bde60625f0e768d6959335feca1a9589a0e5b3092b1d8c5fbfeb10a8c0b895d9ac988e5e01e140ad08e2b910e137a89f4bd6f0514692f06468a7cb90883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4bcd459a274f5e591dc13fc576ede4276fa3b513bd108fe81ff5ec89d662928bfcd233d89f81aa9d4228abef16493ecf27e1734f59e9766af7743e73ff947d00"} 13:51:59 INFO - Running post-run listener: copy_logs_to_upload_dir 13:51:59 INFO - Copying logs to upload dir... 13:51:59 INFO - mkdir: /builds/slave/test/build/upload/logs 13:51:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.816663 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-20 13:51:59.390440) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 13:51:59.393980) ========= 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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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/16508bf00470c51c55ea868ed8faad8d30ea8d4dc701f62be99fb79f6c74f4e5a8f169ccf27003fbe593fb51f63f6d47c89483afb39898044751c4ded7c8f7af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e748d7e1464d79d8acfada929229e4ca922dca15de994be0b87687a1f2d2e53fc490be4a886ca0c8f3ec93656a1ab7d2ddcf8f5cb316330ba08a68e08bd9e151", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/36c92bde60625f0e768d6959335feca1a9589a0e5b3092b1d8c5fbfeb10a8c0b895d9ac988e5e01e140ad08e2b910e137a89f4bd6f0514692f06468a7cb90883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4bcd459a274f5e591dc13fc576ede4276fa3b513bd108fe81ff5ec89d662928bfcd233d89f81aa9d4228abef16493ecf27e1734f59e9766af7743e73ff947d00"} build_url:https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011353 build_url: 'https://queue.taskcluster.net/v1/task/BrJzbkJSSd-UcMXKoTmvoQ/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/16508bf00470c51c55ea868ed8faad8d30ea8d4dc701f62be99fb79f6c74f4e5a8f169ccf27003fbe593fb51f63f6d47c89483afb39898044751c4ded7c8f7af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e748d7e1464d79d8acfada929229e4ca922dca15de994be0b87687a1f2d2e53fc490be4a886ca0c8f3ec93656a1ab7d2ddcf8f5cb316330ba08a68e08bd9e151", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/36c92bde60625f0e768d6959335feca1a9589a0e5b3092b1d8c5fbfeb10a8c0b895d9ac988e5e01e140ad08e2b910e137a89f4bd6f0514692f06468a7cb90883", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/4bcd459a274f5e591dc13fc576ede4276fa3b513bd108fe81ff5ec89d662928bfcd233d89f81aa9d4228abef16493ecf27e1734f59e9766af7743e73ff947d00"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-20 13:51:59.429166) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:51:59.429542) ========= 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.fGv5aldgcD/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.sWDEZ2Jqo4/Listeners TMPDIR=/var/folders/x6/sw5m1xh120z8118jjdxnyt0800000w/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.005017 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-20 13:51:59.478373) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 13:51:59.478827) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-20 13:51:59.479174) ========= ========= Total master_lag: 0.19 =========