builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-069 starttime: 1506224486.05 results: success (0) revision: 42aa84eec339c29bf3d21178ae6e5c138d50e82b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.050865) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.051272) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.051539) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.083189) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.083450) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 20:41:26-- 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% 41.0M=0s 2017-09-23 20:41:26 (41.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152304 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.253740) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.254026) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016478 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.328649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.328952) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 42aa84eec339c29bf3d21178ae6e5c138d50e82b --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 42aa84eec339c29bf3d21178ae6e5c138d50e82b --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=05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 20:41:26,769 truncating revision to first 12 chars 2017-09-23 20:41:26,769 Setting DEBUG logging. 2017-09-23 20:41:26,769 attempt 1/10 2017-09-23 20:41:26,769 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/42aa84eec339?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 20:41:27,022 unpacking tar archive at: mozilla-central-42aa84eec339/testing/mozharness/ program finished with exit code 0 elapsedTime=0.520471 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.865507) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.865790) ========= script_repo_revision: 42aa84eec339c29bf3d21178ae6e5c138d50e82b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.869162) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.869425) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 20:41:26.884121) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-23 20:41:26.884373) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:41:27 INFO - MultiFileLogger online at 20170923 20:41:27 in /builds/slave/test 20:41:27 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 20:41:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:41:27 INFO - {'append_to_log': False, 20:41:27 INFO - 'base_work_dir': '/builds/slave/test', 20:41:27 INFO - 'blob_upload_branch': 'Firefox', 20:41:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:41:27 INFO - 'branch': 'Firefox', 20:41:27 INFO - 'buildbot_json_path': 'buildprops.json', 20:41:27 INFO - 'code_coverage': False, 20:41:27 INFO - 'config_files': ('talos/linux_config.py',), 20:41:27 INFO - 'default_actions': ('clobber', 20:41:27 INFO - 'read-buildbot-config', 20:41:27 INFO - 'download-and-extract', 20:41:27 INFO - 'populate-webroot', 20:41:27 INFO - 'create-virtualenv', 20:41:27 INFO - 'install', 20:41:27 INFO - 'setup-mitmproxy', 20:41:27 INFO - 'run-tests'), 20:41:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:41:27 INFO - 'disable_ccov_upload': False, 20:41:27 INFO - 'disable_stylo': False, 20:41:27 INFO - 'download_minidump_stackwalk': True, 20:41:27 INFO - 'download_symbols': 'ondemand', 20:41:27 INFO - 'enable_stylo': False, 20:41:27 INFO - 'enable_webrender': False, 20:41:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 20:41:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:41:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:41:27 INFO - 'gecko_profile': False, 20:41:27 INFO - 'gecko_profile_interval': 0, 20:41:27 INFO - 'installer_path': 'installer.exe', 20:41:27 INFO - 'log_level': 'info', 20:41:27 INFO - 'log_name': 'talos', 20:41:27 INFO - 'log_to_console': True, 20:41:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:41:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:41:27 INFO - 'opt_config_files': (), 20:41:27 INFO - 'pip_index': False, 20:41:27 INFO - 'suite': 'dromaeojs-e10s', 20:41:27 INFO - 'system_bits': '32', 20:41:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:41:27 INFO - 'title': 'talos-linux64-ix-069', 20:41:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:41:27 INFO - 'use_talos_json': True, 20:41:27 INFO - 'verify': 'False', 20:41:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:41:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:41:27 INFO - 'work_dir': 'build'} 20:41:27 INFO - [mozharness: 2017-09-24 03:41:27.478204Z] Running clobber step. 20:41:27 INFO - Running pre-action listener: _resource_record_pre_action 20:41:27 INFO - Running main action method: clobber 20:41:27 INFO - rmtree: /builds/slave/test/build 20:41:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:41:28 INFO - Running post-action listener: _resource_record_post_action 20:41:28 INFO - [mozharness: 2017-09-24 03:41:28.469325Z] Finished clobber step (success) 20:41:28 INFO - [mozharness: 2017-09-24 03:41:28.469423Z] Running read-buildbot-config step. 20:41:28 INFO - Running pre-action listener: _resource_record_pre_action 20:41:28 INFO - Running main action method: read_buildbot_config 20:41:28 INFO - Using buildbot properties: 20:41:28 INFO - { 20:41:28 INFO - "project": "", 20:41:28 INFO - "product": "firefox", 20:41:28 INFO - "who": "philringnalda@gmail.com", 20:41:28 INFO - "installer_path": "public/build/target.tar.bz2", 20:41:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:41:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 20:41:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 20:41:28 INFO - "stage_platform": "linux64-pgo", 20:41:28 INFO - "basedir": "/builds/slave/test", 20:41:28 INFO - "buildnumber": 202, 20:41:28 INFO - "platform": "ubuntu64_hw", 20:41:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:41:28 INFO - "slavebuilddir": "test", 20:41:28 INFO - "taskId": "TXcSaMPKTna3Y1JQi0z3lg", 20:41:28 INFO - "branch": "mozilla-central", 20:41:28 INFO - "script_repo_revision": "production", 20:41:28 INFO - "revision": "42aa84eec339c29bf3d21178ae6e5c138d50e82b", 20:41:28 INFO - "slavename": "talos-linux64-ix-069", 20:41:28 INFO - "repo_path": "mozilla-central" 20:41:28 INFO - } 20:41:28 INFO - Finding installer, test and symbols from parent task. 20:41:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TXcSaMPKTna3Y1JQi0z3lg'}, attempt #1 20:41:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TXcSaMPKTna3Y1JQi0z3lg'}, attempt #1 20:41:29 INFO - Task dependencies: Q4ywgNwcTHaso13_WBPeKA 20:41:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA'}, attempt #1 20:41:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.tar.bz2 20:41:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.test_packages.json 20:41:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.crashreporter-symbols.zip 20:41:29 INFO - Running post-action listener: _resource_record_post_action 20:41:29 INFO - [mozharness: 2017-09-24 03:41:29.820336Z] Finished read-buildbot-config step (success) 20:41:29 INFO - [mozharness: 2017-09-24 03:41:29.820597Z] Running download-and-extract step. 20:41:29 INFO - Running pre-action listener: _resource_record_pre_action 20:41:29 INFO - Running main action method: download_and_extract 20:41:29 INFO - mkdir: /builds/slave/test/build/tests 20:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:29 INFO - trying https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.test_packages.json 20:41:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:41:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:41:30 INFO - Downloaded 989 bytes. 20:41:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:41:30 INFO - Using the following test package requirements: 20:41:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 20:41:30 INFO - u'common': [u'target.common.tests.zip'], 20:41:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:41:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:41:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:41:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:41:30 INFO - u'mozbase': [u'target.common.tests.zip'], 20:41:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:41:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:41:30 INFO - u'web-platform': [u'target.common.tests.zip', 20:41:30 INFO - u'target.web-platform.tests.tar.gz'], 20:41:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:41:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 20:41:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.common.tests.zip 20:41:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:41:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.common.tests.zip into memory 20:41:32 INFO - Content-Length response header: 55347281 20:41:32 INFO - Bytes received: 55347281 20:41:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.talos.tests.zip 20:41:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:41:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.talos.tests.zip into memory 20:41:44 INFO - Content-Length response header: 14513823 20:41:44 INFO - Bytes received: 14513823 20:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:45 INFO - trying https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.tar.bz2 20:41:45 INFO - Downloading https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:41:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:41:48 INFO - Downloaded 62395661 bytes. 20:41:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.tar.bz2 20:41:48 INFO - mkdir: /builds/slave/test/properties 20:41:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:41:48 INFO - Writing to file /builds/slave/test/properties/build_url 20:41:48 INFO - Contents: 20:41:48 INFO - build_url:https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.tar.bz2 20:41:48 INFO - Running post-action listener: _resource_record_post_action 20:41:48 INFO - Running post-action listener: find_tests_for_verification 20:41:48 INFO - Running post-action listener: set_extra_try_arguments 20:41:48 INFO - [mozharness: 2017-09-24 03:41:48.445068Z] Finished download-and-extract step (success) 20:41:48 INFO - [mozharness: 2017-09-24 03:41:48.445343Z] Running populate-webroot step. 20:41:48 INFO - Running pre-action listener: _resource_record_pre_action 20:41:48 INFO - Running main action method: populate_webroot 20:41:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:41:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:41:48 INFO - 'tests': ['tresize', 20:41:48 INFO - 'tcanvasmark']}, 20:41:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:41:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:41:48 INFO - 'tests': ['dromaeo_css', 20:41:48 INFO - 'kraken']}, 20:41:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:41:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:41:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:41:48 INFO - 'talos_options': ['--disable-stylo'], 20:41:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:41:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:41:48 INFO - 'tests': ['damp', 'tps']}, 20:41:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:41:48 INFO - 'talos_options': ['--disable-stylo'], 20:41:48 INFO - 'tests': ['damp', 'tps']}, 20:41:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:41:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:41:48 INFO - 'tests': ['dromaeo_dom']}, 20:41:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:41:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:41:48 INFO - 'tests': ['basic_compositor_video', 20:41:48 INFO - 'glvideo']}, 20:41:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:41:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:41:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:41:48 INFO - 'talos_options': ['--disable-stylo'], 20:41:48 INFO - 'tests': ['ts_paint_webext', 20:41:48 INFO - 'tp5o_webext']}, 20:41:48 INFO - 'other-e10s': {'tests': ['a11yr', 20:41:48 INFO - 'ts_paint', 20:41:48 INFO - 'tpaint', 20:41:48 INFO - 'sessionrestore', 20:41:48 INFO - 'sessionrestore_many_windows', 20:41:49 INFO - 'sessionrestore_no_auto_restore', 20:41:49 INFO - 'tabpaint']}, 20:41:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:41:49 INFO - 'tests': ['a11yr', 20:41:49 INFO - 'ts_paint', 20:41:49 INFO - 'tpaint', 20:41:49 INFO - 'sessionrestore', 20:41:49 INFO - 'sessionrestore_many_windows', 20:41:49 INFO - 'sessionrestore_no_auto_restore', 20:41:49 INFO - 'tabpaint']}, 20:41:49 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 20:41:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 20:41:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:41:49 INFO - 'tests': ['perf_reftest_singletons']}, 20:41:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:41:49 INFO - 'tests': ['perf_reftest']}, 20:41:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:41:49 INFO - 'tsvgr_opacity', 20:41:49 INFO - 'tart', 20:41:49 INFO - 'tscrollx', 20:41:49 INFO - 'tsvg_static']}, 20:41:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 20:41:49 INFO - 'tests': ['tsvgx', 20:41:49 INFO - 'tsvgr_opacity', 20:41:49 INFO - 'tart', 20:41:49 INFO - 'tscrollx', 20:41:49 INFO - 'tsvg_static']}, 20:41:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:41:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:41:49 INFO - 'talos_options': ['--disable-stylo'], 20:41:49 INFO - 'tests': ['tp5o']}, 20:41:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:41:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 20:41:49 INFO - 'talos_options': ['--mitmproxy', 20:41:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:41:49 INFO - '--firstNonBlankPaint'], 20:41:49 INFO - 'tests': ['tp6_google', 20:41:49 INFO - 'tp6_youtube', 20:41:49 INFO - 'tp6_amazon', 20:41:49 INFO - 'tp6_facebook']}, 20:41:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:41:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 20:41:49 INFO - 'talos_options': ['--disable-stylo', 20:41:49 INFO - '--mitmproxy', 20:41:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:41:49 INFO - '--firstNonBlankPaint'], 20:41:49 INFO - 'tests': ['tp6_google', 20:41:49 INFO - 'tp6_youtube', 20:41:49 INFO - 'tp6_amazon', 20:41:49 INFO - 'tp6_facebook']}, 20:41:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:41:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 20:41:49 INFO - 'talos_options': ['--stylo-threads=1', 20:41:49 INFO - '--mitmproxy', 20:41:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:41:49 INFO - '--firstNonBlankPaint'], 20:41:49 INFO - 'tests': ['tp6_google', 20:41:49 INFO - 'tp6_youtube', 20:41:49 INFO - 'tp6_amazon', 20:41:49 INFO - 'tp6_facebook']}, 20:41:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:41:49 INFO - 'talos_options': ['--xperf_path', 20:41:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:41:49 INFO - 'tests': ['tp5n']}, 20:41:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 20:41:49 INFO - 'talos_options': ['--disable-stylo', 20:41:49 INFO - '--xperf_path', 20:41:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:41:49 INFO - 'tests': ['tp5n']}}} 20:41:49 INFO - Running post-action listener: _resource_record_post_action 20:41:49 INFO - [mozharness: 2017-09-24 03:41:49.005449Z] Finished populate-webroot step (success) 20:41:49 INFO - [mozharness: 2017-09-24 03:41:49.005546Z] Running create-virtualenv step. 20:41:49 INFO - Running pre-action listener: _resource_record_pre_action 20:41:49 INFO - Running main action method: create_virtualenv 20:41:49 INFO - Creating virtualenv /builds/slave/test/build/venv 20:41:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:41:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 20:41:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 20:41:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:41:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:41:49 INFO - Installing distribute.............................................................................................................................................................................................done. 20:41:49 INFO - Installing pip...............done. 20:41:49 INFO - Return code: 0 20:41:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:41:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:41:49 INFO - Reading from file tmpfile_stdout 20:41:49 INFO - Output received: 20:41:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 20:41:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:41: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')]} 20:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41: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')]} 20:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:49 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:41:49 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 20:41:49 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 20:41:49 INFO - Using env: {'DISPLAY': ':0', 20:41:49 INFO - 'HOME': '/home/cltbld', 20:41:49 INFO - 'LANG': 'en_US.UTF-8', 20:41:49 INFO - 'LANGUAGE': 'en_US:en', 20:41:49 INFO - 'LOGNAME': 'cltbld', 20:41:49 INFO - 'MAIL': '/var/mail/cltbld', 20:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:49 INFO - 'MOZ_NO_REMOTE': '1', 20:41:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:41:49 INFO - 'NO_EM_RESTART': '1', 20:41:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:49 INFO - 'PWD': '/builds/slave/test', 20:41:49 INFO - 'SHELL': '/bin/bash', 20:41:49 INFO - 'SHLVL': '1', 20:41:49 INFO - 'TERM': 'linux', 20:41:49 INFO - 'TMOUT': '86400', 20:41:49 INFO - 'USER': 'cltbld', 20:41:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:49 INFO - '_': '/tools/buildbot/bin/python'} 20:41:51 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:41:51 INFO - Downloading/unpacking pip>=1.5 20:41:51 INFO - Running setup.py egg_info for package pip 20:41:51 INFO - warning: no files found matching 'pip/cacert.pem' 20:41:51 INFO - warning: no files found matching '*.html' under directory 'docs' 20:41:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:41:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:41:51 INFO - Installing collected packages: pip 20:41:51 INFO - Found existing installation: pip 1.1 20:41:51 INFO - Uninstalling pip: 20:41:51 INFO - Successfully uninstalled pip 20:41:51 INFO - Running setup.py install for pip 20:41:51 INFO - warning: no files found matching 'pip/cacert.pem' 20:41:51 INFO - warning: no files found matching '*.html' under directory 'docs' 20:41:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:41:51 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:41:51 INFO - Installing pip script to /builds/slave/test/build/venv/bin 20:41:51 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 20:41:51 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 20:41:51 INFO - Successfully installed pip 20:41:51 INFO - Cleaning up... 20:41:51 INFO - Return code: 0 20:41:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:51 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:41:51 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 20:41:51 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 20:41:51 INFO - Using env: {'DISPLAY': ':0', 20:41:51 INFO - 'HOME': '/home/cltbld', 20:41:51 INFO - 'LANG': 'en_US.UTF-8', 20:41:51 INFO - 'LANGUAGE': 'en_US:en', 20:41:51 INFO - 'LOGNAME': 'cltbld', 20:41:51 INFO - 'MAIL': '/var/mail/cltbld', 20:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:51 INFO - 'MOZ_NO_REMOTE': '1', 20:41:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:41:51 INFO - 'NO_EM_RESTART': '1', 20:41:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:51 INFO - 'PWD': '/builds/slave/test', 20:41:51 INFO - 'SHELL': '/bin/bash', 20:41:51 INFO - 'SHLVL': '1', 20:41:51 INFO - 'TERM': 'linux', 20:41:51 INFO - 'TMOUT': '86400', 20:41:51 INFO - 'USER': 'cltbld', 20:41:51 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:51 INFO - '_': '/tools/buildbot/bin/python'} 20:41:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:51 INFO - Downloading/unpacking psutil>=3.1.1 20:41:51 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 20:41:51 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 20:41:51 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 20:41:51 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 20:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:41:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:41:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:41:52 INFO - Installing collected packages: psutil 20:41:52 INFO - Running setup.py install for psutil 20:41:52 INFO - building 'psutil._psutil_linux' extension 20:41:52 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 20:41:52 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 20:41:52 INFO - building 'psutil._psutil_posix' extension 20:41:52 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 20:41:53 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 20:41:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:41:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:41:53 INFO - Successfully installed psutil 20:41:53 INFO - Cleaning up... 20:41:53 INFO - Return code: 0 20:41:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:41:53 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')]} 20:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:53 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')]} 20:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:53 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:41:53 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 20:41:53 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 20:41:53 INFO - Using env: {'DISPLAY': ':0', 20:41:53 INFO - 'HOME': '/home/cltbld', 20:41:53 INFO - 'LANG': 'en_US.UTF-8', 20:41:53 INFO - 'LANGUAGE': 'en_US:en', 20:41:53 INFO - 'LOGNAME': 'cltbld', 20:41:53 INFO - 'MAIL': '/var/mail/cltbld', 20:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:53 INFO - 'MOZ_NO_REMOTE': '1', 20:41:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:41:53 INFO - 'NO_EM_RESTART': '1', 20:41:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:53 INFO - 'PWD': '/builds/slave/test', 20:41:53 INFO - 'SHELL': '/bin/bash', 20:41:53 INFO - 'SHLVL': '1', 20:41:53 INFO - 'TERM': 'linux', 20:41:53 INFO - 'TMOUT': '86400', 20:41:53 INFO - 'USER': 'cltbld', 20:41:53 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:53 INFO - '_': '/tools/buildbot/bin/python'} 20:41:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:41:53 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 20:41:53 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 20:41:53 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 20:41:53 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 20:41:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:41:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:41:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:41:54 INFO - Installing collected packages: mozsystemmonitor 20:41:54 INFO - Running setup.py install for mozsystemmonitor 20:41:55 INFO - Successfully installed mozsystemmonitor 20:41:55 INFO - Cleaning up... 20:41:55 INFO - Return code: 0 20:41:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:41:55 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')]} 20:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:55 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')]} 20:41:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:55 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:41:55 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 20:41:55 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 20:41:55 INFO - Using env: {'DISPLAY': ':0', 20:41:55 INFO - 'HOME': '/home/cltbld', 20:41:55 INFO - 'LANG': 'en_US.UTF-8', 20:41:55 INFO - 'LANGUAGE': 'en_US:en', 20:41:55 INFO - 'LOGNAME': 'cltbld', 20:41:55 INFO - 'MAIL': '/var/mail/cltbld', 20:41:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:55 INFO - 'MOZ_NO_REMOTE': '1', 20:41:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:41:55 INFO - 'NO_EM_RESTART': '1', 20:41:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:41:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:55 INFO - 'PWD': '/builds/slave/test', 20:41:55 INFO - 'SHELL': '/bin/bash', 20:41:55 INFO - 'SHLVL': '1', 20:41:55 INFO - 'TERM': 'linux', 20:41:55 INFO - 'TMOUT': '86400', 20:41:55 INFO - 'USER': 'cltbld', 20:41:55 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:41:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:55 INFO - '_': '/tools/buildbot/bin/python'} 20:41:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:55 INFO - Downloading/unpacking jsonschema==2.5.1 20:41:55 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 20:41:55 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 20:41:55 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 20:41:55 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 20:41:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:41:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:41:56 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 20:41:56 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 20:41:56 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 20:41:56 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 20:41:56 INFO - Downloading functools32-3.2.3-2.tar.gz 20:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:41:56 INFO - warning: no files found matching '*.txt' 20:41:56 INFO - no previously-included directories found matching 'build' 20:41:56 INFO - no previously-included directories found matching 'dist' 20:41:56 INFO - no previously-included directories found matching '.git*' 20:41:56 INFO - Installing collected packages: jsonschema, functools32 20:41:57 INFO - Running setup.py install for functools32 20:41:58 INFO - warning: no files found matching '*.txt' 20:41:58 INFO - no previously-included directories found matching 'build' 20:41:58 INFO - no previously-included directories found matching 'dist' 20:41:58 INFO - no previously-included directories found matching '.git*' 20:41:58 INFO - Successfully installed jsonschema functools32 20:41:58 INFO - Cleaning up... 20:41:58 INFO - Return code: 0 20:41:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:58 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:41:58 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 20:41:58 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 20:41:58 INFO - Using env: {'DISPLAY': ':0', 20:41:58 INFO - 'HOME': '/home/cltbld', 20:41:58 INFO - 'LANG': 'en_US.UTF-8', 20:41:58 INFO - 'LANGUAGE': 'en_US:en', 20:41:58 INFO - 'LOGNAME': 'cltbld', 20:41:58 INFO - 'MAIL': '/var/mail/cltbld', 20:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:58 INFO - 'MOZ_NO_REMOTE': '1', 20:41:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:41:58 INFO - 'NO_EM_RESTART': '1', 20:41:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:58 INFO - 'PWD': '/builds/slave/test', 20:41:58 INFO - 'SHELL': '/bin/bash', 20:41:58 INFO - 'SHLVL': '1', 20:41:58 INFO - 'TERM': 'linux', 20:41:58 INFO - 'TMOUT': '86400', 20:41:58 INFO - 'USER': 'cltbld', 20:41:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:58 INFO - '_': '/tools/buildbot/bin/python'} 20:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:41:58 INFO - Cleaning up... 20:41:58 INFO - Return code: 0 20:41:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:41:58 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:41:58 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 20:41:58 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 20:41:58 INFO - Using env: {'DISPLAY': ':0', 20:41:58 INFO - 'HOME': '/home/cltbld', 20:41:58 INFO - 'LANG': 'en_US.UTF-8', 20:41:58 INFO - 'LANGUAGE': 'en_US:en', 20:41:58 INFO - 'LOGNAME': 'cltbld', 20:41:58 INFO - 'MAIL': '/var/mail/cltbld', 20:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:41:58 INFO - 'MOZ_NO_REMOTE': '1', 20:41:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:41:58 INFO - 'NO_EM_RESTART': '1', 20:41:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:41:58 INFO - 'PWD': '/builds/slave/test', 20:41:58 INFO - 'SHELL': '/bin/bash', 20:41:58 INFO - 'SHLVL': '1', 20:41:58 INFO - 'TERM': 'linux', 20:41:58 INFO - 'TMOUT': '86400', 20:41:58 INFO - 'USER': 'cltbld', 20:41:58 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:41:58 INFO - '_': '/tools/buildbot/bin/python'} 20:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:41:58 INFO - Downloading/unpacking blobuploader==1.2.4 20:41:58 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 20:41:58 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 20:41:58 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 20:41:58 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 20:41:59 INFO - Downloading blobuploader-1.2.4.tar.gz 20:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:41:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:41:59 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 20:41:59 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 20:41:59 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 20:41:59 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 20:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:42:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:42:00 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 20:42:00 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 20:42:00 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 20:42:00 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 20:42:00 INFO - Downloading docopt-0.6.1.tar.gz 20:42:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:42:00 INFO - Installing collected packages: blobuploader, requests, docopt 20:42:00 INFO - Running setup.py install for blobuploader 20:42:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:42:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:42:00 INFO - Running setup.py install for requests 20:42:00 INFO - Running setup.py install for docopt 20:42:00 INFO - Successfully installed blobuploader requests docopt 20:42:00 INFO - Cleaning up... 20:42:00 INFO - Return code: 0 20:42:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:42:00 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')]} 20:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:00 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')]} 20:42:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:00 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:42:00 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 20:42:00 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 20:42:00 INFO - Using env: {'DISPLAY': ':0', 20:42:00 INFO - 'HOME': '/home/cltbld', 20:42:00 INFO - 'LANG': 'en_US.UTF-8', 20:42:00 INFO - 'LANGUAGE': 'en_US:en', 20:42:00 INFO - 'LOGNAME': 'cltbld', 20:42:00 INFO - 'MAIL': '/var/mail/cltbld', 20:42:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:00 INFO - 'MOZ_NO_REMOTE': '1', 20:42:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:42:00 INFO - 'NO_EM_RESTART': '1', 20:42:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:42:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:00 INFO - 'PWD': '/builds/slave/test', 20:42:00 INFO - 'SHELL': '/bin/bash', 20:42:00 INFO - 'SHLVL': '1', 20:42:00 INFO - 'TERM': 'linux', 20:42:00 INFO - 'TMOUT': '86400', 20:42:00 INFO - 'USER': 'cltbld', 20:42:00 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:42:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:00 INFO - '_': '/tools/buildbot/bin/python'} 20:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:42:01 INFO - Running setup.py (path:/tmp/pip-geEUs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:42:01 INFO - Running setup.py (path:/tmp/pip-LPXTM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:42:01 INFO - Running setup.py (path:/tmp/pip-pMiNU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:42:01 INFO - Running setup.py (path:/tmp/pip-X7NHpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:42:01 INFO - Running setup.py (path:/tmp/pip-_rl5LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:42:01 INFO - Running setup.py (path:/tmp/pip-GwVkDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:42:01 INFO - Running setup.py (path:/tmp/pip-xgRfQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:42:01 INFO - Running setup.py (path:/tmp/pip-Qad1ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:42:01 INFO - Running setup.py (path:/tmp/pip-YA1Nu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:42:01 INFO - Running setup.py (path:/tmp/pip-42mJ82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:42:01 INFO - Running setup.py (path:/tmp/pip-zt_qeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:42:01 INFO - Running setup.py (path:/tmp/pip-A_cFD9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:42:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:42:01 INFO - Running setup.py (path:/tmp/pip-IBvre1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:42:02 INFO - Running setup.py (path:/tmp/pip-Kyvu8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:42:02 INFO - Running setup.py (path:/tmp/pip-mgbIcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:42:02 INFO - Running setup.py (path:/tmp/pip-GQmEQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:42:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:42:02 INFO - Running setup.py (path:/tmp/pip-wtnNni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:42:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:42:02 INFO - Running setup.py install for manifestparser 20:42:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:42:02 INFO - Running setup.py install for mozcrash 20:42:02 INFO - Running setup.py install for mozdebug 20:42:02 INFO - Running setup.py install for mozdevice 20:42:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:42:02 INFO - Running setup.py install for mozfile 20:42:02 INFO - Running setup.py install for mozhttpd 20:42:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:42:02 INFO - Running setup.py install for mozinfo 20:42:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Running setup.py install for mozInstall 20:42:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Running setup.py install for mozleak 20:42:03 INFO - Running setup.py install for mozlog 20:42:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Running setup.py install for moznetwork 20:42:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Running setup.py install for mozprocess 20:42:03 INFO - Running setup.py install for mozprofile 20:42:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Running setup.py install for mozrunner 20:42:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:42:03 INFO - Running setup.py install for mozscreenshot 20:42:03 INFO - Running setup.py install for moztest 20:42:03 INFO - Running setup.py install for mozversion 20:42:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:42:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:42:04 INFO - Cleaning up... 20:42:04 INFO - Return code: 0 20:42:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:42:04 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')]} 20:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:04 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')]} 20:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:04 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:42:04 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 20:42:04 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 20:42:04 INFO - Using env: {'DISPLAY': ':0', 20:42:04 INFO - 'HOME': '/home/cltbld', 20:42:04 INFO - 'LANG': 'en_US.UTF-8', 20:42:04 INFO - 'LANGUAGE': 'en_US:en', 20:42:04 INFO - 'LOGNAME': 'cltbld', 20:42:04 INFO - 'MAIL': '/var/mail/cltbld', 20:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:04 INFO - 'MOZ_NO_REMOTE': '1', 20:42:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:42:04 INFO - 'NO_EM_RESTART': '1', 20:42:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:04 INFO - 'PWD': '/builds/slave/test', 20:42:04 INFO - 'SHELL': '/bin/bash', 20:42:04 INFO - 'SHLVL': '1', 20:42:04 INFO - 'TERM': 'linux', 20:42:04 INFO - 'TMOUT': '86400', 20:42:04 INFO - 'USER': 'cltbld', 20:42:04 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:04 INFO - '_': '/tools/buildbot/bin/python'} 20:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:42:04 INFO - Running setup.py (path:/tmp/pip-ES7w8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:42:04 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)) 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:42:04 INFO - Running setup.py (path:/tmp/pip-AEfiTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:42:04 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)) 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:42:04 INFO - Running setup.py (path:/tmp/pip-nCaUbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:42:04 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)) 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:42:04 INFO - Running setup.py (path:/tmp/pip-38N5Hm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:42:04 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)) 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:42:04 INFO - Running setup.py (path:/tmp/pip-7u9pvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:42:04 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)) 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:42:04 INFO - Running setup.py (path:/tmp/pip-521xPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:42:04 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)) 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:42:04 INFO - Running setup.py (path:/tmp/pip-mby46M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:42:04 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)) 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:42:04 INFO - Running setup.py (path:/tmp/pip-uMGQdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:42:04 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)) 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:42:04 INFO - Running setup.py (path:/tmp/pip-WErT79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:42:04 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)) 20:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:42:04 INFO - Running setup.py (path:/tmp/pip-6Ee0Bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:42:05 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)) 20:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:42:05 INFO - Running setup.py (path:/tmp/pip-TvnaVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:42:05 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)) 20:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:42:05 INFO - Running setup.py (path:/tmp/pip-AR9qN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:42:05 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)) 20:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:42:05 INFO - Running setup.py (path:/tmp/pip-acSPKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:42:05 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)) 20:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:42:05 INFO - Running setup.py (path:/tmp/pip-AwjWtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:42:05 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)) 20:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:42:05 INFO - Running setup.py (path:/tmp/pip-ZZLEqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:42:05 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)) 20:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:42:05 INFO - Running setup.py (path:/tmp/pip-2n1b8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:42:05 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)) 20:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:42:05 INFO - Running setup.py (path:/tmp/pip-UyfgEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:42:05 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)) 20:42:05 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)) 20:42:05 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)) 20:42:05 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)) 20:42:05 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)) 20:42:05 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)) 20:42:05 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)) 20:42:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:42:05 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 20:42:05 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 20:42:05 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 20:42:05 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 20:42:06 INFO - Downloading blessings-1.6.tar.gz 20:42:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:42:06 INFO - Installing collected packages: blessings 20:42:06 INFO - Running setup.py install for blessings 20:42:06 INFO - Successfully installed blessings 20:42:06 INFO - Cleaning up... 20:42:06 INFO - Return code: 0 20:42:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:42:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:42:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:42:07 INFO - Reading from file tmpfile_stdout 20:42:07 INFO - Current package versions: 20:42:07 INFO - argparse == 1.2.1 20:42:07 INFO - blessings == 1.6 20:42:07 INFO - blobuploader == 1.2.4 20:42:07 INFO - docopt == 0.6.1 20:42:07 INFO - functools32 == 3.2.3-2 20:42:07 INFO - jsonschema == 2.5.1 20:42:07 INFO - manifestparser == 1.1 20:42:07 INFO - mozInstall == 1.13 20:42:07 INFO - mozcrash == 1.0 20:42:07 INFO - mozdebug == 0.1 20:42:07 INFO - mozdevice == 0.51 20:42:07 INFO - mozfile == 1.2 20:42:07 INFO - mozhttpd == 0.7 20:42:07 INFO - mozinfo == 0.10 20:42:07 INFO - mozleak == 0.1 20:42:07 INFO - mozlog == 3.5 20:42:07 INFO - moznetwork == 0.27 20:42:07 INFO - mozprocess == 0.25 20:42:07 INFO - mozprofile == 0.28 20:42:07 INFO - mozrunner == 6.13 20:42:07 INFO - mozscreenshot == 0.1 20:42:07 INFO - mozsystemmonitor == 0.3 20:42:07 INFO - moztest == 0.8 20:42:07 INFO - mozversion == 1.4 20:42:07 INFO - psutil == 3.1.1 20:42:07 INFO - requests == 1.2.3 20:42:07 INFO - wsgiref == 0.1.2 20:42:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:42:07 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')]} 20:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:07 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')]} 20:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:07 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:42:07 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 20:42:07 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 20:42:07 INFO - Using env: {'DISPLAY': ':0', 20:42:07 INFO - 'HOME': '/home/cltbld', 20:42:07 INFO - 'LANG': 'en_US.UTF-8', 20:42:07 INFO - 'LANGUAGE': 'en_US:en', 20:42:07 INFO - 'LOGNAME': 'cltbld', 20:42:07 INFO - 'MAIL': '/var/mail/cltbld', 20:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:07 INFO - 'MOZ_NO_REMOTE': '1', 20:42:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:42:07 INFO - 'NO_EM_RESTART': '1', 20:42:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:07 INFO - 'PWD': '/builds/slave/test', 20:42:07 INFO - 'SHELL': '/bin/bash', 20:42:07 INFO - 'SHLVL': '1', 20:42:07 INFO - 'TERM': 'linux', 20:42:07 INFO - 'TMOUT': '86400', 20:42:07 INFO - 'USER': 'cltbld', 20:42:07 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:07 INFO - '_': '/tools/buildbot/bin/python'} 20:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:07 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)) 20:42:07 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)) 20:42:07 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)) 20:42:07 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)) 20:42:07 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)) 20:42:07 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)) 20:42:07 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)) 20:42:07 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)) 20:42:07 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)) 20:42:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:42:07 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 20:42:07 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 20:42:07 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 20:42:07 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 20:42:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 20:42:08 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)) 20:42:08 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)) 20:42:08 INFO - Installing collected packages: simplejson 20:42:08 INFO - Running setup.py install for simplejson 20:42:08 INFO - building 'simplejson._speedups' extension 20:42:08 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 20:42:09 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 20:42:09 INFO - Successfully installed simplejson 20:42:09 INFO - Cleaning up... 20:42:09 INFO - Return code: 0 20:42:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:42:09 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')]} 20:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:42:09 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')]} 20:42:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:42:09 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 0x27631f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x292bb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ad09c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', '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 20:42:09 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 20:42:09 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 20:42:09 INFO - Using env: {'DISPLAY': ':0', 20:42:09 INFO - 'HOME': '/home/cltbld', 20:42:09 INFO - 'LANG': 'en_US.UTF-8', 20:42:09 INFO - 'LANGUAGE': 'en_US:en', 20:42:09 INFO - 'LOGNAME': 'cltbld', 20:42:09 INFO - 'MAIL': '/var/mail/cltbld', 20:42:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:09 INFO - 'MOZ_NO_REMOTE': '1', 20:42:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:42:09 INFO - 'NO_EM_RESTART': '1', 20:42:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:42:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:09 INFO - 'PWD': '/builds/slave/test', 20:42:09 INFO - 'SHELL': '/bin/bash', 20:42:09 INFO - 'SHLVL': '1', 20:42:09 INFO - 'TERM': 'linux', 20:42:09 INFO - 'TMOUT': '86400', 20:42:09 INFO - 'USER': 'cltbld', 20:42:09 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:42:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:09 INFO - '_': '/tools/buildbot/bin/python'} 20:42:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:42:09 INFO - Cleaning up... 20:42:09 INFO - Return code: 0 20:42:09 INFO - Running post-action listener: _resource_record_post_action 20:42:09 INFO - Running post-action listener: _start_resource_monitoring 20:42:09 INFO - Starting resource monitoring. 20:42:09 INFO - [mozharness: 2017-09-24 03:42:09.813924Z] Finished create-virtualenv step (success) 20:42:09 INFO - [mozharness: 2017-09-24 03:42:09.814488Z] Running install step. 20:42:09 INFO - Running pre-action listener: _resource_record_pre_action 20:42:09 INFO - Running main action method: install 20:42:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:42:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:42:10 INFO - Reading from file tmpfile_stdout 20:42:10 INFO - Detecting whether we're running mozinstall >=1.0... 20:42:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:42:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:42:10 INFO - Reading from file tmpfile_stdout 20:42:10 INFO - Output received: 20:42:10 INFO - Usage: mozinstall [options] installer 20:42:10 INFO - Options: 20:42:10 INFO - -h, --help show this help message and exit 20:42:10 INFO - -d DEST, --destination=DEST 20:42:10 INFO - Directory to install application into. [default: 20:42:10 INFO - "/builds/slave/test"] 20:42:10 INFO - --app=APP Application being installed. [default: firefox] 20:42:10 INFO - mkdir: /builds/slave/test/build/application 20:42:10 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'] 20:42:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:42:27 INFO - Reading from file tmpfile_stdout 20:42:27 INFO - Output received: 20:42:27 INFO - /builds/slave/test/build/application/firefox/firefox 20:42:27 INFO - Running post-action listener: _resource_record_post_action 20:42:27 INFO - [mozharness: 2017-09-24 03:42:27.969845Z] Finished install step (success) 20:42:27 INFO - [mozharness: 2017-09-24 03:42:27.970189Z] Running setup-mitmproxy step. 20:42:27 INFO - Running pre-action listener: _resource_record_pre_action 20:42:27 INFO - Running main action method: setup_mitmproxy 20:42:27 INFO - Skipping: mitmproxy is not required 20:42:27 INFO - Running post-action listener: _resource_record_post_action 20:42:27 INFO - [mozharness: 2017-09-24 03:42:27.971812Z] Finished setup-mitmproxy step (success) 20:42:27 INFO - [mozharness: 2017-09-24 03:42:27.972124Z] Running run-tests step. 20:42:27 INFO - Running pre-action listener: _resource_record_pre_action 20:42:27 INFO - Running pre-action listener: _set_gcov_prefix 20:42:27 INFO - Running main action method: run_tests 20:42:27 WARNING - Try message not found. 20:42:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:42:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:42:27 INFO - Python 2.7.3 20:42:27 INFO - Return code: 0 20:42:27 INFO - grabbing minidump binary from tooltool 20:42:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:42:27 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 0x2acdd60>, '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 0x2acea60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2aceef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:42:27 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 20:42:27 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 20:42:27 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 20:42:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:42:28 INFO - Return code: 0 20:42:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:42:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:42:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:42:28 INFO - ENV: RUST_BACKTRACE is now full 20:42:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:42:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:42:28 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/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 20:42:28 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/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 20:42:28 INFO - Using env: {'DISPLAY': ':0', 20:42:28 INFO - 'HOME': '/home/cltbld', 20:42:28 INFO - 'LANG': 'en_US.UTF-8', 20:42:28 INFO - 'LANGUAGE': 'en_US:en', 20:42:28 INFO - 'LOGNAME': 'cltbld', 20:42:28 INFO - 'MAIL': '/var/mail/cltbld', 20:42:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:42:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:42:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:28 INFO - 'MOZ_NO_REMOTE': '1', 20:42:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:42:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:42:28 INFO - 'NO_EM_RESTART': '1', 20:42:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:42:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:28 INFO - 'PWD': '/builds/slave/test', 20:42:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:42:28 INFO - 'RUST_BACKTRACE': 'full', 20:42:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:42:28 INFO - 'SHELL': '/bin/bash', 20:42:28 INFO - 'SHLVL': '1', 20:42:28 INFO - 'TERM': 'linux', 20:42:28 INFO - 'TMOUT': '86400', 20:42:28 INFO - 'USER': 'cltbld', 20:42:28 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:42:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:28 INFO - '_': '/tools/buildbot/bin/python'} 20:42:28 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/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 20:42:28 INFO - mozversion application_buildid: 20170924021622 20:42:28 INFO - mozversion application_changeset: 42aa84eec339c29bf3d21178ae6e5c138d50e82b 20:42:28 INFO - mozversion application_display_name: Nightly 20:42:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:42:28 INFO - mozversion application_name: Firefox 20:42:28 INFO - mozversion application_remotingname: firefox 20:42:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:42:28 INFO - mozversion application_vendor: Mozilla 20:42:28 INFO - mozversion application_version: 58.0a1 20:42:28 INFO - mozversion platform_buildid: 20170924021622 20:42:28 INFO - mozversion platform_changeset: 42aa84eec339c29bf3d21178ae6e5c138d50e82b 20:42:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:42:28 INFO - mozversion platform_version: 58.0a1 20:42:28 INFO - using testdate: 1506224548 20:42:28 INFO - actual date: 1506224548 20:42:28 INFO - starting webserver on 'localhost:33016' 20:42:28 INFO - SUITE-START | Running 2 tests 20:42:28 INFO - TEST-START | dromaeo_css 20:42:28 INFO - Initialising browser for dromaeo_css test... 20:42:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33016/getInfo.html -profile /tmp/tmpfjRT6W/profile 20:42:28 INFO - TEST-INFO | started process 5552 (/builds/slave/test/build/application/firefox/firefox http://localhost:33016/getInfo.html) 20:42:34 INFO - TEST-INFO | 5552: exit 0 20:42:34 INFO - Browser initialized. 20:42:34 INFO - Running cycle 1/1 for dromaeo_css test... 20:42:34 INFO - Using env: {'DISPLAY': ':0', 20:42:34 INFO - 'HOME': '/home/cltbld', 20:42:34 INFO - 'JSGC_DISABLE_POISONING': '1', 20:42:34 INFO - 'LANG': 'en_US.UTF-8', 20:42:34 INFO - 'LANGUAGE': 'en_US:en', 20:42:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:42:34 INFO - 'LOGNAME': 'cltbld', 20:42:34 INFO - 'MAIL': '/var/mail/cltbld', 20:42:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:42:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:42:34 INFO - 'MOZ_CRASHREPORTER': '1', 20:42:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:42:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:42:34 INFO - 'MOZ_NO_REMOTE': '1', 20:42:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:42:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:42:34 INFO - 'NO_EM_RESTART': '1', 20:42:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:42:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:42:34 INFO - 'PWD': '/builds/slave/test', 20:42:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:42:34 INFO - 'RUST_BACKTRACE': 'full', 20:42:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:42:34 INFO - 'SHELL': '/bin/bash', 20:42:34 INFO - 'SHLVL': '1', 20:42:34 INFO - 'TERM': 'linux', 20:42:34 INFO - 'TMOUT': '86400', 20:42:34 INFO - 'USER': 'cltbld', 20:42:34 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 20:42:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:42:34 INFO - '_': '/tools/buildbot/bin/python'} 20:42:34 INFO - TEST-INFO | started process 5790 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfjRT6W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:42:34 INFO - PID 5790 | 20:42:34 INFO - PID 5790 | (/builds/slave/test/build/application/firefox/firefox:5840): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:42:34 INFO - PID 5790 | 20:42:45 INFO - PID 5790 | RSS: Main: 144297984 20:42:45 INFO - PID 5790 | 20:45:42 INFO - PID 5790 | Cycle 1(1): loaded http://localhost:33016/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33016/tests/dromaeo/cssquery-ext.html) 20:45:43 INFO - PID 5790 | RSS: Main: 146059264 20:45:43 INFO - PID 5790 | 20:48:44 INFO - PID 5790 | Cycle 1(1): loaded http://localhost:33016/tests/dromaeo/cssquery-ext.html (next: http://localhost:33016/tests/dromaeo/cssquery-jquery.html) 20:48:45 INFO - PID 5790 | RSS: Main: 145690624 20:48:45 INFO - PID 5790 | 20:51:46 INFO - PID 5790 | Cycle 1(1): loaded http://localhost:33016/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33016/tests/dromaeo/cssquery-mootools.html) 20:51:47 INFO - PID 5790 | RSS: Main: 148320256 20:51:47 INFO - PID 5790 | 20:54:49 INFO - PID 5790 | Cycle 1(1): loaded http://localhost:33016/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33016/tests/dromaeo/cssquery-prototype.html) 20:54:49 INFO - PID 5790 | RSS: Main: 146718720 20:54:49 INFO - PID 5790 | 20:57:52 INFO - PID 5790 | Cycle 1(1): loaded http://localhost:33016/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33016/tests/dromaeo/cssquery-yui.html) 20:57:53 INFO - PID 5790 | RSS: Main: 147492864 20:57:53 INFO - PID 5790 | 21:00:54 INFO - PID 5790 | Cycle 1(1): loaded http://localhost:33016/tests/dromaeo/cssquery-yui.html (next: http://localhost:33016/tests/dromaeo/cssquery-dojo.html) 21:00:55 INFO - PID 5790 | RSS: Main: 150138880 21:00:55 INFO - PID 5790 | 21:00:55 INFO - PID 5790 | __start_tp_report 21:00:55 INFO - PID 5790 | _x_x_mozilla_page_load 21:00:55 INFO - PID 5790 | _x_x_mozilla_page_load_details 21:00:55 INFO - PID 5790 | |i|pagename|runs| 21:00:55 INFO - PID 5790 | |0;dojo.html;3859.18957019026;3861.0733423978254;3875.9612403875954;3912.3544615138503;3920.3727403615408;6207.28616209135;6279.215098112736;6359.077933699611;6378.075179099033;6465.288818229997;6344.206974128234;6357.968210158942;6392.424681778639;6396.040593910916;6398;5636.210930469727;5639.520640745521;5708.058170401879;5744.19581258622;5761.625494342875;5721.6853073080965;5765.654060756368;5766.654000759947;5781.479666829984;5828.941710582903;143.46088750463173;5612.971935140339;5617.8033768818095;5667.8583035424235;5758.193852860602;5671.631343962629;5676.063449530822;5738.0532212184935;5761.308642962829;5792.797252096157;5634.6337488063145;5669.546436285087;5708.571857110692;5714.9714251428895;5717.571182161313;6237.438630523275;6272.153259310026;6314.116023756677;6321.589096708699;6408.935910640927;6841.521093523455;6857.074294970215;6904.033435319059;6931.480138989596;6941.5140940134215;6835.555688880207;6868.862622747517;6917.481188910851;6949.826254343631;6950;6387.073874288202;6405.935940640627;6433.099366088752;6464.870702585969;6472.223333199999;7065.89401158983;7072.115985501812;7079.185893622196;7164.92835071653;7179.389751871045;6695.230048544382;6780.627065511444;6793.830154246085;6848.1097457331225;6869.107016087876;6946;6991.30086991307;6993.930060699327;6999.160100787938;7005;6819.522633415712;6832.965835170792;6833.93166068333;6892;6903.723851046002;6724.159480064992;6778.525503214826;6800.4219641330055;6862.794116176523;6891.069705589681;4963.851084467472;5049.990001999615;5058.988202359543;5059.443461219263;5073.264376665363;4560.429946256718;4599.195140850405;4603.056373443503;4612.169809434267;4625.699329543569;6326.49388048955;6362.077498762654;6370.840728981812;6449.6130232186215;6451.516136289797;6830.624315662587;6910.654467276717;6926.653667316614;6977.476689248327;6986.231514533397;6326.525510586633;6373.171487706569;6380.298167201604;6434.388733070351;6471.191101112361;5510.834674959758;5521.3926468088475;5546.112621980464;5632.577556683265;5639.210510528529;7126.857462850714;7129.750458733919;7130.073090498202;7140.178879428829;7167.175774785862;6842.144731908512;6909.827254318581;6912.412444942236;6935.167779866449;6935.757248496278;7018.438524918097;7135.892961605684;7175.748848790267;7199.460040496879;7231.4937954342695;6716.899246511404;6719;6749.831254218488;6795.38841504267;6812.591244525344;6925.238223795278;6930.826729331606;6931.722731090902;6976.232614412512;6989.930100699131;6664.83337916556;6668.533202675766;6678.732850686112;6763.357481039294;6771.796846094625;6612.966935165294;6615.735370585123;6673.833154171185;6725.865482690417;6726.495512836459;6602.8679426410245;6612.140421745143;6636.369544893228;6643.169603799525;6661.267260601427;6688.163979502562;6755.1556055493065;6795.048693183057;6823.692933817891;6837;6534.346565343428;6538.967305163444;6547.803565893031;6620.3710647488415;6643.900341494785 21:00:55 INFO - PID 5790 | |1;ext.html;950.434491477571;966.8743063401756;990.638416977803;1019.3476175247838;1020.1787561013381;43383.56616433835;43961.78019109908;44101.55898441015;44143;44148;40840.795796020975;40888;40984.38523422144;41051.58948410515;41248.175036499255;7699.653515591784;7794.06471223454;7795.493292935941;7809.453338266324;7820.921790782105;7555.924440755604;7653.732119375823;7694.422918281123;7715.035620547432;7733.53598784072;8378.413511054228;8503.702370417037;8509.744707658749;8512.74461766148;8516;8393.202645748675;8427.747167584983;8465.53439560827;8467.407281490297;8467.830643387098;8012.439129260954;8153.551554664491;8315.459495132796;8418.116097809765;8420.789480263042;38849;39138.412923806165;39159.60840391588;39161.41257881134;39163.4125488115;3718.4980027696183;3740.7194460415576;3765.2281282337053;3766.9434958475645;3768.3028639701743;33624.663753362394;33690;33909.660903391145;33933.15167120842;33966;5417.566594672456;5438.945610543923;5482.835514934519;5534.446555344473;5575.275214222125;210619;210908.94545527326;211768;214202.92898535408;215087;51843.481565183865;51867.48132518702;51960;52181.47818521766;52418.737906310984;53601;53708.19437708437;54122;54199.187012194845;54459.72770136124;15396.153211573246;15665.373385064699;15706.214689265491;15727.44953926607;15812.130332831575;12338.64274929757;12411.689707757378;12539.749205015849;12558.120931534704;12588.559400420942;14758.778618320734;14929.850701493064;15053.698926021418;15169.08985460876;15246.23768811555;5860;5916.940830591725;5966.373530779292;5976.551758618121;6107.755689772448;1057.582255009267;1074.4573990134932;1077.5635867473686;1079.7516571188514;1083.4203701019917;24138.034478621008;24233;24452.877735611564;24555.754442455705;24767;1161.1059484197121;1182.9645110646695;1192.379962419537;1197.3115458611333;1211.939403029845;12752.872471275168;13100.737985240432;13236.470541178438;13293.867061329456;13314.93342533281;275512;277062;277504;278301;278400;56169;59598;59828.70085649544;59883.700581496814;60340.698296509116;1436.906601070927;1465.6116129225763;1474.5944865162166;1485.628592851787;1485.7697056956406;28405.147845564665;28505.572416413357;29299.267518312212;29754.404911902075;29827.701722983362;39662.60337396589;39718.00704982399;39780;39787.602123979545;39865.40201896916;227013;227104;227344;227491;229268;33724.3255134891;34744.13139671528;34791.65208347884;34820.65179348276;34949.30101398009;44162.55837441713;44211;44386.11227775491;44490.77754611206;44648.33027504525;33380.666193337755;33900.83049584736;33988.660113399535;34185;34205.315893682484;33216.667833322324;33253.50119748254;33416;33805.66194338025;34449.138771529906;38511.22977540489;39034.41448378334;39051.2189756209;39145.217095658496;39373.2125357497;32800.1799955003;33256.501152483215;33423.49864752079;33736;33944.830275848464;24648.507029859662;24707.25878223656;25126.246212613652;25358.98564057417;25399.238022859343 21:00:55 INFO - PID 5790 | |2;jquery.html;598.9520838332932;607.6748939317466;610.246345762983;629.7292164369322;644.5166125405945;23322.533549329026;23376;24149.034038638456;24155.396115097108;24171.39571510713;22381.552368952613;23582.882085589546;23612.8819355903;23777.524449511042;23861.284161475185;6668.733250669968;6848.486363522731;6866.793996180123;6870.931290687079;6875.31246875311;6519;6738.157730283715;6740.460763138962;6745.156855393076;6777.62723050232;6720.1599800025;6725.697343619524;6742.865142697167;6744.561603495782;6748.730050797987;6685.93314066858;6712.261651218362;6716.294789047129;6726.798196054102;6741.831454213635;6215.067739839015;6256.812295631138;6273.247210334744;6305.4009869062365;6305.779297724604;28858.278543036384;29925.850370748223;30036;30062.54906176409;30151.849240753654;2260.8078313343385;2284.0635339510723;2287.3252390544662;2290.6449500327476;2296.242240060776;19960.50098747543;20456.386308410772;20575;20581.794182058286;20595.58808823815;2480.5659009673236;2513.3590934311633;2558.2708927955628;2604.5442047641586;2611.073069060475;130689;134823;141810;148290;154051;15771.211439427983;15773.68452630941;15899.205039747967;16132.919335403249;16301.266443010092;25176;25366.492670146494;25416;25448.872755636472;25489.872550637498;10023;10042.949785251027;10087.798244035079;10217.74455638615;10261;9194.770130746785;9232.199721054465;9239.953800230956;9254.352195346395;9343.626254949862;9271.16559509634;9454.149126578503;9534.427934323823;9676.274279429072;9751.707448776548;2754.600582915466;2782.7217278272415;2832.830030198195;2868.4693331733697;2873.181143374128;1035.4460363705382;1063.3035361838008;1071.474977261152;1075.0324707763075;1079.109734469066;20052;20470;20486.18055277802;20600;20797.168113275602;1126.6845283320683;1128.9604863829727;1130.4969288666464;1133.1784456269304;1133.1897693149274;11111.38887361203;11329.603463878724;11421.657350279505;11454.140939429577;11484.88515114838;550560;563531;574412;576869;578012;119430;120449;122629;123101.38449307696;123651;1201.303244118409;1218.9024878009739;1249.4377530111306;1250.106174085515;1254.6988722706667;17952.551186220448;18182.27270909149;18254.543636409195;18291.17689703993;18641.533961650526;57564;58049.70975145097;59627.40372596392;59659;62430.37569624246;70908.29091709017;71495;71648.07051381354;72238;72563.27436725565;14177.503787367392;14700.779488307899;15089.245537723289;15140.772888406904;15142.318595663442;22875;23118.42203944915;23375;23468.061277548706;23497.530049398574;19110.808891910903;19184.328548500736;19284.421467356;19473;19516.512087197934;16569.25438355301;16655.333786648884;16996.915015424845;17554.473365799047;17630.501407380518;18810.811891880905;18939.810601894354;19455.70816437726;19567.412977610693;19657;14775.778363324343;14843.851561484247;14845.480408186093;14845.777313340093;14934.029288096239;18809.529761756065;18852.811471885107;18921.148548315083;18993.620127597645;19081.42755717331 21:00:55 INFO - PID 5790 | |3;mootools.html;1355.8983076269278;1456.0681164055;1467.9339429725667;1472.1314424489549;1474.491300501327;17513;18012.369567065154;18034.458966231017;18051.729224061648;18084.36704715335;17615.11924403781;17632.735508967377;17660.646787064252;17735;17759.112044397792;2811.6063751074867;2846.629938108048;2871.5261981772983;2872.1670715492587;2877.381363006953;2632.93417664558;2778.6665600128013;2827.321442853711;2840.9715902840912;2850.0024991253104;2920.1677521906263;2943.793934424591;2945.0281407135594;2946.1456177708437;2953.689862564432;2808.1294798612394;2826.5477523596333;2827.8303301801957;2832.362718388367;2837.7162283771563;2704.5131876262267;2721.414895797413;2724;2732.071095827428;2737.794665400083;16597.087160206185;16715.83284167155;16772.496825095266;16776.07731574763;16784.160791960352;7936.484128531684;8224.300934420582;8246;8277.62750676221;8302.211289927447;5635.09838425854;5922.7038648067855;5973.283206015306;6068.757249710006;6094.085887116924;2010.6883433067899;2085.374387683689;2098.548812005411;2138.957220855574;2149.7420309562795;30512.237194070105;30654.54018189729;30764.38471230585;30859.845700771577;30994;7043.119610048744;7245.38414234796;7251.891221631679;7434.405247580181;7439.479236453396;19768.802311976695;19856.10647520865;19959.900200498905;19978.30075947364;20184.495387615134;7992.200779921961;8212.301954333829;8213.34293256538;8218.178182181853;8220.05469371018;7320.341169294789;7539.32146106853;7581.203973582805;7603.885941710879;7620.161782203953;5700.8004719835435;5815.41845815415;5840.707964601753;5865.120231965239;5873.530117590582;1693.8390852868959;1744.9302027918995;1746.2142036083812;1761.1722490429477;1763.5591102224444;933.9392939458913;942.528735632184;953.4041224234853;960.5389413081759;963.7735132243918;8226.136255693185;8228;8279.58602069894;8291.461055031525;8332.916670833214;788.8698364769749;791.1969351108629;793.2741541489588;793.5833687314091;808.4058217210346;5468.7265636718;5570.108782594846;5579.832605021856;5764.913526297025;5778;24524;25301;25301.873490632428;25508.23475295744;25533.61699574508;24485.38786530351;24798.38004049913;24869.87565062163;24953.62569561458;25300.240992770247;12509.437075331814;12727.10910236293;12880;12885.742285154245;12894.419751111223;8907.198352148338;8945.329100317373;8974.596143173703;8987.101289870961;9083.091690830865;20843.58312833765;21114.366569002956;21359;21447;21958.560828783655;21544.784552154906;21827;22085.33743987683;22170.88914555417;22183.556328873008;12196.695082622651;12397.62807115788;12408.689782755504;12411.069169812481;12420.937895310466;14058.718825623635;14413;14471.638209044859;14591.270436478346;14674.119552826865;12139.75720485603;12309.322987235788;12588.937055314665;12786.552470663482;12920.547780827626;12104.334261615695;12186;12627.11610187278;12672.619821405373;12782.169159004636;9875;9881.209503239867;9919.15687166599;9987.650432234837;9990.250731195045;7675.270849269311;7759.922400776146;7776.755719084919;7812.335951444191;7819.178986206366;7492.138404083382;7652.693892244248;7690.154083050748;7723.806904827425;7764.534127952341 21:00:55 INFO - PID 5790 | |4;prototype.html;539.3231494474434;556.237954003016;574.5748146371686;576.4754073792849;590.8227531740476;6021.296902405023;6065.545084118692;6094.952523738131;6130.9080363794465;6165.876682466348;6023.76512814873;6090.482309003729;6102;6132.478739307153;6141.72362243699;4932.531409516099;4940.901181976358;4981.576565991895;5004.899902001957;5030.798768049273;4877.902441951177;5005.399352077756;5038.823641172559;5041.3194218780545;5042.4201216860165;4956.876078098058;4962.925556116661;4985.825496107637;5026.673266237683;5042.84871453857;4972.726500042496;4976.4525902150735;4977.328060711794;4980.402351717781;4997.825076122318;4550;4556.977215113936;4560.201964656205;4569.634429245652;4589.33454649074;6141.754329826802;6181.505479561624;6201.131841542187;6237.87524249517;6273.56085074045;5862.332638649447;5874.73563689635;5879.412058794129;5905.822825315247;5924.311571202186;6598.670066496704;6609;6620.6689665517015;6675.566088204251;6689.832754181184;4781.6891902026255;5030.2957585938;5081.390233172043;5083.085044691954;5106.259592359124;6533.444657204143;6645.235797883257;6672.466202703772;6855.897161542631;6896.620685862275;5185.22221666753;5201.869953251199;5207.244949482304;5207.3230480037355;5236.607254455931;5269.1569348903995;5273.472652734696;5284.9735751320995;5294.364676238799;5298.814541491107;5853.9414605854245;5862.120681897664;5870.941290587125;5889.970550147222;5930.318013428511;6497.415232629086;6578.572392794453;6598.865538869095;6662.966685166543;6731.427828634523;1755.4031629246117;1776.840084392411;1787.5888545634316;1792.2024699008816;1816.7093265077524;1189.5096207459821;1229.2133034857698;1245.2839617220134;1248.7752204603078;1285.6143157052848;6036.879262414727;6060.575759696867;6104.382338680253;6106.542009349317;6124.326324104345;6478.967605161944;6483.7082331295205;6514.4462720668325;6599.670016499156;6611.63636000015;6041.550027993338;6073.878522429527;6114.755409783559;6129.969350153221;6221.191245138193;3335.149536868083;3517.2262102337445;3519.5776506819348;3540.8937731868086;3549.432090865501;6830.31696830313;6897.172339319314;6918.481113916376;6978.79063628092;7003.54476958996;6857.765602191554;6864.5881247125335;6882.346177113166;6899.862002760017;6925.134358205224;6807.183138023469;6966.5471744336455;6985.860282794316;6995.510314277952;7006.2675556532995;6588.7035083422315;6601.019694091658;6653.301403352578;6721.361470660279;6746.9879518072685;6506.577072490273;6524.6411447368955;6526.934730652632;6540.280569137486;6552.115464412243;6766.221884483348;6885.8967115492305;6917.169939607279;6923.157647505683;7001.334873187039;5181.776851865718;5187.29971453864;5192.558632516128;5205.609579281493;5225.790968361375;6509.316521765147;6569.4744420445295;6634.734610615715;6702.313727449786;6712.865742685217;6525.412712855676;6554.377334153248;6564.212294524687;6589.24223714279;6618.0403841443685;6476.417122459001;6524.184476940382;6543.5419520633095;6560.786254542925;6595.208574971034;6309.968450157719;6379.553431259767;6398.904016439664;6442.613443193423;6496.512761542808;6497.8700425992165;6531.575447595891;6567.146270984741;6573.211214654272;6622.8344291393105;6326.272478665012;6349.936500634934;6395.360463953753;6414.134091897534;6418.807435776934 21:00:55 INFO - PID 5790 | |5;yui.html;916.4592890194785;919.1314208073366;946.3044662173302;954.9809003819923;961.7355227312488;19688.409347719575;20736.585268294624;20736.792632073673;20741.896290518565;20758.688619670716;18427;18492;18677.533061673465;19657.60684786307;19898.801011989875;1035.777307878804;1060.1677683018831;1060.6870973062964;1061.2836335473523;1061.3048453263086;1061.4109169410974;1073.4525392050032;1079.956801727934;1081.8431327457513;1093.8687357517108;1162.4710756605723;1163.4299193395218;1167.684725124216;1168.4625072466677;1169.1173164261013;1086.7174534620976;1127.503898284752;1160.379197129536;1167.241293159449;1170.4674373160192;820.1265652080515;822.3626689315828;822.7079386817653;823.0206054794775;823.9299659528947;20908.163673453044;21114.577708445744;21256.787432125635;21287.467813304636;21331.253406130712;22297.21959731418;23572.528549429087;23738.525229495484;23775.04899804023;23782.88108559446;18575.16411761474;18923.432297030973;19057;19098.61802763951;19164.233430662756;4012.979935100335;4041.6362527372385;4044.2315959967514;4048.291548978917;4051.270771261172;156547;159158;159860;161652;162710.30513931956;18019.72970405445;18154.18306176199;18438.539036524195;18506.537336566424;18650.533736656693;26968.460630787275;27726.445471090465;28096.297592559942;28268.43463130726;28545.71454285472;8696.695615653422;8696.782580435412;8705.6517739291;8750.168733970262;8780.736577902673;4359.912801743947;4489.461264648197;4489.708168969072;4493.236149854532;4493.977530112328;5831.271091113611;5966.31387390447;6013.248343957005;6021.397860214031;6056.242969628796;1078.8381742738652;1090.334895713614;1112.7329440934118;1113.3709454158375;1117.8602674665667;675.5946432140775;687.5840116729406;695.3081683724727;699.0562740300553;703.1527009952995;11539;12112.636620901387;12277.93861030707;12826.422810973529;13160.14459060158;668.1047396488729;677.8847595908708;685.475611157465;692.5221597097986;698.6402002968475;5569.610127291051;5668.943310566924;5679.148127780783;5714.485696287354;5721.799737009184;150365;151853;152867;153008;157161;124079;126632;129884;132793;139591.30204348912;113808;115716;115849;117017.41491292488;121843.39078304551;13536.458541658618;13754.724905502035;13821.516246931309;13843.861561384258;13925.442982280596;54134.18798718101;54407.45592544182;55043.449565505434;55329.723351382985;56439;69701;69766.65116674385;71268.28731712824;72329;74249.6287518559;19034.809651903302;19257.4222773317;19372;19650.50873728111;19867.701984469957;21454.78545214528;21611.675824862326;21698.783012169675;21711.131554737633;21725.239616613344;17930.5517362067;18134;18308.26766929308;18383.72424413608;18411.26354945787;16028.839711603201;16106;16362.936409133368;16392.098434586213;16498.917505412395;18752.53118672044;18765.155567999198;18768.249270029046;18851.717224241922;18964.620707586048;12533.561325353568;12584.119111662461;12867.485300587872;12923.676908077374;12994.545190918272;13594.864051359755;13714.588562343146;14024.859751402355;14060.437582496586;14103.153810771386 21:00:55 INFO - PID 5790 | __end_tp_report 21:00:55 INFO - PID 5790 | __start_cc_report 21:00:55 INFO - PID 5790 | _x_x_mozilla_cycle_collect,292 21:00:55 INFO - PID 5790 | __end_cc_report 21:00:55 INFO - PID 5790 | __startTimestamp1506225655073__endTimestamp 21:00:55 INFO - PID 5790 | 21:00:55 INFO - PID 5790 | ------- Summary: start ------- 21:00:55 INFO - PID 5790 | Number of tests: 6 21:00:55 INFO - PID 5790 | 21:00:55 INFO - PID 5790 | [#0] dojo.html Cycles:165 Average:6313.93 Median:6620.37 stddev:897.22 (13.6%) stddev-sans-first:879.06 21:00:55 INFO - PID 5790 | Values: 3859.2 3861.1 3876.0 3912.4 3920.4 6207.3 6279.2 6359.1 6378.1 6465.3 6344.2 6358.0 6392.4 6396.0 6398.0 5636.2 5639.5 5708.1 5744.2 5761.6 5721.7 5765.7 5766.7 5781.5 5828.9 143.5 5613.0 5617.8 5667.9 5758.2 5671.6 5676.1 5738.1 5761.3 5792.8 5634.6 5669.5 5708.6 5715.0 5717.6 6237.4 6272.2 6314.1 6321.6 6408.9 6841.5 6857.1 6904.0 6931.5 6941.5 6835.6 6868.9 6917.5 6949.8 6950.0 6387.1 6405.9 6433.1 6464.9 6472.2 7065.9 7072.1 7079.2 7164.9 7179.4 6695.2 6780.6 6793.8 6848.1 6869.1 6946.0 6991.3 6993.9 6999.2 7005.0 6819.5 6833.0 6833.9 6892.0 6903.7 6724.2 6778.5 6800.4 6862.8 6891.1 4963.9 5050.0 5059.0 5059.4 5073.3 4560.4 4599.2 4603.1 4612.2 4625.7 6326.5 6362.1 6370.8 6449.6 6451.5 6830.6 6910.7 6926.7 6977.5 6986.2 6326.5 6373.2 6380.3 6434.4 6471.2 5510.8 5521.4 5546.1 5632.6 5639.2 7126.9 7129.8 7130.1 7140.2 7167.2 6842.1 6909.8 6912.4 6935.2 6935.8 7018.4 7135.9 7175.7 7199.5 7231.5 6716.9 6719.0 6749.8 6795.4 6812.6 6925.2 6930.8 6931.7 6976.2 6989.9 6664.8 6668.5 6678.7 6763.4 6771.8 6613.0 6615.7 6673.8 6725.9 6726.5 6602.9 6612.1 6636.4 6643.2 6661.3 6688.2 6755.2 6795.0 6823.7 6837.0 6534.3 6539.0 6547.8 6620.4 6643.9 21:00:55 INFO - PID 5790 | 21:00:55 INFO - PID 5790 | [#1] ext.html Cycles:180 Average:41525.54 Median:34884.98 stddev:62583.59 (179.4%) stddev-sans-first:62684.99 21:00:55 INFO - PID 5790 | Values: 950.4 966.9 990.6 1019.3 1020.2 43383.6 43961.8 44101.6 44143.0 44148.0 40840.8 40888.0 40984.4 41051.6 41248.2 7699.7 7794.1 7795.5 7809.5 7820.9 7555.9 7653.7 7694.4 7715.0 7733.5 8378.4 8503.7 8509.7 8512.7 8516.0 8393.2 8427.7 8465.5 8467.4 8467.8 8012.4 8153.6 8315.5 8418.1 8420.8 38849.0 39138.4 39159.6 39161.4 39163.4 3718.5 3740.7 3765.2 3766.9 3768.3 33624.7 33690.0 33909.7 33933.2 33966.0 5417.6 5438.9 5482.8 5534.4 5575.3 210619.0 210908.9 211768.0 214202.9 215087.0 51843.5 51867.5 51960.0 52181.5 52418.7 53601.0 53708.2 54122.0 54199.2 54459.7 15396.2 15665.4 15706.2 15727.4 15812.1 12338.6 12411.7 12539.7 12558.1 12588.6 14758.8 14929.9 15053.7 15169.1 15246.2 5860.0 5916.9 5966.4 5976.6 6107.8 1057.6 1074.5 1077.6 1079.8 1083.4 24138.0 24233.0 24452.9 24555.8 24767.0 1161.1 1183.0 1192.4 1197.3 1211.9 12752.9 13100.7 13236.5 13293.9 13314.9 275512.0 277062.0 277504.0 278301.0 278400.0 56169.0 59598.0 59828.7 59883.7 60340.7 1436.9 1465.6 1474.6 1485.6 1485.8 28405.1 28505.6 29299.3 29754.4 29827.7 39662.6 39718.0 39780.0 39787.6 39865.4 227013.0 227104.0 227344.0 227491.0 229268.0 33724.3 34744.1 34791.7 34820.7 34949.3 44162.6 44211.0 44386.1 44490.8 44648.3 33380.7 33900.8 33988.7 34185.0 34205.3 33216.7 33253.5 33416.0 33805.7 34449.1 38511.2 39034.4 39051.2 39145.2 39373.2 32800.2 33256.5 33423.5 33736.0 33944.8 24648.5 24707.3 25126.2 25359.0 25399.2 21:00:55 INFO - PID 5790 | 21:00:55 INFO - PID 5790 | [#2] jquery.html Cycles:180 Average:37614.90 Median:2293.44 stddev:95049.74 (4144.4%) stddev-sans-first:95275.74 21:00:55 INFO - PID 5790 | Values: 599.0 607.7 610.2 629.7 644.5 23322.5 23376.0 24149.0 24155.4 24171.4 22381.6 23582.9 23612.9 23777.5 23861.3 6668.7 6848.5 6866.8 6870.9 6875.3 6519.0 6738.2 6740.5 6745.2 6777.6 6720.2 6725.7 6742.9 6744.6 6748.7 6685.9 6712.3 6716.3 6726.8 6741.8 6215.1 6256.8 6273.2 6305.4 6305.8 28858.3 29925.9 30036.0 30062.5 30151.8 2260.8 2284.1 2287.3 2290.6 2296.2 19960.5 20456.4 20575.0 20581.8 20595.6 2480.6 2513.4 2558.3 2604.5 2611.1 130689.0 134823.0 141810.0 148290.0 154051.0 15771.2 15773.7 15899.2 16132.9 16301.3 25176.0 25366.5 25416.0 25448.9 25489.9 10023.0 10042.9 10087.8 10217.7 10261.0 9194.8 9232.2 9240.0 9254.4 9343.6 9271.2 9454.1 9534.4 9676.3 9751.7 2754.6 2782.7 2832.8 2868.5 2873.2 1035.4 1063.3 1071.5 1075.0 1079.1 20052.0 20470.0 20486.2 20600.0 20797.2 1126.7 1129.0 1130.5 1133.2 1133.2 11111.4 11329.6 11421.7 11454.1 11484.9 550560.0 563531.0 574412.0 576869.0 578012.0 119430.0 120449.0 122629.0 123101.4 123651.0 1201.3 1218.9 1249.4 1250.1 1254.7 17952.6 18182.3 18254.5 18291.2 18641.5 57564.0 58049.7 59627.4 59659.0 62430.4 70908.3 71495.0 71648.1 72238.0 72563.3 14177.5 14700.8 15089.2 15140.8 15142.3 22875.0 23118.4 23375.0 23468.1 23497.5 19110.8 19184.3 19284.4 19473.0 19516.5 16569.3 16655.3 16996.9 17554.5 17630.5 18810.8 18939.8 19455.7 19567.4 19657.0 14775.8 14843.9 14845.5 14845.8 14934.0 18809.5 18852.8 18921.1 18993.6 19081.4 21:00:55 INFO - PID 5790 | 21:00:55 INFO - PID 5790 | [#3] mootools.html Cycles:180 Average:10373.40 Median:2827.58 stddev:7802.27 (275.9%) stddev-sans-first:7794.74 21:00:55 INFO - PID 5790 | Values: 1355.9 1456.1 1467.9 1472.1 1474.5 17513.0 18012.4 18034.5 18051.7 18084.4 17615.1 17632.7 17660.6 17735.0 17759.1 2811.6 2846.6 2871.5 2872.2 2877.4 2632.9 2778.7 2827.3 2841.0 2850.0 2920.2 2943.8 2945.0 2946.1 2953.7 2808.1 2826.5 2827.8 2832.4 2837.7 2704.5 2721.4 2724.0 2732.1 2737.8 16597.1 16715.8 16772.5 16776.1 16784.2 7936.5 8224.3 8246.0 8277.6 8302.2 5635.1 5922.7 5973.3 6068.8 6094.1 2010.7 2085.4 2098.5 2139.0 2149.7 30512.2 30654.5 30764.4 30859.8 30994.0 7043.1 7245.4 7251.9 7434.4 7439.5 19768.8 19856.1 19959.9 19978.3 20184.5 7992.2 8212.3 8213.3 8218.2 8220.1 7320.3 7539.3 7581.2 7603.9 7620.2 5700.8 5815.4 5840.7 5865.1 5873.5 1693.8 1744.9 1746.2 1761.2 1763.6 933.9 942.5 953.4 960.5 963.8 8226.1 8228.0 8279.6 8291.5 8332.9 788.9 791.2 793.3 793.6 808.4 5468.7 5570.1 5579.8 5764.9 5778.0 24524.0 25301.0 25301.9 25508.2 25533.6 24485.4 24798.4 24869.9 24953.6 25300.2 12509.4 12727.1 12880.0 12885.7 12894.4 8907.2 8945.3 8974.6 8987.1 9083.1 20843.6 21114.4 21359.0 21447.0 21958.6 21544.8 21827.0 22085.3 22170.9 22183.6 12196.7 12397.6 12408.7 12411.1 12420.9 14058.7 14413.0 14471.6 14591.3 14674.1 12139.8 12309.3 12588.9 12786.6 12920.5 12104.3 12186.0 12627.1 12672.6 12782.2 9875.0 9881.2 9919.2 9987.7 9990.3 7675.3 7759.9 7776.8 7812.3 7819.2 7492.1 7652.7 7690.2 7723.8 7764.5 21:00:55 INFO - PID 5790 | 21:00:55 INFO - PID 5790 | [#4] prototype.html Cycles:180 Average:5587.07 Median:6131.69 stddev:1554.38 (25.3%) stddev-sans-first:1511.86 21:00:55 INFO - PID 5790 | Values: 539.3 556.2 574.6 576.5 590.8 6021.3 6065.5 6095.0 6130.9 6165.9 6023.8 6090.5 6102.0 6132.5 6141.7 4932.5 4940.9 4981.6 5004.9 5030.8 4877.9 5005.4 5038.8 5041.3 5042.4 4956.9 4962.9 4985.8 5026.7 5042.8 4972.7 4976.5 4977.3 4980.4 4997.8 4550.0 4557.0 4560.2 4569.6 4589.3 6141.8 6181.5 6201.1 6237.9 6273.6 5862.3 5874.7 5879.4 5905.8 5924.3 6598.7 6609.0 6620.7 6675.6 6689.8 4781.7 5030.3 5081.4 5083.1 5106.3 6533.4 6645.2 6672.5 6855.9 6896.6 5185.2 5201.9 5207.2 5207.3 5236.6 5269.2 5273.5 5285.0 5294.4 5298.8 5853.9 5862.1 5870.9 5890.0 5930.3 6497.4 6578.6 6598.9 6663.0 6731.4 1755.4 1776.8 1787.6 1792.2 1816.7 1189.5 1229.2 1245.3 1248.8 1285.6 6036.9 6060.6 6104.4 6106.5 6124.3 6479.0 6483.7 6514.4 6599.7 6611.6 6041.6 6073.9 6114.8 6130.0 6221.2 3335.1 3517.2 3519.6 3540.9 3549.4 6830.3 6897.2 6918.5 6978.8 7003.5 6857.8 6864.6 6882.3 6899.9 6925.1 6807.2 6966.5 6985.9 6995.5 7006.3 6588.7 6601.0 6653.3 6721.4 6747.0 6506.6 6524.6 6526.9 6540.3 6552.1 6766.2 6885.9 6917.2 6923.2 7001.3 5181.8 5187.3 5192.6 5205.6 5225.8 6509.3 6569.5 6634.7 6702.3 6712.9 6525.4 6554.4 6564.2 6589.2 6618.0 6476.4 6524.2 6543.5 6560.8 6595.2 6310.0 6379.6 6398.9 6442.6 6496.5 6497.9 6531.6 6567.1 6573.2 6622.8 6326.3 6349.9 6395.4 6414.1 6418.8 21:00:55 INFO - PID 5790 | 21:00:55 INFO - PID 5790 | [#5] yui.html Cycles:180 Average:28367.76 Median:19045.90 stddev:42575.27 (223.5%) stddev-sans-first:42644.81 21:00:55 INFO - PID 5790 | Values: 916.5 919.1 946.3 955.0 961.7 19688.4 20736.6 20736.8 20741.9 20758.7 18427.0 18492.0 18677.5 19657.6 19898.8 1035.8 1060.2 1060.7 1061.3 1061.3 1061.4 1073.5 1080.0 1081.8 1093.9 1162.5 1163.4 1167.7 1168.5 1169.1 1086.7 1127.5 1160.4 1167.2 1170.5 820.1 822.4 822.7 823.0 823.9 20908.2 21114.6 21256.8 21287.5 21331.3 22297.2 23572.5 23738.5 23775.0 23782.9 18575.2 18923.4 19057.0 19098.6 19164.2 4013.0 4041.6 4044.2 4048.3 4051.3 156547.0 159158.0 159860.0 161652.0 162710.3 18019.7 18154.2 18438.5 18506.5 18650.5 26968.5 27726.4 28096.3 28268.4 28545.7 8696.7 8696.8 8705.7 8750.2 8780.7 4359.9 4489.5 4489.7 4493.2 4494.0 5831.3 5966.3 6013.2 6021.4 6056.2 1078.8 1090.3 1112.7 1113.4 1117.9 675.6 687.6 695.3 699.1 703.2 11539.0 12112.6 12277.9 12826.4 13160.1 668.1 677.9 685.5 692.5 698.6 5569.6 5668.9 5679.1 5714.5 5721.8 150365.0 151853.0 152867.0 153008.0 157161.0 124079.0 126632.0 129884.0 132793.0 139591.3 113808.0 115716.0 115849.0 117017.4 121843.4 13536.5 13754.7 13821.5 13843.9 13925.4 54134.2 54407.5 55043.4 55329.7 56439.0 69701.0 69766.7 71268.3 72329.0 74249.6 19034.8 19257.4 19372.0 19650.5 19867.7 21454.8 21611.7 21698.8 21711.1 21725.2 17930.6 18134.0 18308.3 18383.7 18411.3 16028.8 16106.0 16362.9 16392.1 16498.9 18752.5 18765.2 18768.2 18851.7 18964.6 12533.6 12584.1 12867.5 12923.7 12994.5 13594.9 13714.6 14024.9 14060.4 14103.2 21:00:55 INFO - PID 5790 | -------- Summary: end -------- 21:00:55 INFO - PID 5790 | 21:00:55 INFO - TEST-INFO | 5790: exit 0 21:00:55 INFO - TEST-OK | dromaeo_css | took 1107449ms 21:00:55 INFO - TEST-START | kraken 21:00:55 INFO - Initialising browser for kraken test... 21:00:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33016/getInfo.html -profile /tmp/tmpr1CW38/profile 21:00:55 INFO - TEST-INFO | started process 6158 (/builds/slave/test/build/application/firefox/firefox http://localhost:33016/getInfo.html) 21:01:01 INFO - TEST-INFO | 6158: exit 0 21:01:01 INFO - Browser initialized. 21:01:01 INFO - Running cycle 1/1 for kraken test... 21:01:01 INFO - Using env: {'DISPLAY': ':0', 21:01:01 INFO - 'HOME': '/home/cltbld', 21:01:01 INFO - 'JSGC_DISABLE_POISONING': '1', 21:01:01 INFO - 'LANG': 'en_US.UTF-8', 21:01:01 INFO - 'LANGUAGE': 'en_US:en', 21:01:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:01:01 INFO - 'LOGNAME': 'cltbld', 21:01:01 INFO - 'MAIL': '/var/mail/cltbld', 21:01:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:01:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:01:01 INFO - 'MOZ_CRASHREPORTER': '1', 21:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:01:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:01:01 INFO - 'MOZ_NO_REMOTE': '1', 21:01:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:01:01 INFO - 'NO_EM_RESTART': '1', 21:01:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:01:01 INFO - 'PWD': '/builds/slave/test', 21:01:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:01:01 INFO - 'RUST_BACKTRACE': 'full', 21:01:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:01:01 INFO - 'SHELL': '/bin/bash', 21:01:01 INFO - 'SHLVL': '1', 21:01:01 INFO - 'TERM': 'linux', 21:01:01 INFO - 'TMOUT': '86400', 21:01:01 INFO - 'USER': 'cltbld', 21:01:01 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867', 21:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:01:01 INFO - '_': '/tools/buildbot/bin/python'} 21:01:01 INFO - TEST-INFO | started process 6398 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpr1CW38/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:01:01 INFO - PID 6398 | 21:01:01 INFO - PID 6398 | (/builds/slave/test/build/application/firefox/firefox:6447): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:01:01 INFO - PID 6398 | 21:01:12 INFO - PID 6398 | RSS: Main: 144510976 21:01:12 INFO - PID 6398 | 21:01:14 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33016/tests/kraken/driver.html?testName=audio-beat-detection) 21:01:15 INFO - PID 6398 | RSS: Main: 152207360 21:01:15 INFO - PID 6398 | 21:01:18 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33016/tests/kraken/driver.html?testName=audio-dft) 21:01:18 INFO - PID 6398 | RSS: Main: 152068096 21:01:18 INFO - PID 6398 | 21:01:21 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33016/tests/kraken/driver.html?testName=audio-fft) 21:01:22 INFO - PID 6398 | RSS: Main: 148099072 21:01:22 INFO - PID 6398 | 21:01:24 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33016/tests/kraken/driver.html?testName=audio-oscillator) 21:01:24 INFO - PID 6398 | RSS: Main: 149184512 21:01:24 INFO - PID 6398 | 21:01:27 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33016/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:01:27 INFO - PID 6398 | RSS: Main: 148463616 21:01:27 INFO - PID 6398 | 21:01:30 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33016/tests/kraken/driver.html?testName=imaging-darkroom) 21:01:31 INFO - PID 6398 | RSS: Main: 148852736 21:01:31 INFO - PID 6398 | 21:01:34 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33016/tests/kraken/driver.html?testName=imaging-desaturate) 21:01:35 INFO - PID 6398 | RSS: Main: 150228992 21:01:35 INFO - PID 6398 | 21:01:38 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33016/tests/kraken/driver.html?testName=json-parse-financial) 21:01:39 INFO - PID 6398 | RSS: Main: 151654400 21:01:39 INFO - PID 6398 | 21:01:40 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33016/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:01:41 INFO - PID 6398 | RSS: Main: 151126016 21:01:41 INFO - PID 6398 | 21:01:42 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33016/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:01:43 INFO - PID 6398 | RSS: Main: 151236608 21:01:43 INFO - PID 6398 | 21:01:45 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33016/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:01:46 INFO - PID 6398 | RSS: Main: 148480000 21:01:46 INFO - PID 6398 | 21:01:48 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33016/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:01:49 INFO - PID 6398 | RSS: Main: 148365312 21:01:49 INFO - PID 6398 | 21:01:51 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33016/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:01:52 INFO - PID 6398 | RSS: Main: 149213184 21:01:52 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | Cycle 1(1): loaded http://localhost:33016/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33016/tests/kraken/driver.html?testName=ai-astar) 21:01:54 INFO - PID 6398 | RSS: Main: 150810624 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | __start_tp_report 21:01:54 INFO - PID 6398 | _x_x_mozilla_page_load 21:01:54 INFO - PID 6398 | _x_x_mozilla_page_load_details 21:01:54 INFO - PID 6398 | |i|pagename|runs| 21:01:54 INFO - PID 6398 | |0;ai-astar;104;104;106;106;106;109;95;105;105;106 21:01:54 INFO - PID 6398 | |1;audio-beat-detection;114;112;111;112;111;112;146;114;116;115 21:01:54 INFO - PID 6398 | |2;audio-dft;165;162;162;161;164;162;163;163;163;167 21:01:54 INFO - PID 6398 | |3;audio-fft;74;74;73;74;74;74;73;74;74;75 21:01:54 INFO - PID 6398 | |4;audio-oscillator;79;80;79;78;78;77;79;78;78;79 21:01:54 INFO - PID 6398 | |5;imaging-gaussian-blur;114;115;114;114;116;115;113;111;114;114 21:01:54 INFO - PID 6398 | |6;imaging-darkroom;150;151;151;150;151;151;150;149;150;150 21:01:54 INFO - PID 6398 | |7;imaging-desaturate;99;94;94;94;94;95;94;94;95;95 21:01:54 INFO - PID 6398 | |8;json-parse-financial;49;45;45;49;47;46;45;45;46;47 21:01:54 INFO - PID 6398 | |9;json-stringify-tinderbox;35;36;35;35;35;36;36;37;36;36 21:01:54 INFO - PID 6398 | |10;stanford-crypto-aes;72;75;71;72;74;75;72;72;74;71 21:01:54 INFO - PID 6398 | |11;stanford-crypto-ccm;103;103;103;114;110;111;105;106;102;111 21:01:54 INFO - PID 6398 | |12;stanford-crypto-pbkdf2;153;162;150;153;159;151;147;152;154;156 21:01:54 INFO - PID 6398 | |13;stanford-crypto-sha256-iterative;63;61;58;64;61;61;61;60;59;61 21:01:54 INFO - PID 6398 | __end_tp_report 21:01:54 INFO - PID 6398 | __start_cc_report 21:01:54 INFO - PID 6398 | _x_x_mozilla_cycle_collect,705 21:01:54 INFO - PID 6398 | __end_cc_report 21:01:54 INFO - PID 6398 | __startTimestamp1506225714265__endTimestamp 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | ------- Summary: start ------- 21:01:54 INFO - PID 6398 | Number of tests: 14 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#0] ai-astar Cycles:10 Average:104.60 Median:106.00 stddev:3.66 (3.5%) stddev-sans-first:3.87 21:01:54 INFO - PID 6398 | Values: 104.0 104.0 106.0 106.0 106.0 109.0 95.0 105.0 105.0 106.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#1] audio-beat-detection Cycles:10 Average:116.30 Median:114.00 stddev:10.57 (9.3%) stddev-sans-first:11.18 21:01:54 INFO - PID 6398 | Values: 114.0 112.0 111.0 112.0 111.0 112.0 146.0 114.0 116.0 115.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:1.75 (1.1%) stddev-sans-first:1.73 21:01:54 INFO - PID 6398 | Values: 165.0 162.0 162.0 161.0 164.0 162.0 163.0 163.0 163.0 167.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#3] audio-fft Cycles:10 Average:73.90 Median:74.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 21:01:54 INFO - PID 6398 | Values: 74.0 74.0 73.0 74.0 74.0 74.0 73.0 74.0 74.0 75.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#4] audio-oscillator Cycles:10 Average:78.50 Median:79.00 stddev:0.85 (1.1%) stddev-sans-first:0.88 21:01:54 INFO - PID 6398 | Values: 79.0 80.0 79.0 78.0 78.0 77.0 79.0 78.0 78.0 79.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:1.33 (1.2%) stddev-sans-first:1.41 21:01:54 INFO - PID 6398 | Values: 114.0 115.0 114.0 114.0 116.0 115.0 113.0 111.0 114.0 114.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#6] imaging-darkroom Cycles:10 Average:150.30 Median:150.50 stddev:0.67 (0.4%) stddev-sans-first:0.71 21:01:54 INFO - PID 6398 | Values: 150.0 151.0 151.0 150.0 151.0 151.0 150.0 149.0 150.0 150.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:94.50 stddev:1.55 (1.6%) stddev-sans-first:0.50 21:01:54 INFO - PID 6398 | Values: 99.0 94.0 94.0 94.0 94.0 95.0 94.0 94.0 95.0 95.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#8] json-parse-financial Cycles:10 Average:46.40 Median:46.50 stddev:1.58 (3.4%) stddev-sans-first:1.36 21:01:54 INFO - PID 6398 | Values: 49.0 45.0 45.0 49.0 47.0 46.0 45.0 45.0 46.0 47.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#9] json-stringify-tinderbox Cycles:10 Average:35.70 Median:36.00 stddev:0.67 (1.9%) stddev-sans-first:0.67 21:01:54 INFO - PID 6398 | Values: 35.0 36.0 35.0 35.0 35.0 36.0 36.0 37.0 36.0 36.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#10] stanford-crypto-aes Cycles:10 Average:72.80 Median:73.00 stddev:1.55 (2.1%) stddev-sans-first:1.62 21:01:54 INFO - PID 6398 | Values: 72.0 75.0 71.0 72.0 74.0 75.0 72.0 72.0 74.0 71.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#11] stanford-crypto-ccm Cycles:10 Average:106.80 Median:108.00 stddev:4.32 (4.0%) stddev-sans-first:4.35 21:01:54 INFO - PID 6398 | Values: 103.0 103.0 103.0 114.0 110.0 111.0 105.0 106.0 102.0 111.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.70 Median:153.50 stddev:4.37 (2.8%) stddev-sans-first:4.63 21:01:54 INFO - PID 6398 | Values: 153.0 162.0 150.0 153.0 159.0 151.0 147.0 152.0 154.0 156.0 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:1.73 (2.8%) stddev-sans-first:1.66 21:01:54 INFO - PID 6398 | Values: 63.0 61.0 58.0 64.0 61.0 61.0 61.0 60.0 59.0 61.0 21:01:54 INFO - PID 6398 | -------- Summary: end -------- 21:01:54 INFO - PID 6398 | 21:01:54 INFO - PID 6398 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:01:54 INFO - TEST-INFO | 6398: exit 0 21:01:54 INFO - TEST-OK | kraken | took 59210ms 21:01:54 INFO - SUITE-END | took 1166s 21:01:55 INFO - Completed test suite (00:19:27) 21:01:55 INFO - javascript benchmark 21:01:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8971.157492792878, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3859.18957019026, 3861.0733423978254, 3875.9612403875954, 3912.3544615138503, 3920.3727403615408, 6207.28616209135, 6279.215098112736, 6359.077933699611, 6378.075179099033, 6465.288818229997, 6344.206974128234, 6357.968210158942, 6392.424681778639, 6396.040593910916, 6398.0, 5636.210930469727, 5639.520640745521, 5708.058170401879, 5744.19581258622, 5761.625494342875, 5721.6853073080965, 5765.654060756368, 5766.654000759947, 5781.479666829984, 5828.941710582903, 143.46088750463173, 5612.971935140339, 5617.8033768818095, 5667.8583035424235, 5758.193852860602, 5671.631343962629, 5676.063449530822, 5738.0532212184935, 5761.308642962829, 5792.797252096157, 5634.6337488063145, 5669.546436285087, 5708.571857110692, 5714.9714251428895, 5717.571182161313, 6237.438630523275, 6272.153259310026, 6314.116023756677, 6321.589096708699, 6408.935910640927, 6841.521093523455, 6857.074294970215, 6904.033435319059, 6931.480138989596, 6941.5140940134215, 6835.555688880207, 6868.862622747517, 6917.481188910851, 6949.826254343631, 6950.0, 6387.073874288202, 6405.935940640627, 6433.099366088752, 6464.870702585969, 6472.223333199999, 7065.89401158983, 7072.115985501812, 7079.185893622196, 7164.92835071653, 7179.389751871045, 6695.230048544382, 6780.627065511444, 6793.830154246085, 6848.1097457331225, 6869.107016087876, 6946.0, 6991.30086991307, 6993.930060699327, 6999.160100787938, 7005.0, 6819.522633415712, 6832.965835170792, 6833.93166068333, 6892.0, 6903.723851046002, 6724.159480064992, 6778.525503214826, 6800.4219641330055, 6862.794116176523, 6891.069705589681, 4963.851084467472, 5049.990001999615, 5058.988202359543, 5059.443461219263, 5073.264376665363, 4560.429946256718, 4599.195140850405, 4603.056373443503, 4612.169809434267, 4625.699329543569, 6326.49388048955, 6362.077498762654, 6370.840728981812, 6449.6130232186215, 6451.516136289797, 6830.624315662587, 6910.654467276717, 6926.653667316614, 6977.476689248327, 6986.231514533397, 6326.525510586633, 6373.171487706569, 6380.298167201604, 6434.388733070351, 6471.191101112361, 5510.834674959758, 5521.3926468088475, 5546.112621980464, 5632.577556683265, 5639.210510528529, 7126.857462850714, 7129.750458733919, 7130.073090498202, 7140.178879428829, 7167.175774785862, 6842.144731908512, 6909.827254318581, 6912.412444942236, 6935.167779866449, 6935.757248496278, 7018.438524918097, 7135.892961605684, 7175.748848790267, 7199.460040496879, 7231.4937954342695, 6716.899246511404, 6719.0, 6749.831254218488, 6795.38841504267, 6812.591244525344, 6925.238223795278, 6930.826729331606, 6931.722731090902, 6976.232614412512, 6989.930100699131, 6664.83337916556, 6668.533202675766, 6678.732850686112, 6763.357481039294, 6771.796846094625, 6612.966935165294, 6615.735370585123, 6673.833154171185, 6725.865482690417, 6726.495512836459, 6602.8679426410245, 6612.140421745143, 6636.369544893228, 6643.169603799525, 6661.267260601427, 6688.163979502562, 6755.1556055493065, 6795.048693183057, 6823.692933817891, 6837.0, 6534.346565343428, 6538.967305163444, 6547.803565893031, 6620.3710647488415, 6643.900341494785], "value": 6254.175177844999, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [950.434491477571, 966.8743063401756, 990.638416977803, 1019.3476175247838, 1020.1787561013381, 43383.56616433835, 43961.78019109908, 44101.55898441015, 44143.0, 44148.0, 40840.795796020975, 40888.0, 40984.38523422144, 41051.58948410515, 41248.175036499255, 7699.653515591784, 7794.06471223454, 7795.493292935941, 7809.453338266324, 7820.921790782105, 7555.924440755604, 7653.732119375823, 7694.422918281123, 7715.035620547432, 7733.53598784072, 8378.413511054228, 8503.702370417037, 8509.744707658749, 8512.74461766148, 8516.0, 8393.202645748675, 8427.747167584983, 8465.53439560827, 8467.407281490297, 8467.830643387098, 8012.439129260954, 8153.551554664491, 8315.459495132796, 8418.116097809765, 8420.789480263042, 38849.0, 39138.412923806165, 39159.60840391588, 39161.41257881134, 39163.4125488115, 3718.4980027696183, 3740.7194460415576, 3765.2281282337053, 3766.9434958475645, 3768.3028639701743, 33624.663753362394, 33690.0, 33909.660903391145, 33933.15167120842, 33966.0, 5417.566594672456, 5438.945610543923, 5482.835514934519, 5534.446555344473, 5575.275214222125, 210619.0, 210908.94545527326, 211768.0, 214202.92898535408, 215087.0, 51843.481565183865, 51867.48132518702, 51960.0, 52181.47818521766, 52418.737906310984, 53601.0, 53708.19437708437, 54122.0, 54199.187012194845, 54459.72770136124, 15396.153211573246, 15665.373385064699, 15706.214689265491, 15727.44953926607, 15812.130332831575, 12338.64274929757, 12411.689707757378, 12539.749205015849, 12558.120931534704, 12588.559400420942, 14758.778618320734, 14929.850701493064, 15053.698926021418, 15169.08985460876, 15246.23768811555, 5860.0, 5916.940830591725, 5966.373530779292, 5976.551758618121, 6107.755689772448, 1057.582255009267, 1074.4573990134932, 1077.5635867473686, 1079.7516571188514, 1083.4203701019917, 24138.034478621008, 24233.0, 24452.877735611564, 24555.754442455705, 24767.0, 1161.1059484197121, 1182.9645110646695, 1192.379962419537, 1197.3115458611333, 1211.939403029845, 12752.872471275168, 13100.737985240432, 13236.470541178438, 13293.867061329456, 13314.93342533281, 275512.0, 277062.0, 277504.0, 278301.0, 278400.0, 56169.0, 59598.0, 59828.70085649544, 59883.700581496814, 60340.698296509116, 1436.906601070927, 1465.6116129225763, 1474.5944865162166, 1485.628592851787, 1485.7697056956406, 28405.147845564665, 28505.572416413357, 29299.267518312212, 29754.404911902075, 29827.701722983362, 39662.60337396589, 39718.00704982399, 39780.0, 39787.602123979545, 39865.40201896916, 227013.0, 227104.0, 227344.0, 227491.0, 229268.0, 33724.3255134891, 34744.13139671528, 34791.65208347884, 34820.65179348276, 34949.30101398009, 44162.55837441713, 44211.0, 44386.11227775491, 44490.77754611206, 44648.33027504525, 33380.666193337755, 33900.83049584736, 33988.660113399535, 34185.0, 34205.315893682484, 33216.667833322324, 33253.50119748254, 33416.0, 33805.66194338025, 34449.138771529906, 38511.22977540489, 39034.41448378334, 39051.2189756209, 39145.217095658496, 39373.2125357497, 32800.1799955003, 33256.501152483215, 33423.49864752079, 33736.0, 33944.830275848464, 24648.507029859662, 24707.25878223656, 25126.246212613652, 25358.98564057417, 25399.238022859343], "value": 18280.139251821525, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.9520838332932, 607.6748939317466, 610.246345762983, 629.7292164369322, 644.5166125405945, 23322.533549329026, 23376.0, 24149.034038638456, 24155.396115097108, 24171.39571510713, 22381.552368952613, 23582.882085589546, 23612.8819355903, 23777.524449511042, 23861.284161475185, 6668.733250669968, 6848.486363522731, 6866.793996180123, 6870.931290687079, 6875.31246875311, 6519.0, 6738.157730283715, 6740.460763138962, 6745.156855393076, 6777.62723050232, 6720.1599800025, 6725.697343619524, 6742.865142697167, 6744.561603495782, 6748.730050797987, 6685.93314066858, 6712.261651218362, 6716.294789047129, 6726.798196054102, 6741.831454213635, 6215.067739839015, 6256.812295631138, 6273.247210334744, 6305.4009869062365, 6305.779297724604, 28858.278543036384, 29925.850370748223, 30036.0, 30062.54906176409, 30151.849240753654, 2260.8078313343385, 2284.0635339510723, 2287.3252390544662, 2290.6449500327476, 2296.242240060776, 19960.50098747543, 20456.386308410772, 20575.0, 20581.794182058286, 20595.58808823815, 2480.5659009673236, 2513.3590934311633, 2558.2708927955628, 2604.5442047641586, 2611.073069060475, 130689.0, 134823.0, 141810.0, 148290.0, 154051.0, 15771.211439427983, 15773.68452630941, 15899.205039747967, 16132.919335403249, 16301.266443010092, 25176.0, 25366.492670146494, 25416.0, 25448.872755636472, 25489.872550637498, 10023.0, 10042.949785251027, 10087.798244035079, 10217.74455638615, 10261.0, 9194.770130746785, 9232.199721054465, 9239.953800230956, 9254.352195346395, 9343.626254949862, 9271.16559509634, 9454.149126578503, 9534.427934323823, 9676.274279429072, 9751.707448776548, 2754.600582915466, 2782.7217278272415, 2832.830030198195, 2868.4693331733697, 2873.181143374128, 1035.4460363705382, 1063.3035361838008, 1071.474977261152, 1075.0324707763075, 1079.109734469066, 20052.0, 20470.0, 20486.18055277802, 20600.0, 20797.168113275602, 1126.6845283320683, 1128.9604863829727, 1130.4969288666464, 1133.1784456269304, 1133.1897693149274, 11111.38887361203, 11329.603463878724, 11421.657350279505, 11454.140939429577, 11484.88515114838, 550560.0, 563531.0, 574412.0, 576869.0, 578012.0, 119430.0, 120449.0, 122629.0, 123101.38449307696, 123651.0, 1201.303244118409, 1218.9024878009739, 1249.4377530111306, 1250.106174085515, 1254.6988722706667, 17952.551186220448, 18182.27270909149, 18254.543636409195, 18291.17689703993, 18641.533961650526, 57564.0, 58049.70975145097, 59627.40372596392, 59659.0, 62430.37569624246, 70908.29091709017, 71495.0, 71648.07051381354, 72238.0, 72563.27436725565, 14177.503787367392, 14700.779488307899, 15089.245537723289, 15140.772888406904, 15142.318595663442, 22875.0, 23118.42203944915, 23375.0, 23468.061277548706, 23497.530049398574, 19110.808891910903, 19184.328548500736, 19284.421467356, 19473.0, 19516.512087197934, 16569.25438355301, 16655.333786648884, 16996.915015424845, 17554.473365799047, 17630.501407380518, 18810.811891880905, 18939.810601894354, 19455.70816437726, 19567.412977610693, 19657.0, 14775.778363324343, 14843.851561484247, 14845.480408186093, 14845.777313340093, 14934.029288096239, 18809.529761756065, 18852.811471885107, 18921.148548315083, 18993.620127597645, 19081.42755717331], "value": 12451.923890016722, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.8983076269278, 1456.0681164055, 1467.9339429725667, 1472.1314424489549, 1474.491300501327, 17513.0, 18012.369567065154, 18034.458966231017, 18051.729224061648, 18084.36704715335, 17615.11924403781, 17632.735508967377, 17660.646787064252, 17735.0, 17759.112044397792, 2811.6063751074867, 2846.629938108048, 2871.5261981772983, 2872.1670715492587, 2877.381363006953, 2632.93417664558, 2778.6665600128013, 2827.321442853711, 2840.9715902840912, 2850.0024991253104, 2920.1677521906263, 2943.793934424591, 2945.0281407135594, 2946.1456177708437, 2953.689862564432, 2808.1294798612394, 2826.5477523596333, 2827.8303301801957, 2832.362718388367, 2837.7162283771563, 2704.5131876262267, 2721.414895797413, 2724.0, 2732.071095827428, 2737.794665400083, 16597.087160206185, 16715.83284167155, 16772.496825095266, 16776.07731574763, 16784.160791960352, 7936.484128531684, 8224.300934420582, 8246.0, 8277.62750676221, 8302.211289927447, 5635.09838425854, 5922.7038648067855, 5973.283206015306, 6068.757249710006, 6094.085887116924, 2010.6883433067899, 2085.374387683689, 2098.548812005411, 2138.957220855574, 2149.7420309562795, 30512.237194070105, 30654.54018189729, 30764.38471230585, 30859.845700771577, 30994.0, 7043.119610048744, 7245.38414234796, 7251.891221631679, 7434.405247580181, 7439.479236453396, 19768.802311976695, 19856.10647520865, 19959.900200498905, 19978.30075947364, 20184.495387615134, 7992.200779921961, 8212.301954333829, 8213.34293256538, 8218.178182181853, 8220.05469371018, 7320.341169294789, 7539.32146106853, 7581.203973582805, 7603.885941710879, 7620.161782203953, 5700.8004719835435, 5815.41845815415, 5840.707964601753, 5865.120231965239, 5873.530117590582, 1693.8390852868959, 1744.9302027918995, 1746.2142036083812, 1761.1722490429477, 1763.5591102224444, 933.9392939458913, 942.528735632184, 953.4041224234853, 960.5389413081759, 963.7735132243918, 8226.136255693185, 8228.0, 8279.58602069894, 8291.461055031525, 8332.916670833214, 788.8698364769749, 791.1969351108629, 793.2741541489588, 793.5833687314091, 808.4058217210346, 5468.7265636718, 5570.108782594846, 5579.832605021856, 5764.913526297025, 5778.0, 24524.0, 25301.0, 25301.873490632428, 25508.23475295744, 25533.61699574508, 24485.38786530351, 24798.38004049913, 24869.87565062163, 24953.62569561458, 25300.240992770247, 12509.437075331814, 12727.10910236293, 12880.0, 12885.742285154245, 12894.419751111223, 8907.198352148338, 8945.329100317373, 8974.596143173703, 8987.101289870961, 9083.091690830865, 20843.58312833765, 21114.366569002956, 21359.0, 21447.0, 21958.560828783655, 21544.784552154906, 21827.0, 22085.33743987683, 22170.88914555417, 22183.556328873008, 12196.695082622651, 12397.62807115788, 12408.689782755504, 12411.069169812481, 12420.937895310466, 14058.718825623635, 14413.0, 14471.638209044859, 14591.270436478346, 14674.119552826865, 12139.75720485603, 12309.322987235788, 12588.937055314665, 12786.552470663482, 12920.547780827626, 12104.334261615695, 12186.0, 12627.11610187278, 12672.619821405373, 12782.169159004636, 9875.0, 9881.209503239867, 9919.15687166599, 9987.650432234837, 9990.250731195045, 7675.270849269311, 7759.922400776146, 7776.755719084919, 7812.335951444191, 7819.178986206366, 7492.138404083382, 7652.693892244248, 7690.154083050748, 7723.806904827425, 7764.534127952341], "value": 7211.92758505247, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.3231494474434, 556.237954003016, 574.5748146371686, 576.4754073792849, 590.8227531740476, 6021.296902405023, 6065.545084118692, 6094.952523738131, 6130.9080363794465, 6165.876682466348, 6023.76512814873, 6090.482309003729, 6102.0, 6132.478739307153, 6141.72362243699, 4932.531409516099, 4940.901181976358, 4981.576565991895, 5004.899902001957, 5030.798768049273, 4877.902441951177, 5005.399352077756, 5038.823641172559, 5041.3194218780545, 5042.4201216860165, 4956.876078098058, 4962.925556116661, 4985.825496107637, 5026.673266237683, 5042.84871453857, 4972.726500042496, 4976.4525902150735, 4977.328060711794, 4980.402351717781, 4997.825076122318, 4550.0, 4556.977215113936, 4560.201964656205, 4569.634429245652, 4589.33454649074, 6141.754329826802, 6181.505479561624, 6201.131841542187, 6237.87524249517, 6273.56085074045, 5862.332638649447, 5874.73563689635, 5879.412058794129, 5905.822825315247, 5924.311571202186, 6598.670066496704, 6609.0, 6620.6689665517015, 6675.566088204251, 6689.832754181184, 4781.6891902026255, 5030.2957585938, 5081.390233172043, 5083.085044691954, 5106.259592359124, 6533.444657204143, 6645.235797883257, 6672.466202703772, 6855.897161542631, 6896.620685862275, 5185.22221666753, 5201.869953251199, 5207.244949482304, 5207.3230480037355, 5236.607254455931, 5269.1569348903995, 5273.472652734696, 5284.9735751320995, 5294.364676238799, 5298.814541491107, 5853.9414605854245, 5862.120681897664, 5870.941290587125, 5889.970550147222, 5930.318013428511, 6497.415232629086, 6578.572392794453, 6598.865538869095, 6662.966685166543, 6731.427828634523, 1755.4031629246117, 1776.840084392411, 1787.5888545634316, 1792.2024699008816, 1816.7093265077524, 1189.5096207459821, 1229.2133034857698, 1245.2839617220134, 1248.7752204603078, 1285.6143157052848, 6036.879262414727, 6060.575759696867, 6104.382338680253, 6106.542009349317, 6124.326324104345, 6478.967605161944, 6483.7082331295205, 6514.4462720668325, 6599.670016499156, 6611.63636000015, 6041.550027993338, 6073.878522429527, 6114.755409783559, 6129.969350153221, 6221.191245138193, 3335.149536868083, 3517.2262102337445, 3519.5776506819348, 3540.8937731868086, 3549.432090865501, 6830.31696830313, 6897.172339319314, 6918.481113916376, 6978.79063628092, 7003.54476958996, 6857.765602191554, 6864.5881247125335, 6882.346177113166, 6899.862002760017, 6925.134358205224, 6807.183138023469, 6966.5471744336455, 6985.860282794316, 6995.510314277952, 7006.2675556532995, 6588.7035083422315, 6601.019694091658, 6653.301403352578, 6721.361470660279, 6746.9879518072685, 6506.577072490273, 6524.6411447368955, 6526.934730652632, 6540.280569137486, 6552.115464412243, 6766.221884483348, 6885.8967115492305, 6917.169939607279, 6923.157647505683, 7001.334873187039, 5181.776851865718, 5187.29971453864, 5192.558632516128, 5205.609579281493, 5225.790968361375, 6509.316521765147, 6569.4744420445295, 6634.734610615715, 6702.313727449786, 6712.865742685217, 6525.412712855676, 6554.377334153248, 6564.212294524687, 6589.24223714279, 6618.0403841443685, 6476.417122459001, 6524.184476940382, 6543.5419520633095, 6560.786254542925, 6595.208574971034, 6309.968450157719, 6379.553431259767, 6398.904016439664, 6442.613443193423, 6496.512761542808, 6497.8700425992165, 6531.575447595891, 6567.146270984741, 6573.211214654272, 6622.8344291393105, 6326.272478665012, 6349.936500634934, 6395.360463953753, 6414.134091897534, 6418.807435776934], "value": 5138.381352716001, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [916.4592890194785, 919.1314208073366, 946.3044662173302, 954.9809003819923, 961.7355227312488, 19688.409347719575, 20736.585268294624, 20736.792632073673, 20741.896290518565, 20758.688619670716, 18427.0, 18492.0, 18677.533061673465, 19657.60684786307, 19898.801011989875, 1035.777307878804, 1060.1677683018831, 1060.6870973062964, 1061.2836335473523, 1061.3048453263086, 1061.4109169410974, 1073.4525392050032, 1079.956801727934, 1081.8431327457513, 1093.8687357517108, 1162.4710756605723, 1163.4299193395218, 1167.684725124216, 1168.4625072466677, 1169.1173164261013, 1086.7174534620976, 1127.503898284752, 1160.379197129536, 1167.241293159449, 1170.4674373160192, 820.1265652080515, 822.3626689315828, 822.7079386817653, 823.0206054794775, 823.9299659528947, 20908.163673453044, 21114.577708445744, 21256.787432125635, 21287.467813304636, 21331.253406130712, 22297.21959731418, 23572.528549429087, 23738.525229495484, 23775.04899804023, 23782.88108559446, 18575.16411761474, 18923.432297030973, 19057.0, 19098.61802763951, 19164.233430662756, 4012.979935100335, 4041.6362527372385, 4044.2315959967514, 4048.291548978917, 4051.270771261172, 156547.0, 159158.0, 159860.0, 161652.0, 162710.30513931956, 18019.72970405445, 18154.18306176199, 18438.539036524195, 18506.537336566424, 18650.533736656693, 26968.460630787275, 27726.445471090465, 28096.297592559942, 28268.43463130726, 28545.71454285472, 8696.695615653422, 8696.782580435412, 8705.6517739291, 8750.168733970262, 8780.736577902673, 4359.912801743947, 4489.461264648197, 4489.708168969072, 4493.236149854532, 4493.977530112328, 5831.271091113611, 5966.31387390447, 6013.248343957005, 6021.397860214031, 6056.242969628796, 1078.8381742738652, 1090.334895713614, 1112.7329440934118, 1113.3709454158375, 1117.8602674665667, 675.5946432140775, 687.5840116729406, 695.3081683724727, 699.0562740300553, 703.1527009952995, 11539.0, 12112.636620901387, 12277.93861030707, 12826.422810973529, 13160.14459060158, 668.1047396488729, 677.8847595908708, 685.475611157465, 692.5221597097986, 698.6402002968475, 5569.610127291051, 5668.943310566924, 5679.148127780783, 5714.485696287354, 5721.799737009184, 150365.0, 151853.0, 152867.0, 153008.0, 157161.0, 124079.0, 126632.0, 129884.0, 132793.0, 139591.30204348912, 113808.0, 115716.0, 115849.0, 117017.41491292488, 121843.39078304551, 13536.458541658618, 13754.724905502035, 13821.516246931309, 13843.861561384258, 13925.442982280596, 54134.18798718101, 54407.45592544182, 55043.449565505434, 55329.723351382985, 56439.0, 69701.0, 69766.65116674385, 71268.28731712824, 72329.0, 74249.6287518559, 19034.809651903302, 19257.4222773317, 19372.0, 19650.50873728111, 19867.701984469957, 21454.78545214528, 21611.675824862326, 21698.783012169675, 21711.131554737633, 21725.239616613344, 17930.5517362067, 18134.0, 18308.26766929308, 18383.72424413608, 18411.26354945787, 16028.839711603201, 16106.0, 16362.936409133368, 16392.098434586213, 16498.917505412395, 18752.53118672044, 18765.155567999198, 18768.249270029046, 18851.717224241922, 18964.620707586048, 12533.561325353568, 12584.119111662461, 12867.485300587872, 12923.676908077374, 12994.545190918272, 13594.864051359755, 13714.588562343146, 14024.859751402355, 14060.437582496586, 14103.153810771386], "value": 9880.999928435063, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1371.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 104.0, 106.0, 106.0, 106.0, 109.0, 95.0, 105.0, 105.0, 106.0], "value": 104.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 111.0, 112.0, 111.0, 112.0, 146.0, 114.0, 116.0, 115.0], "value": 116.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 162.0, 161.0, 164.0, 162.0, 163.0, 163.0, 163.0, 167.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 74.0, 74.0, 73.0, 74.0, 74.0, 75.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 78.0, 78.0, 77.0, 79.0, 78.0, 78.0, 79.0], "value": 78.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 114.0, 116.0, 115.0, 113.0, 111.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 151.0, 150.0, 151.0, 151.0, 150.0, 149.0, 150.0, 150.0], "value": 150.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 45.0, 45.0, 49.0, 47.0, 46.0, 45.0, 45.0, 46.0, 47.0], "value": 46.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [35.0, 36.0, 35.0, 35.0, 35.0, 36.0, 36.0, 37.0, 36.0, 36.0], "value": 35.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 75.0, 71.0, 72.0, 74.0, 75.0, 72.0, 72.0, 74.0, 71.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 103.0, 103.0, 114.0, 110.0, 111.0, 105.0, 106.0, 102.0, 111.0], "value": 106.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 162.0, 150.0, 153.0, 159.0, 151.0, 147.0, 152.0, 154.0, 156.0], "value": 153.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 58.0, 64.0, 61.0, 61.0, 61.0, 60.0, 59.0, 61.0], "value": 60.9, "unit": "score"}]}]} 21:01:55 INFO - Return code: 0 21:01:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:01:55 INFO - # TBPL SUCCESS # 21:01:55 INFO - Running post-action listener: _package_coverage_data 21:01:55 INFO - Running post-action listener: _resource_record_post_action 21:01:55 INFO - [mozharness: 2017-09-24 04:01:55.562240Z] Finished run-tests step (success) 21:01:55 INFO - Running post-run listener: _resource_record_post_run 21:01:55 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 330698752; Read time: 0; Write time: 312724 21:01:55 INFO - TinderboxPrint: CPU usage
13.1% 21:01:55 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:01:55 INFO - TinderboxPrint: I/O write bytes / time
330,698,752 / 312,724 21:01:55 INFO - TinderboxPrint: CPU idle
8,210.6 (86.9%) 21:01:55 INFO - TinderboxPrint: CPU user
1,186.2 (12.6%) 21:01:55 INFO - TinderboxPrint: Swap in / out
0 / 0 21:01:55 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13668352; Read time: 0; Write time: 1420 21:01:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:01:55 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 0; Write bytes: 316649472; Read time: 0; Write time: 311304 21:01:56 INFO - Running post-run listener: _upload_blobber_files 21:01:56 INFO - Blob upload gear active. 21:01:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:01:56 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 21:01:56 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'] 21:01:56 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 21:01:56 INFO - (blobuploader) - INFO - Open directory for files ... 21:01:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:01:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:01:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:01:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:01:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:01:57 INFO - (blobuploader) - INFO - Done attempting. 21:01:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:01:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:01:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:02:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:02:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:02:05 INFO - (blobuploader) - INFO - Done attempting. 21:02:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:02:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:02:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:02:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:02:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:02:06 INFO - (blobuploader) - INFO - Done attempting. 21:02:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:02:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:02:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:02:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:02:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:02:07 INFO - (blobuploader) - INFO - Done attempting. 21:02:07 INFO - (blobuploader) - INFO - Iteration through files over. 21:02:07 INFO - Return code: 0 21:02:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:02:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:02:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f7480eac0b1f8befa5922442d50b625cd1c4acc07cb248c9942690991fc4a0c4ffee73c6bb7ae0a155cab9005aa90d45d10ebb8130995f08ea0abb0544f94d9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7652e90fdcf753452d4c69a76395195f49f3b5e2d965f78476b1912d5fd8d3e1f53869548096526dec34559f111dff9f866ff334595862656553d5332c24ecfb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b8b0aeb39dc2fbf19fe002ba8e64e4b3cc017d4e205de7a06ca1c77755822dd5ef7ec4a86dbd80d073003469f49fb8fad363994dc378c857541a91c67600377", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8ae9257ed3fcc54e7f973e3b20c8f732b9b5a82d198affd6ece8cafaa33b996c570a308a3a52c910241527ae9e9c1b9e8a6166e5ea0b1988e9f8a979347f619"} 21:02:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:02:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:02:07 INFO - Contents: 21:02:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f7480eac0b1f8befa5922442d50b625cd1c4acc07cb248c9942690991fc4a0c4ffee73c6bb7ae0a155cab9005aa90d45d10ebb8130995f08ea0abb0544f94d9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7652e90fdcf753452d4c69a76395195f49f3b5e2d965f78476b1912d5fd8d3e1f53869548096526dec34559f111dff9f866ff334595862656553d5332c24ecfb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b8b0aeb39dc2fbf19fe002ba8e64e4b3cc017d4e205de7a06ca1c77755822dd5ef7ec4a86dbd80d073003469f49fb8fad363994dc378c857541a91c67600377", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8ae9257ed3fcc54e7f973e3b20c8f732b9b5a82d198affd6ece8cafaa33b996c570a308a3a52c910241527ae9e9c1b9e8a6166e5ea0b1988e9f8a979347f619"} 21:02:07 INFO - Running post-run listener: copy_logs_to_upload_dir 21:02:07 INFO - Copying logs to upload dir... 21:02:07 INFO - mkdir: /builds/slave/test/build/upload/logs 21:02:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.914656 ========= master_lag: 0.40 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-23 21:02:07.198210) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 21:02:07.207135) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867 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/6f7480eac0b1f8befa5922442d50b625cd1c4acc07cb248c9942690991fc4a0c4ffee73c6bb7ae0a155cab9005aa90d45d10ebb8130995f08ea0abb0544f94d9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7652e90fdcf753452d4c69a76395195f49f3b5e2d965f78476b1912d5fd8d3e1f53869548096526dec34559f111dff9f866ff334595862656553d5332c24ecfb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b8b0aeb39dc2fbf19fe002ba8e64e4b3cc017d4e205de7a06ca1c77755822dd5ef7ec4a86dbd80d073003469f49fb8fad363994dc378c857541a91c67600377", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8ae9257ed3fcc54e7f973e3b20c8f732b9b5a82d198affd6ece8cafaa33b996c570a308a3a52c910241527ae9e9c1b9e8a6166e5ea0b1988e9f8a979347f619"} build_url:https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011491 build_url: 'https://queue.taskcluster.net/v1/task/Q4ywgNwcTHaso13_WBPeKA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f7480eac0b1f8befa5922442d50b625cd1c4acc07cb248c9942690991fc4a0c4ffee73c6bb7ae0a155cab9005aa90d45d10ebb8130995f08ea0abb0544f94d9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7652e90fdcf753452d4c69a76395195f49f3b5e2d965f78476b1912d5fd8d3e1f53869548096526dec34559f111dff9f866ff334595862656553d5332c24ecfb", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b8b0aeb39dc2fbf19fe002ba8e64e4b3cc017d4e205de7a06ca1c77755822dd5ef7ec4a86dbd80d073003469f49fb8fad363994dc378c857541a91c67600377", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8ae9257ed3fcc54e7f973e3b20c8f732b9b5a82d198affd6ece8cafaa33b996c570a308a3a52c910241527ae9e9c1b9e8a6166e5ea0b1988e9f8a979347f619"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 21:02:07.237785) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 21:02:07.238118) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1506224001.102927-798517867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004726 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 21:02:07.323991) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 21:02:07.324362) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 21:02:07.324650) ========= ========= Total master_lag: 0.59 =========