Removed context, lowered info log level to debug and some small millisecond time log. (hyperjumptech#29)

* Moved operators functions from Expression to our reflectmath.go

* Removed DataContext from context package to avoid confusion, I know that this update is not additive, but better remove this.

* Lowered log level from info to debug that emit on the start and ends of execute

* Make proper duration millisecond debug

Author: newm4n

Makefile

@@ -8,7 +8,7 @@ build:
 
 test: build
 	go test ./... -v -covermode=count -coverprofile=coverage.out
-	golint -set_exit_status builder/... context/... engine/... examples/... ast/... pkg/... antlr/.
+	golint -set_exit_status builder/... engine/... examples/... ast/... pkg/... antlr/.
 
 test-coverage: test
 	go tool cover -html=coverage.out

context/DataContext_test.go renamed to ast/DataContext_test.go

@@ -1,10 +1,11 @@
-package context
+package ast
 
 import (
 	"fmt"
-	"github.com/hyperjumptech/grule-rule-engine/pkg"
 	"reflect"
 	"testing"
+
+	"github.com/hyperjumptech/grule-rule-engine/pkg"
 )
 
 type TestAStruct struct {

context/DataContext.go

@@ -46,7 +46,7 @@ func (g *GruleEngine) notifySubscribers(r *ast.RuleEntry) {
 // Execute function will execute a knowledge evaluation and action against data context.
 // The engine also do conflict resolution of which rule to execute.
 func (g *GruleEngine) Execute(dataCtx *ast.DataContext, knowledge *ast.KnowledgeBase, memory *ast.WorkingMemory) error {
-	log.Infof("Starting rule execution using knowledge '%s' version %s. Contains %d rule entries", knowledge.Name, knowledge.Version, len(knowledge.RuleEntries))
+	log.Debugf("Starting rule execution using knowledge '%s' version %s. Contains %d rule entries", knowledge.Name, knowledge.Version, len(knowledge.RuleEntries))
 
 	knowledge.WorkingMemory = memory
 
@@ -137,6 +137,6 @@ func (g *GruleEngine) Execute(dataCtx *ast.DataContext, knowledge *ast.Knowledge
 			break
 		}
 	}
-	log.Infof("Finished Rules execution. With knowledge base '%s' version %s. Total #%d cycles. Duration %d microsec.", knowledge.Name, knowledge.Version, cycle, time.Now().Sub(startTime))
+	log.Debugf("Finished Rules execution. With knowledge base '%s' version %s. Total #%d cycles. Duration %d ms.", knowledge.Name, knowledge.Version, cycle, time.Now().Sub(startTime).Milliseconds())
 	return nil
 }

context/KnowledgeContext.go

@@ -86,6 +86,7 @@ rule SetTime "When Distance Recorder time not set, set it." {
 )
 
 func TestGrule_Execute(t *testing.T) {
+	logrus.SetLevel(logrus.DebugLevel)
 	tc := &TestCar{
 		SpeedUp:        true,
 		Speed:          0,
@@ -121,7 +122,7 @@ func TestGrule_Execute(t *testing.T) {
 		} else {
 			dur := time.Since(start)
 			t.Log(dr.TotalDistance)
-			t.Logf("Duration %f ms", float64(dur)/float64(time.Millisecond))
+			t.Logf("Duration %d ms", dur.Milliseconds())
 		}
 	}
 }