Problems with console logging

[alekdavis]

I'm not sure if this is something with my configuration or that's how it works for everyone, but console logging does not work very well.

Here is my demo file:

{{
  console.log("In global section.")
}}

### X
# @name x
{{
  console.log("In pre-request script.")
}}
GET https://httpbin.org/anything/x=y

{{
  console.log("In post-request script (before assert).")
}}

?? status == 200

{{
  console.log("In post-request script (after assert).")
}}

There are no console logs in the httpyac - Log or the httpyac - Request OUTPUT tabs and the TEST RESULTS tab shows:

I would expect TEST RESULTS to show something like:

INFO: In global section.
---------------------

=== X ===

INFO: In pre-request script.
GET https://httpbin.org/anything/x=y
=> 200 (4450 ms, 565 B)
INFO: In post-request script (before assert).
✓ status == 200
INFO: In post-request script (after assert).

Also, not sure why there are no line breaks between log entries (and a delimiter between the log level and the log message would be useful).

[AnWeber]

To support script console output in test runner, I did override complete console output in OutputChannel. I updated logic to support both outputs at the same time. I also forgot adding a line break in test runner output. ops.

[alekdavis]

@AnWeber Btw, what happened to the httpyac - Console output option? There used to be three output options for httpyac, and now I only see two: httpyac - Log and httpyac - Request. I'm not sure httpyac - Console output is needed (since the log entries go into the TEST RESULTS output), but on a rare occasion the httpyac - Console option seems to come back and I'm just a bit puzzled if it should be there or not (mostly when I do training, so I can explain it to others). And is there an explanation of what goes into what output channel? The httpyac - Request output is self-explanatory, but what is supposed to primary purpose of the httpyac - Log output, and if httpyac - Console output is supposed to be there, is it just for the console.log messages? Shedding some light would be helpful.

[AnWeber]

@alekdavis I have now tested it again. The OutputChannel will also work again.

Explanation for the output channels.

• Output Channel httpyac - Log: primary output channel to log outputs for troubleshooting.
• Output Channel httpyac - Request: a formatted output of the send request and the retrieved response (I just wanted to be able to copy the request/response and send it to a third party.)
• Output Channel httpyac - Console: Channel to output custom messages. httpyac does not write to this channel (only if some user provoked exception occurs, e.g. invalid javascript)

[alekdavis]

So, given the tests:

{{
  console.log("In global section.")
}}

### X
# @name x
{{
  console.log("In pre-request script X.")
}}
GET https://httpbin.org/anything/x=1

{{
  console.log("In post-request script X (before assert).")
}}

?? status == 200

{{
  console.log("In post-request script X (after assert).")
}}


### Y
# @name y
{{
  console.log("In pre-request script Y.")
}}
GET https://httpbin.org/anything/y=2

{{
  console.log("In post-request script Y (before assert).")
}}

?? status == 200

{{
  console.log("In post-request script Y (after assert).")
}}

The TEST RESULTS output looks like this:

INFO In global section.
INFO In pre-request script X.
INFO In post-request script X (before assert).
INFO In post-request script X (after assert).

---------------------

=== X ===

GET https://httpbin.org/anything/x=1
=> 200 (610 ms, 565 B)
✓ status == 200
INFO In global section.
INFO In pre-request script Y.
INFO In post-request script Y (before assert).
INFO In post-request script Y (after assert).

---------------------

=== Y ===

GET https://httpbin.org/anything/y=2
=> 200 (580 ms, 565 B)
✓ status == 200

Is this expected? I mean, do all console log outputs go before the requests? I assume it is not possible to have them appear in the logical places (as in the example of my original post), right? But maybe have them placed after the test separator line, e.g.:

---------------------
INFO In global section.
INFO In pre-request script X.
INFO In post-request script X (before assert).
INFO In post-request script X (after assert).

=== X ===

GET https://httpbin.org/anything/x=1
=> 200 (610 ms, 565 B)
✓ status == 200

---------------------
INFO In global section.
INFO In pre-request script Y.
INFO In post-request script Y (before assert).
INFO In post-request script Y (after assert).

=== Y ===

GET https://httpbin.org/anything/y=2
=> 200 (580 ms, 565 B)
✓ status == 200

Or, if it is easier, may be put them after at the end of the test section (if the previous example is not possible), e.g.:

---------------------

=== X ===

GET https://httpbin.org/anything/x=1
=> 200 (610 ms, 565 B)
✓ status == 200

INFO In global section.
INFO In pre-request script X.
INFO In post-request script X (before assert).
INFO In post-request script X (after assert).

---------------------

=== Y ===

GET https://httpbin.org/anything/y=2
=> 200 (580 ms, 565 B)
✓ status == 200

INFO In global section.
INFO In pre-request script Y.
INFO In post-request script Y (before assert).
INFO In post-request script Y (after assert).

This would be a bit easier to read because currently they look like they belong to a wrong test. What do you think?

And thanks a lot for implementing new lines.

P.S. There is a couple of other console log issues I want to bring up, but I will create another issue.