1.2.1

CHANGELOG.md

@@ -1,5 +1,15 @@
 ### Changelog
 
+#### [v1.2.1](https://git.w33ble.com/w33ble/gh-action-bot/compare/v1.2.0...v1.2.1) (23 October 2018)
+- feat: add more debugging output [`3db973f`](https://git.w33ble.com/w33ble/gh-action-bot/commit/3db973febc5387605279f7855976a4ec431f0362)
+- fix: correctly handle errors in logger.error [`c70e8e4`](https://git.w33ble.com/w33ble/gh-action-bot/commit/c70e8e49ecb9041b22a8f4b92398339d45871ca1)
+- fix: correctly check for pr author [`f4c3e02`](https://git.w33ble.com/w33ble/gh-action-bot/commit/f4c3e02c1a31aea89a565f5685dd3b879bd38b14)
+
+#### [v1.2.0](https://git.w33ble.com/w33ble/gh-action-bot/compare/v1.1.0...v1.2.0) (23 October 2018)
+- feat: add debugging output to script [`0dce327`](https://git.w33ble.com/w33ble/gh-action-bot/commit/0dce32781b0f8c138d7f8b28382289d67be730dd)
+- feat: debug logging and better timestamps [`08799b4`](https://git.w33ble.com/w33ble/gh-action-bot/commit/08799b48d74d12266d5ebb85811f5d3fa046b086)
+- docs: add note about using DEBUG=1 output [`872d9c7`](https://git.w33ble.com/w33ble/gh-action-bot/commit/872d9c790fc723f1b255536225be60f547cf5196)
+
 #### [v1.1.0](https://git.w33ble.com/w33ble/gh-action-bot/compare/v1.0.1...v1.1.0) (22 October 2018)
 - feat: add timestamp to log output [`725638e`](https://git.w33ble.com/w33ble/gh-action-bot/commit/725638ee6b7fbb816518239f5524f6c37056da2e)
 

README.md

@@ -17,6 +17,10 @@ You'll need to set some environment flags. Check `sample-env` for what is availa
 
 You will need to provide a Github [Personal Access Token](https://github.com/settings/tokens/new). It will need `write:org` access in order to create comments.
 
+### Debugging
+
+If the script isn't doing what you expect, you can get more output from it by prepending the script with `DEBUG=1`.
+
 #### License
 
 MIT © [w33ble](https://github.com/w33ble)

package.json

@@ -1,6 +1,6 @@
 {
   "name": "gh-action-bot",
-  "version": "1.1.0",
+  "version": "1.2.1",
   "private": true,
   "description": "Action bot for Github",
   "main": "index",

src/index.mjs

@@ -38,9 +38,18 @@ async function ghActionBot() {
     comments.map(async comment => {
       const pull = await getPull(repo, comment.number);
 
-      if (pull.state !== 'open') return false; // filter out any closed pulls
+      if (pull.state !== 'open') {
-      if (PULL_AUTHOR_FILTER && !pull.owner !== PULL_AUTHOR_FILTER) return false; // filter on owner
+        logger.debug(`SKIP #${pull.number}: state is ${pull.state}`);
-      if (PULL_LABEL_FILTER && !pull.labels.includes(PULL_LABEL_FILTER)) return false; // filter on label
+        return false; // filter out any closed pulls
+      }
+      if (PULL_AUTHOR_FILTER && pull.owner !== PULL_AUTHOR_FILTER) {
+        logger.debug(`SKIP #${pull.number}: author is ${pull.owner}`);
+        return false; // filter on owner
+      }
+      if (PULL_LABEL_FILTER && !pull.labels.includes(PULL_LABEL_FILTER)) {
+        logger.debug(`SKIP #${pull.number}: labels are ${pull.labels.join(',')}`);
+        return false; // filter on label
+      }
 
       return { comment, pull };
     })
@@ -51,18 +60,25 @@ async function ghActionBot() {
       const commit = await getCommits(repo, pull.number, true);
       const buildStatus = await getCommitStatus(repo, commit.sha);
       // do nothing if the build has not started, or is pending or successful
-      if (!buildStatus || buildStatus.state === 'pending' || buildStatus.state === 'success')
+      if (!buildStatus || buildStatus.state === 'pending' || buildStatus.state === 'success') {
+        logger.debug(`SKIP #${pull.number}: build status is ${buildStatus.state}`);
         return false;
+      }
       return { comment, pull, commit, buildStatus };
     })
   )).filter(Boolean);
 
-  logger.log(`Found ${records.length} outstanding failures`);
+  if (records.length) {
+    logger.log(`Found ${records.length} outstanding failures`);
+  } else {
+    logger.debug(`Found ${records.length} outstanding failures`);
+  }
 
   await Promise.all(
     records.map(async record => {
       logger.log(`Re-testing PR #${record.pull.number}`);
       const comment = await createComment(repo, records[0].pull.number, PULL_RETEST_BODY);
+      logger.debug(`Created comment id ${comment.id}`);
       await deleteComment(repo, comment.id);
     })
   );

src/lib/get_comments.mjs

@@ -1,3 +1,5 @@
+import logger from './logger.mjs';
+
 export default async function getEvents(repo, { body, actor } = {}) {
   const events = await repo.events.fetch();
 
@@ -13,11 +15,30 @@ export default async function getEvents(repo, { body, actor } = {}) {
 
   return items
     .map(comment => {
-      // only comments created by specific user
+      let skip = false;
-      if (comment.type !== 'IssueCommentEvent') return false;
+      const isComment = comment.type === 'IssueCommentEvent';
-      if (comment.payload.action !== 'created') return false;
+      const bodyMatch = isComment && (!body || body.test(comment.payload.comment.body));
-      if (comment.actor.login !== actor) return false;
+      const isActor = comment.actor.login === actor;
-      if (body && !body.test(comment.payload.comment.body)) return false;
+
+      // skip events that don't match the criteria
+      if (!isComment) skip = true;
+      if (comment.payload.action !== 'created') skip = true;
+      if (!isActor) skip = true;
+      if (isComment && !bodyMatch) skip = true;
+
+      if (skip) {
+        if (isActor) {
+          logger.debug(
+            `SKIP EVENT ${isComment ? `#${comment.payload.issue.number}` : comment.id}: ${
+              comment.type
+            } ${comment.payload.action} by ${comment.actor.login}`,
+            isComment && !bodyMatch && `(${comment.payload.comment.body.slice(0, 30)})`
+          );
+        }
+        return false;
+      }
+
+      logger.debug(`PROCESS #${comment.payload.issue.number}`);
 
       return {
         id: comment.id,

src/lib/logger.mjs

@@ -1,5 +1,24 @@
 /* eslint no-console: 0 */
-const wrapMessage = msg => `${new Date()}: ${msg.join(' ')}`;
+const zeroPad = (str, len) => {
+  let output = `${str}`;
+  if (!len || len <= output.length) return str;
+
+  while (output.length < len) {
+    output = `0${output}`;
+  }
+  return output;
+};
+
+const getTimestamp = () => {
+  const d = new Date();
+  return `${[d.getFullYear(), zeroPad(d.getMonth(), 2), zeroPad(d.getDate(), 2)].join('/')} ${[
+    zeroPad(d.getHours(), 2),
+    zeroPad(d.getMinutes(), 2),
+    zeroPad(d.getSeconds(), 2),
+  ].join(':')}`;
+};
+
+const wrapMessage = msg => `[${getTimestamp()}]: ${msg.join(' ')}`;
 
 const logger = {
   log(...args) {
@@ -9,8 +28,16 @@ const logger = {
     console.warn(wrapMessage(args));
   },
   error(...args) {
+    if (args[0] instanceof Error) {
+      console.error(getTimestamp(), args[0].stack);
+      return;
+    }
     console.error(wrapMessage(args));
   },
+  debug(...args) {
+    if (!process.env.DEBUG) return;
+    console.log(wrapMessage(args));
+  },
 };
 
 export default logger;