docker_cli_events_test.go 29 KB

123456789101112131415161718192021222324252627282930313233343536373839404142434445464748495051525354555657585960616263646566676869707172737475767778798081828384858687888990919293949596979899100101102103104105106107108109110111112113114115116117118119120121122123124125126127128129130131132133134135136137138139140141142143144145146147148149150151152153154155156157158159160161162163164165166167168169170171172173174175176177178179180181182183184185186187188189190191192193194195196197198199200201202203204205206207208209210211212213214215216217218219220221222223224225226227228229230231232233234235236237238239240241242243244245246247248249250251252253254255256257258259260261262263264265266267268269270271272273274275276277278279280281282283284285286287288289290291292293294295296297298299300301302303304305306307308309310311312313314315316317318319320321322323324325326327328329330331332333334335336337338339340341342343344345346347348349350351352353354355356357358359360361362363364365366367368369370371372373374375376377378379380381382383384385386387388389390391392393394395396397398399400401402403404405406407408409410411412413414415416417418419420421422423424425426427428429430431432433434435436437438439440441442443444445446447448449450451452453454455456457458459460461462463464465466467468469470471472473474475476477478479480481482483484485486487488489490491492493494495496497498499500501502503504505506507508509510511512513514515516517518519520521522523524525526527528529530531532533534535536537538539540541542543544545546547548549550551552553554555556557558559560561562563564565566567568569570571572573574575576577578579580581582583584585586587588589590591592593594595596597598599600601602603604605606607608609610611612613614615616617618619620621622623624625626627628629630631632633634635636637638639640641642643644645646647648649650651652653654655656657658659660661662663664665666667668669670671672673674675676677678679680681682683684685686687688689690691692693694695696697698699700701702703704705706707708709710711712713714715716717718719720721722723724725726727728729730731732733734735736737738739740741742743744745746747748749750751752753754755756757758759760761762763764765766767768769770771772773774775776777778779780781782783784785786787788789790791792793794795796797798799800801802803804805
  1. package main
  2. import (
  3. "bufio"
  4. "encoding/json"
  5. "fmt"
  6. "io"
  7. "io/ioutil"
  8. "os"
  9. "os/exec"
  10. "strings"
  11. "time"
  12. "github.com/docker/docker/api/types"
  13. eventtypes "github.com/docker/docker/api/types/events"
  14. "github.com/docker/docker/client"
  15. eventstestutils "github.com/docker/docker/daemon/events/testutils"
  16. "github.com/docker/docker/integration-cli/checker"
  17. "github.com/docker/docker/integration-cli/cli"
  18. "github.com/docker/docker/integration-cli/cli/build"
  19. "github.com/go-check/check"
  20. "github.com/gotestyourself/gotestyourself/icmd"
  21. "golang.org/x/net/context"
  22. )
  23. func (s *DockerSuite) TestEventsTimestampFormats(c *check.C) {
  24. name := "events-time-format-test"
  25. // Start stopwatch, generate an event
  26. start := daemonTime(c)
  27. time.Sleep(1100 * time.Millisecond) // so that first event occur in different second from since (just for the case)
  28. dockerCmd(c, "run", "--rm", "--name", name, "busybox", "true")
  29. time.Sleep(1100 * time.Millisecond) // so that until > since
  30. end := daemonTime(c)
  31. // List of available time formats to --since
  32. unixTs := func(t time.Time) string { return fmt.Sprintf("%v", t.Unix()) }
  33. rfc3339 := func(t time.Time) string { return t.Format(time.RFC3339) }
  34. duration := func(t time.Time) string { return time.Since(t).String() }
  35. // --since=$start must contain only the 'untag' event
  36. for _, f := range []func(time.Time) string{unixTs, rfc3339, duration} {
  37. since, until := f(start), f(end)
  38. out, _ := dockerCmd(c, "events", "--since="+since, "--until="+until)
  39. events := strings.Split(out, "\n")
  40. events = events[:len(events)-1]
  41. nEvents := len(events)
  42. c.Assert(nEvents, checker.GreaterOrEqualThan, 5) //Missing expected event
  43. containerEvents := eventActionsByIDAndType(c, events, name, "container")
  44. c.Assert(containerEvents, checker.HasLen, 5, check.Commentf("events: %v", events))
  45. c.Assert(containerEvents[0], checker.Equals, "create", check.Commentf(out))
  46. c.Assert(containerEvents[1], checker.Equals, "attach", check.Commentf(out))
  47. c.Assert(containerEvents[2], checker.Equals, "start", check.Commentf(out))
  48. c.Assert(containerEvents[3], checker.Equals, "die", check.Commentf(out))
  49. c.Assert(containerEvents[4], checker.Equals, "destroy", check.Commentf(out))
  50. }
  51. }
  52. func (s *DockerSuite) TestEventsUntag(c *check.C) {
  53. image := "busybox"
  54. dockerCmd(c, "tag", image, "utest:tag1")
  55. dockerCmd(c, "tag", image, "utest:tag2")
  56. dockerCmd(c, "rmi", "utest:tag1")
  57. dockerCmd(c, "rmi", "utest:tag2")
  58. result := icmd.RunCmd(icmd.Cmd{
  59. Command: []string{dockerBinary, "events", "--since=1"},
  60. Timeout: time.Millisecond * 2500,
  61. })
  62. result.Assert(c, icmd.Expected{Timeout: true})
  63. events := strings.Split(result.Stdout(), "\n")
  64. nEvents := len(events)
  65. // The last element after the split above will be an empty string, so we
  66. // get the two elements before the last, which are the untags we're
  67. // looking for.
  68. for _, v := range events[nEvents-3 : nEvents-1] {
  69. c.Assert(v, checker.Contains, "untag", check.Commentf("event should be untag"))
  70. }
  71. }
  72. func (s *DockerSuite) TestEventsLimit(c *check.C) {
  73. // Windows: Limit to 4 goroutines creating containers in order to prevent
  74. // timeouts creating so many containers simultaneously. This is a due to
  75. // a bug in the Windows platform. It will be fixed in a Windows Update.
  76. numContainers := 17
  77. numConcurrentContainers := numContainers
  78. if testEnv.DaemonPlatform() == "windows" {
  79. numConcurrentContainers = 4
  80. }
  81. sem := make(chan bool, numConcurrentContainers)
  82. errChan := make(chan error, numContainers)
  83. args := []string{"run", "--rm", "busybox", "true"}
  84. for i := 0; i < numContainers; i++ {
  85. sem <- true
  86. go func() {
  87. defer func() { <-sem }()
  88. out, err := exec.Command(dockerBinary, args...).CombinedOutput()
  89. if err != nil {
  90. err = fmt.Errorf("%v: %s", err, string(out))
  91. }
  92. errChan <- err
  93. }()
  94. }
  95. // Wait for all goroutines to finish
  96. for i := 0; i < cap(sem); i++ {
  97. sem <- true
  98. }
  99. close(errChan)
  100. for err := range errChan {
  101. c.Assert(err, checker.IsNil, check.Commentf("%q failed with error", strings.Join(args, " ")))
  102. }
  103. out, _ := dockerCmd(c, "events", "--since=0", "--until", daemonUnixTime(c))
  104. events := strings.Split(out, "\n")
  105. nEvents := len(events) - 1
  106. c.Assert(nEvents, checker.Equals, 256, check.Commentf("events should be limited to 256, but received %d", nEvents))
  107. }
  108. func (s *DockerSuite) TestEventsContainerEvents(c *check.C) {
  109. dockerCmd(c, "run", "--rm", "--name", "container-events-test", "busybox", "true")
  110. out, _ := dockerCmd(c, "events", "--until", daemonUnixTime(c))
  111. events := strings.Split(out, "\n")
  112. events = events[:len(events)-1]
  113. nEvents := len(events)
  114. c.Assert(nEvents, checker.GreaterOrEqualThan, 5) //Missing expected event
  115. containerEvents := eventActionsByIDAndType(c, events, "container-events-test", "container")
  116. c.Assert(containerEvents, checker.HasLen, 5, check.Commentf("events: %v", events))
  117. c.Assert(containerEvents[0], checker.Equals, "create", check.Commentf(out))
  118. c.Assert(containerEvents[1], checker.Equals, "attach", check.Commentf(out))
  119. c.Assert(containerEvents[2], checker.Equals, "start", check.Commentf(out))
  120. c.Assert(containerEvents[3], checker.Equals, "die", check.Commentf(out))
  121. c.Assert(containerEvents[4], checker.Equals, "destroy", check.Commentf(out))
  122. }
  123. func (s *DockerSuite) TestEventsContainerEventsAttrSort(c *check.C) {
  124. since := daemonUnixTime(c)
  125. dockerCmd(c, "run", "--rm", "--name", "container-events-test", "busybox", "true")
  126. out, _ := dockerCmd(c, "events", "--filter", "container=container-events-test", "--since", since, "--until", daemonUnixTime(c))
  127. events := strings.Split(out, "\n")
  128. nEvents := len(events)
  129. c.Assert(nEvents, checker.GreaterOrEqualThan, 3) //Missing expected event
  130. matchedEvents := 0
  131. for _, event := range events {
  132. matches := eventstestutils.ScanMap(event)
  133. if matches["eventType"] == "container" && matches["action"] == "create" {
  134. matchedEvents++
  135. c.Assert(out, checker.Contains, "(image=busybox, name=container-events-test)", check.Commentf("Event attributes not sorted"))
  136. } else if matches["eventType"] == "container" && matches["action"] == "start" {
  137. matchedEvents++
  138. c.Assert(out, checker.Contains, "(image=busybox, name=container-events-test)", check.Commentf("Event attributes not sorted"))
  139. }
  140. }
  141. c.Assert(matchedEvents, checker.Equals, 2, check.Commentf("missing events for container container-events-test:\n%s", out))
  142. }
  143. func (s *DockerSuite) TestEventsContainerEventsSinceUnixEpoch(c *check.C) {
  144. dockerCmd(c, "run", "--rm", "--name", "since-epoch-test", "busybox", "true")
  145. timeBeginning := time.Unix(0, 0).Format(time.RFC3339Nano)
  146. timeBeginning = strings.Replace(timeBeginning, "Z", ".000000000Z", -1)
  147. out, _ := dockerCmd(c, "events", "--since", timeBeginning, "--until", daemonUnixTime(c))
  148. events := strings.Split(out, "\n")
  149. events = events[:len(events)-1]
  150. nEvents := len(events)
  151. c.Assert(nEvents, checker.GreaterOrEqualThan, 5) //Missing expected event
  152. containerEvents := eventActionsByIDAndType(c, events, "since-epoch-test", "container")
  153. c.Assert(containerEvents, checker.HasLen, 5, check.Commentf("events: %v", events))
  154. c.Assert(containerEvents[0], checker.Equals, "create", check.Commentf(out))
  155. c.Assert(containerEvents[1], checker.Equals, "attach", check.Commentf(out))
  156. c.Assert(containerEvents[2], checker.Equals, "start", check.Commentf(out))
  157. c.Assert(containerEvents[3], checker.Equals, "die", check.Commentf(out))
  158. c.Assert(containerEvents[4], checker.Equals, "destroy", check.Commentf(out))
  159. }
  160. func (s *DockerSuite) TestEventsImageTag(c *check.C) {
  161. time.Sleep(1 * time.Second) // because API has seconds granularity
  162. since := daemonUnixTime(c)
  163. image := "testimageevents:tag"
  164. dockerCmd(c, "tag", "busybox", image)
  165. out, _ := dockerCmd(c, "events",
  166. "--since", since, "--until", daemonUnixTime(c))
  167. events := strings.Split(strings.TrimSpace(out), "\n")
  168. c.Assert(events, checker.HasLen, 1, check.Commentf("was expecting 1 event. out=%s", out))
  169. event := strings.TrimSpace(events[0])
  170. matches := eventstestutils.ScanMap(event)
  171. c.Assert(matchEventID(matches, image), checker.True, check.Commentf("matches: %v\nout:\n%s", matches, out))
  172. c.Assert(matches["action"], checker.Equals, "tag")
  173. }
  174. func (s *DockerSuite) TestEventsImagePull(c *check.C) {
  175. // TODO Windows: Enable this test once pull and reliable image names are available
  176. testRequires(c, DaemonIsLinux)
  177. since := daemonUnixTime(c)
  178. testRequires(c, Network)
  179. dockerCmd(c, "pull", "hello-world")
  180. out, _ := dockerCmd(c, "events",
  181. "--since", since, "--until", daemonUnixTime(c))
  182. events := strings.Split(strings.TrimSpace(out), "\n")
  183. event := strings.TrimSpace(events[len(events)-1])
  184. matches := eventstestutils.ScanMap(event)
  185. c.Assert(matches["id"], checker.Equals, "hello-world:latest")
  186. c.Assert(matches["action"], checker.Equals, "pull")
  187. }
  188. func (s *DockerSuite) TestEventsImageImport(c *check.C) {
  189. // TODO Windows CI. This should be portable once export/import are
  190. // more reliable (@swernli)
  191. testRequires(c, DaemonIsLinux)
  192. out, _ := dockerCmd(c, "run", "-d", "busybox", "true")
  193. cleanedContainerID := strings.TrimSpace(out)
  194. since := daemonUnixTime(c)
  195. out, err := RunCommandPipelineWithOutput(
  196. exec.Command(dockerBinary, "export", cleanedContainerID),
  197. exec.Command(dockerBinary, "import", "-"),
  198. )
  199. c.Assert(err, checker.IsNil, check.Commentf("import failed with output: %q", out))
  200. imageRef := strings.TrimSpace(out)
  201. out, _ = dockerCmd(c, "events", "--since", since, "--until", daemonUnixTime(c), "--filter", "event=import")
  202. events := strings.Split(strings.TrimSpace(out), "\n")
  203. c.Assert(events, checker.HasLen, 1)
  204. matches := eventstestutils.ScanMap(events[0])
  205. c.Assert(matches["id"], checker.Equals, imageRef, check.Commentf("matches: %v\nout:\n%s\n", matches, out))
  206. c.Assert(matches["action"], checker.Equals, "import", check.Commentf("matches: %v\nout:\n%s\n", matches, out))
  207. }
  208. func (s *DockerSuite) TestEventsImageLoad(c *check.C) {
  209. testRequires(c, DaemonIsLinux)
  210. myImageName := "footest:v1"
  211. dockerCmd(c, "tag", "busybox", myImageName)
  212. since := daemonUnixTime(c)
  213. out, _ := dockerCmd(c, "images", "-q", "--no-trunc", myImageName)
  214. longImageID := strings.TrimSpace(out)
  215. c.Assert(longImageID, checker.Not(check.Equals), "", check.Commentf("Id should not be empty"))
  216. dockerCmd(c, "save", "-o", "saveimg.tar", myImageName)
  217. dockerCmd(c, "rmi", myImageName)
  218. out, _ = dockerCmd(c, "images", "-q", myImageName)
  219. noImageID := strings.TrimSpace(out)
  220. c.Assert(noImageID, checker.Equals, "", check.Commentf("Should not have any image"))
  221. dockerCmd(c, "load", "-i", "saveimg.tar")
  222. result := icmd.RunCommand("rm", "-rf", "saveimg.tar")
  223. result.Assert(c, icmd.Success)
  224. out, _ = dockerCmd(c, "images", "-q", "--no-trunc", myImageName)
  225. imageID := strings.TrimSpace(out)
  226. c.Assert(imageID, checker.Equals, longImageID, check.Commentf("Should have same image id as before"))
  227. out, _ = dockerCmd(c, "events", "--since", since, "--until", daemonUnixTime(c), "--filter", "event=load")
  228. events := strings.Split(strings.TrimSpace(out), "\n")
  229. c.Assert(events, checker.HasLen, 1)
  230. matches := eventstestutils.ScanMap(events[0])
  231. c.Assert(matches["id"], checker.Equals, imageID, check.Commentf("matches: %v\nout:\n%s\n", matches, out))
  232. c.Assert(matches["action"], checker.Equals, "load", check.Commentf("matches: %v\nout:\n%s\n", matches, out))
  233. out, _ = dockerCmd(c, "events", "--since", since, "--until", daemonUnixTime(c), "--filter", "event=save")
  234. events = strings.Split(strings.TrimSpace(out), "\n")
  235. c.Assert(events, checker.HasLen, 1)
  236. matches = eventstestutils.ScanMap(events[0])
  237. c.Assert(matches["id"], checker.Equals, imageID, check.Commentf("matches: %v\nout:\n%s\n", matches, out))
  238. c.Assert(matches["action"], checker.Equals, "save", check.Commentf("matches: %v\nout:\n%s\n", matches, out))
  239. }
  240. func (s *DockerSuite) TestEventsPluginOps(c *check.C) {
  241. testRequires(c, DaemonIsLinux, IsAmd64, Network)
  242. since := daemonUnixTime(c)
  243. dockerCmd(c, "plugin", "install", pNameWithTag, "--grant-all-permissions")
  244. dockerCmd(c, "plugin", "disable", pNameWithTag)
  245. dockerCmd(c, "plugin", "remove", pNameWithTag)
  246. out, _ := dockerCmd(c, "events", "--since", since, "--until", daemonUnixTime(c))
  247. events := strings.Split(out, "\n")
  248. events = events[:len(events)-1]
  249. nEvents := len(events)
  250. c.Assert(nEvents, checker.GreaterOrEqualThan, 4)
  251. pluginEvents := eventActionsByIDAndType(c, events, pNameWithTag, "plugin")
  252. c.Assert(pluginEvents, checker.HasLen, 4, check.Commentf("events: %v", events))
  253. c.Assert(pluginEvents[0], checker.Equals, "pull", check.Commentf(out))
  254. c.Assert(pluginEvents[1], checker.Equals, "enable", check.Commentf(out))
  255. c.Assert(pluginEvents[2], checker.Equals, "disable", check.Commentf(out))
  256. c.Assert(pluginEvents[3], checker.Equals, "remove", check.Commentf(out))
  257. }
  258. func (s *DockerSuite) TestEventsFilters(c *check.C) {
  259. since := daemonUnixTime(c)
  260. dockerCmd(c, "run", "--rm", "busybox", "true")
  261. dockerCmd(c, "run", "--rm", "busybox", "true")
  262. out, _ := dockerCmd(c, "events", "--since", since, "--until", daemonUnixTime(c), "--filter", "event=die")
  263. parseEvents(c, out, "die")
  264. out, _ = dockerCmd(c, "events", "--since", since, "--until", daemonUnixTime(c), "--filter", "event=die", "--filter", "event=start")
  265. parseEvents(c, out, "die|start")
  266. // make sure we at least got 2 start events
  267. count := strings.Count(out, "start")
  268. c.Assert(strings.Count(out, "start"), checker.GreaterOrEqualThan, 2, check.Commentf("should have had 2 start events but had %d, out: %s", count, out))
  269. }
  270. func (s *DockerSuite) TestEventsFilterImageName(c *check.C) {
  271. since := daemonUnixTime(c)
  272. out, _ := dockerCmd(c, "run", "--name", "container_1", "-d", "busybox:latest", "true")
  273. container1 := strings.TrimSpace(out)
  274. out, _ = dockerCmd(c, "run", "--name", "container_2", "-d", "busybox", "true")
  275. container2 := strings.TrimSpace(out)
  276. name := "busybox"
  277. out, _ = dockerCmd(c, "events", "--since", since, "--until", daemonUnixTime(c), "--filter", fmt.Sprintf("image=%s", name))
  278. events := strings.Split(out, "\n")
  279. events = events[:len(events)-1]
  280. c.Assert(events, checker.Not(checker.HasLen), 0) //Expected events but found none for the image busybox:latest
  281. count1 := 0
  282. count2 := 0
  283. for _, e := range events {
  284. if strings.Contains(e, container1) {
  285. count1++
  286. } else if strings.Contains(e, container2) {
  287. count2++
  288. }
  289. }
  290. c.Assert(count1, checker.Not(checker.Equals), 0, check.Commentf("Expected event from container but got %d from %s", count1, container1))
  291. c.Assert(count2, checker.Not(checker.Equals), 0, check.Commentf("Expected event from container but got %d from %s", count2, container2))
  292. }
  293. func (s *DockerSuite) TestEventsFilterLabels(c *check.C) {
  294. since := daemonUnixTime(c)
  295. label := "io.docker.testing=foo"
  296. out, _ := dockerCmd(c, "run", "-d", "-l", label, "busybox:latest", "true")
  297. container1 := strings.TrimSpace(out)
  298. out, _ = dockerCmd(c, "run", "-d", "busybox", "true")
  299. container2 := strings.TrimSpace(out)
  300. out, _ = dockerCmd(
  301. c,
  302. "events",
  303. "--since", since,
  304. "--until", daemonUnixTime(c),
  305. "--filter", fmt.Sprintf("label=%s", label))
  306. events := strings.Split(strings.TrimSpace(out), "\n")
  307. c.Assert(len(events), checker.Equals, 3)
  308. for _, e := range events {
  309. c.Assert(e, checker.Contains, container1)
  310. c.Assert(e, checker.Not(checker.Contains), container2)
  311. }
  312. }
  313. func (s *DockerSuite) TestEventsFilterImageLabels(c *check.C) {
  314. since := daemonUnixTime(c)
  315. name := "labelfiltertest"
  316. label := "io.docker.testing=image"
  317. // Build a test image.
  318. buildImageSuccessfully(c, name, build.WithDockerfile(fmt.Sprintf(`
  319. FROM busybox:latest
  320. LABEL %s`, label)))
  321. dockerCmd(c, "tag", name, "labelfiltertest:tag1")
  322. dockerCmd(c, "tag", name, "labelfiltertest:tag2")
  323. dockerCmd(c, "tag", "busybox:latest", "labelfiltertest:tag3")
  324. out, _ := dockerCmd(
  325. c,
  326. "events",
  327. "--since", since,
  328. "--until", daemonUnixTime(c),
  329. "--filter", fmt.Sprintf("label=%s", label),
  330. "--filter", "type=image")
  331. events := strings.Split(strings.TrimSpace(out), "\n")
  332. // 2 events from the "docker tag" command, another one is from "docker build"
  333. c.Assert(events, checker.HasLen, 3, check.Commentf("Events == %s", events))
  334. for _, e := range events {
  335. c.Assert(e, checker.Contains, "labelfiltertest")
  336. }
  337. }
  338. func (s *DockerSuite) TestEventsFilterContainer(c *check.C) {
  339. since := daemonUnixTime(c)
  340. nameID := make(map[string]string)
  341. for _, name := range []string{"container_1", "container_2"} {
  342. dockerCmd(c, "run", "--name", name, "busybox", "true")
  343. id := inspectField(c, name, "Id")
  344. nameID[name] = id
  345. }
  346. until := daemonUnixTime(c)
  347. checkEvents := func(id string, events []string) error {
  348. if len(events) != 4 { // create, attach, start, die
  349. return fmt.Errorf("expected 4 events, got %v", events)
  350. }
  351. for _, event := range events {
  352. matches := eventstestutils.ScanMap(event)
  353. if !matchEventID(matches, id) {
  354. return fmt.Errorf("expected event for container id %s: %s - parsed container id: %s", id, event, matches["id"])
  355. }
  356. }
  357. return nil
  358. }
  359. for name, ID := range nameID {
  360. // filter by names
  361. out, _ := dockerCmd(c, "events", "--since", since, "--until", until, "--filter", "container="+name)
  362. events := strings.Split(strings.TrimSuffix(out, "\n"), "\n")
  363. c.Assert(checkEvents(ID, events), checker.IsNil)
  364. // filter by ID's
  365. out, _ = dockerCmd(c, "events", "--since", since, "--until", until, "--filter", "container="+ID)
  366. events = strings.Split(strings.TrimSuffix(out, "\n"), "\n")
  367. c.Assert(checkEvents(ID, events), checker.IsNil)
  368. }
  369. }
  370. func (s *DockerSuite) TestEventsCommit(c *check.C) {
  371. // Problematic on Windows as cannot commit a running container
  372. testRequires(c, DaemonIsLinux)
  373. out := runSleepingContainer(c)
  374. cID := strings.TrimSpace(out)
  375. cli.WaitRun(c, cID)
  376. cli.DockerCmd(c, "commit", "-m", "test", cID)
  377. cli.DockerCmd(c, "stop", cID)
  378. cli.WaitExited(c, cID, 5*time.Second)
  379. until := daemonUnixTime(c)
  380. out = cli.DockerCmd(c, "events", "-f", "container="+cID, "--until="+until).Combined()
  381. c.Assert(out, checker.Contains, "commit", check.Commentf("Missing 'commit' log event"))
  382. }
  383. func (s *DockerSuite) TestEventsCopy(c *check.C) {
  384. // Build a test image.
  385. buildImageSuccessfully(c, "cpimg", build.WithDockerfile(`
  386. FROM busybox
  387. RUN echo HI > /file`))
  388. id := getIDByName(c, "cpimg")
  389. // Create an empty test file.
  390. tempFile, err := ioutil.TempFile("", "test-events-copy-")
  391. c.Assert(err, checker.IsNil)
  392. defer os.Remove(tempFile.Name())
  393. c.Assert(tempFile.Close(), checker.IsNil)
  394. dockerCmd(c, "create", "--name=cptest", id)
  395. dockerCmd(c, "cp", "cptest:/file", tempFile.Name())
  396. until := daemonUnixTime(c)
  397. out, _ := dockerCmd(c, "events", "--since=0", "-f", "container=cptest", "--until="+until)
  398. c.Assert(out, checker.Contains, "archive-path", check.Commentf("Missing 'archive-path' log event\n"))
  399. dockerCmd(c, "cp", tempFile.Name(), "cptest:/filecopy")
  400. until = daemonUnixTime(c)
  401. out, _ = dockerCmd(c, "events", "-f", "container=cptest", "--until="+until)
  402. c.Assert(out, checker.Contains, "extract-to-dir", check.Commentf("Missing 'extract-to-dir' log event"))
  403. }
  404. func (s *DockerSuite) TestEventsResize(c *check.C) {
  405. out := runSleepingContainer(c, "-d")
  406. cID := strings.TrimSpace(out)
  407. c.Assert(waitRun(cID), checker.IsNil)
  408. cli, err := client.NewEnvClient()
  409. c.Assert(err, checker.IsNil)
  410. defer cli.Close()
  411. options := types.ResizeOptions{
  412. Height: 80,
  413. Width: 24,
  414. }
  415. err = cli.ContainerResize(context.Background(), cID, options)
  416. c.Assert(err, checker.IsNil)
  417. dockerCmd(c, "stop", cID)
  418. until := daemonUnixTime(c)
  419. out, _ = dockerCmd(c, "events", "-f", "container="+cID, "--until="+until)
  420. c.Assert(out, checker.Contains, "resize", check.Commentf("Missing 'resize' log event"))
  421. }
  422. func (s *DockerSuite) TestEventsAttach(c *check.C) {
  423. // TODO Windows CI: Figure out why this test fails intermittently (TP5).
  424. testRequires(c, DaemonIsLinux)
  425. out := cli.DockerCmd(c, "run", "-di", "busybox", "cat").Combined()
  426. cID := strings.TrimSpace(out)
  427. cli.WaitRun(c, cID)
  428. cmd := exec.Command(dockerBinary, "attach", cID)
  429. stdin, err := cmd.StdinPipe()
  430. c.Assert(err, checker.IsNil)
  431. defer stdin.Close()
  432. stdout, err := cmd.StdoutPipe()
  433. c.Assert(err, checker.IsNil)
  434. defer stdout.Close()
  435. c.Assert(cmd.Start(), checker.IsNil)
  436. defer cmd.Process.Kill()
  437. // Make sure we're done attaching by writing/reading some stuff
  438. _, err = stdin.Write([]byte("hello\n"))
  439. c.Assert(err, checker.IsNil)
  440. out, err = bufio.NewReader(stdout).ReadString('\n')
  441. c.Assert(err, checker.IsNil)
  442. c.Assert(strings.TrimSpace(out), checker.Equals, "hello", check.Commentf("expected 'hello'"))
  443. c.Assert(stdin.Close(), checker.IsNil)
  444. cli.DockerCmd(c, "kill", cID)
  445. cli.WaitExited(c, cID, 5*time.Second)
  446. until := daemonUnixTime(c)
  447. out = cli.DockerCmd(c, "events", "-f", "container="+cID, "--until="+until).Combined()
  448. c.Assert(out, checker.Contains, "attach", check.Commentf("Missing 'attach' log event"))
  449. }
  450. func (s *DockerSuite) TestEventsRename(c *check.C) {
  451. out, _ := dockerCmd(c, "run", "--name", "oldName", "busybox", "true")
  452. cID := strings.TrimSpace(out)
  453. dockerCmd(c, "rename", "oldName", "newName")
  454. until := daemonUnixTime(c)
  455. // filter by the container id because the name in the event will be the new name.
  456. out, _ = dockerCmd(c, "events", "-f", "container="+cID, "--until", until)
  457. c.Assert(out, checker.Contains, "rename", check.Commentf("Missing 'rename' log event\n"))
  458. }
  459. func (s *DockerSuite) TestEventsTop(c *check.C) {
  460. // Problematic on Windows as Windows does not support top
  461. testRequires(c, DaemonIsLinux)
  462. out := runSleepingContainer(c, "-d")
  463. cID := strings.TrimSpace(out)
  464. c.Assert(waitRun(cID), checker.IsNil)
  465. dockerCmd(c, "top", cID)
  466. dockerCmd(c, "stop", cID)
  467. until := daemonUnixTime(c)
  468. out, _ = dockerCmd(c, "events", "-f", "container="+cID, "--until="+until)
  469. c.Assert(out, checker.Contains, " top", check.Commentf("Missing 'top' log event"))
  470. }
  471. // #14316
  472. func (s *DockerRegistrySuite) TestEventsImageFilterPush(c *check.C) {
  473. // Problematic to port for Windows CI during TP5 timeframe until
  474. // supporting push
  475. testRequires(c, DaemonIsLinux)
  476. testRequires(c, Network)
  477. repoName := fmt.Sprintf("%v/dockercli/testf", privateRegistryURL)
  478. out, _ := dockerCmd(c, "run", "-d", "busybox", "top")
  479. cID := strings.TrimSpace(out)
  480. c.Assert(waitRun(cID), checker.IsNil)
  481. dockerCmd(c, "commit", cID, repoName)
  482. dockerCmd(c, "stop", cID)
  483. dockerCmd(c, "push", repoName)
  484. until := daemonUnixTime(c)
  485. out, _ = dockerCmd(c, "events", "-f", "image="+repoName, "-f", "event=push", "--until", until)
  486. c.Assert(out, checker.Contains, repoName, check.Commentf("Missing 'push' log event for %s", repoName))
  487. }
  488. func (s *DockerSuite) TestEventsFilterType(c *check.C) {
  489. since := daemonUnixTime(c)
  490. name := "labelfiltertest"
  491. label := "io.docker.testing=image"
  492. // Build a test image.
  493. buildImageSuccessfully(c, name, build.WithDockerfile(fmt.Sprintf(`
  494. FROM busybox:latest
  495. LABEL %s`, label)))
  496. dockerCmd(c, "tag", name, "labelfiltertest:tag1")
  497. dockerCmd(c, "tag", name, "labelfiltertest:tag2")
  498. dockerCmd(c, "tag", "busybox:latest", "labelfiltertest:tag3")
  499. out, _ := dockerCmd(
  500. c,
  501. "events",
  502. "--since", since,
  503. "--until", daemonUnixTime(c),
  504. "--filter", fmt.Sprintf("label=%s", label),
  505. "--filter", "type=image")
  506. events := strings.Split(strings.TrimSpace(out), "\n")
  507. // 2 events from the "docker tag" command, another one is from "docker build"
  508. c.Assert(events, checker.HasLen, 3, check.Commentf("Events == %s", events))
  509. for _, e := range events {
  510. c.Assert(e, checker.Contains, "labelfiltertest")
  511. }
  512. out, _ = dockerCmd(
  513. c,
  514. "events",
  515. "--since", since,
  516. "--until", daemonUnixTime(c),
  517. "--filter", fmt.Sprintf("label=%s", label),
  518. "--filter", "type=container")
  519. events = strings.Split(strings.TrimSpace(out), "\n")
  520. // Events generated by the container that builds the image
  521. c.Assert(events, checker.HasLen, 3, check.Commentf("Events == %s", events))
  522. out, _ = dockerCmd(
  523. c,
  524. "events",
  525. "--since", since,
  526. "--until", daemonUnixTime(c),
  527. "--filter", "type=network")
  528. events = strings.Split(strings.TrimSpace(out), "\n")
  529. c.Assert(len(events), checker.GreaterOrEqualThan, 1, check.Commentf("Events == %s", events))
  530. }
  531. // #25798
  532. func (s *DockerSuite) TestEventsSpecialFiltersWithExecCreate(c *check.C) {
  533. since := daemonUnixTime(c)
  534. runSleepingContainer(c, "--name", "test-container", "-d")
  535. waitRun("test-container")
  536. dockerCmd(c, "exec", "test-container", "echo", "hello-world")
  537. out, _ := dockerCmd(
  538. c,
  539. "events",
  540. "--since", since,
  541. "--until", daemonUnixTime(c),
  542. "--filter",
  543. "event='exec_create: echo hello-world'",
  544. )
  545. events := strings.Split(strings.TrimSpace(out), "\n")
  546. c.Assert(len(events), checker.Equals, 1, check.Commentf(out))
  547. out, _ = dockerCmd(
  548. c,
  549. "events",
  550. "--since", since,
  551. "--until", daemonUnixTime(c),
  552. "--filter",
  553. "event=exec_create",
  554. )
  555. c.Assert(len(events), checker.Equals, 1, check.Commentf(out))
  556. }
  557. func (s *DockerSuite) TestEventsFilterImageInContainerAction(c *check.C) {
  558. since := daemonUnixTime(c)
  559. dockerCmd(c, "run", "--name", "test-container", "-d", "busybox", "true")
  560. waitRun("test-container")
  561. out, _ := dockerCmd(c, "events", "--filter", "image=busybox", "--since", since, "--until", daemonUnixTime(c))
  562. events := strings.Split(strings.TrimSpace(out), "\n")
  563. c.Assert(len(events), checker.GreaterThan, 1, check.Commentf(out))
  564. }
  565. func (s *DockerSuite) TestEventsContainerRestart(c *check.C) {
  566. dockerCmd(c, "run", "-d", "--name=testEvent", "--restart=on-failure:3", "busybox", "false")
  567. // wait until test2 is auto removed.
  568. waitTime := 10 * time.Second
  569. if testEnv.DaemonPlatform() == "windows" {
  570. // Windows takes longer...
  571. waitTime = 90 * time.Second
  572. }
  573. err := waitInspect("testEvent", "{{ .State.Restarting }} {{ .State.Running }}", "false false", waitTime)
  574. c.Assert(err, checker.IsNil)
  575. var (
  576. createCount int
  577. startCount int
  578. dieCount int
  579. )
  580. out, _ := dockerCmd(c, "events", "--since=0", "--until", daemonUnixTime(c), "-f", "container=testEvent")
  581. events := strings.Split(strings.TrimSpace(out), "\n")
  582. nEvents := len(events)
  583. c.Assert(nEvents, checker.GreaterOrEqualThan, 1) //Missing expected event
  584. actions := eventActionsByIDAndType(c, events, "testEvent", "container")
  585. for _, a := range actions {
  586. switch a {
  587. case "create":
  588. createCount++
  589. case "start":
  590. startCount++
  591. case "die":
  592. dieCount++
  593. }
  594. }
  595. c.Assert(createCount, checker.Equals, 1, check.Commentf("testEvent should be created 1 times: %v", actions))
  596. c.Assert(startCount, checker.Equals, 4, check.Commentf("testEvent should start 4 times: %v", actions))
  597. c.Assert(dieCount, checker.Equals, 4, check.Commentf("testEvent should die 4 times: %v", actions))
  598. }
  599. func (s *DockerSuite) TestEventsSinceInTheFuture(c *check.C) {
  600. dockerCmd(c, "run", "--name", "test-container", "-d", "busybox", "true")
  601. waitRun("test-container")
  602. since := daemonTime(c)
  603. until := since.Add(time.Duration(-24) * time.Hour)
  604. out, _, err := dockerCmdWithError("events", "--filter", "image=busybox", "--since", parseEventTime(since), "--until", parseEventTime(until))
  605. c.Assert(err, checker.NotNil)
  606. c.Assert(out, checker.Contains, "cannot be after `until`")
  607. }
  608. func (s *DockerSuite) TestEventsUntilInThePast(c *check.C) {
  609. since := daemonUnixTime(c)
  610. dockerCmd(c, "run", "--name", "test-container", "-d", "busybox", "true")
  611. waitRun("test-container")
  612. until := daemonUnixTime(c)
  613. dockerCmd(c, "run", "--name", "test-container2", "-d", "busybox", "true")
  614. waitRun("test-container2")
  615. out, _ := dockerCmd(c, "events", "--filter", "image=busybox", "--since", since, "--until", until)
  616. c.Assert(out, checker.Not(checker.Contains), "test-container2")
  617. c.Assert(out, checker.Contains, "test-container")
  618. }
  619. func (s *DockerSuite) TestEventsFormat(c *check.C) {
  620. since := daemonUnixTime(c)
  621. dockerCmd(c, "run", "--rm", "busybox", "true")
  622. dockerCmd(c, "run", "--rm", "busybox", "true")
  623. out, _ := dockerCmd(c, "events", "--since", since, "--until", daemonUnixTime(c), "--format", "{{json .}}")
  624. dec := json.NewDecoder(strings.NewReader(out))
  625. // make sure we got 2 start events
  626. startCount := 0
  627. for {
  628. var err error
  629. var ev eventtypes.Message
  630. if err = dec.Decode(&ev); err == io.EOF {
  631. break
  632. }
  633. c.Assert(err, checker.IsNil)
  634. if ev.Status == "start" {
  635. startCount++
  636. }
  637. }
  638. c.Assert(startCount, checker.Equals, 2, check.Commentf("should have had 2 start events but had %d, out: %s", startCount, out))
  639. }
  640. func (s *DockerSuite) TestEventsFormatBadFunc(c *check.C) {
  641. // make sure it fails immediately, without receiving any event
  642. result := dockerCmdWithResult("events", "--format", "{{badFuncString .}}")
  643. result.Assert(c, icmd.Expected{
  644. Error: "exit status 64",
  645. ExitCode: 64,
  646. Err: "Error parsing format: template: :1: function \"badFuncString\" not defined",
  647. })
  648. }
  649. func (s *DockerSuite) TestEventsFormatBadField(c *check.C) {
  650. // make sure it fails immediately, without receiving any event
  651. result := dockerCmdWithResult("events", "--format", "{{.badFieldString}}")
  652. result.Assert(c, icmd.Expected{
  653. Error: "exit status 64",
  654. ExitCode: 64,
  655. Err: "Error parsing format: template: :1:2: executing \"\" at <.badFieldString>: can't evaluate field badFieldString in type *events.Message",
  656. })
  657. }