Bot does not respond after giving pending message

Some notes:
For a9ebacd88150a965fe6b82012cf1bf0828f4bccb the database record for d1 completes but d2 is showing a timeout.
full logs:
portal          | <I> 2019-3-4 07:34:27: AutoTestRouteHandler::atGetResult(..) - delivId: d2; repoId: project_c7s1b_n7o0b; sha: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
portal          | <I> 2019-3-4 07:34:27: ResultsController::getResult( d2, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - start
portal          | <I> 2019-3-4 07:34:27: DatabaseController::getResult( d2, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - not found
portal          | <I> 2019-3-4 07:35:21: AutoTestRouteHandler::atGrade(..) - repoId: project_c7s1b_n7o0b; delivId: d2; body: {"delivId":"d2","repoId":"project_c7s1b_n7o0b","repoURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b","score":95.28,"urlName":"project_c7s1b_n7o0b","URL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","comment":"","timestamp":1551684810301,"custom":{}}
portal          | <I> 2019-3-4 07:35:21: AdminController::processNewAutoTestGrade( .. ) - payload: {"delivId":"d2","repoId":"project_c7s1b_n7o0b","repoURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b","score":95.28,"urlName":"project_c7s1b_n7o0b","URL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","comment":"","timestamp":1551684810301,"custom":{}}
portal          | <I> 2019-3-4 07:35:21: AutoTestRouteHandler::performPostResult(..) - valid result && valid secret; deliv: d2; repo: project_c7s1b_n7o0b; sha: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
portal          | <I> 2019-3-4 07:35:21: DatabaseController::getResult( d2, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - not found
portal          | <I> 2019-3-4 07:37:09: AutoTestRouteHandler::atGrade(..) - repoId: project_c7s1b_n7o0b; delivId: d2; body: {"delivId":"d2","repoId":"project_c7s1b_n7o0b","repoURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b","score":0,"urlName":"project_c7s1b_n7o0b","URL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","comment":"","timestamp":1551684717947,"custom":{}}
portal          | <I> 2019-3-4 07:37:09: AdminController::processNewAutoTestGrade( .. ) - payload: {"delivId":"d2","repoId":"project_c7s1b_n7o0b","repoURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b","score":0,"urlName":"project_c7s1b_n7o0b","URL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","comment":"","timestamp":1551684717947,"custom":{}}
portal          | <I> 2019-3-4 07:37:09: AutoTestRouteHandler::performPostResult(..) - valid result && valid secret; deliv: d2; repo: project_c7s1b_n7o0b; sha: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
portal          | <I> 2019-3-4 07:37:09: DatabaseController::getResult( d2, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - found: {"delivId":"d2","repoId":"project_c7s1b_n7o0b","commitURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","commitSHA":"a9ebacd88150a965fe6b82012cf1bf0828f4bccb","input":{"delivId":"d2","target":{"delivId":"d2","repoId":"project_c7s1b_n7o0b","botMentioned":false,"personId":null,"kind":"push","cloneURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b.git","commitSHA":"a9ebacd88150a965fe6b82012cf1bf0828f4bccb","commitURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","postbackURL":"https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments","timestamp":1551684810301},"containerConfig":{"dockerImage":"grader:latest","studentDelay":43200,"maxExecTime":300,"regressionDelivIds":[],"custom":{},"openTimestamp":1549396743000,"closeTimestamp":1551760200000,"lateAutoTest":true}},"output":{"timestamp":1551684812978,"report":{"attachments":[{"name":"testReportJSON","path":"/output/staff/bot/public/mochawesome-report/mochawesome.json","content_type":"application/json"},{"name":"testReportHTML","path":"/output/staff/bot/public/mochawesome-report/mochawesome.html","content_type":"text/html"},{"name":"coverageJSON","path":"/output/student/coverage/coverage-summary.json","content_type":"application/json"},{"name":"coverageHTML","path":"/output/student/coverage/index.html","content_type":"text/html"}],"custom":{"private":{"passNames":["Identity: Should be able to add dataset if the index is formatted differently","Ultimatum: Should be able to add dataset if some buildings are formatted differently","Loth: Should be able to remove datasets independently","Elmister: Remove the same dataset twice should reject with NotFoundError","Moradin: Should not be able to perform query when dataset has been removed","Obaskyr: Should be able to list both courses and rooms datasets","Diesel: Should be able to find address of a building given lat and lon","Hopper: Filter by room names","Kleene: Find all group type rooms without some furniture","Nitro: Should be able to find all rooms with a certain type of furniture","Omega: Should be able to find some specific rooms","Onomatopoeia: Should be able to handle a deep query","Piglet: Valid query should fulfill","Quadro: Invalid strings should reject","Romeo: Should be able to sort on multiple keys in reverse order","Scorpio: Invalid query should reject","Sodium: Invalid query should reject","Taurus: Should be able to find the average of all courses within a department","Titanium: Should be able to find and sort by the number of unique offerings for each course","Topaz: Invalid query should reject","Uranium: Invalid query should reject","Vanadium: Complex transformation query over many sections","Virgo: Deeply nested query should be supported","Vulcan: Should be able to order with multiple keys","Watson: Valid query should fulfill","Xenon: Valid query should fulfill"],"failNames":["Corellon: Should still be able to perform query when the removed dataset has different id"],"skipNames":[],"scoreTest":96.3,"attachments":[{"name":"testReportJSON","path":"/output/staff/bot/private/mochawesome-report/mochawesome.json","content_type":"application/json"},{"name":"testReportHTML","path":"/output/staff/bot/private/mochawesome-report/mochawesome.html","content_type":"text/html"}]},"combined":{"scoreOverall":95.52,"feedback":"**Public test results**\n\n- Test summary: 95.12% (39 passing, 2 failing, 0 skipped)\n- Statement coverage: 95.94%\n\nYour code failed the tests:\n- Otyluke: Should be able to perform query when dataset has been added\n- Possum: Should query with only some set of columns\n\n**Private test results**\n\n- Test summary: 96.3% (26 passing, 1 failing, 0 skipped)\n\n\nYour code failed the tests:\n- Corellon: Should still be able to perform query when the removed dataset has different id\n\n**Overall results**\n\nYour final combined score for deliverable **d2** is **95.52**%."}},"errorNames":[],"failNames":["Otyluke: Should be able to perform query when dataset has been added","Possum: Should query with only some set of columns"],"feedback":"**Public test results**\n\nFor deliverable **d2**, this commit received a grade of **95.28**%.\n- Test summary: 95.12% (39 passing, 2 failing, 0 skipped)\n- Statement coverage: 95.94%\n\nYour code failed the tests:\n- Otyluke: Should be able to perform query when dataset has been added\n- Possum: Should query with only some set of columns\n\n**Private test results**\n\nWithheld until after deadline.","passNames":["Waterdeep: addDataset should fulfill with name of dataset","Xanathar: addDatasets should be able to add datasets independently","Lathander: Should be able to add dataset without too many assumptions","Vecna: Should be able to remove datasets independently","Elmister2: Should not be able to perform query when dataset has not been added","Dritzz: Should not be able to perform query when dataset has been removed","Obaskyr: Should be able to list both courses and rooms datasets","Argon: Should be able to find rooms in a specific building","Boron: Should be able to find rooms with plenty of seats in a building","Canary: Should be able to query with AND and OR","Cardinal: Should reject invalid ORDER","Einstein: Should be able to find lat and lon given address of a building","Germanium: Should be able to find rooms with tables","Googolplex: Filter by room fullnames","Helium: Filter by partial href","Hydrogen: Should be able to find hyperlink for rooms","Nautilus: Should be able to find all rooms of a certain type","Odyssey: Should be able to find all rooms within a certain bounding box","Pisces: Should be able to sort urls","Python: Invalid number comparison should reject with InsightError","Revolution: Should be able to sort using D2 syntax","Ritchie: Should be able to sort in reverse order","Riviera: Should be able to sort on multiple keys","Royal: Apply: MAX should be supported","Rutabaga: Apply: MIN should be supported","Sacrilicious: Apply: SUM should be supported","Sagittarius: Apply: COUNT should be supported","Sahara: Apply: AVG should be supported","Saturn: Invalid query should reject with InsightError","Selenium: Invalid query should reject with InsightError","Silver: Invalid query should reject with InsightError","Sky: Invalid query should reject with InsightError","Sputnik: Invalid query should reject with InsightError","SteamedHam: Should be able to query with more than one APPLY rule","Sunergy: Invalid query should reject with InsightError","Topcat: Invalid query should reject with InsightError","Tungsten: Invalid query having keys for more than 1 dataset should reject with InsightError","Uranus: Invalid query should reject with InsightError","Voyager: Should be able to query with group and apply"],"result":"SUCCESS","scoreCover":95.94,"scoreOverall":95.28,"scoreTest":95.12,"skipNames":[]},"postbackOnComplete":false,"custom":{},"state":"SUCCESS","graderTaskId":"a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2"},"people":["c7s1b","n7o0b"]}
portal          | <I> 2019-3-4 07:40:30: AutoTestRouteHandler::atGetResult(..) - delivId: d2; repoId: project_c7s1b_n7o0b; sha: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
portal          | <I> 2019-3-4 07:40:30: ResultsController::getResult( d2, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - start
portal          | <I> 2019-3-4 07:40:30: DatabaseController::getResult( d2, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - found: {"delivId":"d2","repoId":"project_c7s1b_n7o0b","commitURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","commitSHA":"a9ebacd88150a965fe6b82012cf1bf0828f4bccb","input":{"delivId":"d2","target":{"delivId":"d2","repoId":"project_c7s1b_n7o0b","botMentioned":false,"personId":null,"kind":"push","cloneURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b.git","commitSHA":"a9ebacd88150a965fe6b82012cf1bf0828f4bccb","commitURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","postbackURL":"https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments","timestamp":1551684717947},"containerConfig":{"dockerImage":"grader:latest","studentDelay":43200,"maxExecTime":300,"regressionDelivIds":[],"custom":{},"openTimestamp":1549396743000,"closeTimestamp":1551760200000,"lateAutoTest":true}},"output":{"timestamp":1551684717967,"report":{"scoreOverall":0,"scoreCover":null,"scoreTest":null,"feedback":"Container did not complete for `d2` in the allotted time.","passNames":[],"skipNames":[],"failNames":[],"errorNames":[],"result":"FAIL","attachments":[],"custom":{}},"postbackOnComplete":true,"custom":{},"state":"TIMEOUT","graderTaskId":"a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2"},"people":["c7s1b","n7o0b"]}
portal          | <I> 2019-3-4 08:23:34: GeneralRoutes::getResource(..) - user: i1y9a; token: e20762a707e27cc91e189714bec4080c58151e9a; path: /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 08:23:34: GeneralRoutes::performGetResource( [object Object], /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt ) - start
portal          | <I> 2019-3-4 08:23:34: GeneralRoutes::getResource(..) - start; trying to read file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 08:23:34: GeneralRoutes::getResource(..) - done; finished reading file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 16:46:46: AutoTestRouteHandler::atGetResult(..) - delivId: d1; repoId: project_c7s1b_n7o0b; sha: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
portal          | <I> 2019-3-4 16:46:46: ResultsController::getResult( d1, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - start
portal          | <I> 2019-3-4 16:46:46: DatabaseController::getResult( d1, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - not found
portal          | <I> 2019-3-4 16:48:36: AutoTestRouteHandler::atGrade(..) - repoId: project_c7s1b_n7o0b; delivId: d1; body: {"delivId":"d1","repoId":"project_c7s1b_n7o0b","repoURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b","score":91.92,"urlName":"project_c7s1b_n7o0b","URL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","comment":"","timestamp":1551718006473,"custom":{}}
portal          | <I> 2019-3-4 16:48:36: AdminController::processNewAutoTestGrade( .. ) - payload: {"delivId":"d1","repoId":"project_c7s1b_n7o0b","repoURL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b","score":91.92,"urlName":"project_c7s1b_n7o0b","URL":"https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb","comment":"","timestamp":1551718006473,"custom":{}}
portal          | <I> 2019-3-4 16:48:36: AutoTestRouteHandler::performPostResult(..) - valid result && valid secret; deliv: d1; repo: project_c7s1b_n7o0b; sha: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
portal          | <I> 2019-3-4 16:48:36: DatabaseController::getResult( d1, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - not found
portal          | <I> 2019-3-4 17:00:46: GeneralRoutes::getResource(..) - user: g4l0b; token: e3d6d08382f7d87a729ceddc8cae3329105236d0; path: /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:00:46: GeneralRoutes::performGetResource( [object Object], /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt ) - start
portal          | <I> 2019-3-4 17:00:46: GeneralRoutes::getResource(..) - start; trying to read file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:00:46: GeneralRoutes::getResource(..) - done; finished reading file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:03:49: GeneralRoutes::getResource(..) - user: g4l0b; token: e3d6d08382f7d87a729ceddc8cae3329105236d0; path: /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:03:49: GeneralRoutes::performGetResource( [object Object], /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt ) - start
portal          | <I> 2019-3-4 17:03:49: GeneralRoutes::getResource(..) - start; trying to read file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:03:49: GeneralRoutes::getResource(..) - done; finished reading file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:04:28: GeneralRoutes::getResource(..) - user: g4l0b; token: e3d6d08382f7d87a729ceddc8cae3329105236d0; path: /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:04:28: GeneralRoutes::performGetResource( [object Object], /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt ) - start
portal          | <I> 2019-3-4 17:04:28: GeneralRoutes::getResource(..) - start; trying to read file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:04:28: GeneralRoutes::getResource(..) - done; finished reading file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:21:47: GeneralRoutes::getResource(..) - user: g4l0b; token: e3d6d08382f7d87a729ceddc8cae3329105236d0; path: /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:21:47: GeneralRoutes::performGetResource( [object Object], /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt ) - start
portal          | <I> 2019-3-4 17:21:47: GeneralRoutes::getResource(..) - start; trying to read file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:21:47: GeneralRoutes::getResource(..) - done; finished reading file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:55:33: GeneralRoutes::getResource(..) - user: b2k0b; token: 70670923d9babe22f14defefebdcf33049ff8c6b; path: /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:55:33: GeneralRoutes::performGetResource( [object Object], /a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt ) - start
portal          | <I> 2019-3-4 17:55:33: GeneralRoutes::getResource(..) - start; trying to read file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
portal          | <I> 2019-3-4 17:55:33: GeneralRoutes::getResource(..) - done; finished reading file: /output/runs/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt
proxy           | 207.6.230.21 - - [04/Mar/2019:08:23:34 +0000] "GET /stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb HTTP/1.1" 200 6631 "https://cs310.ugrad.cs.ubc.ca/index.html" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 207.6.230.21 - - [04/Mar/2019:08:23:34 +0000] "GET /portal/resource/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt HTTP/1.1" 200 405211 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 207.6.230.21 - - [04/Mar/2019:08:23:35 +0000] "GET //portal/admin/dashboard/d2/project_c7s1b_n7o0b HTTP/1.1" 200 140941 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_3) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.154.231 - - [04/Mar/2019:17:00:46 +0000] "GET /stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb HTTP/1.1" 200 6631 "https://cs310.ugrad.cs.ubc.ca/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.154.231 - - [04/Mar/2019:17:00:46 +0000] "GET /portal/resource/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt HTTP/1.1" 200 404965 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.154.231 - - [04/Mar/2019:17:00:47 +0000] "GET //portal/admin/dashboard/d2/project_c7s1b_n7o0b HTTP/1.1" 200 140941 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.154.231 - - [04/Mar/2019:17:03:49 +0000] "GET /portal/resource/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt HTTP/1.1" 200 404989 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.154.231 - - [04/Mar/2019:17:03:50 +0000] "GET //portal/admin/dashboard/d2/project_c7s1b_n7o0b HTTP/1.1" 200 140941 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.154.231 - - [04/Mar/2019:17:04:28 +0000] "GET /portal/resource/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt HTTP/1.1" 200 405139 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.154.231 - - [04/Mar/2019:17:04:29 +0000] "GET //portal/admin/dashboard/d2/project_c7s1b_n7o0b HTTP/1.1" 200 140941 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.154.231 - - [04/Mar/2019:17:21:47 +0000] "GET /portal/resource/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt HTTP/1.1" 200 404965 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.154.231 - - [04/Mar/2019:17:21:48 +0000] "GET //portal/admin/dashboard/d2/project_c7s1b_n7o0b HTTP/1.1" 200 140941 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_14_1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.210.101 - - [04/Mar/2019:17:55:33 +0000] "GET /stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb HTTP/1.1" 200 6631 "https://cs310.ugrad.cs.ubc.ca/index.html" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.210.101 - - [04/Mar/2019:17:55:33 +0000] "GET /portal/resource/a9ebacd88150a965fe6b82012cf1bf0828f4bccb-d2/staff/stdio.txt HTTP/1.1" 200 405125 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 206.87.210.101 - - [04/Mar/2019:17:55:34 +0000] "GET //portal/admin/dashboard/d2/project_c7s1b_n7o0b HTTP/1.1" 200 140941 "https://cs310.ugrad.cs.ubc.ca/stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/72.0.3626.119 Safari/537.36"
proxy           | 18.207.236.34 - - [04/Mar/2019:18:02:06 +0000] "GET /stdio.html?delivId=d2&repoId=project_c7s1b_n7o0b&sha=a9ebacd88150a965fe6b82012cf1bf0828f4bccb HTTP/1.1" 200 6631 "-" "Slackbot-LinkExpanding 1.0 (+https://api.slack.com/robots)"
autotest        | <I> 2019-3-4 07:31:57: GitHubUtil::processPush(..) - regular push; # commits: 1; URL: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:31:57: GitHubUtil::processPush(..) - sha: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; commitURL: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:31:57: GitHubAutoTest::handlePushEvent(..) - start; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:31:57: AutoTest::addToStandardQueue(..) - start; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:31:57: Queue::scheduleNext() - standard done; delivId: d2; commitURL: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:31:57: AutoTest::tick(..) - starting job on: standard; deliv: d2; repo: project_c7s1b_n7o0b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:31:57: AutoTest::handleTick(..) - start; delivId: d2; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:31:57: GitHubAutoTest::handlePushEvent(..) - done; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; took: 12 ms
autotest        | <I> 2019-3-4 07:33:30: GitHubUtil::processPush(..) - regular push; # commits: 2; URL: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:33:30: GitHubUtil::processPush(..) - sha: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; commitURL: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:33:30: GitHubAutoTest::handlePushEvent(..) - start; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:33:30: AutoTest::addToStandardQueue(..) - start; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:33:30: GitHubAutoTest::handlePushEvent(..) - done; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; took: 10 ms
autotest        | <I> 2019-3-4 07:33:32: Queue::scheduleNext() - standard done; delivId: d2; commitURL: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:33:32: AutoTest::tick(..) - starting job on: standard; deliv: d2; repo: project_c7s1b_n7o0b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:33:32: AutoTest::handleTick(..) - start; delivId: d2; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::handleCommentEvent(..) - start; for: c7s1b; deliv: d2; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::checkCommentPreconditions(..) - for: c7s1b; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: ClassPortal::getResut( d2, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - start
autotest        | <I> 2019-3-4 07:34:27: ClassPortal::getResult(..) - requesting from: https://portal:3000/portal/at/result/d2/project_c7s1b_n7o0b/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::handleCommentEvent(..) - handleStudent; for: c7s1b; deliv: d2; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::handleCommentStudent(..) - handling student request for: c7s1b; deliv: d2; for commit: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::handleCommentStudent(..) - handling student request for: c7s1b for commit: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb; null previous: true; null delay: true
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::handleCommentStudent(..) - not too early; for: c7s1b; SHA: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::processCommentNew(..) - handling request for user: c7s1b for commit: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::processCommentNew(..) - result not yet done; handling for: c7s1b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: MongoDataStore::saveComment(..) - start; delivId: d2; repo: project_c7s1b_n7o0b; url: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::postToGitHub(..) - posting message to: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments
autotest        | <I> 2019-3-4 07:34:27: GitHubUtil::postMarkdownToGithub(..) - Posting markdown to url: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments; message: This commit is still queued for processing against d2. Your results will be post...
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::handleCommentEvent(..) - start; for: autobot; deliv: null; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:34:27: GitHubAutoTest::checkCommentPreconditions(..) - for: autobot; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:35:21: ClassPortal::sendGrade(..) - grade accepted; url: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:35:21: AutoTest::handleExecutionComplete(..) - start: delivId: d2; repoId: project_c7s1b_n7o0b; took (waiting + execution): 1 minute and 51 seconds; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:35:21: ClassPortal::sendResult(..) - result accepted; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:35:21: GitHubAutoTest::processExecution(..) - check feedback requested; deliv: d2; repo: project_c7s1b_n7o0b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bcc; for: c7s1b
autotest        | <I> 2019-3-4 07:35:21: GitHubAutoTest::postToGitHub(..) - posting message to: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments
autotest        | <I> 2019-3-4 07:35:21: GitHubUtil::postMarkdownToGithub(..) - Posting markdown to url: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments; message: **Public test results**
autotest        | <I> 2019-3-4 07:35:22: AutoTest::handleExecutionComplete(..) - done; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; final processing took: 260 ms
autotest        | <I> 2019-3-4 07:35:22: AutoTest::handleTick(..) - complete; delivId: d2; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; took: 1 minute and 49 seconds
autotest        | <I> 2019-3-4 07:35:22: GitHubAutoTest::handleCommentEvent(..) - start; for: autobot; deliv: null; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:35:22: GitHubAutoTest::checkCommentPreconditions(..) - for: autobot; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:37:09: ClassPortal::sendGrade(..) - grade accepted; url: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:37:09: AutoTest::handleExecutionComplete(..) - start: delivId: d2; repoId: project_c7s1b_n7o0b; took (waiting + execution): 5 minutes and 11 seconds; SHA:a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:37:09: ClassPortal::sendResult(..) - result accepted; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:37:09: GitHubAutoTest::processExecution(..) - postback: true; deliv: d2; repo: project_c7s1b_n7o0b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:37:09: GitHubAutoTest::postToGitHub(..) - posting message to: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments
autotest        | <I> 2019-3-4 07:37:09: GitHubUtil::postMarkdownToGithub(..) - Posting markdown to url: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments; message: Container did not complete for `d2` in the allotted time.
autotest        | <I> 2019-3-4 07:37:09: AutoTest::handleExecutionComplete(..) - done; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; final processing took: 258 ms
autotest        | <I> 2019-3-4 07:37:09: AutoTest::handleTick(..) - complete; delivId: d2; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; took: 5 minutes and 11 seconds
autotest        | <I> 2019-3-4 07:37:09: GitHubAutoTest::handleCommentEvent(..) - start; for: autobot; deliv: null; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:37:09: GitHubAutoTest::checkCommentPreconditions(..) - for: autobot; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::handleCommentEvent(..) - start; for: c7s1b; deliv: d2; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::checkCommentPreconditions(..) - for: c7s1b; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: ClassPortal::getResut( d2, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - start
autotest        | <I> 2019-3-4 07:40:30: ClassPortal::getResult(..) - requesting from: https://portal:3000/portal/at/result/d2/project_c7s1b_n7o0b/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::handleCommentEvent(..) - handleStudent; for: c7s1b; deliv: d2; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::handleCommentStudent(..) - handling student request for: c7s1b; deliv: d2; for commit: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::handleCommentStudent(..) - handling student request for: c7s1b for commit: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb; null previous: false; null delay: false
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::handleCommentStudent(..) - feedback previously given for: c7s1b; deliv: d2; SHA: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::processCommentExists(..) - handling request for user: c7s1b for commit: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::processCommentExists(..) - result already exists; handling for: c7s1b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::postToGitHub(..) - posting message to: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments
autotest        | <I> 2019-3-4 07:40:30: GitHubUtil::postMarkdownToGithub(..) - Posting markdown to url: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments; message: Container did not complete for `d2` in the allotted time.
autotest        | <I> 2019-3-4 07:40:30: MongoDataStore::saveComment(..) - start; delivId: d2; repo: project_c7s1b_n7o0b; url: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::processCommentExists(..) - result already exists; feedback request skipped for: c7s1b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bcc
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::handleCommentEvent(..) - start; for: autobot; deliv: null; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 07:40:30: GitHubAutoTest::checkCommentPreconditions(..) - for: autobot; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::handleCommentEvent(..) - start; for: c7s1b; deliv: d1; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::checkCommentPreconditions(..) - for: c7s1b; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: ClassPortal::getResut( d1, project_c7s1b_n7o0b, a9ebacd88150a965fe6b82012cf1bf0828f4bccb ) - start
autotest        | <I> 2019-3-4 16:46:46: ClassPortal::getResult(..) - requesting from: https://portal:3000/portal/at/result/d1/project_c7s1b_n7o0b/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::handleCommentEvent(..) - handleStudent; for: c7s1b; deliv: d1; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::handleCommentStudent(..) - handling student request for: c7s1b; deliv: d1; for commit: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::handleCommentStudent(..) - handling student request for: c7s1b for commit: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb; null previous: true; null delay: true
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::handleCommentStudent(..) - not too early; for: c7s1b; SHA: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::processCommentNew(..) - handling request for user: c7s1b for commit: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::processCommentNew(..) - result not yet done; handling for: c7s1b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::schedule(..) - scheduling for: c7s1b; SHA: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: AutoTest::addToStandardQueue(..) - start; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: Queue::scheduleNext() - standard done; delivId: d1; commitURL: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: AutoTest::tick(..) - starting job on: standard; deliv: d1; repo: project_c7s1b_n7o0b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: AutoTest::handleTick(..) - start; delivId: d1; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::schedule(..) - scheduling completed for: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: MongoDataStore::saveComment(..) - start; delivId: d1; repo: project_c7s1b_n7o0b; url: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::postToGitHub(..) - posting message to: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments
autotest        | <I> 2019-3-4 16:46:46: GitHubUtil::postMarkdownToGithub(..) - Posting markdown to url: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments; message: This commit has been queued for processing against d1. Your results will be post...
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::handleCommentEvent(..) - start; for: autobot; deliv: null; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:46:46: GitHubAutoTest::checkCommentPreconditions(..) - for: autobot; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:48:36: ClassPortal::sendGrade(..) - grade accepted; url: https://github.ugrad.cs.ubc.ca/CPSC310-2018W-T2/project_c7s1b_n7o0b/commit/a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:48:36: AutoTest::handleExecutionComplete(..) - start: delivId: d1; repoId: project_c7s1b_n7o0b; took (waiting + execution): 1 minute and 49 seconds; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:48:36: ClassPortal::sendResult(..) - result accepted; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:48:36: GitHubAutoTest::processExecution(..) - check feedback requested; deliv: d1; repo: project_c7s1b_n7o0b; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bcc; for: c7s1b
autotest        | <I> 2019-3-4 16:48:36: GitHubAutoTest::postToGitHub(..) - posting message to: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments
autotest        | <I> 2019-3-4 16:48:36: GitHubUtil::postMarkdownToGithub(..) - Posting markdown to url: https://github.ugrad.cs.ubc.ca/api/v3/repos/CPSC310-2018W-T2/project_c7s1b_n7o0b/commits/a9ebacd88150a965fe6b82012cf1bf0828f4bccb/comments; message: **Public test results**
autotest        | <I> 2019-3-4 16:48:36: AutoTest::handleExecutionComplete(..) - done; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; final processing took: 230 ms
autotest        | <I> 2019-3-4 16:48:36: AutoTest::handleTick(..) - complete; delivId: d1; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb; took: 1 minute and 49 seconds
autotest        | <I> 2019-3-4 16:48:36: GitHubAutoTest::handleCommentEvent(..) - start; for: autobot; deliv: null; SHA: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
autotest        | <I> 2019-3-4 16:48:36: GitHubAutoTest::checkCommentPreconditions(..) - for: autobot; commit: a9ebacd88150a965fe6b82012cf1bf0828f4bccb
I think the real question is why a d2 result was returned the first time.
ubccpsc / classy

Bot does not respond after giving pending message #232
