builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-066 starttime: 1501207367.39 results: success (0) revision: 556f19ef392ac2d9aac579864e2179d6c1d464e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:47.391929) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:47.392558) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:47.392906) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:47.444854) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:47.445507) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-07-27 19:02:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 109M=0s 2017-07-27 19:02:47 (109 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126345 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:47.596877) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:47.597217) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014605 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:47.649756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:47.650547) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 556f19ef392ac2d9aac579864e2179d6c1d464e8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 556f19ef392ac2d9aac579864e2179d6c1d464e8 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-07-27 19:02:47,880 truncating revision to first 12 chars 2017-07-27 19:02:47,880 Setting DEBUG logging. 2017-07-27 19:02:47,880 attempt 1/10 2017-07-27 19:02:47,880 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/556f19ef392a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-07-27 19:02:48,196 unpacking tar archive at: mozilla-central-556f19ef392a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.689059 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:48.359714) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:48.360473) ========= script_repo_revision: 556f19ef392ac2d9aac579864e2179d6c1d464e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:48.361485) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:48.362204) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-07-27 19:02:48.373466) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-07-27 19:02:48.373791) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:02:48 INFO - MultiFileLogger online at 20170727 19:02:48 in /builds/slave/test 19:02:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 19:02:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:02:48 INFO - {'append_to_log': False, 19:02:48 INFO - 'base_work_dir': '/builds/slave/test', 19:02:48 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 19:02:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:02:48 INFO - 'branch': 'Firefox-Non-PGO', 19:02:48 INFO - 'buildbot_json_path': 'buildprops.json', 19:02:48 INFO - 'code_coverage': False, 19:02:48 INFO - 'config_files': ('talos/linux_config.py',), 19:02:48 INFO - 'default_actions': ('clobber', 19:02:48 INFO - 'read-buildbot-config', 19:02:48 INFO - 'download-and-extract', 19:02:48 INFO - 'populate-webroot', 19:02:48 INFO - 'create-virtualenv', 19:02:48 INFO - 'install', 19:02:48 INFO - 'setup-mitmproxy', 19:02:48 INFO - 'run-tests'), 19:02:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:02:48 INFO - 'disable_ccov_upload': False, 19:02:48 INFO - 'download_minidump_stackwalk': True, 19:02:48 INFO - 'download_symbols': 'ondemand', 19:02:48 INFO - 'enable_stylo': False, 19:02:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:02:48 INFO - 'tooltool.py': '/tools/tooltool.py', 19:02:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:02:48 INFO - '/tools/misc-python/virtualenv.py')}, 19:02:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:02:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:02:48 INFO - 'gecko_profile': False, 19:02:48 INFO - 'gecko_profile_interval': 0, 19:02:48 INFO - 'installer_path': 'installer.exe', 19:02:48 INFO - 'log_level': 'info', 19:02:48 INFO - 'log_name': 'talos', 19:02:48 INFO - 'log_to_console': True, 19:02:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:02:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:02:48 INFO - 'opt_config_files': (), 19:02:48 INFO - 'pip_index': False, 19:02:48 INFO - 'suite': 'dromaeojs-e10s', 19:02:48 INFO - 'system_bits': '32', 19:02:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:02:48 INFO - 'title': 'talos-linux64-ix-066', 19:02:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:02:48 INFO - 'use_talos_json': True, 19:02:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:02:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:02:48 INFO - 'work_dir': 'build'} 19:02:48 INFO - [mozharness: 2017-07-28 02:02:48.821871Z] Running clobber step. 19:02:48 INFO - Running pre-action listener: _resource_record_pre_action 19:02:48 INFO - Running main action method: clobber 19:02:48 INFO - rmtree: /builds/slave/test/build 19:02:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:02:49 INFO - Running post-action listener: _resource_record_post_action 19:02:49 INFO - [mozharness: 2017-07-28 02:02:49.804127Z] Finished clobber step (success) 19:02:49 INFO - [mozharness: 2017-07-28 02:02:49.804230Z] Running read-buildbot-config step. 19:02:49 INFO - Running pre-action listener: _resource_record_pre_action 19:02:49 INFO - Running main action method: read_buildbot_config 19:02:49 INFO - Using buildbot properties: 19:02:49 INFO - { 19:02:49 INFO - "project": "", 19:02:49 INFO - "product": "firefox", 19:02:49 INFO - "who": "Callek@gmail.com", 19:02:49 INFO - "installer_path": "public/build/target.tar.bz2", 19:02:49 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 19:02:49 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 19:02:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 19:02:49 INFO - "stage_platform": "linux64", 19:02:49 INFO - "basedir": "/builds/slave/test", 19:02:49 INFO - "buildnumber": 1040, 19:02:49 INFO - "platform": "ubuntu64_hw", 19:02:49 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:02:49 INFO - "slavebuilddir": "test", 19:02:49 INFO - "taskId": "BbWGE-3HSkCZ5aBAlG5vMQ", 19:02:49 INFO - "branch": "mozilla-central", 19:02:49 INFO - "script_repo_revision": "production", 19:02:49 INFO - "revision": "556f19ef392ac2d9aac579864e2179d6c1d464e8", 19:02:49 INFO - "slavename": "talos-linux64-ix-066", 19:02:49 INFO - "repo_path": "mozilla-central" 19:02:49 INFO - } 19:02:49 INFO - Finding installer, test and symbols from parent task. 19:02:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BbWGE-3HSkCZ5aBAlG5vMQ'}, attempt #1 19:02:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BbWGE-3HSkCZ5aBAlG5vMQ'}, attempt #1 19:02:50 INFO - Task dependencies: clJckGzdS1qkQbdfhk8DRA 19:02:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA'}, attempt #1 19:02:51 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.tar.bz2 19:02:51 INFO - Set test_url: https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/test_packages.json 19:02:51 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.crashreporter-symbols.zip 19:02:51 INFO - Running post-action listener: _resource_record_post_action 19:02:51 INFO - [mozharness: 2017-07-28 02:02:51.177894Z] Finished read-buildbot-config step (success) 19:02:51 INFO - [mozharness: 2017-07-28 02:02:51.178118Z] Running download-and-extract step. 19:02:51 INFO - Running pre-action listener: _resource_record_pre_action 19:02:51 INFO - Running main action method: download_and_extract 19:02:51 INFO - mkdir: /builds/slave/test/build/tests 19:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:51 INFO - trying https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.test_packages.json 19:02:51 INFO - Downloading https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:02:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:02:51 INFO - Downloaded 989 bytes. 19:02:51 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:02:51 INFO - Using the following test package requirements: 19:02:51 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:02:51 INFO - u'common': [u'target.common.tests.zip'], 19:02:51 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:02:51 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:02:51 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:02:51 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:02:51 INFO - u'mozbase': [u'target.common.tests.zip'], 19:02:51 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:02:51 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:02:51 INFO - u'web-platform': [u'target.common.tests.zip', 19:02:51 INFO - u'target.web-platform.tests.tar.gz'], 19:02:51 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:02:51 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:02:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.common.tests.zip 19:02:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:02:51 INFO - Fetch https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.common.tests.zip into memory 19:02:53 INFO - Content-Length response header: 72561232 19:02:53 INFO - Bytes received: 72561232 19:03:06 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:03:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.common.tests.zip 19:03:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:03:06 INFO - Fetch https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.common.tests.zip into memory 19:03:07 INFO - Content-Length response header: 72561232 19:03:07 INFO - Bytes received: 72561232 19:03:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.talos.tests.zip 19:03:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:03:21 INFO - Fetch https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.talos.tests.zip into memory 19:03:21 INFO - Content-Length response header: 14496465 19:03:21 INFO - Bytes received: 14496465 19:03:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:22 INFO - trying https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.tar.bz2 19:03:22 INFO - Downloading https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:03:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:03:23 INFO - Downloaded 62038064 bytes. 19:03:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.tar.bz2 19:03:23 INFO - mkdir: /builds/slave/test/properties 19:03:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:03:23 INFO - Writing to file /builds/slave/test/properties/build_url 19:03:23 INFO - Contents: 19:03:23 INFO - build_url:https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.tar.bz2 19:03:23 INFO - Running post-action listener: _resource_record_post_action 19:03:23 INFO - Running post-action listener: set_extra_try_arguments 19:03:23 INFO - [mozharness: 2017-07-28 02:03:23.374876Z] Finished download-and-extract step (success) 19:03:23 INFO - [mozharness: 2017-07-28 02:03:23.374975Z] Running populate-webroot step. 19:03:23 INFO - Running pre-action listener: _resource_record_pre_action 19:03:23 INFO - Running main action method: populate_webroot 19:03:23 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:03:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:03:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:03:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:03:23 INFO - 'tests': ['damp', 'tps']}, 19:03:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:03:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:03:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:03:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:03:23 INFO - 'other-e10s': {'tests': ['a11yr', 19:03:23 INFO - 'ts_paint', 19:03:23 INFO - 'tpaint', 19:03:23 INFO - 'sessionrestore', 19:03:23 INFO - 'sessionrestore_many_windows', 19:03:23 INFO - 'sessionrestore_no_auto_restore', 19:03:23 INFO - 'tabpaint']}, 19:03:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 19:03:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 19:03:23 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:03:23 INFO - 'talos_options': ['--mitmproxy', 19:03:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:03:23 INFO - '--firstNonBlankPaint'], 19:03:23 INFO - 'tests': ['quantum_pageload_google', 19:03:23 INFO - 'quantum_pageload_youtube', 19:03:23 INFO - 'quantum_pageload_amazon', 19:03:23 INFO - 'quantum_pageload_facebook']}, 19:03:23 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:03:23 INFO - 'talos_options': ['--stylo', 19:03:23 INFO - '--mitmproxy', 19:03:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:03:23 INFO - '--firstNonBlankPaint'], 19:03:23 INFO - 'tests': ['quantum_pageload_google', 19:03:23 INFO - 'quantum_pageload_youtube', 19:03:23 INFO - 'quantum_pageload_amazon', 19:03:23 INFO - 'quantum_pageload_facebook']}, 19:03:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:03:23 INFO - 'tsvgr_opacity', 19:03:23 INFO - 'tart', 19:03:23 INFO - 'tscrollx', 19:03:23 INFO - 'cart', 19:03:23 INFO - 'tsvg_static']}, 19:03:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:03:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:03:23 INFO - 'talos_options': ['--xperf_path', 19:03:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:23 INFO - 'tests': ['tp5n']}}} 19:03:23 INFO - Running post-action listener: _resource_record_post_action 19:03:23 INFO - [mozharness: 2017-07-28 02:03:23.382402Z] Finished populate-webroot step (success) 19:03:23 INFO - [mozharness: 2017-07-28 02:03:23.382494Z] Running create-virtualenv step. 19:03:23 INFO - Running pre-action listener: _resource_record_pre_action 19:03:23 INFO - Running main action method: create_virtualenv 19:03:23 INFO - Creating virtualenv /builds/slave/test/build/venv 19:03:23 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:03:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:03:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:03:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:03:23 INFO - Using real prefix '/usr' 19:03:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:03:24 INFO - Installing distribute.............................................................................................................................................................................................done. 19:03:26 INFO - Installing pip.................done. 19:03:26 INFO - Return code: 0 19:03:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:03:26 INFO - Reading from file tmpfile_stdout 19:03:26 INFO - Output received: 19:03:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:03:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:03:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:03:26 INFO - Using env: {'DISPLAY': ':0', 19:03:26 INFO - 'HOME': '/home/cltbld', 19:03:26 INFO - 'LANG': 'en_US.UTF-8', 19:03:26 INFO - 'LANGUAGE': 'en_US:en', 19:03:26 INFO - 'LOGNAME': 'cltbld', 19:03:26 INFO - 'MAIL': '/var/mail/cltbld', 19:03:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:26 INFO - 'MOZ_NO_REMOTE': '1', 19:03:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:26 INFO - 'NO_EM_RESTART': '1', 19:03:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:26 INFO - 'PWD': '/builds/slave/test', 19:03:26 INFO - 'SHELL': '/bin/bash', 19:03:26 INFO - 'SHLVL': '1', 19:03:26 INFO - 'TERM': 'linux', 19:03:26 INFO - 'TMOUT': '86400', 19:03:26 INFO - 'USER': 'cltbld', 19:03:26 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:26 INFO - '_': '/tools/buildbot/bin/python'} 19:03:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:26 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 19:03:26 INFO - Cleaning up... 19:03:26 INFO - Return code: 0 19:03:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:03:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:03:26 INFO - Using env: {'DISPLAY': ':0', 19:03:26 INFO - 'HOME': '/home/cltbld', 19:03:26 INFO - 'LANG': 'en_US.UTF-8', 19:03:26 INFO - 'LANGUAGE': 'en_US:en', 19:03:26 INFO - 'LOGNAME': 'cltbld', 19:03:26 INFO - 'MAIL': '/var/mail/cltbld', 19:03:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:26 INFO - 'MOZ_NO_REMOTE': '1', 19:03:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:26 INFO - 'NO_EM_RESTART': '1', 19:03:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:26 INFO - 'PWD': '/builds/slave/test', 19:03:26 INFO - 'SHELL': '/bin/bash', 19:03:26 INFO - 'SHLVL': '1', 19:03:26 INFO - 'TERM': 'linux', 19:03:26 INFO - 'TMOUT': '86400', 19:03:26 INFO - 'USER': 'cltbld', 19:03:26 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:26 INFO - '_': '/tools/buildbot/bin/python'} 19:03:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:26 INFO - Downloading/unpacking psutil>=3.1.1 19:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:03:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:28 INFO - Installing collected packages: psutil 19:03:28 INFO - Running setup.py install for psutil 19:03:28 INFO - building 'psutil._psutil_linux' extension 19:03:28 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 19:03:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 19:03:29 INFO - building 'psutil._psutil_posix' extension 19:03:29 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 19:03:29 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 19:03:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:29 INFO - Successfully installed psutil 19:03:29 INFO - Cleaning up... 19:03:29 INFO - Return code: 0 19:03:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:03:29 INFO - Using env: {'DISPLAY': ':0', 19:03:29 INFO - 'HOME': '/home/cltbld', 19:03:29 INFO - 'LANG': 'en_US.UTF-8', 19:03:29 INFO - 'LANGUAGE': 'en_US:en', 19:03:29 INFO - 'LOGNAME': 'cltbld', 19:03:29 INFO - 'MAIL': '/var/mail/cltbld', 19:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:29 INFO - 'MOZ_NO_REMOTE': '1', 19:03:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:29 INFO - 'NO_EM_RESTART': '1', 19:03:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:29 INFO - 'PWD': '/builds/slave/test', 19:03:29 INFO - 'SHELL': '/bin/bash', 19:03:29 INFO - 'SHLVL': '1', 19:03:29 INFO - 'TERM': 'linux', 19:03:29 INFO - 'TMOUT': '86400', 19:03:29 INFO - 'USER': 'cltbld', 19:03:29 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:29 INFO - '_': '/tools/buildbot/bin/python'} 19:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:03:31 INFO - Installing collected packages: mozsystemmonitor 19:03:31 INFO - Running setup.py install for mozsystemmonitor 19:03:31 INFO - Successfully installed mozsystemmonitor 19:03:31 INFO - Cleaning up... 19:03:31 INFO - Return code: 0 19:03:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:03:31 INFO - Using env: {'DISPLAY': ':0', 19:03:31 INFO - 'HOME': '/home/cltbld', 19:03:31 INFO - 'LANG': 'en_US.UTF-8', 19:03:31 INFO - 'LANGUAGE': 'en_US:en', 19:03:31 INFO - 'LOGNAME': 'cltbld', 19:03:31 INFO - 'MAIL': '/var/mail/cltbld', 19:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:31 INFO - 'MOZ_NO_REMOTE': '1', 19:03:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:31 INFO - 'NO_EM_RESTART': '1', 19:03:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:31 INFO - 'PWD': '/builds/slave/test', 19:03:31 INFO - 'SHELL': '/bin/bash', 19:03:31 INFO - 'SHLVL': '1', 19:03:31 INFO - 'TERM': 'linux', 19:03:31 INFO - 'TMOUT': '86400', 19:03:31 INFO - 'USER': 'cltbld', 19:03:31 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:31 INFO - '_': '/tools/buildbot/bin/python'} 19:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:31 INFO - Downloading/unpacking jsonschema==2.5.1 19:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:03:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:32 INFO - Downloading functools32-3.2.3-2.tar.gz 19:03:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:03:32 INFO - warning: no files found matching '*.txt' 19:03:32 INFO - no previously-included directories found matching 'build' 19:03:32 INFO - no previously-included directories found matching 'dist' 19:03:32 INFO - no previously-included directories found matching '.git*' 19:03:32 INFO - Installing collected packages: jsonschema, functools32 19:03:32 INFO - Running setup.py install for functools32 19:03:32 INFO - warning: no files found matching '*.txt' 19:03:32 INFO - no previously-included directories found matching 'build' 19:03:32 INFO - no previously-included directories found matching 'dist' 19:03:32 INFO - no previously-included directories found matching '.git*' 19:03:32 INFO - Successfully installed jsonschema functools32 19:03:32 INFO - Cleaning up... 19:03:32 INFO - Return code: 0 19:03:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:03:32 INFO - Using env: {'DISPLAY': ':0', 19:03:32 INFO - 'HOME': '/home/cltbld', 19:03:32 INFO - 'LANG': 'en_US.UTF-8', 19:03:32 INFO - 'LANGUAGE': 'en_US:en', 19:03:32 INFO - 'LOGNAME': 'cltbld', 19:03:32 INFO - 'MAIL': '/var/mail/cltbld', 19:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:32 INFO - 'MOZ_NO_REMOTE': '1', 19:03:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:32 INFO - 'NO_EM_RESTART': '1', 19:03:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:32 INFO - 'PWD': '/builds/slave/test', 19:03:32 INFO - 'SHELL': '/bin/bash', 19:03:32 INFO - 'SHLVL': '1', 19:03:32 INFO - 'TERM': 'linux', 19:03:32 INFO - 'TMOUT': '86400', 19:03:32 INFO - 'USER': 'cltbld', 19:03:32 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:32 INFO - '_': '/tools/buildbot/bin/python'} 19:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:03:33 INFO - Cleaning up... 19:03:33 INFO - Return code: 0 19:03:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:03:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:03:33 INFO - Using env: {'DISPLAY': ':0', 19:03:33 INFO - 'HOME': '/home/cltbld', 19:03:33 INFO - 'LANG': 'en_US.UTF-8', 19:03:33 INFO - 'LANGUAGE': 'en_US:en', 19:03:33 INFO - 'LOGNAME': 'cltbld', 19:03:33 INFO - 'MAIL': '/var/mail/cltbld', 19:03:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:33 INFO - 'MOZ_NO_REMOTE': '1', 19:03:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:33 INFO - 'NO_EM_RESTART': '1', 19:03:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:33 INFO - 'PWD': '/builds/slave/test', 19:03:33 INFO - 'SHELL': '/bin/bash', 19:03:33 INFO - 'SHLVL': '1', 19:03:33 INFO - 'TERM': 'linux', 19:03:33 INFO - 'TMOUT': '86400', 19:03:33 INFO - 'USER': 'cltbld', 19:03:33 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:33 INFO - '_': '/tools/buildbot/bin/python'} 19:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:33 INFO - Downloading/unpacking blobuploader==1.2.4 19:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:34 INFO - Downloading blobuploader-1.2.4.tar.gz 19:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:03:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:03:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:34 INFO - Downloading docopt-0.6.1.tar.gz 19:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:03:35 INFO - Installing collected packages: blobuploader, requests, docopt 19:03:35 INFO - Running setup.py install for blobuploader 19:03:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:03:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:03:35 INFO - Running setup.py install for requests 19:03:35 INFO - Running setup.py install for docopt 19:03:35 INFO - Successfully installed blobuploader requests docopt 19:03:35 INFO - Cleaning up... 19:03:35 INFO - Return code: 0 19:03:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:03:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:03:35 INFO - Using env: {'DISPLAY': ':0', 19:03:35 INFO - 'HOME': '/home/cltbld', 19:03:35 INFO - 'LANG': 'en_US.UTF-8', 19:03:35 INFO - 'LANGUAGE': 'en_US:en', 19:03:35 INFO - 'LOGNAME': 'cltbld', 19:03:35 INFO - 'MAIL': '/var/mail/cltbld', 19:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:35 INFO - 'MOZ_NO_REMOTE': '1', 19:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:35 INFO - 'NO_EM_RESTART': '1', 19:03:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:35 INFO - 'PWD': '/builds/slave/test', 19:03:35 INFO - 'SHELL': '/bin/bash', 19:03:35 INFO - 'SHLVL': '1', 19:03:35 INFO - 'TERM': 'linux', 19:03:35 INFO - 'TMOUT': '86400', 19:03:35 INFO - 'USER': 'cltbld', 19:03:35 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:35 INFO - '_': '/tools/buildbot/bin/python'} 19:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:03:35 INFO - Running setup.py (path:/tmp/pip-VaAIhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:03:35 INFO - Running setup.py (path:/tmp/pip-bH1vn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:03:35 INFO - Running setup.py (path:/tmp/pip-72ZA2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:03:36 INFO - Running setup.py (path:/tmp/pip-ypUZI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:03:36 INFO - Running setup.py (path:/tmp/pip-lAwT_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:03:36 INFO - Running setup.py (path:/tmp/pip-Usz1NW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:03:36 INFO - Running setup.py (path:/tmp/pip-bgVL2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:03:36 INFO - Running setup.py (path:/tmp/pip-HHfoZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:03:36 INFO - Running setup.py (path:/tmp/pip-3wQJCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:03:36 INFO - Running setup.py (path:/tmp/pip-ms2Qd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:03:36 INFO - Running setup.py (path:/tmp/pip-2LMqdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:03:36 INFO - Running setup.py (path:/tmp/pip-DaPPjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:03:36 INFO - Running setup.py (path:/tmp/pip-C6ckZr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:03:36 INFO - Running setup.py (path:/tmp/pip-lW3gEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:36 INFO - Running setup.py (path:/tmp/pip-1nMK_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:03:36 INFO - Running setup.py (path:/tmp/pip-1tXKW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:03:36 INFO - Running setup.py (path:/tmp/pip-8LLRHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:03:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:03:37 INFO - Running setup.py install for manifestparser 19:03:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:03:37 INFO - Running setup.py install for mozcrash 19:03:37 INFO - Running setup.py install for mozdebug 19:03:37 INFO - Running setup.py install for mozdevice 19:03:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:03:37 INFO - Running setup.py install for mozfile 19:03:37 INFO - Running setup.py install for mozhttpd 19:03:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:03:37 INFO - Running setup.py install for mozinfo 19:03:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:03:37 INFO - Running setup.py install for mozInstall 19:03:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:03:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:03:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:03:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:03:37 INFO - Running setup.py install for mozleak 19:03:37 INFO - Running setup.py install for mozlog 19:03:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:03:38 INFO - Running setup.py install for moznetwork 19:03:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:03:38 INFO - Running setup.py install for mozprocess 19:03:38 INFO - Running setup.py install for mozprofile 19:03:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:03:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:03:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:03:38 INFO - Running setup.py install for mozrunner 19:03:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:03:38 INFO - Running setup.py install for mozscreenshot 19:03:38 INFO - Running setup.py install for moztest 19:03:38 INFO - Running setup.py install for mozversion 19:03:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:03:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:03:38 INFO - Cleaning up... 19:03:38 INFO - Return code: 0 19:03:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 19:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:03:38 INFO - Using env: {'DISPLAY': ':0', 19:03:38 INFO - 'HOME': '/home/cltbld', 19:03:38 INFO - 'LANG': 'en_US.UTF-8', 19:03:38 INFO - 'LANGUAGE': 'en_US:en', 19:03:38 INFO - 'LOGNAME': 'cltbld', 19:03:38 INFO - 'MAIL': '/var/mail/cltbld', 19:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:38 INFO - 'MOZ_NO_REMOTE': '1', 19:03:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:38 INFO - 'NO_EM_RESTART': '1', 19:03:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:38 INFO - 'PWD': '/builds/slave/test', 19:03:38 INFO - 'SHELL': '/bin/bash', 19:03:38 INFO - 'SHLVL': '1', 19:03:38 INFO - 'TERM': 'linux', 19:03:38 INFO - 'TMOUT': '86400', 19:03:38 INFO - 'USER': 'cltbld', 19:03:38 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:38 INFO - '_': '/tools/buildbot/bin/python'} 19:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:03:39 INFO - Running setup.py (path:/tmp/pip-FPuoAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:03:39 INFO - Running setup.py (path:/tmp/pip-zRoqh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:03:39 INFO - Running setup.py (path:/tmp/pip-qrNCpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:03:39 INFO - Running setup.py (path:/tmp/pip-WSe5PH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:03:39 INFO - Running setup.py (path:/tmp/pip-CK99h5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:03:39 INFO - Running setup.py (path:/tmp/pip-jTUJ_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:03:39 INFO - Running setup.py (path:/tmp/pip-YDkJyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:03:39 INFO - Running setup.py (path:/tmp/pip-Eg_zkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:03:39 INFO - Running setup.py (path:/tmp/pip-EbTya8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:03:39 INFO - Running setup.py (path:/tmp/pip-EnAqz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:03:39 INFO - Running setup.py (path:/tmp/pip-UAipkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:03:39 INFO - Running setup.py (path:/tmp/pip-xNMqTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:03:39 INFO - Running setup.py (path:/tmp/pip-7F0sfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 19:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:03:40 INFO - Running setup.py (path:/tmp/pip-kkPc1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:40 INFO - Running setup.py (path:/tmp/pip-kWZfG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:03:40 INFO - Running setup.py (path:/tmp/pip-SMsnbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:03:40 INFO - Running setup.py (path:/tmp/pip-C2GdUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:03:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:41 INFO - Downloading blessings-1.6.tar.gz 19:03:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:03:41 INFO - Installing collected packages: blessings 19:03:41 INFO - Running setup.py install for blessings 19:03:41 INFO - Successfully installed blessings 19:03:41 INFO - Cleaning up... 19:03:41 INFO - Return code: 0 19:03:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:03:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:03:41 INFO - Reading from file tmpfile_stdout 19:03:41 INFO - Current package versions: 19:03:41 INFO - argparse == 1.2.1 19:03:41 INFO - blessings == 1.6 19:03:41 INFO - blobuploader == 1.2.4 19:03:41 INFO - docopt == 0.6.1 19:03:41 INFO - functools32 == 3.2.3-2 19:03:41 INFO - jsonschema == 2.5.1 19:03:41 INFO - manifestparser == 1.1 19:03:41 INFO - mozInstall == 1.13 19:03:41 INFO - mozcrash == 1.0 19:03:41 INFO - mozdebug == 0.1 19:03:41 INFO - mozdevice == 0.51 19:03:41 INFO - mozfile == 1.2 19:03:41 INFO - mozhttpd == 0.7 19:03:41 INFO - mozinfo == 0.10 19:03:41 INFO - mozleak == 0.1 19:03:41 INFO - mozlog == 3.5 19:03:41 INFO - moznetwork == 0.27 19:03:41 INFO - mozprocess == 0.25 19:03:41 INFO - mozprofile == 0.28 19:03:41 INFO - mozrunner == 6.13 19:03:41 INFO - mozscreenshot == 0.1 19:03:41 INFO - mozsystemmonitor == 0.3 19:03:41 INFO - moztest == 0.8 19:03:41 INFO - mozversion == 1.4 19:03:41 INFO - psutil == 3.1.1 19:03:41 INFO - requests == 1.2.3 19:03:41 INFO - wsgiref == 0.1.2 19:03:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 19:03:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:03:41 INFO - Using env: {'DISPLAY': ':0', 19:03:41 INFO - 'HOME': '/home/cltbld', 19:03:41 INFO - 'LANG': 'en_US.UTF-8', 19:03:41 INFO - 'LANGUAGE': 'en_US:en', 19:03:41 INFO - 'LOGNAME': 'cltbld', 19:03:41 INFO - 'MAIL': '/var/mail/cltbld', 19:03:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:41 INFO - 'MOZ_NO_REMOTE': '1', 19:03:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:41 INFO - 'NO_EM_RESTART': '1', 19:03:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:41 INFO - 'PWD': '/builds/slave/test', 19:03:41 INFO - 'SHELL': '/bin/bash', 19:03:41 INFO - 'SHLVL': '1', 19:03:41 INFO - 'TERM': 'linux', 19:03:41 INFO - 'TMOUT': '86400', 19:03:41 INFO - 'USER': 'cltbld', 19:03:41 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:41 INFO - '_': '/tools/buildbot/bin/python'} 19:03:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 19:03:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 19:03:42 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:43 INFO - Downloading marionette_driver-1.4.0.tar.gz 19:03:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 19:03:43 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11)) 19:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 19:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 19:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 19:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:03:43 INFO - Installing collected packages: marionette-driver, simplejson 19:03:43 INFO - Running setup.py install for marionette-driver 19:03:43 INFO - Running setup.py install for simplejson 19:03:43 INFO - building 'simplejson._speedups' extension 19:03:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 19:03:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 19:03:44 INFO - Successfully installed marionette-driver simplejson 19:03:44 INFO - Cleaning up... 19:03:44 INFO - Return code: 0 19:03:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11bf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x138ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x152fd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 19:03:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:03:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:03:44 INFO - Using env: {'DISPLAY': ':0', 19:03:44 INFO - 'HOME': '/home/cltbld', 19:03:44 INFO - 'LANG': 'en_US.UTF-8', 19:03:44 INFO - 'LANGUAGE': 'en_US:en', 19:03:44 INFO - 'LOGNAME': 'cltbld', 19:03:44 INFO - 'MAIL': '/var/mail/cltbld', 19:03:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:44 INFO - 'MOZ_NO_REMOTE': '1', 19:03:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:03:44 INFO - 'NO_EM_RESTART': '1', 19:03:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:03:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:44 INFO - 'PWD': '/builds/slave/test', 19:03:44 INFO - 'SHELL': '/bin/bash', 19:03:44 INFO - 'SHLVL': '1', 19:03:44 INFO - 'TERM': 'linux', 19:03:44 INFO - 'TMOUT': '86400', 19:03:44 INFO - 'USER': 'cltbld', 19:03:44 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:03:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:44 INFO - '_': '/tools/buildbot/bin/python'} 19:03:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:03:44 INFO - Cleaning up... 19:03:44 INFO - Return code: 0 19:03:44 INFO - Running post-action listener: _resource_record_post_action 19:03:44 INFO - Running post-action listener: _start_resource_monitoring 19:03:44 INFO - Starting resource monitoring. 19:03:44 INFO - [mozharness: 2017-07-28 02:03:44.923367Z] Finished create-virtualenv step (success) 19:03:44 INFO - [mozharness: 2017-07-28 02:03:44.924380Z] Running install step. 19:03:44 INFO - Running pre-action listener: _resource_record_pre_action 19:03:44 INFO - Running main action method: install 19:03:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:03:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:03:45 INFO - Reading from file tmpfile_stdout 19:03:45 INFO - Detecting whether we're running mozinstall >=1.0... 19:03:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:03:45 INFO - Reading from file tmpfile_stdout 19:03:45 INFO - Output received: 19:03:45 INFO - Usage: mozinstall [options] installer 19:03:45 INFO - Options: 19:03:45 INFO - -h, --help show this help message and exit 19:03:45 INFO - -d DEST, --destination=DEST 19:03:45 INFO - Directory to install application into. [default: 19:03:45 INFO - "/builds/slave/test"] 19:03:45 INFO - --app=APP Application being installed. [default: firefox] 19:03:45 INFO - mkdir: /builds/slave/test/build/application 19:03:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:04:03 INFO - Reading from file tmpfile_stdout 19:04:03 INFO - Output received: 19:04:03 INFO - /builds/slave/test/build/application/firefox/firefox 19:04:03 INFO - Running post-action listener: _resource_record_post_action 19:04:03 INFO - [mozharness: 2017-07-28 02:04:03.222454Z] Finished install step (success) 19:04:03 INFO - [mozharness: 2017-07-28 02:04:03.222712Z] Running setup-mitmproxy step. 19:04:03 INFO - Running pre-action listener: _resource_record_pre_action 19:04:03 INFO - Running main action method: setup_mitmproxy 19:04:03 INFO - Skipping: mitmproxy is not required 19:04:03 INFO - Running post-action listener: _resource_record_post_action 19:04:03 INFO - [mozharness: 2017-07-28 02:04:03.223855Z] Finished setup-mitmproxy step (success) 19:04:03 INFO - [mozharness: 2017-07-28 02:04:03.224078Z] Running run-tests step. 19:04:03 INFO - Running pre-action listener: _resource_record_pre_action 19:04:03 INFO - Running pre-action listener: _set_gcov_prefix 19:04:03 INFO - Running main action method: run_tests 19:04:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:04:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:04:03 INFO - Python 2.7.3 19:04:03 INFO - Return code: 0 19:04:03 INFO - grabbing minidump binary from tooltool 19:04:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:04:03 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ceb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x152ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x152e240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:04:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 19:04:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 19:04:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 19:04:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:04:03 INFO - Return code: 0 19:04:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:04:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:04:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:04:03 INFO - ENV: RUST_BACKTRACE is now 1 19:04:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:04:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:04:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:04:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:04:03 INFO - Using env: {'DISPLAY': ':0', 19:04:03 INFO - 'HOME': '/home/cltbld', 19:04:03 INFO - 'LANG': 'en_US.UTF-8', 19:04:03 INFO - 'LANGUAGE': 'en_US:en', 19:04:03 INFO - 'LOGNAME': 'cltbld', 19:04:03 INFO - 'MAIL': '/var/mail/cltbld', 19:04:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:04:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:04:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:03 INFO - 'MOZ_NO_REMOTE': '1', 19:04:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:04:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:03 INFO - 'NO_EM_RESTART': '1', 19:04:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:03 INFO - 'PWD': '/builds/slave/test', 19:04:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:04:03 INFO - 'RUST_BACKTRACE': '1', 19:04:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:04:03 INFO - 'SHELL': '/bin/bash', 19:04:03 INFO - 'SHLVL': '1', 19:04:03 INFO - 'TERM': 'linux', 19:04:03 INFO - 'TMOUT': '86400', 19:04:03 INFO - 'USER': 'cltbld', 19:04:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255', 19:04:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:03 INFO - '_': '/tools/buildbot/bin/python'} 19:04:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:04:03 INFO - mozversion application_buildid: 20170728011528 19:04:03 INFO - mozversion application_changeset: 556f19ef392ac2d9aac579864e2179d6c1d464e8 19:04:03 INFO - mozversion application_display_name: Nightly 19:04:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:04:03 INFO - mozversion application_name: Firefox 19:04:03 INFO - mozversion application_remotingname: firefox 19:04:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:04:03 INFO - mozversion application_vendor: Mozilla 19:04:03 INFO - mozversion application_version: 56.0a1 19:04:03 INFO - mozversion platform_buildid: 20170728011528 19:04:03 INFO - mozversion platform_changeset: 556f19ef392ac2d9aac579864e2179d6c1d464e8 19:04:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:04:03 INFO - mozversion platform_version: 56.0a1 19:04:03 INFO - using testdate: 1501207443 19:04:03 INFO - actual date: 1501207443 19:04:03 INFO - starting webserver on 'localhost:52453' 19:04:03 INFO - SUITE-START | Running 2 tests 19:04:03 INFO - TEST-START | dromaeo_css 19:04:03 INFO - Initialising browser for dromaeo_css test... 19:04:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52453/getInfo.html -profile /tmp/tmpceFs9g/profile 19:04:03 INFO - TEST-INFO | started process 3340 (/builds/slave/test/build/application/firefox/firefox http://localhost:52453/getInfo.html) 19:04:13 INFO - TEST-INFO | 3340: exit 0 19:04:13 INFO - Browser initialized. 19:04:13 INFO - Running cycle 1/1 for dromaeo_css test... 19:04:13 INFO - TEST-INFO | started process 3547 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpceFs9g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:04:14 INFO - PID 3547 | 19:04:14 INFO - PID 3547 | (/builds/slave/test/build/application/firefox/firefox:3595): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:04:14 INFO - PID 3547 | 19:04:24 INFO - PID 3547 | RSS: Main: 171204608 19:04:24 INFO - PID 3547 | 19:07:24 INFO - PID 3547 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52453/tests/dromaeo/cssquery-ext.html) 19:07:25 INFO - PID 3547 | RSS: Main: 172654592 19:07:25 INFO - PID 3547 | 19:10:27 INFO - PID 3547 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-ext.html (next: http://localhost:52453/tests/dromaeo/cssquery-jquery.html) 19:10:28 INFO - PID 3547 | RSS: Main: 170172416 19:10:28 INFO - PID 3547 | 19:13:30 INFO - PID 3547 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52453/tests/dromaeo/cssquery-mootools.html) 19:13:30 INFO - PID 3547 | RSS: Main: 172593152 19:13:30 INFO - PID 3547 | 19:16:32 INFO - PID 3547 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52453/tests/dromaeo/cssquery-prototype.html) 19:16:33 INFO - PID 3547 | RSS: Main: 171388928 19:16:33 INFO - PID 3547 | 19:19:36 INFO - PID 3547 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52453/tests/dromaeo/cssquery-yui.html) 19:19:37 INFO - PID 3547 | RSS: Main: 171556864 19:19:37 INFO - PID 3547 | 19:22:39 INFO - PID 3547 | Cycle 1(1): loaded http://localhost:52453/tests/dromaeo/cssquery-yui.html (next: http://localhost:52453/tests/dromaeo/cssquery-dojo.html) 19:22:39 INFO - PID 3547 | RSS: Main: 174899200 19:22:39 INFO - PID 3547 | 19:22:39 INFO - PID 3547 | __start_tp_report 19:22:39 INFO - PID 3547 | _x_x_mozilla_page_load 19:22:39 INFO - PID 3547 | _x_x_mozilla_page_load_details 19:22:39 INFO - PID 3547 | |i|pagename|runs| 19:22:39 INFO - PID 3547 | |0;dojo.html;3135.2945587242875;3175.3649270145947;3175.444297247981;3192.55304257404;3198.7920785148963;6191.071339299102;6202.78290259841;6204.689765511717;6211.720472578733;6284.245890493135;6212.254529456461;6237.18916540849;6303.401176888188;6336.588121772082;6349.619022858632;5672.5178359839465;5760.0783874580075;5763.538916886639;5772.682502462373;5794.043982742864;5690.687012214326;5762.855928601797;5772.769089236425;5813.360530341659;5860.472557469827;5605.299337582802;5674.290713660793;5676.772929082832;5688.715564221793;5694.943050569504;5643.774249030034;5688.544916406678;5692.743826527816;5747.626404283708;5751.281089863767;5668.348139963916;5685.658860468385;5738.139279108125;5747.281589801275;5761.942380576224;6136.9079463808075;6184.721687524072;6279.089532017878;6287.434130928238;6290.528210384194;6513.869722605568;6657.1012913257055;6693.2302785179845;6747.865042699168;6759.628220447873;6654.434373078294;6753.864922701568;6758.290379510179;6759.695813688396;6786.423154031913;6174.96912515439;6283.59156654816;6291.842703932392;6295.685215739195;6325.715342809584;6880.174379074543;6961.443084553325;6969.54697944632;7001.089858318386;7005.404540614105;6423.967880160662;6518.706658200393;6538.1827271591055;6565.080888675589;6682.031105489774;6642.070110184578;6646;6648.301928297556;6649.035889795952;6676.26561078281;6530.869382612321;6608.735650574019;6616.768413105518;6633.635150066696;6660.533762636569;6630.9668451657435;6664.733410663616;6668.399844014062;6673.933260667428;6674.232463266787;5414.3502779665905;5430.13117901134;5465.152901300305;5486.177073439016;5486.286782718221;4560.452745670541;4622.14490319292;4696.342512048316;4705.835295764632;4707.7175369477945;5745.109508026263;5772.7113644317615;5815.534757219412;5862;5867.73595188209;6634.734610615618;6648.66756662165;6757.189137303555;6762.391384775394;6787.932120678829;5878.735456904449;5900.262467191601;5907.3797251288;5908.675022873783;5941.22764040672;5934.792282270186;5943.702814859326;6017.06735456005;6030.33666296707;6046.3651316612;6381.585196962183;6501.317361677145;6504.5121615879;6586.571872828251;6590.439812615886;6714.899276510954;6785.491088168308;6799.286074962256;6804.727810887509;6805.319468053155;6433.967830160819;6475.676216189266;6495.772647957298;6522.934770652233;6526;6588.7364505421165;6600.669966501559;6633.834154146185;6644.169478815148;6650.534462587572;6728.73085076611;6733.3939945405145;6841.315868413318;6845.315468453115;6847.452203823782;6314.6842657866;6424.389682980296;6460.515461340512;6508.056331831953;6514.1531600891585;6517.055027021151;6534.281229064704;6536.248331441755;6540.182477190351;6555.6066636002;6407.231132264158;6465.612063276219;6503.6097834130105;6503.869922601616;6506.41442270198;6505.902411463737;6517.837054073686;6580.901286480611;6610.867782644224;6630.270670226368;6212.627242365473;6292.685365731787;6309.7160627770945;6356.936430635635;6394.4564712 19:22:39 INFO - PID 3547 | |1;ext.html;836.7531578184435;848.4909054567256;871.5293741379654;895.6909866096198;898.0211569389364;41257.38113928294;41774.7911260444;41793;41794.582054179446;42001.57998420015;37361.06597335061;37605.247895042085;37656.43515347265;37688.43467347985;37886.43170352447;6992.790216293507;7098.290170982912;7108.502404831664;7109.786706398791;7113.466490013244;6897.41371983382;6975.5814651120845;7023.719051237945;7027.683754231046;7029.086218791575;6988.126484189476;7819.178986206452;7944.841103177933;7968.043834739839;7979.441439099265;7825.960870195641;7883.802904927394;7904.644291006918;7908.406869484812;7912.881306780403;7717.189695082048;7783.182765809565;7833.216678332178;7844.37245020397;7853;38571.22857542834;38629.806850965564;38802.61197388046;38811.029724256834;38860.417093743614;3479.3911065563425;3533.5406397168463;3539.8407071681836;3540.150363912642;3547.024568243728;31243.531347029813;31321.216969575715;31479;31498.527522087188;31528.842355788074;4777.28340748887;4799.784009719571;4811.13399588074;4832.226843704991;4906.705597664151;184007;184659.99999999866;184702;184783;185388;49010.75494622504;49013.26480102801;49048.75475622599;49118.26322605164;49520.50479495159;51371.22943155855;51547.22679159816;51560.48439515557;51581.48418515842;51680.483195168315;16903;17037.318507259817;17054.232559534852;17095.401660941807;17097.743533847002;13741.450341986409;13945.093568917988;13945.721085578232;13954.302284885714;13960;14499.85500144985;14537.055091419024;14546.41814327415;14594.927025364805;14798.260086995608;5669.34802497718;5837.503812176013;5931.665375290525;6030.065339872327;6080.847978800565;975.9853602195973;983.2478154212033;988.3378136648463;1010.9494525273707;1011.8178727829062;21757.891210543847;22241.27788136515;22611.321660350215;22991.885040575024;23159.53680926372;1051.169576034924;1053.6786280184465;1063.8085144674033;1078.5470102556944;1092.9125669946545;11903.464344104363;12001.219920705125;12092.214006089753;12131.636050918487;12175.269483830998;219284;219973;221289;221579;221598;53137;53144;56466;57852.421475785544;58004;1454.142056186855;1465.274689028916;1472.801171841809;1477.95989815406;1491.5376233367474;26053.609195862457;26759.19722408331;26838.59742103831;27129.593056104568;27248.863755681094;37673;37794.81102594469;38106.23787524289;38128.42807357836;38145;191247;191312;191729;191837;193268;34567;34749.30501389907;34934.47598285977;37065.62934370622;37984;45428.54571454243;46727;47228.29157562708;47349.52650473451;47705.761471192425;31299.53050704287;32198;32235.516467252542;32636.347273054882;32824.83587582047;30513.694863051085;30655.846720766254;32489.67510324961;32506.83746581252;32523.837380812944;37359.25281494409;37430.251394972496;37549.62450375461;37612.435813462274;38532;32990.83504582462;33388.499172512915;33389.33221335511;33741;33833.323333533684;22852.885735571217;23732.762672373745;23918.64122038136;24222.63666045046;25090.37274068163 19:22:39 INFO - PID 3547 | |2;jquery.html;573.7160105747655;579.5913880714097;579.9072148456245;592.6325678079592;597.4055814464607;22487.55024899503;23312.06751729929;23315.300540983797;23326;23361.766382336173;21580.244691435804;21943.341699749035;22024.559508809813;22024.559508809853;22047.448813779665;5981.401859814027;6405.7757978470645;6545.116409284757;6546.836329091787;6548.27968923418;6515.7719479818215;6520.836979075513;6563.803085907431;6567.277599464055;6567.408933196011;6510.739570417178;6527.543071984963;6530.2163740351225;6530.412262896339;6532.08550802888;6518.804435866931;6535.379138981789;6536.575122617015;6539.313372095909;6540.6729663516635;6053.606515576474;6061.545384096166;6080.270367555921;6081.665508397036;6082.635041897501;27969;28184.85907570449;28823.279418014507;28840.855795720887;28894.71105288941;1913.0721600023978;1951.9121639526256;1957.275807951053;1958.4810025343295;1961.725358449804;17999.820001799944;18412.63174736511;18530.629387412177;18536.165872535767;18575.72136417955;2215.9778402215934;2250.8762018089;2260.3332017054845;2271.738750043733;2395.077895010416;124672.37663811713;129460;130191;130262.34868825595;130480.34759826235;14163.362648680833;14578.125312481285;14805.333759980827;14852.777208341884;15203.467878624195;22395.32814015582;22518.887405563197;22535.52109267979;22604.660930086062;22626.773732262795;10069.194464442828;10167.949160254151;10261.230407719451;10302.433366164783;10335.121514671187;8704.434211776213;9085.36402451822;9481.4785186814;9665.71002869915;9674.08096230857;9005.729828105168;9264.629414823332;9267.953660231655;9323.813523729488;9330.533473326306;2617.437250991046;2689.9596506052426;2774.279946433184;2775.264554892955;2852.1015879997735;922.3174850610634;931.2270815223349;951.8096380723883;966.4974213408988;968.3512046928574;18673.439796806117;19101.567382446374;19179.520511987314;19221.121848361632;19430.8056919429;1008.7730260691286;1009.9596016159418;1013.9265202660577;1037.486444210121;1038.4236748604596;10551.894481055244;10702;10968.23222374442;12558.74882502345;13517.729645407233;472149;481770;482620;483109;486718;110892;113637.43181284206;115367.42316288364;118641.40679296548;120233.39883300703;1242.9378531073592;1262.059765474727;1287.8261434706383;1292.4184117147133;1292.8448586169532;19486.902565487082;20672.069756861278;20704.689429658265;20950.790492094882;21319;53663.463365365846;54651.72674136763;55395;56293.71853140872;56438.153427699435;68176.318236817;68276.317236827;68498;68926.65536672284;69455.65272173607;12472.87527124717;14606.269686515845;15862.206889655701;17684.64630707353;17769.111544422467;24092.759072409754;24403.145889893767;24602.138925137533;25205.495890082464;25460.363490912874;18414.44756657249;18950.52623684419;19045.333413330467;19136.617267654292;19146.7127993083;15649.652720891007;15770.526884193492;16766.32934682599;16851.494455166365;17222.741658874875;18925.716114258023;19379.903100484407;19486.610267794847;19617;19675.508112297306;21209.257675981265;21459.89270053702;21484.14063437445;21514.354569362946;21543.46141346479;18562.164702588147;18707;19097.045147742836;19410.8058919409;19445 19:22:39 INFO - PID 3547 | |3;mootools.html;1340.5844188301626;1375.5735721926203;1388.763910135277;1392.9164250144988;1394.4840409048654;13751.862481375196;14196.574102776907;14200.07699499533;14251.073680210779;14259.3583288752;13844.515441959533;14302.427902883897;14783.704325913475;14800.185989770558;14815.481458148966;2554.3103362092224;2592.4685439484956;2608.6478325426015;2609.712931577525;2619.240420278117;2437.85372877627;2607.34816295926;2625.8424494530295;2670.492606404777;2673.5989601559822;2724.101046654609;2740.4245108527234;2746.9038583649576;2747.244507760372;2750.339918419575;2639.907603233887;2642.51113543994;2656.561667324889;2660.334916270942;2666.520026395248;2592.611108333765;2596.182202606173;2601.791856651463;2603.8437693738247;2604.7134815170316;13836.446542138403;14036.087654302437;14044.92977535116;14063.36714847824;14114.50599229022;7275.9636201819185;7497.1753107158165;7553.69785208591;7582.658780354897;7586.62066896653;4874.780634871439;4945.184044632631;5040.656094915882;5108.591312694975;5119.948800511985;1815.700409432442;1820.4629634257926;1843.75109360236;1869.2242719271485;1880.4546681462496;22291.331260062223;22398.88800556003;22713.20503782376;22732.090716371324;22741.317760467213;6123.295820980555;6253.781117660951;6305.4009869062365;6441.710123044474;6476;15191.696166076617;15675.05949643025;15715.607109822346;15815.762763558556;15818.05091694502;7346.5592064476305;7460.216677248919;7504.737334193277;7566.470347075762;7571.3942884569105;6821.1814582250445;6964.651767411609;6977.546459480118;7110.146782386147;7162.641867906688;4930.7288099154175;5018.924716129261;5025.949740502549;5045.243213517928;5089.643724939218;1436.9510257512075;1438.518096437684;1461.2474575593578;1463.8088966054104;1465.3259500629617;830.543201239316;846.8094678697244;846.8137009857821;864.446754077391;880.3221519430003;6409.743610255631;6640.601563906181;6786;6801.421879140328;7752.26353496417;866.445474896067;898.85168947123;948.5439849830477;954.7231302922074;988.5699720621552;4319.460067491564;4562.292844609091;4571.588557029883;4689.812407503729;4701.78841952113;17918.820811791975;18003.72994405085;18456.07719614014;18471.90764046171;18503.629927401376;17731.822681773276;17931.820681793015;18094.547636308933;18293.26826926908;18389.90805045966;10758.892411075945;10814.729631759268;10939.343639381663;10940.12479001678;10945.343279403101;6737.966310168418;7282.089738782653;7311.305425984523;7357.337839594248;7377.4098072155175;14770.48303309379;16411.784409104617;16705.91647041757;16887;17000.829991699924;16368.75446868274;16549.58626034359;16735.163241837618;16918;17060.91469542694;11116.722081948015;11318.773624527628;11505.252158609663;11543.88456115428;11583.247088939192;12477.875221248034;12574.622761317176;12647;12650.493980241055;12653.746925061263;10051.547680354255;10371.118454931415;10414.062734353945;10480.737981550523;10703.732406689584;9409.717708468757;9724.9027509724;9927.950360248153;10070;10250.948745256226;8907;8968.641254349754;8981.550922453982;9013.504257265913;9015.774605634911;6694.297098804555;6729.192496900403;6742.6291553965;6751.122354093936;6789;6444.130042444398;6603.273639899703;6639.3692599203;6810.897836532555;6917.446604271546 19:22:39 INFO - PID 3547 | |4;prototype.html;497.7456808841719;502.21212483558514;525.060142345202;525.4902744337991;533.5864704853738;6232.626042437451;6279.18370611821;6286.6228026318395;6300.810975670725;6341.270753863307;5926.170336152942;6097.420745029226;6183.59806612571;6185;6217.782377616784;4881.243407271861;4916.336294600237;4930.383702037246;4938.283948827418;4960.429550601672;4905.533974272456;4931.358923339961;4998.325226094467;4999.175136102539;5010.073136469747;4761.452432970201;4897.534734200245;4898.1345912641145;4929.704217746928;4930.422264875224;4928.236123400879;4938.555530002299;4968.950310496921;4994.175960966436;5038.697678139323;4438.800253988578;4446.333050042487;4450.577195166454;4452.755098469583;4494.325851122325;6025.07783724893;6061.848453788646;6070.2412198475195;6126;6144.170536977495;5699.373068962411;5712.22884910536;5726.570507211975;5745.477448476157;5792.131180322943;6484.610923344567;6505.41451269388;6554.606723596552;6598.769043083469;6607.966960165217;4848.418189817209;4873.853784386474;4886.267059941038;4892.706437613754;4903.730294833783;6774.220964589086;6943.51395402322;6946.4225923648355;6981.476389270774;6989.89515157273;5007.94674183583;5009.44896061433;5013.323201367842;5026.346574945233;5052.873678158002;5020.447750747415;5048.29323894655;5054.346468104201;5097.93533618885;5101.0818052750865;5682.914756278659;5714.342850572154;5742.971285143548;5761.567882408836;5772.134179872969;6405.220919657425;6432.2281326241155;6440.291567927524;6453.386928241809;6611.140551728245;1672.4397326895626;1699.2947926610318;1710.7947046354268;1731.6277000444966;1746.1443892492841;1199.7540504196618;1205.5539450403407;1209.6552482542434;1237.3813093453273;1237.6287113865803;5693.971530142323;5772.134179872969;5807.332156801937;5818.127280907897;5819.679917604488;6554.901676474857;6663.533552651268;6718.899216511756;6725.5363487803825;6754.425873800684;5799.217105690762;5808.3901190375245;5831.737571809279;5874.443272532782;5887.438099949554;3543.344481270973;3606.6393360663724;3638.9090272742865;3651.7626354286886;3667.661303624158;6559.639219842859;6562.179727534059;6585.80242592723;6630.270670226271;6651.800445986628;6943.51395402317;6953.165620125617;6963.79108626742;7016.719331226694;7026;6503.869922601616;6553.63954982471;6556.18047744032;6570.5400621956005;6667.233268174125;6625.668716564249;6665.633390163393;6764.594124352555;6785.287544807921;6838.5213035089055;6691.799246022627;6729.913726744529;6730.226024007302;6731;6754.5609535380045;6480.25477070143;6492.285848556749;6544.8363790905605;6549.639769812516;6584.8353791155605;4963.181075122564;4993.675411098267;4995.750212489288;5026.070177017264;5061.39263288393;6508.739650413931;6716.899246511404;6779.322067793181;6832.316768323128;6841.863162736817;6352.396522330371;6416.294207637249;6422.527326979053;6446.645434501147;6602.867942641216;6490.870182596416;6524.792913311053;6558.475321974326;6558.704858281293;6567.376099270562;6304.842378940563;6346.746130154742;6386.616802991835;6454.644994525158;6470.579412338183;6472;6548.410643042149;6613.016095171333;6647.135872336566;6655.667216639052;6336.257529179513;6352.491800655844;6397.976323788172;6401.199850018747;6447.6131432114225 19:22:39 INFO - PID 3547 | |5;yui.html;866.2982983783132;885.2740752582885;894.7718331825383;902.6569903436693;906.0667512462162;17495.212715427806;18155.909220453912;18159.90920045401;18162.818371816276;18171.364002259925;16859;16880.915595422004;17454.825451745477;19923;21273.68089478659;1033.5090831854884;1060.083028180626;1060.363781730963;1060.697701155171;1060.9628662996797;1061.909737672295;1064.2656566968806;1064.4518073192273;1065.1532032034552;1065.1798115451097;1038.3562191441317;1088.1349327284788;1091.3670071358592;1115.6931843742955;1125.8142406502918;1073.2165519171049;1073.2809017957986;1073.8550295710097;1073.8765042020189;1082.123479981214;787.5825812319423;790.6600161930368;793.4406243598249;795.9164287749819;796.9402294827911;18669.53326166843;19452.416427507196;19479;19501.707474387895;19518.707219391577;19965.301214457424;20400;20582.79417205824;20924.895375523025;20927.68608470874;15044.924775376163;15379.307931143014;15502.379904803794;15508.224588770516;15512.844871551253;3385.3229354129026;3428.3314753623054;3431.022158684788;3437.5015622734813;3437.5874895012757;115676;119336;119903;124371;124954;15177.6205594861;15744.212789360487;15747.68504629901;15762.76355854663;15780.60548486297;27423.86288068547;29352.559711604343;29535.85232073869;29536.704632953824;29727;8851.247643950208;8906.109389061172;8939.865902011476;8942.776430589287;8960.910390896139;3977.840886364571;4110.568390319033;4118.032262418329;4125.958740412558;4139.254934111888;4951.579115775199;5057.898842023139;5075.0357432087785;5076.466970968069;5098.923516147261;1030.907218350352;1063.4842101580728;1072.5495291977388;1080.0333701337258;1080.3517889266377;621.5431657731564;642.6722371590522;668.5654324688991;668.9732410703615;677.8508728079814;10637.946810266054;11293.887061129448;11857.407129643656;12022.699432514257;12623.936880315723;633.8288662061218;635.5614625908108;639.0861068671751;652.5171373183905;673.0778832998725;5307.893842123136;5383.192521121863;5465.836024919259;5475.616706830484;5665.575081868877;104117;104580;105461.47269263757;106046;106089;85610.57194713986;88366.55816720876;90876.54561727149;90883.72790680153;94808.52595737115;75702.62148689332;78617.21382786213;78839;81348.99999999882;82273;10428.895711042793;10694.786104278026;11346.546138154381;11959.162858599815;12396.690082747642;33855.66144338525;35333.29333413266;35413.521053526005;35750.4637430444;37304;65065.34934650782;65790.34209657842;66871.33128668845;67088.32911671016;68435.65782171057;18923.90538047301;19191.424257272305;19222.231110755973;19234.326798561982;19502.609947801247;18771.530711732317;19033.238670453025;19233;21417.678734818677;22013.009414576703;16484.752728708838;18553.53616159607;19091.236350545823;19176.904115479334;19612.803871961667;19113.23547058102;19122.617547649246;19262.8073719261;19386.4184074478;19422;18200.635987280446;18445.077746112907;18732.906335468237;18733.90633046826;18945.810541894403;13219.867801321863;13235.073544851768;13563.236779218638;13713.520026799015;14083.225422601856;13676.589702308947;13864.514741983981;14321.928390357982;14428.42286308536;14651.340689668777 19:22:39 INFO - PID 3547 | __end_tp_report 19:22:39 INFO - PID 3547 | __start_cc_report 19:22:39 INFO - PID 3547 | _x_x_mozilla_cycle_collect,360 19:22:39 INFO - PID 3547 | __end_cc_report 19:22:39 INFO - PID 3547 | __startTimestamp1501208559481__endTimestamp 19:22:39 INFO - PID 3547 | 19:22:39 INFO - PID 3547 | ------- Summary: start ------- 19:22:39 INFO - PID 3547 | Number of tests: 6 19:22:39 INFO - PID 3547 | 19:22:39 INFO - PID 3547 | [#0] dojo.html Cycles:165 Average:6174.72 Median:6407.23 stddev:722.01 (11.3%) stddev-sans-first:683.72 19:22:39 INFO - PID 3547 | Values: 3135.3 3175.4 3175.4 3192.6 3198.8 6191.1 6202.8 6204.7 6211.7 6284.2 6212.3 6237.2 6303.4 6336.6 6349.6 5672.5 5760.1 5763.5 5772.7 5794.0 5690.7 5762.9 5772.8 5813.4 5860.5 5605.3 5674.3 5676.8 5688.7 5694.9 5643.8 5688.5 5692.7 5747.6 5751.3 5668.3 5685.7 5738.1 5747.3 5761.9 6136.9 6184.7 6279.1 6287.4 6290.5 6513.9 6657.1 6693.2 6747.9 6759.6 6654.4 6753.9 6758.3 6759.7 6786.4 6175.0 6283.6 6291.8 6295.7 6325.7 6880.2 6961.4 6969.5 7001.1 7005.4 6424.0 6518.7 6538.2 6565.1 6682.0 6642.1 6646.0 6648.3 6649.0 6676.3 6530.9 6608.7 6616.8 6633.6 6660.5 6631.0 6664.7 6668.4 6673.9 6674.2 5414.4 5430.1 5465.2 5486.2 5486.3 4560.5 4622.1 4696.3 4705.8 4707.7 5745.1 5772.7 5815.5 5862.0 5867.7 6634.7 6648.7 6757.2 6762.4 6787.9 5878.7 5900.3 5907.4 5908.7 5941.2 5934.8 5943.7 6017.1 6030.3 6046.4 6381.6 6501.3 6504.5 6586.6 6590.4 6714.9 6785.5 6799.3 6804.7 6805.3 6434.0 6475.7 6495.8 6522.9 6526.0 6588.7 6600.7 6633.8 6644.2 6650.5 6728.7 6733.4 6841.3 6845.3 6847.5 6314.7 6424.4 6460.5 6508.1 6514.2 6517.1 6534.3 6536.2 6540.2 6555.6 6407.2 6465.6 6503.6 6503.9 6506.4 6505.9 6517.8 6580.9 6610.9 6630.3 6212.6 6292.7 6309.7 6356.9 6394.5 19:22:39 INFO - PID 3547 | 19:22:39 INFO - PID 3547 | [#1] ext.html Cycles:180 Average:37394.89 Median:37212.44 stddev:51694.75 (138.9%) stddev-sans-first:51766.88 19:22:39 INFO - PID 3547 | Values: 836.8 848.5 871.5 895.7 898.0 41257.4 41774.8 41793.0 41794.6 42001.6 37361.1 37605.2 37656.4 37688.4 37886.4 6992.8 7098.3 7108.5 7109.8 7113.5 6897.4 6975.6 7023.7 7027.7 7029.1 6988.1 7819.2 7944.8 7968.0 7979.4 7826.0 7883.8 7904.6 7908.4 7912.9 7717.2 7783.2 7833.2 7844.4 7853.0 38571.2 38629.8 38802.6 38811.0 38860.4 3479.4 3533.5 3539.8 3540.2 3547.0 31243.5 31321.2 31479.0 31498.5 31528.8 4777.3 4799.8 4811.1 4832.2 4906.7 184007.0 184660.0 184702.0 184783.0 185388.0 49010.8 49013.3 49048.8 49118.3 49520.5 51371.2 51547.2 51560.5 51581.5 51680.5 16903.0 17037.3 17054.2 17095.4 17097.7 13741.5 13945.1 13945.7 13954.3 13960.0 14499.9 14537.1 14546.4 14594.9 14798.3 5669.3 5837.5 5931.7 6030.1 6080.8 976.0 983.2 988.3 1010.9 1011.8 21757.9 22241.3 22611.3 22991.9 23159.5 1051.2 1053.7 1063.8 1078.5 1092.9 11903.5 12001.2 12092.2 12131.6 12175.3 219284.0 219973.0 221289.0 221579.0 221598.0 53137.0 53144.0 56466.0 57852.4 58004.0 1454.1 1465.3 1472.8 1478.0 1491.5 26053.6 26759.2 26838.6 27129.6 27248.9 37673.0 37794.8 38106.2 38128.4 38145.0 191247.0 191312.0 191729.0 191837.0 193268.0 34567.0 34749.3 34934.5 37065.6 37984.0 45428.5 46727.0 47228.3 47349.5 47705.8 31299.5 32198.0 32235.5 32636.3 32824.8 30513.7 30655.8 32489.7 32506.8 32523.8 37359.3 37430.3 37549.6 37612.4 38532.0 32990.8 33388.5 33389.3 33741.0 33833.3 22852.9 23732.8 23918.6 24222.6 25090.4 19:22:39 INFO - PID 3547 | 19:22:39 INFO - PID 3547 | [#2] jquery.html Cycles:180 Average:34391.57 Median:12305.65 stddev:80833.77 (656.9%) stddev-sans-first:81020.65 19:22:39 INFO - PID 3547 | Values: 573.7 579.6 579.9 592.6 597.4 22487.6 23312.1 23315.3 23326.0 23361.8 21580.2 21943.3 22024.6 22024.6 22047.4 5981.4 6405.8 6545.1 6546.8 6548.3 6515.8 6520.8 6563.8 6567.3 6567.4 6510.7 6527.5 6530.2 6530.4 6532.1 6518.8 6535.4 6536.6 6539.3 6540.7 6053.6 6061.5 6080.3 6081.7 6082.6 27969.0 28184.9 28823.3 28840.9 28894.7 1913.1 1951.9 1957.3 1958.5 1961.7 17999.8 18412.6 18530.6 18536.2 18575.7 2216.0 2250.9 2260.3 2271.7 2395.1 124672.4 129460.0 130191.0 130262.3 130480.3 14163.4 14578.1 14805.3 14852.8 15203.5 22395.3 22518.9 22535.5 22604.7 22626.8 10069.2 10167.9 10261.2 10302.4 10335.1 8704.4 9085.4 9481.5 9665.7 9674.1 9005.7 9264.6 9268.0 9323.8 9330.5 2617.4 2690.0 2774.3 2775.3 2852.1 922.3 931.2 951.8 966.5 968.4 18673.4 19101.6 19179.5 19221.1 19430.8 1008.8 1010.0 1013.9 1037.5 1038.4 10551.9 10702.0 10968.2 12558.7 13517.7 472149.0 481770.0 482620.0 483109.0 486718.0 110892.0 113637.4 115367.4 118641.4 120233.4 1242.9 1262.1 1287.8 1292.4 1292.8 19486.9 20672.1 20704.7 20950.8 21319.0 53663.5 54651.7 55395.0 56293.7 56438.2 68176.3 68276.3 68498.0 68926.7 69455.7 12472.9 14606.3 15862.2 17684.6 17769.1 24092.8 24403.1 24602.1 25205.5 25460.4 18414.4 18950.5 19045.3 19136.6 19146.7 15649.7 15770.5 16766.3 16851.5 17222.7 18925.7 19379.9 19486.6 19617.0 19675.5 21209.3 21459.9 21484.1 21514.4 21543.5 18562.2 18707.0 19097.0 19410.8 19445.0 19:22:39 INFO - PID 3547 | 19:22:39 INFO - PID 3547 | [#3] mootools.html Cycles:180 Average:8460.28 Median:2608.00 stddev:5775.36 (221.4%) stddev-sans-first:5766.79 19:22:39 INFO - PID 3547 | Values: 1340.6 1375.6 1388.8 1392.9 1394.5 13751.9 14196.6 14200.1 14251.1 14259.4 13844.5 14302.4 14783.7 14800.2 14815.5 2554.3 2592.5 2608.6 2609.7 2619.2 2437.9 2607.3 2625.8 2670.5 2673.6 2724.1 2740.4 2746.9 2747.2 2750.3 2639.9 2642.5 2656.6 2660.3 2666.5 2592.6 2596.2 2601.8 2603.8 2604.7 13836.4 14036.1 14044.9 14063.4 14114.5 7276.0 7497.2 7553.7 7582.7 7586.6 4874.8 4945.2 5040.7 5108.6 5119.9 1815.7 1820.5 1843.8 1869.2 1880.5 22291.3 22398.9 22713.2 22732.1 22741.3 6123.3 6253.8 6305.4 6441.7 6476.0 15191.7 15675.1 15715.6 15815.8 15818.1 7346.6 7460.2 7504.7 7566.5 7571.4 6821.2 6964.7 6977.5 7110.1 7162.6 4930.7 5018.9 5025.9 5045.2 5089.6 1437.0 1438.5 1461.2 1463.8 1465.3 830.5 846.8 846.8 864.4 880.3 6409.7 6640.6 6786.0 6801.4 7752.3 866.4 898.9 948.5 954.7 988.6 4319.5 4562.3 4571.6 4689.8 4701.8 17918.8 18003.7 18456.1 18471.9 18503.6 17731.8 17931.8 18094.5 18293.3 18389.9 10758.9 10814.7 10939.3 10940.1 10945.3 6738.0 7282.1 7311.3 7357.3 7377.4 14770.5 16411.8 16705.9 16887.0 17000.8 16368.8 16549.6 16735.2 16918.0 17060.9 11116.7 11318.8 11505.3 11543.9 11583.2 12477.9 12574.6 12647.0 12650.5 12653.7 10051.5 10371.1 10414.1 10480.7 10703.7 9409.7 9724.9 9928.0 10070.0 10250.9 8907.0 8968.6 8981.6 9013.5 9015.8 6694.3 6729.2 6742.6 6751.1 6789.0 6444.1 6603.3 6639.4 6810.9 6917.4 19:22:39 INFO - PID 3547 | 19:22:39 INFO - PID 3547 | [#4] prototype.html Cycles:180 Average:5519.53 Median:6135.09 stddev:1555.13 (25.3%) stddev-sans-first:1513.12 19:22:39 INFO - PID 3547 | Values: 497.7 502.2 525.1 525.5 533.6 6232.6 6279.2 6286.6 6300.8 6341.3 5926.2 6097.4 6183.6 6185.0 6217.8 4881.2 4916.3 4930.4 4938.3 4960.4 4905.5 4931.4 4998.3 4999.2 5010.1 4761.5 4897.5 4898.1 4929.7 4930.4 4928.2 4938.6 4969.0 4994.2 5038.7 4438.8 4446.3 4450.6 4452.8 4494.3 6025.1 6061.8 6070.2 6126.0 6144.2 5699.4 5712.2 5726.6 5745.5 5792.1 6484.6 6505.4 6554.6 6598.8 6608.0 4848.4 4873.9 4886.3 4892.7 4903.7 6774.2 6943.5 6946.4 6981.5 6989.9 5007.9 5009.4 5013.3 5026.3 5052.9 5020.4 5048.3 5054.3 5097.9 5101.1 5682.9 5714.3 5743.0 5761.6 5772.1 6405.2 6432.2 6440.3 6453.4 6611.1 1672.4 1699.3 1710.8 1731.6 1746.1 1199.8 1205.6 1209.7 1237.4 1237.6 5694.0 5772.1 5807.3 5818.1 5819.7 6554.9 6663.5 6718.9 6725.5 6754.4 5799.2 5808.4 5831.7 5874.4 5887.4 3543.3 3606.6 3638.9 3651.8 3667.7 6559.6 6562.2 6585.8 6630.3 6651.8 6943.5 6953.2 6963.8 7016.7 7026.0 6503.9 6553.6 6556.2 6570.5 6667.2 6625.7 6665.6 6764.6 6785.3 6838.5 6691.8 6729.9 6730.2 6731.0 6754.6 6480.3 6492.3 6544.8 6549.6 6584.8 4963.2 4993.7 4995.8 5026.1 5061.4 6508.7 6716.9 6779.3 6832.3 6841.9 6352.4 6416.3 6422.5 6446.6 6602.9 6490.9 6524.8 6558.5 6558.7 6567.4 6304.8 6346.7 6386.6 6454.6 6470.6 6472.0 6548.4 6613.0 6647.1 6655.7 6336.3 6352.5 6398.0 6401.2 6447.6 19:22:39 INFO - PID 3547 | 19:22:39 INFO - PID 3547 | [#5] yui.html Cycles:180 Average:22670.86 Median:19233.66 stddev:30264.66 (157.4%) stddev-sans-first:30305.27 19:22:39 INFO - PID 3547 | Values: 866.3 885.3 894.8 902.7 906.1 17495.2 18155.9 18159.9 18162.8 18171.4 16859.0 16880.9 17454.8 19923.0 21273.7 1033.5 1060.1 1060.4 1060.7 1061.0 1061.9 1064.3 1064.5 1065.2 1065.2 1038.4 1088.1 1091.4 1115.7 1125.8 1073.2 1073.3 1073.9 1073.9 1082.1 787.6 790.7 793.4 795.9 796.9 18669.5 19452.4 19479.0 19501.7 19518.7 19965.3 20400.0 20582.8 20924.9 20927.7 15044.9 15379.3 15502.4 15508.2 15512.8 3385.3 3428.3 3431.0 3437.5 3437.6 115676.0 119336.0 119903.0 124371.0 124954.0 15177.6 15744.2 15747.7 15762.8 15780.6 27423.9 29352.6 29535.9 29536.7 29727.0 8851.2 8906.1 8939.9 8942.8 8960.9 3977.8 4110.6 4118.0 4126.0 4139.3 4951.6 5057.9 5075.0 5076.5 5098.9 1030.9 1063.5 1072.5 1080.0 1080.4 621.5 642.7 668.6 669.0 677.9 10637.9 11293.9 11857.4 12022.7 12623.9 633.8 635.6 639.1 652.5 673.1 5307.9 5383.2 5465.8 5475.6 5665.6 104117.0 104580.0 105461.5 106046.0 106089.0 85610.6 88366.6 90876.5 90883.7 94808.5 75702.6 78617.2 78839.0 81349.0 82273.0 10428.9 10694.8 11346.5 11959.2 12396.7 33855.7 35333.3 35413.5 35750.5 37304.0 65065.3 65790.3 66871.3 67088.3 68435.7 18923.9 19191.4 19222.2 19234.3 19502.6 18771.5 19033.2 19233.0 21417.7 22013.0 16484.8 18553.5 19091.2 19176.9 19612.8 19113.2 19122.6 19262.8 19386.4 19422.0 18200.6 18445.1 18732.9 18733.9 18945.8 13219.9 13235.1 13563.2 13713.5 14083.2 13676.6 13864.5 14321.9 14428.4 14651.3 19:22:39 INFO - PID 3547 | -------- Summary: end -------- 19:22:39 INFO - PID 3547 | 19:22:40 INFO - TEST-INFO | 3547: exit 0 19:22:40 INFO - TEST-OK | dromaeo_css | took 1116669ms 19:22:40 INFO - TEST-START | kraken 19:22:40 INFO - Initialising browser for kraken test... 19:22:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52453/getInfo.html -profile /tmp/tmpCxm5VG/profile 19:22:40 INFO - TEST-INFO | started process 3905 (/builds/slave/test/build/application/firefox/firefox http://localhost:52453/getInfo.html) 19:22:46 INFO - TEST-INFO | 3905: exit 0 19:22:46 INFO - Browser initialized. 19:22:46 INFO - Running cycle 1/1 for kraken test... 19:22:46 INFO - TEST-INFO | started process 4113 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCxm5VG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:22:47 INFO - PID 4113 | 19:22:47 INFO - PID 4113 | (/builds/slave/test/build/application/firefox/firefox:4161): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:22:47 INFO - PID 4113 | 19:22:58 INFO - PID 4113 | RSS: Main: 170860544 19:22:58 INFO - PID 4113 | 19:23:00 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52453/tests/kraken/driver.html?testName=audio-beat-detection) 19:23:01 INFO - PID 4113 | RSS: Main: 177246208 19:23:01 INFO - PID 4113 | 19:23:04 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52453/tests/kraken/driver.html?testName=audio-dft) 19:23:04 INFO - PID 4113 | RSS: Main: 177569792 19:23:04 INFO - PID 4113 | 19:23:07 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52453/tests/kraken/driver.html?testName=audio-fft) 19:23:08 INFO - PID 4113 | RSS: Main: 171143168 19:23:08 INFO - PID 4113 | 19:23:10 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52453/tests/kraken/driver.html?testName=audio-oscillator) 19:23:11 INFO - PID 4113 | RSS: Main: 171569152 19:23:11 INFO - PID 4113 | 19:23:13 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52453/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:23:14 INFO - PID 4113 | RSS: Main: 172097536 19:23:14 INFO - PID 4113 | 19:23:17 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52453/tests/kraken/driver.html?testName=imaging-darkroom) 19:23:17 INFO - PID 4113 | RSS: Main: 174362624 19:23:17 INFO - PID 4113 | 19:23:21 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52453/tests/kraken/driver.html?testName=imaging-desaturate) 19:23:21 INFO - PID 4113 | RSS: Main: 174657536 19:23:21 INFO - PID 4113 | 19:23:24 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52453/tests/kraken/driver.html?testName=json-parse-financial) 19:23:25 INFO - PID 4113 | RSS: Main: 174891008 19:23:25 INFO - PID 4113 | 19:23:27 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52453/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:23:27 INFO - PID 4113 | RSS: Main: 174886912 19:23:27 INFO - PID 4113 | 19:23:29 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:23:30 INFO - PID 4113 | RSS: Main: 174862336 19:23:30 INFO - PID 4113 | 19:23:32 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:23:32 INFO - PID 4113 | RSS: Main: 171601920 19:23:32 INFO - PID 4113 | 19:23:35 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:23:36 INFO - PID 4113 | RSS: Main: 172429312 19:23:36 INFO - PID 4113 | 19:23:39 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:23:39 INFO - PID 4113 | RSS: Main: 172482560 19:23:39 INFO - PID 4113 | 19:23:41 INFO - PID 4113 | Cycle 1(1): loaded http://localhost:52453/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52453/tests/kraken/driver.html?testName=ai-astar) 19:23:42 INFO - PID 4113 | RSS: Main: 173932544 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | __start_tp_report 19:23:42 INFO - PID 4113 | _x_x_mozilla_page_load 19:23:42 INFO - PID 4113 | _x_x_mozilla_page_load_details 19:23:42 INFO - PID 4113 | |i|pagename|runs| 19:23:42 INFO - PID 4113 | |0;ai-astar;99;108;103;111;109;109;108;100;110;114 19:23:42 INFO - PID 4113 | |1;audio-beat-detection;152;124;144;193;125;145;159;119;171;119 19:23:42 INFO - PID 4113 | |2;audio-dft;164;163;164;163;162;164;171;165;163;162 19:23:42 INFO - PID 4113 | |3;audio-fft;76;80;75;173;81;75;75;76;150;76 19:23:42 INFO - PID 4113 | |4;audio-oscillator;79;80;136;79;79;79;132;79;79;126 19:23:42 INFO - PID 4113 | |5;imaging-gaussian-blur;114;115;114;114;115;117;114;118;114;114 19:23:42 INFO - PID 4113 | |6;imaging-darkroom;152;153;150;151;151;150;151;150;150;151 19:23:42 INFO - PID 4113 | |7;imaging-desaturate;94;94;95;94;94;94;98;94;93;95 19:23:42 INFO - PID 4113 | |8;json-parse-financial;56;57;56;56;56;56;59;55;59;57 19:23:42 INFO - PID 4113 | |9;json-stringify-tinderbox;54;54;54;54;54;54;55;55;55;54 19:23:42 INFO - PID 4113 | |10;stanford-crypto-aes;83;82;89;89;81;81;81;82;81;81 19:23:42 INFO - PID 4113 | |11;stanford-crypto-ccm;127;136;136;145;128;126;123;124;126;169 19:23:42 INFO - PID 4113 | |12;stanford-crypto-pbkdf2;188;173;170;173;174;179;170;171;172;173 19:23:42 INFO - PID 4113 | |13;stanford-crypto-sha256-iterative;72;68;69;72;69;67;69;70;68;67 19:23:42 INFO - PID 4113 | __end_tp_report 19:23:42 INFO - PID 4113 | __start_cc_report 19:23:42 INFO - PID 4113 | _x_x_mozilla_cycle_collect,985 19:23:42 INFO - PID 4113 | __end_cc_report 19:23:42 INFO - PID 4113 | __startTimestamp1501208622028__endTimestamp 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | ------- Summary: start ------- 19:23:42 INFO - PID 4113 | Number of tests: 14 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#0] ai-astar Cycles:10 Average:107.10 Median:109.50 stddev:4.86 (4.4%) stddev-sans-first:4.18 19:23:42 INFO - PID 4113 | Values: 99.0 108.0 103.0 111.0 109.0 109.0 108.0 100.0 110.0 114.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:148.50 stddev:24.54 (16.5%) stddev-sans-first:25.90 19:23:42 INFO - PID 4113 | Values: 152.0 124.0 144.0 193.0 125.0 145.0 159.0 119.0 171.0 119.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.60 (1.6%) stddev-sans-first:2.76 19:23:42 INFO - PID 4113 | Values: 164.0 163.0 164.0 163.0 162.0 164.0 171.0 165.0 163.0 162.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#3] audio-fft Cycles:10 Average:93.70 Median:76.00 stddev:36.20 (47.6%) stddev-sans-first:37.83 19:23:42 INFO - PID 4113 | Values: 76.0 80.0 75.0 173.0 81.0 75.0 75.0 76.0 150.0 76.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:25.32 (32.1%) stddev-sans-first:26.21 19:23:42 INFO - PID 4113 | Values: 79.0 80.0 136.0 79.0 79.0 79.0 132.0 79.0 79.0 126.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#5] imaging-gaussian-blur Cycles:10 Average:114.90 Median:114.50 stddev:1.45 (1.3%) stddev-sans-first:1.50 19:23:42 INFO - PID 4113 | Values: 114.0 115.0 114.0 114.0 115.0 117.0 114.0 118.0 114.0 114.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:0.99 (0.7%) stddev-sans-first:0.97 19:23:42 INFO - PID 4113 | Values: 152.0 153.0 150.0 151.0 151.0 150.0 151.0 150.0 150.0 151.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:1.35 (1.4%) stddev-sans-first:1.42 19:23:42 INFO - PID 4113 | Values: 94.0 94.0 95.0 94.0 94.0 94.0 98.0 94.0 93.0 95.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:56.50 stddev:1.34 (2.4%) stddev-sans-first:1.39 19:23:42 INFO - PID 4113 | Values: 56.0 57.0 56.0 56.0 56.0 56.0 59.0 55.0 59.0 57.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#9] json-stringify-tinderbox Cycles:10 Average:54.30 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.50 19:23:42 INFO - PID 4113 | Values: 54.0 54.0 54.0 54.0 54.0 54.0 55.0 55.0 55.0 54.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:82.00 stddev:3.23 (3.9%) stddev-sans-first:3.43 19:23:42 INFO - PID 4113 | Values: 83.0 82.0 89.0 89.0 81.0 81.0 81.0 82.0 81.0 81.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:132.00 stddev:14.09 (10.7%) stddev-sans-first:14.72 19:23:42 INFO - PID 4113 | Values: 127.0 136.0 136.0 145.0 128.0 126.0 123.0 124.0 126.0 169.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.30 Median:173.00 stddev:5.46 (3.2%) stddev-sans-first:2.73 19:23:42 INFO - PID 4113 | Values: 188.0 173.0 170.0 173.0 174.0 179.0 170.0 171.0 172.0 173.0 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.79 (2.6%) stddev-sans-first:1.56 19:23:42 INFO - PID 4113 | Values: 72.0 68.0 69.0 72.0 69.0 67.0 69.0 70.0 68.0 67.0 19:23:42 INFO - PID 4113 | -------- Summary: end -------- 19:23:42 INFO - PID 4113 | 19:23:42 INFO - PID 4113 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:23:42 INFO - TEST-INFO | 4113: exit 0 19:23:42 INFO - TEST-OK | kraken | took 62350ms 19:23:42 INFO - SUITE-END | took 1179s 19:23:43 INFO - Completed test suite (00:19:39) 19:23:43 INFO - javascript benchmark 19:23:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8427.776974702127, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3135.2945587242875, 3175.3649270145947, 3175.444297247981, 3192.55304257404, 3198.7920785148963, 6191.071339299102, 6202.78290259841, 6204.689765511717, 6211.720472578733, 6284.245890493135, 6212.254529456461, 6237.18916540849, 6303.401176888188, 6336.588121772082, 6349.619022858632, 5672.5178359839465, 5760.0783874580075, 5763.538916886639, 5772.682502462373, 5794.043982742864, 5690.687012214326, 5762.855928601797, 5772.769089236425, 5813.360530341659, 5860.472557469827, 5605.299337582802, 5674.290713660793, 5676.772929082832, 5688.715564221793, 5694.943050569504, 5643.774249030034, 5688.544916406678, 5692.743826527816, 5747.626404283708, 5751.281089863767, 5668.348139963916, 5685.658860468385, 5738.139279108125, 5747.281589801275, 5761.942380576224, 6136.9079463808075, 6184.721687524072, 6279.089532017878, 6287.434130928238, 6290.528210384194, 6513.869722605568, 6657.1012913257055, 6693.2302785179845, 6747.865042699168, 6759.628220447873, 6654.434373078294, 6753.864922701568, 6758.290379510179, 6759.695813688396, 6786.423154031913, 6174.96912515439, 6283.59156654816, 6291.842703932392, 6295.685215739195, 6325.715342809584, 6880.174379074543, 6961.443084553325, 6969.54697944632, 7001.089858318386, 7005.404540614105, 6423.967880160662, 6518.706658200393, 6538.1827271591055, 6565.080888675589, 6682.031105489774, 6642.070110184578, 6646.0, 6648.301928297556, 6649.035889795952, 6676.26561078281, 6530.869382612321, 6608.735650574019, 6616.768413105518, 6633.635150066696, 6660.533762636569, 6630.9668451657435, 6664.733410663616, 6668.399844014062, 6673.933260667428, 6674.232463266787, 5414.3502779665905, 5430.13117901134, 5465.152901300305, 5486.177073439016, 5486.286782718221, 4560.452745670541, 4622.14490319292, 4696.342512048316, 4705.835295764632, 4707.7175369477945, 5745.109508026263, 5772.7113644317615, 5815.534757219412, 5862.0, 5867.73595188209, 6634.734610615618, 6648.66756662165, 6757.189137303555, 6762.391384775394, 6787.932120678829, 5878.735456904449, 5900.262467191601, 5907.3797251288, 5908.675022873783, 5941.22764040672, 5934.792282270186, 5943.702814859326, 6017.06735456005, 6030.33666296707, 6046.3651316612, 6381.585196962183, 6501.317361677145, 6504.5121615879, 6586.571872828251, 6590.439812615886, 6714.899276510954, 6785.491088168308, 6799.286074962256, 6804.727810887509, 6805.319468053155, 6433.967830160819, 6475.676216189266, 6495.772647957298, 6522.934770652233, 6526.0, 6588.7364505421165, 6600.669966501559, 6633.834154146185, 6644.169478815148, 6650.534462587572, 6728.73085076611, 6733.3939945405145, 6841.315868413318, 6845.315468453115, 6847.452203823782, 6314.6842657866, 6424.389682980296, 6460.515461340512, 6508.056331831953, 6514.1531600891585, 6517.055027021151, 6534.281229064704, 6536.248331441755, 6540.182477190351, 6555.6066636002, 6407.231132264158, 6465.612063276219, 6503.6097834130105, 6503.869922601616, 6506.41442270198, 6505.902411463737, 6517.837054073686, 6580.901286480611, 6610.867782644224, 6630.270670226368, 6212.627242365473, 6292.685365731787, 6309.7160627770945, 6356.936430635635, 6394.4564712], "value": 6120.860212375681, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [836.7531578184435, 848.4909054567256, 871.5293741379654, 895.6909866096198, 898.0211569389364, 41257.38113928294, 41774.7911260444, 41793.0, 41794.582054179446, 42001.57998420015, 37361.06597335061, 37605.247895042085, 37656.43515347265, 37688.43467347985, 37886.43170352447, 6992.790216293507, 7098.290170982912, 7108.502404831664, 7109.786706398791, 7113.466490013244, 6897.41371983382, 6975.5814651120845, 7023.719051237945, 7027.683754231046, 7029.086218791575, 6988.126484189476, 7819.178986206452, 7944.841103177933, 7968.043834739839, 7979.441439099265, 7825.960870195641, 7883.802904927394, 7904.644291006918, 7908.406869484812, 7912.881306780403, 7717.189695082048, 7783.182765809565, 7833.216678332178, 7844.37245020397, 7853.0, 38571.22857542834, 38629.806850965564, 38802.61197388046, 38811.029724256834, 38860.417093743614, 3479.3911065563425, 3533.5406397168463, 3539.8407071681836, 3540.150363912642, 3547.024568243728, 31243.531347029813, 31321.216969575715, 31479.0, 31498.527522087188, 31528.842355788074, 4777.28340748887, 4799.784009719571, 4811.13399588074, 4832.226843704991, 4906.705597664151, 184007.0, 184659.99999999866, 184702.0, 184783.0, 185388.0, 49010.75494622504, 49013.26480102801, 49048.75475622599, 49118.26322605164, 49520.50479495159, 51371.22943155855, 51547.22679159816, 51560.48439515557, 51581.48418515842, 51680.483195168315, 16903.0, 17037.318507259817, 17054.232559534852, 17095.401660941807, 17097.743533847002, 13741.450341986409, 13945.093568917988, 13945.721085578232, 13954.302284885714, 13960.0, 14499.85500144985, 14537.055091419024, 14546.41814327415, 14594.927025364805, 14798.260086995608, 5669.34802497718, 5837.503812176013, 5931.665375290525, 6030.065339872327, 6080.847978800565, 975.9853602195973, 983.2478154212033, 988.3378136648463, 1010.9494525273707, 1011.8178727829062, 21757.891210543847, 22241.27788136515, 22611.321660350215, 22991.885040575024, 23159.53680926372, 1051.169576034924, 1053.6786280184465, 1063.8085144674033, 1078.5470102556944, 1092.9125669946545, 11903.464344104363, 12001.219920705125, 12092.214006089753, 12131.636050918487, 12175.269483830998, 219284.0, 219973.0, 221289.0, 221579.0, 221598.0, 53137.0, 53144.0, 56466.0, 57852.421475785544, 58004.0, 1454.142056186855, 1465.274689028916, 1472.801171841809, 1477.95989815406, 1491.5376233367474, 26053.609195862457, 26759.19722408331, 26838.59742103831, 27129.593056104568, 27248.863755681094, 37673.0, 37794.81102594469, 38106.23787524289, 38128.42807357836, 38145.0, 191247.0, 191312.0, 191729.0, 191837.0, 193268.0, 34567.0, 34749.30501389907, 34934.47598285977, 37065.62934370622, 37984.0, 45428.54571454243, 46727.0, 47228.29157562708, 47349.52650473451, 47705.761471192425, 31299.53050704287, 32198.0, 32235.516467252542, 32636.347273054882, 32824.83587582047, 30513.694863051085, 30655.846720766254, 32489.67510324961, 32506.83746581252, 32523.837380812944, 37359.25281494409, 37430.251394972496, 37549.62450375461, 37612.435813462274, 38532.0, 32990.83504582462, 33388.499172512915, 33389.33221335511, 33741.0, 33833.323333533684, 22852.885735571217, 23732.762672373745, 23918.64122038136, 24222.63666045046, 25090.37274068163], "value": 17258.84500132495, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.7160105747655, 579.5913880714097, 579.9072148456245, 592.6325678079592, 597.4055814464607, 22487.55024899503, 23312.06751729929, 23315.300540983797, 23326.0, 23361.766382336173, 21580.244691435804, 21943.341699749035, 22024.559508809813, 22024.559508809853, 22047.448813779665, 5981.401859814027, 6405.7757978470645, 6545.116409284757, 6546.836329091787, 6548.27968923418, 6515.7719479818215, 6520.836979075513, 6563.803085907431, 6567.277599464055, 6567.408933196011, 6510.739570417178, 6527.543071984963, 6530.2163740351225, 6530.412262896339, 6532.08550802888, 6518.804435866931, 6535.379138981789, 6536.575122617015, 6539.313372095909, 6540.6729663516635, 6053.606515576474, 6061.545384096166, 6080.270367555921, 6081.665508397036, 6082.635041897501, 27969.0, 28184.85907570449, 28823.279418014507, 28840.855795720887, 28894.71105288941, 1913.0721600023978, 1951.9121639526256, 1957.275807951053, 1958.4810025343295, 1961.725358449804, 17999.820001799944, 18412.63174736511, 18530.629387412177, 18536.165872535767, 18575.72136417955, 2215.9778402215934, 2250.8762018089, 2260.3332017054845, 2271.738750043733, 2395.077895010416, 124672.37663811713, 129460.0, 130191.0, 130262.34868825595, 130480.34759826235, 14163.362648680833, 14578.125312481285, 14805.333759980827, 14852.777208341884, 15203.467878624195, 22395.32814015582, 22518.887405563197, 22535.52109267979, 22604.660930086062, 22626.773732262795, 10069.194464442828, 10167.949160254151, 10261.230407719451, 10302.433366164783, 10335.121514671187, 8704.434211776213, 9085.36402451822, 9481.4785186814, 9665.71002869915, 9674.08096230857, 9005.729828105168, 9264.629414823332, 9267.953660231655, 9323.813523729488, 9330.533473326306, 2617.437250991046, 2689.9596506052426, 2774.279946433184, 2775.264554892955, 2852.1015879997735, 922.3174850610634, 931.2270815223349, 951.8096380723883, 966.4974213408988, 968.3512046928574, 18673.439796806117, 19101.567382446374, 19179.520511987314, 19221.121848361632, 19430.8056919429, 1008.7730260691286, 1009.9596016159418, 1013.9265202660577, 1037.486444210121, 1038.4236748604596, 10551.894481055244, 10702.0, 10968.23222374442, 12558.74882502345, 13517.729645407233, 472149.0, 481770.0, 482620.0, 483109.0, 486718.0, 110892.0, 113637.43181284206, 115367.42316288364, 118641.40679296548, 120233.39883300703, 1242.9378531073592, 1262.059765474727, 1287.8261434706383, 1292.4184117147133, 1292.8448586169532, 19486.902565487082, 20672.069756861278, 20704.689429658265, 20950.790492094882, 21319.0, 53663.463365365846, 54651.72674136763, 55395.0, 56293.71853140872, 56438.153427699435, 68176.318236817, 68276.317236827, 68498.0, 68926.65536672284, 69455.65272173607, 12472.87527124717, 14606.269686515845, 15862.206889655701, 17684.64630707353, 17769.111544422467, 24092.759072409754, 24403.145889893767, 24602.138925137533, 25205.495890082464, 25460.363490912874, 18414.44756657249, 18950.52623684419, 19045.333413330467, 19136.617267654292, 19146.7127993083, 15649.652720891007, 15770.526884193492, 16766.32934682599, 16851.494455166365, 17222.741658874875, 18925.716114258023, 19379.903100484407, 19486.610267794847, 19617.0, 19675.508112297306, 21209.257675981265, 21459.89270053702, 21484.14063437445, 21514.354569362946, 21543.46141346479, 18562.164702588147, 18707.0, 19097.045147742836, 19410.8058919409, 19445.0], "value": 12079.939462848197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.5844188301626, 1375.5735721926203, 1388.763910135277, 1392.9164250144988, 1394.4840409048654, 13751.862481375196, 14196.574102776907, 14200.07699499533, 14251.073680210779, 14259.3583288752, 13844.515441959533, 14302.427902883897, 14783.704325913475, 14800.185989770558, 14815.481458148966, 2554.3103362092224, 2592.4685439484956, 2608.6478325426015, 2609.712931577525, 2619.240420278117, 2437.85372877627, 2607.34816295926, 2625.8424494530295, 2670.492606404777, 2673.5989601559822, 2724.101046654609, 2740.4245108527234, 2746.9038583649576, 2747.244507760372, 2750.339918419575, 2639.907603233887, 2642.51113543994, 2656.561667324889, 2660.334916270942, 2666.520026395248, 2592.611108333765, 2596.182202606173, 2601.791856651463, 2603.8437693738247, 2604.7134815170316, 13836.446542138403, 14036.087654302437, 14044.92977535116, 14063.36714847824, 14114.50599229022, 7275.9636201819185, 7497.1753107158165, 7553.69785208591, 7582.658780354897, 7586.62066896653, 4874.780634871439, 4945.184044632631, 5040.656094915882, 5108.591312694975, 5119.948800511985, 1815.700409432442, 1820.4629634257926, 1843.75109360236, 1869.2242719271485, 1880.4546681462496, 22291.331260062223, 22398.88800556003, 22713.20503782376, 22732.090716371324, 22741.317760467213, 6123.295820980555, 6253.781117660951, 6305.4009869062365, 6441.710123044474, 6476.0, 15191.696166076617, 15675.05949643025, 15715.607109822346, 15815.762763558556, 15818.05091694502, 7346.5592064476305, 7460.216677248919, 7504.737334193277, 7566.470347075762, 7571.3942884569105, 6821.1814582250445, 6964.651767411609, 6977.546459480118, 7110.146782386147, 7162.641867906688, 4930.7288099154175, 5018.924716129261, 5025.949740502549, 5045.243213517928, 5089.643724939218, 1436.9510257512075, 1438.518096437684, 1461.2474575593578, 1463.8088966054104, 1465.3259500629617, 830.543201239316, 846.8094678697244, 846.8137009857821, 864.446754077391, 880.3221519430003, 6409.743610255631, 6640.601563906181, 6786.0, 6801.421879140328, 7752.26353496417, 866.445474896067, 898.85168947123, 948.5439849830477, 954.7231302922074, 988.5699720621552, 4319.460067491564, 4562.292844609091, 4571.588557029883, 4689.812407503729, 4701.78841952113, 17918.820811791975, 18003.72994405085, 18456.07719614014, 18471.90764046171, 18503.629927401376, 17731.822681773276, 17931.820681793015, 18094.547636308933, 18293.26826926908, 18389.90805045966, 10758.892411075945, 10814.729631759268, 10939.343639381663, 10940.12479001678, 10945.343279403101, 6737.966310168418, 7282.089738782653, 7311.305425984523, 7357.337839594248, 7377.4098072155175, 14770.48303309379, 16411.784409104617, 16705.91647041757, 16887.0, 17000.829991699924, 16368.75446868274, 16549.58626034359, 16735.163241837618, 16918.0, 17060.91469542694, 11116.722081948015, 11318.773624527628, 11505.252158609663, 11543.88456115428, 11583.247088939192, 12477.875221248034, 12574.622761317176, 12647.0, 12650.493980241055, 12653.746925061263, 10051.547680354255, 10371.118454931415, 10414.062734353945, 10480.737981550523, 10703.732406689584, 9409.717708468757, 9724.9027509724, 9927.950360248153, 10070.0, 10250.948745256226, 8907.0, 8968.641254349754, 8981.550922453982, 9013.504257265913, 9015.774605634911, 6694.297098804555, 6729.192496900403, 6742.6291553965, 6751.122354093936, 6789.0, 6444.130042444398, 6603.273639899703, 6639.3692599203, 6810.897836532555, 6917.446604271546], "value": 6191.597454369134, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.7456808841719, 502.21212483558514, 525.060142345202, 525.4902744337991, 533.5864704853738, 6232.626042437451, 6279.18370611821, 6286.6228026318395, 6300.810975670725, 6341.270753863307, 5926.170336152942, 6097.420745029226, 6183.59806612571, 6185.0, 6217.782377616784, 4881.243407271861, 4916.336294600237, 4930.383702037246, 4938.283948827418, 4960.429550601672, 4905.533974272456, 4931.358923339961, 4998.325226094467, 4999.175136102539, 5010.073136469747, 4761.452432970201, 4897.534734200245, 4898.1345912641145, 4929.704217746928, 4930.422264875224, 4928.236123400879, 4938.555530002299, 4968.950310496921, 4994.175960966436, 5038.697678139323, 4438.800253988578, 4446.333050042487, 4450.577195166454, 4452.755098469583, 4494.325851122325, 6025.07783724893, 6061.848453788646, 6070.2412198475195, 6126.0, 6144.170536977495, 5699.373068962411, 5712.22884910536, 5726.570507211975, 5745.477448476157, 5792.131180322943, 6484.610923344567, 6505.41451269388, 6554.606723596552, 6598.769043083469, 6607.966960165217, 4848.418189817209, 4873.853784386474, 4886.267059941038, 4892.706437613754, 4903.730294833783, 6774.220964589086, 6943.51395402322, 6946.4225923648355, 6981.476389270774, 6989.89515157273, 5007.94674183583, 5009.44896061433, 5013.323201367842, 5026.346574945233, 5052.873678158002, 5020.447750747415, 5048.29323894655, 5054.346468104201, 5097.93533618885, 5101.0818052750865, 5682.914756278659, 5714.342850572154, 5742.971285143548, 5761.567882408836, 5772.134179872969, 6405.220919657425, 6432.2281326241155, 6440.291567927524, 6453.386928241809, 6611.140551728245, 1672.4397326895626, 1699.2947926610318, 1710.7947046354268, 1731.6277000444966, 1746.1443892492841, 1199.7540504196618, 1205.5539450403407, 1209.6552482542434, 1237.3813093453273, 1237.6287113865803, 5693.971530142323, 5772.134179872969, 5807.332156801937, 5818.127280907897, 5819.679917604488, 6554.901676474857, 6663.533552651268, 6718.899216511756, 6725.5363487803825, 6754.425873800684, 5799.217105690762, 5808.3901190375245, 5831.737571809279, 5874.443272532782, 5887.438099949554, 3543.344481270973, 3606.6393360663724, 3638.9090272742865, 3651.7626354286886, 3667.661303624158, 6559.639219842859, 6562.179727534059, 6585.80242592723, 6630.270670226271, 6651.800445986628, 6943.51395402317, 6953.165620125617, 6963.79108626742, 7016.719331226694, 7026.0, 6503.869922601616, 6553.63954982471, 6556.18047744032, 6570.5400621956005, 6667.233268174125, 6625.668716564249, 6665.633390163393, 6764.594124352555, 6785.287544807921, 6838.5213035089055, 6691.799246022627, 6729.913726744529, 6730.226024007302, 6731.0, 6754.5609535380045, 6480.25477070143, 6492.285848556749, 6544.8363790905605, 6549.639769812516, 6584.8353791155605, 4963.181075122564, 4993.675411098267, 4995.750212489288, 5026.070177017264, 5061.39263288393, 6508.739650413931, 6716.899246511404, 6779.322067793181, 6832.316768323128, 6841.863162736817, 6352.396522330371, 6416.294207637249, 6422.527326979053, 6446.645434501147, 6602.867942641216, 6490.870182596416, 6524.792913311053, 6558.475321974326, 6558.704858281293, 6567.376099270562, 6304.842378940563, 6346.746130154742, 6386.616802991835, 6454.644994525158, 6470.579412338183, 6472.0, 6548.410643042149, 6613.016095171333, 6647.135872336566, 6655.667216639052, 6336.257529179513, 6352.491800655844, 6397.976323788172, 6401.199850018747, 6447.6131432114225], "value": 5060.842017066557, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [866.2982983783132, 885.2740752582885, 894.7718331825383, 902.6569903436693, 906.0667512462162, 17495.212715427806, 18155.909220453912, 18159.90920045401, 18162.818371816276, 18171.364002259925, 16859.0, 16880.915595422004, 17454.825451745477, 19923.0, 21273.68089478659, 1033.5090831854884, 1060.083028180626, 1060.363781730963, 1060.697701155171, 1060.9628662996797, 1061.909737672295, 1064.2656566968806, 1064.4518073192273, 1065.1532032034552, 1065.1798115451097, 1038.3562191441317, 1088.1349327284788, 1091.3670071358592, 1115.6931843742955, 1125.8142406502918, 1073.2165519171049, 1073.2809017957986, 1073.8550295710097, 1073.8765042020189, 1082.123479981214, 787.5825812319423, 790.6600161930368, 793.4406243598249, 795.9164287749819, 796.9402294827911, 18669.53326166843, 19452.416427507196, 19479.0, 19501.707474387895, 19518.707219391577, 19965.301214457424, 20400.0, 20582.79417205824, 20924.895375523025, 20927.68608470874, 15044.924775376163, 15379.307931143014, 15502.379904803794, 15508.224588770516, 15512.844871551253, 3385.3229354129026, 3428.3314753623054, 3431.022158684788, 3437.5015622734813, 3437.5874895012757, 115676.0, 119336.0, 119903.0, 124371.0, 124954.0, 15177.6205594861, 15744.212789360487, 15747.68504629901, 15762.76355854663, 15780.60548486297, 27423.86288068547, 29352.559711604343, 29535.85232073869, 29536.704632953824, 29727.0, 8851.247643950208, 8906.109389061172, 8939.865902011476, 8942.776430589287, 8960.910390896139, 3977.840886364571, 4110.568390319033, 4118.032262418329, 4125.958740412558, 4139.254934111888, 4951.579115775199, 5057.898842023139, 5075.0357432087785, 5076.466970968069, 5098.923516147261, 1030.907218350352, 1063.4842101580728, 1072.5495291977388, 1080.0333701337258, 1080.3517889266377, 621.5431657731564, 642.6722371590522, 668.5654324688991, 668.9732410703615, 677.8508728079814, 10637.946810266054, 11293.887061129448, 11857.407129643656, 12022.699432514257, 12623.936880315723, 633.8288662061218, 635.5614625908108, 639.0861068671751, 652.5171373183905, 673.0778832998725, 5307.893842123136, 5383.192521121863, 5465.836024919259, 5475.616706830484, 5665.575081868877, 104117.0, 104580.0, 105461.47269263757, 106046.0, 106089.0, 85610.57194713986, 88366.55816720876, 90876.54561727149, 90883.72790680153, 94808.52595737115, 75702.62148689332, 78617.21382786213, 78839.0, 81348.99999999882, 82273.0, 10428.895711042793, 10694.786104278026, 11346.546138154381, 11959.162858599815, 12396.690082747642, 33855.66144338525, 35333.29333413266, 35413.521053526005, 35750.4637430444, 37304.0, 65065.34934650782, 65790.34209657842, 66871.33128668845, 67088.32911671016, 68435.65782171057, 18923.90538047301, 19191.424257272305, 19222.231110755973, 19234.326798561982, 19502.609947801247, 18771.530711732317, 19033.238670453025, 19233.0, 21417.678734818677, 22013.009414576703, 16484.752728708838, 18553.53616159607, 19091.236350545823, 19176.904115479334, 19612.803871961667, 19113.23547058102, 19122.617547649246, 19262.8073719261, 19386.4184074478, 19422.0, 18200.635987280446, 18445.077746112907, 18732.906335468237, 18733.90633046826, 18945.810541894403, 13219.867801321863, 13235.073544851768, 13563.236779218638, 13713.520026799015, 14083.225422601856, 13676.589702308947, 13864.514741983981, 14321.928390357982, 14428.42286308536, 14651.340689668777], "value": 8960.587117205458, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1536.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 108.0, 103.0, 111.0, 109.0, 109.0, 108.0, 100.0, 110.0, 114.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 144.0, 193.0, 125.0, 145.0, 159.0, 119.0, 171.0, 119.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 163.0, 162.0, 164.0, 171.0, 165.0, 163.0, 162.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 80.0, 75.0, 173.0, 81.0, 75.0, 75.0, 76.0, 150.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 136.0, 79.0, 79.0, 79.0, 132.0, 79.0, 79.0, 126.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 114.0, 115.0, 117.0, 114.0, 118.0, 114.0, 114.0], "value": 114.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 150.0, 151.0, 151.0, 150.0, 151.0, 150.0, 150.0, 151.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 98.0, 94.0, 93.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 56.0, 56.0, 56.0, 59.0, 55.0, 59.0, 57.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 54.0, 54.0, 54.0, 55.0, 55.0, 55.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 89.0, 89.0, 81.0, 81.0, 81.0, 82.0, 81.0, 81.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 136.0, 136.0, 145.0, 128.0, 126.0, 123.0, 124.0, 126.0, 169.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 173.0, 170.0, 173.0, 174.0, 179.0, 170.0, 171.0, 172.0, 173.0], "value": 174.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 69.0, 72.0, 69.0, 67.0, 69.0, 70.0, 68.0, 67.0], "value": 69.1, "unit": "score"}]}]} 19:23:43 INFO - Return code: 0 19:23:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:23:43 INFO - # TBPL SUCCESS # 19:23:43 INFO - Running post-action listener: _package_coverage_data 19:23:43 INFO - Running post-action listener: _resource_record_post_action 19:23:43 INFO - [mozharness: 2017-07-28 02:23:43.312605Z] Finished run-tests step (success) 19:23:43 INFO - Running post-run listener: _resource_record_post_run 19:23:43 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 5861376; Write bytes: 438108160; Read time: 3248; Write time: 529308 19:23:43 INFO - TinderboxPrint: CPU usage
13.1% 19:23:43 INFO - TinderboxPrint: I/O read bytes / time
5,861,376 / 3,248 19:23:43 INFO - TinderboxPrint: I/O write bytes / time
438,108,160 / 529,308 19:23:43 INFO - TinderboxPrint: CPU idle
8,297.3 (86.9%) 19:23:43 INFO - TinderboxPrint: CPU user
1,199.6 (12.6%) 19:23:43 INFO - TinderboxPrint: Swap in / out
0 / 0 19:23:43 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 115765248; Read time: 220; Write time: 124800 19:23:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:23:43 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 3624960; Write bytes: 322342912; Read time: 2780; Write time: 404508 19:23:43 INFO - Running post-run listener: _upload_blobber_files 19:23:43 INFO - Blob upload gear active. 19:23:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:23:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:23:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:23:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:23:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:23:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:45 INFO - (blobuploader) - INFO - Done attempting. 19:23:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:23:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:23:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:46 INFO - (blobuploader) - INFO - Done attempting. 19:23:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:23:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:23:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:50 INFO - (blobuploader) - INFO - Done attempting. 19:23:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:23:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:23:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:51 INFO - (blobuploader) - INFO - Done attempting. 19:23:51 INFO - (blobuploader) - INFO - Iteration through files over. 19:23:51 INFO - Return code: 0 19:23:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:23:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:23:51 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/104e7ecae9de91d7ad599695095b9c7570e6257b938b681a211c9cb8abc915d111bdbede4a6dec9df0eaa6512098c3e6f2b4438401b0b4c685b3a35b25f51024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c03f69d8abc9dc6106e927988168bf32247c812c6db54ab70a484a97b4b644b102fcf099a643a9169e350f17df5c0d460bfa9e08d5cf31a6d55f9c68bba39ef", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/93bc9c49518d6137031a1a33e0219831a716bd19a74d244fb4431b8fae36529ec1c68682e76266bfacc073f47bce1c7281247ff72b35965f24c50fdd76891a74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e5b6b61a951b3bffab47e02ff4617acb429e41ad914ac072e02d6c868239452db299e12cffcdfd886e62f53628a870d2a928b966caf3de403b62ce10e1eea4e"} 19:23:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:23:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:23:51 INFO - Contents: 19:23:51 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/104e7ecae9de91d7ad599695095b9c7570e6257b938b681a211c9cb8abc915d111bdbede4a6dec9df0eaa6512098c3e6f2b4438401b0b4c685b3a35b25f51024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c03f69d8abc9dc6106e927988168bf32247c812c6db54ab70a484a97b4b644b102fcf099a643a9169e350f17df5c0d460bfa9e08d5cf31a6d55f9c68bba39ef", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/93bc9c49518d6137031a1a33e0219831a716bd19a74d244fb4431b8fae36529ec1c68682e76266bfacc073f47bce1c7281247ff72b35965f24c50fdd76891a74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e5b6b61a951b3bffab47e02ff4617acb429e41ad914ac072e02d6c868239452db299e12cffcdfd886e62f53628a870d2a928b966caf3de403b62ce10e1eea4e"} 19:23:51 INFO - Running post-run listener: copy_logs_to_upload_dir 19:23:51 INFO - Copying logs to upload dir... 19:23:51 INFO - mkdir: /builds/slave/test/build/upload/logs 19:23:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.518084 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-07-27 19:23:50.885709) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 19:23:50.889247) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/104e7ecae9de91d7ad599695095b9c7570e6257b938b681a211c9cb8abc915d111bdbede4a6dec9df0eaa6512098c3e6f2b4438401b0b4c685b3a35b25f51024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c03f69d8abc9dc6106e927988168bf32247c812c6db54ab70a484a97b4b644b102fcf099a643a9169e350f17df5c0d460bfa9e08d5cf31a6d55f9c68bba39ef", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/93bc9c49518d6137031a1a33e0219831a716bd19a74d244fb4431b8fae36529ec1c68682e76266bfacc073f47bce1c7281247ff72b35965f24c50fdd76891a74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e5b6b61a951b3bffab47e02ff4617acb429e41ad914ac072e02d6c868239452db299e12cffcdfd886e62f53628a870d2a928b966caf3de403b62ce10e1eea4e"} build_url:https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://queue.taskcluster.net/v1/task/clJckGzdS1qkQbdfhk8DRA/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/104e7ecae9de91d7ad599695095b9c7570e6257b938b681a211c9cb8abc915d111bdbede4a6dec9df0eaa6512098c3e6f2b4438401b0b4c685b3a35b25f51024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c03f69d8abc9dc6106e927988168bf32247c812c6db54ab70a484a97b4b644b102fcf099a643a9169e350f17df5c0d460bfa9e08d5cf31a6d55f9c68bba39ef", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/93bc9c49518d6137031a1a33e0219831a716bd19a74d244fb4431b8fae36529ec1c68682e76266bfacc073f47bce1c7281247ff72b35965f24c50fdd76891a74", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1e5b6b61a951b3bffab47e02ff4617acb429e41ad914ac072e02d6c868239452db299e12cffcdfd886e62f53628a870d2a928b966caf3de403b62ce10e1eea4e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-07-27 19:23:50.913726) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 19:23:50.914086) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1501201228.537240-862313255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004498 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-07-27 19:23:50.995917) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 19:23:50.996226) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-07-27 19:23:50.996525) ========= ========= Total master_lag: 0.17 =========