Measuring Performance Using Logging by. 2018/6/19 Roppongi.swift 1

takasek @takasek Works OSS: ActionClosurable౳ App: PasteTheType Articles ʮίϯύΠϧΤϥʔ΍ϥϯλΠϜΤϥʔΛ௚͍ͯ͘͠ ͚ͩͰiOSΞϓϦͷ࡞Γํ͕Θ͔ΔϓϩδΣΫτʯ ʮ͓લΒ͕ModelͱݺͿΞϨΛͳΜͱݺͿ΂͖͔ʯ ! ʮiOSΞϓϦઃܭύλʔϯೖ໳ʯʢڞஶʣ 2

os_log 3

os_log WWDC2016͔Βಋೖ͞Εͨ let logHandle = OSLog( subsystem: "com.example.widget", category: "Setup" ) os_log(.info, log: logHandle, "Hello, %{public}s!", world ) 4

signpost 5

signpost • os_log family • InstrumentsͰύϑΥʔϚϯεܭଌʹ࢖͑ΔΑ͏ʹ֦ு • ͍ܰ 6

ϧʔϓॲཧͷ࣌ؒΛ ܭଌ͍ͨ͠ 7

for element in panel.elements { fetchAsset(for: element) } 8

import os.signpost for element in panel.elements { fetchAsset(for: element) } 9

import os.signpost let refreshLog = OSLog( // ! subsystem: "com.example.your-app", category: "RefreshOperations" ) for element in panel.elements { fetchAsset(for: element) } 10

import os.signpost let refreshLog = OSLog( subsystem: "com.example.your-app", category: "RefreshOperations" ) for element in panel.elements { os_signpost(type: .begin, log: refreshLog, // name: " " ") fetchAsset(for: element) os_signpost(type: .end, log: refreshLog, // name: " " ") } 11

ॲཧશମͷ࣌ؒΛ ܭଌ͍ͨ͠ 12

for element in panel.elements { os_signpost(type: .begin, log: refreshLog, name: " ") fetchAsset(for: element) os_signpost(type: .end, log: refreshLog, name: " ") } 13

os_signpost(type: .begin, log: refreshLog, name: " ") // for element in panel.elements { os_signpost(type: .begin, log: refreshLog, name: " ") fetchAsset(for: element) os_signpost(type: .end, log: refreshLog, name: " ") } os_signpost(type: .end, log: refreshLog, name: " ") // 14

ඇಉظॲཧʹ͍ͨ͠ ! ͕ಉ࣌ʹ૸Δ͜ͱ΋͋Δ… 15

os_signpost(type: .begin, log: refreshLog, name: " ") for element in panel.elements { os_signpost(type: .begin, log: refreshLog, name: " ") fetchAssetAsync(for: element) { os_signpost(type: .end, log: refreshLog, name: " ") } } notifyWhenDone { os_signpost(type: .end, log: refreshLog, name: " ") } 16

let !♻ = OSSignpostID(log: refreshLog) // os_signpost(type: .begin, log: refreshLog, name: " ") for element in panel.elements { let !$ = OSSignpostID(log: refreshLog, object: element) // os_signpost(type: .begin, log: refreshLog, name: " ") fetchAssetAsync(for: element) { os_signpost(type: .end, log: refreshLog, name: " ") } } notifyWhenDone { os_signpost(type: .end, log: refreshLog, name: " ") } 17

let !♻ = OSSignpostID(log: refreshLog) os_signpost(type: .begin, log: refreshLog, name: " ", signpostID: !♻ ) // # for element in panel.elements { let !$ = OSSignpostID(log: refreshLog, object: element) os_signpost(type: .begin, log: refreshLog, name: " ", signpostID: !$ ) // # fetchAssetAsync(for: element) { os_signpost(type: .end, log: refreshLog, name: " ", signpostID: !$ ) // # } } notifyWhenDone { os_signpost(type: .end, log: refreshLog, name: " ", signpostID: !♻ ) // # } 18

Example Represents Log category "RefreshOperatio ns" ؔ࿈͢Δॲཧͷ ·ͱ·Γ Signpost name "!" ܭଌର৅ Signpost ID "! ܭଌ͢Δॲཧ ͻͱͭͻͱͭ 19

ϝλσʔλΛ ೖΕ͍ͨ 20

os_signpost( type: .begin, log: log, name: "Compute Physics", "for %{public}@ at (%.1f, %.1f)" + "with mass %.2f and velocity (%.1f, %.1f)", description, x1, y1, m, x2, y2 ) • %.1f , %.2f , %d ... • %{public}@ • %{xcode:size-in-bytes}llu • Instrumentsʹ෼ੳσʔλͱͯ͠ೝࣝͤ͞Δ 21

୯ൃΠϕϯτΛ ه࿥͍ͨ͠ 22

os_signpost( .event, log: log, name: "Swipe", "For action 0x%x", actionCode ) 23

ϩάΛoffʹ͍ͨ͠ 24

let refreshLog: OSLog if ProcessInfo.processInfo.environment.keys.contains("SIGNPOSTS_FOR_REFRESH") { refreshLog = OSLog( subsystem: "com.example.your-app", category: "RefreshOperations" ) } else { refreshLog = .disabled } 25

ϩάͷͨΊʹίετͷ͔͔ΔॲཧΛ͍ͯ͠Δ৔߹… if refreshLog.signpostsEnabled { let information = copyDescription() os_signpost(..., information) } 26

Instruments 27

• Instruments → Blank → [+] → "os_signpost" ͰϑΟϧλ • signposts͕֬ೝͰ͖Δʢ˞࣮ػͰಈ͔͢͜ͱʣ • Time Profiler • ͰΠϕϯτΛ֬ೝͰ͖ΔOSLog΋͋Δ • OSLog(subsystem: ..., category: .pointsOfInterest) ʹ .event ΛૹΔ • Custom Instruments • ϏδϡΞϥΠζͷํ๏ΛΧελϚΠζͰ͖Δ • ʮnඵҎ্͔͔͍ͬͯΔॲཧΛ੺͘දࣔ͢Δʯͱ͔ 28

·ͱΊ signpostsͰίʔυΛΞϊςʔτ͠Α͏ • ؆୯ʹॲཧͷϧʔϓΛϩάͰ͖Δ • ϝλσʔλ΋ؚΊΒΕΔ InstrumentsͰ֬ೝ͠Α͏ • ॲཧ࣌ؒΛϏδϡΞϥΠζͰ͖Δ • ϓϩάϥϜ͕ԿΛ͍ͯ͠Δ͔Θ͔Δ 29

Refer to Measuring Performance Using Logging https://developer.apple.com/videos/play/ wwdc2018/405/ • ͜ͷࢿྉ͸ʢ΄΅ʣηογϣϯͷҾ͖ࣸ͠Ͱ͢ • InstrumentsͷσϞ͸Tips΋ॆ࣮ͯͯ͠ඞݟ • ͱ͍͏Θ͚ͰݩηογϣϯΛݟ·͠ΐ͏ 30