builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-085 starttime: 1496279455.48 results: success (0) revision: a8f378825e81daff1279a7d6e940b610912ee6dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:55.482038) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:55.482433) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:55.482729) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:55.512180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:55.512457) ========= 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=5c37b704b588aaa989324530000001d0-1496277235.346173-57920793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-05-31 18:10:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.7M=0s 2017-05-31 18:10:56 (42.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.089113 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:55.614163) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:55.614444) ========= 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=5c37b704b588aaa989324530000001d0-1496277235.346173-57920793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023017 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:55.664669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:55.664977) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a8f378825e81daff1279a7d6e940b610912ee6dc --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 a8f378825e81daff1279a7d6e940b610912ee6dc --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=5c37b704b588aaa989324530000001d0-1496277235.346173-57920793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-05-31 18:10:56,331 truncating revision to first 12 chars 2017-05-31 18:10:56,331 Setting DEBUG logging. 2017-05-31 18:10:56,331 attempt 1/10 2017-05-31 18:10:56,331 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a8f378825e81?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 18:10:56,832 unpacking tar archive at: mozilla-central-a8f378825e81/testing/mozharness/ program finished with exit code 0 elapsedTime=0.839409 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:56.519630) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:56.519947) ========= script_repo_revision: a8f378825e81daff1279a7d6e940b610912ee6dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:56.523348) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:56.523623) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 18:10:56.532283) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2017-05-31 18:10:56.532546) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=5c37b704b588aaa989324530000001d0-1496277235.346173-57920793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:10:57 INFO - MultiFileLogger online at 20170531 18:10:57 in /builds/slave/test 18:10:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:10:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:10:57 INFO - {'append_to_log': False, 18:10:57 INFO - 'base_work_dir': '/builds/slave/test', 18:10:57 INFO - 'blob_upload_branch': 'Firefox', 18:10:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:10:57 INFO - 'branch': 'Firefox', 18:10:57 INFO - 'buildbot_json_path': 'buildprops.json', 18:10:57 INFO - 'config_files': ('talos/linux_config.py',), 18:10:57 INFO - 'default_actions': ('clobber', 18:10:57 INFO - 'read-buildbot-config', 18:10:57 INFO - 'download-and-extract', 18:10:57 INFO - 'populate-webroot', 18:10:57 INFO - 'create-virtualenv', 18:10:57 INFO - 'install', 18:10:57 INFO - 'setup-mitmproxy', 18:10:57 INFO - 'run-tests'), 18:10:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:10:57 INFO - 'download_minidump_stackwalk': True, 18:10:57 INFO - 'download_symbols': 'ondemand', 18:10:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:10:57 INFO - 'tooltool.py': '/tools/tooltool.py', 18:10:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:10:57 INFO - '/tools/misc-python/virtualenv.py')}, 18:10:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:10:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:10:57 INFO - 'gecko_profile': False, 18:10:57 INFO - 'gecko_profile_interval': 0, 18:10:57 INFO - 'installer_path': 'installer.exe', 18:10:57 INFO - 'log_level': 'info', 18:10:57 INFO - 'log_name': 'talos', 18:10:57 INFO - 'log_to_console': True, 18:10:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:10:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:10:57 INFO - 'opt_config_files': (), 18:10:57 INFO - 'pip_index': False, 18:10:57 INFO - 'suite': 'dromaeojs-e10s', 18:10:57 INFO - 'system_bits': '32', 18:10:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:10:57 INFO - 'title': 'talos-linux64-ix-085', 18:10:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:10:57 INFO - 'use_talos_json': True, 18:10:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:10:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:10:57 INFO - 'work_dir': 'build'} 18:10:57 INFO - [mozharness: 2017-06-01 01:10:57.297107Z] Running clobber step. 18:10:57 INFO - Running pre-action listener: _resource_record_pre_action 18:10:57 INFO - Running main action method: clobber 18:10:57 INFO - rmtree: /builds/slave/test/build 18:10:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:10:58 INFO - Running post-action listener: _resource_record_post_action 18:10:58 INFO - [mozharness: 2017-06-01 01:10:58.317979Z] Finished clobber step (success) 18:10:58 INFO - [mozharness: 2017-06-01 01:10:58.318080Z] Running read-buildbot-config step. 18:10:58 INFO - Running pre-action listener: _resource_record_pre_action 18:10:58 INFO - Running main action method: read_buildbot_config 18:10:58 INFO - Using buildbot properties: 18:10:58 INFO - { 18:10:58 INFO - "project": "", 18:10:58 INFO - "product": "firefox", 18:10:58 INFO - "who": "ryanvm@gmail.com", 18:10:58 INFO - "installer_path": "public/build/target.tar.bz2", 18:10:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 18:10:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:10:58 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 18:10:58 INFO - "stage_platform": "linux64-stylosequential", 18:10:58 INFO - "basedir": "/builds/slave/test", 18:10:58 INFO - "buildnumber": 40, 18:10:58 INFO - "platform": "ubuntu64_hw_styloseq", 18:10:58 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:10:58 INFO - "slavebuilddir": "test", 18:10:58 INFO - "taskId": "IXqxnnWURKSU4a9gALkc5g", 18:10:58 INFO - "branch": "mozilla-central", 18:10:58 INFO - "script_repo_revision": "production", 18:10:58 INFO - "revision": "a8f378825e81daff1279a7d6e940b610912ee6dc", 18:10:58 INFO - "slavename": "talos-linux64-ix-085", 18:10:58 INFO - "repo_path": "mozilla-central" 18:10:58 INFO - } 18:10:58 INFO - Finding installer, test and symbols from parent task. 18:10:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IXqxnnWURKSU4a9gALkc5g'}, attempt #1 18:10:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IXqxnnWURKSU4a9gALkc5g'}, attempt #1 18:10:59 INFO - Task dependencies: S2XffUwES2Oyfh8-LbSVjw 18:10:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw'}, attempt #1 18:10:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.tar.bz2 18:10:59 INFO - Set test_url: https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/test_packages.json 18:10:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.crashreporter-symbols.zip 18:10:59 INFO - Running post-action listener: _resource_record_post_action 18:10:59 INFO - [mozharness: 2017-06-01 01:10:59.696462Z] Finished read-buildbot-config step (success) 18:10:59 INFO - [mozharness: 2017-06-01 01:10:59.696684Z] Running download-and-extract step. 18:10:59 INFO - Running pre-action listener: _resource_record_pre_action 18:10:59 INFO - Running main action method: download_and_extract 18:10:59 INFO - mkdir: /builds/slave/test/build/tests 18:10:59 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:10:59 INFO - trying https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.test_packages.json 18:10:59 INFO - Downloading https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:10:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:11:00 INFO - Downloaded 986 bytes. 18:11:00 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:11:00 INFO - Using the following test package requirements: 18:11:00 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:11:00 INFO - u'common': [u'target.common.tests.zip'], 18:11:00 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:11:00 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:11:00 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:11:00 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:11:00 INFO - u'mozbase': [u'target.common.tests.zip'], 18:11:00 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:11:00 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:11:00 INFO - u'web-platform': [u'target.common.tests.zip', 18:11:00 INFO - u'target.web-platform.tests.zip'], 18:11:00 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:11:00 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:11:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.common.tests.zip 18:11:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:11:00 INFO - Fetch https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.common.tests.zip into memory 18:11:01 INFO - Content-Length response header: 63527520 18:11:01 INFO - Bytes received: 63527520 18:11:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:11:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.common.tests.zip 18:11:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:11:12 INFO - Fetch https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.common.tests.zip into memory 18:11:13 INFO - Content-Length response header: 63527520 18:11:13 INFO - Bytes received: 63527520 18:11:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.talos.tests.zip 18:11:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:11:25 INFO - Fetch https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.talos.tests.zip into memory 18:11:26 INFO - Content-Length response header: 14447425 18:11:26 INFO - Bytes received: 14447425 18:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:26 INFO - trying https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.tar.bz2 18:11:26 INFO - Downloading https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:11:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:11:28 INFO - Downloaded 55803120 bytes. 18:11:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.tar.bz2 18:11:28 INFO - mkdir: /builds/slave/test/properties 18:11:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:11:28 INFO - Writing to file /builds/slave/test/properties/build_url 18:11:28 INFO - Contents: 18:11:28 INFO - build_url:https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.tar.bz2 18:11:28 INFO - Running post-action listener: _resource_record_post_action 18:11:28 INFO - Running post-action listener: set_extra_try_arguments 18:11:28 INFO - [mozharness: 2017-06-01 01:11:28.753002Z] Finished download-and-extract step (success) 18:11:28 INFO - [mozharness: 2017-06-01 01:11:28.753230Z] Running populate-webroot step. 18:11:28 INFO - Running pre-action listener: _resource_record_pre_action 18:11:28 INFO - Running main action method: populate_webroot 18:11:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:11:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:11:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:11:28 INFO - '--tppagecycles', 18:11:28 INFO - '7'], 18:11:28 INFO - 'tests': ['tsvgm']}}, 18:11:28 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:11:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:11:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:11:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:11:28 INFO - 'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:11:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:11:28 INFO - 'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['damp', 'tps']}, 18:11:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:11:28 INFO - 'tests': ['damp', 'tps']}, 18:11:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['dromaeo_dom']}, 18:11:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:11:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:11:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:11:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:11:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:11:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['a11yr', 18:11:28 INFO - 'ts_paint', 18:11:28 INFO - 'tpaint', 18:11:28 INFO - 'sessionrestore', 18:11:28 INFO - 'sessionrestore_no_auto_restore', 18:11:28 INFO - 'tabpaint']}, 18:11:28 INFO - 'other-e10s': {'tests': ['a11yr', 18:11:28 INFO - 'ts_paint', 18:11:28 INFO - 'tpaint', 18:11:28 INFO - 'sessionrestore', 18:11:28 INFO - 'sessionrestore_no_auto_restore', 18:11:28 INFO - 'tabpaint']}, 18:11:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:11:28 INFO - 'ts_paint', 18:11:28 INFO - 'tpaint', 18:11:28 INFO - 'sessionrestore', 18:11:28 INFO - 'sessionrestore_no_auto_restore', 18:11:28 INFO - 'tabpaint']}, 18:11:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:11:28 INFO - 'ts_paint', 18:11:28 INFO - 'tpaint', 18:11:28 INFO - 'sessionrestore', 18:11:28 INFO - 'sessionrestore_no_auto_restore', 18:11:28 INFO - 'tabpaint']}, 18:11:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['a11yr', 18:11:28 INFO - 'ts_paint', 18:11:28 INFO - 'tpaint', 18:11:28 INFO - 'sessionrestore', 18:11:28 INFO - 'sessionrestore_no_auto_restore', 18:11:28 INFO - 'tabpaint']}, 18:11:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['a11yr', 18:11:28 INFO - 'ts_paint', 18:11:28 INFO - 'tpaint', 18:11:28 INFO - 'sessionrestore', 18:11:28 INFO - 'sessionrestore_no_auto_restore', 18:11:28 INFO - 'tabpaint']}, 18:11:28 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:11:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:11:28 INFO - 'bloom_basic_ref']}, 18:11:28 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:11:28 INFO - 'talos_options': ['--mitmproxy', 18:11:28 INFO - 'mitmproxy-recording-1.mp', 18:11:28 INFO - '--firstNonBlankPaint'], 18:11:28 INFO - 'tests': ['Quantum_1']}, 18:11:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['tsvgx', 18:11:28 INFO - 'tsvgr_opacity', 18:11:28 INFO - 'tart', 18:11:28 INFO - 'tscrollx', 18:11:28 INFO - 'cart', 18:11:28 INFO - 'tsvg_static']}, 18:11:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:11:28 INFO - 'tsvgr_opacity', 18:11:28 INFO - 'tart', 18:11:28 INFO - 'tscrollx', 18:11:28 INFO - 'cart', 18:11:28 INFO - 'tsvg_static']}, 18:11:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:11:28 INFO - 'talos_options': ['--disable-e10s'], 18:11:28 INFO - 'tests': ['tp5o']}, 18:11:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:11:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:11:28 INFO - 'talos_options': ['--disable-e10s', 18:11:28 INFO - '--xperf_path', 18:11:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:28 INFO - 'tests': ['tp5n']}, 18:11:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:11:28 INFO - 'talos_options': ['--xperf_path', 18:11:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:28 INFO - 'tests': ['tp5n']}}, 18:11:28 INFO - 'talos.zip': {'path': '', 18:11:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:11:28 INFO - Running post-action listener: _resource_record_post_action 18:11:28 INFO - [mozharness: 2017-06-01 01:11:28.769789Z] Finished populate-webroot step (success) 18:11:28 INFO - [mozharness: 2017-06-01 01:11:28.769890Z] Running create-virtualenv step. 18:11:28 INFO - Running pre-action listener: _resource_record_pre_action 18:11:28 INFO - Running main action method: create_virtualenv 18:11:28 INFO - Creating virtualenv /builds/slave/test/build/venv 18:11:28 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:11:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:11:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:11:28 INFO - Using real prefix '/usr' 18:11:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:11:29 INFO - Installing distribute.............................................................................................................................................................................................done. 18:11:31 INFO - Installing pip.................done. 18:11:31 INFO - Return code: 0 18:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:11:31 INFO - Reading from file tmpfile_stdout 18:11:31 INFO - Output received: 18:11:31 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:11:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:11:31 INFO - Using env: {'DISPLAY': ':0', 18:11:31 INFO - 'HOME': '/home/cltbld', 18:11:31 INFO - 'LANG': 'en_US.UTF-8', 18:11:31 INFO - 'LANGUAGE': 'en_US:en', 18:11:31 INFO - 'LOGNAME': 'cltbld', 18:11:31 INFO - 'MAIL': '/var/mail/cltbld', 18:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:31 INFO - 'MOZ_NO_REMOTE': '1', 18:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:31 INFO - 'NO_EM_RESTART': '1', 18:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:31 INFO - 'PWD': '/builds/slave/test', 18:11:31 INFO - 'SHELL': '/bin/bash', 18:11:31 INFO - 'SHLVL': '1', 18:11:31 INFO - 'TERM': 'linux', 18:11:31 INFO - 'TMOUT': '86400', 18:11:31 INFO - 'USER': 'cltbld', 18:11:31 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:31 INFO - '_': '/tools/buildbot/bin/python'} 18:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:31 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:11:31 INFO - Cleaning up... 18:11:31 INFO - Return code: 0 18:11:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:11:31 INFO - Using env: {'DISPLAY': ':0', 18:11:31 INFO - 'HOME': '/home/cltbld', 18:11:31 INFO - 'LANG': 'en_US.UTF-8', 18:11:31 INFO - 'LANGUAGE': 'en_US:en', 18:11:31 INFO - 'LOGNAME': 'cltbld', 18:11:31 INFO - 'MAIL': '/var/mail/cltbld', 18:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:31 INFO - 'MOZ_NO_REMOTE': '1', 18:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:31 INFO - 'NO_EM_RESTART': '1', 18:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:31 INFO - 'PWD': '/builds/slave/test', 18:11:31 INFO - 'SHELL': '/bin/bash', 18:11:31 INFO - 'SHLVL': '1', 18:11:31 INFO - 'TERM': 'linux', 18:11:31 INFO - 'TMOUT': '86400', 18:11:31 INFO - 'USER': 'cltbld', 18:11:31 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:31 INFO - '_': '/tools/buildbot/bin/python'} 18:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:31 INFO - Downloading/unpacking psutil>=3.1.1 18:11: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:11: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:11: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:11: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:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:11:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:11:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:11:33 INFO - Installing collected packages: psutil 18:11:33 INFO - Running setup.py install for psutil 18:11:33 INFO - building 'psutil._psutil_linux' extension 18:11:33 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:11:33 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:11:33 INFO - building 'psutil._psutil_posix' extension 18:11:33 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:11:33 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:11:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:11:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:11:33 INFO - Successfully installed psutil 18:11:33 INFO - Cleaning up... 18:11:33 INFO - Return code: 0 18:11:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:11: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:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11: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:11:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:11:33 INFO - Using env: {'DISPLAY': ':0', 18:11:33 INFO - 'HOME': '/home/cltbld', 18:11:33 INFO - 'LANG': 'en_US.UTF-8', 18:11:33 INFO - 'LANGUAGE': 'en_US:en', 18:11:33 INFO - 'LOGNAME': 'cltbld', 18:11:33 INFO - 'MAIL': '/var/mail/cltbld', 18:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:33 INFO - 'MOZ_NO_REMOTE': '1', 18:11:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:33 INFO - 'NO_EM_RESTART': '1', 18:11:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:33 INFO - 'PWD': '/builds/slave/test', 18:11:33 INFO - 'SHELL': '/bin/bash', 18:11:33 INFO - 'SHLVL': '1', 18:11:33 INFO - 'TERM': 'linux', 18:11:33 INFO - 'TMOUT': '86400', 18:11:33 INFO - 'USER': 'cltbld', 18:11:33 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:33 INFO - '_': '/tools/buildbot/bin/python'} 18:11:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:11:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:11:35 INFO - Installing collected packages: mozsystemmonitor 18:11:35 INFO - Running setup.py install for mozsystemmonitor 18:11:35 INFO - Successfully installed mozsystemmonitor 18:11:35 INFO - Cleaning up... 18:11:35 INFO - Return code: 0 18:11:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:11:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:35 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 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:35 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:11:35 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:11:35 INFO - Using env: {'DISPLAY': ':0', 18:11:35 INFO - 'HOME': '/home/cltbld', 18:11:35 INFO - 'LANG': 'en_US.UTF-8', 18:11:35 INFO - 'LANGUAGE': 'en_US:en', 18:11:35 INFO - 'LOGNAME': 'cltbld', 18:11:35 INFO - 'MAIL': '/var/mail/cltbld', 18:11:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:35 INFO - 'MOZ_NO_REMOTE': '1', 18:11:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:35 INFO - 'NO_EM_RESTART': '1', 18:11:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:35 INFO - 'PWD': '/builds/slave/test', 18:11:35 INFO - 'SHELL': '/bin/bash', 18:11:35 INFO - 'SHLVL': '1', 18:11:35 INFO - 'TERM': 'linux', 18:11:35 INFO - 'TMOUT': '86400', 18:11:35 INFO - 'USER': 'cltbld', 18:11:35 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:35 INFO - '_': '/tools/buildbot/bin/python'} 18:11:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:35 INFO - Downloading/unpacking jsonschema==2.5.1 18:11:35 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:11:35 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:11:35 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:11:35 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:11:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:11:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:11:36 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:11:36 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:11:36 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:11:36 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:11:36 INFO - Downloading functools32-3.2.3-2.tar.gz 18:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:11:36 INFO - warning: no files found matching '*.txt' 18:11:36 INFO - no previously-included directories found matching 'build' 18:11:36 INFO - no previously-included directories found matching 'dist' 18:11:36 INFO - no previously-included directories found matching '.git*' 18:11:36 INFO - Installing collected packages: jsonschema, functools32 18:11:36 INFO - Running setup.py install for functools32 18:11:36 INFO - warning: no files found matching '*.txt' 18:11:36 INFO - no previously-included directories found matching 'build' 18:11:36 INFO - no previously-included directories found matching 'dist' 18:11:36 INFO - no previously-included directories found matching '.git*' 18:11:36 INFO - Successfully installed jsonschema functools32 18:11:36 INFO - Cleaning up... 18:11:36 INFO - Return code: 0 18:11:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:11: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:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11: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:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:36 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 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:36 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:11:36 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:11:36 INFO - Using env: {'DISPLAY': ':0', 18:11:36 INFO - 'HOME': '/home/cltbld', 18:11:36 INFO - 'LANG': 'en_US.UTF-8', 18:11:36 INFO - 'LANGUAGE': 'en_US:en', 18:11:36 INFO - 'LOGNAME': 'cltbld', 18:11:36 INFO - 'MAIL': '/var/mail/cltbld', 18:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:36 INFO - 'MOZ_NO_REMOTE': '1', 18:11:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:36 INFO - 'NO_EM_RESTART': '1', 18:11:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:36 INFO - 'PWD': '/builds/slave/test', 18:11:36 INFO - 'SHELL': '/bin/bash', 18:11:36 INFO - 'SHLVL': '1', 18:11:36 INFO - 'TERM': 'linux', 18:11:36 INFO - 'TMOUT': '86400', 18:11:36 INFO - 'USER': 'cltbld', 18:11:36 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:36 INFO - '_': '/tools/buildbot/bin/python'} 18:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:11:37 INFO - Cleaning up... 18:11:37 INFO - Return code: 0 18:11:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:11:37 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:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:37 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:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:37 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 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:37 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:11:37 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:11:37 INFO - Using env: {'DISPLAY': ':0', 18:11:37 INFO - 'HOME': '/home/cltbld', 18:11:37 INFO - 'LANG': 'en_US.UTF-8', 18:11:37 INFO - 'LANGUAGE': 'en_US:en', 18:11:37 INFO - 'LOGNAME': 'cltbld', 18:11:37 INFO - 'MAIL': '/var/mail/cltbld', 18:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:37 INFO - 'MOZ_NO_REMOTE': '1', 18:11:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:37 INFO - 'NO_EM_RESTART': '1', 18:11:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:37 INFO - 'PWD': '/builds/slave/test', 18:11:37 INFO - 'SHELL': '/bin/bash', 18:11:37 INFO - 'SHLVL': '1', 18:11:37 INFO - 'TERM': 'linux', 18:11:37 INFO - 'TMOUT': '86400', 18:11:37 INFO - 'USER': 'cltbld', 18:11:37 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:37 INFO - '_': '/tools/buildbot/bin/python'} 18:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:37 INFO - Downloading/unpacking blobuploader==1.2.4 18:11: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:11: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:11: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:11: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:11:38 INFO - Downloading blobuploader-1.2.4.tar.gz 18:11:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:11:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:11:38 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:11:38 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:11:38 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:11:38 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:11:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:11:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:11:38 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:11:38 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:11:38 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:11:38 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:11:38 INFO - Downloading docopt-0.6.1.tar.gz 18:11:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:11:38 INFO - Installing collected packages: blobuploader, requests, docopt 18:11:38 INFO - Running setup.py install for blobuploader 18:11:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:11:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:11:39 INFO - Running setup.py install for requests 18:11:39 INFO - Running setup.py install for docopt 18:11:39 INFO - Successfully installed blobuploader requests docopt 18:11:39 INFO - Cleaning up... 18:11:39 INFO - Return code: 0 18:11:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11: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:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11: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:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:39 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 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:39 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:11:39 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:11:39 INFO - Using env: {'DISPLAY': ':0', 18:11:39 INFO - 'HOME': '/home/cltbld', 18:11:39 INFO - 'LANG': 'en_US.UTF-8', 18:11:39 INFO - 'LANGUAGE': 'en_US:en', 18:11:39 INFO - 'LOGNAME': 'cltbld', 18:11:39 INFO - 'MAIL': '/var/mail/cltbld', 18:11:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:39 INFO - 'MOZ_NO_REMOTE': '1', 18:11:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:39 INFO - 'NO_EM_RESTART': '1', 18:11:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:39 INFO - 'PWD': '/builds/slave/test', 18:11:39 INFO - 'SHELL': '/bin/bash', 18:11:39 INFO - 'SHLVL': '1', 18:11:39 INFO - 'TERM': 'linux', 18:11:39 INFO - 'TMOUT': '86400', 18:11:39 INFO - 'USER': 'cltbld', 18:11:39 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:39 INFO - '_': '/tools/buildbot/bin/python'} 18:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:11:39 INFO - Running setup.py (path:/tmp/pip-v5_i5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:11:39 INFO - Running setup.py (path:/tmp/pip-Bc1UTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:11:39 INFO - Running setup.py (path:/tmp/pip-fuXxVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:11:39 INFO - Running setup.py (path:/tmp/pip-ulLXSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:11:40 INFO - Running setup.py (path:/tmp/pip-xjCEcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:11:40 INFO - Running setup.py (path:/tmp/pip-U0ayL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:11:40 INFO - Running setup.py (path:/tmp/pip-zbYpU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:11:40 INFO - Running setup.py (path:/tmp/pip-DBYOWn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:11:40 INFO - Running setup.py (path:/tmp/pip-18rUOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:11:40 INFO - Running setup.py (path:/tmp/pip-G849I7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:11:40 INFO - Running setup.py (path:/tmp/pip-4bkuug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:11:40 INFO - Running setup.py (path:/tmp/pip-HzDd6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:11:40 INFO - Running setup.py (path:/tmp/pip-9xqmrc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:11:40 INFO - Running setup.py (path:/tmp/pip-VPEfP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:40 INFO - Running setup.py (path:/tmp/pip-DnKWSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:11:40 INFO - Running setup.py (path:/tmp/pip-8UQGOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:11:40 INFO - Running setup.py (path:/tmp/pip-L2apcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:11:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:11:40 INFO - Running setup.py install for manifestparser 18:11:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:11:41 INFO - Running setup.py install for mozcrash 18:11:41 INFO - Running setup.py install for mozdebug 18:11:41 INFO - Running setup.py install for mozdevice 18:11:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:11:41 INFO - Running setup.py install for mozfile 18:11:41 INFO - Running setup.py install for mozhttpd 18:11:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:11:41 INFO - Running setup.py install for mozinfo 18:11:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:11:41 INFO - Running setup.py install for mozInstall 18:11:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:11:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:11:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:11:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:11:41 INFO - Running setup.py install for mozleak 18:11:41 INFO - Running setup.py install for mozlog 18:11:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:11:41 INFO - Running setup.py install for moznetwork 18:11:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:11:42 INFO - Running setup.py install for mozprocess 18:11:42 INFO - Running setup.py install for mozprofile 18:11:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:11:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:11:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:11:42 INFO - Running setup.py install for mozrunner 18:11:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:11:42 INFO - Running setup.py install for mozscreenshot 18:11:42 INFO - Running setup.py install for moztest 18:11:42 INFO - Running setup.py install for mozversion 18:11:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:11:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:11:42 INFO - Cleaning up... 18:11:42 INFO - Return code: 0 18:11:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11:42 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:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:42 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:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:42 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 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:42 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:11:42 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:11:42 INFO - Using env: {'DISPLAY': ':0', 18:11:42 INFO - 'HOME': '/home/cltbld', 18:11:42 INFO - 'LANG': 'en_US.UTF-8', 18:11:42 INFO - 'LANGUAGE': 'en_US:en', 18:11:42 INFO - 'LOGNAME': 'cltbld', 18:11:42 INFO - 'MAIL': '/var/mail/cltbld', 18:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:42 INFO - 'MOZ_NO_REMOTE': '1', 18:11:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:42 INFO - 'NO_EM_RESTART': '1', 18:11:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:42 INFO - 'PWD': '/builds/slave/test', 18:11:42 INFO - 'SHELL': '/bin/bash', 18:11:42 INFO - 'SHLVL': '1', 18:11:42 INFO - 'TERM': 'linux', 18:11:42 INFO - 'TMOUT': '86400', 18:11:42 INFO - 'USER': 'cltbld', 18:11:42 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:42 INFO - '_': '/tools/buildbot/bin/python'} 18:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:11:42 INFO - Running setup.py (path:/tmp/pip-nLyJpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:11:43 INFO - Running setup.py (path:/tmp/pip-XSD2Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:11:43 INFO - Running setup.py (path:/tmp/pip-W4gQi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:11:43 INFO - Running setup.py (path:/tmp/pip-9uV9qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:11:43 INFO - Running setup.py (path:/tmp/pip-DlLJE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:11:43 INFO - Running setup.py (path:/tmp/pip-0N8JKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:11:43 INFO - Running setup.py (path:/tmp/pip-tSaX07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:11:43 INFO - Running setup.py (path:/tmp/pip-TMrthp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:11:43 INFO - Running setup.py (path:/tmp/pip-CRjOPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:11:43 INFO - Running setup.py (path:/tmp/pip-C0Yq78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:11:43 INFO - Running setup.py (path:/tmp/pip-3vapKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:11:43 INFO - Running setup.py (path:/tmp/pip-fabjbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:11:43 INFO - Running setup.py (path:/tmp/pip-NIHDZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:11:43 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:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:11:43 INFO - Running setup.py (path:/tmp/pip-92RSIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:11:44 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:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:44 INFO - Running setup.py (path:/tmp/pip-sTqOR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:11:44 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:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:11:44 INFO - Running setup.py (path:/tmp/pip-DCJn_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:11:44 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:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:11:44 INFO - Running setup.py (path:/tmp/pip-7x_E8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:11:44 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:11:44 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:11:44 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:11:44 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:11:44 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:11:44 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:11:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:11:44 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:11:44 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:11:44 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:11:44 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:11:45 INFO - Downloading blessings-1.6.tar.gz 18:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:11:45 INFO - Installing collected packages: blessings 18:11:45 INFO - Running setup.py install for blessings 18:11:45 INFO - Successfully installed blessings 18:11:45 INFO - Cleaning up... 18:11:45 INFO - Return code: 0 18:11:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:11:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:11:45 INFO - Reading from file tmpfile_stdout 18:11:45 INFO - Current package versions: 18:11:45 INFO - argparse == 1.2.1 18:11:45 INFO - blessings == 1.6 18:11:45 INFO - blobuploader == 1.2.4 18:11:45 INFO - docopt == 0.6.1 18:11:45 INFO - functools32 == 3.2.3-2 18:11:45 INFO - jsonschema == 2.5.1 18:11:45 INFO - manifestparser == 1.1 18:11:45 INFO - mozInstall == 1.12 18:11:45 INFO - mozcrash == 1.0 18:11:45 INFO - mozdebug == 0.1 18:11:45 INFO - mozdevice == 0.51 18:11:45 INFO - mozfile == 1.2 18:11:45 INFO - mozhttpd == 0.7 18:11:45 INFO - mozinfo == 0.9 18:11:45 INFO - mozleak == 0.1 18:11:45 INFO - mozlog == 3.4 18:11:45 INFO - moznetwork == 0.27 18:11:45 INFO - mozprocess == 0.25 18:11:45 INFO - mozprofile == 0.28 18:11:45 INFO - mozrunner == 6.13 18:11:45 INFO - mozscreenshot == 0.1 18:11:45 INFO - mozsystemmonitor == 0.3 18:11:45 INFO - moztest == 0.8 18:11:45 INFO - mozversion == 1.4 18:11:45 INFO - psutil == 3.1.1 18:11:45 INFO - requests == 1.2.3 18:11:45 INFO - wsgiref == 0.1.2 18:11:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:11:45 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:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:45 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:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:45 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 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:45 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:11:45 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:11:45 INFO - Using env: {'DISPLAY': ':0', 18:11:45 INFO - 'HOME': '/home/cltbld', 18:11:45 INFO - 'LANG': 'en_US.UTF-8', 18:11:45 INFO - 'LANGUAGE': 'en_US:en', 18:11:45 INFO - 'LOGNAME': 'cltbld', 18:11:45 INFO - 'MAIL': '/var/mail/cltbld', 18:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:45 INFO - 'MOZ_NO_REMOTE': '1', 18:11:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:45 INFO - 'NO_EM_RESTART': '1', 18:11:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:45 INFO - 'PWD': '/builds/slave/test', 18:11:45 INFO - 'SHELL': '/bin/bash', 18:11:45 INFO - 'SHLVL': '1', 18:11:45 INFO - 'TERM': 'linux', 18:11:45 INFO - 'TMOUT': '86400', 18:11:45 INFO - 'USER': 'cltbld', 18:11:45 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:45 INFO - '_': '/tools/buildbot/bin/python'} 18:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:45 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:11:45 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:11:45 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:11:45 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:11:45 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:11:45 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:11:45 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:11:45 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:11:45 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:11:45 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:11:45 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:11:45 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:11:45 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:11:45 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:11:47 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:11:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:11:47 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:11:47 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:11:47 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:11:47 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:11:47 INFO - Installing collected packages: marionette-driver 18:11:47 INFO - Running setup.py install for marionette-driver 18:11:47 INFO - Successfully installed marionette-driver 18:11:47 INFO - Cleaning up... 18:11:47 INFO - Return code: 0 18:11:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:11:47 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:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:11:47 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:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:11:47 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 0x1ced3b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ec7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x206bdd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, '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': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', '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:11:47 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:11:47 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:11:47 INFO - Using env: {'DISPLAY': ':0', 18:11:47 INFO - 'HOME': '/home/cltbld', 18:11:47 INFO - 'LANG': 'en_US.UTF-8', 18:11:47 INFO - 'LANGUAGE': 'en_US:en', 18:11:47 INFO - 'LOGNAME': 'cltbld', 18:11:47 INFO - 'MAIL': '/var/mail/cltbld', 18:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:11:47 INFO - 'MOZ_NO_REMOTE': '1', 18:11:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:11:47 INFO - 'NO_EM_RESTART': '1', 18:11:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:11:47 INFO - 'PWD': '/builds/slave/test', 18:11:47 INFO - 'SHELL': '/bin/bash', 18:11:47 INFO - 'SHLVL': '1', 18:11:47 INFO - 'TERM': 'linux', 18:11:47 INFO - 'TMOUT': '86400', 18:11:47 INFO - 'USER': 'cltbld', 18:11:47 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:11:47 INFO - '_': '/tools/buildbot/bin/python'} 18:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:11:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:11:47 INFO - Cleaning up... 18:11:47 INFO - Return code: 0 18:11:47 INFO - Running post-action listener: _resource_record_post_action 18:11:47 INFO - Running post-action listener: _start_resource_monitoring 18:11:47 INFO - Starting resource monitoring. 18:11:47 INFO - [mozharness: 2017-06-01 01:11:47.579158Z] Finished create-virtualenv step (success) 18:11:47 INFO - [mozharness: 2017-06-01 01:11:47.579629Z] Running install step. 18:11:47 INFO - Running pre-action listener: _resource_record_pre_action 18:11:47 INFO - Running main action method: install 18:11:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:11:47 INFO - Reading from file tmpfile_stdout 18:11:47 INFO - Detecting whether we're running mozinstall >=1.0... 18:11:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:11:47 INFO - Reading from file tmpfile_stdout 18:11:47 INFO - Output received: 18:11:47 INFO - Usage: mozinstall [options] installer 18:11:47 INFO - Options: 18:11:47 INFO - -h, --help show this help message and exit 18:11:47 INFO - -d DEST, --destination=DEST 18:11:47 INFO - Directory to install application into. [default: 18:11:47 INFO - "/builds/slave/test"] 18:11:47 INFO - --app=APP Application being installed. [default: firefox] 18:11:47 INFO - mkdir: /builds/slave/test/build/application 18:11:47 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:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:12:03 INFO - Reading from file tmpfile_stdout 18:12:03 INFO - Output received: 18:12:03 INFO - /builds/slave/test/build/application/firefox/firefox 18:12:03 INFO - Running post-action listener: _resource_record_post_action 18:12:03 INFO - [mozharness: 2017-06-01 01:12:03.878464Z] Finished install step (success) 18:12:03 INFO - [mozharness: 2017-06-01 01:12:03.878742Z] Running setup-mitmproxy step. 18:12:03 INFO - Running pre-action listener: _resource_record_pre_action 18:12:03 INFO - Running main action method: setup_mitmproxy 18:12:03 INFO - Skipping: mitmproxy is not required 18:12:03 INFO - Running post-action listener: _resource_record_post_action 18:12:03 INFO - [mozharness: 2017-06-01 01:12:03.879855Z] Finished setup-mitmproxy step (success) 18:12:03 INFO - [mozharness: 2017-06-01 01:12:03.880076Z] Running run-tests step. 18:12:03 INFO - Running pre-action listener: _resource_record_pre_action 18:12:03 INFO - Running main action method: run_tests 18:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:03 INFO - Python 2.7.3 18:12:03 INFO - Return code: 0 18:12:03 INFO - grabbing minidump binary from tooltool 18:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:12:03 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2068840>, '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 0x2069740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2069bd0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:12:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:12:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:12:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:03 INFO - Return code: 0 18:12:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:12:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:03 INFO - ENV: RUST_BACKTRACE is now 1 18:12:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:12:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-085', '--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:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-085 --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:12:03 INFO - Using env: {'DISPLAY': ':0', 18:12:03 INFO - 'HOME': '/home/cltbld', 18:12:03 INFO - 'LANG': 'en_US.UTF-8', 18:12:03 INFO - 'LANGUAGE': 'en_US:en', 18:12:03 INFO - 'LOGNAME': 'cltbld', 18:12:03 INFO - 'MAIL': '/var/mail/cltbld', 18:12:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:03 INFO - 'MOZ_NO_REMOTE': '1', 18:12:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:03 INFO - 'NO_EM_RESTART': '1', 18:12:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:03 INFO - 'PWD': '/builds/slave/test', 18:12:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:03 INFO - 'RUST_BACKTRACE': '1', 18:12:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:12:03 INFO - 'SHELL': '/bin/bash', 18:12:03 INFO - 'SHLVL': '1', 18:12:03 INFO - 'TERM': 'linux', 18:12:03 INFO - 'TMOUT': '86400', 18:12:03 INFO - 'USER': 'cltbld', 18:12:03 INFO - 'XDG_SESSION_COOKIE': '5c37b704b588aaa989324530000001d0-1496277235.346173-57920793', 18:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:03 INFO - '_': '/tools/buildbot/bin/python'} 18:12:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-085', '--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:12:04 INFO - mozversion application_buildid: 20170601002558 18:12:04 INFO - mozversion application_changeset: a8f378825e81daff1279a7d6e940b610912ee6dc 18:12:04 INFO - mozversion application_display_name: Nightly 18:12:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:04 INFO - mozversion application_name: Firefox 18:12:04 INFO - mozversion application_remotingname: firefox 18:12:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:12:04 INFO - mozversion application_vendor: Mozilla 18:12:04 INFO - mozversion application_version: 55.0a1 18:12:04 INFO - mozversion platform_buildid: 20170601002558 18:12:04 INFO - mozversion platform_changeset: a8f378825e81daff1279a7d6e940b610912ee6dc 18:12:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:12:04 INFO - mozversion platform_version: 55.0a1 18:12:04 INFO - using testdate: 1496279524 18:12:04 INFO - actual date: 1496279524 18:12:04 INFO - starting webserver on 'localhost:36886' 18:12:04 INFO - SUITE-START | Running 2 tests 18:12:04 INFO - TEST-START | dromaeo_css 18:12:04 INFO - operating with platform_type : linux_ 18:12:04 INFO - Initialising browser for dromaeo_css test... 18:12:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36886/getInfo.html -profile /tmp/tmpg8h3ly/profile 18:12:04 INFO - TEST-INFO | started process 30275 (/builds/slave/test/build/application/firefox/firefox http://localhost:36886/getInfo.html) 18:12:39 INFO - TEST-INFO | 30275: killed by SIGTERM 18:12:39 INFO - Browser initialized. 18:12:39 INFO - Running cycle 1/1 for dromaeo_css test... 18:12:39 INFO - TEST-INFO | started process 30507 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg8h3ly/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:12:51 INFO - PID 30507 | RSS: Main: 188379136 18:12:51 INFO - PID 30507 | 18:15:56 INFO - PID 30507 | Cycle 1(1): loaded http://localhost:36886/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36886/tests/dromaeo/cssquery-ext.html) 18:15:57 INFO - PID 30507 | RSS: Main: 182480896 18:15:57 INFO - PID 30507 | 18:18:59 INFO - PID 30507 | Cycle 1(1): loaded http://localhost:36886/tests/dromaeo/cssquery-ext.html (next: http://localhost:36886/tests/dromaeo/cssquery-jquery.html) 18:19:00 INFO - PID 30507 | RSS: Main: 188039168 18:19:00 INFO - PID 30507 | 18:22:02 INFO - PID 30507 | Cycle 1(1): loaded http://localhost:36886/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36886/tests/dromaeo/cssquery-mootools.html) 18:22:03 INFO - PID 30507 | RSS: Main: 185028608 18:22:03 INFO - PID 30507 | 18:25:04 INFO - PID 30507 | Cycle 1(1): loaded http://localhost:36886/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36886/tests/dromaeo/cssquery-prototype.html) 18:25:05 INFO - PID 30507 | RSS: Main: 189157376 18:25:05 INFO - PID 30507 | 18:28:08 INFO - PID 30507 | Cycle 1(1): loaded http://localhost:36886/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36886/tests/dromaeo/cssquery-yui.html) 18:28:09 INFO - PID 30507 | RSS: Main: 187670528 18:28:09 INFO - PID 30507 | 18:31:10 INFO - PID 30507 | Cycle 1(1): loaded http://localhost:36886/tests/dromaeo/cssquery-yui.html (next: http://localhost:36886/tests/dromaeo/cssquery-dojo.html) 18:31:11 INFO - PID 30507 | RSS: Main: 185499648 18:31:11 INFO - PID 30507 | 18:31:11 INFO - PID 30507 | __start_tp_report 18:31:11 INFO - PID 30507 | _x_x_mozilla_page_load 18:31:11 INFO - PID 30507 | _x_x_mozilla_page_load_details 18:31:11 INFO - PID 30507 | |i|pagename|runs| 18:31:11 INFO - PID 30507 | |0;dojo.html;2703.3376822678447;2746.395792925558;2767.4326763013582;2774.9445011099774;2792.204221796787;5266.973665131679;5380;5380.273663055491;5403.324584426947;5413.485718856712;5334.226537152111;5339.946600534003;5409.43200963898;5484.588655850807;5511.669299842002;4784.928226076594;4796.112719146951;4801.2798080287885;4814;4848.321235027099;4781.115493633672;4829.855104346875;4847.1517484440255;4866.78099485524;4907.82822601209;4713.363695901044;4756.595689366373;4763.261694437368;4776.068666610013;4824.107540105092;4785.736784476852;4787.329773831666;4794.400699912511;4796.664233503656;4806.375171227718;4706.3411122442885;4708.599769019637;4708.717476951394;4731.621470282404;4748.667593268473;5270.1040823060175;5333.493318134771;5339.572834173296;5344.91982620261;5389.676619402849;5745.454181852759;5754.079347304411;5792.4497172768515;5798.3911689272445;5856.238688970449;5927.140564618149;5947.910781338283;5963.045912653998;5965.105234214859;5977.880442391128;5479.561635069185;5492.670439773627;5495.313085864267;5499.340079190522;5500.972495137498;5879.265091863517;6014.097885317237;6014.51883849291;6020.036794112921;6036.396360363929;5681.431856814368;5693.829185124453;5739.397363276879;5765.88468230633;5786.07422812348;5735.541156707453;5869.911951320733;5874.50066744323;5874.500667443315;5894.351621321651;5732.197492351074;5838.591298609056;5838.766449341979;5839.240898683229;5848.502877255481;5744.913826292609;5757.280339957505;5770.596058275964;5778.768849245983;5832.912506312408;4804.8798780030775;4820.25286080658;4820.927686084711;4852.2479015752615;4859.708417494961;4110.7328023678965;4127.215828992482;4155.833766649361;4168.062186008185;4235.7670328131635;4752.738599377068;4788.640851936118;4812.51874812523;4813.302071199727;4825.252085926687;5774.105013722879;5796.188533605298;5814.563907706939;5851.678157701367;5873.882522349529;4764;4766.737829419346;4767.022760334124;4782.378290822171;4832.226843704991;5019.472955339636;5118;5120.283160357553;5124.385073791169;5135.15269980449;5299.258103865539;5369;5372.2747429098135;5455.236266922555;5456.48163424474;5692.829215123553;5725.971370143123;5733.283339582552;5815.360310365941;5854.209681692917;5331.280277162534;5352.4915133062295;5356.250124982584;5389.299391079135;5423.2407462954425;5637.52081073097;5714.571407144397;5729.942700572941;5756.453136951983;5770.567207459373;5802.216700745345;5876.882462350643;5898.3216930053595;5900.822975310576;5918.378570250062;5624.100143976944;5702.600817942704;5707.0583353747825;5708.229389032594;5722.198892155185;5575.8606034847835;5588.133839254922;5614.2701448811395;5615.326160860723;5669.773209071591;5447.455254474521;5526.198701188386;5535.39110697831;5552.167174923794;5594.664320140641;5580.469855363734;5590.608657393944;5649.039663257174;5658.745356459051;5663.943360566342;5579.581531385244;5581.302337207849;5584.636998595079;5600.215969764317;5642.576806739429 18:31:11 INFO - PID 30507 | |1;ext.html;697.1982220446486;760.7413479416999;784.0630446616294;1063.5214153630868;1089.9891001089986;32741.345173096524;33570;33589;33632.49551256733;33665.960084789825;34732.30535389291;35004.474932875964;35068.473972890366;35068.473972890366;35089.82455087721;6362.331955144713;6515.674216289191;6801.795946121624;6943.79168624942;6943.930560694404;6743.662816859162;6839.794806155798;6839.965800171017;6848.794536163923;6857.794266172023;6795.354441328066;6847.589144651312;6848.657567121649;6865.794026179223;6890.138732658418;6813.114295141625;6841.521093523455;6849.691763870588;6856.348646878563;6858.622775747332;6773.830654233683;6810.659467026629;6812.591244525344;6814.693338799716;6816.727330906758;32095.19762005945;32095.839520802245;32124.357512849845;32147.678523214938;32164.839175803972;2933.0467598030514;2967.807092538978;2968.421157874215;2974.7917645764805;2983.284011837165;24445.511089778105;24523.141690040942;24567.63148552773;24638;24650.13724519651;3015.2612609910493;3020.2147441665106;3023.89416370426;3035.666076731558;3048.8170709757487;120851.39574302072;121492;135986.3200684;136032.31983840017;137347.31326343404;39298.2140357197;39331;39593;39703.60296397057;39751.00622484403;40328.1934361317;40681;40749.59250407458;40914;40921;11618.70953226176;11750.471228794553;11766.117541184445;11853.229540079867;11948.103892208119;9641.37331073478;9663.323567350219;9672.516374181263;9672.903270967201;9694.321397502106;12588.37058147089;12634.81047784284;12635.936820316023;12654.456752109425;12845.421956012002;5254.316938798008;5256.737163141827;5356.544693701079;5364.436734142859;5368.489993450616;851.3870013590205;900.5812297281665;908.6728777640044;908.9545522723837;920.07992007992;20445.591088178153;20977.58044839125;21445.249416270668;21583.784162158492;21606.78393216079;970.7815741458115;972.6741541583649;974.7904200596905;976.0776066616979;991.5884907763342;10615.150787936947;11035.44822758859;11172.329660220414;11228.719282017852;11261.099112071015;168268;169963;170053.14973425053;170161;171951;39492.60507394947;39635.801820991284;39932.2013559733;40108.398374024415;40263.79868100699;1178.587494376961;1206.5596057439147;1209.8245754365744;1211.8424604801319;1225.7609766095525;19383.612327753646;19948.102335395233;20148.496287592927;20839.270625528035;21001;28652.283692907844;28699.42601148007;28701.712982869903;28709.42581148407;28789.42421151607;147642;148077;148759.25620371828;149070;149555;28436.857815710788;28477.864160188485;28743.99396021213;30009.699903001565;30154.54768178523;34960.650393495736;35165;35239.47140792839;35451.11372215612;35580.466293005105;25961.480770384864;27094.1871743848;27177.1846844595;27258;27648.72351276542;26393;26473.60289595619;27260.727392725817;27347.58978615362;27968.30079248035;30347.24131896632;30538.389232214788;30565.847170764006;30617.54073688852;30638.234044149074;26090.34774130586;26109;26639.46721065529;27103;27900.441991160467;21112.472188195417;21767.673484898056;22132.778672213073;22185.66721499211;22310.88844555767 18:31:11 INFO - PID 30507 | |2;jquery.html;410.1202919737166;410.6406893967778;415.81496234175785;426.54146792198384;434.2032370599949;12967.74064518709;13574.185548867075;17549.824501754978;18190.545236369097;18207.362742304023;16199.595010124753;16967.23647435865;17035.48893533193;17038.914805425986;17047.659046819055;5518.144687573423;5668.149777533383;5679.062954612484;5681.147827825818;5698.743556539945;5424.972875135619;5477.643953143033;5479.808206712766;5505.256790333314;5514.944850551483;5432.049391356497;5456.672599644034;5460.590455715817;5476.342838859342;5478.616496845222;5226.268322434862;5256.500632439912;5271.710055946922;5289.206619007141;5481.451854814526;4498.820047198108;4668.766561671937;4730.172219861545;4734.313524538957;4790.041991601659;22102.447438813997;22118.115275389126;22211.888940555356;22214.222502212346;22320.77679223203;1632.159437889488;1639.8032236131621;1654.040656419274;1655.8758093143063;1656.9585760355967;14384.85615143856;14676.633084172874;14682.119072855663;14730.55808325752;14764.557063288012;1992.1832048859899;1994.6110508450859;2004.2484068474323;2009.357005758158;2013.6878783788536;100252;101515;101750;101900;101963;10566.31318964265;10814.243002989715;10865.185111116698;11391.20261581698;11532.25040372373;15867.206639668202;15871.365145394286;15882.76175857363;15897;15909.363625454853;7212.4590655699985;7255.8911616325795;7460.515066520658;7522.322990930733;7679.232076792276;6783.253842077367;6843.315668433117;6912.377885990285;6917.031615573824;6925.099737034153;6888.862222755517;7021.964890175516;7051.753188638373;7118.822029449201;7205.315495027964;1986.358143668917;2001.2995451591826;2032.6442872497255;2088.822450091759;2165.5560610075213;820.4216027700456;845.2435070611768;851.9872201916976;872.646578135856;883.3639779358851;17064.146792660318;17182;17385;17398.478045658652;17420;868.4224990381452;878.9648414063494;880.9647614095364;889.3418870036127;891.4072142025481;9036.412633178823;9481.146696797321;9757.365771224848;11166.553337866557;12349;359245;359679;364342;365429;366497;87670;89211;89282.10717892868;89576.5521172403;89861;1068.7167900506372;1072.3780207479642;1102.2559772153763;1104.6133853151493;1107.983380249297;17558;17836;17839.10804459798;18358.72461913099;18759.155837987597;45101.54898450973;45116;45235.321470178635;46314.76842615765;46622.405311362854;56974.715126424104;57106.14340784975;57191.71404142953;57477.42522574888;57489.712551436976;9222.95388523053;9255.490947997925;9290.16388525036;9323.393979391318;9362.859557106513;15554;15559.222038897784;15674.059556426651;15798;15827.366905323657;12832;13117;13308.201507909556;13330.533431329857;13369.933150334185;11794.29234245948;11939.403029848647;12171.33057681836;12605.68485787826;12617.621471355875;13257.734845303234;13263.403146858222;13416.731665366442;13548.661283467993;13568.79646805279;9131.634734610541;9132.726018219464;9136.132067453582;9145.268378529834;9155.542222888696;12951.086005649031;13132.868671313165;13483.932580337036;13526.79709804334;13571.86428135706 18:31:11 INFO - PID 30507 | |3;mootools.html;1198.5325722968043;1245.925244485331;1259.7480503899217;1260.6155122687576;1262.8295180150676;9602.5678844452;9751.12239898409;10013.699589012323;10014.649487267947;10278.845817312746;9920.55357508912;10035.749106272347;10036.397816131037;10058.849117263248;10068.597256109746;2086.6974288728165;2138.2409244718133;2140.721706178195;2142.260919982611;2146.7960543748395;1899.9525011874744;1943.640426521098;1944.3292064237885;1944.7277381166648;1958.2167133146713;1955.7946415626297;1981.821636052755;1991.3428568572408;1994.3418671838258;1995.8103980121866;2020.9494762630904;2036.3381900882273;2044.2845004248543;2047.3653167518116;2048.1909645690016;1858.7676540432446;1876.9155388007573;1885.6228754249205;1895.0808857704003;1952.3654812185955;9688.273379496566;9771.609135634566;9775.608975640966;9776;9804.754881127958;5127.641065125443;5310.787568497255;5336.38631557372;5336.786528538853;5342.519173274385;3094.3037816491333;3241.6434192238835;3280.425925463034;3282.5896762904636;3312.4865645824934;998.7902540466511;1035.7358873487287;1044.3211912257093;1090.2368342160519;1114.7324642085923;14283.500077497238;14388.496402625859;14458.132512049206;14467.78298325526;14495.420183192766;4061;4158.209940111429;4201.747895126302;4310.3103503439925;4332.653387728974;10789.298695584763;10836.729081773019;10865.348079115278;10908.181886358396;10908.672739817817;4934.284528743384;4971.030649023478;5010.549050585465;5029.0947629427055;5037.823676171316;4681.578657920803;4722.740249286254;4727.621790256771;4751.714897106184;4761.7142971421135;3703.6111208323277;3784.7161462890394;3860.3244432224246;3860.9613903861164;3866.8839934802;1060.6393826099163;1099.9395033273247;1100.9064229540418;1114.9888501115047;1118.0384869012642;658.8122385120314;680.4590350671228;685.9519833611599;704.9964750176318;706.0221593093603;5114.488551144856;5121.1550094234035;5122.590192784568;5290.021346050993;5307.044731948286;522.7725939216454;526.7892842862809;532.3345817727841;536.5385768239311;537.2666310486236;3088.104449709559;3097.9535306970415;3110.688931106871;3149.1339881532303;3151.3697260547983;13007.674808129683;13583.1850088993;13589.932050339685;13628;13631.591052268448;12599;12725.490980360866;13135.211887286794;13225.338733063309;13253.801192982113;6760.1549806274215;6906.930930690629;7029.051078104492;7096.432285417154;7111.857762844714;5538.584606154474;5886.411358864079;5896.174535564938;5931.555133364928;6073.089036644538;11465;11533.270009498307;11778.116641251769;11932.28406295625;12095.516179352728;12286.385680716106;12582.370881455707;12648.114631975674;12795;12811;7919.722809701633;8227.794305142419;8231;8232;8233.024074222263;9140.908590914272;9238.95380523093;9273.397229180082;9291.2102471288;9299.395539289924;7399.186089530255;7598.468107232441;7617.581033043014;7623.504472209289;7816.57008864518;7577.280158384945;7677.193894640982;7758.92241077582;7821.648025838737;7880;6849.4177994871;6937;6987.336203060701;6999.020137180901;7006.9649651751415;4884.92672609904;4970.204767237225;4997.900041999067;5007.39911210657;5055.620828437808;4753.928691069706;4869.147899117711;4909.239067944474;4927.334809800775;4929.556339929423 18:31:11 INFO - PID 30507 | |4;prototype.html;356.891148199799;375.30755755630867;376.572590110225;381.7728451571314;383.05576753303103;5310.468953104688;5495.230667706519;5502.80740174094;5603.7478313475885;5652.219550841487;5347.224652425396;5475.4524547545225;5491.28613280273;5497.642653227537;5505.889882202354;4332.198543269497;4332.461975998523;4340.131973605276;4342.392065110887;4352.151330490556;4281.379200015996;4297.849575264866;4342.435483387155;4359.171757366106;4364.716293440932;4271.658267338621;4307.440032795748;4321.524632290446;4325.82696692132;4331.631811296043;4324.291904697627;4337.934930976038;4338.110687309126;4352.934705979413;4354.281543545311;3886;3923.9215215695526;3944.822482988244;3944.915148334231;3952.7628342299267;5442.727863606804;5458.044842152607;5459.945400546023;5478.671279723189;5481;5176.715280659562;5177.715225662587;5179.922301165486;5189.584833213334;5201.193814958688;5922.289325280994;5933.584649074566;5945.583809133363;5946.940530594682;5965.105234214902;4458.888527786831;4459.933101003455;4461.330800379937;4477.104579084196;4486.685931984763;5880.176775251425;6172.969135154341;6174.382561743834;6195.473384762346;6204.87590248197;4247.048301430476;4252.851150209728;4253.255680255943;4270.935935960963;4276.914461710749;4307.341673455742;4310.137972405531;4313.84901528445;4321.719088259253;4322.924996001937;4790.952090479121;4903.583195428429;4907.650396141018;4940.2342636891335;4992.850214493571;5868.2664666916635;5891.204687367151;5898.2894960461845;5915.40845915405;5915.911261331084;1473.5358362115899;1475.837657857635;1481.888858335629;1483.317673870032;1488.3153749275455;987.4766373821742;995.323180237431;1018.5111146649501;1027.275770581753;1027.6249169053242;4958.677685950402;4980.402351717745;4984.576311013532;4989.077020751172;4995.300657907896;5977.296470505972;6010.158577799111;6025.276966764016;6029.547783916224;6069.089636554464;4917.336159618477;4942.233953737176;4943.505649435099;4947.90104197914;4955.108080545537;3198.6801319867827;3227.0802821196316;3238.578984732017;3252.1869532616847;3256.886008989674;5611.9719401403545;5629.549636029108;5638.4925356718095;5639.915401268984;5647.265855438849;6231.127642130105;6247.937520624736;6279.780207692709;6288.9056664150075;6324.209473815773;5563.972180139073;5606.187102870061;5613.887722245532;5646.350669672958;5660.85847853807;6027.21646185993;6084.787032453843;6084.939150608438;6104.786332478342;6126.938730612636;5692.43075692444;5976.043832986875;5989;6000.699965001645;6003.5797494174985;5570.832875013756;5582.581306401955;5598.048331783525;5621.578381621313;5648.15277708347;4215.430916826189;4241.448611680586;4246.256905041543;4249.575042495726;4252.447181866338;5951.821445356646;6098.11577321277;6100.176476175837;6105.870413973429;6136.23297087864;5862.208601838868;5916.171735957056;5933.970330148321;5934.43622855828;5943.52451803846;5837.153612726216;5841.824745257649;5853.887761325334;5879.353271140257;5907.405000649715;5820.534357251325;5821.010428227127;5827.94172058274;5830.271216097988;5851.1223316502865;5941;5972.492338151305;5974.970125149521;5989.311229208697;6003.309619393825;5801.882312955657;5820.330661973758;5827.79602713903;5833;5837.67892765885 18:31:11 INFO - PID 30507 | |5;yui.html;664.5514277862442;690.5269890125264;724.4638967164299;735.6432130416747;751.9548827070377;13239.933800330997;13596.45614175433;13717.382717777698;15397;16563;15076.6984660307;15264.084154950682;15302;15318.080915145072;15360;828.838376516578;840.7519781664423;843.784834867108;846.3144852669327;846.9872951905726;788.0661416221795;845.3744229270327;846.0228436156218;850.8170743290192;855.3755758296447;881.1276835932445;881.3962435731512;882.9249513791335;884;884.1644645809712;876.3646356391629;877.934154938379;878.714417814213;879.3317079019927;882.3735148044864;641.1376698340724;642.1202951955851;645.3643161485933;647.5499527828161;647.6437959122463;14590;14681.559553213421;14774.261286935718;14803.777943330859;14807.925960370128;14895.925520372437;15341.539753807403;15418.152001639904;15423.151726655029;15442.227888605637;11196.552137914556;11430.085593152528;11827.467763950643;11941.761164776743;11966.82049769254;3001.0546677796424;3002.8498575071158;3015.381846721417;3016.9396612067635;3019.607451031352;84445.57777211136;86604;86956.56521717351;88004;88214.11785882124;12478.81281780774;12521.812172817416;12766.489340426278;12803.103782735305;12823.487060517662;21150.15399384038;21987;22018.229361972575;22036.669449958263;22037;6037.879242415215;6057.212562366864;6104.664243466652;6149.815505534841;6194.915889719371;2955.896543621006;3033.1507177990197;3033.1507177990197;3034.013945467732;3040.847957602111;3242.6595207502874;3367.107716455141;3563.768355056965;3697.9815100924325;3702.148505843671;701.5194591704699;712.6187489693093;713.0694443750955;713.8929160625844;714.2785786355714;515.2400209690676;526.715573590257;532.5021105266511;532.97068661224;537.231758585219;9379.24966002718;9657.613695452103;9833.655822046338;9898.356606820678;10300.124489418333;570.9457601527848;579.9101139323411;584.7427132061879;584.7602482982043;604.2809057221892;4159.83360665576;5009.599232061426;5163;5290.312259406252;5588.0577957995065;73000.63499682468;73530;73847.63076184691;75453;80270.5986470064;65694.67152664301;67429.32570674329;67968.6601566989;68287;69272.6536367315;58155.127673084935;59394.703026485455;59956.70021649864;60920.39079609236;61973.07040394397;9706.660266890625;10052.849207262043;10646.148308135176;10697.732556686145;10872.510737016695;26201.868990654926;26556.46887062209;26723.599146012435;27793.722062779114;28367.858160709064;34293.314133717686;34731.82634086813;34830.12924676904;35153.47269791007;35342.64657353394;13489.797653035015;13571.217900330606;13752.518661846787;13755.862441375859;13991;16232.513024609281;16288.267027984006;16392.426265080663;16472.341106356092;16704.498865034067;14700;15139.091654500766;15169.165695886832;15445.691086178438;15484.922575387502;13795.724085518434;13871.167729936236;13937.233452160228;14015;14274.571762847132;14832;14995.475158369842;15103.44040453175;15116.470923517623;15387.846121538641;10076.84884726715;10170.89829101729;10186.796264074528;10531.78936421252;10642.73393165146;11415.714607134556;11980.700482488008;12014.279143251084;12158.088143389105;12207.267563946192 18:31:11 INFO - PID 30507 | __end_tp_report 18:31:11 INFO - PID 30507 | __start_cc_report 18:31:11 INFO - PID 30507 | _x_x_mozilla_cycle_collect,374 18:31:11 INFO - PID 30507 | __end_cc_report 18:31:11 INFO - PID 30507 | __startTimestamp1496280671206__endTimestamp 18:31:11 INFO - PID 30507 | 18:31:11 INFO - PID 30507 | ------- Summary: start ------- 18:31:11 INFO - PID 30507 | Number of tests: 6 18:31:11 INFO - PID 30507 | 18:31:11 INFO - PID 30507 | [#0] dojo.html Cycles:165 Average:5300.38 Median:5495.31 stddev:644.68 (11.7%) stddev-sans-first:613.62 18:31:11 INFO - PID 30507 | Values: 2703.3 2746.4 2767.4 2774.9 2792.2 5267.0 5380.0 5380.3 5403.3 5413.5 5334.2 5339.9 5409.4 5484.6 5511.7 4784.9 4796.1 4801.3 4814.0 4848.3 4781.1 4829.9 4847.2 4866.8 4907.8 4713.4 4756.6 4763.3 4776.1 4824.1 4785.7 4787.3 4794.4 4796.7 4806.4 4706.3 4708.6 4708.7 4731.6 4748.7 5270.1 5333.5 5339.6 5344.9 5389.7 5745.5 5754.1 5792.4 5798.4 5856.2 5927.1 5947.9 5963.0 5965.1 5977.9 5479.6 5492.7 5495.3 5499.3 5501.0 5879.3 6014.1 6014.5 6020.0 6036.4 5681.4 5693.8 5739.4 5765.9 5786.1 5735.5 5869.9 5874.5 5874.5 5894.4 5732.2 5838.6 5838.8 5839.2 5848.5 5744.9 5757.3 5770.6 5778.8 5832.9 4804.9 4820.3 4820.9 4852.2 4859.7 4110.7 4127.2 4155.8 4168.1 4235.8 4752.7 4788.6 4812.5 4813.3 4825.3 5774.1 5796.2 5814.6 5851.7 5873.9 4764.0 4766.7 4767.0 4782.4 4832.2 5019.5 5118.0 5120.3 5124.4 5135.2 5299.3 5369.0 5372.3 5455.2 5456.5 5692.8 5726.0 5733.3 5815.4 5854.2 5331.3 5352.5 5356.3 5389.3 5423.2 5637.5 5714.6 5729.9 5756.5 5770.6 5802.2 5876.9 5898.3 5900.8 5918.4 5624.1 5702.6 5707.1 5708.2 5722.2 5575.9 5588.1 5614.3 5615.3 5669.8 5447.5 5526.2 5535.4 5552.2 5594.7 5580.5 5590.6 5649.0 5658.7 5663.9 5579.6 5581.3 5584.6 5600.2 5642.6 18:31:11 INFO - PID 30507 | 18:31:11 INFO - PID 30507 | [#1] ext.html Cycles:180 Average:29354.67 Median:16691.45 stddev:38839.69 (232.7%) stddev-sans-first:38889.04 18:31:11 INFO - PID 30507 | Values: 697.2 760.7 784.1 1063.5 1090.0 32741.3 33570.0 33589.0 33632.5 33666.0 34732.3 35004.5 35068.5 35068.5 35089.8 6362.3 6515.7 6801.8 6943.8 6943.9 6743.7 6839.8 6840.0 6848.8 6857.8 6795.4 6847.6 6848.7 6865.8 6890.1 6813.1 6841.5 6849.7 6856.3 6858.6 6773.8 6810.7 6812.6 6814.7 6816.7 32095.2 32095.8 32124.4 32147.7 32164.8 2933.0 2967.8 2968.4 2974.8 2983.3 24445.5 24523.1 24567.6 24638.0 24650.1 3015.3 3020.2 3023.9 3035.7 3048.8 120851.4 121492.0 135986.3 136032.3 137347.3 39298.2 39331.0 39593.0 39703.6 39751.0 40328.2 40681.0 40749.6 40914.0 40921.0 11618.7 11750.5 11766.1 11853.2 11948.1 9641.4 9663.3 9672.5 9672.9 9694.3 12588.4 12634.8 12635.9 12654.5 12845.4 5254.3 5256.7 5356.5 5364.4 5368.5 851.4 900.6 908.7 909.0 920.1 20445.6 20977.6 21445.2 21583.8 21606.8 970.8 972.7 974.8 976.1 991.6 10615.2 11035.4 11172.3 11228.7 11261.1 168268.0 169963.0 170053.1 170161.0 171951.0 39492.6 39635.8 39932.2 40108.4 40263.8 1178.6 1206.6 1209.8 1211.8 1225.8 19383.6 19948.1 20148.5 20839.3 21001.0 28652.3 28699.4 28701.7 28709.4 28789.4 147642.0 148077.0 148759.3 149070.0 149555.0 28436.9 28477.9 28744.0 30009.7 30154.5 34960.7 35165.0 35239.5 35451.1 35580.5 25961.5 27094.2 27177.2 27258.0 27648.7 26393.0 26473.6 27260.7 27347.6 27968.3 30347.2 30538.4 30565.8 30617.5 30638.2 26090.3 26109.0 26639.5 27103.0 27900.4 21112.5 21767.7 22132.8 22185.7 22310.9 18:31:11 INFO - PID 30507 | 18:31:11 INFO - PID 30507 | [#2] jquery.html Cycles:180 Average:26171.18 Median:22267.50 stddev:61299.54 (275.3%) stddev-sans-first:61440.98 18:31:11 INFO - PID 30507 | Values: 410.1 410.6 415.8 426.5 434.2 12967.7 13574.2 17549.8 18190.5 18207.4 16199.6 16967.2 17035.5 17038.9 17047.7 5518.1 5668.1 5679.1 5681.1 5698.7 5425.0 5477.6 5479.8 5505.3 5514.9 5432.0 5456.7 5460.6 5476.3 5478.6 5226.3 5256.5 5271.7 5289.2 5481.5 4498.8 4668.8 4730.2 4734.3 4790.0 22102.4 22118.1 22211.9 22214.2 22320.8 1632.2 1639.8 1654.0 1655.9 1657.0 14384.9 14676.6 14682.1 14730.6 14764.6 1992.2 1994.6 2004.2 2009.4 2013.7 100252.0 101515.0 101750.0 101900.0 101963.0 10566.3 10814.2 10865.2 11391.2 11532.3 15867.2 15871.4 15882.8 15897.0 15909.4 7212.5 7255.9 7460.5 7522.3 7679.2 6783.3 6843.3 6912.4 6917.0 6925.1 6888.9 7022.0 7051.8 7118.8 7205.3 1986.4 2001.3 2032.6 2088.8 2165.6 820.4 845.2 852.0 872.6 883.4 17064.1 17182.0 17385.0 17398.5 17420.0 868.4 879.0 881.0 889.3 891.4 9036.4 9481.1 9757.4 11166.6 12349.0 359245.0 359679.0 364342.0 365429.0 366497.0 87670.0 89211.0 89282.1 89576.6 89861.0 1068.7 1072.4 1102.3 1104.6 1108.0 17558.0 17836.0 17839.1 18358.7 18759.2 45101.5 45116.0 45235.3 46314.8 46622.4 56974.7 57106.1 57191.7 57477.4 57489.7 9223.0 9255.5 9290.2 9323.4 9362.9 15554.0 15559.2 15674.1 15798.0 15827.4 12832.0 13117.0 13308.2 13330.5 13369.9 11794.3 11939.4 12171.3 12605.7 12617.6 13257.7 13263.4 13416.7 13548.7 13568.8 9131.6 9132.7 9136.1 9145.3 9155.5 12951.1 13132.9 13483.9 13526.8 13571.9 18:31:11 INFO - PID 30507 | 18:31:11 INFO - PID 30507 | [#3] mootools.html Cycles:180 Average:6041.63 Median:3963.94 stddev:4041.49 (102.0%) stddev-sans-first:4036.45 18:31:11 INFO - PID 30507 | Values: 1198.5 1245.9 1259.7 1260.6 1262.8 9602.6 9751.1 10013.7 10014.6 10278.8 9920.6 10035.7 10036.4 10058.8 10068.6 2086.7 2138.2 2140.7 2142.3 2146.8 1900.0 1943.6 1944.3 1944.7 1958.2 1955.8 1981.8 1991.3 1994.3 1995.8 2020.9 2036.3 2044.3 2047.4 2048.2 1858.8 1876.9 1885.6 1895.1 1952.4 9688.3 9771.6 9775.6 9776.0 9804.8 5127.6 5310.8 5336.4 5336.8 5342.5 3094.3 3241.6 3280.4 3282.6 3312.5 998.8 1035.7 1044.3 1090.2 1114.7 14283.5 14388.5 14458.1 14467.8 14495.4 4061.0 4158.2 4201.7 4310.3 4332.7 10789.3 10836.7 10865.3 10908.2 10908.7 4934.3 4971.0 5010.5 5029.1 5037.8 4681.6 4722.7 4727.6 4751.7 4761.7 3703.6 3784.7 3860.3 3861.0 3866.9 1060.6 1099.9 1100.9 1115.0 1118.0 658.8 680.5 686.0 705.0 706.0 5114.5 5121.2 5122.6 5290.0 5307.0 522.8 526.8 532.3 536.5 537.3 3088.1 3098.0 3110.7 3149.1 3151.4 13007.7 13583.2 13589.9 13628.0 13631.6 12599.0 12725.5 13135.2 13225.3 13253.8 6760.2 6906.9 7029.1 7096.4 7111.9 5538.6 5886.4 5896.2 5931.6 6073.1 11465.0 11533.3 11778.1 11932.3 12095.5 12286.4 12582.4 12648.1 12795.0 12811.0 7919.7 8227.8 8231.0 8232.0 8233.0 9140.9 9239.0 9273.4 9291.2 9299.4 7399.2 7598.5 7617.6 7623.5 7816.6 7577.3 7677.2 7758.9 7821.6 7880.0 6849.4 6937.0 6987.3 6999.0 7007.0 4884.9 4970.2 4997.9 5007.4 5055.6 4753.9 4869.1 4909.2 4927.3 4929.6 18:31:11 INFO - PID 30507 | 18:31:11 INFO - PID 30507 | [#4] prototype.html Cycles:180 Average:4881.17 Median:5486.14 stddev:1416.37 (25.8%) stddev-sans-first:1379.03 18:31:11 INFO - PID 30507 | Values: 356.9 375.3 376.6 381.8 383.1 5310.5 5495.2 5502.8 5603.7 5652.2 5347.2 5475.5 5491.3 5497.6 5505.9 4332.2 4332.5 4340.1 4342.4 4352.2 4281.4 4297.8 4342.4 4359.2 4364.7 4271.7 4307.4 4321.5 4325.8 4331.6 4324.3 4337.9 4338.1 4352.9 4354.3 3886.0 3923.9 3944.8 3944.9 3952.8 5442.7 5458.0 5459.9 5478.7 5481.0 5176.7 5177.7 5179.9 5189.6 5201.2 5922.3 5933.6 5945.6 5946.9 5965.1 4458.9 4459.9 4461.3 4477.1 4486.7 5880.2 6173.0 6174.4 6195.5 6204.9 4247.0 4252.9 4253.3 4270.9 4276.9 4307.3 4310.1 4313.8 4321.7 4322.9 4791.0 4903.6 4907.7 4940.2 4992.9 5868.3 5891.2 5898.3 5915.4 5915.9 1473.5 1475.8 1481.9 1483.3 1488.3 987.5 995.3 1018.5 1027.3 1027.6 4958.7 4980.4 4984.6 4989.1 4995.3 5977.3 6010.2 6025.3 6029.5 6069.1 4917.3 4942.2 4943.5 4947.9 4955.1 3198.7 3227.1 3238.6 3252.2 3256.9 5612.0 5629.5 5638.5 5639.9 5647.3 6231.1 6247.9 6279.8 6288.9 6324.2 5564.0 5606.2 5613.9 5646.4 5660.9 6027.2 6084.8 6084.9 6104.8 6126.9 5692.4 5976.0 5989.0 6000.7 6003.6 5570.8 5582.6 5598.0 5621.6 5648.2 4215.4 4241.4 4246.3 4249.6 4252.4 5951.8 6098.1 6100.2 6105.9 6136.2 5862.2 5916.2 5934.0 5934.4 5943.5 5837.2 5841.8 5853.9 5879.4 5907.4 5820.5 5821.0 5827.9 5830.3 5851.1 5941.0 5972.5 5975.0 5989.3 6003.3 5801.9 5820.3 5827.8 5833.0 5837.7 18:31:11 INFO - PID 30507 | 18:31:11 INFO - PID 30507 | [#5] yui.html Cycles:180 Average:16723.99 Median:3304.88 stddev:21551.47 (652.1%) stddev-sans-first:21578.19 18:31:11 INFO - PID 30507 | Values: 664.6 690.5 724.5 735.6 752.0 13239.9 13596.5 13717.4 15397.0 16563.0 15076.7 15264.1 15302.0 15318.1 15360.0 828.8 840.8 843.8 846.3 847.0 788.1 845.4 846.0 850.8 855.4 881.1 881.4 882.9 884.0 884.2 876.4 877.9 878.7 879.3 882.4 641.1 642.1 645.4 647.5 647.6 14590.0 14681.6 14774.3 14803.8 14807.9 14895.9 15341.5 15418.2 15423.2 15442.2 11196.6 11430.1 11827.5 11941.8 11966.8 3001.1 3002.8 3015.4 3016.9 3019.6 84445.6 86604.0 86956.6 88004.0 88214.1 12478.8 12521.8 12766.5 12803.1 12823.5 21150.2 21987.0 22018.2 22036.7 22037.0 6037.9 6057.2 6104.7 6149.8 6194.9 2955.9 3033.2 3033.2 3034.0 3040.8 3242.7 3367.1 3563.8 3698.0 3702.1 701.5 712.6 713.1 713.9 714.3 515.2 526.7 532.5 533.0 537.2 9379.2 9657.6 9833.7 9898.4 10300.1 570.9 579.9 584.7 584.8 604.3 4159.8 5009.6 5163.0 5290.3 5588.1 73000.6 73530.0 73847.6 75453.0 80270.6 65694.7 67429.3 67968.7 68287.0 69272.7 58155.1 59394.7 59956.7 60920.4 61973.1 9706.7 10052.8 10646.1 10697.7 10872.5 26201.9 26556.5 26723.6 27793.7 28367.9 34293.3 34731.8 34830.1 35153.5 35342.6 13489.8 13571.2 13752.5 13755.9 13991.0 16232.5 16288.3 16392.4 16472.3 16704.5 14700.0 15139.1 15169.2 15445.7 15484.9 13795.7 13871.2 13937.2 14015.0 14274.6 14832.0 14995.5 15103.4 15116.5 15387.8 10076.8 10170.9 10186.8 10531.8 10642.7 11415.7 11980.7 12014.3 12158.1 12207.3 18:31:11 INFO - PID 30507 | -------- Summary: end -------- 18:31:11 INFO - PID 30507 | 18:31:12 INFO - TEST-INFO | 30507: exit 0 18:31:12 INFO - TEST-OK | dromaeo_css | took 1147993ms 18:31:12 INFO - TEST-START | kraken 18:31:12 INFO - operating with platform_type : linux_ 18:31:12 INFO - Initialising browser for kraken test... 18:31:12 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36886/getInfo.html -profile /tmp/tmpu7YWpp/profile 18:31:12 INFO - TEST-INFO | started process 30931 (/builds/slave/test/build/application/firefox/firefox http://localhost:36886/getInfo.html) 18:31:47 INFO - TEST-INFO | 30931: killed by SIGTERM 18:31:47 INFO - Browser initialized. 18:31:47 INFO - Running cycle 1/1 for kraken test... 18:31:47 INFO - TEST-INFO | started process 31161 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpu7YWpp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:31:59 INFO - PID 31161 | RSS: Main: 188723200 18:31:59 INFO - PID 31161 | 18:32:01 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36886/tests/kraken/driver.html?testName=audio-beat-detection) 18:32:03 INFO - PID 31161 | RSS: Main: 192286720 18:32:03 INFO - PID 31161 | 18:32:05 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36886/tests/kraken/driver.html?testName=audio-dft) 18:32:06 INFO - PID 31161 | RSS: Main: 191238144 18:32:06 INFO - PID 31161 | 18:32:09 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36886/tests/kraken/driver.html?testName=audio-fft) 18:32:09 INFO - PID 31161 | RSS: Main: 186404864 18:32:09 INFO - PID 31161 | 18:32:12 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36886/tests/kraken/driver.html?testName=audio-oscillator) 18:32:12 INFO - PID 31161 | RSS: Main: 187031552 18:32:12 INFO - PID 31161 | 18:32:14 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36886/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:32:15 INFO - PID 31161 | RSS: Main: 186781696 18:32:15 INFO - PID 31161 | 18:32:18 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36886/tests/kraken/driver.html?testName=imaging-darkroom) 18:32:19 INFO - PID 31161 | RSS: Main: 187846656 18:32:19 INFO - PID 31161 | 18:32:22 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36886/tests/kraken/driver.html?testName=imaging-desaturate) 18:32:23 INFO - PID 31161 | RSS: Main: 187215872 18:32:23 INFO - PID 31161 | 18:32:26 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36886/tests/kraken/driver.html?testName=json-parse-financial) 18:32:27 INFO - PID 31161 | RSS: Main: 187703296 18:32:27 INFO - PID 31161 | 18:32:28 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36886/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:32:29 INFO - PID 31161 | RSS: Main: 187834368 18:32:29 INFO - PID 31161 | 18:32:31 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36886/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:32:31 INFO - PID 31161 | RSS: Main: 187662336 18:32:31 INFO - PID 31161 | 18:32:34 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36886/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:32:34 INFO - PID 31161 | RSS: Main: 187510784 18:32:34 INFO - PID 31161 | 18:32:37 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36886/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:32:38 INFO - PID 31161 | RSS: Main: 187568128 18:32:38 INFO - PID 31161 | 18:32:41 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36886/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:32:42 INFO - PID 31161 | RSS: Main: 187834368 18:32:42 INFO - PID 31161 | 18:32:43 INFO - PID 31161 | Cycle 1(1): loaded http://localhost:36886/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36886/tests/kraken/driver.html?testName=ai-astar) 18:32:44 INFO - PID 31161 | RSS: Main: 189079552 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | __start_tp_report 18:32:44 INFO - PID 31161 | _x_x_mozilla_page_load 18:32:44 INFO - PID 31161 | _x_x_mozilla_page_load_details 18:32:44 INFO - PID 31161 | |i|pagename|runs| 18:32:44 INFO - PID 31161 | |0;ai-astar;102;111;110;108;109;109;110;110;110;112 18:32:44 INFO - PID 31161 | |1;audio-beat-detection;150;128;126;160;135;121;122;122;149;124 18:32:44 INFO - PID 31161 | |2;audio-dft;165;166;163;165;169;169;174;163;163;164 18:32:44 INFO - PID 31161 | |3;audio-fft;74;74;75;115;75;74;75;80;98;76 18:32:44 INFO - PID 31161 | |4;audio-oscillator;82;84;107;84;84;82;111;82;83;111 18:32:44 INFO - PID 31161 | |5;imaging-gaussian-blur;119;115;114;115;114;117;118;113;113;115 18:32:44 INFO - PID 31161 | |6;imaging-darkroom;152;154;151;150;150;151;150;152;150;149 18:32:44 INFO - PID 31161 | |7;imaging-desaturate;94;95;94;94;94;94;96;96;95;95 18:32:44 INFO - PID 31161 | |8;json-parse-financial;57;53;57;58;58;55;56;58;55;56 18:32:44 INFO - PID 31161 | |9;json-stringify-tinderbox;56;56;58;55;57;57;58;62;55;57 18:32:44 INFO - PID 31161 | |10;stanford-crypto-aes;87;87;90;88;85;91;87;88;115;87 18:32:44 INFO - PID 31161 | |11;stanford-crypto-ccm;146;139;140;130;131;142;132;134;131;147 18:32:44 INFO - PID 31161 | |12;stanford-crypto-pbkdf2;190;191;193;191;198;196;188;200;201;200 18:32:44 INFO - PID 31161 | |13;stanford-crypto-sha256-iterative;73;76;74;73;72;76;73;72;75;76 18:32:44 INFO - PID 31161 | __end_tp_report 18:32:44 INFO - PID 31161 | __start_cc_report 18:32:44 INFO - PID 31161 | _x_x_mozilla_cycle_collect,1060 18:32:44 INFO - PID 31161 | __end_cc_report 18:32:44 INFO - PID 31161 | __startTimestamp1496280764202__endTimestamp 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | ------- Summary: start ------- 18:32:44 INFO - PID 31161 | Number of tests: 14 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:2.73 (2.5%) stddev-sans-first:1.17 18:32:44 INFO - PID 31161 | Values: 102.0 111.0 110.0 108.0 109.0 109.0 110.0 110.0 110.0 112.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#1] audio-beat-detection Cycles:10 Average:133.70 Median:131.50 stddev:14.20 (10.8%) stddev-sans-first:13.78 18:32:44 INFO - PID 31161 | Values: 150.0 128.0 126.0 160.0 135.0 121.0 122.0 122.0 149.0 124.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#2] audio-dft Cycles:10 Average:166.10 Median:165.50 stddev:3.57 (2.2%) stddev-sans-first:3.77 18:32:44 INFO - PID 31161 | Values: 165.0 166.0 163.0 165.0 169.0 169.0 174.0 163.0 163.0 164.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.00 stddev:13.83 (18.4%) stddev-sans-first:14.40 18:32:44 INFO - PID 31161 | Values: 74.0 74.0 75.0 115.0 75.0 74.0 75.0 80.0 98.0 76.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:82.50 stddev:12.95 (15.7%) stddev-sans-first:13.32 18:32:44 INFO - PID 31161 | Values: 82.0 84.0 107.0 84.0 84.0 82.0 111.0 82.0 83.0 111.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#5] imaging-gaussian-blur Cycles:10 Average:115.30 Median:115.00 stddev:2.06 (1.8%) stddev-sans-first:1.69 18:32:44 INFO - PID 31161 | Values: 119.0 115.0 114.0 115.0 114.0 117.0 118.0 113.0 113.0 115.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#6] imaging-darkroom Cycles:10 Average:150.90 Median:151.00 stddev:1.45 (1.0%) stddev-sans-first:1.48 18:32:44 INFO - PID 31161 | Values: 152.0 154.0 151.0 150.0 150.0 151.0 150.0 152.0 150.0 149.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 18:32:44 INFO - PID 31161 | Values: 94.0 95.0 94.0 94.0 94.0 94.0 96.0 96.0 95.0 95.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:57.00 stddev:1.64 (2.9%) stddev-sans-first:1.72 18:32:44 INFO - PID 31161 | Values: 57.0 53.0 57.0 58.0 58.0 55.0 56.0 58.0 55.0 56.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#9] json-stringify-tinderbox Cycles:10 Average:57.10 Median:57.00 stddev:2.02 (3.6%) stddev-sans-first:2.11 18:32:44 INFO - PID 31161 | Values: 56.0 56.0 58.0 55.0 57.0 57.0 58.0 62.0 55.0 57.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:87.50 stddev:8.77 (10.0%) stddev-sans-first:9.21 18:32:44 INFO - PID 31161 | Values: 87.0 87.0 90.0 88.0 85.0 91.0 87.0 88.0 115.0 87.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#11] stanford-crypto-ccm Cycles:10 Average:137.20 Median:139.50 stddev:6.44 (4.6%) stddev-sans-first:6.00 18:32:44 INFO - PID 31161 | Values: 146.0 139.0 140.0 130.0 131.0 142.0 132.0 134.0 131.0 147.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.80 Median:197.00 stddev:4.78 (2.4%) stddev-sans-first:4.74 18:32:44 INFO - PID 31161 | Values: 190.0 191.0 193.0 191.0 198.0 196.0 188.0 200.0 201.0 200.0 18:32:44 INFO - PID 31161 | 18:32:44 INFO - PID 31161 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:74.50 stddev:1.63 (2.2%) stddev-sans-first:1.69 18:32:44 INFO - PID 31161 | Values: 73.0 76.0 74.0 73.0 72.0 76.0 73.0 72.0 75.0 76.0 18:32:44 INFO - PID 31161 | -------- Summary: end -------- 18:32:44 INFO - PID 31161 | 18:32:45 INFO - TEST-INFO | 31161: exit 0 18:32:45 INFO - TEST-OK | kraken | took 93318ms 18:32:45 INFO - SUITE-END | took 1241s 18:32:45 INFO - Completed test suite (00:20:41) 18:32:45 INFO - javascript benchmark 18:32:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6753.376752378679, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2703.3376822678447, 2746.395792925558, 2767.4326763013582, 2774.9445011099774, 2792.204221796787, 5266.973665131679, 5380.0, 5380.273663055491, 5403.324584426947, 5413.485718856712, 5334.226537152111, 5339.946600534003, 5409.43200963898, 5484.588655850807, 5511.669299842002, 4784.928226076594, 4796.112719146951, 4801.2798080287885, 4814.0, 4848.321235027099, 4781.115493633672, 4829.855104346875, 4847.1517484440255, 4866.78099485524, 4907.82822601209, 4713.363695901044, 4756.595689366373, 4763.261694437368, 4776.068666610013, 4824.107540105092, 4785.736784476852, 4787.329773831666, 4794.400699912511, 4796.664233503656, 4806.375171227718, 4706.3411122442885, 4708.599769019637, 4708.717476951394, 4731.621470282404, 4748.667593268473, 5270.1040823060175, 5333.493318134771, 5339.572834173296, 5344.91982620261, 5389.676619402849, 5745.454181852759, 5754.079347304411, 5792.4497172768515, 5798.3911689272445, 5856.238688970449, 5927.140564618149, 5947.910781338283, 5963.045912653998, 5965.105234214859, 5977.880442391128, 5479.561635069185, 5492.670439773627, 5495.313085864267, 5499.340079190522, 5500.972495137498, 5879.265091863517, 6014.097885317237, 6014.51883849291, 6020.036794112921, 6036.396360363929, 5681.431856814368, 5693.829185124453, 5739.397363276879, 5765.88468230633, 5786.07422812348, 5735.541156707453, 5869.911951320733, 5874.50066744323, 5874.500667443315, 5894.351621321651, 5732.197492351074, 5838.591298609056, 5838.766449341979, 5839.240898683229, 5848.502877255481, 5744.913826292609, 5757.280339957505, 5770.596058275964, 5778.768849245983, 5832.912506312408, 4804.8798780030775, 4820.25286080658, 4820.927686084711, 4852.2479015752615, 4859.708417494961, 4110.7328023678965, 4127.215828992482, 4155.833766649361, 4168.062186008185, 4235.7670328131635, 4752.738599377068, 4788.640851936118, 4812.51874812523, 4813.302071199727, 4825.252085926687, 5774.105013722879, 5796.188533605298, 5814.563907706939, 5851.678157701367, 5873.882522349529, 4764.0, 4766.737829419346, 4767.022760334124, 4782.378290822171, 4832.226843704991, 5019.472955339636, 5118.0, 5120.283160357553, 5124.385073791169, 5135.15269980449, 5299.258103865539, 5369.0, 5372.2747429098135, 5455.236266922555, 5456.48163424474, 5692.829215123553, 5725.971370143123, 5733.283339582552, 5815.360310365941, 5854.209681692917, 5331.280277162534, 5352.4915133062295, 5356.250124982584, 5389.299391079135, 5423.2407462954425, 5637.52081073097, 5714.571407144397, 5729.942700572941, 5756.453136951983, 5770.567207459373, 5802.216700745345, 5876.882462350643, 5898.3216930053595, 5900.822975310576, 5918.378570250062, 5624.100143976944, 5702.600817942704, 5707.0583353747825, 5708.229389032594, 5722.198892155185, 5575.8606034847835, 5588.133839254922, 5614.2701448811395, 5615.326160860723, 5669.773209071591, 5447.455254474521, 5526.198701188386, 5535.39110697831, 5552.167174923794, 5594.664320140641, 5580.469855363734, 5590.608657393944, 5649.039663257174, 5658.745356459051, 5663.943360566342, 5579.581531385244, 5581.302337207849, 5584.636998595079, 5600.215969764317, 5642.576806739429], "value": 5251.963184646236, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.1982220446486, 760.7413479416999, 784.0630446616294, 1063.5214153630868, 1089.9891001089986, 32741.345173096524, 33570.0, 33589.0, 33632.49551256733, 33665.960084789825, 34732.30535389291, 35004.474932875964, 35068.473972890366, 35068.473972890366, 35089.82455087721, 6362.331955144713, 6515.674216289191, 6801.795946121624, 6943.79168624942, 6943.930560694404, 6743.662816859162, 6839.794806155798, 6839.965800171017, 6848.794536163923, 6857.794266172023, 6795.354441328066, 6847.589144651312, 6848.657567121649, 6865.794026179223, 6890.138732658418, 6813.114295141625, 6841.521093523455, 6849.691763870588, 6856.348646878563, 6858.622775747332, 6773.830654233683, 6810.659467026629, 6812.591244525344, 6814.693338799716, 6816.727330906758, 32095.19762005945, 32095.839520802245, 32124.357512849845, 32147.678523214938, 32164.839175803972, 2933.0467598030514, 2967.807092538978, 2968.421157874215, 2974.7917645764805, 2983.284011837165, 24445.511089778105, 24523.141690040942, 24567.63148552773, 24638.0, 24650.13724519651, 3015.2612609910493, 3020.2147441665106, 3023.89416370426, 3035.666076731558, 3048.8170709757487, 120851.39574302072, 121492.0, 135986.3200684, 136032.31983840017, 137347.31326343404, 39298.2140357197, 39331.0, 39593.0, 39703.60296397057, 39751.00622484403, 40328.1934361317, 40681.0, 40749.59250407458, 40914.0, 40921.0, 11618.70953226176, 11750.471228794553, 11766.117541184445, 11853.229540079867, 11948.103892208119, 9641.37331073478, 9663.323567350219, 9672.516374181263, 9672.903270967201, 9694.321397502106, 12588.37058147089, 12634.81047784284, 12635.936820316023, 12654.456752109425, 12845.421956012002, 5254.316938798008, 5256.737163141827, 5356.544693701079, 5364.436734142859, 5368.489993450616, 851.3870013590205, 900.5812297281665, 908.6728777640044, 908.9545522723837, 920.07992007992, 20445.591088178153, 20977.58044839125, 21445.249416270668, 21583.784162158492, 21606.78393216079, 970.7815741458115, 972.6741541583649, 974.7904200596905, 976.0776066616979, 991.5884907763342, 10615.150787936947, 11035.44822758859, 11172.329660220414, 11228.719282017852, 11261.099112071015, 168268.0, 169963.0, 170053.14973425053, 170161.0, 171951.0, 39492.60507394947, 39635.801820991284, 39932.2013559733, 40108.398374024415, 40263.79868100699, 1178.587494376961, 1206.5596057439147, 1209.8245754365744, 1211.8424604801319, 1225.7609766095525, 19383.612327753646, 19948.102335395233, 20148.496287592927, 20839.270625528035, 21001.0, 28652.283692907844, 28699.42601148007, 28701.712982869903, 28709.42581148407, 28789.42421151607, 147642.0, 148077.0, 148759.25620371828, 149070.0, 149555.0, 28436.857815710788, 28477.864160188485, 28743.99396021213, 30009.699903001565, 30154.54768178523, 34960.650393495736, 35165.0, 35239.47140792839, 35451.11372215612, 35580.466293005105, 25961.480770384864, 27094.1871743848, 27177.1846844595, 27258.0, 27648.72351276542, 26393.0, 26473.60289595619, 27260.727392725817, 27347.58978615362, 27968.30079248035, 30347.24131896632, 30538.389232214788, 30565.847170764006, 30617.54073688852, 30638.234044149074, 26090.34774130586, 26109.0, 26639.46721065529, 27103.0, 27900.441991160467, 21112.472188195417, 21767.673484898056, 22132.778672213073, 22185.66721499211, 22310.88844555767], "value": 14243.720954689572, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.1202919737166, 410.6406893967778, 415.81496234175785, 426.54146792198384, 434.2032370599949, 12967.74064518709, 13574.185548867075, 17549.824501754978, 18190.545236369097, 18207.362742304023, 16199.595010124753, 16967.23647435865, 17035.48893533193, 17038.914805425986, 17047.659046819055, 5518.144687573423, 5668.149777533383, 5679.062954612484, 5681.147827825818, 5698.743556539945, 5424.972875135619, 5477.643953143033, 5479.808206712766, 5505.256790333314, 5514.944850551483, 5432.049391356497, 5456.672599644034, 5460.590455715817, 5476.342838859342, 5478.616496845222, 5226.268322434862, 5256.500632439912, 5271.710055946922, 5289.206619007141, 5481.451854814526, 4498.820047198108, 4668.766561671937, 4730.172219861545, 4734.313524538957, 4790.041991601659, 22102.447438813997, 22118.115275389126, 22211.888940555356, 22214.222502212346, 22320.77679223203, 1632.159437889488, 1639.8032236131621, 1654.040656419274, 1655.8758093143063, 1656.9585760355967, 14384.85615143856, 14676.633084172874, 14682.119072855663, 14730.55808325752, 14764.557063288012, 1992.1832048859899, 1994.6110508450859, 2004.2484068474323, 2009.357005758158, 2013.6878783788536, 100252.0, 101515.0, 101750.0, 101900.0, 101963.0, 10566.31318964265, 10814.243002989715, 10865.185111116698, 11391.20261581698, 11532.25040372373, 15867.206639668202, 15871.365145394286, 15882.76175857363, 15897.0, 15909.363625454853, 7212.4590655699985, 7255.8911616325795, 7460.515066520658, 7522.322990930733, 7679.232076792276, 6783.253842077367, 6843.315668433117, 6912.377885990285, 6917.031615573824, 6925.099737034153, 6888.862222755517, 7021.964890175516, 7051.753188638373, 7118.822029449201, 7205.315495027964, 1986.358143668917, 2001.2995451591826, 2032.6442872497255, 2088.822450091759, 2165.5560610075213, 820.4216027700456, 845.2435070611768, 851.9872201916976, 872.646578135856, 883.3639779358851, 17064.146792660318, 17182.0, 17385.0, 17398.478045658652, 17420.0, 868.4224990381452, 878.9648414063494, 880.9647614095364, 889.3418870036127, 891.4072142025481, 9036.412633178823, 9481.146696797321, 9757.365771224848, 11166.553337866557, 12349.0, 359245.0, 359679.0, 364342.0, 365429.0, 366497.0, 87670.0, 89211.0, 89282.10717892868, 89576.5521172403, 89861.0, 1068.7167900506372, 1072.3780207479642, 1102.2559772153763, 1104.6133853151493, 1107.983380249297, 17558.0, 17836.0, 17839.10804459798, 18358.72461913099, 18759.155837987597, 45101.54898450973, 45116.0, 45235.321470178635, 46314.76842615765, 46622.405311362854, 56974.715126424104, 57106.14340784975, 57191.71404142953, 57477.42522574888, 57489.712551436976, 9222.95388523053, 9255.490947997925, 9290.16388525036, 9323.393979391318, 9362.859557106513, 15554.0, 15559.222038897784, 15674.059556426651, 15798.0, 15827.366905323657, 12832.0, 13117.0, 13308.201507909556, 13330.533431329857, 13369.933150334185, 11794.29234245948, 11939.403029848647, 12171.33057681836, 12605.68485787826, 12617.621471355875, 13257.734845303234, 13263.403146858222, 13416.731665366442, 13548.661283467993, 13568.79646805279, 9131.634734610541, 9132.726018219464, 9136.132067453582, 9145.268378529834, 9155.542222888696, 12951.086005649031, 13132.868671313165, 13483.932580337036, 13526.79709804334, 13571.86428135706], "value": 9243.053355771362, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1198.5325722968043, 1245.925244485331, 1259.7480503899217, 1260.6155122687576, 1262.8295180150676, 9602.5678844452, 9751.12239898409, 10013.699589012323, 10014.649487267947, 10278.845817312746, 9920.55357508912, 10035.749106272347, 10036.397816131037, 10058.849117263248, 10068.597256109746, 2086.6974288728165, 2138.2409244718133, 2140.721706178195, 2142.260919982611, 2146.7960543748395, 1899.9525011874744, 1943.640426521098, 1944.3292064237885, 1944.7277381166648, 1958.2167133146713, 1955.7946415626297, 1981.821636052755, 1991.3428568572408, 1994.3418671838258, 1995.8103980121866, 2020.9494762630904, 2036.3381900882273, 2044.2845004248543, 2047.3653167518116, 2048.1909645690016, 1858.7676540432446, 1876.9155388007573, 1885.6228754249205, 1895.0808857704003, 1952.3654812185955, 9688.273379496566, 9771.609135634566, 9775.608975640966, 9776.0, 9804.754881127958, 5127.641065125443, 5310.787568497255, 5336.38631557372, 5336.786528538853, 5342.519173274385, 3094.3037816491333, 3241.6434192238835, 3280.425925463034, 3282.5896762904636, 3312.4865645824934, 998.7902540466511, 1035.7358873487287, 1044.3211912257093, 1090.2368342160519, 1114.7324642085923, 14283.500077497238, 14388.496402625859, 14458.132512049206, 14467.78298325526, 14495.420183192766, 4061.0, 4158.209940111429, 4201.747895126302, 4310.3103503439925, 4332.653387728974, 10789.298695584763, 10836.729081773019, 10865.348079115278, 10908.181886358396, 10908.672739817817, 4934.284528743384, 4971.030649023478, 5010.549050585465, 5029.0947629427055, 5037.823676171316, 4681.578657920803, 4722.740249286254, 4727.621790256771, 4751.714897106184, 4761.7142971421135, 3703.6111208323277, 3784.7161462890394, 3860.3244432224246, 3860.9613903861164, 3866.8839934802, 1060.6393826099163, 1099.9395033273247, 1100.9064229540418, 1114.9888501115047, 1118.0384869012642, 658.8122385120314, 680.4590350671228, 685.9519833611599, 704.9964750176318, 706.0221593093603, 5114.488551144856, 5121.1550094234035, 5122.590192784568, 5290.021346050993, 5307.044731948286, 522.7725939216454, 526.7892842862809, 532.3345817727841, 536.5385768239311, 537.2666310486236, 3088.104449709559, 3097.9535306970415, 3110.688931106871, 3149.1339881532303, 3151.3697260547983, 13007.674808129683, 13583.1850088993, 13589.932050339685, 13628.0, 13631.591052268448, 12599.0, 12725.490980360866, 13135.211887286794, 13225.338733063309, 13253.801192982113, 6760.1549806274215, 6906.930930690629, 7029.051078104492, 7096.432285417154, 7111.857762844714, 5538.584606154474, 5886.411358864079, 5896.174535564938, 5931.555133364928, 6073.089036644538, 11465.0, 11533.270009498307, 11778.116641251769, 11932.28406295625, 12095.516179352728, 12286.385680716106, 12582.370881455707, 12648.114631975674, 12795.0, 12811.0, 7919.722809701633, 8227.794305142419, 8231.0, 8232.0, 8233.024074222263, 9140.908590914272, 9238.95380523093, 9273.397229180082, 9291.2102471288, 9299.395539289924, 7399.186089530255, 7598.468107232441, 7617.581033043014, 7623.504472209289, 7816.57008864518, 7577.280158384945, 7677.193894640982, 7758.92241077582, 7821.648025838737, 7880.0, 6849.4177994871, 6937.0, 6987.336203060701, 6999.020137180901, 7006.9649651751415, 4884.92672609904, 4970.204767237225, 4997.900041999067, 5007.39911210657, 5055.620828437808, 4753.928691069706, 4869.147899117711, 4909.239067944474, 4927.334809800775, 4929.556339929423], "value": 4448.196344672926, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.891148199799, 375.30755755630867, 376.572590110225, 381.7728451571314, 383.05576753303103, 5310.468953104688, 5495.230667706519, 5502.80740174094, 5603.7478313475885, 5652.219550841487, 5347.224652425396, 5475.4524547545225, 5491.28613280273, 5497.642653227537, 5505.889882202354, 4332.198543269497, 4332.461975998523, 4340.131973605276, 4342.392065110887, 4352.151330490556, 4281.379200015996, 4297.849575264866, 4342.435483387155, 4359.171757366106, 4364.716293440932, 4271.658267338621, 4307.440032795748, 4321.524632290446, 4325.82696692132, 4331.631811296043, 4324.291904697627, 4337.934930976038, 4338.110687309126, 4352.934705979413, 4354.281543545311, 3886.0, 3923.9215215695526, 3944.822482988244, 3944.915148334231, 3952.7628342299267, 5442.727863606804, 5458.044842152607, 5459.945400546023, 5478.671279723189, 5481.0, 5176.715280659562, 5177.715225662587, 5179.922301165486, 5189.584833213334, 5201.193814958688, 5922.289325280994, 5933.584649074566, 5945.583809133363, 5946.940530594682, 5965.105234214902, 4458.888527786831, 4459.933101003455, 4461.330800379937, 4477.104579084196, 4486.685931984763, 5880.176775251425, 6172.969135154341, 6174.382561743834, 6195.473384762346, 6204.87590248197, 4247.048301430476, 4252.851150209728, 4253.255680255943, 4270.935935960963, 4276.914461710749, 4307.341673455742, 4310.137972405531, 4313.84901528445, 4321.719088259253, 4322.924996001937, 4790.952090479121, 4903.583195428429, 4907.650396141018, 4940.2342636891335, 4992.850214493571, 5868.2664666916635, 5891.204687367151, 5898.2894960461845, 5915.40845915405, 5915.911261331084, 1473.5358362115899, 1475.837657857635, 1481.888858335629, 1483.317673870032, 1488.3153749275455, 987.4766373821742, 995.323180237431, 1018.5111146649501, 1027.275770581753, 1027.6249169053242, 4958.677685950402, 4980.402351717745, 4984.576311013532, 4989.077020751172, 4995.300657907896, 5977.296470505972, 6010.158577799111, 6025.276966764016, 6029.547783916224, 6069.089636554464, 4917.336159618477, 4942.233953737176, 4943.505649435099, 4947.90104197914, 4955.108080545537, 3198.6801319867827, 3227.0802821196316, 3238.578984732017, 3252.1869532616847, 3256.886008989674, 5611.9719401403545, 5629.549636029108, 5638.4925356718095, 5639.915401268984, 5647.265855438849, 6231.127642130105, 6247.937520624736, 6279.780207692709, 6288.9056664150075, 6324.209473815773, 5563.972180139073, 5606.187102870061, 5613.887722245532, 5646.350669672958, 5660.85847853807, 6027.21646185993, 6084.787032453843, 6084.939150608438, 6104.786332478342, 6126.938730612636, 5692.43075692444, 5976.043832986875, 5989.0, 6000.699965001645, 6003.5797494174985, 5570.832875013756, 5582.581306401955, 5598.048331783525, 5621.578381621313, 5648.15277708347, 4215.430916826189, 4241.448611680586, 4246.256905041543, 4249.575042495726, 4252.447181866338, 5951.821445356646, 6098.11577321277, 6100.176476175837, 6105.870413973429, 6136.23297087864, 5862.208601838868, 5916.171735957056, 5933.970330148321, 5934.43622855828, 5943.52451803846, 5837.153612726216, 5841.824745257649, 5853.887761325334, 5879.353271140257, 5907.405000649715, 5820.534357251325, 5821.010428227127, 5827.94172058274, 5830.271216097988, 5851.1223316502865, 5941.0, 5972.492338151305, 5974.970125149521, 5989.311229208697, 6003.309619393825, 5801.882312955657, 5820.330661973758, 5827.79602713903, 5833.0, 5837.67892765885], "value": 4439.184343211845, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.5514277862442, 690.5269890125264, 724.4638967164299, 735.6432130416747, 751.9548827070377, 13239.933800330997, 13596.45614175433, 13717.382717777698, 15397.0, 16563.0, 15076.6984660307, 15264.084154950682, 15302.0, 15318.080915145072, 15360.0, 828.838376516578, 840.7519781664423, 843.784834867108, 846.3144852669327, 846.9872951905726, 788.0661416221795, 845.3744229270327, 846.0228436156218, 850.8170743290192, 855.3755758296447, 881.1276835932445, 881.3962435731512, 882.9249513791335, 884.0, 884.1644645809712, 876.3646356391629, 877.934154938379, 878.714417814213, 879.3317079019927, 882.3735148044864, 641.1376698340724, 642.1202951955851, 645.3643161485933, 647.5499527828161, 647.6437959122463, 14590.0, 14681.559553213421, 14774.261286935718, 14803.777943330859, 14807.925960370128, 14895.925520372437, 15341.539753807403, 15418.152001639904, 15423.151726655029, 15442.227888605637, 11196.552137914556, 11430.085593152528, 11827.467763950643, 11941.761164776743, 11966.82049769254, 3001.0546677796424, 3002.8498575071158, 3015.381846721417, 3016.9396612067635, 3019.607451031352, 84445.57777211136, 86604.0, 86956.56521717351, 88004.0, 88214.11785882124, 12478.81281780774, 12521.812172817416, 12766.489340426278, 12803.103782735305, 12823.487060517662, 21150.15399384038, 21987.0, 22018.229361972575, 22036.669449958263, 22037.0, 6037.879242415215, 6057.212562366864, 6104.664243466652, 6149.815505534841, 6194.915889719371, 2955.896543621006, 3033.1507177990197, 3033.1507177990197, 3034.013945467732, 3040.847957602111, 3242.6595207502874, 3367.107716455141, 3563.768355056965, 3697.9815100924325, 3702.148505843671, 701.5194591704699, 712.6187489693093, 713.0694443750955, 713.8929160625844, 714.2785786355714, 515.2400209690676, 526.715573590257, 532.5021105266511, 532.97068661224, 537.231758585219, 9379.24966002718, 9657.613695452103, 9833.655822046338, 9898.356606820678, 10300.124489418333, 570.9457601527848, 579.9101139323411, 584.7427132061879, 584.7602482982043, 604.2809057221892, 4159.83360665576, 5009.599232061426, 5163.0, 5290.312259406252, 5588.0577957995065, 73000.63499682468, 73530.0, 73847.63076184691, 75453.0, 80270.5986470064, 65694.67152664301, 67429.32570674329, 67968.6601566989, 68287.0, 69272.6536367315, 58155.127673084935, 59394.703026485455, 59956.70021649864, 60920.39079609236, 61973.07040394397, 9706.660266890625, 10052.849207262043, 10646.148308135176, 10697.732556686145, 10872.510737016695, 26201.868990654926, 26556.46887062209, 26723.599146012435, 27793.722062779114, 28367.858160709064, 34293.314133717686, 34731.82634086813, 34830.12924676904, 35153.47269791007, 35342.64657353394, 13489.797653035015, 13571.217900330606, 13752.518661846787, 13755.862441375859, 13991.0, 16232.513024609281, 16288.267027984006, 16392.426265080663, 16472.341106356092, 16704.498865034067, 14700.0, 15139.091654500766, 15169.165695886832, 15445.691086178438, 15484.922575387502, 13795.724085518434, 13871.167729936236, 13937.233452160228, 14015.0, 14274.571762847132, 14832.0, 14995.475158369842, 15103.44040453175, 15116.470923517623, 15387.846121538641, 10076.84884726715, 10170.89829101729, 10186.796264074528, 10531.78936421252, 10642.73393165146, 11415.714607134556, 11980.700482488008, 12014.279143251084, 12158.088143389105, 12207.267563946192], "value": 6947.776917738708, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 110.0, 108.0, 109.0, 109.0, 110.0, 110.0, 110.0, 112.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 128.0, 126.0, 160.0, 135.0, 121.0, 122.0, 122.0, 149.0, 124.0], "value": 133.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 163.0, 165.0, 169.0, 169.0, 174.0, 163.0, 163.0, 164.0], "value": 166.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 115.0, 75.0, 74.0, 75.0, 80.0, 98.0, 76.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 107.0, 84.0, 84.0, 82.0, 111.0, 82.0, 83.0, 111.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 115.0, 114.0, 115.0, 114.0, 117.0, 118.0, 113.0, 113.0, 115.0], "value": 115.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 154.0, 151.0, 150.0, 150.0, 151.0, 150.0, 152.0, 150.0, 149.0], "value": 150.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 96.0, 96.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 53.0, 57.0, 58.0, 58.0, 55.0, 56.0, 58.0, 55.0, 56.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 58.0, 55.0, 57.0, 57.0, 58.0, 62.0, 55.0, 57.0], "value": 57.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 90.0, 88.0, 85.0, 91.0, 87.0, 88.0, 115.0, 87.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 139.0, 140.0, 130.0, 131.0, 142.0, 132.0, 134.0, 131.0, 147.0], "value": 137.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 191.0, 193.0, 191.0, 198.0, 196.0, 188.0, 200.0, 201.0, 200.0], "value": 194.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 74.0, 73.0, 72.0, 76.0, 73.0, 72.0, 75.0, 76.0], "value": 74.0, "unit": "score"}]}]} 18:32:45 INFO - Return code: 0 18:32:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:32:45 INFO - # TBPL SUCCESS # 18:32:45 INFO - Running post-action listener: _resource_record_post_action 18:32:45 INFO - [mozharness: 2017-06-01 01:32:45.982806Z] Finished run-tests step (success) 18:32:45 INFO - Running post-run listener: _resource_record_post_run 18:32:46 INFO - Total resource usage - Wall time: 1258s; CPU: 13.0%; Read bytes: 4096; Write bytes: 409817088; Read time: 8; Write time: 385204 18:32:46 INFO - TinderboxPrint: CPU usage
12.7% 18:32:46 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 18:32:46 INFO - TinderboxPrint: I/O write bytes / time
409,817,088 / 385,204 18:32:46 INFO - TinderboxPrint: CPU idle
8,764.8 (87.3%) 18:32:46 INFO - TinderboxPrint: CPU user
1,223.0 (12.2%) 18:32:46 INFO - TinderboxPrint: Swap in / out
0 / 0 18:32:46 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99229696; Read time: 0; Write time: 143668 18:32:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 18:32:46 INFO - run-tests - Wall time: 1242s; CPU: 13.0%; Read bytes: 0; Write bytes: 310587392; Read time: 0; Write time: 241536 18:32:46 INFO - Running post-run listener: _upload_blobber_files 18:32:46 INFO - Blob upload gear active. 18:32:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:32:46 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 18:32:46 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:32:46 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:32:47 INFO - (blobuploader) - INFO - Open directory for files ... 18:32:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:32:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:32:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:48 INFO - (blobuploader) - INFO - Done attempting. 18:32:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:32:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:32:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:52 INFO - (blobuploader) - INFO - Done attempting. 18:32:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:32:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:32:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:53 INFO - (blobuploader) - INFO - Done attempting. 18:32:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:32:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:32:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:32:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:32:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:32:54 INFO - (blobuploader) - INFO - Done attempting. 18:32:54 INFO - (blobuploader) - INFO - Iteration through files over. 18:32:54 INFO - Return code: 0 18:32:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:32:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:32:54 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1023e46b9e1b775f85b0b7bed737a7225ecb3c834c2602e0b23d89a6e24855b20182ff2a1012038e353f6252a7f14238f2cd4e683d485f6f0e21777c88d38715", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/985f5fc2ce6d7c97dcd68fd7a678dca035275b44a915f4cf669460c34d4e74def9d373f921f5315a2c1ef9aeee6880d8f311587e96790a8cae399311edcf9f66", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78f330f69109a3929a7c056eb577cb163960348acaf6af55fedd1729bf109a50a13721c00e84c7de1748e7b759d469f381cfd6c978a1a0f0e6443af497cbcebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9533ead9b8abe4a5b5b361fb8d9871877eb79e8bc14cd9ad6966ed52bf14bce5552c4063bbd3f2cbe24cb9374e8c2671bfab27d072c7619e0ef618544d742a38"} 18:32:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:32:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:32:54 INFO - Contents: 18:32:54 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1023e46b9e1b775f85b0b7bed737a7225ecb3c834c2602e0b23d89a6e24855b20182ff2a1012038e353f6252a7f14238f2cd4e683d485f6f0e21777c88d38715", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/985f5fc2ce6d7c97dcd68fd7a678dca035275b44a915f4cf669460c34d4e74def9d373f921f5315a2c1ef9aeee6880d8f311587e96790a8cae399311edcf9f66", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78f330f69109a3929a7c056eb577cb163960348acaf6af55fedd1729bf109a50a13721c00e84c7de1748e7b759d469f381cfd6c978a1a0f0e6443af497cbcebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9533ead9b8abe4a5b5b361fb8d9871877eb79e8bc14cd9ad6966ed52bf14bce5552c4063bbd3f2cbe24cb9374e8c2671bfab27d072c7619e0ef618544d742a38"} 18:32:54 INFO - Running post-run listener: copy_logs_to_upload_dir 18:32:54 INFO - Copying logs to upload dir... 18:32:54 INFO - mkdir: /builds/slave/test/build/upload/logs 18:32:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1317.085470 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2017-05-31 18:32:53.958511) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 18:32:53.962796) ========= 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=5c37b704b588aaa989324530000001d0-1496277235.346173-57920793 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/sha512/1023e46b9e1b775f85b0b7bed737a7225ecb3c834c2602e0b23d89a6e24855b20182ff2a1012038e353f6252a7f14238f2cd4e683d485f6f0e21777c88d38715", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/985f5fc2ce6d7c97dcd68fd7a678dca035275b44a915f4cf669460c34d4e74def9d373f921f5315a2c1ef9aeee6880d8f311587e96790a8cae399311edcf9f66", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78f330f69109a3929a7c056eb577cb163960348acaf6af55fedd1729bf109a50a13721c00e84c7de1748e7b759d469f381cfd6c978a1a0f0e6443af497cbcebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9533ead9b8abe4a5b5b361fb8d9871877eb79e8bc14cd9ad6966ed52bf14bce5552c4063bbd3f2cbe24cb9374e8c2671bfab27d072c7619e0ef618544d742a38"} build_url:https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011285 build_url: 'https://queue.taskcluster.net/v1/task/S2XffUwES2Oyfh8-LbSVjw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1023e46b9e1b775f85b0b7bed737a7225ecb3c834c2602e0b23d89a6e24855b20182ff2a1012038e353f6252a7f14238f2cd4e683d485f6f0e21777c88d38715", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/985f5fc2ce6d7c97dcd68fd7a678dca035275b44a915f4cf669460c34d4e74def9d373f921f5315a2c1ef9aeee6880d8f311587e96790a8cae399311edcf9f66", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78f330f69109a3929a7c056eb577cb163960348acaf6af55fedd1729bf109a50a13721c00e84c7de1748e7b759d469f381cfd6c978a1a0f0e6443af497cbcebc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9533ead9b8abe4a5b5b361fb8d9871877eb79e8bc14cd9ad6966ed52bf14bce5552c4063bbd3f2cbe24cb9374e8c2671bfab27d072c7619e0ef618544d742a38"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 18:32:53.989188) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:32:53.989485) ========= 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=5c37b704b588aaa989324530000001d0-1496277235.346173-57920793 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 18:32:54.069821) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 18:32:54.070110) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 18:32:54.073385) ========= ========= Total master_lag: 0.49 =========