builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-067 starttime: 1496972504.14 results: success (0) revision: b42d50cafb154e3e50fdde3ca853635a000cb219 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:44.143367) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:44.143849) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:44.144145) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:44.194393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:44.194776) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-08 18:41:44-- 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% 120M=0s 2017-06-08 18:41:44 (120 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155582 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:44.393428) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:44.393741) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.062430 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:44.468195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 18:41:44.468542) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b42d50cafb154e3e50fdde3ca853635a000cb219 --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 b42d50cafb154e3e50fdde3ca853635a000cb219 --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=e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-08 18:41:44,697 truncating revision to first 12 chars 2017-06-08 18:41:44,697 Setting DEBUG logging. 2017-06-08 18:41:44,697 attempt 1/10 2017-06-08 18:41:44,697 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b42d50cafb15?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-08 18:41:45,592 unpacking tar archive at: mozilla-central-b42d50cafb15/testing/mozharness/ program finished with exit code 0 elapsedTime=1.207942 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 18:41:45.689089) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:45.689449) ========= script_repo_revision: b42d50cafb154e3e50fdde3ca853635a000cb219 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:45.689872) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:45.690192) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 18:41:45.700468) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 58 secs) (at 2017-06-08 18:41:45.700817) ========= /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=e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:41:46 INFO - MultiFileLogger online at 20170608 18:41:46 in /builds/slave/test 18:41:46 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 18:41:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:41:46 INFO - {'append_to_log': False, 18:41:46 INFO - 'base_work_dir': '/builds/slave/test', 18:41:46 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:41:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:41:46 INFO - 'branch': 'Firefox-Non-PGO', 18:41:46 INFO - 'buildbot_json_path': 'buildprops.json', 18:41:46 INFO - 'config_files': ('talos/linux_config.py',), 18:41:46 INFO - 'default_actions': ('clobber', 18:41:46 INFO - 'read-buildbot-config', 18:41:46 INFO - 'download-and-extract', 18:41:46 INFO - 'populate-webroot', 18:41:46 INFO - 'create-virtualenv', 18:41:46 INFO - 'install', 18:41:46 INFO - 'setup-mitmproxy', 18:41:46 INFO - 'run-tests'), 18:41:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:41:46 INFO - 'download_minidump_stackwalk': True, 18:41:46 INFO - 'download_symbols': 'ondemand', 18:41:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:41:46 INFO - 'tooltool.py': '/tools/tooltool.py', 18:41:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:41:46 INFO - '/tools/misc-python/virtualenv.py')}, 18:41:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:41:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:41:46 INFO - 'gecko_profile': False, 18:41:46 INFO - 'gecko_profile_interval': 0, 18:41:46 INFO - 'installer_path': 'installer.exe', 18:41:46 INFO - 'log_level': 'info', 18:41:46 INFO - 'log_name': 'talos', 18:41:46 INFO - 'log_to_console': True, 18:41:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:41:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:41:46 INFO - 'opt_config_files': (), 18:41:46 INFO - 'pip_index': False, 18:41:46 INFO - 'suite': 'dromaeojs-e10s', 18:41:46 INFO - 'system_bits': '32', 18:41:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:41:46 INFO - 'title': 'talos-linux64-ix-067', 18:41:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:41:46 INFO - 'use_talos_json': True, 18:41:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:41:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:41:46 INFO - 'work_dir': 'build'} 18:41:46 INFO - [mozharness: 2017-06-09 01:41:46.150407Z] Running clobber step. 18:41:46 INFO - Running pre-action listener: _resource_record_pre_action 18:41:46 INFO - Running main action method: clobber 18:41:46 INFO - rmtree: /builds/slave/test/build 18:41:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:41:51 INFO - Running post-action listener: _resource_record_post_action 18:41:51 INFO - [mozharness: 2017-06-09 01:41:51.217737Z] Finished clobber step (success) 18:41:51 INFO - [mozharness: 2017-06-09 01:41:51.217837Z] Running read-buildbot-config step. 18:41:51 INFO - Running pre-action listener: _resource_record_pre_action 18:41:51 INFO - Running main action method: read_buildbot_config 18:41:51 INFO - Using buildbot properties: 18:41:51 INFO - { 18:41:51 INFO - "project": "", 18:41:51 INFO - "product": "firefox", 18:41:51 INFO - "who": "kwierso@gmail.com", 18:41:51 INFO - "installer_path": "public/build/target.tar.bz2", 18:41:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:41:51 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:41:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:41:51 INFO - "stage_platform": "linux64", 18:41:51 INFO - "basedir": "/builds/slave/test", 18:41:51 INFO - "buildnumber": 944, 18:41:51 INFO - "platform": "ubuntu64_hw", 18:41:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:41:51 INFO - "slavebuilddir": "test", 18:41:51 INFO - "taskId": "CkrmCrpaRKmGuPJtMzXRvg", 18:41:51 INFO - "branch": "mozilla-central", 18:41:51 INFO - "script_repo_revision": "production", 18:41:51 INFO - "revision": "b42d50cafb154e3e50fdde3ca853635a000cb219", 18:41:51 INFO - "slavename": "talos-linux64-ix-067", 18:41:51 INFO - "repo_path": "mozilla-central" 18:41:51 INFO - } 18:41:51 INFO - Finding installer, test and symbols from parent task. 18:41:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CkrmCrpaRKmGuPJtMzXRvg'}, attempt #1 18:41:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CkrmCrpaRKmGuPJtMzXRvg'}, attempt #1 18:41:52 INFO - Task dependencies: N7l3ReNzRHiM6_49kPAeYQ 18:41:52 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ'}, attempt #1 18:41:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.tar.bz2 18:41:52 INFO - Set test_url: https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/test_packages.json 18:41:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.crashreporter-symbols.zip 18:41:52 INFO - Running post-action listener: _resource_record_post_action 18:41:52 INFO - [mozharness: 2017-06-09 01:41:52.587705Z] Finished read-buildbot-config step (success) 18:41:52 INFO - [mozharness: 2017-06-09 01:41:52.587925Z] Running download-and-extract step. 18:41:52 INFO - Running pre-action listener: _resource_record_pre_action 18:41:52 INFO - Running main action method: download_and_extract 18:41:52 INFO - mkdir: /builds/slave/test/build/tests 18:41:52 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')]} 18:41:52 INFO - trying https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.test_packages.json 18:41:52 INFO - Downloading https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:41:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:41:53 INFO - Downloaded 989 bytes. 18:41:53 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:41:53 INFO - Using the following test package requirements: 18:41:53 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:41:53 INFO - u'common': [u'target.common.tests.zip'], 18:41:53 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:41:53 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:41:53 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:41:53 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:41:53 INFO - u'mozbase': [u'target.common.tests.zip'], 18:41:53 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:41:53 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:41:53 INFO - u'web-platform': [u'target.common.tests.zip', 18:41:53 INFO - u'target.web-platform.tests.tar.gz'], 18:41:53 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:41:53 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:41:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.common.tests.zip 18:41:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:41:53 INFO - Fetch https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.common.tests.zip into memory 18:41:54 INFO - Content-Length response header: 67875725 18:41:54 INFO - Bytes received: 67875725 18:42:05 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:42:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.common.tests.zip 18:42:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:42:05 INFO - Fetch https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.common.tests.zip into memory 18:42:06 INFO - Content-Length response header: 67875725 18:42:06 INFO - Bytes received: 67875725 18:42:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.talos.tests.zip 18:42:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:42:18 INFO - Fetch https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.talos.tests.zip into memory 18:42:19 INFO - Content-Length response header: 14447568 18:42:19 INFO - Bytes received: 14447568 18:42:19 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')]} 18:42:19 INFO - trying https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.tar.bz2 18:42:19 INFO - Downloading https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:42:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:42:21 INFO - Downloaded 58839065 bytes. 18:42:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.tar.bz2 18:42:21 INFO - mkdir: /builds/slave/test/properties 18:42:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:42:21 INFO - Writing to file /builds/slave/test/properties/build_url 18:42:21 INFO - Contents: 18:42:21 INFO - build_url:https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.tar.bz2 18:42:21 INFO - Running post-action listener: _resource_record_post_action 18:42:21 INFO - Running post-action listener: set_extra_try_arguments 18:42:21 INFO - [mozharness: 2017-06-09 01:42:21.022357Z] Finished download-and-extract step (success) 18:42:21 INFO - [mozharness: 2017-06-09 01:42:21.022455Z] Running populate-webroot step. 18:42:21 INFO - Running pre-action listener: _resource_record_pre_action 18:42:21 INFO - Running main action method: populate_webroot 18:42:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:42:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:42:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:42:21 INFO - '--tppagecycles', 18:42:21 INFO - '7'], 18:42:21 INFO - 'tests': ['tsvgm']}}, 18:42:21 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:42:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:42:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:42:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:42:21 INFO - 'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:42:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:42:21 INFO - 'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['damp', 'tps']}, 18:42:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:42:21 INFO - 'tests': ['damp', 'tps']}, 18:42:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['dromaeo_dom']}, 18:42:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:42:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:42:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:42:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:42:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:42:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['a11yr', 18:42:21 INFO - 'ts_paint', 18:42:21 INFO - 'tpaint', 18:42:21 INFO - 'sessionrestore', 18:42:21 INFO - 'sessionrestore_no_auto_restore', 18:42:21 INFO - 'tabpaint']}, 18:42:21 INFO - 'other-e10s': {'tests': ['a11yr', 18:42:21 INFO - 'ts_paint', 18:42:21 INFO - 'tpaint', 18:42:21 INFO - 'sessionrestore', 18:42:21 INFO - 'sessionrestore_no_auto_restore', 18:42:21 INFO - 'tabpaint']}, 18:42:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:42:21 INFO - 'ts_paint', 18:42:21 INFO - 'tpaint', 18:42:21 INFO - 'sessionrestore', 18:42:21 INFO - 'sessionrestore_no_auto_restore', 18:42:21 INFO - 'tabpaint']}, 18:42:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:42:21 INFO - 'ts_paint', 18:42:21 INFO - 'tpaint', 18:42:21 INFO - 'sessionrestore', 18:42:21 INFO - 'sessionrestore_no_auto_restore', 18:42:21 INFO - 'tabpaint']}, 18:42:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['a11yr', 18:42:21 INFO - 'ts_paint', 18:42:21 INFO - 'tpaint', 18:42:21 INFO - 'sessionrestore', 18:42:21 INFO - 'sessionrestore_no_auto_restore', 18:42:21 INFO - 'tabpaint']}, 18:42:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['a11yr', 18:42:21 INFO - 'ts_paint', 18:42:21 INFO - 'tpaint', 18:42:21 INFO - 'sessionrestore', 18:42:21 INFO - 'sessionrestore_no_auto_restore', 18:42:21 INFO - 'tabpaint']}, 18:42:21 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:42:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:42:21 INFO - 'bloom_basic_ref']}, 18:42:21 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:42:21 INFO - 'talos_options': ['--mitmproxy', 18:42:21 INFO - 'mitmproxy-recording-1.mp', 18:42:21 INFO - '--firstNonBlankPaint'], 18:42:21 INFO - 'tests': ['Quantum_1']}, 18:42:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['tsvgx', 18:42:21 INFO - 'tsvgr_opacity', 18:42:21 INFO - 'tart', 18:42:21 INFO - 'tscrollx', 18:42:21 INFO - 'cart', 18:42:21 INFO - 'tsvg_static']}, 18:42:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:42:21 INFO - 'tsvgr_opacity', 18:42:21 INFO - 'tart', 18:42:21 INFO - 'tscrollx', 18:42:21 INFO - 'cart', 18:42:21 INFO - 'tsvg_static']}, 18:42:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:42:21 INFO - 'talos_options': ['--disable-e10s'], 18:42:21 INFO - 'tests': ['tp5o']}, 18:42:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:42:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:42:21 INFO - 'talos_options': ['--disable-e10s', 18:42:21 INFO - '--xperf_path', 18:42:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:21 INFO - 'tests': ['tp5n']}, 18:42:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:42:21 INFO - 'talos_options': ['--xperf_path', 18:42:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:21 INFO - 'tests': ['tp5n']}}, 18:42:21 INFO - 'talos.zip': {'path': '', 18:42:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:42:21 INFO - Running post-action listener: _resource_record_post_action 18:42:21 INFO - [mozharness: 2017-06-09 01:42:21.036754Z] Finished populate-webroot step (success) 18:42:21 INFO - [mozharness: 2017-06-09 01:42:21.036848Z] Running create-virtualenv step. 18:42:21 INFO - Running pre-action listener: _resource_record_pre_action 18:42:21 INFO - Running main action method: create_virtualenv 18:42:21 INFO - Creating virtualenv /builds/slave/test/build/venv 18:42:21 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 18:42:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:42:21 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:42:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:42:21 INFO - Using real prefix '/usr' 18:42:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:42:21 INFO - Installing distribute.............................................................................................................................................................................................done. 18:42:23 INFO - Installing pip.................done. 18:42:23 INFO - Return code: 0 18:42:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:42:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:42:24 INFO - Reading from file tmpfile_stdout 18:42:24 INFO - Output received: 18:42:24 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) 18:42:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:42:24 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')]} 18:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:24 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')]} 18:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:24 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:24 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 18:42:24 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 18:42:24 INFO - Using env: {'DISPLAY': ':0', 18:42:24 INFO - 'HOME': '/home/cltbld', 18:42:24 INFO - 'LANG': 'en_US.UTF-8', 18:42:24 INFO - 'LANGUAGE': 'en_US:en', 18:42:24 INFO - 'LOGNAME': 'cltbld', 18:42:24 INFO - 'MAIL': '/var/mail/cltbld', 18:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:24 INFO - 'MOZ_NO_REMOTE': '1', 18:42:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:24 INFO - 'NO_EM_RESTART': '1', 18:42:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:24 INFO - 'PWD': '/builds/slave/test', 18:42:24 INFO - 'SHELL': '/bin/bash', 18:42:24 INFO - 'SHLVL': '1', 18:42:24 INFO - 'TERM': 'linux', 18:42:24 INFO - 'TMOUT': '86400', 18:42:24 INFO - 'USER': 'cltbld', 18:42:24 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:24 INFO - '_': '/tools/buildbot/bin/python'} 18:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:24 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 18:42:24 INFO - Cleaning up... 18:42:24 INFO - Return code: 0 18:42:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:42:24 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')]} 18:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:24 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')]} 18:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:24 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:24 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 18:42:24 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 18:42:24 INFO - Using env: {'DISPLAY': ':0', 18:42:24 INFO - 'HOME': '/home/cltbld', 18:42:24 INFO - 'LANG': 'en_US.UTF-8', 18:42:24 INFO - 'LANGUAGE': 'en_US:en', 18:42:24 INFO - 'LOGNAME': 'cltbld', 18:42:24 INFO - 'MAIL': '/var/mail/cltbld', 18:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:24 INFO - 'MOZ_NO_REMOTE': '1', 18:42:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:24 INFO - 'NO_EM_RESTART': '1', 18:42:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:24 INFO - 'PWD': '/builds/slave/test', 18:42:24 INFO - 'SHELL': '/bin/bash', 18:42:24 INFO - 'SHLVL': '1', 18:42:24 INFO - 'TERM': 'linux', 18:42:24 INFO - 'TMOUT': '86400', 18:42:24 INFO - 'USER': 'cltbld', 18:42:24 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:24 INFO - '_': '/tools/buildbot/bin/python'} 18:42:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:24 INFO - Downloading/unpacking psutil>=3.1.1 18:42:24 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 18:42:24 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 18:42:24 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 18:42:24 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 18:42:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:42:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:25 INFO - Installing collected packages: psutil 18:42:25 INFO - Running setup.py install for psutil 18:42:25 INFO - building 'psutil._psutil_linux' extension 18:42:25 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 18:42:26 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 18:42:26 INFO - building 'psutil._psutil_posix' extension 18:42:26 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 18:42:27 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 18:42:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:27 INFO - Successfully installed psutil 18:42:27 INFO - Cleaning up... 18:42:27 INFO - Return code: 0 18:42:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:42:27 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')]} 18:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:27 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')]} 18:42:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:27 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:27 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 18:42:27 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 18:42:27 INFO - Using env: {'DISPLAY': ':0', 18:42:27 INFO - 'HOME': '/home/cltbld', 18:42:27 INFO - 'LANG': 'en_US.UTF-8', 18:42:27 INFO - 'LANGUAGE': 'en_US:en', 18:42:27 INFO - 'LOGNAME': 'cltbld', 18:42:27 INFO - 'MAIL': '/var/mail/cltbld', 18:42:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:27 INFO - 'MOZ_NO_REMOTE': '1', 18:42:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:27 INFO - 'NO_EM_RESTART': '1', 18:42:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:27 INFO - 'PWD': '/builds/slave/test', 18:42:27 INFO - 'SHELL': '/bin/bash', 18:42:27 INFO - 'SHLVL': '1', 18:42:27 INFO - 'TERM': 'linux', 18:42:27 INFO - 'TMOUT': '86400', 18:42:27 INFO - 'USER': 'cltbld', 18:42:27 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:27 INFO - '_': '/tools/buildbot/bin/python'} 18:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:42:27 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 18:42:27 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 18:42:27 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 18:42:27 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 18:42:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:42:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:42:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:42:28 INFO - Installing collected packages: mozsystemmonitor 18:42:28 INFO - Running setup.py install for mozsystemmonitor 18:42:28 INFO - Successfully installed mozsystemmonitor 18:42:28 INFO - Cleaning up... 18:42:28 INFO - Return code: 0 18:42:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:42:28 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')]} 18:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:28 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')]} 18:42:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:28 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:28 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 18:42:28 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 18:42:28 INFO - Using env: {'DISPLAY': ':0', 18:42:28 INFO - 'HOME': '/home/cltbld', 18:42:28 INFO - 'LANG': 'en_US.UTF-8', 18:42:28 INFO - 'LANGUAGE': 'en_US:en', 18:42:28 INFO - 'LOGNAME': 'cltbld', 18:42:28 INFO - 'MAIL': '/var/mail/cltbld', 18:42:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:28 INFO - 'MOZ_NO_REMOTE': '1', 18:42:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:28 INFO - 'NO_EM_RESTART': '1', 18:42:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:28 INFO - 'PWD': '/builds/slave/test', 18:42:28 INFO - 'SHELL': '/bin/bash', 18:42:28 INFO - 'SHLVL': '1', 18:42:28 INFO - 'TERM': 'linux', 18:42:28 INFO - 'TMOUT': '86400', 18:42:28 INFO - 'USER': 'cltbld', 18:42:28 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:28 INFO - '_': '/tools/buildbot/bin/python'} 18:42:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:28 INFO - Downloading/unpacking jsonschema==2.5.1 18:42:28 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 18:42:28 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 18:42:28 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 18:42:28 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 18:42:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:42:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:42: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 18:42: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 18:42: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 18:42: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 18:42:30 INFO - Downloading functools32-3.2.3-2.tar.gz 18:42:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:42:30 INFO - warning: no files found matching '*.txt' 18:42:30 INFO - no previously-included directories found matching 'build' 18:42:30 INFO - no previously-included directories found matching 'dist' 18:42:30 INFO - no previously-included directories found matching '.git*' 18:42:30 INFO - Installing collected packages: jsonschema, functools32 18:42:30 INFO - Running setup.py install for functools32 18:42:30 INFO - warning: no files found matching '*.txt' 18:42:30 INFO - no previously-included directories found matching 'build' 18:42:30 INFO - no previously-included directories found matching 'dist' 18:42:30 INFO - no previously-included directories found matching '.git*' 18:42:30 INFO - Successfully installed jsonschema functools32 18:42:30 INFO - Cleaning up... 18:42:30 INFO - Return code: 0 18:42:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:42:30 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')]} 18:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:30 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')]} 18:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:30 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:30 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 18:42:30 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 18:42:30 INFO - Using env: {'DISPLAY': ':0', 18:42:30 INFO - 'HOME': '/home/cltbld', 18:42:30 INFO - 'LANG': 'en_US.UTF-8', 18:42:30 INFO - 'LANGUAGE': 'en_US:en', 18:42:30 INFO - 'LOGNAME': 'cltbld', 18:42:30 INFO - 'MAIL': '/var/mail/cltbld', 18:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:30 INFO - 'MOZ_NO_REMOTE': '1', 18:42:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:30 INFO - 'NO_EM_RESTART': '1', 18:42:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:30 INFO - 'PWD': '/builds/slave/test', 18:42:30 INFO - 'SHELL': '/bin/bash', 18:42:30 INFO - 'SHLVL': '1', 18:42:30 INFO - 'TERM': 'linux', 18:42:30 INFO - 'TMOUT': '86400', 18:42:30 INFO - 'USER': 'cltbld', 18:42:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:30 INFO - '_': '/tools/buildbot/bin/python'} 18:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:42:30 INFO - Cleaning up... 18:42:30 INFO - Return code: 0 18:42:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:42:30 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')]} 18:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:30 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')]} 18:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:30 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:30 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 18:42:30 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 18:42:30 INFO - Using env: {'DISPLAY': ':0', 18:42:30 INFO - 'HOME': '/home/cltbld', 18:42:30 INFO - 'LANG': 'en_US.UTF-8', 18:42:30 INFO - 'LANGUAGE': 'en_US:en', 18:42:30 INFO - 'LOGNAME': 'cltbld', 18:42:30 INFO - 'MAIL': '/var/mail/cltbld', 18:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:30 INFO - 'MOZ_NO_REMOTE': '1', 18:42:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:30 INFO - 'NO_EM_RESTART': '1', 18:42:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:30 INFO - 'PWD': '/builds/slave/test', 18:42:30 INFO - 'SHELL': '/bin/bash', 18:42:30 INFO - 'SHLVL': '1', 18:42:30 INFO - 'TERM': 'linux', 18:42:30 INFO - 'TMOUT': '86400', 18:42:30 INFO - 'USER': 'cltbld', 18:42:30 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:30 INFO - '_': '/tools/buildbot/bin/python'} 18:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:30 INFO - Downloading/unpacking blobuploader==1.2.4 18:42:30 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 18:42:30 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 18:42:30 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 18:42:30 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 18:42:31 INFO - Downloading blobuploader-1.2.4.tar.gz 18:42:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:42:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:42: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 18:42: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 18:42: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 18:42: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 18:42:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:42:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:42: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 18:42: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 18:42: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 18:42: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 18:42:32 INFO - Downloading docopt-0.6.1.tar.gz 18:42:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:42:32 INFO - Installing collected packages: blobuploader, requests, docopt 18:42:32 INFO - Running setup.py install for blobuploader 18:42:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:42:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:42:32 INFO - Running setup.py install for requests 18:42:32 INFO - Running setup.py install for docopt 18:42:33 INFO - Successfully installed blobuploader requests docopt 18:42:33 INFO - Cleaning up... 18:42:33 INFO - Return code: 0 18:42:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42: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')]} 18:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:33 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:33 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 18:42:33 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 18:42:33 INFO - Using env: {'DISPLAY': ':0', 18:42:33 INFO - 'HOME': '/home/cltbld', 18:42:33 INFO - 'LANG': 'en_US.UTF-8', 18:42:33 INFO - 'LANGUAGE': 'en_US:en', 18:42:33 INFO - 'LOGNAME': 'cltbld', 18:42:33 INFO - 'MAIL': '/var/mail/cltbld', 18:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:33 INFO - 'MOZ_NO_REMOTE': '1', 18:42:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:33 INFO - 'NO_EM_RESTART': '1', 18:42:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:33 INFO - 'PWD': '/builds/slave/test', 18:42:33 INFO - 'SHELL': '/bin/bash', 18:42:33 INFO - 'SHLVL': '1', 18:42:33 INFO - 'TERM': 'linux', 18:42:33 INFO - 'TMOUT': '86400', 18:42:33 INFO - 'USER': 'cltbld', 18:42:33 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:33 INFO - '_': '/tools/buildbot/bin/python'} 18:42:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:33 INFO - Running setup.py (path:/tmp/pip-T5dAuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:33 INFO - Running setup.py (path:/tmp/pip-2o8jAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:33 INFO - Running setup.py (path:/tmp/pip-Zuvq_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:33 INFO - Running setup.py (path:/tmp/pip-rY4cNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:33 INFO - Running setup.py (path:/tmp/pip-DLhufx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:33 INFO - Running setup.py (path:/tmp/pip-_RMq_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:33 INFO - Running setup.py (path:/tmp/pip-wjyR6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:33 INFO - Running setup.py (path:/tmp/pip-Tek3pY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:33 INFO - Running setup.py (path:/tmp/pip-Up3cz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:33 INFO - Running setup.py (path:/tmp/pip-4n1VgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:34 INFO - Running setup.py (path:/tmp/pip-o1FDYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:34 INFO - Running setup.py (path:/tmp/pip-nhKqBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:34 INFO - Running setup.py (path:/tmp/pip-QKN1Ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:34 INFO - Running setup.py (path:/tmp/pip-UTTP6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:34 INFO - Running setup.py (path:/tmp/pip-M4zwl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:34 INFO - Running setup.py (path:/tmp/pip-QTyTeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:34 INFO - Running setup.py (path:/tmp/pip-RklEDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:42:34 INFO - Running setup.py install for manifestparser 18:42:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:42:34 INFO - Running setup.py install for mozcrash 18:42:34 INFO - Running setup.py install for mozdebug 18:42:34 INFO - Running setup.py install for mozdevice 18:42:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:42:34 INFO - Running setup.py install for mozfile 18:42:35 INFO - Running setup.py install for mozhttpd 18:42:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Running setup.py install for mozinfo 18:42:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Running setup.py install for mozInstall 18:42:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Running setup.py install for mozleak 18:42:35 INFO - Running setup.py install for mozlog 18:42:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Running setup.py install for moznetwork 18:42:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Running setup.py install for mozprocess 18:42:35 INFO - Running setup.py install for mozprofile 18:42:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Running setup.py install for mozrunner 18:42:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:42:35 INFO - Running setup.py install for mozscreenshot 18:42:36 INFO - Running setup.py install for moztest 18:42:36 INFO - Running setup.py install for mozversion 18:42:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:42:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:42:36 INFO - Cleaning up... 18:42:36 INFO - Return code: 0 18:42:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:36 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')]} 18:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:36 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')]} 18:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:36 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:36 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 18:42:36 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 18:42:36 INFO - Using env: {'DISPLAY': ':0', 18:42:36 INFO - 'HOME': '/home/cltbld', 18:42:36 INFO - 'LANG': 'en_US.UTF-8', 18:42:36 INFO - 'LANGUAGE': 'en_US:en', 18:42:36 INFO - 'LOGNAME': 'cltbld', 18:42:36 INFO - 'MAIL': '/var/mail/cltbld', 18:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:36 INFO - 'MOZ_NO_REMOTE': '1', 18:42:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:36 INFO - 'NO_EM_RESTART': '1', 18:42:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:36 INFO - 'PWD': '/builds/slave/test', 18:42:36 INFO - 'SHELL': '/bin/bash', 18:42:36 INFO - 'SHLVL': '1', 18:42:36 INFO - 'TERM': 'linux', 18:42:36 INFO - 'TMOUT': '86400', 18:42:36 INFO - 'USER': 'cltbld', 18:42:36 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:36 INFO - '_': '/tools/buildbot/bin/python'} 18:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:36 INFO - Running setup.py (path:/tmp/pip-QvCyBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:36 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)) 18:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:36 INFO - Running setup.py (path:/tmp/pip-_Ir0EJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:36 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)) 18:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:36 INFO - Running setup.py (path:/tmp/pip-reKYd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:36 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)) 18:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:36 INFO - Running setup.py (path:/tmp/pip-j86QRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:36 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)) 18:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:36 INFO - Running setup.py (path:/tmp/pip-bXHyOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:36 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)) 18:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:36 INFO - Running setup.py (path:/tmp/pip-uVQ4Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:36 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)) 18:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:36 INFO - Running setup.py (path:/tmp/pip-9sem80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:37 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:37 INFO - Running setup.py (path:/tmp/pip-od8uTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:37 INFO - Running setup.py (path:/tmp/pip-3GpkfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:37 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:37 INFO - Running setup.py (path:/tmp/pip-ArEAjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:37 INFO - Running setup.py (path:/tmp/pip-PMk0s8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:37 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:37 INFO - Running setup.py (path:/tmp/pip-oiM5wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:37 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:37 INFO - Running setup.py (path:/tmp/pip-D1KMOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:37 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:37 INFO - Running setup.py (path:/tmp/pip-8ot95j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:37 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:37 INFO - Running setup.py (path:/tmp/pip-jkw4Df-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:37 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:37 INFO - Running setup.py (path:/tmp/pip-lMrEVM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:37 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)) 18:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:37 INFO - Running setup.py (path:/tmp/pip-P2HYQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:37 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)) 18:42:37 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)) 18:42:37 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)) 18:42:37 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)) 18:42:37 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)) 18:42:37 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)) 18:42:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:42:37 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 18:42:37 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 18:42:37 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 18:42:37 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 18:42:38 INFO - Downloading blessings-1.6.tar.gz 18:42:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:42:38 INFO - Installing collected packages: blessings 18:42:38 INFO - Running setup.py install for blessings 18:42:39 INFO - Successfully installed blessings 18:42:39 INFO - Cleaning up... 18:42:39 INFO - Return code: 0 18:42:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:42:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:39 INFO - Reading from file tmpfile_stdout 18:42:39 INFO - Current package versions: 18:42:39 INFO - argparse == 1.2.1 18:42:39 INFO - blessings == 1.6 18:42:39 INFO - blobuploader == 1.2.4 18:42:39 INFO - docopt == 0.6.1 18:42:39 INFO - functools32 == 3.2.3-2 18:42:39 INFO - jsonschema == 2.5.1 18:42:39 INFO - manifestparser == 1.1 18:42:39 INFO - mozInstall == 1.12 18:42:39 INFO - mozcrash == 1.0 18:42:39 INFO - mozdebug == 0.1 18:42:39 INFO - mozdevice == 0.51 18:42:39 INFO - mozfile == 1.2 18:42:39 INFO - mozhttpd == 0.7 18:42:39 INFO - mozinfo == 0.10 18:42:39 INFO - mozleak == 0.1 18:42:39 INFO - mozlog == 3.4 18:42:39 INFO - moznetwork == 0.27 18:42:39 INFO - mozprocess == 0.25 18:42:39 INFO - mozprofile == 0.28 18:42:39 INFO - mozrunner == 6.13 18:42:39 INFO - mozscreenshot == 0.1 18:42:39 INFO - mozsystemmonitor == 0.3 18:42:39 INFO - moztest == 0.8 18:42:39 INFO - mozversion == 1.4 18:42:39 INFO - psutil == 3.1.1 18:42:39 INFO - requests == 1.2.3 18:42:39 INFO - wsgiref == 0.1.2 18:42:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:39 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')]} 18:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:39 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')]} 18:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:39 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:39 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 18:42:39 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 18:42:39 INFO - Using env: {'DISPLAY': ':0', 18:42:39 INFO - 'HOME': '/home/cltbld', 18:42:39 INFO - 'LANG': 'en_US.UTF-8', 18:42:39 INFO - 'LANGUAGE': 'en_US:en', 18:42:39 INFO - 'LOGNAME': 'cltbld', 18:42:39 INFO - 'MAIL': '/var/mail/cltbld', 18:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:39 INFO - 'MOZ_NO_REMOTE': '1', 18:42:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:39 INFO - 'NO_EM_RESTART': '1', 18:42:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:39 INFO - 'PWD': '/builds/slave/test', 18:42:39 INFO - 'SHELL': '/bin/bash', 18:42:39 INFO - 'SHLVL': '1', 18:42:39 INFO - 'TERM': 'linux', 18:42:39 INFO - 'TMOUT': '86400', 18:42:39 INFO - 'USER': 'cltbld', 18:42:39 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:39 INFO - '_': '/tools/buildbot/bin/python'} 18:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:39 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)) 18:42:39 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)) 18:42:39 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)) 18:42:39 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)) 18:42:39 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)) 18:42:39 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)) 18:42:39 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)) 18:42:39 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)) 18:42:39 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)) 18:42:39 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:42:39 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 18:42:39 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 18:42:39 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 18:42:39 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 18:42:40 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:42:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:42:40 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)) 18:42:40 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)) 18:42:40 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)) 18:42:40 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)) 18:42:40 INFO - Installing collected packages: marionette-driver 18:42:40 INFO - Running setup.py install for marionette-driver 18:42:40 INFO - Successfully installed marionette-driver 18:42:40 INFO - Cleaning up... 18:42:40 INFO - Return code: 0 18:42:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:42:40 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')]} 18:42:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:40 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')]} 18:42:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:40 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 0x1ffa3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21d4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23789a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', '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 18:42:40 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 18:42:40 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 18:42:40 INFO - Using env: {'DISPLAY': ':0', 18:42:40 INFO - 'HOME': '/home/cltbld', 18:42:40 INFO - 'LANG': 'en_US.UTF-8', 18:42:40 INFO - 'LANGUAGE': 'en_US:en', 18:42:40 INFO - 'LOGNAME': 'cltbld', 18:42:40 INFO - 'MAIL': '/var/mail/cltbld', 18:42:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:40 INFO - 'MOZ_NO_REMOTE': '1', 18:42:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:40 INFO - 'NO_EM_RESTART': '1', 18:42:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:40 INFO - 'PWD': '/builds/slave/test', 18:42:40 INFO - 'SHELL': '/bin/bash', 18:42:40 INFO - 'SHLVL': '1', 18:42:40 INFO - 'TERM': 'linux', 18:42:40 INFO - 'TMOUT': '86400', 18:42:40 INFO - 'USER': 'cltbld', 18:42:40 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:40 INFO - '_': '/tools/buildbot/bin/python'} 18:42:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:42:41 INFO - Cleaning up... 18:42:41 INFO - Return code: 0 18:42:41 INFO - Running post-action listener: _resource_record_post_action 18:42:41 INFO - Running post-action listener: _start_resource_monitoring 18:42:41 INFO - Starting resource monitoring. 18:42:41 INFO - [mozharness: 2017-06-09 01:42:41.124149Z] Finished create-virtualenv step (success) 18:42:41 INFO - [mozharness: 2017-06-09 01:42:41.125143Z] Running install step. 18:42:41 INFO - Running pre-action listener: _resource_record_pre_action 18:42:41 INFO - Running main action method: install 18:42:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:41 INFO - Reading from file tmpfile_stdout 18:42:41 INFO - Detecting whether we're running mozinstall >=1.0... 18:42:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:42:41 INFO - Reading from file tmpfile_stdout 18:42:41 INFO - Output received: 18:42:41 INFO - Usage: mozinstall [options] installer 18:42:41 INFO - Options: 18:42:41 INFO - -h, --help show this help message and exit 18:42:41 INFO - -d DEST, --destination=DEST 18:42:41 INFO - Directory to install application into. [default: 18:42:41 INFO - "/builds/slave/test"] 18:42:41 INFO - --app=APP Application being installed. [default: firefox] 18:42:41 INFO - mkdir: /builds/slave/test/build/application 18:42:41 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'] 18:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:42:58 INFO - Reading from file tmpfile_stdout 18:42:58 INFO - Output received: 18:42:58 INFO - /builds/slave/test/build/application/firefox/firefox 18:42:58 INFO - Running post-action listener: _resource_record_post_action 18:42:58 INFO - [mozharness: 2017-06-09 01:42:58.404224Z] Finished install step (success) 18:42:58 INFO - [mozharness: 2017-06-09 01:42:58.404505Z] Running setup-mitmproxy step. 18:42:58 INFO - Running pre-action listener: _resource_record_pre_action 18:42:58 INFO - Running main action method: setup_mitmproxy 18:42:58 INFO - Skipping: mitmproxy is not required 18:42:58 INFO - Running post-action listener: _resource_record_post_action 18:42:58 INFO - [mozharness: 2017-06-09 01:42:58.405700Z] Finished setup-mitmproxy step (success) 18:42:58 INFO - [mozharness: 2017-06-09 01:42:58.405926Z] Running run-tests step. 18:42:58 INFO - Running pre-action listener: _resource_record_pre_action 18:42:58 INFO - Running main action method: run_tests 18:42:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:42:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:42:58 INFO - Python 2.7.3 18:42:58 INFO - Return code: 0 18:42:58 INFO - grabbing minidump binary from tooltool 18:42:58 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')]} 18:42:58 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 0x2376140>, '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 0x2376a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2376ed0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:42:58 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 18:42:58 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 18:42:58 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 18:42:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:42:58 INFO - Return code: 0 18:42:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:42:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:42:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:42:58 INFO - ENV: RUST_BACKTRACE is now 1 18:42:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:42:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:42:58 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/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 18:42:58 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/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 18:42:58 INFO - Using env: {'DISPLAY': ':0', 18:42:58 INFO - 'HOME': '/home/cltbld', 18:42:58 INFO - 'LANG': 'en_US.UTF-8', 18:42:58 INFO - 'LANGUAGE': 'en_US:en', 18:42:58 INFO - 'LOGNAME': 'cltbld', 18:42:58 INFO - 'MAIL': '/var/mail/cltbld', 18:42:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:42:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:58 INFO - 'MOZ_NO_REMOTE': '1', 18:42:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:42:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:42:58 INFO - 'NO_EM_RESTART': '1', 18:42:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:58 INFO - 'PWD': '/builds/slave/test', 18:42:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:42:58 INFO - 'RUST_BACKTRACE': '1', 18:42:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:42:58 INFO - 'SHELL': '/bin/bash', 18:42:58 INFO - 'SHLVL': '1', 18:42:58 INFO - 'TERM': 'linux', 18:42:58 INFO - 'TMOUT': '86400', 18:42:58 INFO - 'USER': 'cltbld', 18:42:58 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365', 18:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:58 INFO - '_': '/tools/buildbot/bin/python'} 18:42:58 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/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 18:42:58 INFO - mozversion application_buildid: 20170609011144 18:42:58 INFO - mozversion application_changeset: b42d50cafb154e3e50fdde3ca853635a000cb219 18:42:58 INFO - mozversion application_display_name: Nightly 18:42:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:42:58 INFO - mozversion application_name: Firefox 18:42:58 INFO - mozversion application_remotingname: firefox 18:42:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:42:58 INFO - mozversion application_vendor: Mozilla 18:42:58 INFO - mozversion application_version: 55.0a1 18:42:58 INFO - mozversion platform_buildid: 20170609011144 18:42:58 INFO - mozversion platform_changeset: b42d50cafb154e3e50fdde3ca853635a000cb219 18:42:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:42:58 INFO - mozversion platform_version: 55.0a1 18:42:58 INFO - using testdate: 1496972578 18:42:58 INFO - actual date: 1496972578 18:42:58 INFO - starting webserver on 'localhost:49638' 18:42:58 INFO - SUITE-START | Running 2 tests 18:42:58 INFO - TEST-START | dromaeo_css 18:42:58 INFO - operating with platform_type : linux_ 18:42:58 INFO - Initialising browser for dromaeo_css test... 18:42:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49638/getInfo.html -profile /tmp/tmptxmAi0/profile 18:42:58 INFO - TEST-INFO | started process 3364 (/builds/slave/test/build/application/firefox/firefox http://localhost:49638/getInfo.html) 18:43:34 INFO - TEST-INFO | 3364: killed by SIGTERM 18:43:34 INFO - Browser initialized. 18:43:34 INFO - Running cycle 1/1 for dromaeo_css test... 18:43:34 INFO - TEST-INFO | started process 3570 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptxmAi0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:46 INFO - PID 3570 | RSS: Main: 183148544 18:43:46 INFO - PID 3570 | 18:46:47 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:49638/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49638/tests/dromaeo/cssquery-ext.html) 18:46:49 INFO - PID 3570 | RSS: Main: 181690368 18:46:49 INFO - PID 3570 | 18:49:51 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:49638/tests/dromaeo/cssquery-ext.html (next: http://localhost:49638/tests/dromaeo/cssquery-jquery.html) 18:49:51 INFO - PID 3570 | RSS: Main: 184741888 18:49:51 INFO - PID 3570 | 18:52:53 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:49638/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49638/tests/dromaeo/cssquery-mootools.html) 18:52:53 INFO - PID 3570 | RSS: Main: 183959552 18:52:53 INFO - PID 3570 | 18:55:55 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:49638/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49638/tests/dromaeo/cssquery-prototype.html) 18:55:56 INFO - PID 3570 | RSS: Main: 185573376 18:55:56 INFO - PID 3570 | 18:58:58 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:49638/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49638/tests/dromaeo/cssquery-yui.html) 18:58:59 INFO - PID 3570 | RSS: Main: 183676928 18:58:59 INFO - PID 3570 | 19:02:01 INFO - PID 3570 | Cycle 1(1): loaded http://localhost:49638/tests/dromaeo/cssquery-yui.html (next: http://localhost:49638/tests/dromaeo/cssquery-dojo.html) 19:02:01 INFO - PID 3570 | RSS: Main: 184745984 19:02:01 INFO - PID 3570 | 19:02:01 INFO - PID 3570 | __start_tp_report 19:02:01 INFO - PID 3570 | _x_x_mozilla_page_load 19:02:01 INFO - PID 3570 | _x_x_mozilla_page_load_details 19:02:01 INFO - PID 3570 | |i|pagename|runs| 19:02:01 INFO - PID 3570 | |0;dojo.html;3076.6923307669235;3104.937901241974;3112.875484980601;3128.9061328160165;3130.62432508099;5798.855028624286;5825.708714564276;5886.146508756227;5959.463648271655;5979.491743201827;5850.444207800252;5854.356020837705;5891.528677705784;5954.345022047572;5966.850828729284;5396;5490;5518.530924871391;5519;5534.142207957753;5567.916481252764;5592.217089607459;5593.3847276799725;5610.607257491977;5613.214150019001;5361.490658387486;5452.972735136339;5465.152901300305;5519.530839878595;5559.277293951801;5474.972625136888;5516.696581688006;5523.4200408956885;5524.1989911462615;5538.833834984957;5446.509814116748;5461.344638643349;5475.206095116226;5478.041342765;5479.479449452296;5793.073108302693;5871.089981052901;5910.527157827363;5931.792387266425;5933.080372542263;6287.52843536732;6316.873662526769;6375.968120159416;6414.903776443357;6424.38968298011;6225.657588832612;6230.59501132425;6233.719482623293;6243.313235544087;6250.9062364064575;5766.971165144189;5794.594378393472;5798.898209340254;5802;5836.2996440427;6366.936330636727;6446.290908000116;6516.641584712792;6569.80290591283;6614.735410583619;6069.878602427927;6108.129596501707;6148.754049838046;6187.195664563578;6198.938010619836;6334.9366506334345;6336.936630633634;6342.8414289642205;6351.745930162834;6367.363263673596;6293.748250069946;6312.7790527332245;6333.936660633335;6335.081413195061;6338.7464501420345;6139.600925939889;6180.381961803783;6195.2255968004;6201.844953876189;6266.530010249249;5101.234814777813;5107.055194788976;5127.53852153308;5135.409427915837;5172.120739474299;4379.693421460467;4391.8462853799965;4397.6042156206095;4402.273624851863;4426.933595996063;5507.917381239285;5516.724163791794;5549.556035517149;5563.109902415594;5639.407862174413;6475.546711730133;6481.351864813575;6484.319146489645;6502.837429064217;6510.153680021567;5612.410696876769;5616.185653080281;5616.213730077792;5627.2413947897;5665.206871038058;5582.274304340491;5643.322801263875;5717.17101020358;5718.027935251017;5732.34078081026;6036.9698151508965;6111.991521399008;6120.908186377119;6123.8162855114415;6126.142340072393;6366.076918846648;6399.616023038632;6405.167328247298;6405.711742971671;6412.487001039813;6128.846778830565;6156.230471191101;6173.41352571505;6177.413145751147;6193.969030154821;6158.107074474086;6179.938200617937;6264.31092579825;6356.650384228912;6365.936340636535;6416;6428.774992875227;6438.259600146043;6443.130177425987;6472.611643301417;6112.205413296243;6121.571489995657;6154.815355539341;6204.193454851021;6236.939720247478;6113.29387775398;6231.470325022424;6232.626042437287;6240.188775459162;6249.406306400884;5938.524918006636;5970.522358211246;6117.888303246575;6241.157443745218;6258.593191442542;6296.0555916612875;6347.96826015867;6351.460125889351;6384.170057892445;6415.1981002374705;6121.602095863933;6162.07568864656;6191.19514463135;6200.100985357189;6210.975189093749 19:02:01 INFO - PID 3570 | |1;ext.html;655.8917778566537;682.4097155960094;714.143028365961;1191.1007189571867;1241.7268200995777;36934.26131477369;38955.805220973925;39021.268511204195;39043.609563904356;39102.60897391025;37064;37282.8135859321;37387.25225495488;37476.43785343222;37533.249335013286;6910.516263861544;7246.130464344286;7450.515716478439;7451.2921272479025;7464.552126872378;7359.852802943938;7424.331810137087;7459;7464.066991626047;7466.887996680054;7448.590327531983;7488.176300606929;7493.550386976799;7495.138059123189;7509.774706758779;7395.371393431511;7428.405727541784;7437.51656142349;7446.9627651861665;7496.662650180755;7299.343059124704;7352.963235183844;7402.555846649218;7403;7408.111026676779;36220.27559448822;36300.09249768777;36418.63581364205;36466.81766591177;36671.266574668625;3531.2407832315916;3568.518250036264;3571;3577.8032208228537;3590.317839610466;27579.448411031866;27655.30861728453;27759.167224983285;27846.443071138667;27887.721122788716;3143.528470729386;3171.9365612687616;3187.7768556201077;3205.4550726376574;3210.3739770744714;133099;136894.31552842172;169705;170773;170786;38689.6131038686;39155.216895662496;39283.21433571313;39353;39525;40881.59118408837;41194.79402603028;41293;41314.586854131674;51513;15024.474143404928;15156.469523566622;15196.392144314324;15197.088174709554;15262.923685381502;12241.510339586495;12318.199317044364;12333.136680432463;12354.814677779841;12358.50565977351;14522.20127892955;14581.781273280909;14670.63323416923;14681.412743490355;14879.776803347959;5856.736446859902;5857.970710146507;5982.880342393128;5998.220231369894;6038.879222415615;993.3940296419179;994.9801003979879;997.2371136080903;1046.3721766939775;1051.5636011055326;23055.884720576625;24493.142740004016;24814.751852481244;24873.378165545644;24901.12846050416;1105.9170562207867;1106.7620461600795;1125.6341688951125;1138.5275110828914;1141.7545227776068;12518.374081295899;13086.607401777961;13161.60515184546;13294.933525332504;16262.593435163979;206412;206705;207759;208409;208834;55404;55867.44132558704;56447.435525645036;56481.7175914126;56571.43428565744;1454.9054311469931;1458.1105525629357;1487.2192099148033;1488.5757559095605;1494.5177735875384;26439.471210576066;28457;29091;29249;29304;37048.81475592605;45548.31677524906;48134.277985830944;48202.75898620485;48427.75786121188;173409;174599;176871;176992;177100;34864.954051378496;35080;35085.29829403449;35504.82247588746;35650.643493564734;45140.77429612831;45900;46912;47301.763491182326;47797;32857;34251.82874085614;34535.82732086424;35097;35684;32896.342073158885;33345.166370840925;33712.3257534843;33859.83070084634;35192.12019699528;37971.32947288511;38226.617733822306;38275.80862095783;38507.42238866363;38808;32321.838390807894;33276.50085248772;33968.490472643425;34069;34783;25980.35049123787;26737.33156671021;26899.73100268972;26995;27057 19:02:01 INFO - PID 3570 | |2;jquery.html;484.7237074867325;495.19777959705283;497.7287378378782;504.6265763335131;515.626171026006;19466;20017.99999999998;20233.39299821006;21051;23137.19019834306;23229.419264518354;23841.16555920543;23872.28383148508;23885.761142388572;23893.28320150394;6134.907976380347;6296.811095667114;6298.118263443099;6308.211473565804;6312.873742525147;6178.320384757673;6206.224221972254;6235.469985051276;6237.625742455444;6240.812775616716;5705.572082093839;5969.194158788551;5983.1025346197985;5994.220751302325;6041.4562689357945;5950.702464876739;5952.315483719384;5962.79130230442;5994.0409534474275;6000.6099603525845;5514.255575497336;5519.69641669708;5535.114381698949;5546.251256080418;5548.472895074961;23636.936337864838;24031.639525407132;24174.274771756875;24365.269041928772;24455.877720611283;1810.1401834128815;1831.285798538571;1843.889366638006;1857.136431559318;1865.6362009408172;17016;17440.21519031634;17445.563860903578;17459;17478.475645730523;2258.9661155082686;2261.0164578408444;2311.3759284992957;2333.5916214662534;2346.272655476808;112536;117176;117880.41059794732;118382;118878;13126.934365328303;13233.669158271121;13320.866791332157;13365.599032029055;13412.798808017887;20437.38687839367;20450;20465.79534204639;20469.692954605693;20476.488087797923;8825.20573148407;9264.583093760797;9328.580213890391;9372.437653740797;9459.574319155518;8730.38887277897;8775.517346545874;8857.158569935846;8935.642574297086;8966.775830604156;8220.547869867214;8295.62669679866;8344.62449189788;8446.155384461505;8538.188872057144;2322.210448447536;2326.139328448451;2344.9648255276184;2417.2385698504913;2450.4486490539844;924.7688077980505;924.8751418558543;929.5352323838081;971.159946646146;971.655062452819;18883;19737.605247895248;20185.394438166877;20271.4932126698;20746.585068298853;959.3716115944068;970.5583959298502;992.5979978108877;997.6258902911409;1011.7774089700254;10022.398656080659;10166.949165254126;10539.94730026345;10638.468076596138;10835.349879007284;411173;412192;418218;418434;418459;95739.52130239304;99224;101574.49212753889;102267.4886625562;102635;1140.8267250887072;1180.492388273051;1234.0559472003924;1258.6916205529888;1260.4517035089582;18736.344227951955;19967.72952748277;20304.79695203029;20545.383638490868;20749.377518674464;48592;48690;49354;50320.74839625778;50449.747751262476;61199.38800611937;61287;61450;62025.06962395658;62228.688856555425;10952.178586606196;11581.536738530365;11651.41742912834;11689.181757276909;11712.765744685228;17139.571510712332;17444.47666570005;17564.121793910508;17968.46094617111;18139;14039.227842468763;14075;14210.573682789533;15161.772573411186;15336.079835209923;12483.257433699395;12747.936260318638;12808.551700690432;13065.346732663138;13171.73656526845;14682.192479413308;14709.485168019068;14970.17664028489;15047.699046018799;15066.472673456376;11451.312921224753;11528.82706759416;11579.421028948687;11582.305061695988;11810.173287869766;13689.589312320646;13902.930485347508;13956.2324072177;13984;13991.370388332753 19:02:01 INFO - PID 3570 | |3;mootools.html;1307.3136603283276;1352.52661568451;1352.9526466573666;1356.1117468058426;1362.8432730236025;11798;12377.133600647947;12399.380030998447;12536.81194782079;12825.871741282584;11949.223300485484;12083.758324833498;12107.515699372014;12147.271163730182;12205.389730513483;2447.49826285611;2531.962020569693;2533.227365653475;2533.670622819031;2539.746025397464;2429.987850060756;2537.314924970257;2538.365408647838;2538.8984440622353;2538.9492210155786;2540.530001949636;2542.377117606189;2565.551028569993;2570.678665166854;2579.4067364506177;2496.1388321029217;2508.122157244959;2514.660520829683;2524.911628093008;2538.7080485744004;2232.1071571371417;2258.5432396104584;2276.6129757941026;2281.851679640818;2286.668433077211;12326.383680815923;12413.565525206664;12440;12479.188852724634;12491.812622810665;6629.469642428642;6967.163940327193;6971.477139214579;6976.825579360506;6977.720891164347;4052.4529188559463;4141.855035073758;4199.68502362321;4221.598948099926;4243.321068629005;1306.745184688986;1313.192386682193;1318.6044186743939;1337.0306527767389;1342.0873805812043;18743.906280468647;19178.616427671368;19182.52043698905;19206.519837004187;19388.806111938982;5689.943100569024;5720.713964301768;5893.174955506232;5926.496247818984;5953.702314884238;13856.376463059187;13934.930325348309;13937.930310348384;13988.790168147487;14008;6689.665516724144;6775.932240677629;6776.085228494188;6786.117804685458;6796.864062718718;6245.469135123474;6341.619502829845;6368.10846481493;6381.074744162071;6462.903056454157;4380.342948557742;4480.484744254387;4567.794449249792;4596.448426188878;4597.7241365518175;1232.2421710647886;1242.714175739585;1254.4229654358915;1254.4856608790535;1265.974680506403;836.180543067797;860.4794099569796;869.1352104655781;884.0496466298755;897.9595918183697;6449.709763060673;6515.609063456208;6636.2368327642935;6819.863602728017;7417.184109747924;693.8369483171551;710.7441321124392;711.1324184494908;712.6650474277081;714.7819914925998;4051;4099.344104943195;4109.527404348479;4188.623023927861;4226.852060177879;15614.906956512936;16272.755908661144;16359;16360.836391636169;16372.426965056404;15430;16003.839961600468;16032.919835400748;16081.115538645252;16092.195390230441;9267.860982085274;9605.951970240105;9855.162311203485;9888.802223955481;9997.650082247232;6950.721971121098;7266.091738532684;7446.73936412223;7556.8488630228185;7677.616119193906;14903.552893413214;15044.849551504345;15138.318775655342;15166.469173578871;15335.693286134438;15085.54743357701;15086.321115549606;15171.468998585438;15321.157336346609;15395.691366233996;9560.713178604654;10035.849462258218;10053.296269261082;10089.697309080739;10112.544935478069;11653.825192622287;11726.589569365031;11760.05919526453;11762.94118529402;11764.764704706029;9315.441073535609;9497.715068547956;9572.808543828945;9595.47224902637;9871.703848884545;9548.713538593853;9729.318947673595;9740.75648108803;9798.657047003606;9988.200943924614;8436.915630843612;8545.316374689886;8644;8646;8651.091635378194;6036.577439579363;6285.528585356025;6323.272823625342;6436.742530298736;6488.091667166506;5904.52763778888;5968.134620480093;6143.354947730602;6152.077188421773;6155.4767844731905 19:02:01 INFO - PID 3570 | |4;prototype.html;412.6616174736717;443.707153278836;447.45634054623633;456.8789270843225;458.73164198943624;6362.236531616207;6384.680765961706;6421.550491465599;6426.7750628728;6430.3569643035735;6130.632162070279;6171.598846075002;6198.876022479548;6223.782167624134;6269.279032911217;4740.810367585293;4880.731559764212;4895.21676531755;5018.598512119031;5030.874228144289;4904.60763138948;4912;4916.606671466274;4919.72941488218;4922.114019476493;4823.421189457253;4832.661713680044;4835.8549243522575;4848.393950756155;4864.1730905745935;4844.370231869852;4851.884066664669;4874.805007799684;4876.804927802884;4888.706677599355;4323.740575565476;4327.242732521828;4345.978270108661;4350.412694286294;4353.782310884475;6092.147099406086;6099.383663329221;6110.938890611082;6184.443944731797;6193.194884665009;5582.609217354787;5600.103983362643;5604.355499117612;5604.8038318658655;5606.831795046155;6586.407223349922;6627.138471998609;6648.900266496006;6651.833704157387;6663.300353462913;4705.12955103307;4719.622430205575;4725.598324142417;4728.763561821896;4729.290606409032;6614.9338506614795;6921.238663746984;6925.930740692579;6932.826679333007;7050.391355198558;4776.641751868624;4788.712677239377;4825;4832.903341933212;4835.419749630067;4789.568938795525;4836.234774307422;4844.128056949783;4850.878728031827;4865.537773911473;5375;5480.3971563128025;5526.668399896019;5542.806001789999;5553.50018498329;6596.646213499068;6601.141851559362;6622.2715501294815;6648.4681225501845;6668.299828518033;1676.1200369805706;1696.7200411931892;1697.4313604942236;1699.541123896541;1702.34021828716;1128.8250321200228;1155.1625071822862;1168.9181757277079;1174.8590169179754;1176.2707121584672;5856.267966504187;5862.325832529228;5870.970645146833;5895.056790913433;5936.436038576328;6783.966080169666;6784.660766961632;6815.863682726318;6816.488763342769;6819;5859.700821616694;5907.23205983228;5919.940800592025;5920.763169473259;5927.9703601481715;3657.8536858525363;3663.4871118043493;3667.249562827879;3679.981600092036;3685;6645.1361323027695;6646.202455705346;6651.16860392451;6655;6656.201255849232;6935.757248496379;7009.264027277164;7037.964810176019;7054.96636446333;7071.82320441993;6561.770338038145;6572.14562106923;6606.795704083515;6621.27166011748;6621.3047629998155;6822.897656535057;6830.59016459014;6855.897161542681;6874.9656251718425;6889.724411023503;6421.646809425526;6758.695858686273;6791.422729068085;6847.289656136914;6868.278830722702;6555.672216389257;6585.670716464062;6629.337066293332;6634.701438435379;6677.122563759335;4783.425988881357;4799.112164249625;4802.8799280018275;4819.542143496362;4820.517948205292;6649.800505984828;6852.246252912275;6879.828004299932;6893.690613644485;6896.517243792886;6687.799366019027;6689.364510371507;6697.0958920547055;6701.229358623821;6723.865522689421;6650.168728908887;6670.366315200048;6672.933270667231;6703.162104736908;6705.22889867652;6508.511861610493;6538.378854008862;6572.605643661395;6591.34086591337;6627.271000190071;6670.899936501053;6730;6730.831729206613;6731.124953755981;6733.326667333424;6470.773522926675;6484.70818813164;6485.189351331083;6507.284198738041;6507.837304067436 19:02:01 INFO - PID 3570 | |5;yui.html;749.1609397474829;768.5427170833356;800.1438460846892;819.2094628683321;832.7002279179494;16735.246913888874;17278.740818887727;17314.480565583053;18204.453866383996;19853.602927941432;17813.73279400807;18149.63700725988;18275.543111422223;18319;18581.442556723287;967.5597603090577;1008.3899240959224;1020.122694482748;1020.1634659579148;1020.5866624017248;970.6457143142773;993.4436715439377;1022.1720406470782;1022.3303736052898;1025.2874252394588;1065.3288428290166;1067.1676092647747;1070.0476575847501;1070.561069961316;1071.1002757683543;1050.1913526584526;1052.2003277509073;1054.7099547624389;1057.0856209378908;1058.2962330050507;771.0285040848515;773.2422226218338;778.7118766056595;779.0378882080682;779.7309928074861;15902.76145857813;16985.405510807184;17028.48914532566;17062.06158661273;17189.656206875792;18799.24803007878;18929.05354732258;19107.426777196706;19202.423927282205;19235.807641923682;14569.927150364181;15066.924665376713;15103.697926041528;15163;15179.772303415348;3358.983205083983;3435.707964822993;3456.0495863637448;3484.0418884806627;3510.8771193008124;79282.6035869817;80516.59741701254;80679;80978.59510702408;81353.59323203345;14161.858381416054;14734.484293049692;14752;14960.401583936738;15075.321610527553;25627;25763;25789.871050644626;25811.35471613187;26216.60675089875;7915.881261781078;7964;8437.198466145706;8438.198371154731;8454.535000575028;4144.316187829035;4235.576442355801;4243.469566304212;4252.957470425256;4263.168682107022;4409.294512877924;4521.299198624219;4534.614557762628;4966.726830024311;5118.539331460186;882.2280504558511;894.3292530602048;908.8136931928939;909.1817364371985;910.7586489580134;597.265363602771;617.108278537518;632.585656395062;637.1907677249959;641.6503005861816;10882.455877206108;11343.319400835977;11750.882491174978;12269.141160118706;12483.126181167412;625.2434554189482;641.3298103481873;646.7930262316107;658.8122385120314;674.7199912036548;5021.59827213822;5131.61512886535;5141.280220769096;5376.112941364631;5537.723113844372;88615.55692221498;91090;92470;96671.51664241774;97469.51265243629;78922.60538697384;79958;80297;80597.19402806014;81291;70695.64652176706;71199.64400177966;71261.64369178224;71498.64250678713;74230.62884685434;10232.334898231591;10855;10905.454727263765;11211;11548;28536.003719609143;28997.56503652489;29383.265418363855;29638;30222.244443888198;55055.72472137774;56128.43871561232;56553;56698.7165064172;56840;16991.575210619834;17260.482185534453;17578.472645820133;17627.41353278211;17642.82357176412;18673.53316167107;19671.606567868275;19689.093470367858;19747.506312342306;20133;16996.915015424845;17085.914570427565;17183.656326873144;17922.91038544799;18249.178786954355;16673;17272.309107635552;17601.383951561635;17949.910250448665;18063.2774689011;15352.155631439931;16469.58826029311;16587.17064146812;16837.831621684116;17149.056801876017;11975.401229938643;11996;12411.937940310241;12531.93734031324;12586.496540138658;12566.623001309976;12881.48474061027;13002.934985325013;13176.47294108225;13360.532381366995 19:02:01 INFO - PID 3570 | __end_tp_report 19:02:01 INFO - PID 3570 | __start_cc_report 19:02:01 INFO - PID 3570 | _x_x_mozilla_cycle_collect,373 19:02:01 INFO - PID 3570 | __end_cc_report 19:02:01 INFO - PID 3570 | __startTimestamp1496973721601__endTimestamp 19:02:01 INFO - PID 3570 | 19:02:01 INFO - PID 3570 | ------- Summary: start ------- 19:02:01 INFO - PID 3570 | Number of tests: 6 19:02:01 INFO - PID 3570 | 19:02:01 INFO - PID 3570 | [#0] dojo.html Cycles:165 Average:5864.27 Median:6113.29 stddev:665.77 (10.9%) stddev-sans-first:630.88 19:02:01 INFO - PID 3570 | Values: 3076.7 3104.9 3112.9 3128.9 3130.6 5798.9 5825.7 5886.1 5959.5 5979.5 5850.4 5854.4 5891.5 5954.3 5966.9 5396.0 5490.0 5518.5 5519.0 5534.1 5567.9 5592.2 5593.4 5610.6 5613.2 5361.5 5453.0 5465.2 5519.5 5559.3 5475.0 5516.7 5523.4 5524.2 5538.8 5446.5 5461.3 5475.2 5478.0 5479.5 5793.1 5871.1 5910.5 5931.8 5933.1 6287.5 6316.9 6376.0 6414.9 6424.4 6225.7 6230.6 6233.7 6243.3 6250.9 5767.0 5794.6 5798.9 5802.0 5836.3 6366.9 6446.3 6516.6 6569.8 6614.7 6069.9 6108.1 6148.8 6187.2 6198.9 6334.9 6336.9 6342.8 6351.7 6367.4 6293.7 6312.8 6333.9 6335.1 6338.7 6139.6 6180.4 6195.2 6201.8 6266.5 5101.2 5107.1 5127.5 5135.4 5172.1 4379.7 4391.8 4397.6 4402.3 4426.9 5507.9 5516.7 5549.6 5563.1 5639.4 6475.5 6481.4 6484.3 6502.8 6510.2 5612.4 5616.2 5616.2 5627.2 5665.2 5582.3 5643.3 5717.2 5718.0 5732.3 6037.0 6112.0 6120.9 6123.8 6126.1 6366.1 6399.6 6405.2 6405.7 6412.5 6128.8 6156.2 6173.4 6177.4 6194.0 6158.1 6179.9 6264.3 6356.7 6365.9 6416.0 6428.8 6438.3 6443.1 6472.6 6112.2 6121.6 6154.8 6204.2 6236.9 6113.3 6231.5 6232.6 6240.2 6249.4 5938.5 5970.5 6117.9 6241.2 6258.6 6296.1 6348.0 6351.5 6384.2 6415.2 6121.6 6162.1 6191.2 6200.1 6211.0 19:02:01 INFO - PID 3570 | 19:02:01 INFO - PID 3570 | [#1] ext.html Cycles:180 Average:35438.25 Median:19361.68 stddev:46907.48 (242.3%) stddev-sans-first:46966.35 19:02:01 INFO - PID 3570 | Values: 655.9 682.4 714.1 1191.1 1241.7 36934.3 38955.8 39021.3 39043.6 39102.6 37064.0 37282.8 37387.3 37476.4 37533.2 6910.5 7246.1 7450.5 7451.3 7464.6 7359.9 7424.3 7459.0 7464.1 7466.9 7448.6 7488.2 7493.6 7495.1 7509.8 7395.4 7428.4 7437.5 7447.0 7496.7 7299.3 7353.0 7402.6 7403.0 7408.1 36220.3 36300.1 36418.6 36466.8 36671.3 3531.2 3568.5 3571.0 3577.8 3590.3 27579.4 27655.3 27759.2 27846.4 27887.7 3143.5 3171.9 3187.8 3205.5 3210.4 133099.0 136894.3 169705.0 170773.0 170786.0 38689.6 39155.2 39283.2 39353.0 39525.0 40881.6 41194.8 41293.0 41314.6 51513.0 15024.5 15156.5 15196.4 15197.1 15262.9 12241.5 12318.2 12333.1 12354.8 12358.5 14522.2 14581.8 14670.6 14681.4 14879.8 5856.7 5858.0 5982.9 5998.2 6038.9 993.4 995.0 997.2 1046.4 1051.6 23055.9 24493.1 24814.8 24873.4 24901.1 1105.9 1106.8 1125.6 1138.5 1141.8 12518.4 13086.6 13161.6 13294.9 16262.6 206412.0 206705.0 207759.0 208409.0 208834.0 55404.0 55867.4 56447.4 56481.7 56571.4 1454.9 1458.1 1487.2 1488.6 1494.5 26439.5 28457.0 29091.0 29249.0 29304.0 37048.8 45548.3 48134.3 48202.8 48427.8 173409.0 174599.0 176871.0 176992.0 177100.0 34865.0 35080.0 35085.3 35504.8 35650.6 45140.8 45900.0 46912.0 47301.8 47797.0 32857.0 34251.8 34535.8 35097.0 35684.0 32896.3 33345.2 33712.3 33859.8 35192.1 37971.3 38226.6 38275.8 38507.4 38808.0 32321.8 33276.5 33968.5 34069.0 34783.0 25980.4 26737.3 26899.7 26995.0 27057.0 19:02:01 INFO - PID 3570 | 19:02:01 INFO - PID 3570 | [#2] jquery.html Cycles:180 Average:29940.64 Median:12724.28 stddev:70062.35 (550.6%) stddev-sans-first:70223.99 19:02:01 INFO - PID 3570 | Values: 484.7 495.2 497.7 504.6 515.6 19466.0 20018.0 20233.4 21051.0 23137.2 23229.4 23841.2 23872.3 23885.8 23893.3 6134.9 6296.8 6298.1 6308.2 6312.9 6178.3 6206.2 6235.5 6237.6 6240.8 5705.6 5969.2 5983.1 5994.2 6041.5 5950.7 5952.3 5962.8 5994.0 6000.6 5514.3 5519.7 5535.1 5546.3 5548.5 23636.9 24031.6 24174.3 24365.3 24455.9 1810.1 1831.3 1843.9 1857.1 1865.6 17016.0 17440.2 17445.6 17459.0 17478.5 2259.0 2261.0 2311.4 2333.6 2346.3 112536.0 117176.0 117880.4 118382.0 118878.0 13126.9 13233.7 13320.9 13365.6 13412.8 20437.4 20450.0 20465.8 20469.7 20476.5 8825.2 9264.6 9328.6 9372.4 9459.6 8730.4 8775.5 8857.2 8935.6 8966.8 8220.5 8295.6 8344.6 8446.2 8538.2 2322.2 2326.1 2345.0 2417.2 2450.4 924.8 924.9 929.5 971.2 971.7 18883.0 19737.6 20185.4 20271.5 20746.6 959.4 970.6 992.6 997.6 1011.8 10022.4 10166.9 10539.9 10638.5 10835.3 411173.0 412192.0 418218.0 418434.0 418459.0 95739.5 99224.0 101574.5 102267.5 102635.0 1140.8 1180.5 1234.1 1258.7 1260.5 18736.3 19967.7 20304.8 20545.4 20749.4 48592.0 48690.0 49354.0 50320.7 50449.7 61199.4 61287.0 61450.0 62025.1 62228.7 10952.2 11581.5 11651.4 11689.2 11712.8 17139.6 17444.5 17564.1 17968.5 18139.0 14039.2 14075.0 14210.6 15161.8 15336.1 12483.3 12747.9 12808.6 13065.3 13171.7 14682.2 14709.5 14970.2 15047.7 15066.5 11451.3 11528.8 11579.4 11582.3 11810.2 13689.6 13902.9 13956.2 13984.0 13991.4 19:02:01 INFO - PID 3570 | 19:02:01 INFO - PID 3570 | [#3] mootools.html Cycles:180 Average:7636.05 Median:2553.96 stddev:5074.58 (198.7%) stddev-sans-first:5066.54 19:02:01 INFO - PID 3570 | Values: 1307.3 1352.5 1353.0 1356.1 1362.8 11798.0 12377.1 12399.4 12536.8 12825.9 11949.2 12083.8 12107.5 12147.3 12205.4 2447.5 2532.0 2533.2 2533.7 2539.7 2430.0 2537.3 2538.4 2538.9 2538.9 2540.5 2542.4 2565.6 2570.7 2579.4 2496.1 2508.1 2514.7 2524.9 2538.7 2232.1 2258.5 2276.6 2281.9 2286.7 12326.4 12413.6 12440.0 12479.2 12491.8 6629.5 6967.2 6971.5 6976.8 6977.7 4052.5 4141.9 4199.7 4221.6 4243.3 1306.7 1313.2 1318.6 1337.0 1342.1 18743.9 19178.6 19182.5 19206.5 19388.8 5689.9 5720.7 5893.2 5926.5 5953.7 13856.4 13934.9 13937.9 13988.8 14008.0 6689.7 6775.9 6776.1 6786.1 6796.9 6245.5 6341.6 6368.1 6381.1 6462.9 4380.3 4480.5 4567.8 4596.4 4597.7 1232.2 1242.7 1254.4 1254.5 1266.0 836.2 860.5 869.1 884.0 898.0 6449.7 6515.6 6636.2 6819.9 7417.2 693.8 710.7 711.1 712.7 714.8 4051.0 4099.3 4109.5 4188.6 4226.9 15614.9 16272.8 16359.0 16360.8 16372.4 15430.0 16003.8 16032.9 16081.1 16092.2 9267.9 9606.0 9855.2 9888.8 9997.7 6950.7 7266.1 7446.7 7556.8 7677.6 14903.6 15044.8 15138.3 15166.5 15335.7 15085.5 15086.3 15171.5 15321.2 15395.7 9560.7 10035.8 10053.3 10089.7 10112.5 11653.8 11726.6 11760.1 11762.9 11764.8 9315.4 9497.7 9572.8 9595.5 9871.7 9548.7 9729.3 9740.8 9798.7 9988.2 8436.9 8545.3 8644.0 8646.0 8651.1 6036.6 6285.5 6323.3 6436.7 6488.1 5904.5 5968.1 6143.4 6152.1 6155.5 19:02:01 INFO - PID 3570 | 19:02:01 INFO - PID 3570 | [#4] prototype.html Cycles:180 Average:5529.88 Median:6188.82 stddev:1612.22 (26.1%) stddev-sans-first:1570.33 19:02:01 INFO - PID 3570 | Values: 412.7 443.7 447.5 456.9 458.7 6362.2 6384.7 6421.6 6426.8 6430.4 6130.6 6171.6 6198.9 6223.8 6269.3 4740.8 4880.7 4895.2 5018.6 5030.9 4904.6 4912.0 4916.6 4919.7 4922.1 4823.4 4832.7 4835.9 4848.4 4864.2 4844.4 4851.9 4874.8 4876.8 4888.7 4323.7 4327.2 4346.0 4350.4 4353.8 6092.1 6099.4 6110.9 6184.4 6193.2 5582.6 5600.1 5604.4 5604.8 5606.8 6586.4 6627.1 6648.9 6651.8 6663.3 4705.1 4719.6 4725.6 4728.8 4729.3 6614.9 6921.2 6925.9 6932.8 7050.4 4776.6 4788.7 4825.0 4832.9 4835.4 4789.6 4836.2 4844.1 4850.9 4865.5 5375.0 5480.4 5526.7 5542.8 5553.5 6596.6 6601.1 6622.3 6648.5 6668.3 1676.1 1696.7 1697.4 1699.5 1702.3 1128.8 1155.2 1168.9 1174.9 1176.3 5856.3 5862.3 5871.0 5895.1 5936.4 6784.0 6784.7 6815.9 6816.5 6819.0 5859.7 5907.2 5919.9 5920.8 5928.0 3657.9 3663.5 3667.2 3680.0 3685.0 6645.1 6646.2 6651.2 6655.0 6656.2 6935.8 7009.3 7038.0 7055.0 7071.8 6561.8 6572.1 6606.8 6621.3 6621.3 6822.9 6830.6 6855.9 6875.0 6889.7 6421.6 6758.7 6791.4 6847.3 6868.3 6555.7 6585.7 6629.3 6634.7 6677.1 4783.4 4799.1 4802.9 4819.5 4820.5 6649.8 6852.2 6879.8 6893.7 6896.5 6687.8 6689.4 6697.1 6701.2 6723.9 6650.2 6670.4 6672.9 6703.2 6705.2 6508.5 6538.4 6572.6 6591.3 6627.3 6670.9 6730.0 6730.8 6731.1 6733.3 6470.8 6484.7 6485.2 6507.3 6507.8 19:02:01 INFO - PID 3570 | 19:02:01 INFO - PID 3570 | [#5] yui.html Cycles:180 Average:19701.26 Median:19993.30 stddev:24539.66 (122.7%) stddev-sans-first:24567.23 19:02:01 INFO - PID 3570 | Values: 749.2 768.5 800.1 819.2 832.7 16735.2 17278.7 17314.5 18204.5 19853.6 17813.7 18149.6 18275.5 18319.0 18581.4 967.6 1008.4 1020.1 1020.2 1020.6 970.6 993.4 1022.2 1022.3 1025.3 1065.3 1067.2 1070.0 1070.6 1071.1 1050.2 1052.2 1054.7 1057.1 1058.3 771.0 773.2 778.7 779.0 779.7 15902.8 16985.4 17028.5 17062.1 17189.7 18799.2 18929.1 19107.4 19202.4 19235.8 14569.9 15066.9 15103.7 15163.0 15179.8 3359.0 3435.7 3456.0 3484.0 3510.9 79282.6 80516.6 80679.0 80978.6 81353.6 14161.9 14734.5 14752.0 14960.4 15075.3 25627.0 25763.0 25789.9 25811.4 26216.6 7915.9 7964.0 8437.2 8438.2 8454.5 4144.3 4235.6 4243.5 4253.0 4263.2 4409.3 4521.3 4534.6 4966.7 5118.5 882.2 894.3 908.8 909.2 910.8 597.3 617.1 632.6 637.2 641.7 10882.5 11343.3 11750.9 12269.1 12483.1 625.2 641.3 646.8 658.8 674.7 5021.6 5131.6 5141.3 5376.1 5537.7 88615.6 91090.0 92470.0 96671.5 97469.5 78922.6 79958.0 80297.0 80597.2 81291.0 70695.6 71199.6 71261.6 71498.6 74230.6 10232.3 10855.0 10905.5 11211.0 11548.0 28536.0 28997.6 29383.3 29638.0 30222.2 55055.7 56128.4 56553.0 56698.7 56840.0 16991.6 17260.5 17578.5 17627.4 17642.8 18673.5 19671.6 19689.1 19747.5 20133.0 16996.9 17085.9 17183.7 17922.9 18249.2 16673.0 17272.3 17601.4 17949.9 18063.3 15352.2 16469.6 16587.2 16837.8 17149.1 11975.4 11996.0 12411.9 12531.9 12586.5 12566.6 12881.5 13002.9 13176.5 13360.5 19:02:01 INFO - PID 3570 | -------- Summary: end -------- 19:02:01 INFO - PID 3570 | 19:02:02 INFO - TEST-INFO | 3570: exit 0 19:02:02 INFO - TEST-OK | dromaeo_css | took 1143728ms 19:02:02 INFO - TEST-START | kraken 19:02:02 INFO - operating with platform_type : linux_ 19:02:02 INFO - Initialising browser for kraken test... 19:02:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49638/getInfo.html -profile /tmp/tmpE8VV5X/profile 19:02:02 INFO - TEST-INFO | started process 3969 (/builds/slave/test/build/application/firefox/firefox http://localhost:49638/getInfo.html) 19:02:36 INFO - TEST-INFO | 3969: killed by SIGTERM 19:02:37 INFO - Browser initialized. 19:02:37 INFO - Running cycle 1/1 for kraken test... 19:02:37 INFO - TEST-INFO | started process 4180 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpE8VV5X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:02:48 INFO - PID 4180 | RSS: Main: 183898112 19:02:48 INFO - PID 4180 | 19:02:51 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49638/tests/kraken/driver.html?testName=audio-beat-detection) 19:02:52 INFO - PID 4180 | RSS: Main: 189612032 19:02:52 INFO - PID 4180 | 19:02:55 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49638/tests/kraken/driver.html?testName=audio-dft) 19:02:55 INFO - PID 4180 | RSS: Main: 187985920 19:02:55 INFO - PID 4180 | 19:02:58 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49638/tests/kraken/driver.html?testName=audio-fft) 19:02:59 INFO - PID 4180 | RSS: Main: 184053760 19:02:59 INFO - PID 4180 | 19:03:01 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49638/tests/kraken/driver.html?testName=audio-oscillator) 19:03:01 INFO - PID 4180 | RSS: Main: 184909824 19:03:01 INFO - PID 4180 | 19:03:04 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49638/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:03:04 INFO - PID 4180 | RSS: Main: 184782848 19:03:04 INFO - PID 4180 | 19:03:07 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49638/tests/kraken/driver.html?testName=imaging-darkroom) 19:03:08 INFO - PID 4180 | RSS: Main: 185778176 19:03:08 INFO - PID 4180 | 19:03:11 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49638/tests/kraken/driver.html?testName=imaging-desaturate) 19:03:12 INFO - PID 4180 | RSS: Main: 184692736 19:03:12 INFO - PID 4180 | 19:03:15 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49638/tests/kraken/driver.html?testName=json-parse-financial) 19:03:16 INFO - PID 4180 | RSS: Main: 185294848 19:03:16 INFO - PID 4180 | 19:03:17 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49638/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:03:18 INFO - PID 4180 | RSS: Main: 185532416 19:03:18 INFO - PID 4180 | 19:03:19 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49638/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:03:20 INFO - PID 4180 | RSS: Main: 185708544 19:03:20 INFO - PID 4180 | 19:03:22 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49638/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:03:23 INFO - PID 4180 | RSS: Main: 186126336 19:03:23 INFO - PID 4180 | 19:03:26 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49638/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:03:26 INFO - PID 4180 | RSS: Main: 185765888 19:03:26 INFO - PID 4180 | 19:03:29 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49638/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:03:30 INFO - PID 4180 | RSS: Main: 185872384 19:03:30 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | Cycle 1(1): loaded http://localhost:49638/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49638/tests/kraken/driver.html?testName=ai-astar) 19:03:32 INFO - PID 4180 | RSS: Main: 187441152 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | __start_tp_report 19:03:32 INFO - PID 4180 | _x_x_mozilla_page_load 19:03:32 INFO - PID 4180 | _x_x_mozilla_page_load_details 19:03:32 INFO - PID 4180 | |i|pagename|runs| 19:03:32 INFO - PID 4180 | |0;ai-astar;102;109;104;109;109;112;110;102;108;112 19:03:32 INFO - PID 4180 | |1;audio-beat-detection;156;123;147;179;131;145;153;127;169;120 19:03:32 INFO - PID 4180 | |2;audio-dft;163;164;164;161;165;173;170;165;174;163 19:03:32 INFO - PID 4180 | |3;audio-fft;79;76;76;176;74;74;73;75;181;73 19:03:32 INFO - PID 4180 | |4;audio-oscillator;83;82;138;84;82;82;132;81;82;138 19:03:32 INFO - PID 4180 | |5;imaging-gaussian-blur;113;114;114;114;114;114;114;116;115;114 19:03:32 INFO - PID 4180 | |6;imaging-darkroom;150;151;151;150;150;151;150;150;150;151 19:03:32 INFO - PID 4180 | |7;imaging-desaturate;94;95;94;94;94;94;94;95;94;94 19:03:32 INFO - PID 4180 | |8;json-parse-financial;53;56;55;53;53;54;54;55;53;52 19:03:32 INFO - PID 4180 | |9;json-stringify-tinderbox;56;55;56;56;55;56;56;57;55;56 19:03:32 INFO - PID 4180 | |10;stanford-crypto-aes;81;82;84;82;84;92;85;82;102;94 19:03:32 INFO - PID 4180 | |11;stanford-crypto-ccm;134;134;131;143;145;136;137;128;128;140 19:03:32 INFO - PID 4180 | |12;stanford-crypto-pbkdf2;191;196;194;197;182;185;192;190;187;177 19:03:32 INFO - PID 4180 | |13;stanford-crypto-sha256-iterative;73;72;69;72;79;77;70;71;75;71 19:03:32 INFO - PID 4180 | __end_tp_report 19:03:32 INFO - PID 4180 | __start_cc_report 19:03:32 INFO - PID 4180 | _x_x_mozilla_cycle_collect,992 19:03:32 INFO - PID 4180 | __end_cc_report 19:03:32 INFO - PID 4180 | __startTimestamp1496973812567__endTimestamp 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | ------- Summary: start ------- 19:03:32 INFO - PID 4180 | Number of tests: 14 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#0] ai-astar Cycles:10 Average:107.70 Median:109.00 stddev:3.74 (3.4%) stddev-sans-first:3.35 19:03:32 INFO - PID 4180 | Values: 102.0 109.0 104.0 109.0 109.0 112.0 110.0 102.0 108.0 112.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#1] audio-beat-detection Cycles:10 Average:145.00 Median:150.00 stddev:19.86 (13.2%) stddev-sans-first:20.66 19:03:32 INFO - PID 4180 | Values: 156.0 123.0 147.0 179.0 131.0 145.0 153.0 127.0 169.0 120.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#2] audio-dft Cycles:10 Average:166.20 Median:165.00 stddev:4.49 (2.7%) stddev-sans-first:4.61 19:03:32 INFO - PID 4180 | Values: 163.0 164.0 164.0 161.0 165.0 173.0 170.0 165.0 174.0 163.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#3] audio-fft Cycles:10 Average:95.70 Median:74.50 stddev:43.69 (58.6%) stddev-sans-first:45.92 19:03:32 INFO - PID 4180 | Values: 79.0 76.0 76.0 176.0 74.0 74.0 73.0 75.0 181.0 73.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:82.00 stddev:26.01 (31.7%) stddev-sans-first:26.98 19:03:32 INFO - PID 4180 | Values: 83.0 82.0 138.0 84.0 82.0 82.0 132.0 81.0 82.0 138.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.79 (0.7%) stddev-sans-first:0.71 19:03:32 INFO - PID 4180 | Values: 113.0 114.0 114.0 114.0 114.0 114.0 114.0 116.0 115.0 114.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#6] imaging-darkroom Cycles:10 Average:150.40 Median:150.50 stddev:0.52 (0.3%) stddev-sans-first:0.53 19:03:32 INFO - PID 4180 | Values: 150.0 151.0 151.0 150.0 150.0 151.0 150.0 150.0 150.0 151.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 19:03:32 INFO - PID 4180 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.23 (2.3%) stddev-sans-first:1.27 19:03:32 INFO - PID 4180 | Values: 53.0 56.0 55.0 53.0 53.0 54.0 54.0 55.0 53.0 52.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#9] json-stringify-tinderbox Cycles:10 Average:55.80 Median:56.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 19:03:32 INFO - PID 4180 | Values: 56.0 55.0 56.0 56.0 55.0 56.0 56.0 57.0 55.0 56.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:84.00 stddev:6.92 (8.2%) stddev-sans-first:7.02 19:03:32 INFO - PID 4180 | Values: 81.0 82.0 84.0 82.0 84.0 92.0 85.0 82.0 102.0 94.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:136.50 stddev:5.83 (4.3%) stddev-sans-first:6.16 19:03:32 INFO - PID 4180 | Values: 134.0 134.0 131.0 143.0 145.0 136.0 137.0 128.0 128.0 140.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.10 Median:191.50 stddev:6.37 (3.3%) stddev-sans-first:6.72 19:03:32 INFO - PID 4180 | Values: 191.0 196.0 194.0 197.0 182.0 185.0 192.0 190.0 187.0 177.0 19:03:32 INFO - PID 4180 | 19:03:32 INFO - PID 4180 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.90 Median:72.50 stddev:3.18 (4.4%) stddev-sans-first:3.37 19:03:32 INFO - PID 4180 | Values: 73.0 72.0 69.0 72.0 79.0 77.0 70.0 71.0 75.0 71.0 19:03:32 INFO - PID 4180 | -------- Summary: end -------- 19:03:32 INFO - PID 4180 | 19:03:33 INFO - TEST-INFO | 4180: exit 0 19:03:33 INFO - TEST-OK | kraken | took 91207ms 19:03:33 INFO - SUITE-END | took 1234s 19:03:33 INFO - Completed test suite (00:20:34) 19:03:33 INFO - javascript benchmark 19:03:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7904.957566852388, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3076.6923307669235, 3104.937901241974, 3112.875484980601, 3128.9061328160165, 3130.62432508099, 5798.855028624286, 5825.708714564276, 5886.146508756227, 5959.463648271655, 5979.491743201827, 5850.444207800252, 5854.356020837705, 5891.528677705784, 5954.345022047572, 5966.850828729284, 5396.0, 5490.0, 5518.530924871391, 5519.0, 5534.142207957753, 5567.916481252764, 5592.217089607459, 5593.3847276799725, 5610.607257491977, 5613.214150019001, 5361.490658387486, 5452.972735136339, 5465.152901300305, 5519.530839878595, 5559.277293951801, 5474.972625136888, 5516.696581688006, 5523.4200408956885, 5524.1989911462615, 5538.833834984957, 5446.509814116748, 5461.344638643349, 5475.206095116226, 5478.041342765, 5479.479449452296, 5793.073108302693, 5871.089981052901, 5910.527157827363, 5931.792387266425, 5933.080372542263, 6287.52843536732, 6316.873662526769, 6375.968120159416, 6414.903776443357, 6424.38968298011, 6225.657588832612, 6230.59501132425, 6233.719482623293, 6243.313235544087, 6250.9062364064575, 5766.971165144189, 5794.594378393472, 5798.898209340254, 5802.0, 5836.2996440427, 6366.936330636727, 6446.290908000116, 6516.641584712792, 6569.80290591283, 6614.735410583619, 6069.878602427927, 6108.129596501707, 6148.754049838046, 6187.195664563578, 6198.938010619836, 6334.9366506334345, 6336.936630633634, 6342.8414289642205, 6351.745930162834, 6367.363263673596, 6293.748250069946, 6312.7790527332245, 6333.936660633335, 6335.081413195061, 6338.7464501420345, 6139.600925939889, 6180.381961803783, 6195.2255968004, 6201.844953876189, 6266.530010249249, 5101.234814777813, 5107.055194788976, 5127.53852153308, 5135.409427915837, 5172.120739474299, 4379.693421460467, 4391.8462853799965, 4397.6042156206095, 4402.273624851863, 4426.933595996063, 5507.917381239285, 5516.724163791794, 5549.556035517149, 5563.109902415594, 5639.407862174413, 6475.546711730133, 6481.351864813575, 6484.319146489645, 6502.837429064217, 6510.153680021567, 5612.410696876769, 5616.185653080281, 5616.213730077792, 5627.2413947897, 5665.206871038058, 5582.274304340491, 5643.322801263875, 5717.17101020358, 5718.027935251017, 5732.34078081026, 6036.9698151508965, 6111.991521399008, 6120.908186377119, 6123.8162855114415, 6126.142340072393, 6366.076918846648, 6399.616023038632, 6405.167328247298, 6405.711742971671, 6412.487001039813, 6128.846778830565, 6156.230471191101, 6173.41352571505, 6177.413145751147, 6193.969030154821, 6158.107074474086, 6179.938200617937, 6264.31092579825, 6356.650384228912, 6365.936340636535, 6416.0, 6428.774992875227, 6438.259600146043, 6443.130177425987, 6472.611643301417, 6112.205413296243, 6121.571489995657, 6154.815355539341, 6204.193454851021, 6236.939720247478, 6113.29387775398, 6231.470325022424, 6232.626042437287, 6240.188775459162, 6249.406306400884, 5938.524918006636, 5970.522358211246, 6117.888303246575, 6241.157443745218, 6258.593191442542, 6296.0555916612875, 6347.96826015867, 6351.460125889351, 6384.170057892445, 6415.1981002374705, 6121.602095863933, 6162.07568864656, 6191.19514463135, 6200.100985357189, 6210.975189093749], "value": 5816.562840412906, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.8917778566537, 682.4097155960094, 714.143028365961, 1191.1007189571867, 1241.7268200995777, 36934.26131477369, 38955.805220973925, 39021.268511204195, 39043.609563904356, 39102.60897391025, 37064.0, 37282.8135859321, 37387.25225495488, 37476.43785343222, 37533.249335013286, 6910.516263861544, 7246.130464344286, 7450.515716478439, 7451.2921272479025, 7464.552126872378, 7359.852802943938, 7424.331810137087, 7459.0, 7464.066991626047, 7466.887996680054, 7448.590327531983, 7488.176300606929, 7493.550386976799, 7495.138059123189, 7509.774706758779, 7395.371393431511, 7428.405727541784, 7437.51656142349, 7446.9627651861665, 7496.662650180755, 7299.343059124704, 7352.963235183844, 7402.555846649218, 7403.0, 7408.111026676779, 36220.27559448822, 36300.09249768777, 36418.63581364205, 36466.81766591177, 36671.266574668625, 3531.2407832315916, 3568.518250036264, 3571.0, 3577.8032208228537, 3590.317839610466, 27579.448411031866, 27655.30861728453, 27759.167224983285, 27846.443071138667, 27887.721122788716, 3143.528470729386, 3171.9365612687616, 3187.7768556201077, 3205.4550726376574, 3210.3739770744714, 133099.0, 136894.31552842172, 169705.0, 170773.0, 170786.0, 38689.6131038686, 39155.216895662496, 39283.21433571313, 39353.0, 39525.0, 40881.59118408837, 41194.79402603028, 41293.0, 41314.586854131674, 51513.0, 15024.474143404928, 15156.469523566622, 15196.392144314324, 15197.088174709554, 15262.923685381502, 12241.510339586495, 12318.199317044364, 12333.136680432463, 12354.814677779841, 12358.50565977351, 14522.20127892955, 14581.781273280909, 14670.63323416923, 14681.412743490355, 14879.776803347959, 5856.736446859902, 5857.970710146507, 5982.880342393128, 5998.220231369894, 6038.879222415615, 993.3940296419179, 994.9801003979879, 997.2371136080903, 1046.3721766939775, 1051.5636011055326, 23055.884720576625, 24493.142740004016, 24814.751852481244, 24873.378165545644, 24901.12846050416, 1105.9170562207867, 1106.7620461600795, 1125.6341688951125, 1138.5275110828914, 1141.7545227776068, 12518.374081295899, 13086.607401777961, 13161.60515184546, 13294.933525332504, 16262.593435163979, 206412.0, 206705.0, 207759.0, 208409.0, 208834.0, 55404.0, 55867.44132558704, 56447.435525645036, 56481.7175914126, 56571.43428565744, 1454.9054311469931, 1458.1105525629357, 1487.2192099148033, 1488.5757559095605, 1494.5177735875384, 26439.471210576066, 28457.0, 29091.0, 29249.0, 29304.0, 37048.81475592605, 45548.31677524906, 48134.277985830944, 48202.75898620485, 48427.75786121188, 173409.0, 174599.0, 176871.0, 176992.0, 177100.0, 34864.954051378496, 35080.0, 35085.29829403449, 35504.82247588746, 35650.643493564734, 45140.77429612831, 45900.0, 46912.0, 47301.763491182326, 47797.0, 32857.0, 34251.82874085614, 34535.82732086424, 35097.0, 35684.0, 32896.342073158885, 33345.166370840925, 33712.3257534843, 33859.83070084634, 35192.12019699528, 37971.32947288511, 38226.617733822306, 38275.80862095783, 38507.42238866363, 38808.0, 32321.838390807894, 33276.50085248772, 33968.490472643425, 34069.0, 34783.0, 25980.35049123787, 26737.33156671021, 26899.73100268972, 26995.0, 27057.0], "value": 16844.321406179723, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.7237074867325, 495.19777959705283, 497.7287378378782, 504.6265763335131, 515.626171026006, 19466.0, 20017.99999999998, 20233.39299821006, 21051.0, 23137.19019834306, 23229.419264518354, 23841.16555920543, 23872.28383148508, 23885.761142388572, 23893.28320150394, 6134.907976380347, 6296.811095667114, 6298.118263443099, 6308.211473565804, 6312.873742525147, 6178.320384757673, 6206.224221972254, 6235.469985051276, 6237.625742455444, 6240.812775616716, 5705.572082093839, 5969.194158788551, 5983.1025346197985, 5994.220751302325, 6041.4562689357945, 5950.702464876739, 5952.315483719384, 5962.79130230442, 5994.0409534474275, 6000.6099603525845, 5514.255575497336, 5519.69641669708, 5535.114381698949, 5546.251256080418, 5548.472895074961, 23636.936337864838, 24031.639525407132, 24174.274771756875, 24365.269041928772, 24455.877720611283, 1810.1401834128815, 1831.285798538571, 1843.889366638006, 1857.136431559318, 1865.6362009408172, 17016.0, 17440.21519031634, 17445.563860903578, 17459.0, 17478.475645730523, 2258.9661155082686, 2261.0164578408444, 2311.3759284992957, 2333.5916214662534, 2346.272655476808, 112536.0, 117176.0, 117880.41059794732, 118382.0, 118878.0, 13126.934365328303, 13233.669158271121, 13320.866791332157, 13365.599032029055, 13412.798808017887, 20437.38687839367, 20450.0, 20465.79534204639, 20469.692954605693, 20476.488087797923, 8825.20573148407, 9264.583093760797, 9328.580213890391, 9372.437653740797, 9459.574319155518, 8730.38887277897, 8775.517346545874, 8857.158569935846, 8935.642574297086, 8966.775830604156, 8220.547869867214, 8295.62669679866, 8344.62449189788, 8446.155384461505, 8538.188872057144, 2322.210448447536, 2326.139328448451, 2344.9648255276184, 2417.2385698504913, 2450.4486490539844, 924.7688077980505, 924.8751418558543, 929.5352323838081, 971.159946646146, 971.655062452819, 18883.0, 19737.605247895248, 20185.394438166877, 20271.4932126698, 20746.585068298853, 959.3716115944068, 970.5583959298502, 992.5979978108877, 997.6258902911409, 1011.7774089700254, 10022.398656080659, 10166.949165254126, 10539.94730026345, 10638.468076596138, 10835.349879007284, 411173.0, 412192.0, 418218.0, 418434.0, 418459.0, 95739.52130239304, 99224.0, 101574.49212753889, 102267.4886625562, 102635.0, 1140.8267250887072, 1180.492388273051, 1234.0559472003924, 1258.6916205529888, 1260.4517035089582, 18736.344227951955, 19967.72952748277, 20304.79695203029, 20545.383638490868, 20749.377518674464, 48592.0, 48690.0, 49354.0, 50320.74839625778, 50449.747751262476, 61199.38800611937, 61287.0, 61450.0, 62025.06962395658, 62228.688856555425, 10952.178586606196, 11581.536738530365, 11651.41742912834, 11689.181757276909, 11712.765744685228, 17139.571510712332, 17444.47666570005, 17564.121793910508, 17968.46094617111, 18139.0, 14039.227842468763, 14075.0, 14210.573682789533, 15161.772573411186, 15336.079835209923, 12483.257433699395, 12747.936260318638, 12808.551700690432, 13065.346732663138, 13171.73656526845, 14682.192479413308, 14709.485168019068, 14970.17664028489, 15047.699046018799, 15066.472673456376, 11451.312921224753, 11528.82706759416, 11579.421028948687, 11582.305061695988, 11810.173287869766, 13689.589312320646, 13902.930485347508, 13956.2324072177, 13984.0, 13991.370388332753], "value": 10647.861838596444, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1307.3136603283276, 1352.52661568451, 1352.9526466573666, 1356.1117468058426, 1362.8432730236025, 11798.0, 12377.133600647947, 12399.380030998447, 12536.81194782079, 12825.871741282584, 11949.223300485484, 12083.758324833498, 12107.515699372014, 12147.271163730182, 12205.389730513483, 2447.49826285611, 2531.962020569693, 2533.227365653475, 2533.670622819031, 2539.746025397464, 2429.987850060756, 2537.314924970257, 2538.365408647838, 2538.8984440622353, 2538.9492210155786, 2540.530001949636, 2542.377117606189, 2565.551028569993, 2570.678665166854, 2579.4067364506177, 2496.1388321029217, 2508.122157244959, 2514.660520829683, 2524.911628093008, 2538.7080485744004, 2232.1071571371417, 2258.5432396104584, 2276.6129757941026, 2281.851679640818, 2286.668433077211, 12326.383680815923, 12413.565525206664, 12440.0, 12479.188852724634, 12491.812622810665, 6629.469642428642, 6967.163940327193, 6971.477139214579, 6976.825579360506, 6977.720891164347, 4052.4529188559463, 4141.855035073758, 4199.68502362321, 4221.598948099926, 4243.321068629005, 1306.745184688986, 1313.192386682193, 1318.6044186743939, 1337.0306527767389, 1342.0873805812043, 18743.906280468647, 19178.616427671368, 19182.52043698905, 19206.519837004187, 19388.806111938982, 5689.943100569024, 5720.713964301768, 5893.174955506232, 5926.496247818984, 5953.702314884238, 13856.376463059187, 13934.930325348309, 13937.930310348384, 13988.790168147487, 14008.0, 6689.665516724144, 6775.932240677629, 6776.085228494188, 6786.117804685458, 6796.864062718718, 6245.469135123474, 6341.619502829845, 6368.10846481493, 6381.074744162071, 6462.903056454157, 4380.342948557742, 4480.484744254387, 4567.794449249792, 4596.448426188878, 4597.7241365518175, 1232.2421710647886, 1242.714175739585, 1254.4229654358915, 1254.4856608790535, 1265.974680506403, 836.180543067797, 860.4794099569796, 869.1352104655781, 884.0496466298755, 897.9595918183697, 6449.709763060673, 6515.609063456208, 6636.2368327642935, 6819.863602728017, 7417.184109747924, 693.8369483171551, 710.7441321124392, 711.1324184494908, 712.6650474277081, 714.7819914925998, 4051.0, 4099.344104943195, 4109.527404348479, 4188.623023927861, 4226.852060177879, 15614.906956512936, 16272.755908661144, 16359.0, 16360.836391636169, 16372.426965056404, 15430.0, 16003.839961600468, 16032.919835400748, 16081.115538645252, 16092.195390230441, 9267.860982085274, 9605.951970240105, 9855.162311203485, 9888.802223955481, 9997.650082247232, 6950.721971121098, 7266.091738532684, 7446.73936412223, 7556.8488630228185, 7677.616119193906, 14903.552893413214, 15044.849551504345, 15138.318775655342, 15166.469173578871, 15335.693286134438, 15085.54743357701, 15086.321115549606, 15171.468998585438, 15321.157336346609, 15395.691366233996, 9560.713178604654, 10035.849462258218, 10053.296269261082, 10089.697309080739, 10112.544935478069, 11653.825192622287, 11726.589569365031, 11760.05919526453, 11762.94118529402, 11764.764704706029, 9315.441073535609, 9497.715068547956, 9572.808543828945, 9595.47224902637, 9871.703848884545, 9548.713538593853, 9729.318947673595, 9740.75648108803, 9798.657047003606, 9988.200943924614, 8436.915630843612, 8545.316374689886, 8644.0, 8646.0, 8651.091635378194, 6036.577439579363, 6285.528585356025, 6323.272823625342, 6436.742530298736, 6488.091667166506, 5904.52763778888, 5968.134620480093, 6143.354947730602, 6152.077188421773, 6155.4767844731905], "value": 5604.821702297547, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.6616174736717, 443.707153278836, 447.45634054623633, 456.8789270843225, 458.73164198943624, 6362.236531616207, 6384.680765961706, 6421.550491465599, 6426.7750628728, 6430.3569643035735, 6130.632162070279, 6171.598846075002, 6198.876022479548, 6223.782167624134, 6269.279032911217, 4740.810367585293, 4880.731559764212, 4895.21676531755, 5018.598512119031, 5030.874228144289, 4904.60763138948, 4912.0, 4916.606671466274, 4919.72941488218, 4922.114019476493, 4823.421189457253, 4832.661713680044, 4835.8549243522575, 4848.393950756155, 4864.1730905745935, 4844.370231869852, 4851.884066664669, 4874.805007799684, 4876.804927802884, 4888.706677599355, 4323.740575565476, 4327.242732521828, 4345.978270108661, 4350.412694286294, 4353.782310884475, 6092.147099406086, 6099.383663329221, 6110.938890611082, 6184.443944731797, 6193.194884665009, 5582.609217354787, 5600.103983362643, 5604.355499117612, 5604.8038318658655, 5606.831795046155, 6586.407223349922, 6627.138471998609, 6648.900266496006, 6651.833704157387, 6663.300353462913, 4705.12955103307, 4719.622430205575, 4725.598324142417, 4728.763561821896, 4729.290606409032, 6614.9338506614795, 6921.238663746984, 6925.930740692579, 6932.826679333007, 7050.391355198558, 4776.641751868624, 4788.712677239377, 4825.0, 4832.903341933212, 4835.419749630067, 4789.568938795525, 4836.234774307422, 4844.128056949783, 4850.878728031827, 4865.537773911473, 5375.0, 5480.3971563128025, 5526.668399896019, 5542.806001789999, 5553.50018498329, 6596.646213499068, 6601.141851559362, 6622.2715501294815, 6648.4681225501845, 6668.299828518033, 1676.1200369805706, 1696.7200411931892, 1697.4313604942236, 1699.541123896541, 1702.34021828716, 1128.8250321200228, 1155.1625071822862, 1168.9181757277079, 1174.8590169179754, 1176.2707121584672, 5856.267966504187, 5862.325832529228, 5870.970645146833, 5895.056790913433, 5936.436038576328, 6783.966080169666, 6784.660766961632, 6815.863682726318, 6816.488763342769, 6819.0, 5859.700821616694, 5907.23205983228, 5919.940800592025, 5920.763169473259, 5927.9703601481715, 3657.8536858525363, 3663.4871118043493, 3667.249562827879, 3679.981600092036, 3685.0, 6645.1361323027695, 6646.202455705346, 6651.16860392451, 6655.0, 6656.201255849232, 6935.757248496379, 7009.264027277164, 7037.964810176019, 7054.96636446333, 7071.82320441993, 6561.770338038145, 6572.14562106923, 6606.795704083515, 6621.27166011748, 6621.3047629998155, 6822.897656535057, 6830.59016459014, 6855.897161542681, 6874.9656251718425, 6889.724411023503, 6421.646809425526, 6758.695858686273, 6791.422729068085, 6847.289656136914, 6868.278830722702, 6555.672216389257, 6585.670716464062, 6629.337066293332, 6634.701438435379, 6677.122563759335, 4783.425988881357, 4799.112164249625, 4802.8799280018275, 4819.542143496362, 4820.517948205292, 6649.800505984828, 6852.246252912275, 6879.828004299932, 6893.690613644485, 6896.517243792886, 6687.799366019027, 6689.364510371507, 6697.0958920547055, 6701.229358623821, 6723.865522689421, 6650.168728908887, 6670.366315200048, 6672.933270667231, 6703.162104736908, 6705.22889867652, 6508.511861610493, 6538.378854008862, 6572.605643661395, 6591.34086591337, 6627.271000190071, 6670.899936501053, 6730.0, 6730.831729206613, 6731.124953755981, 6733.326667333424, 6470.773522926675, 6484.70818813164, 6485.189351331083, 6507.284198738041, 6507.837304067436], "value": 5033.919004733598, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.1609397474829, 768.5427170833356, 800.1438460846892, 819.2094628683321, 832.7002279179494, 16735.246913888874, 17278.740818887727, 17314.480565583053, 18204.453866383996, 19853.602927941432, 17813.73279400807, 18149.63700725988, 18275.543111422223, 18319.0, 18581.442556723287, 967.5597603090577, 1008.3899240959224, 1020.122694482748, 1020.1634659579148, 1020.5866624017248, 970.6457143142773, 993.4436715439377, 1022.1720406470782, 1022.3303736052898, 1025.2874252394588, 1065.3288428290166, 1067.1676092647747, 1070.0476575847501, 1070.561069961316, 1071.1002757683543, 1050.1913526584526, 1052.2003277509073, 1054.7099547624389, 1057.0856209378908, 1058.2962330050507, 771.0285040848515, 773.2422226218338, 778.7118766056595, 779.0378882080682, 779.7309928074861, 15902.76145857813, 16985.405510807184, 17028.48914532566, 17062.06158661273, 17189.656206875792, 18799.24803007878, 18929.05354732258, 19107.426777196706, 19202.423927282205, 19235.807641923682, 14569.927150364181, 15066.924665376713, 15103.697926041528, 15163.0, 15179.772303415348, 3358.983205083983, 3435.707964822993, 3456.0495863637448, 3484.0418884806627, 3510.8771193008124, 79282.6035869817, 80516.59741701254, 80679.0, 80978.59510702408, 81353.59323203345, 14161.858381416054, 14734.484293049692, 14752.0, 14960.401583936738, 15075.321610527553, 25627.0, 25763.0, 25789.871050644626, 25811.35471613187, 26216.60675089875, 7915.881261781078, 7964.0, 8437.198466145706, 8438.198371154731, 8454.535000575028, 4144.316187829035, 4235.576442355801, 4243.469566304212, 4252.957470425256, 4263.168682107022, 4409.294512877924, 4521.299198624219, 4534.614557762628, 4966.726830024311, 5118.539331460186, 882.2280504558511, 894.3292530602048, 908.8136931928939, 909.1817364371985, 910.7586489580134, 597.265363602771, 617.108278537518, 632.585656395062, 637.1907677249959, 641.6503005861816, 10882.455877206108, 11343.319400835977, 11750.882491174978, 12269.141160118706, 12483.126181167412, 625.2434554189482, 641.3298103481873, 646.7930262316107, 658.8122385120314, 674.7199912036548, 5021.59827213822, 5131.61512886535, 5141.280220769096, 5376.112941364631, 5537.723113844372, 88615.55692221498, 91090.0, 92470.0, 96671.51664241774, 97469.51265243629, 78922.60538697384, 79958.0, 80297.0, 80597.19402806014, 81291.0, 70695.64652176706, 71199.64400177966, 71261.64369178224, 71498.64250678713, 74230.62884685434, 10232.334898231591, 10855.0, 10905.454727263765, 11211.0, 11548.0, 28536.003719609143, 28997.56503652489, 29383.265418363855, 29638.0, 30222.244443888198, 55055.72472137774, 56128.43871561232, 56553.0, 56698.7165064172, 56840.0, 16991.575210619834, 17260.482185534453, 17578.472645820133, 17627.41353278211, 17642.82357176412, 18673.53316167107, 19671.606567868275, 19689.093470367858, 19747.506312342306, 20133.0, 16996.915015424845, 17085.914570427565, 17183.656326873144, 17922.91038544799, 18249.178786954355, 16673.0, 17272.309107635552, 17601.383951561635, 17949.910250448665, 18063.2774689011, 15352.155631439931, 16469.58826029311, 16587.17064146812, 16837.831621684116, 17149.056801876017, 11975.401229938643, 11996.0, 12411.937940310241, 12531.93734031324, 12586.496540138658, 12566.623001309976, 12881.48474061027, 13002.934985325013, 13176.47294108225, 13360.532381366995], "value": 8289.324459686293, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 109.0, 104.0, 109.0, 109.0, 112.0, 110.0, 102.0, 108.0, 112.0], "value": 107.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 147.0, 179.0, 131.0, 145.0, 153.0, 127.0, 169.0, 120.0], "value": 145.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 164.0, 161.0, 165.0, 173.0, 170.0, 165.0, 174.0, 163.0], "value": 166.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 76.0, 176.0, 74.0, 74.0, 73.0, 75.0, 181.0, 73.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 138.0, 84.0, 82.0, 82.0, 132.0, 81.0, 82.0, 138.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0, 116.0, 115.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 151.0, 150.0, 150.0, 151.0, 150.0, 150.0, 150.0, 151.0], "value": 150.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 55.0, 53.0, 53.0, 54.0, 54.0, 55.0, 53.0, 52.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 56.0, 55.0, 56.0, 56.0, 57.0, 55.0, 56.0], "value": 55.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 84.0, 82.0, 84.0, 92.0, 85.0, 82.0, 102.0, 94.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 134.0, 131.0, 143.0, 145.0, 136.0, 137.0, 128.0, 128.0, 140.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 196.0, 194.0, 197.0, 182.0, 185.0, 192.0, 190.0, 187.0, 177.0], "value": 189.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 69.0, 72.0, 79.0, 77.0, 70.0, 71.0, 75.0, 71.0], "value": 72.9, "unit": "score"}]}]} 19:03:33 INFO - Return code: 0 19:03:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:03:33 INFO - # TBPL SUCCESS # 19:03:33 INFO - Running post-action listener: _resource_record_post_action 19:03:33 INFO - [mozharness: 2017-06-09 02:03:33.963472Z] Finished run-tests step (success) 19:03:33 INFO - Running post-run listener: _resource_record_post_run 19:03:34 INFO - Total resource usage - Wall time: 1252s; CPU: 13.0%; Read bytes: 8454144; Write bytes: 426496000; Read time: 1640; Write time: 283532 19:03:34 INFO - TinderboxPrint: CPU usage
12.6% 19:03:34 INFO - TinderboxPrint: I/O read bytes / time
8,454,144 / 1,640 19:03:34 INFO - TinderboxPrint: I/O write bytes / time
426,496,000 / 283,532 19:03:34 INFO - TinderboxPrint: CPU idle
8,730.3 (87.4%) 19:03:34 INFO - TinderboxPrint: CPU user
1,208.9 (12.1%) 19:03:34 INFO - TinderboxPrint: Swap in / out
0 / 0 19:03:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 102141952; Read time: 0; Write time: 57228 19:03:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:03:34 INFO - run-tests - Wall time: 1236s; CPU: 13.0%; Read bytes: 6221824; Write bytes: 324354048; Read time: 1524; Write time: 226304 19:03:34 INFO - Running post-run listener: _upload_blobber_files 19:03:34 INFO - Blob upload gear active. 19:03:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:03:34 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:03:34 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:03:34 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:03:35 INFO - (blobuploader) - INFO - Open directory for files ... 19:03:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:03:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:03:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:03:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:03:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:03:36 INFO - (blobuploader) - INFO - Done attempting. 19:03:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:03:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:03:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:03:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:03:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:03:36 INFO - (blobuploader) - INFO - Done attempting. 19:03:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:03:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:03:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:03:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:03:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:03:43 INFO - (blobuploader) - INFO - Done attempting. 19:03:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:03:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:03:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:03:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:03:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:03:44 INFO - (blobuploader) - INFO - Done attempting. 19:03:44 INFO - (blobuploader) - INFO - Iteration through files over. 19:03:44 INFO - Return code: 0 19:03:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:03:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:03:44 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d82726c1baaa9628fe7ff9c5946de952e87bd33191b24e4afb69a8d7e94257a16517fe8d076e10cf3a4ba9c299ffd892809cf01da77160c3bdb02815cd5164de", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4730b40ea59dc49bc0018ce5c3da48ca5aa6185cc4a4cb9b8b161d81028582b68cb5147e81e16dd8687c3c9bcdcd4fdfe8c062ab1d4d3081bf39d6c6567d96b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1d4f2c88d8393d7a511a1e19da69cfe8978e2b3bbc1d02f6491d25688ea7445dd01d64e6d4495675f52724c9b0def271a39e634a08fd5f0305755ff5a31ae21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9aa4b6bab3cdbce33f409b2b2125287174e8169ce8cd8592ebabb7ac79c78083cfe4f603528df2a098a426636c6be155069214444da77594dabb231abcb83d27"} 19:03:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:03:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:03:44 INFO - Contents: 19:03:44 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d82726c1baaa9628fe7ff9c5946de952e87bd33191b24e4afb69a8d7e94257a16517fe8d076e10cf3a4ba9c299ffd892809cf01da77160c3bdb02815cd5164de", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4730b40ea59dc49bc0018ce5c3da48ca5aa6185cc4a4cb9b8b161d81028582b68cb5147e81e16dd8687c3c9bcdcd4fdfe8c062ab1d4d3081bf39d6c6567d96b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1d4f2c88d8393d7a511a1e19da69cfe8978e2b3bbc1d02f6491d25688ea7445dd01d64e6d4495675f52724c9b0def271a39e634a08fd5f0305755ff5a31ae21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9aa4b6bab3cdbce33f409b2b2125287174e8169ce8cd8592ebabb7ac79c78083cfe4f603528df2a098a426636c6be155069214444da77594dabb231abcb83d27"} 19:03:44 INFO - Running post-run listener: copy_logs_to_upload_dir 19:03:44 INFO - Copying logs to upload dir... 19:03:44 INFO - mkdir: /builds/slave/test/build/upload/logs 19:03:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1318.393628 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 58 secs) (at 2017-06-08 19:03:44.087590) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 19:03:44.091406) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365 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/d82726c1baaa9628fe7ff9c5946de952e87bd33191b24e4afb69a8d7e94257a16517fe8d076e10cf3a4ba9c299ffd892809cf01da77160c3bdb02815cd5164de", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4730b40ea59dc49bc0018ce5c3da48ca5aa6185cc4a4cb9b8b161d81028582b68cb5147e81e16dd8687c3c9bcdcd4fdfe8c062ab1d4d3081bf39d6c6567d96b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1d4f2c88d8393d7a511a1e19da69cfe8978e2b3bbc1d02f6491d25688ea7445dd01d64e6d4495675f52724c9b0def271a39e634a08fd5f0305755ff5a31ae21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9aa4b6bab3cdbce33f409b2b2125287174e8169ce8cd8592ebabb7ac79c78083cfe4f603528df2a098a426636c6be155069214444da77594dabb231abcb83d27"} build_url:https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011257 build_url: 'https://queue.taskcluster.net/v1/task/N7l3ReNzRHiM6_49kPAeYQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d82726c1baaa9628fe7ff9c5946de952e87bd33191b24e4afb69a8d7e94257a16517fe8d076e10cf3a4ba9c299ffd892809cf01da77160c3bdb02815cd5164de", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4730b40ea59dc49bc0018ce5c3da48ca5aa6185cc4a4cb9b8b161d81028582b68cb5147e81e16dd8687c3c9bcdcd4fdfe8c062ab1d4d3081bf39d6c6567d96b9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a1d4f2c88d8393d7a511a1e19da69cfe8978e2b3bbc1d02f6491d25688ea7445dd01d64e6d4495675f52724c9b0def271a39e634a08fd5f0305755ff5a31ae21", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9aa4b6bab3cdbce33f409b2b2125287174e8169ce8cd8592ebabb7ac79c78083cfe4f603528df2a098a426636c6be155069214444da77594dabb231abcb83d27"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 19:03:44.115295) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 19:03:44.115612) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1496965364.213923-910747365 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004389 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 19:03:44.198895) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 19:03:44.199231) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 19:03:44.199517) ========= ========= Total master_lag: 0.15 =========