builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-049 starttime: 1503884702.44 results: success (0) revision: d10c97627b51a226e19d0fa801201897fe1932f6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:02.436401) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:02.436865) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:02.437152) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:02.459377) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:02.459724) ========= 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=db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-27 18:45:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2017-08-27 18:45:02 (227 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.082053 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:02.559444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:02.560013) ========= 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=db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018356 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:02.611868) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:02.612230) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d10c97627b51a226e19d0fa801201897fe1932f6 --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 d10c97627b51a226e19d0fa801201897fe1932f6 --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=db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-27 18:45:02,648 truncating revision to first 12 chars 2017-08-27 18:45:02,648 Setting DEBUG logging. 2017-08-27 18:45:02,648 attempt 1/10 2017-08-27 18:45:02,648 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d10c97627b51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-27 18:45:03,216 unpacking tar archive at: mozilla-central-d10c97627b51/testing/mozharness/ program finished with exit code 0 elapsedTime=0.871390 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:03.498973) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:03.499302) ========= script_repo_revision: d10c97627b51a226e19d0fa801201897fe1932f6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:03.499708) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:03.500388) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-27 18:45:03.512589) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-08-27 18:45:03.513026) ========= /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=db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:45:03 INFO - MultiFileLogger online at 20170827 18:45:03 in /builds/slave/test 18:45:03 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:45:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:45:03 INFO - {'append_to_log': False, 18:45:03 INFO - 'base_work_dir': '/builds/slave/test', 18:45:03 INFO - 'blob_upload_branch': 'Firefox', 18:45:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:45:03 INFO - 'branch': 'Firefox', 18:45:03 INFO - 'buildbot_json_path': 'buildprops.json', 18:45:03 INFO - 'code_coverage': False, 18:45:03 INFO - 'config_files': ('talos/linux_config.py',), 18:45:03 INFO - 'default_actions': ('clobber', 18:45:03 INFO - 'read-buildbot-config', 18:45:03 INFO - 'download-and-extract', 18:45:03 INFO - 'populate-webroot', 18:45:03 INFO - 'create-virtualenv', 18:45:03 INFO - 'install', 18:45:03 INFO - 'setup-mitmproxy', 18:45:03 INFO - 'run-tests'), 18:45:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:45:03 INFO - 'disable_ccov_upload': False, 18:45:03 INFO - 'download_minidump_stackwalk': True, 18:45:03 INFO - 'download_symbols': 'ondemand', 18:45:03 INFO - 'enable_stylo': False, 18:45:03 INFO - 'enable_webrender': False, 18:45:03 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 18:45:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:45:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:45:03 INFO - 'gecko_profile': False, 18:45:03 INFO - 'gecko_profile_interval': 0, 18:45:03 INFO - 'installer_path': 'installer.exe', 18:45:03 INFO - 'log_level': 'info', 18:45:03 INFO - 'log_name': 'talos', 18:45:03 INFO - 'log_to_console': True, 18:45:03 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:45:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:45:03 INFO - 'opt_config_files': (), 18:45:03 INFO - 'pip_index': False, 18:45:03 INFO - 'suite': 'dromaeojs-e10s', 18:45:03 INFO - 'system_bits': '32', 18:45:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:45:03 INFO - 'title': 'talos-linux64-ix-049', 18:45:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:45:03 INFO - 'use_talos_json': True, 18:45:03 INFO - 'verify': 'False', 18:45:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:45:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:45:03 INFO - 'work_dir': 'build'} 18:45:03 INFO - [mozharness: 2017-08-28 01:45:03.696498Z] Running clobber step. 18:45:03 INFO - Running pre-action listener: _resource_record_pre_action 18:45:03 INFO - Running main action method: clobber 18:45:03 INFO - rmtree: /builds/slave/test/build 18:45:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:45:05 INFO - Running post-action listener: _resource_record_post_action 18:45:05 INFO - [mozharness: 2017-08-28 01:45:05.218282Z] Finished clobber step (success) 18:45:05 INFO - [mozharness: 2017-08-28 01:45:05.218379Z] Running read-buildbot-config step. 18:45:05 INFO - Running pre-action listener: _resource_record_pre_action 18:45:05 INFO - Running main action method: read_buildbot_config 18:45:05 INFO - Using buildbot properties: 18:45:05 INFO - { 18:45:05 INFO - "project": "", 18:45:05 INFO - "product": "firefox", 18:45:05 INFO - "who": "philringnalda@gmail.com", 18:45:05 INFO - "installer_path": "public/build/target.tar.bz2", 18:45:05 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:45:05 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 18:45:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 18:45:05 INFO - "stage_platform": "linux64-pgo", 18:45:05 INFO - "basedir": "/builds/slave/test", 18:45:05 INFO - "buildnumber": 144, 18:45:05 INFO - "platform": "ubuntu64_hw", 18:45:05 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 18:45:05 INFO - "slavebuilddir": "test", 18:45:05 INFO - "taskId": "bPpw4osgTO6CQVeXUfY2-w", 18:45:05 INFO - "branch": "mozilla-central", 18:45:05 INFO - "script_repo_revision": "production", 18:45:05 INFO - "revision": "d10c97627b51a226e19d0fa801201897fe1932f6", 18:45:05 INFO - "slavename": "talos-linux64-ix-049", 18:45:05 INFO - "repo_path": "mozilla-central" 18:45:05 INFO - } 18:45:05 INFO - Finding installer, test and symbols from parent task. 18:45:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bPpw4osgTO6CQVeXUfY2-w'}, attempt #1 18:45:05 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bPpw4osgTO6CQVeXUfY2-w'}, attempt #1 18:45:06 INFO - Task dependencies: cxN0HP4PS6CEXlCtAZijtQ 18:45:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ'}, attempt #1 18:45:06 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.tar.bz2 18:45:06 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.test_packages.json 18:45:06 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.crashreporter-symbols.zip 18:45:06 INFO - Running post-action listener: _resource_record_post_action 18:45:06 INFO - [mozharness: 2017-08-28 01:45:06.571295Z] Finished read-buildbot-config step (success) 18:45:06 INFO - [mozharness: 2017-08-28 01:45:06.571511Z] Running download-and-extract step. 18:45:06 INFO - Running pre-action listener: _resource_record_pre_action 18:45:06 INFO - Running main action method: download_and_extract 18:45:06 INFO - mkdir: /builds/slave/test/build/tests 18:45:06 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:45:06 INFO - trying https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.test_packages.json 18:45:06 INFO - Downloading https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 18:45:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 18:45:07 INFO - Downloaded 989 bytes. 18:45:07 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 18:45:07 INFO - Using the following test package requirements: 18:45:07 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 18:45:07 INFO - u'common': [u'target.common.tests.zip'], 18:45:07 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 18:45:07 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 18:45:07 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 18:45:07 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 18:45:07 INFO - u'mozbase': [u'target.common.tests.zip'], 18:45:07 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 18:45:07 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 18:45:07 INFO - u'web-platform': [u'target.common.tests.zip', 18:45:07 INFO - u'target.web-platform.tests.tar.gz'], 18:45:07 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 18:45:07 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 18:45:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.common.tests.zip 18:45:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:45:07 INFO - Fetch https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.common.tests.zip into memory 18:45:08 INFO - Content-Length response header: 55719293 18:45:08 INFO - Bytes received: 55719293 18:45:19 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 18:45:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.common.tests.zip 18:45:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 18:45:19 INFO - Fetch https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.common.tests.zip into memory 18:45:20 INFO - Content-Length response header: 55719293 18:45:20 INFO - Bytes received: 55719293 18:45:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.talos.tests.zip 18:45:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 18:45:32 INFO - Fetch https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.talos.tests.zip into memory 18:45:32 INFO - Content-Length response header: 14496611 18:45:32 INFO - Bytes received: 14496611 18:45: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:45:33 INFO - trying https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.tar.bz2 18:45:33 INFO - Downloading https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 18:45:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 18:45:35 INFO - Downloaded 62335124 bytes. 18:45:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.tar.bz2 18:45:35 INFO - mkdir: /builds/slave/test/properties 18:45:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:45:35 INFO - Writing to file /builds/slave/test/properties/build_url 18:45:35 INFO - Contents: 18:45:35 INFO - build_url:https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.tar.bz2 18:45:35 INFO - Running post-action listener: _resource_record_post_action 18:45:35 INFO - Running post-action listener: find_tests_for_verification 18:45:35 INFO - Running post-action listener: set_extra_try_arguments 18:45:35 INFO - [mozharness: 2017-08-28 01:45:35.986365Z] Finished download-and-extract step (success) 18:45:35 INFO - [mozharness: 2017-08-28 01:45:35.986570Z] Running populate-webroot step. 18:45:35 INFO - Running pre-action listener: _resource_record_pre_action 18:45:35 INFO - Running main action method: populate_webroot 18:45:35 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:45:35 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 18:45:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:45:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:45:35 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 18:45:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:45:35 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:45:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:45:35 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:45:35 INFO - 'talos_options': ['--stylo'], 18:45:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:45:35 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:45:35 INFO - 'tests': ['damp', 'tps']}, 18:45:35 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:45:35 INFO - 'talos_options': ['--stylo'], 18:45:35 INFO - 'tests': ['damp', 'tps']}, 18:45:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:45:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:45:36 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 18:45:36 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:45:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:45:36 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'talos_options': ['--stylo'], 18:45:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:45:36 INFO - 'other-e10s': {'tests': ['a11yr', 18:45:36 INFO - 'ts_paint', 18:45:36 INFO - 'tpaint', 18:45:36 INFO - 'sessionrestore', 18:45:36 INFO - 'sessionrestore_many_windows', 18:45:36 INFO - 'sessionrestore_no_auto_restore', 18:45:36 INFO - 'tabpaint']}, 18:45:36 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 18:45:36 INFO - 'tests': ['a11yr', 18:45:36 INFO - 'ts_paint', 18:45:36 INFO - 'tpaint', 18:45:36 INFO - 'sessionrestore', 18:45:36 INFO - 'sessionrestore_many_windows', 18:45:36 INFO - 'sessionrestore_no_auto_restore', 18:45:36 INFO - 'tabpaint']}, 18:45:36 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 18:45:36 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 18:45:36 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 18:45:36 INFO - 'tests': ['bloom_basic_singleton']}, 18:45:36 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 18:45:36 INFO - 'tests': ['bloom_basic']}, 18:45:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:45:36 INFO - 'tsvgr_opacity', 18:45:36 INFO - 'tart', 18:45:36 INFO - 'tscrollx', 18:45:36 INFO - 'tsvg_static']}, 18:45:36 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 18:45:36 INFO - 'tests': ['tsvgx', 18:45:36 INFO - 'tsvgr_opacity', 18:45:36 INFO - 'tart', 18:45:36 INFO - 'tscrollx', 18:45:36 INFO - 'tsvg_static']}, 18:45:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:45:36 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'talos_options': ['--stylo'], 18:45:36 INFO - 'tests': ['tp5o']}, 18:45:36 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:45:36 INFO - 'talos_options': ['--mitmproxy', 18:45:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:45:36 INFO - '--firstNonBlankPaint'], 18:45:36 INFO - 'tests': ['tp6_google', 18:45:36 INFO - 'tp6_youtube', 18:45:36 INFO - 'tp6_amazon', 18:45:36 INFO - 'tp6_facebook']}, 18:45:36 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:45:36 INFO - 'talos_options': ['--stylo', 18:45:36 INFO - '--mitmproxy', 18:45:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:45:36 INFO - '--firstNonBlankPaint'], 18:45:36 INFO - 'tests': ['tp6_google', 18:45:36 INFO - 'tp6_youtube', 18:45:36 INFO - 'tp6_amazon', 18:45:36 INFO - 'tp6_facebook']}, 18:45:36 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 18:45:36 INFO - 'talos_options': ['--stylo', 18:45:36 INFO - '--stylo-threads=1', 18:45:36 INFO - '--mitmproxy', 18:45:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 18:45:36 INFO - '--firstNonBlankPaint'], 18:45:36 INFO - 'tests': ['tp6_google', 18:45:36 INFO - 'tp6_youtube', 18:45:36 INFO - 'tp6_amazon', 18:45:36 INFO - 'tp6_facebook']}, 18:45:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'talos_options': ['--xperf_path', 18:45:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:45:36 INFO - 'tests': ['tp5n']}, 18:45:36 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 18:45:36 INFO - 'talos_options': ['--stylo', 18:45:36 INFO - '--xperf_path', 18:45:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:45:36 INFO - 'tests': ['tp5n']}}} 18:45:36 INFO - Running post-action listener: _resource_record_post_action 18:45:36 INFO - [mozharness: 2017-08-28 01:45:36.006068Z] Finished populate-webroot step (success) 18:45:36 INFO - [mozharness: 2017-08-28 01:45:36.006157Z] Running create-virtualenv step. 18:45:36 INFO - Running pre-action listener: _resource_record_pre_action 18:45:36 INFO - Running main action method: create_virtualenv 18:45:36 INFO - Creating virtualenv /builds/slave/test/build/venv 18:45:36 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:45:36 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 18:45:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:45:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:45:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:45:36 INFO - Installing distribute.............................................................................................................................................................................................done. 18:45:36 INFO - Installing pip...............done. 18:45:36 INFO - Return code: 0 18:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:45:36 INFO - Reading from file tmpfile_stdout 18:45:36 INFO - Output received: 18:45:36 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 18:45:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:45: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:45:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45: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:45:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45: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', 'pip>=1.5'] in /builds/slave/test/build 18:45: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 pip>=1.5 18:45:37 INFO - Using env: {'DISPLAY': ':0', 18:45:37 INFO - 'HOME': '/home/cltbld', 18:45:37 INFO - 'LANG': 'en_US.UTF-8', 18:45:37 INFO - 'LANGUAGE': 'en_US:en', 18:45:37 INFO - 'LOGNAME': 'cltbld', 18:45:37 INFO - 'MAIL': '/var/mail/cltbld', 18:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:37 INFO - 'MOZ_NO_REMOTE': '1', 18:45:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:37 INFO - 'NO_EM_RESTART': '1', 18:45:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:37 INFO - 'PWD': '/builds/slave/test', 18:45:37 INFO - 'SHELL': '/bin/bash', 18:45:37 INFO - 'SHLVL': '1', 18:45:37 INFO - 'TERM': 'linux', 18:45:37 INFO - 'TMOUT': '86400', 18:45:37 INFO - 'USER': 'cltbld', 18:45:37 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:37 INFO - '_': '/tools/buildbot/bin/python'} 18:45:38 INFO - Ignoring indexes: http://pypi.python.org/simple/ 18:45:38 INFO - Downloading/unpacking pip>=1.5 18:45:38 INFO - Running setup.py egg_info for package pip 18:45:38 INFO - warning: no files found matching 'pip/cacert.pem' 18:45:38 INFO - warning: no files found matching '*.html' under directory 'docs' 18:45:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:45:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:45:38 INFO - Installing collected packages: pip 18:45:38 INFO - Found existing installation: pip 1.1 18:45:38 INFO - Uninstalling pip: 18:45:38 INFO - Successfully uninstalled pip 18:45:38 INFO - Running setup.py install for pip 18:45:38 INFO - warning: no files found matching 'pip/cacert.pem' 18:45:38 INFO - warning: no files found matching '*.html' under directory 'docs' 18:45:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 18:45:38 INFO - no previously-included directories found matching 'docs/_build/_sources' 18:45:38 INFO - Installing pip script to /builds/slave/test/build/venv/bin 18:45:38 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 18:45:38 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 18:45:38 INFO - Successfully installed pip 18:45:38 INFO - Cleaning up... 18:45:38 INFO - Return code: 0 18:45:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:38 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 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45:38 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:45:38 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:45:38 INFO - Using env: {'DISPLAY': ':0', 18:45:38 INFO - 'HOME': '/home/cltbld', 18:45:38 INFO - 'LANG': 'en_US.UTF-8', 18:45:38 INFO - 'LANGUAGE': 'en_US:en', 18:45:38 INFO - 'LOGNAME': 'cltbld', 18:45:38 INFO - 'MAIL': '/var/mail/cltbld', 18:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:38 INFO - 'MOZ_NO_REMOTE': '1', 18:45:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:38 INFO - 'NO_EM_RESTART': '1', 18:45:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:38 INFO - 'PWD': '/builds/slave/test', 18:45:38 INFO - 'SHELL': '/bin/bash', 18:45:38 INFO - 'SHLVL': '1', 18:45:38 INFO - 'TERM': 'linux', 18:45:38 INFO - 'TMOUT': '86400', 18:45:38 INFO - 'USER': 'cltbld', 18:45:38 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:38 INFO - '_': '/tools/buildbot/bin/python'} 18:45:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:38 INFO - Downloading/unpacking psutil>=3.1.1 18:45: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:45: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:45: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:45: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:45:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:45:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:45:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:45:39 INFO - Installing collected packages: psutil 18:45:39 INFO - Running setup.py install for psutil 18:45:39 INFO - building 'psutil._psutil_linux' extension 18:45:39 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:45:39 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:45:39 INFO - building 'psutil._psutil_posix' extension 18:45:39 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:45:40 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:45:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:45:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:45:40 INFO - Successfully installed psutil 18:45:40 INFO - Cleaning up... 18:45:40 INFO - Return code: 0 18:45:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:45:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:45:40 INFO - Using env: {'DISPLAY': ':0', 18:45:40 INFO - 'HOME': '/home/cltbld', 18:45:40 INFO - 'LANG': 'en_US.UTF-8', 18:45:40 INFO - 'LANGUAGE': 'en_US:en', 18:45:40 INFO - 'LOGNAME': 'cltbld', 18:45:40 INFO - 'MAIL': '/var/mail/cltbld', 18:45:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:40 INFO - 'MOZ_NO_REMOTE': '1', 18:45:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:40 INFO - 'NO_EM_RESTART': '1', 18:45:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:40 INFO - 'PWD': '/builds/slave/test', 18:45:40 INFO - 'SHELL': '/bin/bash', 18:45:40 INFO - 'SHLVL': '1', 18:45:40 INFO - 'TERM': 'linux', 18:45:40 INFO - 'TMOUT': '86400', 18:45:40 INFO - 'USER': 'cltbld', 18:45:40 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:40 INFO - '_': '/tools/buildbot/bin/python'} 18:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:45:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:45:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:45:41 INFO - Installing collected packages: mozsystemmonitor 18:45:41 INFO - Running setup.py install for mozsystemmonitor 18:45:41 INFO - Successfully installed mozsystemmonitor 18:45:41 INFO - Cleaning up... 18:45:41 INFO - Return code: 0 18:45:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:45:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:41 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 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45:41 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:45:41 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:45:41 INFO - Using env: {'DISPLAY': ':0', 18:45:41 INFO - 'HOME': '/home/cltbld', 18:45:41 INFO - 'LANG': 'en_US.UTF-8', 18:45:41 INFO - 'LANGUAGE': 'en_US:en', 18:45:41 INFO - 'LOGNAME': 'cltbld', 18:45:41 INFO - 'MAIL': '/var/mail/cltbld', 18:45:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:41 INFO - 'MOZ_NO_REMOTE': '1', 18:45:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:41 INFO - 'NO_EM_RESTART': '1', 18:45:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:41 INFO - 'PWD': '/builds/slave/test', 18:45:41 INFO - 'SHELL': '/bin/bash', 18:45:41 INFO - 'SHLVL': '1', 18:45:41 INFO - 'TERM': 'linux', 18:45:41 INFO - 'TMOUT': '86400', 18:45:41 INFO - 'USER': 'cltbld', 18:45:41 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:41 INFO - '_': '/tools/buildbot/bin/python'} 18:45:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:41 INFO - Downloading/unpacking jsonschema==2.5.1 18:45:41 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:45:41 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:45:41 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:45:41 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:45:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:45:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:43 INFO - Downloading functools32-3.2.3-2.tar.gz 18:45:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:45:43 INFO - warning: no files found matching '*.txt' 18:45:43 INFO - no previously-included directories found matching 'build' 18:45:43 INFO - no previously-included directories found matching 'dist' 18:45:43 INFO - no previously-included directories found matching '.git*' 18:45:43 INFO - Installing collected packages: jsonschema, functools32 18:45:43 INFO - Running setup.py install for functools32 18:45:43 INFO - warning: no files found matching '*.txt' 18:45:43 INFO - no previously-included directories found matching 'build' 18:45:43 INFO - no previously-included directories found matching 'dist' 18:45:43 INFO - no previously-included directories found matching '.git*' 18:45:43 INFO - Successfully installed jsonschema functools32 18:45:43 INFO - Cleaning up... 18:45:43 INFO - Return code: 0 18:45:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:45:43 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:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:43 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:45:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:43 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 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45:43 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:45:43 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:45:43 INFO - Using env: {'DISPLAY': ':0', 18:45:43 INFO - 'HOME': '/home/cltbld', 18:45:43 INFO - 'LANG': 'en_US.UTF-8', 18:45:43 INFO - 'LANGUAGE': 'en_US:en', 18:45:43 INFO - 'LOGNAME': 'cltbld', 18:45:43 INFO - 'MAIL': '/var/mail/cltbld', 18:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:43 INFO - 'MOZ_NO_REMOTE': '1', 18:45:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:43 INFO - 'NO_EM_RESTART': '1', 18:45:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:43 INFO - 'PWD': '/builds/slave/test', 18:45:43 INFO - 'SHELL': '/bin/bash', 18:45:43 INFO - 'SHLVL': '1', 18:45:43 INFO - 'TERM': 'linux', 18:45:43 INFO - 'TMOUT': '86400', 18:45:43 INFO - 'USER': 'cltbld', 18:45:43 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:43 INFO - '_': '/tools/buildbot/bin/python'} 18:45:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:45:43 INFO - Cleaning up... 18:45:43 INFO - Return code: 0 18:45:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:45:43 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:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:43 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:45:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:43 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 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45:43 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:45:43 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:45:43 INFO - Using env: {'DISPLAY': ':0', 18:45:43 INFO - 'HOME': '/home/cltbld', 18:45:43 INFO - 'LANG': 'en_US.UTF-8', 18:45:43 INFO - 'LANGUAGE': 'en_US:en', 18:45:43 INFO - 'LOGNAME': 'cltbld', 18:45:43 INFO - 'MAIL': '/var/mail/cltbld', 18:45:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:43 INFO - 'MOZ_NO_REMOTE': '1', 18:45:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:43 INFO - 'NO_EM_RESTART': '1', 18:45:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:43 INFO - 'PWD': '/builds/slave/test', 18:45:43 INFO - 'SHELL': '/bin/bash', 18:45:43 INFO - 'SHLVL': '1', 18:45:43 INFO - 'TERM': 'linux', 18:45:43 INFO - 'TMOUT': '86400', 18:45:43 INFO - 'USER': 'cltbld', 18:45:43 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:43 INFO - '_': '/tools/buildbot/bin/python'} 18:45:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:43 INFO - Downloading/unpacking blobuploader==1.2.4 18:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:45:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:45:44 INFO - Downloading blobuploader-1.2.4.tar.gz 18:45:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:45:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:45: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:45: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:45: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:45: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:45:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:45:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:45: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:45: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:45: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:45: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:45:45 INFO - Downloading docopt-0.6.1.tar.gz 18:45:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:45:45 INFO - Installing collected packages: blobuploader, requests, docopt 18:45:45 INFO - Running setup.py install for blobuploader 18:45:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:45:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:45:45 INFO - Running setup.py install for requests 18:45:45 INFO - Running setup.py install for docopt 18:45:46 INFO - Successfully installed blobuploader requests docopt 18:45:46 INFO - Cleaning up... 18:45:46 INFO - Return code: 0 18:45:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45:46 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:45:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:46 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:45:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:46 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 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45:46 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:45:46 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:45:46 INFO - Using env: {'DISPLAY': ':0', 18:45:46 INFO - 'HOME': '/home/cltbld', 18:45:46 INFO - 'LANG': 'en_US.UTF-8', 18:45:46 INFO - 'LANGUAGE': 'en_US:en', 18:45:46 INFO - 'LOGNAME': 'cltbld', 18:45:46 INFO - 'MAIL': '/var/mail/cltbld', 18:45:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:46 INFO - 'MOZ_NO_REMOTE': '1', 18:45:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:46 INFO - 'NO_EM_RESTART': '1', 18:45:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:46 INFO - 'PWD': '/builds/slave/test', 18:45:46 INFO - 'SHELL': '/bin/bash', 18:45:46 INFO - 'SHLVL': '1', 18:45:46 INFO - 'TERM': 'linux', 18:45:46 INFO - 'TMOUT': '86400', 18:45:46 INFO - 'USER': 'cltbld', 18:45:46 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:46 INFO - '_': '/tools/buildbot/bin/python'} 18:45:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:45:46 INFO - Running setup.py (path:/tmp/pip-u5wM3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:45:46 INFO - Running setup.py (path:/tmp/pip-QhEBeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:45:46 INFO - Running setup.py (path:/tmp/pip-2h0Tvs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:45:46 INFO - Running setup.py (path:/tmp/pip-6JgcXO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:45:46 INFO - Running setup.py (path:/tmp/pip-tHgylt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:45:46 INFO - Running setup.py (path:/tmp/pip-eU6ZSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:45:46 INFO - Running setup.py (path:/tmp/pip-HR1YDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:45:46 INFO - Running setup.py (path:/tmp/pip-YEYckU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:45:46 INFO - Running setup.py (path:/tmp/pip-eKKD4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:45:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:45:46 INFO - Running setup.py (path:/tmp/pip-VEAdjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:45:47 INFO - Running setup.py (path:/tmp/pip-4rZMM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:45:47 INFO - Running setup.py (path:/tmp/pip-Y3WZvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:45:47 INFO - Running setup.py (path:/tmp/pip-TnEzXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:45:47 INFO - Running setup.py (path:/tmp/pip-yX5jMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:47 INFO - Running setup.py (path:/tmp/pip-bWifDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:45:47 INFO - Running setup.py (path:/tmp/pip-kPZUiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:45:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:45:47 INFO - Running setup.py (path:/tmp/pip-Lo6Clq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:45:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:45:47 INFO - Running setup.py install for manifestparser 18:45:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:45:47 INFO - Running setup.py install for mozcrash 18:45:47 INFO - Running setup.py install for mozdebug 18:45:47 INFO - Running setup.py install for mozdevice 18:45:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:45:47 INFO - Running setup.py install for mozfile 18:45:48 INFO - Running setup.py install for mozhttpd 18:45:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Running setup.py install for mozinfo 18:45:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Running setup.py install for mozInstall 18:45:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Running setup.py install for mozleak 18:45:48 INFO - Running setup.py install for mozlog 18:45:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Running setup.py install for moznetwork 18:45:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Running setup.py install for mozprocess 18:45:48 INFO - Running setup.py install for mozprofile 18:45:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:45:48 INFO - Running setup.py install for mozrunner 18:45:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:45:49 INFO - Running setup.py install for mozscreenshot 18:45:49 INFO - Running setup.py install for moztest 18:45:49 INFO - Running setup.py install for mozversion 18:45:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:45:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:45:49 INFO - Cleaning up... 18:45:49 INFO - Return code: 0 18:45:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45:49 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:45:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:49 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:45:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:49 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 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45:49 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:45:49 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:45:49 INFO - Using env: {'DISPLAY': ':0', 18:45:49 INFO - 'HOME': '/home/cltbld', 18:45:49 INFO - 'LANG': 'en_US.UTF-8', 18:45:49 INFO - 'LANGUAGE': 'en_US:en', 18:45:49 INFO - 'LOGNAME': 'cltbld', 18:45:49 INFO - 'MAIL': '/var/mail/cltbld', 18:45:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:49 INFO - 'MOZ_NO_REMOTE': '1', 18:45:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:49 INFO - 'NO_EM_RESTART': '1', 18:45:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:49 INFO - 'PWD': '/builds/slave/test', 18:45:49 INFO - 'SHELL': '/bin/bash', 18:45:49 INFO - 'SHLVL': '1', 18:45:49 INFO - 'TERM': 'linux', 18:45:49 INFO - 'TMOUT': '86400', 18:45:49 INFO - 'USER': 'cltbld', 18:45:49 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:49 INFO - '_': '/tools/buildbot/bin/python'} 18:45:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:45:49 INFO - Running setup.py (path:/tmp/pip-CSKzlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:45:49 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:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:45:49 INFO - Running setup.py (path:/tmp/pip-WHTJdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:45:49 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:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:45:49 INFO - Running setup.py (path:/tmp/pip-EctrH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:45:49 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:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:45:49 INFO - Running setup.py (path:/tmp/pip-EJ8r0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:45:49 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:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:45:49 INFO - Running setup.py (path:/tmp/pip-dXO_kA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:45:49 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:45:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:45:49 INFO - Running setup.py (path:/tmp/pip-_ScH4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:45:50 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:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:45:50 INFO - Running setup.py (path:/tmp/pip-4ZeZEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:45:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:45:50 INFO - Running setup.py (path:/tmp/pip-7rfyne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:45:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:45:50 INFO - Running setup.py (path:/tmp/pip-N0kj_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:45:50 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:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:45:50 INFO - Running setup.py (path:/tmp/pip-ATRwyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:45:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:45:50 INFO - Running setup.py (path:/tmp/pip-UEXN3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:45:50 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:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:45:50 INFO - Running setup.py (path:/tmp/pip-JBfvfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:45:50 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:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:45:50 INFO - Running setup.py (path:/tmp/pip-xvkZWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:45:50 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:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:45:50 INFO - Running setup.py (path:/tmp/pip-S5Fbmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:45:50 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:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:50 INFO - Running setup.py (path:/tmp/pip-pDvvV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:45:50 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:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:45:50 INFO - Running setup.py (path:/tmp/pip-sNpzqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:45:50 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:45:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:45:50 INFO - Running setup.py (path:/tmp/pip-HNDgVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:45:50 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:45:50 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:45:50 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:45:50 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:45:50 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:45:50 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:45:50 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:45:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:45:50 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:45:50 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:45:50 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:45:50 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:45:51 INFO - Downloading blessings-1.6.tar.gz 18:45:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:45:51 INFO - Installing collected packages: blessings 18:45:51 INFO - Running setup.py install for blessings 18:45:52 INFO - Successfully installed blessings 18:45:52 INFO - Cleaning up... 18:45:52 INFO - Return code: 0 18:45:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:45:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:45:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:45:52 INFO - Reading from file tmpfile_stdout 18:45:52 INFO - Current package versions: 18:45:52 INFO - argparse == 1.2.1 18:45:52 INFO - blessings == 1.6 18:45:52 INFO - blobuploader == 1.2.4 18:45:52 INFO - docopt == 0.6.1 18:45:52 INFO - functools32 == 3.2.3-2 18:45:52 INFO - jsonschema == 2.5.1 18:45:52 INFO - manifestparser == 1.1 18:45:52 INFO - mozInstall == 1.13 18:45:52 INFO - mozcrash == 1.0 18:45:52 INFO - mozdebug == 0.1 18:45:52 INFO - mozdevice == 0.51 18:45:52 INFO - mozfile == 1.2 18:45:52 INFO - mozhttpd == 0.7 18:45:52 INFO - mozinfo == 0.10 18:45:52 INFO - mozleak == 0.1 18:45:52 INFO - mozlog == 3.5 18:45:52 INFO - moznetwork == 0.27 18:45:52 INFO - mozprocess == 0.25 18:45:52 INFO - mozprofile == 0.28 18:45:52 INFO - mozrunner == 6.13 18:45:52 INFO - mozscreenshot == 0.1 18:45:52 INFO - mozsystemmonitor == 0.3 18:45:52 INFO - moztest == 0.8 18:45:52 INFO - mozversion == 1.4 18:45:52 INFO - psutil == 3.1.1 18:45:52 INFO - requests == 1.2.3 18:45:52 INFO - wsgiref == 0.1.2 18:45:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:45:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:52 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 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45:52 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:45:52 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:45:52 INFO - Using env: {'DISPLAY': ':0', 18:45:52 INFO - 'HOME': '/home/cltbld', 18:45:52 INFO - 'LANG': 'en_US.UTF-8', 18:45:52 INFO - 'LANGUAGE': 'en_US:en', 18:45:52 INFO - 'LOGNAME': 'cltbld', 18:45:52 INFO - 'MAIL': '/var/mail/cltbld', 18:45:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:52 INFO - 'MOZ_NO_REMOTE': '1', 18:45:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:52 INFO - 'NO_EM_RESTART': '1', 18:45:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:52 INFO - 'PWD': '/builds/slave/test', 18:45:52 INFO - 'SHELL': '/bin/bash', 18:45:52 INFO - 'SHLVL': '1', 18:45:52 INFO - 'TERM': 'linux', 18:45:52 INFO - 'TMOUT': '86400', 18:45:52 INFO - 'USER': 'cltbld', 18:45:52 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:52 INFO - '_': '/tools/buildbot/bin/python'} 18:45:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:52 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:45:52 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:45:52 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:45:52 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:45:52 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:45:52 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:45:52 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:45:52 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:45:52 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:45:52 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:45:52 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:45:52 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:45:52 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:45:52 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:45:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 18:45:53 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:45:53 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:45:53 INFO - Installing collected packages: simplejson 18:45:53 INFO - Running setup.py install for simplejson 18:45:53 INFO - building 'simplejson._speedups' extension 18:45:53 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 18:45:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 18:45:54 INFO - Successfully installed simplejson 18:45:54 INFO - Cleaning up... 18:45:54 INFO - Return code: 0 18:45:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:45:54 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:45:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:45:54 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:45:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:45:54 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 0x14d91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a4a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1847f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, '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': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', '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:45:54 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:45:54 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:45:54 INFO - Using env: {'DISPLAY': ':0', 18:45:54 INFO - 'HOME': '/home/cltbld', 18:45:54 INFO - 'LANG': 'en_US.UTF-8', 18:45:54 INFO - 'LANGUAGE': 'en_US:en', 18:45:54 INFO - 'LOGNAME': 'cltbld', 18:45:54 INFO - 'MAIL': '/var/mail/cltbld', 18:45:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:45:54 INFO - 'MOZ_NO_REMOTE': '1', 18:45:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:45:54 INFO - 'NO_EM_RESTART': '1', 18:45:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:45:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:45:54 INFO - 'PWD': '/builds/slave/test', 18:45:54 INFO - 'SHELL': '/bin/bash', 18:45:54 INFO - 'SHLVL': '1', 18:45:54 INFO - 'TERM': 'linux', 18:45:54 INFO - 'TMOUT': '86400', 18:45:54 INFO - 'USER': 'cltbld', 18:45:54 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:45:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:45:54 INFO - '_': '/tools/buildbot/bin/python'} 18:45:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:45:55 INFO - Cleaning up... 18:45:55 INFO - Return code: 0 18:45:55 INFO - Running post-action listener: _resource_record_post_action 18:45:55 INFO - Running post-action listener: _start_resource_monitoring 18:45:55 INFO - Starting resource monitoring. 18:45:55 INFO - [mozharness: 2017-08-28 01:45:55.052137Z] Finished create-virtualenv step (success) 18:45:55 INFO - [mozharness: 2017-08-28 01:45:55.052598Z] Running install step. 18:45:55 INFO - Running pre-action listener: _resource_record_pre_action 18:45:55 INFO - Running main action method: install 18:45:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:45:55 INFO - Reading from file tmpfile_stdout 18:45:55 INFO - Detecting whether we're running mozinstall >=1.0... 18:45:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:45:55 INFO - Reading from file tmpfile_stdout 18:45:55 INFO - Output received: 18:45:55 INFO - Usage: mozinstall [options] installer 18:45:55 INFO - Options: 18:45:55 INFO - -h, --help show this help message and exit 18:45:55 INFO - -d DEST, --destination=DEST 18:45:55 INFO - Directory to install application into. [default: 18:45:55 INFO - "/builds/slave/test"] 18:45:55 INFO - --app=APP Application being installed. [default: firefox] 18:45:55 INFO - mkdir: /builds/slave/test/build/application 18:45:55 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:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 18:46:13 INFO - Reading from file tmpfile_stdout 18:46:13 INFO - Output received: 18:46:13 INFO - /builds/slave/test/build/application/firefox/firefox 18:46:13 INFO - Running post-action listener: _resource_record_post_action 18:46:13 INFO - [mozharness: 2017-08-28 01:46:13.214553Z] Finished install step (success) 18:46:13 INFO - [mozharness: 2017-08-28 01:46:13.214895Z] Running setup-mitmproxy step. 18:46:13 INFO - Running pre-action listener: _resource_record_pre_action 18:46:13 INFO - Running main action method: setup_mitmproxy 18:46:13 INFO - Skipping: mitmproxy is not required 18:46:13 INFO - Running post-action listener: _resource_record_post_action 18:46:13 INFO - [mozharness: 2017-08-28 01:46:13.216553Z] Finished setup-mitmproxy step (success) 18:46:13 INFO - [mozharness: 2017-08-28 01:46:13.216863Z] Running run-tests step. 18:46:13 INFO - Running pre-action listener: _resource_record_pre_action 18:46:13 INFO - Running pre-action listener: _set_gcov_prefix 18:46:13 INFO - Running main action method: run_tests 18:46:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:46:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:46:13 INFO - Python 2.7.3 18:46:13 INFO - Return code: 0 18:46:13 INFO - grabbing minidump binary from tooltool 18:46:13 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:46:13 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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 0x1845130>, '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 0x1846030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18464c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:46:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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:46:13 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 18:46:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--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:46:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:46:13 INFO - Return code: 0 18:46:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:46:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:46:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:46:13 INFO - ENV: RUST_BACKTRACE is now full 18:46:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:46:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:46:13 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/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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:46:13 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/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-049 --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:46:13 INFO - Using env: {'DISPLAY': ':0', 18:46:13 INFO - 'HOME': '/home/cltbld', 18:46:13 INFO - 'LANG': 'en_US.UTF-8', 18:46:13 INFO - 'LANGUAGE': 'en_US:en', 18:46:13 INFO - 'LOGNAME': 'cltbld', 18:46:13 INFO - 'MAIL': '/var/mail/cltbld', 18:46:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:46:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:46:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:13 INFO - 'MOZ_NO_REMOTE': '1', 18:46:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:46:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:13 INFO - 'NO_EM_RESTART': '1', 18:46:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:13 INFO - 'PWD': '/builds/slave/test', 18:46:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:46:13 INFO - 'RUST_BACKTRACE': 'full', 18:46:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:46:13 INFO - 'SHELL': '/bin/bash', 18:46:13 INFO - 'SHLVL': '1', 18:46:13 INFO - 'TERM': 'linux', 18:46:13 INFO - 'TMOUT': '86400', 18:46:13 INFO - 'USER': 'cltbld', 18:46:13 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:46:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:13 INFO - '_': '/tools/buildbot/bin/python'} 18:46:13 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/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--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:46:13 INFO - mozversion application_buildid: 20170828003210 18:46:13 INFO - mozversion application_changeset: d10c97627b51a226e19d0fa801201897fe1932f6 18:46:13 INFO - mozversion application_display_name: Nightly 18:46:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:46:13 INFO - mozversion application_name: Firefox 18:46:13 INFO - mozversion application_remotingname: firefox 18:46:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:46:13 INFO - mozversion application_vendor: Mozilla 18:46:13 INFO - mozversion application_version: 57.0a1 18:46:13 INFO - mozversion platform_buildid: 20170828003210 18:46:13 INFO - mozversion platform_changeset: d10c97627b51a226e19d0fa801201897fe1932f6 18:46:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:46:13 INFO - mozversion platform_version: 57.0a1 18:46:13 INFO - using testdate: 1503884773 18:46:13 INFO - actual date: 1503884773 18:46:13 INFO - starting webserver on 'localhost:40536' 18:46:13 INFO - SUITE-START | Running 2 tests 18:46:13 INFO - TEST-START | dromaeo_css 18:46:13 INFO - Initialising browser for dromaeo_css test... 18:46:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40536/getInfo.html -profile /tmp/tmpyYVLtt/profile 18:46:13 INFO - TEST-INFO | started process 15275 (/builds/slave/test/build/application/firefox/firefox http://localhost:40536/getInfo.html) 18:46:18 INFO - TEST-INFO | 15275: exit 0 18:46:18 INFO - Browser initialized. 18:46:18 INFO - Running cycle 1/1 for dromaeo_css test... 18:46:18 INFO - Using env: {'DISPLAY': ':0', 18:46:18 INFO - 'HOME': '/home/cltbld', 18:46:18 INFO - 'JSGC_DISABLE_POISONING': '1', 18:46:18 INFO - 'LANG': 'en_US.UTF-8', 18:46:18 INFO - 'LANGUAGE': 'en_US:en', 18:46:18 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 18:46:18 INFO - 'LOGNAME': 'cltbld', 18:46:18 INFO - 'MAIL': '/var/mail/cltbld', 18:46:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:46:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:46:18 INFO - 'MOZ_CRASHREPORTER': '1', 18:46:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:18 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 18:46:18 INFO - 'MOZ_NO_REMOTE': '1', 18:46:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:46:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:46:18 INFO - 'NO_EM_RESTART': '1', 18:46:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:46:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:18 INFO - 'PWD': '/builds/slave/test', 18:46:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:46:18 INFO - 'RUST_BACKTRACE': 'full', 18:46:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:46:18 INFO - 'SHELL': '/bin/bash', 18:46:18 INFO - 'SHLVL': '1', 18:46:18 INFO - 'TERM': 'linux', 18:46:18 INFO - 'TMOUT': '86400', 18:46:18 INFO - 'USER': 'cltbld', 18:46:18 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 18:46:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:18 INFO - '_': '/tools/buildbot/bin/python'} 18:46:18 INFO - TEST-INFO | started process 15488 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyYVLtt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 18:46:19 INFO - PID 15488 | 18:46:19 INFO - PID 15488 | (/builds/slave/test/build/application/firefox/firefox:15539): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:46:19 INFO - PID 15488 | 18:46:20 INFO - PID 15488 | 18:46:20 INFO - PID 15488 | (/builds/slave/test/build/application/firefox/firefox:15596): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 18:46:20 INFO - PID 15488 | 18:46:20 INFO - PID 15488 | __metrics Screen width/height:1600/1200 18:46:20 INFO - PID 15488 | colorDepth:24 18:46:20 INFO - PID 15488 | Browser inner width/height: 1024/768 18:46:20 INFO - PID 15488 | __metrics 18:46:30 INFO - PID 15488 | RSS: Main: 166735872 18:46:30 INFO - PID 15488 | 18:49:29 INFO - PID 15488 | Cycle 1(1): loaded http://localhost:40536/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40536/tests/dromaeo/cssquery-ext.html) 18:49:30 INFO - PID 15488 | RSS: Main: 170864640 18:49:30 INFO - PID 15488 | 18:52:32 INFO - PID 15488 | Cycle 1(1): loaded http://localhost:40536/tests/dromaeo/cssquery-ext.html (next: http://localhost:40536/tests/dromaeo/cssquery-jquery.html) 18:52:32 INFO - PID 15488 | RSS: Main: 166834176 18:52:32 INFO - PID 15488 | 18:55:34 INFO - PID 15488 | Cycle 1(1): loaded http://localhost:40536/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40536/tests/dromaeo/cssquery-mootools.html) 18:55:34 INFO - PID 15488 | RSS: Main: 169205760 18:55:34 INFO - PID 15488 | 18:58:36 INFO - PID 15488 | Cycle 1(1): loaded http://localhost:40536/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40536/tests/dromaeo/cssquery-prototype.html) 18:58:37 INFO - PID 15488 | RSS: Main: 167669760 18:58:37 INFO - PID 15488 | 19:01:40 INFO - PID 15488 | Cycle 1(1): loaded http://localhost:40536/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40536/tests/dromaeo/cssquery-yui.html) 19:01:41 INFO - PID 15488 | RSS: Main: 171536384 19:01:41 INFO - PID 15488 | 19:04:42 INFO - PID 15488 | Cycle 1(1): loaded http://localhost:40536/tests/dromaeo/cssquery-yui.html (next: http://localhost:40536/tests/dromaeo/cssquery-dojo.html) 19:04:42 INFO - PID 15488 | RSS: Main: 171810816 19:04:42 INFO - PID 15488 | 19:04:42 INFO - PID 15488 | __start_tp_report 19:04:42 INFO - PID 15488 | _x_x_mozilla_page_load 19:04:42 INFO - PID 15488 | _x_x_mozilla_page_load_details 19:04:42 INFO - PID 15488 | |i|pagename|runs| 19:04:42 INFO - PID 15488 | |0;dojo.html;3843.231353729253;3865.188310454804;3921.9607803921963;3953.8813835584924;3963.960360396397;6088.330283668793;6092.451679348857;6147.985582378908;6195.504359651229;6215.689215539216;6239.500839932806;6288.905666414996;6299.496040316775;6326.430621244087;6330.050492426127;5567.49892509674;5581.2186293918885;5614.214010038588;5666.490015898568;5709.400512946143;5593.552515798747;5622;5669.206311116447;5671.6597004179675;5676.233708449368;5570.610057295991;5578.191162281467;5644.774209031634;5682.460166284196;5687.7156142192725;5523.061079616475;5586.050371436825;5600.887982240332;5648.548116150698;5662.037453632893;5569.944300556983;5621.943780562183;5642.6614403135945;5645.294338207724;5650.6892120933335;6124.418180272867;6175.66033868137;6197.349278325801;6244.718987645567;6284.308726040132;6615.106960560311;6633.535652504278;6654.168228971379;6692.799216023527;6728.394444500019;6662.700178491979;6668.366505182;6669;6671.899921501133;6673.499487538454;6163.167972323724;6248.1564988726395;6301.306856245809;6325.399087086719;6332.050192471074;6796.184457864989;6800;6802.693878775368;6885.655717214119;6887.690053947585;6568;6588.96705516479;6590.340965903371;6590.670466476753;6592.439642630334;6661.60030398168;6661.966690166518;6673.666316684146;6705.195376554844;6793.626350550669;6603.240627327823;6665.700043498055;6682.231543372477;6684.097646817618;6692.631905245258;6599.439047681001;6607.041978913031;6617.172853393326;6683.4987375947;6696.2968888267005;4998.775055122528;5023.397192336943;5038.6976781392505;5049.293098966147;5052.6715763475995;4585.747783871852;4609.423822022247;4622.699524530894;4663.253879379351;4664.113818374498;6127.295361033449;6213.627182369072;6285.120083188357;6294.874102517924;6308.526860485482;6607.80176594703;6624.735010599618;6625.204975402886;6714.630695311707;6717.865642687118;6076.665783381868;6164.506839452923;6186.536009799283;6257.030160325156;6293.842653933597;5553.805616803393;5573.303337082864;5573.526250268692;5616.719164041781;5616.831495055155;6952.304769523007;6953.930460695429;7031;7049.977753225754;7051.083359163378;6646.169228846395;6657.866842663023;6692.397684208445;6754.0881980931945;6754.628495432798;6821.1814582250445;6922.376986071277;6953.965230173817;7000.7549735759;7021.227664956953;6595.274519802721;6608.240052394036;6625.900611490735;6636.535442518977;6640.269570347161;6637.203535575762;6644.933550664431;6666.566673166229;6720.596764194164;6762.459003279824;6276.089966954857;6368.9044664330995;6377.74489020434;6448.613083215022;6451.290358060516;6392.552521323649;6396.10454536356;6451.516136289891;6456.483481321577;6502.6748662567625;6346.809595712136;6426.550141490051;6439.7424103035355;6447.387498187664;6455;6347.936520634735;6410.230772307353;6437.839054023687;6521.64130972801;6534.411902928759;6203.875922481616;6282.246130464374;6288.717007734572;6402.487800976004;6450.3549645037 19:04:42 INFO - PID 15488 | |1;ext.html;861.8566855454322;896.9237614802742;918.5085979001236;944.2729098594087;946.8248374050797;42010;43019.56980430195;43058;43093.56906430943;43115;39539.20921581567;39707.60292397075;39742.20515589687;39819.402708959315;40010.799946000305;6923.134608173978;7010.579365238102;7015.263397343282;7015.7544485943;7024.332688394595;7113.89329160063;7206.49554531183;7213.495055346128;7221.422286217116;7223.602701851396;8038.236367545074;8180.427370084097;8182.836343273131;8184.058833234165;8186.83626327476;7941.24558166979;8059.07320658126;8076.959615201945;8085;8106.310963568104;7451.590162541058;7486.363659088984;7487.213842546508;7491.8501629967095;7498.287662672037;39577.40633890494;40102.19795604071;40103.19793604111;40118.59881401178;40135.79932100321;3556.8399422026073;3590.838412271454;3591.44332628443;3594.964050359489;3595.9640403596154;32134.357312853845;32157.51763723546;32245.51631725526;32250.35499290001;32268.35463290721;5405.864683450054;5428.75570599324;5436.646617969819;5452.40023597404;5472.2338872557875;198411;201663;204644;205595;208474;52327;52514;52543.474565254626;52572.474275257526;52585.7370713144;54190.72904635531;54310.45689543133;54349.1847622286;54395.45604543983;54538.72730636401;16052.919735401249;16114.435994740128;16120.274587643584;16174.19129043543;16308.347666093461;13295.73408531824;13313;13330.866691333156;13344.332783360793;13362.866371336357;15183.316750746022;15191.772123418157;15210.08739475635;15252.618684532976;15303.617409564627;5987.401259873977;6063.54523410746;6217.222847144107;6227.782027629011;6228.128062071313;1009.6769033909079;1042.2443728296923;1042.4006196437078;1050.107408702612;1061.0237983855693;23619.7638023621;23952.52094958126;23952.760472395054;24074.27777166688;24107.15624953118;1120.675004248775;1150.5282834038005;1156.3177725142207;1156.8669602995762;1167.5096459486865;12631.494740210288;12751.681207969876;12794.936025319814;12796.232226066466;13052.738945221043;237702;237859;238212;239136;239586;55097.724511377186;55432;55442.72278638581;55655.443445565026;55698.443015569326;1236.3262022197923;1270.3394234997934;1280.7054377493043;1284.5760898903338;1448.6812901161727;23836.88081559581;23974.880125599262;24034.87982560076;24210.394740131636;24290.514189715752;39148.80425597854;39261.80369098136;39271;39318.41022384609;39350.80324598359;199095;199576;199704;199791;199887;34239;36749.81625091857;37092;37374.626253737115;37429.6257037437;47886.28170577514;48280.7585962068;49017;49051.264231037276;49647.50352496573;35846.28307433889;35895;36279.27441451209;36679;37006.6299337014;35090.298194035466;35202.11994700153;35644.82177589199;35770.10574735652;36003.45994810132;40421;40672.38991415072;40789.38815917823;40887.38668919909;41067.58932410757;34252.48621270733;34255.14362140966;34472.82763586166;34617.65382346144;34629.13427164341;24341.878290608434;25019.49961000806;25223.36941576402;25231.36921576975;25482.872585637695 19:04:42 INFO - PID 15488 | |2;jquery.html;593.1843714892024;593.9524838012959;597.6653074278404;598.3388355866767;608.7169466198217;23251.3024609262;23515.5296894062;24117;24164;24177.637335439962;25517.872410637967;26070.217893463177;26228.868855655695;26245;26250.081247156355;6243.094751261065;6370.426661600478;6375.936240637604;6376.744930202786;6376.776812811552;6225.5953363031485;6233.844153896166;6263.780767673133;6457.934440817259;6493.415592596665;6428.19647544057;6449;6459.450946669539;6467.805965821033;6476.935230647704;6438.098666186737;6444.709988050549;6483.935160648428;6488.3187265337165;6510.576812507172;6009;6021.668808215591;6028.728707208186;6055.788047418319;6064.36324185963;30881.227969300722;31140.37719245625;31425.21436964094;31658.683413165803;32044.83977580097;2311.2835021143333;2340.9297721068397;2341.906323747048;2342.672025916373;2343.5195784864063;20083.899580502002;20781.16875324985;20784.584308313748;20803.79196208049;20820.271290504912;2374.8456350337174;2419.7822196002444;2421.5762241607827;2425.805935525154;2513.6857892763405;141175;144885;144934;146864;147922;15247.923760381349;15740.370385184464;15797.763033554505;15870;16323.836761632469;24645.63031554528;25002.874985624956;25449.236522904343;25451;25465.872670636898;10440.68677939663;10527.526261318259;10584.788304233873;10611.787764244826;10850.403227822388;9677.612895484242;9743.415395076318;9801.656942007137;9829.115379615869;9902.950485247527;9547.61809527625;9891.802163956681;9916.652917147865;10002.999400120005;10331.48342582868;2661.232318388854;2670.145553422925;2753.9449211015667;2788.177487641151;2942.132071039006;998.201438848918;1004.5077911823154;1012.86832711747;1027.4554486122368;1036;20589.588208235753;20892;21020;21099.78900211009;21227.787722122583;1081.5835903177176;1086.3807629651023;1089.3355053417408;1089.4117176724637;1096.2326371539955;11415.143864210217;11526.193166478433;11595.76808463826;11669.824952625717;11685.240459370114;602246;609688;619252;620304;624964;120755;124959;126536.36731816283;128330;133683;1313.356455336878;1320.7424552212226;1360.204280495921;1363.5023216526095;1365.3514580574147;21933.890330548245;22023.559528809656;22127.66808497906;22753.431164221027;22978.770212297663;68536.6573167131;68712.6564367175;69961.65019174872;70170.64914675395;70397.29602703908;85036.14963850283;88898.55550722464;89065;89464.55267723619;89670;23115.537689245786;23377.532449351256;23585.0565977359;23594.646080308466;23725;25341;25434.23697289084;25554.10560630369;25563.23310300694;25690.486190276464;19838.008099594674;19878.60242795107;20373;20387.79612203859;20398.388048358574;18125.821821581554;18187.545311366794;19020;19116.426507204807;19170.13734381984;20626;20974.370768876957;21729.348119556438;21732.348029558507;21736.891315543322;22334.88832555827;22458;22919.6248225107;22991.195308164133;23208.883955580113;20115.49133814999;20141.39575812728;20307.28924487636;20407.69388459204;20566.074526646637 19:04:42 INFO - PID 15488 | |3;mootools.html;1428.7999680044793;1479.8594133557308;1492.507472534064;1496.7305884940706;1505.382793054847;15705.842941570581;16350.999999999985;16363.018218906875;16381.508554743377;16563.58591035225;16703.582410439743;16969.66060678789;16985.575360615992;16995.150242487856;17005.064721440314;2761.8895244190257;2801.5517517197254;2804.9298767530872;2805.1724741201397;2805.298675331167;2722.333028408043;2750.944981100377;2813.607264404123;2815.2398852309866;2822.2238884306776;2592.8962841486414;2639.6304517367585;2640.643513125732;2642.128097727745;2643.550596398607;2636.604509323607;2669.0391459074717;2683.1413947536794;2684.396010897552;2686.382132109606;2620.5938079597695;2627.737226277376;2640.6435131257417;2641.2340421278;2643.3920198354294;16117.355305787873;16405.261763220566;16421.096839673814;16438.75341869873;16455.83544164555;8103.756887293391;8298.460600060977;8311.418200725951;8339.666413343459;8370.53962032088;5297.205419187153;5438.7280635968045;5454.509094181503;5466.781328746845;5497.587680923907;2038.5817523043486;2041.8672786268994;2052.3124753207694;2086.374087773662;2087.7390326209224;28072.29819254535;28470.85764571164;28685.426291474054;28769.71230287712;28954.13137605875;6898.1377327834025;6974;7094.467914906402;7175.461840361993;7184.137903451619;19138.71291930622;19185;19186.232550697816;19198.520036999187;19204.327848525234;7968.681252749825;8225.46534475269;8382.371322150862;8389.370797190235;8395.076541580544;7660.310572048543;7688.884666730003;7691.1924247954275;7699.422543309274;7701.768946931601;5348.732563371816;5405.567554595623;5420.13277875538;5429.755660995264;5476.780928762805;1488.6129606302222;1491.246920305239;1498.4455751371845;1516.3100789140915;1521.2849960518538;912.8539433690578;953.5041778275258;957.7270477913761;959.8608201810698;966.4394651102343;8271.090180080186;8294.253517183482;8312.33501319893;8332;8364.832703345899;739.4601940583296;749.1422321441919;751.6054071612338;756.6179079564895;758.5448730761499;5516.641418307797;5581.441855814386;5745.224394706661;5779.017567013618;5893.410658934073;22872.31383058511;23427.41431464193;23681.881590592282;23745.28764137044;23746.762532374454;22808.771912280998;23243.418914527272;23304.65043024356;23563.64654530149;23662.408439789142;9858.704238872846;10032.799344013225;12458.190217635825;12554.309512976692;12664.986801055893;8407.831843362976;9749.658761943298;9939.801203975736;10095.495225238856;10171.28800983924;19978.30075947335;20111.195552178335;20185.6972145415;20442.38672839817;21282.78717212808;20852.895735521226;20884.582308353445;21016.789832102095;21194.576108477435;21470.355889323346;11695.473703683185;12779;13117.212967221998;13198.14212076212;13219.933900330438;14890.404383824525;14920.179390133648;15031.398744050115;15033.398664053315;15074.54776356711;11887.643370698894;11910.464029118537;12045.457954392248;12185.207961482476;12226.144169908377;11836.408179590813;11943.223690460092;12136.939315303367;12223.877761222275;12411.875881241072;9168;9336.486493242664;9342.486163261086;9342.813143736952;9359.110884465965;7482.738104166328;7788.610569471391;7841.490303130278;8003.319717823817;8022.398320126131;8299.6680132794;8401.579921004048;8416.747497575083;8423.957880210559;8448.281896038905 19:04:42 INFO - PID 15488 | |4;prototype.html;530.9920351194733;533.7384681506063;566.1903478026422;569.2969183058918;573.3492486028357;6197.752089916398;6265.686715664216;6292;6297.937020629793;6362.459190968768;5992.970035149829;6106.786262480814;6139.171211886405;6152.261728592575;6206.751729930808;4862.489438608948;4895.510448955094;4966.875828104308;4988.551030407262;4994.051130285243;4841.128596852565;4908.460069392382;4946.258061290817;4953.257011448293;4973.179425394806;4886.462489126193;4915.729634870081;4955.306257124005;4968.378952630921;4996.65023448357;4806.836923384491;4854.12625727369;4879.585235255007;4911.582515486188;4938.8765280868265;4471.374007638933;4484.372187893698;4510.962478629934;4523.773811309455;4551.954480455186;6137.079438084278;6166.136740856284;6167.488965203554;6179.104029915637;6193.225846769154;5751.338596061465;5770.884582308372;5805.941940580582;5819.272590926134;5860.267466566679;6552.853250681112;6559.278479367265;6563.029367232803;6594.142761440982;6595.274519802818;4951.851444456672;4961.3798275215595;4962.925556116661;4972.7513624319;4979.1784355581285;6858.530928865604;6871.175458944908;6880.65596720162;6883.795335816212;6897.517173797836;5097.108006098918;5109.540141387219;5109.948900511022;5128.358955130609;5128.871778205575;5187.844364669066;5193.68837869729;5207.635465517377;5210.843674689765;5224.50367215114;5640;5736.024875771129;5766.798162064308;5817.592768506249;5837.6205546640185;6501.1548498694865;6565.80302590923;6570;6573.605583664996;6576.5067619928695;1702.5147832867574;1749.7987731410797;1754.8596112310984;1772.6986412309939;1787.6782179207607;1221.883921027501;1257.9622611321674;1258.0123405020047;1285.5950375631646;1289.832321798179;6209.813705588839;6222.146725554075;6226.40849119333;6240.687965601702;6271.905921411182;6569.507286953402;6682.264950855402;6709.932900671029;6744.6627668616375;6797.218319893275;6198.349173336825;6265.838285791395;6276;6303.243610766737;6315.905261421082;3476.4090104682264;3505.158761897177;3526.2242306692488;3580.4092324766643;3590.622984586633;6926.653667316614;6991.6853741580735;7043.626492833848;7091.326323999212;7097.361237488651;6809.693563789641;6881.724731010803;6887.449004079662;6979.790606281819;7029.789106326818;6860.451163906975;6920.9307906920285;6951.2353641099435;7013.649317534103;7102.28977102296;6712.194536655633;6739.5619284746335;6744;6744.7976560703255;6758.662066896537;6683.331666833278;6708.329167083252;6728.966355168389;6736.259011508632;6750.561213521106;6938.167419909441;6969.58182509051;6991.126109236346;7033.261507541839;7046.929530704832;5003.824866129814;5007.649464537448;5025.899482010266;5037.471065538066;5047.394312682502;6486.189226346707;6760;6766.763163289262;6808.897866532105;6872.656367181521;6237.844053898689;6352.650604216812;6438.935610643834;6440.516961227833;6475.83434981689;6415.454686351526;6431;6445.967770161119;6452.290248072803;6461.67691615408;6295.401936815995;6312.56966068058;6313.43179113882;6427.58220715652;6473.158489396348;6335.167223829517;6380.776672816429;6415.71129299192;6493.220813502221;6539.803805885831;6381.659851431252;6388.8083357499345;6391.10524526576;6406.455451286693;6426.646534440651 19:04:42 INFO - PID 15488 | |5;yui.html;954.2652157838464;971.349196038654;995.4326034160529;997.6508222122258;998.381003777658;17950.281988720453;18724.719129213074;18760.812391876076;18800.529986750338;20389.898050509764;20296.086676099574;20916.477088072807;21219.469513262175;21291.467713307135;21361.572768544658;1009.8889122196534;1045.5295117197254;1046.7540128069897;1046.8115739166985;1047.1779652972418;1037.8287582548871;1096.7477480179564;1098.6758906122714;1106.6016234155697;1106.6956586938616;1135.2677522997662;1142.714321419645;1142.9257098288626;1143.3311512765042;1143.7026373143;1126.555010770745;1132.0321125437797;1132.1678566253756;1135.403912945702;1136.9715757106055;842.9199226073574;843.1273631791066;843.161054750521;843.6372359885247;843.6751850537526;18669.719954200697;18830.152643131092;18850.151743171453;19008.90495547527;19920.103595338245;22130.225442109448;22182.11271549136;22437;22550.43623909399;22556.66165007526;16974.575635609086;17818;18037.098145092823;18096.18567164481;18687.906560467247;3806.7525610835205;3837.7505462144873;3837.846486140579;3838.7312888097845;3839.347310957144;133501.3324933379;135337;137289;137424;137657;16798.748018779726;17055.48833534996;17443.21505532254;17524.91237543804;17528.649427011387;32226.19434514109;32458.188545286557;32770.836145819594;32844;33266.83366583152;7935;8640.956795216109;9160.725178244664;9498.762530936781;9533.856992145124;4436.911261774746;4518.254488009507;4521.344405061313;4547.886302842389;4552.385427967249;5750.683712395775;5901.144334071536;6171.6297022178815;6340.302566717657;6398.264199617103;1237.189640785287;1256.8303279057393;1269.05455435701;1274.3309762374643;1276.8403949506312;724.6883839948767;738.1253214940313;740.4594645908517;748.6219459173192;760.7261385901071;12829.486820527074;12980.286084265455;13413.463461461628;13435.261060641758;13453.394597243148;723.0780754538006;726.5531698005794;734.8787450070786;736.683226212723;741.4068745004083;5887.970560147171;5961.463468287875;6024.849378765566;6056.606320589148;6136.8772624547255;126313;126362;128169.3591532055;129387;130030;112838.43580782044;115790.42104789423;120922;121921;126107;100522.49738751406;104251.4787426058;104376;105121;106572.99999999844;12816.359182041047;13690.86309136896;13866.791998120238;13902.582922512342;13970.441182352592;48889.51110488992;49404.50595493999;49708.502914970384;50128.49871501384;51297.487025129274;63540.682296588224;64204;64378.356216437234;64484.35515644783;64655.353446464935;18124.72812907834;18227.270909163486;18336.81663183351;18343.54141146482;18647;20424.795752042883;21641.242556510446;21709.34871953844;21926.45183870352;21931.12275508962;20359.287424940056;20420.693689594966;20651.17395304231;20874.37376878696;20885;18991.335303264874;19106.904465477583;19109.904450477658;20143;20914.37256882296;19242.7113593299;19492.122854471298;19940.60118797587;20077.096530655865;20261.493462662962;13674.453021879013;14085.788713169106;14450.710985780435;14489;14589.708205835612;13984;14662.560123196321;14761.483348082766;14835.85164148388;15041.924790375979 19:04:42 INFO - PID 15488 | __end_tp_report 19:04:42 INFO - PID 15488 | __start_cc_report 19:04:42 INFO - PID 15488 | _x_x_mozilla_cycle_collect,353 19:04:42 INFO - PID 15488 | __end_cc_report 19:04:42 INFO - PID 15488 | __startTimestamp1503885882892__endTimestamp 19:04:42 INFO - PID 15488 | 19:04:42 INFO - PID 15488 | ------- Summary: start ------- 19:04:42 INFO - PID 15488 | Number of tests: 6 19:04:42 INFO - PID 15488 | 19:04:42 INFO - PID 15488 | [#0] dojo.html Cycles:165 Average:6183.72 Median:6377.74 stddev:679.17 (10.6%) stddev-sans-first:655.96 19:04:42 INFO - PID 15488 | Values: 3843.2 3865.2 3922.0 3953.9 3964.0 6088.3 6092.5 6148.0 6195.5 6215.7 6239.5 6288.9 6299.5 6326.4 6330.1 5567.5 5581.2 5614.2 5666.5 5709.4 5593.6 5622.0 5669.2 5671.7 5676.2 5570.6 5578.2 5644.8 5682.5 5687.7 5523.1 5586.1 5600.9 5648.5 5662.0 5569.9 5621.9 5642.7 5645.3 5650.7 6124.4 6175.7 6197.3 6244.7 6284.3 6615.1 6633.5 6654.2 6692.8 6728.4 6662.7 6668.4 6669.0 6671.9 6673.5 6163.2 6248.2 6301.3 6325.4 6332.1 6796.2 6800.0 6802.7 6885.7 6887.7 6568.0 6589.0 6590.3 6590.7 6592.4 6661.6 6662.0 6673.7 6705.2 6793.6 6603.2 6665.7 6682.2 6684.1 6692.6 6599.4 6607.0 6617.2 6683.5 6696.3 4998.8 5023.4 5038.7 5049.3 5052.7 4585.7 4609.4 4622.7 4663.3 4664.1 6127.3 6213.6 6285.1 6294.9 6308.5 6607.8 6624.7 6625.2 6714.6 6717.9 6076.7 6164.5 6186.5 6257.0 6293.8 5553.8 5573.3 5573.5 5616.7 5616.8 6952.3 6953.9 7031.0 7050.0 7051.1 6646.2 6657.9 6692.4 6754.1 6754.6 6821.2 6922.4 6954.0 7000.8 7021.2 6595.3 6608.2 6625.9 6636.5 6640.3 6637.2 6644.9 6666.6 6720.6 6762.5 6276.1 6368.9 6377.7 6448.6 6451.3 6392.6 6396.1 6451.5 6456.5 6502.7 6346.8 6426.6 6439.7 6447.4 6455.0 6347.9 6410.2 6437.8 6521.6 6534.4 6203.9 6282.2 6288.7 6402.5 6450.4 19:04:42 INFO - PID 15488 | 19:04:42 INFO - PID 15488 | [#1] ext.html Cycles:180 Average:39411.43 Median:36878.22 stddev:55648.91 (150.9%) stddev-sans-first:55729.73 19:04:42 INFO - PID 15488 | Values: 861.9 896.9 918.5 944.3 946.8 42010.0 43019.6 43058.0 43093.6 43115.0 39539.2 39707.6 39742.2 39819.4 40010.8 6923.1 7010.6 7015.3 7015.8 7024.3 7113.9 7206.5 7213.5 7221.4 7223.6 8038.2 8180.4 8182.8 8184.1 8186.8 7941.2 8059.1 8077.0 8085.0 8106.3 7451.6 7486.4 7487.2 7491.9 7498.3 39577.4 40102.2 40103.2 40118.6 40135.8 3556.8 3590.8 3591.4 3595.0 3596.0 32134.4 32157.5 32245.5 32250.4 32268.4 5405.9 5428.8 5436.6 5452.4 5472.2 198411.0 201663.0 204644.0 205595.0 208474.0 52327.0 52514.0 52543.5 52572.5 52585.7 54190.7 54310.5 54349.2 54395.5 54538.7 16052.9 16114.4 16120.3 16174.2 16308.3 13295.7 13313.0 13330.9 13344.3 13362.9 15183.3 15191.8 15210.1 15252.6 15303.6 5987.4 6063.5 6217.2 6227.8 6228.1 1009.7 1042.2 1042.4 1050.1 1061.0 23619.8 23952.5 23952.8 24074.3 24107.2 1120.7 1150.5 1156.3 1156.9 1167.5 12631.5 12751.7 12794.9 12796.2 13052.7 237702.0 237859.0 238212.0 239136.0 239586.0 55097.7 55432.0 55442.7 55655.4 55698.4 1236.3 1270.3 1280.7 1284.6 1448.7 23836.9 23974.9 24034.9 24210.4 24290.5 39148.8 39261.8 39271.0 39318.4 39350.8 199095.0 199576.0 199704.0 199791.0 199887.0 34239.0 36749.8 37092.0 37374.6 37429.6 47886.3 48280.8 49017.0 49051.3 49647.5 35846.3 35895.0 36279.3 36679.0 37006.6 35090.3 35202.1 35644.8 35770.1 36003.5 40421.0 40672.4 40789.4 40887.4 41067.6 34252.5 34255.1 34472.8 34617.7 34629.1 24341.9 25019.5 25223.4 25231.4 25482.9 19:04:42 INFO - PID 15488 | 19:04:42 INFO - PID 15488 | [#2] jquery.html Cycles:180 Average:40807.91 Median:23589.85 stddev:102625.69 (435.0%) stddev-sans-first:102869.16 19:04:42 INFO - PID 15488 | Values: 593.2 594.0 597.7 598.3 608.7 23251.3 23515.5 24117.0 24164.0 24177.6 25517.9 26070.2 26228.9 26245.0 26250.1 6243.1 6370.4 6375.9 6376.7 6376.8 6225.6 6233.8 6263.8 6457.9 6493.4 6428.2 6449.0 6459.5 6467.8 6476.9 6438.1 6444.7 6483.9 6488.3 6510.6 6009.0 6021.7 6028.7 6055.8 6064.4 30881.2 31140.4 31425.2 31658.7 32044.8 2311.3 2340.9 2341.9 2342.7 2343.5 20083.9 20781.2 20784.6 20803.8 20820.3 2374.8 2419.8 2421.6 2425.8 2513.7 141175.0 144885.0 144934.0 146864.0 147922.0 15247.9 15740.4 15797.8 15870.0 16323.8 24645.6 25002.9 25449.2 25451.0 25465.9 10440.7 10527.5 10584.8 10611.8 10850.4 9677.6 9743.4 9801.7 9829.1 9903.0 9547.6 9891.8 9916.7 10003.0 10331.5 2661.2 2670.1 2753.9 2788.2 2942.1 998.2 1004.5 1012.9 1027.5 1036.0 20589.6 20892.0 21020.0 21099.8 21227.8 1081.6 1086.4 1089.3 1089.4 1096.2 11415.1 11526.2 11595.8 11669.8 11685.2 602246.0 609688.0 619252.0 620304.0 624964.0 120755.0 124959.0 126536.4 128330.0 133683.0 1313.4 1320.7 1360.2 1363.5 1365.4 21933.9 22023.6 22127.7 22753.4 22978.8 68536.7 68712.7 69961.7 70170.6 70397.3 85036.1 88898.6 89065.0 89464.6 89670.0 23115.5 23377.5 23585.1 23594.6 23725.0 25341.0 25434.2 25554.1 25563.2 25690.5 19838.0 19878.6 20373.0 20387.8 20398.4 18125.8 18187.5 19020.0 19116.4 19170.1 20626.0 20974.4 21729.3 21732.3 21736.9 22334.9 22458.0 22919.6 22991.2 23208.9 20115.5 20141.4 20307.3 20407.7 20566.1 19:04:42 INFO - PID 15488 | 19:04:42 INFO - PID 15488 | [#3] mootools.html Cycles:180 Average:10021.04 Median:2643.47 stddev:7337.93 (277.6%) stddev-sans-first:7330.12 19:04:42 INFO - PID 15488 | Values: 1428.8 1479.9 1492.5 1496.7 1505.4 15705.8 16351.0 16363.0 16381.5 16563.6 16703.6 16969.7 16985.6 16995.2 17005.1 2761.9 2801.6 2804.9 2805.2 2805.3 2722.3 2750.9 2813.6 2815.2 2822.2 2592.9 2639.6 2640.6 2642.1 2643.6 2636.6 2669.0 2683.1 2684.4 2686.4 2620.6 2627.7 2640.6 2641.2 2643.4 16117.4 16405.3 16421.1 16438.8 16455.8 8103.8 8298.5 8311.4 8339.7 8370.5 5297.2 5438.7 5454.5 5466.8 5497.6 2038.6 2041.9 2052.3 2086.4 2087.7 28072.3 28470.9 28685.4 28769.7 28954.1 6898.1 6974.0 7094.5 7175.5 7184.1 19138.7 19185.0 19186.2 19198.5 19204.3 7968.7 8225.5 8382.4 8389.4 8395.1 7660.3 7688.9 7691.2 7699.4 7701.8 5348.7 5405.6 5420.1 5429.8 5476.8 1488.6 1491.2 1498.4 1516.3 1521.3 912.9 953.5 957.7 959.9 966.4 8271.1 8294.3 8312.3 8332.0 8364.8 739.5 749.1 751.6 756.6 758.5 5516.6 5581.4 5745.2 5779.0 5893.4 22872.3 23427.4 23681.9 23745.3 23746.8 22808.8 23243.4 23304.7 23563.6 23662.4 9858.7 10032.8 12458.2 12554.3 12665.0 8407.8 9749.7 9939.8 10095.5 10171.3 19978.3 20111.2 20185.7 20442.4 21282.8 20852.9 20884.6 21016.8 21194.6 21470.4 11695.5 12779.0 13117.2 13198.1 13219.9 14890.4 14920.2 15031.4 15033.4 15074.5 11887.6 11910.5 12045.5 12185.2 12226.1 11836.4 11943.2 12136.9 12223.9 12411.9 9168.0 9336.5 9342.5 9342.8 9359.1 7482.7 7788.6 7841.5 8003.3 8022.4 8299.7 8401.6 8416.7 8424.0 8448.3 19:04:42 INFO - PID 15488 | 19:04:42 INFO - PID 15488 | [#4] prototype.html Cycles:180 Average:5586.16 Median:6239.27 stddev:1576.39 (25.3%) stddev-sans-first:1534.47 19:04:42 INFO - PID 15488 | Values: 531.0 533.7 566.2 569.3 573.3 6197.8 6265.7 6292.0 6297.9 6362.5 5993.0 6106.8 6139.2 6152.3 6206.8 4862.5 4895.5 4966.9 4988.6 4994.1 4841.1 4908.5 4946.3 4953.3 4973.2 4886.5 4915.7 4955.3 4968.4 4996.7 4806.8 4854.1 4879.6 4911.6 4938.9 4471.4 4484.4 4511.0 4523.8 4552.0 6137.1 6166.1 6167.5 6179.1 6193.2 5751.3 5770.9 5805.9 5819.3 5860.3 6552.9 6559.3 6563.0 6594.1 6595.3 4951.9 4961.4 4962.9 4972.8 4979.2 6858.5 6871.2 6880.7 6883.8 6897.5 5097.1 5109.5 5109.9 5128.4 5128.9 5187.8 5193.7 5207.6 5210.8 5224.5 5640.0 5736.0 5766.8 5817.6 5837.6 6501.2 6565.8 6570.0 6573.6 6576.5 1702.5 1749.8 1754.9 1772.7 1787.7 1221.9 1258.0 1258.0 1285.6 1289.8 6209.8 6222.1 6226.4 6240.7 6271.9 6569.5 6682.3 6709.9 6744.7 6797.2 6198.3 6265.8 6276.0 6303.2 6315.9 3476.4 3505.2 3526.2 3580.4 3590.6 6926.7 6991.7 7043.6 7091.3 7097.4 6809.7 6881.7 6887.4 6979.8 7029.8 6860.5 6920.9 6951.2 7013.6 7102.3 6712.2 6739.6 6744.0 6744.8 6758.7 6683.3 6708.3 6729.0 6736.3 6750.6 6938.2 6969.6 6991.1 7033.3 7046.9 5003.8 5007.6 5025.9 5037.5 5047.4 6486.2 6760.0 6766.8 6808.9 6872.7 6237.8 6352.7 6438.9 6440.5 6475.8 6415.5 6431.0 6446.0 6452.3 6461.7 6295.4 6312.6 6313.4 6427.6 6473.2 6335.2 6380.8 6415.7 6493.2 6539.8 6381.7 6388.8 6391.1 6406.5 6426.6 19:04:42 INFO - PID 15488 | 19:04:42 INFO - PID 15488 | [#5] yui.html Cycles:180 Average:26264.73 Median:19176.31 stddev:36710.67 (191.4%) stddev-sans-first:36764.46 19:04:42 INFO - PID 15488 | Values: 954.3 971.3 995.4 997.7 998.4 17950.3 18724.7 18760.8 18800.5 20389.9 20296.1 20916.5 21219.5 21291.5 21361.6 1009.9 1045.5 1046.8 1046.8 1047.2 1037.8 1096.7 1098.7 1106.6 1106.7 1135.3 1142.7 1142.9 1143.3 1143.7 1126.6 1132.0 1132.2 1135.4 1137.0 842.9 843.1 843.2 843.6 843.7 18669.7 18830.2 18850.2 19008.9 19920.1 22130.2 22182.1 22437.0 22550.4 22556.7 16974.6 17818.0 18037.1 18096.2 18687.9 3806.8 3837.8 3837.8 3838.7 3839.3 133501.3 135337.0 137289.0 137424.0 137657.0 16798.7 17055.5 17443.2 17524.9 17528.6 32226.2 32458.2 32770.8 32844.0 33266.8 7935.0 8641.0 9160.7 9498.8 9533.9 4436.9 4518.3 4521.3 4547.9 4552.4 5750.7 5901.1 6171.6 6340.3 6398.3 1237.2 1256.8 1269.1 1274.3 1276.8 724.7 738.1 740.5 748.6 760.7 12829.5 12980.3 13413.5 13435.3 13453.4 723.1 726.6 734.9 736.7 741.4 5888.0 5961.5 6024.8 6056.6 6136.9 126313.0 126362.0 128169.4 129387.0 130030.0 112838.4 115790.4 120922.0 121921.0 126107.0 100522.5 104251.5 104376.0 105121.0 106573.0 12816.4 13690.9 13866.8 13902.6 13970.4 48889.5 49404.5 49708.5 50128.5 51297.5 63540.7 64204.0 64378.4 64484.4 64655.4 18124.7 18227.3 18336.8 18343.5 18647.0 20424.8 21641.2 21709.3 21926.5 21931.1 20359.3 20420.7 20651.2 20874.4 20885.0 18991.3 19106.9 19109.9 20143.0 20914.4 19242.7 19492.1 19940.6 20077.1 20261.5 13674.5 14085.8 14450.7 14489.0 14589.7 13984.0 14662.6 14761.5 14835.9 15041.9 19:04:42 INFO - PID 15488 | -------- Summary: end -------- 19:04:42 INFO - PID 15488 | 19:04:43 INFO - TEST-INFO | 15488: exit 0 19:04:43 INFO - TEST-OK | dromaeo_css | took 1109985ms 19:04:43 INFO - TEST-START | kraken 19:04:43 INFO - Initialising browser for kraken test... 19:04:43 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40536/getInfo.html -profile /tmp/tmpd2bJjK/profile 19:04:43 INFO - TEST-INFO | started process 15895 (/builds/slave/test/build/application/firefox/firefox http://localhost:40536/getInfo.html) 19:04:48 INFO - TEST-INFO | 15895: exit 0 19:04:48 INFO - Browser initialized. 19:04:48 INFO - Running cycle 1/1 for kraken test... 19:04:48 INFO - Using env: {'DISPLAY': ':0', 19:04:48 INFO - 'HOME': '/home/cltbld', 19:04:48 INFO - 'JSGC_DISABLE_POISONING': '1', 19:04:48 INFO - 'LANG': 'en_US.UTF-8', 19:04:48 INFO - 'LANGUAGE': 'en_US:en', 19:04:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:04:48 INFO - 'LOGNAME': 'cltbld', 19:04:48 INFO - 'MAIL': '/var/mail/cltbld', 19:04:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:04:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:04:48 INFO - 'MOZ_CRASHREPORTER': '1', 19:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:04:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:04:48 INFO - 'MOZ_NO_REMOTE': '1', 19:04:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:04:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:04:48 INFO - 'NO_EM_RESTART': '1', 19:04:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:04:48 INFO - 'PWD': '/builds/slave/test', 19:04:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:04:48 INFO - 'RUST_BACKTRACE': 'full', 19:04:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:04:48 INFO - 'SHELL': '/bin/bash', 19:04:48 INFO - 'SHLVL': '1', 19:04:48 INFO - 'TERM': 'linux', 19:04:48 INFO - 'TMOUT': '86400', 19:04:48 INFO - 'USER': 'cltbld', 19:04:48 INFO - 'XDG_SESSION_COOKIE': 'db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786', 19:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:04:48 INFO - '_': '/tools/buildbot/bin/python'} 19:04:48 INFO - TEST-INFO | started process 16068 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd2bJjK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:04:49 INFO - PID 16068 | 19:04:49 INFO - PID 16068 | (/builds/slave/test/build/application/firefox/firefox:16118): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:04:49 INFO - PID 16068 | 19:04:50 INFO - PID 16068 | 19:04:50 INFO - PID 16068 | (/builds/slave/test/build/application/firefox/firefox:16176): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:04:50 INFO - PID 16068 | 19:04:50 INFO - PID 16068 | __metrics Screen width/height:1600/1200 19:04:50 INFO - PID 16068 | colorDepth:24 19:04:50 INFO - PID 16068 | Browser inner width/height: 1024/768 19:04:50 INFO - PID 16068 | __metrics 19:04:59 INFO - PID 16068 | RSS: Main: 171794432 19:04:59 INFO - PID 16068 | 19:05:02 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40536/tests/kraken/driver.html?testName=audio-beat-detection) 19:05:03 INFO - PID 16068 | RSS: Main: 176586752 19:05:03 INFO - PID 16068 | 19:05:05 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40536/tests/kraken/driver.html?testName=audio-dft) 19:05:06 INFO - PID 16068 | RSS: Main: 177246208 19:05:06 INFO - PID 16068 | 19:05:09 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40536/tests/kraken/driver.html?testName=audio-fft) 19:05:09 INFO - PID 16068 | RSS: Main: 170205184 19:05:09 INFO - PID 16068 | 19:05:11 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40536/tests/kraken/driver.html?testName=audio-oscillator) 19:05:12 INFO - PID 16068 | RSS: Main: 171126784 19:05:12 INFO - PID 16068 | 19:05:14 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40536/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:05:15 INFO - PID 16068 | RSS: Main: 171184128 19:05:15 INFO - PID 16068 | 19:05:18 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40536/tests/kraken/driver.html?testName=imaging-darkroom) 19:05:18 INFO - PID 16068 | RSS: Main: 170811392 19:05:18 INFO - PID 16068 | 19:05:22 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40536/tests/kraken/driver.html?testName=imaging-desaturate) 19:05:22 INFO - PID 16068 | RSS: Main: 172552192 19:05:22 INFO - PID 16068 | 19:05:25 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40536/tests/kraken/driver.html?testName=json-parse-financial) 19:05:26 INFO - PID 16068 | RSS: Main: 172806144 19:05:26 INFO - PID 16068 | 19:05:27 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40536/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:05:28 INFO - PID 16068 | RSS: Main: 172720128 19:05:28 INFO - PID 16068 | 19:05:29 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40536/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:05:30 INFO - PID 16068 | RSS: Main: 172929024 19:05:30 INFO - PID 16068 | 19:05:32 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40536/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:05:33 INFO - PID 16068 | RSS: Main: 172318720 19:05:33 INFO - PID 16068 | 19:05:35 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:05:36 INFO - PID 16068 | RSS: Main: 169611264 19:05:36 INFO - PID 16068 | 19:05:39 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:05:39 INFO - PID 16068 | RSS: Main: 169709568 19:05:39 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | Cycle 1(1): loaded http://localhost:40536/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40536/tests/kraken/driver.html?testName=ai-astar) 19:05:41 INFO - PID 16068 | RSS: Main: 171712512 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | __start_tp_report 19:05:41 INFO - PID 16068 | _x_x_mozilla_page_load 19:05:41 INFO - PID 16068 | _x_x_mozilla_page_load_details 19:05:41 INFO - PID 16068 | |i|pagename|runs| 19:05:41 INFO - PID 16068 | |0;ai-astar;98;109;99;104;106;104;108;96;106;109 19:05:41 INFO - PID 16068 | |1;audio-beat-detection;141;116;137;160;114;139;139;115;170;116 19:05:41 INFO - PID 16068 | |2;audio-dft;161;163;162;162;162;160;162;163;161;162 19:05:41 INFO - PID 16068 | |3;audio-fft;73;75;77;174;74;82;73;74;183;73 19:05:41 INFO - PID 16068 | |4;audio-oscillator;79;79;138;78;78;78;128;77;78;130 19:05:41 INFO - PID 16068 | |5;imaging-gaussian-blur;113;114;114;114;115;114;114;115;113;114 19:05:41 INFO - PID 16068 | |6;imaging-darkroom;149;150;149;150;150;150;150;148;150;149 19:05:41 INFO - PID 16068 | |7;imaging-desaturate;94;94;96;94;94;94;93;94;93;94 19:05:41 INFO - PID 16068 | |8;json-parse-financial;48;53;49;47;47;48;49;47;53;47 19:05:41 INFO - PID 16068 | |9;json-stringify-tinderbox;40;39;38;39;39;39;39;39;40;39 19:05:41 INFO - PID 16068 | |10;stanford-crypto-aes;72;73;78;72;74;73;78;79;74;73 19:05:41 INFO - PID 16068 | |11;stanford-crypto-ccm;117;118;114;117;115;112;113;117;128;169 19:05:41 INFO - PID 16068 | |12;stanford-crypto-pbkdf2;166;164;182;164;181;166;176;154;165;167 19:05:41 INFO - PID 16068 | |13;stanford-crypto-sha256-iterative;64;60;64;61;61;60;61;59;62;60 19:05:41 INFO - PID 16068 | __end_tp_report 19:05:41 INFO - PID 16068 | __start_cc_report 19:05:41 INFO - PID 16068 | _x_x_mozilla_cycle_collect,927 19:05:41 INFO - PID 16068 | __end_cc_report 19:05:41 INFO - PID 16068 | __startTimestamp1503885941925__endTimestamp 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | ------- Summary: start ------- 19:05:41 INFO - PID 16068 | Number of tests: 14 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#0] ai-astar Cycles:10 Average:103.90 Median:109.00 stddev:4.70 (4.3%) stddev-sans-first:4.48 19:05:41 INFO - PID 16068 | Values: 98.0 109.0 99.0 104.0 106.0 104.0 108.0 96.0 106.0 109.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#1] audio-beat-detection Cycles:10 Average:134.70 Median:139.00 stddev:19.68 (14.2%) stddev-sans-first:20.74 19:05:41 INFO - PID 16068 | Values: 141.0 116.0 137.0 160.0 114.0 139.0 139.0 115.0 170.0 116.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 19:05:41 INFO - PID 16068 | Values: 161.0 163.0 162.0 162.0 162.0 160.0 162.0 163.0 161.0 162.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#3] audio-fft Cycles:10 Average:95.80 Median:74.00 stddev:43.72 (59.1%) stddev-sans-first:45.59 19:05:41 INFO - PID 16068 | Values: 73.0 75.0 77.0 174.0 74.0 82.0 73.0 74.0 183.0 73.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:78.00 stddev:26.14 (33.5%) stddev-sans-first:27.13 19:05:41 INFO - PID 16068 | Values: 79.0 79.0 138.0 78.0 78.0 78.0 128.0 77.0 78.0 130.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 19:05:41 INFO - PID 16068 | Values: 113.0 114.0 114.0 114.0 115.0 114.0 114.0 115.0 113.0 114.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:150.00 stddev:0.71 (0.5%) stddev-sans-first:0.73 19:05:41 INFO - PID 16068 | Values: 149.0 150.0 149.0 150.0 150.0 150.0 150.0 148.0 150.0 149.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 19:05:41 INFO - PID 16068 | Values: 94.0 94.0 96.0 94.0 94.0 94.0 93.0 94.0 93.0 94.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#8] json-parse-financial Cycles:10 Average:48.80 Median:48.50 stddev:2.35 (4.8%) stddev-sans-first:2.47 19:05:41 INFO - PID 16068 | Values: 48.0 53.0 49.0 47.0 47.0 48.0 49.0 47.0 53.0 47.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#9] json-stringify-tinderbox Cycles:10 Average:39.10 Median:39.00 stddev:0.57 (1.5%) stddev-sans-first:0.50 19:05:41 INFO - PID 16068 | Values: 40.0 39.0 38.0 39.0 39.0 39.0 39.0 39.0 40.0 39.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#10] stanford-crypto-aes Cycles:10 Average:74.60 Median:74.00 stddev:2.67 (3.6%) stddev-sans-first:2.67 19:05:41 INFO - PID 16068 | Values: 72.0 73.0 78.0 72.0 74.0 73.0 78.0 79.0 74.0 73.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#11] stanford-crypto-ccm Cycles:10 Average:122.00 Median:117.00 stddev:17.09 (14.6%) stddev-sans-first:18.04 19:05:41 INFO - PID 16068 | Values: 117.0 118.0 114.0 117.0 115.0 112.0 113.0 117.0 128.0 169.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:166.50 stddev:8.64 (5.2%) stddev-sans-first:9.12 19:05:41 INFO - PID 16068 | Values: 166.0 164.0 182.0 164.0 181.0 166.0 176.0 154.0 165.0 167.0 19:05:41 INFO - PID 16068 | 19:05:41 INFO - PID 16068 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.69 (2.8%) stddev-sans-first:1.45 19:05:41 INFO - PID 16068 | Values: 64.0 60.0 64.0 61.0 61.0 60.0 61.0 59.0 62.0 60.0 19:05:41 INFO - PID 16068 | -------- Summary: end -------- 19:05:41 INFO - PID 16068 | 19:05:42 INFO - PID 16068 | 19:05:42 INFO - PID 16068 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 19:05:42 INFO - PID 16068 | 19:05:42 INFO - PID 16068 | 19:05:42 INFO - PID 16068 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 19:05:42 INFO - PID 16068 | 19:05:42 INFO - PID 16068 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:05:42 INFO - TEST-INFO | 16068: exit 0 19:05:42 INFO - TEST-OK | kraken | took 59008ms 19:05:42 INFO - SUITE-END | took 1168s 19:05:42 INFO - Completed test suite (00:19:28) 19:05:42 INFO - javascript benchmark 19:05:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8946.649693726082, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3843.231353729253, 3865.188310454804, 3921.9607803921963, 3953.8813835584924, 3963.960360396397, 6088.330283668793, 6092.451679348857, 6147.985582378908, 6195.504359651229, 6215.689215539216, 6239.500839932806, 6288.905666414996, 6299.496040316775, 6326.430621244087, 6330.050492426127, 5567.49892509674, 5581.2186293918885, 5614.214010038588, 5666.490015898568, 5709.400512946143, 5593.552515798747, 5622.0, 5669.206311116447, 5671.6597004179675, 5676.233708449368, 5570.610057295991, 5578.191162281467, 5644.774209031634, 5682.460166284196, 5687.7156142192725, 5523.061079616475, 5586.050371436825, 5600.887982240332, 5648.548116150698, 5662.037453632893, 5569.944300556983, 5621.943780562183, 5642.6614403135945, 5645.294338207724, 5650.6892120933335, 6124.418180272867, 6175.66033868137, 6197.349278325801, 6244.718987645567, 6284.308726040132, 6615.106960560311, 6633.535652504278, 6654.168228971379, 6692.799216023527, 6728.394444500019, 6662.700178491979, 6668.366505182, 6669.0, 6671.899921501133, 6673.499487538454, 6163.167972323724, 6248.1564988726395, 6301.306856245809, 6325.399087086719, 6332.050192471074, 6796.184457864989, 6800.0, 6802.693878775368, 6885.655717214119, 6887.690053947585, 6568.0, 6588.96705516479, 6590.340965903371, 6590.670466476753, 6592.439642630334, 6661.60030398168, 6661.966690166518, 6673.666316684146, 6705.195376554844, 6793.626350550669, 6603.240627327823, 6665.700043498055, 6682.231543372477, 6684.097646817618, 6692.631905245258, 6599.439047681001, 6607.041978913031, 6617.172853393326, 6683.4987375947, 6696.2968888267005, 4998.775055122528, 5023.397192336943, 5038.6976781392505, 5049.293098966147, 5052.6715763475995, 4585.747783871852, 4609.423822022247, 4622.699524530894, 4663.253879379351, 4664.113818374498, 6127.295361033449, 6213.627182369072, 6285.120083188357, 6294.874102517924, 6308.526860485482, 6607.80176594703, 6624.735010599618, 6625.204975402886, 6714.630695311707, 6717.865642687118, 6076.665783381868, 6164.506839452923, 6186.536009799283, 6257.030160325156, 6293.842653933597, 5553.805616803393, 5573.303337082864, 5573.526250268692, 5616.719164041781, 5616.831495055155, 6952.304769523007, 6953.930460695429, 7031.0, 7049.977753225754, 7051.083359163378, 6646.169228846395, 6657.866842663023, 6692.397684208445, 6754.0881980931945, 6754.628495432798, 6821.1814582250445, 6922.376986071277, 6953.965230173817, 7000.7549735759, 7021.227664956953, 6595.274519802721, 6608.240052394036, 6625.900611490735, 6636.535442518977, 6640.269570347161, 6637.203535575762, 6644.933550664431, 6666.566673166229, 6720.596764194164, 6762.459003279824, 6276.089966954857, 6368.9044664330995, 6377.74489020434, 6448.613083215022, 6451.290358060516, 6392.552521323649, 6396.10454536356, 6451.516136289891, 6456.483481321577, 6502.6748662567625, 6346.809595712136, 6426.550141490051, 6439.7424103035355, 6447.387498187664, 6455.0, 6347.936520634735, 6410.230772307353, 6437.839054023687, 6521.64130972801, 6534.411902928759, 6203.875922481616, 6282.246130464374, 6288.717007734572, 6402.487800976004, 6450.3549645037], "value": 6141.259913580471, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.8566855454322, 896.9237614802742, 918.5085979001236, 944.2729098594087, 946.8248374050797, 42010.0, 43019.56980430195, 43058.0, 43093.56906430943, 43115.0, 39539.20921581567, 39707.60292397075, 39742.20515589687, 39819.402708959315, 40010.799946000305, 6923.134608173978, 7010.579365238102, 7015.263397343282, 7015.7544485943, 7024.332688394595, 7113.89329160063, 7206.49554531183, 7213.495055346128, 7221.422286217116, 7223.602701851396, 8038.236367545074, 8180.427370084097, 8182.836343273131, 8184.058833234165, 8186.83626327476, 7941.24558166979, 8059.07320658126, 8076.959615201945, 8085.0, 8106.310963568104, 7451.590162541058, 7486.363659088984, 7487.213842546508, 7491.8501629967095, 7498.287662672037, 39577.40633890494, 40102.19795604071, 40103.19793604111, 40118.59881401178, 40135.79932100321, 3556.8399422026073, 3590.838412271454, 3591.44332628443, 3594.964050359489, 3595.9640403596154, 32134.357312853845, 32157.51763723546, 32245.51631725526, 32250.35499290001, 32268.35463290721, 5405.864683450054, 5428.75570599324, 5436.646617969819, 5452.40023597404, 5472.2338872557875, 198411.0, 201663.0, 204644.0, 205595.0, 208474.0, 52327.0, 52514.0, 52543.474565254626, 52572.474275257526, 52585.7370713144, 54190.72904635531, 54310.45689543133, 54349.1847622286, 54395.45604543983, 54538.72730636401, 16052.919735401249, 16114.435994740128, 16120.274587643584, 16174.19129043543, 16308.347666093461, 13295.73408531824, 13313.0, 13330.866691333156, 13344.332783360793, 13362.866371336357, 15183.316750746022, 15191.772123418157, 15210.08739475635, 15252.618684532976, 15303.617409564627, 5987.401259873977, 6063.54523410746, 6217.222847144107, 6227.782027629011, 6228.128062071313, 1009.6769033909079, 1042.2443728296923, 1042.4006196437078, 1050.107408702612, 1061.0237983855693, 23619.7638023621, 23952.52094958126, 23952.760472395054, 24074.27777166688, 24107.15624953118, 1120.675004248775, 1150.5282834038005, 1156.3177725142207, 1156.8669602995762, 1167.5096459486865, 12631.494740210288, 12751.681207969876, 12794.936025319814, 12796.232226066466, 13052.738945221043, 237702.0, 237859.0, 238212.0, 239136.0, 239586.0, 55097.724511377186, 55432.0, 55442.72278638581, 55655.443445565026, 55698.443015569326, 1236.3262022197923, 1270.3394234997934, 1280.7054377493043, 1284.5760898903338, 1448.6812901161727, 23836.88081559581, 23974.880125599262, 24034.87982560076, 24210.394740131636, 24290.514189715752, 39148.80425597854, 39261.80369098136, 39271.0, 39318.41022384609, 39350.80324598359, 199095.0, 199576.0, 199704.0, 199791.0, 199887.0, 34239.0, 36749.81625091857, 37092.0, 37374.626253737115, 37429.6257037437, 47886.28170577514, 48280.7585962068, 49017.0, 49051.264231037276, 49647.50352496573, 35846.28307433889, 35895.0, 36279.27441451209, 36679.0, 37006.6299337014, 35090.298194035466, 35202.11994700153, 35644.82177589199, 35770.10574735652, 36003.45994810132, 40421.0, 40672.38991415072, 40789.38815917823, 40887.38668919909, 41067.58932410757, 34252.48621270733, 34255.14362140966, 34472.82763586166, 34617.65382346144, 34629.13427164341, 24341.878290608434, 25019.49961000806, 25223.36941576402, 25231.36921576975, 25482.872585637695], "value": 17831.761160109592, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.1843714892024, 593.9524838012959, 597.6653074278404, 598.3388355866767, 608.7169466198217, 23251.3024609262, 23515.5296894062, 24117.0, 24164.0, 24177.637335439962, 25517.872410637967, 26070.217893463177, 26228.868855655695, 26245.0, 26250.081247156355, 6243.094751261065, 6370.426661600478, 6375.936240637604, 6376.744930202786, 6376.776812811552, 6225.5953363031485, 6233.844153896166, 6263.780767673133, 6457.934440817259, 6493.415592596665, 6428.19647544057, 6449.0, 6459.450946669539, 6467.805965821033, 6476.935230647704, 6438.098666186737, 6444.709988050549, 6483.935160648428, 6488.3187265337165, 6510.576812507172, 6009.0, 6021.668808215591, 6028.728707208186, 6055.788047418319, 6064.36324185963, 30881.227969300722, 31140.37719245625, 31425.21436964094, 31658.683413165803, 32044.83977580097, 2311.2835021143333, 2340.9297721068397, 2341.906323747048, 2342.672025916373, 2343.5195784864063, 20083.899580502002, 20781.16875324985, 20784.584308313748, 20803.79196208049, 20820.271290504912, 2374.8456350337174, 2419.7822196002444, 2421.5762241607827, 2425.805935525154, 2513.6857892763405, 141175.0, 144885.0, 144934.0, 146864.0, 147922.0, 15247.923760381349, 15740.370385184464, 15797.763033554505, 15870.0, 16323.836761632469, 24645.63031554528, 25002.874985624956, 25449.236522904343, 25451.0, 25465.872670636898, 10440.68677939663, 10527.526261318259, 10584.788304233873, 10611.787764244826, 10850.403227822388, 9677.612895484242, 9743.415395076318, 9801.656942007137, 9829.115379615869, 9902.950485247527, 9547.61809527625, 9891.802163956681, 9916.652917147865, 10002.999400120005, 10331.48342582868, 2661.232318388854, 2670.145553422925, 2753.9449211015667, 2788.177487641151, 2942.132071039006, 998.201438848918, 1004.5077911823154, 1012.86832711747, 1027.4554486122368, 1036.0, 20589.588208235753, 20892.0, 21020.0, 21099.78900211009, 21227.787722122583, 1081.5835903177176, 1086.3807629651023, 1089.3355053417408, 1089.4117176724637, 1096.2326371539955, 11415.143864210217, 11526.193166478433, 11595.76808463826, 11669.824952625717, 11685.240459370114, 602246.0, 609688.0, 619252.0, 620304.0, 624964.0, 120755.0, 124959.0, 126536.36731816283, 128330.0, 133683.0, 1313.356455336878, 1320.7424552212226, 1360.204280495921, 1363.5023216526095, 1365.3514580574147, 21933.890330548245, 22023.559528809656, 22127.66808497906, 22753.431164221027, 22978.770212297663, 68536.6573167131, 68712.6564367175, 69961.65019174872, 70170.64914675395, 70397.29602703908, 85036.14963850283, 88898.55550722464, 89065.0, 89464.55267723619, 89670.0, 23115.537689245786, 23377.532449351256, 23585.0565977359, 23594.646080308466, 23725.0, 25341.0, 25434.23697289084, 25554.10560630369, 25563.23310300694, 25690.486190276464, 19838.008099594674, 19878.60242795107, 20373.0, 20387.79612203859, 20398.388048358574, 18125.821821581554, 18187.545311366794, 19020.0, 19116.426507204807, 19170.13734381984, 20626.0, 20974.370768876957, 21729.348119556438, 21732.348029558507, 21736.891315543322, 22334.88832555827, 22458.0, 22919.6248225107, 22991.195308164133, 23208.883955580113, 20115.49133814999, 20141.39575812728, 20307.28924487636, 20407.69388459204, 20566.074526646637], "value": 13165.704293184916, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.7999680044793, 1479.8594133557308, 1492.507472534064, 1496.7305884940706, 1505.382793054847, 15705.842941570581, 16350.999999999985, 16363.018218906875, 16381.508554743377, 16563.58591035225, 16703.582410439743, 16969.66060678789, 16985.575360615992, 16995.150242487856, 17005.064721440314, 2761.8895244190257, 2801.5517517197254, 2804.9298767530872, 2805.1724741201397, 2805.298675331167, 2722.333028408043, 2750.944981100377, 2813.607264404123, 2815.2398852309866, 2822.2238884306776, 2592.8962841486414, 2639.6304517367585, 2640.643513125732, 2642.128097727745, 2643.550596398607, 2636.604509323607, 2669.0391459074717, 2683.1413947536794, 2684.396010897552, 2686.382132109606, 2620.5938079597695, 2627.737226277376, 2640.6435131257417, 2641.2340421278, 2643.3920198354294, 16117.355305787873, 16405.261763220566, 16421.096839673814, 16438.75341869873, 16455.83544164555, 8103.756887293391, 8298.460600060977, 8311.418200725951, 8339.666413343459, 8370.53962032088, 5297.205419187153, 5438.7280635968045, 5454.509094181503, 5466.781328746845, 5497.587680923907, 2038.5817523043486, 2041.8672786268994, 2052.3124753207694, 2086.374087773662, 2087.7390326209224, 28072.29819254535, 28470.85764571164, 28685.426291474054, 28769.71230287712, 28954.13137605875, 6898.1377327834025, 6974.0, 7094.467914906402, 7175.461840361993, 7184.137903451619, 19138.71291930622, 19185.0, 19186.232550697816, 19198.520036999187, 19204.327848525234, 7968.681252749825, 8225.46534475269, 8382.371322150862, 8389.370797190235, 8395.076541580544, 7660.310572048543, 7688.884666730003, 7691.1924247954275, 7699.422543309274, 7701.768946931601, 5348.732563371816, 5405.567554595623, 5420.13277875538, 5429.755660995264, 5476.780928762805, 1488.6129606302222, 1491.246920305239, 1498.4455751371845, 1516.3100789140915, 1521.2849960518538, 912.8539433690578, 953.5041778275258, 957.7270477913761, 959.8608201810698, 966.4394651102343, 8271.090180080186, 8294.253517183482, 8312.33501319893, 8332.0, 8364.832703345899, 739.4601940583296, 749.1422321441919, 751.6054071612338, 756.6179079564895, 758.5448730761499, 5516.641418307797, 5581.441855814386, 5745.224394706661, 5779.017567013618, 5893.410658934073, 22872.31383058511, 23427.41431464193, 23681.881590592282, 23745.28764137044, 23746.762532374454, 22808.771912280998, 23243.418914527272, 23304.65043024356, 23563.64654530149, 23662.408439789142, 9858.704238872846, 10032.799344013225, 12458.190217635825, 12554.309512976692, 12664.986801055893, 8407.831843362976, 9749.658761943298, 9939.801203975736, 10095.495225238856, 10171.28800983924, 19978.30075947335, 20111.195552178335, 20185.6972145415, 20442.38672839817, 21282.78717212808, 20852.895735521226, 20884.582308353445, 21016.789832102095, 21194.576108477435, 21470.355889323346, 11695.473703683185, 12779.0, 13117.212967221998, 13198.14212076212, 13219.933900330438, 14890.404383824525, 14920.179390133648, 15031.398744050115, 15033.398664053315, 15074.54776356711, 11887.643370698894, 11910.464029118537, 12045.457954392248, 12185.207961482476, 12226.144169908377, 11836.408179590813, 11943.223690460092, 12136.939315303367, 12223.877761222275, 12411.875881241072, 9168.0, 9336.486493242664, 9342.486163261086, 9342.813143736952, 9359.110884465965, 7482.738104166328, 7788.610569471391, 7841.490303130278, 8003.319717823817, 8022.398320126131, 8299.6680132794, 8401.579921004048, 8416.747497575083, 8423.957880210559, 8448.281896038905], "value": 7006.186615718168, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [530.9920351194733, 533.7384681506063, 566.1903478026422, 569.2969183058918, 573.3492486028357, 6197.752089916398, 6265.686715664216, 6292.0, 6297.937020629793, 6362.459190968768, 5992.970035149829, 6106.786262480814, 6139.171211886405, 6152.261728592575, 6206.751729930808, 4862.489438608948, 4895.510448955094, 4966.875828104308, 4988.551030407262, 4994.051130285243, 4841.128596852565, 4908.460069392382, 4946.258061290817, 4953.257011448293, 4973.179425394806, 4886.462489126193, 4915.729634870081, 4955.306257124005, 4968.378952630921, 4996.65023448357, 4806.836923384491, 4854.12625727369, 4879.585235255007, 4911.582515486188, 4938.8765280868265, 4471.374007638933, 4484.372187893698, 4510.962478629934, 4523.773811309455, 4551.954480455186, 6137.079438084278, 6166.136740856284, 6167.488965203554, 6179.104029915637, 6193.225846769154, 5751.338596061465, 5770.884582308372, 5805.941940580582, 5819.272590926134, 5860.267466566679, 6552.853250681112, 6559.278479367265, 6563.029367232803, 6594.142761440982, 6595.274519802818, 4951.851444456672, 4961.3798275215595, 4962.925556116661, 4972.7513624319, 4979.1784355581285, 6858.530928865604, 6871.175458944908, 6880.65596720162, 6883.795335816212, 6897.517173797836, 5097.108006098918, 5109.540141387219, 5109.948900511022, 5128.358955130609, 5128.871778205575, 5187.844364669066, 5193.68837869729, 5207.635465517377, 5210.843674689765, 5224.50367215114, 5640.0, 5736.024875771129, 5766.798162064308, 5817.592768506249, 5837.6205546640185, 6501.1548498694865, 6565.80302590923, 6570.0, 6573.605583664996, 6576.5067619928695, 1702.5147832867574, 1749.7987731410797, 1754.8596112310984, 1772.6986412309939, 1787.6782179207607, 1221.883921027501, 1257.9622611321674, 1258.0123405020047, 1285.5950375631646, 1289.832321798179, 6209.813705588839, 6222.146725554075, 6226.40849119333, 6240.687965601702, 6271.905921411182, 6569.507286953402, 6682.264950855402, 6709.932900671029, 6744.6627668616375, 6797.218319893275, 6198.349173336825, 6265.838285791395, 6276.0, 6303.243610766737, 6315.905261421082, 3476.4090104682264, 3505.158761897177, 3526.2242306692488, 3580.4092324766643, 3590.622984586633, 6926.653667316614, 6991.6853741580735, 7043.626492833848, 7091.326323999212, 7097.361237488651, 6809.693563789641, 6881.724731010803, 6887.449004079662, 6979.790606281819, 7029.789106326818, 6860.451163906975, 6920.9307906920285, 6951.2353641099435, 7013.649317534103, 7102.28977102296, 6712.194536655633, 6739.5619284746335, 6744.0, 6744.7976560703255, 6758.662066896537, 6683.331666833278, 6708.329167083252, 6728.966355168389, 6736.259011508632, 6750.561213521106, 6938.167419909441, 6969.58182509051, 6991.126109236346, 7033.261507541839, 7046.929530704832, 5003.824866129814, 5007.649464537448, 5025.899482010266, 5037.471065538066, 5047.394312682502, 6486.189226346707, 6760.0, 6766.763163289262, 6808.897866532105, 6872.656367181521, 6237.844053898689, 6352.650604216812, 6438.935610643834, 6440.516961227833, 6475.83434981689, 6415.454686351526, 6431.0, 6445.967770161119, 6452.290248072803, 6461.67691615408, 6295.401936815995, 6312.56966068058, 6313.43179113882, 6427.58220715652, 6473.158489396348, 6335.167223829517, 6380.776672816429, 6415.71129299192, 6493.220813502221, 6539.803805885831, 6381.659851431252, 6388.8083357499345, 6391.10524526576, 6406.455451286693, 6426.646534440651], "value": 5130.296284131877, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [954.2652157838464, 971.349196038654, 995.4326034160529, 997.6508222122258, 998.381003777658, 17950.281988720453, 18724.719129213074, 18760.812391876076, 18800.529986750338, 20389.898050509764, 20296.086676099574, 20916.477088072807, 21219.469513262175, 21291.467713307135, 21361.572768544658, 1009.8889122196534, 1045.5295117197254, 1046.7540128069897, 1046.8115739166985, 1047.1779652972418, 1037.8287582548871, 1096.7477480179564, 1098.6758906122714, 1106.6016234155697, 1106.6956586938616, 1135.2677522997662, 1142.714321419645, 1142.9257098288626, 1143.3311512765042, 1143.7026373143, 1126.555010770745, 1132.0321125437797, 1132.1678566253756, 1135.403912945702, 1136.9715757106055, 842.9199226073574, 843.1273631791066, 843.161054750521, 843.6372359885247, 843.6751850537526, 18669.719954200697, 18830.152643131092, 18850.151743171453, 19008.90495547527, 19920.103595338245, 22130.225442109448, 22182.11271549136, 22437.0, 22550.43623909399, 22556.66165007526, 16974.575635609086, 17818.0, 18037.098145092823, 18096.18567164481, 18687.906560467247, 3806.7525610835205, 3837.7505462144873, 3837.846486140579, 3838.7312888097845, 3839.347310957144, 133501.3324933379, 135337.0, 137289.0, 137424.0, 137657.0, 16798.748018779726, 17055.48833534996, 17443.21505532254, 17524.91237543804, 17528.649427011387, 32226.19434514109, 32458.188545286557, 32770.836145819594, 32844.0, 33266.83366583152, 7935.0, 8640.956795216109, 9160.725178244664, 9498.762530936781, 9533.856992145124, 4436.911261774746, 4518.254488009507, 4521.344405061313, 4547.886302842389, 4552.385427967249, 5750.683712395775, 5901.144334071536, 6171.6297022178815, 6340.302566717657, 6398.264199617103, 1237.189640785287, 1256.8303279057393, 1269.05455435701, 1274.3309762374643, 1276.8403949506312, 724.6883839948767, 738.1253214940313, 740.4594645908517, 748.6219459173192, 760.7261385901071, 12829.486820527074, 12980.286084265455, 13413.463461461628, 13435.261060641758, 13453.394597243148, 723.0780754538006, 726.5531698005794, 734.8787450070786, 736.683226212723, 741.4068745004083, 5887.970560147171, 5961.463468287875, 6024.849378765566, 6056.606320589148, 6136.8772624547255, 126313.0, 126362.0, 128169.3591532055, 129387.0, 130030.0, 112838.43580782044, 115790.42104789423, 120922.0, 121921.0, 126107.0, 100522.49738751406, 104251.4787426058, 104376.0, 105121.0, 106572.99999999844, 12816.359182041047, 13690.86309136896, 13866.791998120238, 13902.582922512342, 13970.441182352592, 48889.51110488992, 49404.50595493999, 49708.502914970384, 50128.49871501384, 51297.487025129274, 63540.682296588224, 64204.0, 64378.356216437234, 64484.35515644783, 64655.353446464935, 18124.72812907834, 18227.270909163486, 18336.81663183351, 18343.54141146482, 18647.0, 20424.795752042883, 21641.242556510446, 21709.34871953844, 21926.45183870352, 21931.12275508962, 20359.287424940056, 20420.693689594966, 20651.17395304231, 20874.37376878696, 20885.0, 18991.335303264874, 19106.904465477583, 19109.904450477658, 20143.0, 20914.37256882296, 19242.7113593299, 19492.122854471298, 19940.60118797587, 20077.096530655865, 20261.493462662962, 13674.453021879013, 14085.788713169106, 14450.710985780435, 14489.0, 14589.708205835612, 13984.0, 14662.560123196321, 14761.483348082766, 14835.85164148388, 15041.924790375979], "value": 9894.988833864067, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1462.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 99.0, 104.0, 106.0, 104.0, 108.0, 96.0, 106.0, 109.0], "value": 103.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 137.0, 160.0, 114.0, 139.0, 139.0, 115.0, 170.0, 116.0], "value": 134.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 162.0, 162.0, 160.0, 162.0, 163.0, 161.0, 162.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 77.0, 174.0, 74.0, 82.0, 73.0, 74.0, 183.0, 73.0], "value": 95.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 138.0, 78.0, 78.0, 78.0, 128.0, 77.0, 78.0, 130.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 114.0, 115.0, 114.0, 114.0, 115.0, 113.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 149.0, 150.0, 150.0, 150.0, 150.0, 148.0, 150.0, 149.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 94.0, 94.0, 93.0, 94.0, 93.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 53.0, 49.0, 47.0, 47.0, 48.0, 49.0, 47.0, 53.0, 47.0], "value": 48.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 39.0, 38.0, 39.0, 39.0, 39.0, 39.0, 39.0, 40.0, 39.0], "value": 39.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 78.0, 72.0, 74.0, 73.0, 78.0, 79.0, 74.0, 73.0], "value": 74.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 114.0, 117.0, 115.0, 112.0, 113.0, 117.0, 128.0, 169.0], "value": 122.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 182.0, 164.0, 181.0, 166.0, 176.0, 154.0, 165.0, 167.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 60.0, 64.0, 61.0, 61.0, 60.0, 61.0, 59.0, 62.0, 60.0], "value": 61.2, "unit": "score"}]}]} 19:05:42 INFO - Return code: 0 19:05:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:05:42 INFO - # TBPL SUCCESS # 19:05:42 INFO - Running post-action listener: _package_coverage_data 19:05:42 INFO - Running post-action listener: _resource_record_post_action 19:05:42 INFO - [mozharness: 2017-08-28 02:05:42.686273Z] Finished run-tests step (success) 19:05:42 INFO - Running post-run listener: _resource_record_post_run 19:05:42 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 36864; Write bytes: 416796672; Read time: 292; Write time: 419136 19:05:42 INFO - TinderboxPrint: CPU usage
13.1% 19:05:42 INFO - TinderboxPrint: I/O read bytes / time
36,864 / 292 19:05:42 INFO - TinderboxPrint: I/O write bytes / time
416,796,672 / 419,136 19:05:42 INFO - TinderboxPrint: CPU idle
8,227.8 (86.9%) 19:05:42 INFO - TinderboxPrint: CPU user
1,188.2 (12.6%) 19:05:42 INFO - TinderboxPrint: Swap in / out
0 / 0 19:05:42 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 28672; Write bytes: 106610688; Read time: 92; Write time: 177608 19:05:42 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:05:42 INFO - run-tests - Wall time: 1169s; CPU: 13.0%; Read bytes: 8192; Write bytes: 307851264; Read time: 200; Write time: 240884 19:05:43 INFO - Running post-run listener: _upload_blobber_files 19:05:43 INFO - Blob upload gear active. 19:05:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:05:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:05:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:05:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:05:43 INFO - (blobuploader) - INFO - Open directory for files ... 19:05:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:05:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:05:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:05:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:05:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:05:44 INFO - (blobuploader) - INFO - Done attempting. 19:05:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:05:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:05:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:05:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:05:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:05:45 INFO - (blobuploader) - INFO - Done attempting. 19:05:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:05:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:05:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:05:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:05:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:05:46 INFO - (blobuploader) - INFO - Done attempting. 19:05:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:05:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:05:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:05:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:05:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:05:50 INFO - (blobuploader) - INFO - Done attempting. 19:05:50 INFO - (blobuploader) - INFO - Iteration through files over. 19:05:50 INFO - Return code: 0 19:05:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:05:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:05:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e76aebe920e9884612d56222e9f9617c53b3a1bc0276fb2627ed21c039e77f541a2b564cc841408896ad9dc36474e5ca22ae1d129fd1efe2295ccf713be1579a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c6858625e7df21185eb376401a16f733addb99ad2ee800bad2d86532b47085d0f81cd0985067491741d3772f8fbf20a2e6fa6d904490b1ef260cfb603a2f232", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f835ec657a42b77f320272d0a2390686686de5783d4b218a4a78fe5970d586e82e8fb8dc0bee7da6c9541a34e1cac150cdc8fab5b3f01c567a484c00471aed8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a623859bbe08fc728774612631cb183240a4804ee39c38fcdcac596a3d905930af5c64c695cddb460c980b7432319562144655ef355d5bb9467004218b49f19"} 19:05:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:05:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:05:50 INFO - Contents: 19:05:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e76aebe920e9884612d56222e9f9617c53b3a1bc0276fb2627ed21c039e77f541a2b564cc841408896ad9dc36474e5ca22ae1d129fd1efe2295ccf713be1579a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c6858625e7df21185eb376401a16f733addb99ad2ee800bad2d86532b47085d0f81cd0985067491741d3772f8fbf20a2e6fa6d904490b1ef260cfb603a2f232", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f835ec657a42b77f320272d0a2390686686de5783d4b218a4a78fe5970d586e82e8fb8dc0bee7da6c9541a34e1cac150cdc8fab5b3f01c567a484c00471aed8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a623859bbe08fc728774612631cb183240a4804ee39c38fcdcac596a3d905930af5c64c695cddb460c980b7432319562144655ef355d5bb9467004218b49f19"} 19:05:50 INFO - Running post-run listener: copy_logs_to_upload_dir 19:05:50 INFO - Copying logs to upload dir... 19:05:50 INFO - mkdir: /builds/slave/test/build/upload/logs 19:05:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.805953 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2017-08-27 19:05:50.398467) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 19:05:50.403420) ========= 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=db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e76aebe920e9884612d56222e9f9617c53b3a1bc0276fb2627ed21c039e77f541a2b564cc841408896ad9dc36474e5ca22ae1d129fd1efe2295ccf713be1579a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c6858625e7df21185eb376401a16f733addb99ad2ee800bad2d86532b47085d0f81cd0985067491741d3772f8fbf20a2e6fa6d904490b1ef260cfb603a2f232", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f835ec657a42b77f320272d0a2390686686de5783d4b218a4a78fe5970d586e82e8fb8dc0bee7da6c9541a34e1cac150cdc8fab5b3f01c567a484c00471aed8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a623859bbe08fc728774612631cb183240a4804ee39c38fcdcac596a3d905930af5c64c695cddb460c980b7432319562144655ef355d5bb9467004218b49f19"} build_url:https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/cxN0HP4PS6CEXlCtAZijtQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e76aebe920e9884612d56222e9f9617c53b3a1bc0276fb2627ed21c039e77f541a2b564cc841408896ad9dc36474e5ca22ae1d129fd1efe2295ccf713be1579a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c6858625e7df21185eb376401a16f733addb99ad2ee800bad2d86532b47085d0f81cd0985067491741d3772f8fbf20a2e6fa6d904490b1ef260cfb603a2f232", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3f835ec657a42b77f320272d0a2390686686de5783d4b218a4a78fe5970d586e82e8fb8dc0bee7da6c9541a34e1cac150cdc8fab5b3f01c567a484c00471aed8", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1a623859bbe08fc728774612631cb183240a4804ee39c38fcdcac596a3d905930af5c64c695cddb460c980b7432319562144655ef355d5bb9467004218b49f19"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-27 19:05:50.430659) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 19:05:50.431017) ========= 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=db7e7c15f3ca076d53731445000001ba-1503884425.805558-1128731786 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004446 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-27 19:05:50.513815) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 19:05:50.514222) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-27 19:05:50.514603) ========= ========= Total master_lag: 0.24 =========