builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-080 starttime: 1506938245.07 results: success (0) revision: 0a2877a3738c35b1cb9c6073d6c5be76f9c07656 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.070441) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.071140) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.071417) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.124365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.124655) ========= 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=781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-02 02:57:25-- 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% 86.6M=0s 2017-10-02 02:57:25 (86.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106160 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.264651) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.264963) ========= 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=781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016305 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.321299) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.321714) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0a2877a3738c35b1cb9c6073d6c5be76f9c07656 --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 0a2877a3738c35b1cb9c6073d6c5be76f9c07656 --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=781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-02 02:57:25,372 truncating revision to first 12 chars 2017-10-02 02:57:25,372 Setting DEBUG logging. 2017-10-02 02:57:25,372 attempt 1/10 2017-10-02 02:57:25,373 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0a2877a3738c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-02 02:57:25,646 unpacking tar archive at: mozilla-central-0a2877a3738c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.610405 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.955072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.955424) ========= script_repo_revision: 0a2877a3738c35b1cb9c6073d6c5be76f9c07656 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.955837) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.956148) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-02 02:57:25.973353) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-02 02:57:25.973744) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:57:26 INFO - MultiFileLogger online at 20171002 02:57:26 in /builds/slave/test 02:57:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:57:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:57:26 INFO - {'append_to_log': False, 02:57:26 INFO - 'base_work_dir': '/builds/slave/test', 02:57:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:57:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:57:26 INFO - 'branch': 'Firefox-Non-PGO', 02:57:26 INFO - 'buildbot_json_path': 'buildprops.json', 02:57:26 INFO - 'code_coverage': False, 02:57:26 INFO - 'config_files': ('talos/linux_config.py',), 02:57:26 INFO - 'default_actions': ('clobber', 02:57:26 INFO - 'read-buildbot-config', 02:57:26 INFO - 'download-and-extract', 02:57:26 INFO - 'populate-webroot', 02:57:26 INFO - 'create-virtualenv', 02:57:26 INFO - 'install', 02:57:26 INFO - 'setup-mitmproxy', 02:57:26 INFO - 'run-tests'), 02:57:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:57:26 INFO - 'disable_ccov_upload': False, 02:57:26 INFO - 'disable_stylo': False, 02:57:26 INFO - 'download_minidump_stackwalk': True, 02:57:26 INFO - 'download_symbols': 'ondemand', 02:57:26 INFO - 'enable_stylo': False, 02:57:26 INFO - 'enable_webrender': False, 02:57:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:57:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:57:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:57:26 INFO - 'gecko_profile': False, 02:57:26 INFO - 'gecko_profile_interval': 0, 02:57:26 INFO - 'installer_path': 'installer.exe', 02:57:26 INFO - 'log_level': 'info', 02:57:26 INFO - 'log_name': 'talos', 02:57:26 INFO - 'log_to_console': True, 02:57:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:57:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:57:26 INFO - 'opt_config_files': (), 02:57:26 INFO - 'pip_index': False, 02:57:26 INFO - 'suite': 'dromaeojs-e10s', 02:57:26 INFO - 'system_bits': '32', 02:57:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:57:26 INFO - 'title': 'talos-linux64-ix-080', 02:57:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:57:26 INFO - 'use_talos_json': True, 02:57:26 INFO - 'verify': 'False', 02:57:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:57:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:57:26 INFO - 'work_dir': 'build'} 02:57:26 INFO - [mozharness: 2017-10-02 09:57:26.164402Z] Running clobber step. 02:57:26 INFO - Running pre-action listener: _resource_record_pre_action 02:57:26 INFO - Running main action method: clobber 02:57:26 INFO - rmtree: /builds/slave/test/build 02:57:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:57:27 INFO - Running post-action listener: _resource_record_post_action 02:57:27 INFO - [mozharness: 2017-10-02 09:57:27.173626Z] Finished clobber step (success) 02:57:27 INFO - [mozharness: 2017-10-02 09:57:27.173725Z] Running read-buildbot-config step. 02:57:27 INFO - Running pre-action listener: _resource_record_pre_action 02:57:27 INFO - Running main action method: read_buildbot_config 02:57:27 INFO - Using buildbot properties: 02:57:27 INFO - { 02:57:27 INFO - "project": "", 02:57:27 INFO - "product": "firefox", 02:57:27 INFO - "who": "archaeopteryx@coole-files.de", 02:57:27 INFO - "installer_path": "public/build/target.tar.bz2", 02:57:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:57:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:57:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 02:57:27 INFO - "stage_platform": "linux64", 02:57:27 INFO - "basedir": "/builds/slave/test", 02:57:27 INFO - "buildnumber": 889, 02:57:27 INFO - "platform": "ubuntu64_hw", 02:57:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:57:27 INFO - "slavebuilddir": "test", 02:57:27 INFO - "taskId": "cdyOJphiQj60VVVDW9yrrA", 02:57:27 INFO - "branch": "mozilla-central", 02:57:27 INFO - "script_repo_revision": "production", 02:57:27 INFO - "revision": "0a2877a3738c35b1cb9c6073d6c5be76f9c07656", 02:57:27 INFO - "slavename": "talos-linux64-ix-080", 02:57:27 INFO - "repo_path": "mozilla-central" 02:57:27 INFO - } 02:57:27 INFO - Finding installer, test and symbols from parent task. 02:57:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cdyOJphiQj60VVVDW9yrrA'}, attempt #1 02:57:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cdyOJphiQj60VVVDW9yrrA'}, attempt #1 02:57:28 INFO - Task dependencies: PYtAjz9HRZeMO2pXV7pH9g 02:57:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g'}, attempt #1 02:57:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.tar.bz2 02:57:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.test_packages.json 02:57:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.crashreporter-symbols.zip 02:57:28 INFO - Running post-action listener: _resource_record_post_action 02:57:28 INFO - [mozharness: 2017-10-02 09:57:28.521879Z] Finished read-buildbot-config step (success) 02:57:28 INFO - [mozharness: 2017-10-02 09:57:28.522097Z] Running download-and-extract step. 02:57:28 INFO - Running pre-action listener: _resource_record_pre_action 02:57:28 INFO - Running main action method: download_and_extract 02:57:28 INFO - mkdir: /builds/slave/test/build/tests 02:57:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:28 INFO - trying https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.test_packages.json 02:57:28 INFO - Downloading https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:57:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:57:29 INFO - Downloaded 989 bytes. 02:57:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:57:29 INFO - Using the following test package requirements: 02:57:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:57:29 INFO - u'common': [u'target.common.tests.zip'], 02:57:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:57:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:57:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:57:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:57:29 INFO - u'mozbase': [u'target.common.tests.zip'], 02:57:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:57:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:57:29 INFO - u'web-platform': [u'target.common.tests.zip', 02:57:29 INFO - u'target.web-platform.tests.tar.gz'], 02:57:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:57:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 02:57:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.common.tests.zip 02:57:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:57:29 INFO - Fetch https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.common.tests.zip into memory 02:57:30 INFO - Content-Length response header: 55366722 02:57:30 INFO - Bytes received: 55366722 02:57:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.talos.tests.zip 02:57:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:57:40 INFO - Fetch https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.talos.tests.zip into memory 02:57:41 INFO - Content-Length response header: 14522385 02:57:41 INFO - Bytes received: 14522385 02:57:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:41 INFO - trying https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.tar.bz2 02:57:41 INFO - Downloading https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:57:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:57:42 INFO - Downloaded 61505409 bytes. 02:57:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.tar.bz2 02:57:42 INFO - mkdir: /builds/slave/test/properties 02:57:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:57:42 INFO - Writing to file /builds/slave/test/properties/build_url 02:57:42 INFO - Contents: 02:57:42 INFO - build_url:https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.tar.bz2 02:57:42 INFO - Running post-action listener: _resource_record_post_action 02:57:42 INFO - Running post-action listener: find_tests_for_verification 02:57:42 INFO - Running post-action listener: set_extra_try_arguments 02:57:42 INFO - [mozharness: 2017-10-02 09:57:42.928530Z] Finished download-and-extract step (success) 02:57:42 INFO - [mozharness: 2017-10-02 09:57:42.928631Z] Running populate-webroot step. 02:57:42 INFO - Running pre-action listener: _resource_record_pre_action 02:57:42 INFO - Running main action method: populate_webroot 02:57:42 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:57:42 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['tresize', 02:57:42 INFO - 'tcanvasmark']}, 02:57:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:57:42 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['dromaeo_css', 02:57:42 INFO - 'kraken']}, 02:57:42 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:57:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:42 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:57:42 INFO - 'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:42 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:57:42 INFO - 'tests': ['damp', 'tps']}, 02:57:42 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:57:42 INFO - 'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['damp', 'tps']}, 02:57:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:57:42 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['dromaeo_dom']}, 02:57:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:57:42 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['basic_compositor_video', 02:57:42 INFO - 'glvideo']}, 02:57:42 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:57:42 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:57:42 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:57:42 INFO - 'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['ts_paint_webext', 02:57:42 INFO - 'tp5o_webext']}, 02:57:42 INFO - 'other-e10s': {'tests': ['a11yr', 02:57:42 INFO - 'ts_paint', 02:57:42 INFO - 'tpaint', 02:57:42 INFO - 'sessionrestore', 02:57:42 INFO - 'sessionrestore_many_windows', 02:57:42 INFO - 'sessionrestore_no_auto_restore', 02:57:42 INFO - 'tabpaint', 02:57:42 INFO - 'cpstartup']}, 02:57:42 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['a11yr', 02:57:42 INFO - 'ts_paint', 02:57:42 INFO - 'tpaint', 02:57:42 INFO - 'sessionrestore', 02:57:42 INFO - 'sessionrestore_many_windows', 02:57:42 INFO - 'sessionrestore_no_auto_restore', 02:57:42 INFO - 'tabpaint', 02:57:42 INFO - 'cpstartup']}, 02:57:42 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 02:57:42 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 02:57:42 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['perf_reftest_singletons']}, 02:57:42 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['perf_reftest']}, 02:57:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:57:42 INFO - 'tsvgr_opacity', 02:57:42 INFO - 'tart', 02:57:42 INFO - 'tscrollx', 02:57:42 INFO - 'tsvg_static']}, 02:57:42 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['tsvgx', 02:57:42 INFO - 'tsvgr_opacity', 02:57:42 INFO - 'tart', 02:57:42 INFO - 'tscrollx', 02:57:42 INFO - 'tsvg_static']}, 02:57:42 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:57:42 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:57:42 INFO - 'talos_options': ['--disable-stylo'], 02:57:42 INFO - 'tests': ['tp5o']}, 02:57:42 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:57:42 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 02:57:42 INFO - 'talos_options': ['--mitmproxy', 02:57:42 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:57:42 INFO - '--firstNonBlankPaint'], 02:57:42 INFO - 'tests': ['tp6_google', 02:57:42 INFO - 'tp6_youtube', 02:57:42 INFO - 'tp6_amazon', 02:57:42 INFO - 'tp6_facebook']}, 02:57:42 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:57:42 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 02:57:42 INFO - 'talos_options': ['--disable-stylo', 02:57:42 INFO - '--mitmproxy', 02:57:42 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:57:42 INFO - '--firstNonBlankPaint'], 02:57:42 INFO - 'tests': ['tp6_google', 02:57:42 INFO - 'tp6_youtube', 02:57:42 INFO - 'tp6_amazon', 02:57:42 INFO - 'tp6_facebook']}, 02:57:42 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:57:42 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 02:57:42 INFO - 'talos_options': ['--stylo-threads=1', 02:57:42 INFO - '--mitmproxy', 02:57:42 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:57:42 INFO - '--firstNonBlankPaint'], 02:57:42 INFO - 'tests': ['tp6_google', 02:57:42 INFO - 'tp6_youtube', 02:57:42 INFO - 'tp6_amazon', 02:57:42 INFO - 'tp6_facebook']}, 02:57:42 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:57:42 INFO - 'talos_options': ['--xperf_path', 02:57:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:42 INFO - 'tests': ['tp5n']}, 02:57:42 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:57:42 INFO - 'talos_options': ['--disable-stylo', 02:57:42 INFO - '--xperf_path', 02:57:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:42 INFO - 'tests': ['tp5n']}}} 02:57:42 INFO - Running post-action listener: _resource_record_post_action 02:57:42 INFO - [mozharness: 2017-10-02 09:57:42.946214Z] Finished populate-webroot step (success) 02:57:42 INFO - [mozharness: 2017-10-02 09:57:42.946324Z] Running create-virtualenv step. 02:57:42 INFO - Running pre-action listener: _resource_record_pre_action 02:57:42 INFO - Running main action method: create_virtualenv 02:57:42 INFO - Creating virtualenv /builds/slave/test/build/venv 02:57:42 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:57:42 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:57:42 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:57:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:57:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:57:43 INFO - Installing distribute.............................................................................................................................................................................................done. 02:57:44 INFO - Installing pip...............done. 02:57:44 INFO - Return code: 0 02:57:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:57:44 INFO - Reading from file tmpfile_stdout 02:57:44 INFO - Output received: 02:57:44 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:57:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:57:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:57:44 INFO - Using env: {'DISPLAY': ':0', 02:57:44 INFO - 'HOME': '/home/cltbld', 02:57:44 INFO - 'LANG': 'en_US.UTF-8', 02:57:44 INFO - 'LANGUAGE': 'en_US:en', 02:57:44 INFO - 'LOGNAME': 'cltbld', 02:57:44 INFO - 'MAIL': '/var/mail/cltbld', 02:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:44 INFO - 'MOZ_NO_REMOTE': '1', 02:57:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:44 INFO - 'NO_EM_RESTART': '1', 02:57:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:44 INFO - 'PWD': '/builds/slave/test', 02:57:44 INFO - 'SHELL': '/bin/bash', 02:57:44 INFO - 'SHLVL': '1', 02:57:44 INFO - 'TERM': 'linux', 02:57:44 INFO - 'TMOUT': '86400', 02:57:44 INFO - 'USER': 'cltbld', 02:57:44 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:44 INFO - '_': '/tools/buildbot/bin/python'} 02:57:45 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:57:45 INFO - Downloading/unpacking pip>=1.5 02:57:45 INFO - Running setup.py egg_info for package pip 02:57:45 INFO - warning: no files found matching 'pip/cacert.pem' 02:57:45 INFO - warning: no files found matching '*.html' under directory 'docs' 02:57:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:57:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:57:45 INFO - Installing collected packages: pip 02:57:45 INFO - Found existing installation: pip 1.1 02:57:45 INFO - Uninstalling pip: 02:57:45 INFO - Successfully uninstalled pip 02:57:45 INFO - Running setup.py install for pip 02:57:45 INFO - warning: no files found matching 'pip/cacert.pem' 02:57:45 INFO - warning: no files found matching '*.html' under directory 'docs' 02:57:45 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:57:45 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:57:45 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:57:45 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:57:45 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:57:45 INFO - Successfully installed pip 02:57:45 INFO - Cleaning up... 02:57:45 INFO - Return code: 0 02:57:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:45 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 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:57:45 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 02:57:45 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 02:57:45 INFO - Using env: {'DISPLAY': ':0', 02:57:45 INFO - 'HOME': '/home/cltbld', 02:57:45 INFO - 'LANG': 'en_US.UTF-8', 02:57:45 INFO - 'LANGUAGE': 'en_US:en', 02:57:45 INFO - 'LOGNAME': 'cltbld', 02:57:45 INFO - 'MAIL': '/var/mail/cltbld', 02:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:45 INFO - 'MOZ_NO_REMOTE': '1', 02:57:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:45 INFO - 'NO_EM_RESTART': '1', 02:57:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:45 INFO - 'PWD': '/builds/slave/test', 02:57:45 INFO - 'SHELL': '/bin/bash', 02:57:45 INFO - 'SHLVL': '1', 02:57:45 INFO - 'TERM': 'linux', 02:57:45 INFO - 'TMOUT': '86400', 02:57:45 INFO - 'USER': 'cltbld', 02:57:45 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:45 INFO - '_': '/tools/buildbot/bin/python'} 02:57:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:45 INFO - Downloading/unpacking psutil>=3.1.1 02:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:57:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:47 INFO - Installing collected packages: psutil 02:57:47 INFO - Running setup.py install for psutil 02:57:47 INFO - building 'psutil._psutil_linux' extension 02:57:47 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 02:57:47 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 02:57:47 INFO - building 'psutil._psutil_posix' extension 02:57:47 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 02:57:47 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 02:57:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:47 INFO - Successfully installed psutil 02:57:47 INFO - Cleaning up... 02:57:47 INFO - Return code: 0 02:57:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:57:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:57:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:57:47 INFO - Using env: {'DISPLAY': ':0', 02:57:47 INFO - 'HOME': '/home/cltbld', 02:57:47 INFO - 'LANG': 'en_US.UTF-8', 02:57:47 INFO - 'LANGUAGE': 'en_US:en', 02:57:47 INFO - 'LOGNAME': 'cltbld', 02:57:47 INFO - 'MAIL': '/var/mail/cltbld', 02:57:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:47 INFO - 'MOZ_NO_REMOTE': '1', 02:57:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:47 INFO - 'NO_EM_RESTART': '1', 02:57:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:47 INFO - 'PWD': '/builds/slave/test', 02:57:47 INFO - 'SHELL': '/bin/bash', 02:57:47 INFO - 'SHLVL': '1', 02:57:47 INFO - 'TERM': 'linux', 02:57:47 INFO - 'TMOUT': '86400', 02:57:47 INFO - 'USER': 'cltbld', 02:57:47 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:57:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:47 INFO - '_': '/tools/buildbot/bin/python'} 02:57:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:57:47 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 02:57:47 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 02:57:47 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 02:57:47 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 02:57:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:57:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:57:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:57:48 INFO - Installing collected packages: mozsystemmonitor 02:57:48 INFO - Running setup.py install for mozsystemmonitor 02:57:49 INFO - Successfully installed mozsystemmonitor 02:57:49 INFO - Cleaning up... 02:57:49 INFO - Return code: 0 02:57:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57: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', '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 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:57: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:57: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 jsonschema==2.5.1 02:57:49 INFO - Using env: {'DISPLAY': ':0', 02:57:49 INFO - 'HOME': '/home/cltbld', 02:57:49 INFO - 'LANG': 'en_US.UTF-8', 02:57:49 INFO - 'LANGUAGE': 'en_US:en', 02:57:49 INFO - 'LOGNAME': 'cltbld', 02:57:49 INFO - 'MAIL': '/var/mail/cltbld', 02:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:49 INFO - 'MOZ_NO_REMOTE': '1', 02:57:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:49 INFO - 'NO_EM_RESTART': '1', 02:57:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:49 INFO - 'PWD': '/builds/slave/test', 02:57:49 INFO - 'SHELL': '/bin/bash', 02:57:49 INFO - 'SHLVL': '1', 02:57:49 INFO - 'TERM': 'linux', 02:57:49 INFO - 'TMOUT': '86400', 02:57:49 INFO - 'USER': 'cltbld', 02:57:49 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:49 INFO - '_': '/tools/buildbot/bin/python'} 02:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:49 INFO - Downloading/unpacking jsonschema==2.5.1 02:57:49 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 02:57:49 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 02:57:49 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 02:57:49 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 02:57:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:57:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:57: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 02:57: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 02:57: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 02:57: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 02:57:51 INFO - Downloading functools32-3.2.3-2.tar.gz 02:57:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:57:51 INFO - warning: no files found matching '*.txt' 02:57:51 INFO - no previously-included directories found matching 'build' 02:57:51 INFO - no previously-included directories found matching 'dist' 02:57:51 INFO - no previously-included directories found matching '.git*' 02:57:51 INFO - Installing collected packages: jsonschema, functools32 02:57:52 INFO - Running setup.py install for functools32 02:57:52 INFO - warning: no files found matching '*.txt' 02:57:52 INFO - no previously-included directories found matching 'build' 02:57:52 INFO - no previously-included directories found matching 'dist' 02:57:52 INFO - no previously-included directories found matching '.git*' 02:57:52 INFO - Successfully installed jsonschema functools32 02:57:52 INFO - Cleaning up... 02:57:52 INFO - Return code: 0 02:57:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:52 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 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:57:52 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 02:57:52 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 02:57:52 INFO - Using env: {'DISPLAY': ':0', 02:57:52 INFO - 'HOME': '/home/cltbld', 02:57:52 INFO - 'LANG': 'en_US.UTF-8', 02:57:52 INFO - 'LANGUAGE': 'en_US:en', 02:57:52 INFO - 'LOGNAME': 'cltbld', 02:57:52 INFO - 'MAIL': '/var/mail/cltbld', 02:57:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:52 INFO - 'MOZ_NO_REMOTE': '1', 02:57:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:52 INFO - 'NO_EM_RESTART': '1', 02:57:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:52 INFO - 'PWD': '/builds/slave/test', 02:57:52 INFO - 'SHELL': '/bin/bash', 02:57:52 INFO - 'SHLVL': '1', 02:57:52 INFO - 'TERM': 'linux', 02:57:52 INFO - 'TMOUT': '86400', 02:57:52 INFO - 'USER': 'cltbld', 02:57:52 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:57:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:52 INFO - '_': '/tools/buildbot/bin/python'} 02:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:57:53 INFO - Cleaning up... 02:57:53 INFO - Return code: 0 02:57:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57: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', '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 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:57: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:57: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 blobuploader==1.2.4 02:57:53 INFO - Using env: {'DISPLAY': ':0', 02:57:53 INFO - 'HOME': '/home/cltbld', 02:57:53 INFO - 'LANG': 'en_US.UTF-8', 02:57:53 INFO - 'LANGUAGE': 'en_US:en', 02:57:53 INFO - 'LOGNAME': 'cltbld', 02:57:53 INFO - 'MAIL': '/var/mail/cltbld', 02:57:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:53 INFO - 'MOZ_NO_REMOTE': '1', 02:57:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:53 INFO - 'NO_EM_RESTART': '1', 02:57:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:53 INFO - 'PWD': '/builds/slave/test', 02:57:53 INFO - 'SHELL': '/bin/bash', 02:57:53 INFO - 'SHLVL': '1', 02:57:53 INFO - 'TERM': 'linux', 02:57:53 INFO - 'TMOUT': '86400', 02:57:53 INFO - 'USER': 'cltbld', 02:57:53 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:57:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:53 INFO - '_': '/tools/buildbot/bin/python'} 02:57:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:53 INFO - Downloading/unpacking blobuploader==1.2.4 02:57: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 02:57: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 02:57: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 02:57: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 02:57:54 INFO - Downloading blobuploader-1.2.4.tar.gz 02:57:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:57:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:57:54 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 02:57:54 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 02:57:54 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 02:57:54 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 02:57:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:57:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:57: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 02:57: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 02:57: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 02:57: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 02:57:55 INFO - Downloading docopt-0.6.1.tar.gz 02:57:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:57:55 INFO - Installing collected packages: blobuploader, requests, docopt 02:57:55 INFO - Running setup.py install for blobuploader 02:57:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:57:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:57:55 INFO - Running setup.py install for requests 02:57:55 INFO - Running setup.py install for docopt 02:57:55 INFO - Successfully installed blobuploader requests docopt 02:57:55 INFO - Cleaning up... 02:57:55 INFO - Return code: 0 02:57:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:55 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 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:57:55 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 02:57:55 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 02:57:55 INFO - Using env: {'DISPLAY': ':0', 02:57:55 INFO - 'HOME': '/home/cltbld', 02:57:55 INFO - 'LANG': 'en_US.UTF-8', 02:57:55 INFO - 'LANGUAGE': 'en_US:en', 02:57:55 INFO - 'LOGNAME': 'cltbld', 02:57:55 INFO - 'MAIL': '/var/mail/cltbld', 02:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:55 INFO - 'MOZ_NO_REMOTE': '1', 02:57:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:55 INFO - 'NO_EM_RESTART': '1', 02:57:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:55 INFO - 'PWD': '/builds/slave/test', 02:57:55 INFO - 'SHELL': '/bin/bash', 02:57:55 INFO - 'SHLVL': '1', 02:57:55 INFO - 'TERM': 'linux', 02:57:55 INFO - 'TMOUT': '86400', 02:57:55 INFO - 'USER': 'cltbld', 02:57:55 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:55 INFO - '_': '/tools/buildbot/bin/python'} 02:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:56 INFO - Running setup.py (path:/tmp/pip-XSpFam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:56 INFO - Running setup.py (path:/tmp/pip-1ePloY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:56 INFO - Running setup.py (path:/tmp/pip-XkmWNV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:56 INFO - Running setup.py (path:/tmp/pip-UWHZJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:56 INFO - Running setup.py (path:/tmp/pip-h7tFJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:56 INFO - Running setup.py (path:/tmp/pip-uc1MxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:56 INFO - Running setup.py (path:/tmp/pip-RT06PO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:56 INFO - Running setup.py (path:/tmp/pip-PIKAWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:56 INFO - Running setup.py (path:/tmp/pip-i27O5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:56 INFO - Running setup.py (path:/tmp/pip-edKYGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:56 INFO - Running setup.py (path:/tmp/pip-mFf6qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:56 INFO - Running setup.py (path:/tmp/pip-z_ye3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:56 INFO - Running setup.py (path:/tmp/pip-sFVXxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:56 INFO - Running setup.py (path:/tmp/pip-vw35HV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:57 INFO - Running setup.py (path:/tmp/pip-j3X04L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:57 INFO - Running setup.py (path:/tmp/pip-mNcBpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:57 INFO - Running setup.py (path:/tmp/pip-6BNS1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:57:57 INFO - Running setup.py install for manifestparser 02:57:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:57:57 INFO - Running setup.py install for mozcrash 02:57:57 INFO - Running setup.py install for mozdebug 02:57:57 INFO - Running setup.py install for mozdevice 02:57:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:57:57 INFO - Running setup.py install for mozfile 02:57:57 INFO - Running setup.py install for mozhttpd 02:57:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:57:57 INFO - Running setup.py install for mozinfo 02:57:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:57:57 INFO - Running setup.py install for mozInstall 02:57:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:57:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:57:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:57:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:57:58 INFO - Running setup.py install for mozleak 02:57:58 INFO - Running setup.py install for mozlog 02:57:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:57:58 INFO - Running setup.py install for moznetwork 02:57:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:57:58 INFO - Running setup.py install for mozprocess 02:57:58 INFO - Running setup.py install for mozprofile 02:57:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:57:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:57:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:57:58 INFO - Running setup.py install for mozrunner 02:57:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:57:58 INFO - Running setup.py install for mozscreenshot 02:57:58 INFO - Running setup.py install for moztest 02:57:58 INFO - Running setup.py install for mozversion 02:57:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:57:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:57:58 INFO - Cleaning up... 02:57:58 INFO - Return code: 0 02:57:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57: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')]} 02:57:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:59 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 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:57:59 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 02:57:59 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 02:57:59 INFO - Using env: {'DISPLAY': ':0', 02:57:59 INFO - 'HOME': '/home/cltbld', 02:57:59 INFO - 'LANG': 'en_US.UTF-8', 02:57:59 INFO - 'LANGUAGE': 'en_US:en', 02:57:59 INFO - 'LOGNAME': 'cltbld', 02:57:59 INFO - 'MAIL': '/var/mail/cltbld', 02:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:59 INFO - 'MOZ_NO_REMOTE': '1', 02:57:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:57:59 INFO - 'NO_EM_RESTART': '1', 02:57:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:59 INFO - 'PWD': '/builds/slave/test', 02:57:59 INFO - 'SHELL': '/bin/bash', 02:57:59 INFO - 'SHLVL': '1', 02:57:59 INFO - 'TERM': 'linux', 02:57:59 INFO - 'TMOUT': '86400', 02:57:59 INFO - 'USER': 'cltbld', 02:57:59 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:59 INFO - '_': '/tools/buildbot/bin/python'} 02:57:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:59 INFO - Running setup.py (path:/tmp/pip-9pxkp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:59 INFO - Running setup.py (path:/tmp/pip-wY2hMv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:59 INFO - Running setup.py (path:/tmp/pip-PuTAp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:59 INFO - Running setup.py (path:/tmp/pip-Bf_HMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:59 INFO - Running setup.py (path:/tmp/pip-wE2LsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:59 INFO - Running setup.py (path:/tmp/pip-2hIo_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:59 INFO - Running setup.py (path:/tmp/pip-YHV6Yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:59 INFO - Running setup.py (path:/tmp/pip-rbn2A7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:59 INFO - Running setup.py (path:/tmp/pip-wDMAr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:59 INFO - Running setup.py (path:/tmp/pip-NbFjX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:59 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)) 02:57:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:59 INFO - Running setup.py (path:/tmp/pip-HjxyJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:58:00 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)) 02:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:58:00 INFO - Running setup.py (path:/tmp/pip-4xm7Td-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:58:00 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)) 02:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:58:00 INFO - Running setup.py (path:/tmp/pip-OulqtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:58:00 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)) 02:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:58:00 INFO - Running setup.py (path:/tmp/pip-UjHRyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:58:00 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)) 02:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:00 INFO - Running setup.py (path:/tmp/pip-mt1fke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:58:00 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)) 02:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:58:00 INFO - Running setup.py (path:/tmp/pip-RgDHfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:58:00 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)) 02:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:58:00 INFO - Running setup.py (path:/tmp/pip-loAK16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:58:00 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)) 02:58:00 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)) 02:58:00 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)) 02:58:00 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)) 02:58:00 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)) 02:58:00 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)) 02:58:00 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)) 02:58:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:58: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 02:58: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 02:58: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 02:58: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 02:58:01 INFO - Downloading blessings-1.6.tar.gz 02:58:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:58:01 INFO - Installing collected packages: blessings 02:58:01 INFO - Running setup.py install for blessings 02:58:01 INFO - Successfully installed blessings 02:58:01 INFO - Cleaning up... 02:58:01 INFO - Return code: 0 02:58:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:58:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:58:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:58:01 INFO - Reading from file tmpfile_stdout 02:58:01 INFO - Current package versions: 02:58:01 INFO - argparse == 1.2.1 02:58:01 INFO - blessings == 1.6 02:58:01 INFO - blobuploader == 1.2.4 02:58:01 INFO - docopt == 0.6.1 02:58:01 INFO - functools32 == 3.2.3-2 02:58:01 INFO - jsonschema == 2.5.1 02:58:01 INFO - manifestparser == 1.1 02:58:01 INFO - mozInstall == 1.13 02:58:01 INFO - mozcrash == 1.0 02:58:01 INFO - mozdebug == 0.1 02:58:01 INFO - mozdevice == 0.51 02:58:01 INFO - mozfile == 1.2 02:58:01 INFO - mozhttpd == 0.7 02:58:01 INFO - mozinfo == 0.10 02:58:01 INFO - mozleak == 0.1 02:58:01 INFO - mozlog == 3.5 02:58:01 INFO - moznetwork == 0.27 02:58:01 INFO - mozprocess == 0.25 02:58:01 INFO - mozprofile == 0.28 02:58:01 INFO - mozrunner == 6.13 02:58:01 INFO - mozscreenshot == 0.1 02:58:01 INFO - mozsystemmonitor == 0.3 02:58:01 INFO - moztest == 0.8 02:58:01 INFO - mozversion == 1.4 02:58:01 INFO - psutil == 3.1.1 02:58:01 INFO - requests == 1.2.3 02:58:01 INFO - wsgiref == 0.1.2 02:58:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:58:01 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')]} 02:58:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58:01 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')]} 02:58:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:01 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 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:58:01 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 02:58:01 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 02:58:01 INFO - Using env: {'DISPLAY': ':0', 02:58:01 INFO - 'HOME': '/home/cltbld', 02:58:01 INFO - 'LANG': 'en_US.UTF-8', 02:58:01 INFO - 'LANGUAGE': 'en_US:en', 02:58:01 INFO - 'LOGNAME': 'cltbld', 02:58:01 INFO - 'MAIL': '/var/mail/cltbld', 02:58:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:01 INFO - 'MOZ_NO_REMOTE': '1', 02:58:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:02 INFO - 'NO_EM_RESTART': '1', 02:58:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:02 INFO - 'PWD': '/builds/slave/test', 02:58:02 INFO - 'SHELL': '/bin/bash', 02:58:02 INFO - 'SHLVL': '1', 02:58:02 INFO - 'TERM': 'linux', 02:58:02 INFO - 'TMOUT': '86400', 02:58:02 INFO - 'USER': 'cltbld', 02:58:02 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:58:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:02 INFO - '_': '/tools/buildbot/bin/python'} 02:58:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:02 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)) 02:58:02 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)) 02:58:02 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)) 02:58:02 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)) 02:58:02 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)) 02:58:02 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)) 02:58:02 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)) 02:58:02 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)) 02:58:02 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)) 02:58:02 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:58:02 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 02:58:02 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 02:58:02 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 02:58:02 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 02:58:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:58:03 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)) 02:58:03 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)) 02:58:03 INFO - Installing collected packages: simplejson 02:58:03 INFO - Running setup.py install for simplejson 02:58:03 INFO - building 'simplejson._speedups' extension 02:58:03 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 02:58:04 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 02:58:04 INFO - Successfully installed simplejson 02:58:04 INFO - Cleaning up... 02:58:04 INFO - Return code: 0 02:58:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:58: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')]} 02:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:58: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')]} 02:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:58:04 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 0xfdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11a5b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x134a9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, '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': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', '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 02:58:04 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 02:58:04 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 02:58:04 INFO - Using env: {'DISPLAY': ':0', 02:58:04 INFO - 'HOME': '/home/cltbld', 02:58:04 INFO - 'LANG': 'en_US.UTF-8', 02:58:04 INFO - 'LANGUAGE': 'en_US:en', 02:58:04 INFO - 'LOGNAME': 'cltbld', 02:58:04 INFO - 'MAIL': '/var/mail/cltbld', 02:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:04 INFO - 'MOZ_NO_REMOTE': '1', 02:58:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:04 INFO - 'NO_EM_RESTART': '1', 02:58:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:04 INFO - 'PWD': '/builds/slave/test', 02:58:04 INFO - 'SHELL': '/bin/bash', 02:58:04 INFO - 'SHLVL': '1', 02:58:04 INFO - 'TERM': 'linux', 02:58:04 INFO - 'TMOUT': '86400', 02:58:04 INFO - 'USER': 'cltbld', 02:58:04 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:04 INFO - '_': '/tools/buildbot/bin/python'} 02:58:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:58:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:58:04 INFO - Cleaning up... 02:58:04 INFO - Return code: 0 02:58:04 INFO - Running post-action listener: _resource_record_post_action 02:58:04 INFO - Running post-action listener: _start_resource_monitoring 02:58:04 INFO - Starting resource monitoring. 02:58:04 INFO - [mozharness: 2017-10-02 09:58:04.702141Z] Finished create-virtualenv step (success) 02:58:04 INFO - [mozharness: 2017-10-02 09:58:04.702611Z] Running install step. 02:58:04 INFO - Running pre-action listener: _resource_record_pre_action 02:58:04 INFO - Running main action method: install 02:58:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:58:04 INFO - Reading from file tmpfile_stdout 02:58:04 INFO - Detecting whether we're running mozinstall >=1.0... 02:58:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:58:05 INFO - Reading from file tmpfile_stdout 02:58:05 INFO - Output received: 02:58:05 INFO - Usage: mozinstall [options] installer 02:58:05 INFO - Options: 02:58:05 INFO - -h, --help show this help message and exit 02:58:05 INFO - -d DEST, --destination=DEST 02:58:05 INFO - Directory to install application into. [default: 02:58:05 INFO - "/builds/slave/test"] 02:58:05 INFO - --app=APP Application being installed. [default: firefox] 02:58:05 INFO - mkdir: /builds/slave/test/build/application 02:58:05 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'] 02:58:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:58:22 INFO - Reading from file tmpfile_stdout 02:58:22 INFO - Output received: 02:58:22 INFO - /builds/slave/test/build/application/firefox/firefox 02:58:22 INFO - Running post-action listener: _resource_record_post_action 02:58:22 INFO - [mozharness: 2017-10-02 09:58:22.841508Z] Finished install step (success) 02:58:22 INFO - [mozharness: 2017-10-02 09:58:22.841763Z] Running setup-mitmproxy step. 02:58:22 INFO - Running pre-action listener: _resource_record_pre_action 02:58:22 INFO - Running main action method: setup_mitmproxy 02:58:22 INFO - Skipping: mitmproxy is not required 02:58:22 INFO - Running post-action listener: _resource_record_post_action 02:58:22 INFO - [mozharness: 2017-10-02 09:58:22.842897Z] Finished setup-mitmproxy step (success) 02:58:22 INFO - [mozharness: 2017-10-02 09:58:22.843133Z] Running run-tests step. 02:58:22 INFO - Running pre-action listener: _resource_record_pre_action 02:58:22 INFO - Running pre-action listener: _set_gcov_prefix 02:58:22 INFO - Running main action method: run_tests 02:58:22 WARNING - Try message not found. 02:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:58:22 INFO - Python 2.7.3 02:58:22 INFO - Return code: 0 02:58:22 INFO - grabbing minidump binary from tooltool 02:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:22 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 0x1347d60>, '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 0x1348a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1348ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:58:22 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 02:58:22 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 02:58:22 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 02:58:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:58:22 INFO - Return code: 0 02:58:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:58:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:58:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:58:22 INFO - ENV: RUST_BACKTRACE is now full 02:58:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:58:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:58:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--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 02:58:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-080 --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 02:58:22 INFO - Using env: {'DISPLAY': ':0', 02:58:22 INFO - 'HOME': '/home/cltbld', 02:58:22 INFO - 'LANG': 'en_US.UTF-8', 02:58:22 INFO - 'LANGUAGE': 'en_US:en', 02:58:22 INFO - 'LOGNAME': 'cltbld', 02:58:22 INFO - 'MAIL': '/var/mail/cltbld', 02:58:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:58:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:22 INFO - 'MOZ_NO_REMOTE': '1', 02:58:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:22 INFO - 'NO_EM_RESTART': '1', 02:58:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:22 INFO - 'PWD': '/builds/slave/test', 02:58:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:22 INFO - 'RUST_BACKTRACE': 'full', 02:58:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:58:22 INFO - 'SHELL': '/bin/bash', 02:58:22 INFO - 'SHLVL': '1', 02:58:22 INFO - 'TERM': 'linux', 02:58:22 INFO - 'TMOUT': '86400', 02:58:22 INFO - 'USER': 'cltbld', 02:58:22 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:58:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:22 INFO - '_': '/tools/buildbot/bin/python'} 02:58:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-080', '--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 02:58:23 INFO - mozversion application_buildid: 20171002090256 02:58:23 INFO - mozversion application_changeset: 0a2877a3738c35b1cb9c6073d6c5be76f9c07656 02:58:23 INFO - mozversion application_display_name: FirefoxNightly 02:58:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:58:23 INFO - mozversion application_name: Firefox 02:58:23 INFO - mozversion application_remotingname: firefox 02:58:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:58:23 INFO - mozversion application_vendor: Mozilla 02:58:23 INFO - mozversion application_version: 58.0a1 02:58:23 INFO - mozversion platform_buildid: 20171002090256 02:58:23 INFO - mozversion platform_changeset: 0a2877a3738c35b1cb9c6073d6c5be76f9c07656 02:58:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:58:23 INFO - mozversion platform_version: 58.0a1 02:58:23 INFO - using testdate: 1506938303 02:58:23 INFO - actual date: 1506938303 02:58:23 INFO - starting webserver on 'localhost:49107' 02:58:23 INFO - SUITE-START | Running 2 tests 02:58:23 INFO - TEST-START | dromaeo_css 02:58:23 INFO - Initialising browser for dromaeo_css test... 02:58:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49107/getInfo.html -profile /tmp/tmpqSk2l0/profile 02:58:23 INFO - TEST-INFO | started process 26868 (/builds/slave/test/build/application/firefox/firefox http://localhost:49107/getInfo.html) 02:58:29 INFO - TEST-INFO | 26868: exit 0 02:58:29 INFO - Browser initialized. 02:58:29 INFO - Running cycle 1/1 for dromaeo_css test... 02:58:29 INFO - Using env: {'DISPLAY': ':0', 02:58:29 INFO - 'HOME': '/home/cltbld', 02:58:29 INFO - 'JSGC_DISABLE_POISONING': '1', 02:58:29 INFO - 'LANG': 'en_US.UTF-8', 02:58:29 INFO - 'LANGUAGE': 'en_US:en', 02:58:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:58:29 INFO - 'LOGNAME': 'cltbld', 02:58:29 INFO - 'MAIL': '/var/mail/cltbld', 02:58:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:58:29 INFO - 'MOZ_CRASHREPORTER': '1', 02:58:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:58:29 INFO - 'MOZ_NO_REMOTE': '1', 02:58:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:29 INFO - 'NO_EM_RESTART': '1', 02:58:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:29 INFO - 'PWD': '/builds/slave/test', 02:58:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:29 INFO - 'RUST_BACKTRACE': 'full', 02:58:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:58:29 INFO - 'SHELL': '/bin/bash', 02:58:29 INFO - 'SHLVL': '1', 02:58:29 INFO - 'TERM': 'linux', 02:58:29 INFO - 'TMOUT': '86400', 02:58:29 INFO - 'USER': 'cltbld', 02:58:29 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 02:58:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:29 INFO - '_': '/tools/buildbot/bin/python'} 02:58:29 INFO - TEST-INFO | started process 27105 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqSk2l0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:58:30 INFO - PID 27105 | 02:58:30 INFO - PID 27105 | (/builds/slave/test/build/application/firefox/firefox:27154): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:58:30 INFO - PID 27105 | 02:58:40 INFO - PID 27105 | RSS: Main: 152363008 02:58:40 INFO - PID 27105 | 03:01:38 INFO - PID 27105 | Cycle 1(1): loaded http://localhost:49107/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49107/tests/dromaeo/cssquery-ext.html) 03:01:39 INFO - PID 27105 | RSS: Main: 149966848 03:01:39 INFO - PID 27105 | 03:04:41 INFO - PID 27105 | Cycle 1(1): loaded http://localhost:49107/tests/dromaeo/cssquery-ext.html (next: http://localhost:49107/tests/dromaeo/cssquery-jquery.html) 03:04:42 INFO - PID 27105 | RSS: Main: 150380544 03:04:42 INFO - PID 27105 | 03:07:43 INFO - PID 27105 | Cycle 1(1): loaded http://localhost:49107/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49107/tests/dromaeo/cssquery-mootools.html) 03:07:44 INFO - PID 27105 | RSS: Main: 151760896 03:07:44 INFO - PID 27105 | 03:10:46 INFO - PID 27105 | Cycle 1(1): loaded http://localhost:49107/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49107/tests/dromaeo/cssquery-prototype.html) 03:10:46 INFO - PID 27105 | RSS: Main: 152145920 03:10:46 INFO - PID 27105 | 03:13:49 INFO - PID 27105 | Cycle 1(1): loaded http://localhost:49107/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49107/tests/dromaeo/cssquery-yui.html) 03:13:50 INFO - PID 27105 | RSS: Main: 154587136 03:13:50 INFO - PID 27105 | 03:16:52 INFO - PID 27105 | Cycle 1(1): loaded http://localhost:49107/tests/dromaeo/cssquery-yui.html (next: http://localhost:49107/tests/dromaeo/cssquery-dojo.html) 03:16:52 INFO - PID 27105 | RSS: Main: 156012544 03:16:52 INFO - PID 27105 | 03:16:52 INFO - PID 27105 | __start_tp_report 03:16:52 INFO - PID 27105 | _x_x_mozilla_page_load 03:16:52 INFO - PID 27105 | _x_x_mozilla_page_load_details 03:16:52 INFO - PID 27105 | |i|pagename|runs| 03:16:52 INFO - PID 27105 | |0;dojo.html;3168.603924509436;3239.2549713565863;3239.4492936200845;3245.902622921312;3249.23642943908;6246.531510136735;6360.840978975528;6371.139896114023;6452.354764523545;6511.576747511414;6313.337099604544;6334.841628959279;6354.237491501015;6355.618662880231;6366.554341196118;5841.386654401269;5863.736131874076;5868.794592189285;5885;5895.263092113486;5861.85345366365;5880.76476940924;5903.4686878180955;5925.081612350093;5977.222961015062;5939.821805345847;5958.612690175125;5960.046392577188;5961.582689211757;5975.163477113208;5884.735186916599;5930.0511918093325;5950.732217050243;5990.311114221833;6003.699815009276;5710.229119068914;5785.913211301834;5816.360200377955;5888.793892213795;5899.174115623816;6387.744490220385;6436.420722135031;6447.742090316382;6448.806535803933;6458.225012998423;6870.381665650116;6885;6939.097917270773;6959.860802783916;6968.407685346701;6867.141607299088;6900.447964162855;6977.092977912889;6983.022376867242;6985.755498557577;6334.746610135589;6423.903641445382;6426.742930282783;6426.807195784134;6434.292227854932;7041.331073548107;7050.471214658921;7082.043924070287;7171.1394632644415;7175.425965922818;6533.738650454024;6685.565438246498;6689.063531105649;6691.7323307068145;6725.730970761213;6763.628000460022;6828.487863410165;6924.480663950224;6940.479464040217;6961.825954351182;6830.975353696886;6848.178218613699;6928.861422771516;6942.7222911084;6949.478789090838;6861.965690171517;6878.037074809531;6887.552309099892;6956;6978.0230767692565;5234.136367499396;5329.786808527693;5332.013577488177;5334.493223143795;5454.699991500426;4691.3432119503295;4722;4753.382060332135;4763.76181190939;4802.543758342952;5851.180834683148;5884.499817515473;5919.496842768327;5945.435183657545;5982.042873140364;6903.6203008835;6911.861762764817;6950.339717726808;7006.789796306119;7037.296270372922;5716.399778023248;5756.107803290497;5814.912776308359;5908.852278692981;5917.615355001911;5917.082852157922;5958.910616340759;5959.165716799652;5963.821085367446;5999.340072592011;6622.9006564901565;6630.701618427182;6645.3022432643875;6747.257801641816;6749.6962636681465;6907.13660792401;6910.654467276717;6982.615956122462;6997.895031574429;7003.054587630605;6447.742090316522;6554.868902621825;6621.867562648817;6691.1636045494315;6715.4291885190305;6847.21257055445;6875.209350924708;6884.173899132136;6894.034835123187;6902.033715279765;6850.828729281808;6971.5468494547695;7028.718851245892;7054.153501579844;7058.188308344606;6491.740330386731;6617.602943823386;6693.799186024427;6713.832154196184;6809.693563789542;6590.275069742421;6617.239017513048;6618.437432818265;6641.535092543476;6644.202695676549;6586.901196481961;6646.03632473279;6650.534462587766;6660.167479065117;6711.295313991961;6720.32796720324;6728.428083612752;6828.863422731617;6831.760888368884;6852.417544508573;6507.967460162668;6517.73929042833;6581.703823327866;6616.073749674953;6633.270340262664 03:16:52 INFO - PID 27105 | |1;ext.html;853.504967119071;877.2981614708228;877.675260153743;878.7495563764328;880.8898887639045;41567.37648935268;42204.36693449601;42344.36483452751;42429;42429;38778.61221387785;38848;38897.805510972474;39029;39107.60892391075;7230.349268565828;7364.226756190603;7377.741779037735;7379.59412232327;7385.593792341419;7233.529820561673;7306.744263950763;7311.013013243197;7334.119905611307;7343.853122937538;7298.708051677927;7807.219278072176;8146.837063258731;8164.306033987118;8171.142030086845;8040.678372865078;8054.23484768946;8054.355651547891;8081.95959020207;8085.474444161111;7577.3180413762475;7849.960750196213;7933.682652693943;7950;7995.240452157036;38830.223395532215;39366.60633393687;39396;39397;39492.80253598742;3509.508668786372;3543.3799085160254;3558.87543935961;3563.4476656118345;3566.5363502744735;32464.350712985608;32464.837675811705;32472.18819529507;32492.35015299681;32517.349653007044;5249.186376111708;5280.155175171954;5287.339082614673;5310.62825602208;5357.249985002103;194233;194390;195270.02364988226;195315;195904;42661.78669106697;43343.34984975228;43344.349834751876;49998;50903.23645145326;52691.73654131781;52934.47065529296;53001.67495812636;53083;53137;16235.756463653055;16307.83692163087;16320.428784992706;16372;16423.343066277215;13377.264250466318;13399.665008374868;13443.731125377437;13466.797998030037;13467;14674.55976320712;14704.33830477631;14763.63090922714;14782.704345913022;14928.25358732059;5814.273215848019;5863.120531920246;5904.321003084614;5963.373845746221;6031.215941927521;1016.5577973581516;1025.4718819807806;1036.8237399642078;1039.4231201683137;1053.6101642392364;22963.7703622965;23243.651345229835;23418.414539636644;23559.528809423715;23851.045958161827;1124.9043831274432;1143.8341440491083;1150.8561429821273;1153.7058050197145;1154.8094564396781;12146.271223726604;12274.324912129918;12287.095500197305;12353.752924941451;12368.381580920917;207894;210238;214420;222641;222928;53629;54031.45968540343;54231.186532202046;54369.728151359785;54380;1417.496788640018;1460.9634759131106;1471.3526048538608;1473.7641977283797;1485.5988883001746;22898;22968;22994.27753222933;23343.88328058349;23661.763382365956;38223.23553528858;38232.42651360288;38411.61588384192;38412.23175536418;38456.423153652155;193479;193568;193569;193742;194092;32848;33724.3255134891;33916;33935.32129357448;33960.320793583494;41790.582094178666;42221;43617.56382436135;43720.78139609409;43823.34264985964;31874.681253187173;32381.35237295288;32686.182845429055;32788.67211327856;33106.33787324288;31782;31844.36311273808;31954.36091278208;32099.197520062186;32342.514862277556;35915.64084359123;35985.82007089948;36235;36343.45484817677;36664;31205.37589248157;31312.373752525276;31409.743610255333;31467.685323146474;31831.363372732878;23764.333531353;23822.285331440085;23886.64170037416;24080.397990050387;24243.75756242415 03:16:52 INFO - PID 27105 | |2;jquery.html;578.9855253618659;585.6076428792709;592.7628948420632;594.9137375080611;612.6997771092165;21950.560988780217;22682.319530414094;22698.432539186528;22701.20545780898;24062.518749624996;25799;26214.606780898255;26222.868885655593;26225.868870655668;26308.210753677373;6759.662016899148;6931.3068693130535;6944.270851560589;6949.235584085746;6978.8953165702305;6362.872742545146;6687.699053542602;6706.564073335242;6796.558223715443;6858.485613578977;6647.335266473362;6650.800475985729;6650.833729156786;6654.401103900648;6657.866842663144;6659.866802663943;6666.23338316095;6671.599704017726;6676.732930682767;6677.399034086907;6171.197744293258;6216.191895053659;6217.96891015542;6221.129041934133;6227;28725.85637071801;29681;29704.257393565116;29743.405131897456;30179.245518861986;2062.9484262893397;2107.6417009108486;2112.8098471137673;2117.819985301251;2118.66101423773;19236.326728564574;19731.309404170785;19757.308494202633;19795.604087918306;19842.801571984382;2376.697018425129;2421.1646981791255;2439.1828737373003;2458.7787099160983;2461.6307553866886;135453;136246;136479.999999999;138335;138510;14414.13515189092;15084.321205545777;15233.923830380998;15450.690986180438;15631.296591653403;23402.88298558496;23894.402639933793;24293.878530607588;24330;24403.755962440147;10418.895811041944;10573.154147668169;10801.945990269998;10846.78306433867;10852.94573527143;9769.120779129771;9771.755706107262;9885.802283954281;9913.603455861685;9933.602655893828;9222.53887305632;9526.142647161789;9596.328257021942;9605.711828645151;9651.855222171673;2658.8803503842373;2671.6927553331616;2672.2116975492277;2677.718839521861;2690.7712844408443;952.9714108576753;977.6431602465049;989.7875102998913;990.3265779270022;1002.3634991780272;19452.70820937687;19708.356590383566;19744.210231590863;20290.289839855534;20323.288684895957;1041.1254546181244;1056.7146870344964;1058.4178701714027;1058.5025038232018;1058.9099926506178;10860.022597966063;10985.61550345734;11003.17476189289;11039.282446640942;11122.61070862516;458213;460391;474778;485002;487284;114610.42694786639;116932.41533792447;117558;119128;120161;1155.6013175454455;1176.7764124816258;1198.784218840617;1206.1797977375293;1213.362984433162;17873.553161170556;17894.821051789317;18136.539543871888;18225.54436139054;18355.908220459347;55069.724651376484;55474.722626388226;55835.72082139563;56773.43226567681;57051;70047.02147413933;70082;70295.64852175706;71831.64084179545;72280.63859680668;14498.202598857164;14505.347259373142;14619.19594422317;14717.190554519177;14774.33515491827;21444.892775536024;21558.6766198504;21635.891820540797;21679;21849.890750546147;17895.73156402629;18216.180271887533;18420.35528756487;18442.72335914989;18465.53836154107;16236.350545978028;16416.589585260463;16457.25942332574;16780.66438671244;17124.229409676343;17096.48710538686;18136.455906322302;18276.543086422946;18312.90843545774;18450.72323915169;14377.85622143807;14525.128492290496;14545.272736363351;14556.344964476411;14564.781528277295;17460.825391746428;17569.121543923007;17611.911940440215;17635.647287054442;17725.64548708993 03:16:52 INFO - PID 27105 | |3;mootools.html;1365.1536047650457;1418.375914597577;1427.7715565509507;1428.9356978935946;1431.1627697796791;14578.12531248126;15331.693366132671;15335;15339.386424543005;15349.155796431192;14701.632459188526;14841.851581484181;14849.1090534568;14852.777208341884;14862.405503779863;2666.4533770576986;2793.748562629363;2795.6086147939304;2802.2293869185933;2804.5092108880967;2699.6085567592686;2762.4475104978983;2835.3053501892064;2840.2757296889263;2844.6159768431307;2698.136596289188;2731.508328500869;2734.3847634282324;2735.220462168282;2737.7262273772662;2716.375233696251;2736.6852811926583;2751.2296556964084;2756.8208066475813;2766.695663477016;2712.620233167358;2760;2775.9583606245924;2777.05580102764;2779.277387879146;14432.71134577313;14837.851621483755;14888.702225955529;14937.551873443814;14963.177025263607;7963.522188668699;8250.174982501761;8261.297789687884;8285.50286982783;8305.750827475125;5245.31810864589;5576.525995290405;5644.858878528029;5698.772049118072;5724.16999535065;1824.6259516799157;1871.419859843453;1893.5856782602154;1940.2239104358227;1950.1711772496633;25758.227253182245;25872.74127258741;25946.481070378675;26150.73849261521;26200.86899565509;6898.896516552256;6951.270116637781;6995.370416662525;7081.822954426078;7139.92860071403;17044.573885652957;17080.316787328367;17177;17200.31198752061;17261.56846078858;7804.843903121906;8137.633806478722;8144.552049637208;8156.062052863878;8173.591320433955;7222.277772222736;7464.477486575996;7494.21310762373;7531.924680753232;7553.509021913558;5164.122099243138;5311.654742441729;5317.521423071865;5339.626226164131;5370;1300.2003767682995;1361.734461779963;1503.9849601504063;1545.3509525999107;1548.6383265520171;932.9300302477205;941.110650435332;941.2187884056217;951.533748463248;953.9141477267079;7811.687532498637;7881.211878812073;7970.083440404312;8019.799505012421;8024.638891249908;989.9950500247453;993.3344659078434;1004.4274763384947;1018.1447584029451;1019.7909428567126;4736.64475164364;4764.237721964469;4821.156297647897;4851.126797176542;4921.2864134700985;20177.798222017886;21180;21454.24910128138;21568.676469852966;21602;20857.791422085887;20952.89523552403;21463.46341341479;21582.460438488848;21768.673469897964;11652.417379131179;11813.763724725457;11854.229475084092;11859.46632401544;11962.940185299018;8562.657493700182;8600.268977137013;8642.092580279232;8644.308455323433;8700.78248043804;17818.376356827448;18109.63780724352;18110.637787244446;18433.90783046076;18498.260069597065;18736.250549978395;19110.80889191146;19186.328478503186;19264;19301.32445364462;10945.616903408343;10978.231523793578;11020.50407731638;11070.5571777128;11166.609168679057;13687.315634218448;13779.035467517579;13868.514601988882;13878.306084695523;13888.791668125188;11039.282446640942;11290.943545282222;11345.092392608736;11363.37501437429;11486.195966282614;11105.888941110808;11175.608853690406;11366.659000230007;11494.425278735862;11615;9942.552585133542;10069.395836249849;10111.494425278854;10122.544485498023;10126.987301269814;7740.535567865946;7755.806104847424;7774.3003129716335;7797;7838.529688218725;7329.450291228286;7479.551226926402;7491.887621685788;7551.395888329031;7567 03:16:52 INFO - PID 27105 | |4;prototype.html;511.31228497670645;529.1507131054659;534.4314437127031;543.2801537962199;544.3467838593687;6306.486443253621;6348.016057511093;6392.808215753535;6453.7741179058685;6487.286398496168;6181.134641150243;6222.5021998240145;6237.220347456568;6277.372262773721;6281.466075383586;5201.843944681666;5248.737563121847;5251.50995898507;5253.711045892474;5261.263423120766;4903.68126071806;5222.138347172712;5236.738163091849;5266.289050978107;5294.338207724035;5000.19996800512;5030.346055012861;5069.670471419365;5080.009398167359;5091.439941606421;5028.7485625718755;5049.444561098277;5082.847514574569;5096.362954630671;5146.095944500559;4552.248878934972;4596.678677267958;4598.494165641777;4620.792064357079;4634.490206077328;6277.748890044393;6280;6286.9056964145575;6313.715882785285;6318.842028949267;5869.354371019184;5878.970605146947;5888.499477544414;5928.347881733006;5954.255718035246;6563.671816409161;6590.835229119262;6639.800805975829;6730.966345168292;6751.864962700718;4982.202847544376;4983.476734942851;4994.500549945013;4999.175136102539;5006.848424862265;6716.764913228062;6888.758893438706;6983.895241571431;7009.649517524154;7023.89464158038;5149.6910185389;5168.8449346519665;5169.896602067937;5179.19722443022;5188.351456067991;5163.767630456563;5203.569018519937;5217.217417387422;5247.501487358695;5264.131418315935;5774.88450230993;5785.479306862402;5807.912881306784;5842.8539286517325;5858.5898987070495;6734.1278764709505;6740.561863478858;6744.325567443217;6747.696353664097;6807.897881531781;1782.9910850445665;1812.6465339258982;1816.2553353125156;1818.4453741608936;1837.1824538080425;1208.6926658017187;1214.5870404062662;1253.1729058821134;1258.936198911918;1269.181378011196;5737.799177028784;5742.368339482654;5752;5762.769489220468;5814.622049566765;6763.289854565298;6856.177258728984;6910.585364878123;6914.55055421396;6941.403477200171;5739.426057394311;5751.913721294184;5767.596268261265;5777.248957635565;5778.237637520512;3458.2564748578675;3536.504889315498;3597.686844301811;3631.7094632429344;3638.2541578976247;6560.212774467094;6581.638009909405;6585.703643335965;6611.570247933965;6642.243468960424;7033.683484243119;7048.541844780073;7048.859022819515;7087.89368159478;7092.503524753218;6531.771388001397;6548.541602087904;6586.637734924625;6634.947923954938;6697.129373181553;6699.196096468455;6830.31696830313;6837.9316206839285;6886.655667216519;6936;6805.9659701701175;6818.965905170442;6826.726930922708;6866.862662746617;6899.517033807585;6565.982272747732;6574.967125164344;6639.435647969976;6644.335566443317;6661.467082633475;5102.285680004877;5110.105731496898;5125.666831655931;5131.204663277198;5146.922796157985;6517.9348206517325;6784.423324017514;6791.363168569977;6812.727490900308;6817.829554261183;6466.256380516301;6602.481429271198;6602.7689030883685;6612.437942774918;6637.668116594247;6595.406413422815;6609;6625.933740662532;6630.569013014138;6696.933030669631;6391.265004524354;6416.197975253093;6418.614883107029;6493.610383377012;6498.512611554247;6574.567410058309;6585.769498067736;6631.535792494479;6682.131322928182;6747.628880411625;6306.747730090745;6420.597083545728;6451.870962580816;6483.513736469878;6524.67376631157 03:16:52 INFO - PID 27105 | |5;yui.html;917.5045475443261;917.5870858113847;934.7429456899356;935.0836180543064;948.0472125513858;16316.59208519786;17509.124543772825;17577.209025593846;22281.220157294498;23634.290971270846;20923.790762092376;20990.89504552479;21140.788592114073;21693.891530542325;22044.33866983989;957.5020989085672;984.517586382671;988.4859872866106;989.2234595842265;991.415065111584;950.6340059077236;986.3687240166299;991.1179050644931;991.2714155096035;993.3195760903768;1050.6007717067503;1053.3574519543072;1053.4995876958421;1054.520193312045;1055.7730088031071;1022.2435397805644;1023.9948800255988;1028.2236911132086;1030.5826140413108;1031.092638478142;752.9171791102974;753.8756105242628;754.8339365339616;754.9282818132268;756.8751156059244;19073.523161920923;19820.207191712314;19933.601327973505;19984.80015199844;19996.600067998705;22249.22127725538;22261.109555617917;22531;22538.77461225383;22545.436364090863;18452.815471845242;18457.353992610195;18515.44453666379;18527.814721852745;18566.62866742671;3703.2038111805823;3783.735138540303;3810.771353718758;3817.3701339278987;3843.404272337792;143020.28489857484;143131.28434357865;144514;146739;147660.26169869083;17101;17139.314427423014;17342.393016244372;17461.82538174602;17624.823751762317;32288.35423291568;32388.190295242806;32487.35025299481;32530;32548.349033019207;8978.730638080868;9006.684766033288;9034.367594268339;9086.227670647937;9141.497217652959;4239.42767726353;4345.196138714348;4399.560043995575;4408.809621402268;4461.330800379969;5476.91784623231;5519.089350257163;5561.4716601922755;5608.49523542875;5630.943690563042;1059.0891833023593;1092.7049696582037;1102.0522350778324;1104.5140138339266;1105.635140403681;648.7275344355382;652.1912828093225;652.9477641788645;654.2247436787422;694.2848865668369;12072.275663460045;12417;12917.418716157796;13101.868981310065;13157.144785588906;639.7824739588563;643.9420452159328;648.8377905523619;678.8947713104477;690.7962151165418;5767.279090113736;5826.271716035496;5855.151003104526;5913.319968203712;5922.873945639594;123633;127179;127280.8179795494;130119.34940325427;130759.34620326837;109209;110935;111178;112672;116256.41871790586;90323;91352;94938.52530737303;96667.99999999859;96898;10758.354498730101;11102.777944440904;11128.721781955515;11258.437078145897;11377.601783937522;42669.35995960125;42768.57231427646;43744.343834841864;45198.32202516899;45367;78015;79511;81559.7766033498;83362.16637833786;85994.57002714946;19330.13014414383;19437.8056219436;19508;19637.901810490857;19648.508787280432;20050.799492005477;20082;20179.59640807205;20311;21741;18537.44387668372;18576.44270671882;18587.163577638767;18872.716909246647;20688.68966965527;18916.14877330551;18983.810161898204;19455.221791128195;19567.315143969892;20219;18168.454946351103;19330.8066919329;19437.805621944164;19697.704534432283;19745.506362341057;13196.14225075367;13649.658758531115;13657;13704.862951370758;13706.588802335946;14423.639409014859;14658.340374682952;14799.334029968893;14893.702125957638;14920.253987300375 03:16:52 INFO - PID 27105 | __end_tp_report 03:16:52 INFO - PID 27105 | __start_cc_report 03:16:52 INFO - PID 27105 | _x_x_mozilla_cycle_collect,269 03:16:52 INFO - PID 27105 | __end_cc_report 03:16:52 INFO - PID 27105 | __startTimestamp1506939412514__endTimestamp 03:16:52 INFO - PID 27105 | 03:16:52 INFO - PID 27105 | ------- Summary: start ------- 03:16:52 INFO - PID 27105 | Number of tests: 6 03:16:52 INFO - PID 27105 | 03:16:52 INFO - PID 27105 | [#0] dojo.html Cycles:165 Average:6327.79 Median:6586.90 stddev:770.03 (11.7%) stddev-sans-first:731.42 03:16:52 INFO - PID 27105 | Values: 3168.6 3239.3 3239.4 3245.9 3249.2 6246.5 6360.8 6371.1 6452.4 6511.6 6313.3 6334.8 6354.2 6355.6 6366.6 5841.4 5863.7 5868.8 5885.0 5895.3 5861.9 5880.8 5903.5 5925.1 5977.2 5939.8 5958.6 5960.0 5961.6 5975.2 5884.7 5930.1 5950.7 5990.3 6003.7 5710.2 5785.9 5816.4 5888.8 5899.2 6387.7 6436.4 6447.7 6448.8 6458.2 6870.4 6885.0 6939.1 6959.9 6968.4 6867.1 6900.4 6977.1 6983.0 6985.8 6334.7 6423.9 6426.7 6426.8 6434.3 7041.3 7050.5 7082.0 7171.1 7175.4 6533.7 6685.6 6689.1 6691.7 6725.7 6763.6 6828.5 6924.5 6940.5 6961.8 6831.0 6848.2 6928.9 6942.7 6949.5 6862.0 6878.0 6887.6 6956.0 6978.0 5234.1 5329.8 5332.0 5334.5 5454.7 4691.3 4722.0 4753.4 4763.8 4802.5 5851.2 5884.5 5919.5 5945.4 5982.0 6903.6 6911.9 6950.3 7006.8 7037.3 5716.4 5756.1 5814.9 5908.9 5917.6 5917.1 5958.9 5959.2 5963.8 5999.3 6622.9 6630.7 6645.3 6747.3 6749.7 6907.1 6910.7 6982.6 6997.9 7003.1 6447.7 6554.9 6621.9 6691.2 6715.4 6847.2 6875.2 6884.2 6894.0 6902.0 6850.8 6971.5 7028.7 7054.2 7058.2 6491.7 6617.6 6693.8 6713.8 6809.7 6590.3 6617.2 6618.4 6641.5 6644.2 6586.9 6646.0 6650.5 6660.2 6711.3 6720.3 6728.4 6828.9 6831.8 6852.4 6508.0 6517.7 6581.7 6616.1 6633.3 03:16:52 INFO - PID 27105 | 03:16:52 INFO - PID 27105 | [#1] ext.html Cycles:180 Average:37300.72 Median:3526.44 stddev:52175.16 (1479.5%) stddev-sans-first:52249.75 03:16:52 INFO - PID 27105 | Values: 853.5 877.3 877.7 878.7 880.9 41567.4 42204.4 42344.4 42429.0 42429.0 38778.6 38848.0 38897.8 39029.0 39107.6 7230.3 7364.2 7377.7 7379.6 7385.6 7233.5 7306.7 7311.0 7334.1 7343.9 7298.7 7807.2 8146.8 8164.3 8171.1 8040.7 8054.2 8054.4 8082.0 8085.5 7577.3 7850.0 7933.7 7950.0 7995.2 38830.2 39366.6 39396.0 39397.0 39492.8 3509.5 3543.4 3558.9 3563.4 3566.5 32464.4 32464.8 32472.2 32492.4 32517.3 5249.2 5280.2 5287.3 5310.6 5357.2 194233.0 194390.0 195270.0 195315.0 195904.0 42661.8 43343.3 43344.3 49998.0 50903.2 52691.7 52934.5 53001.7 53083.0 53137.0 16235.8 16307.8 16320.4 16372.0 16423.3 13377.3 13399.7 13443.7 13466.8 13467.0 14674.6 14704.3 14763.6 14782.7 14928.3 5814.3 5863.1 5904.3 5963.4 6031.2 1016.6 1025.5 1036.8 1039.4 1053.6 22963.8 23243.7 23418.4 23559.5 23851.0 1124.9 1143.8 1150.9 1153.7 1154.8 12146.3 12274.3 12287.1 12353.8 12368.4 207894.0 210238.0 214420.0 222641.0 222928.0 53629.0 54031.5 54231.2 54369.7 54380.0 1417.5 1461.0 1471.4 1473.8 1485.6 22898.0 22968.0 22994.3 23343.9 23661.8 38223.2 38232.4 38411.6 38412.2 38456.4 193479.0 193568.0 193569.0 193742.0 194092.0 32848.0 33724.3 33916.0 33935.3 33960.3 41790.6 42221.0 43617.6 43720.8 43823.3 31874.7 32381.4 32686.2 32788.7 33106.3 31782.0 31844.4 31954.4 32099.2 32342.5 35915.6 35985.8 36235.0 36343.5 36664.0 31205.4 31312.4 31409.7 31467.7 31831.4 23764.3 23822.3 23886.6 24080.4 24243.8 03:16:52 INFO - PID 27105 | 03:16:52 INFO - PID 27105 | [#2] jquery.html Cycles:180 Average:34355.49 Median:21900.23 stddev:79998.14 (365.3%) stddev-sans-first:80182.36 03:16:52 INFO - PID 27105 | Values: 579.0 585.6 592.8 594.9 612.7 21950.6 22682.3 22698.4 22701.2 24062.5 25799.0 26214.6 26222.9 26225.9 26308.2 6759.7 6931.3 6944.3 6949.2 6978.9 6362.9 6687.7 6706.6 6796.6 6858.5 6647.3 6650.8 6650.8 6654.4 6657.9 6659.9 6666.2 6671.6 6676.7 6677.4 6171.2 6216.2 6218.0 6221.1 6227.0 28725.9 29681.0 29704.3 29743.4 30179.2 2062.9 2107.6 2112.8 2117.8 2118.7 19236.3 19731.3 19757.3 19795.6 19842.8 2376.7 2421.2 2439.2 2458.8 2461.6 135453.0 136246.0 136480.0 138335.0 138510.0 14414.1 15084.3 15233.9 15450.7 15631.3 23402.9 23894.4 24293.9 24330.0 24403.8 10418.9 10573.2 10801.9 10846.8 10852.9 9769.1 9771.8 9885.8 9913.6 9933.6 9222.5 9526.1 9596.3 9605.7 9651.9 2658.9 2671.7 2672.2 2677.7 2690.8 953.0 977.6 989.8 990.3 1002.4 19452.7 19708.4 19744.2 20290.3 20323.3 1041.1 1056.7 1058.4 1058.5 1058.9 10860.0 10985.6 11003.2 11039.3 11122.6 458213.0 460391.0 474778.0 485002.0 487284.0 114610.4 116932.4 117558.0 119128.0 120161.0 1155.6 1176.8 1198.8 1206.2 1213.4 17873.6 17894.8 18136.5 18225.5 18355.9 55069.7 55474.7 55835.7 56773.4 57051.0 70047.0 70082.0 70295.6 71831.6 72280.6 14498.2 14505.3 14619.2 14717.2 14774.3 21444.9 21558.7 21635.9 21679.0 21849.9 17895.7 18216.2 18420.4 18442.7 18465.5 16236.4 16416.6 16457.3 16780.7 17124.2 17096.5 18136.5 18276.5 18312.9 18450.7 14377.9 14525.1 14545.3 14556.3 14564.8 17460.8 17569.1 17611.9 17635.6 17725.6 03:16:52 INFO - PID 27105 | 03:16:52 INFO - PID 27105 | [#3] mootools.html Cycles:180 Average:9333.96 Median:26175.80 stddev:6552.26 (25.0%) stddev-sans-first:6543.28 03:16:52 INFO - PID 27105 | Values: 1365.2 1418.4 1427.8 1428.9 1431.2 14578.1 15331.7 15335.0 15339.4 15349.2 14701.6 14841.9 14849.1 14852.8 14862.4 2666.5 2793.7 2795.6 2802.2 2804.5 2699.6 2762.4 2835.3 2840.3 2844.6 2698.1 2731.5 2734.4 2735.2 2737.7 2716.4 2736.7 2751.2 2756.8 2766.7 2712.6 2760.0 2776.0 2777.1 2779.3 14432.7 14837.9 14888.7 14937.6 14963.2 7963.5 8250.2 8261.3 8285.5 8305.8 5245.3 5576.5 5644.9 5698.8 5724.2 1824.6 1871.4 1893.6 1940.2 1950.2 25758.2 25872.7 25946.5 26150.7 26200.9 6898.9 6951.3 6995.4 7081.8 7139.9 17044.6 17080.3 17177.0 17200.3 17261.6 7804.8 8137.6 8144.6 8156.1 8173.6 7222.3 7464.5 7494.2 7531.9 7553.5 5164.1 5311.7 5317.5 5339.6 5370.0 1300.2 1361.7 1504.0 1545.4 1548.6 932.9 941.1 941.2 951.5 953.9 7811.7 7881.2 7970.1 8019.8 8024.6 990.0 993.3 1004.4 1018.1 1019.8 4736.6 4764.2 4821.2 4851.1 4921.3 20177.8 21180.0 21454.2 21568.7 21602.0 20857.8 20952.9 21463.5 21582.5 21768.7 11652.4 11813.8 11854.2 11859.5 11962.9 8562.7 8600.3 8642.1 8644.3 8700.8 17818.4 18109.6 18110.6 18433.9 18498.3 18736.3 19110.8 19186.3 19264.0 19301.3 10945.6 10978.2 11020.5 11070.6 11166.6 13687.3 13779.0 13868.5 13878.3 13888.8 11039.3 11290.9 11345.1 11363.4 11486.2 11105.9 11175.6 11366.7 11494.4 11615.0 9942.6 10069.4 10111.5 10122.5 10127.0 7740.5 7755.8 7774.3 7797.0 7838.5 7329.5 7479.6 7491.9 7551.4 7567.0 03:16:52 INFO - PID 27105 | 03:16:52 INFO - PID 27105 | [#4] prototype.html Cycles:180 Average:5618.37 Median:6280.73 stddev:1564.81 (24.9%) stddev-sans-first:1521.53 03:16:52 INFO - PID 27105 | Values: 511.3 529.2 534.4 543.3 544.3 6306.5 6348.0 6392.8 6453.8 6487.3 6181.1 6222.5 6237.2 6277.4 6281.5 5201.8 5248.7 5251.5 5253.7 5261.3 4903.7 5222.1 5236.7 5266.3 5294.3 5000.2 5030.3 5069.7 5080.0 5091.4 5028.7 5049.4 5082.8 5096.4 5146.1 4552.2 4596.7 4598.5 4620.8 4634.5 6277.7 6280.0 6286.9 6313.7 6318.8 5869.4 5879.0 5888.5 5928.3 5954.3 6563.7 6590.8 6639.8 6731.0 6751.9 4982.2 4983.5 4994.5 4999.2 5006.8 6716.8 6888.8 6983.9 7009.6 7023.9 5149.7 5168.8 5169.9 5179.2 5188.4 5163.8 5203.6 5217.2 5247.5 5264.1 5774.9 5785.5 5807.9 5842.9 5858.6 6734.1 6740.6 6744.3 6747.7 6807.9 1783.0 1812.6 1816.3 1818.4 1837.2 1208.7 1214.6 1253.2 1258.9 1269.2 5737.8 5742.4 5752.0 5762.8 5814.6 6763.3 6856.2 6910.6 6914.6 6941.4 5739.4 5751.9 5767.6 5777.2 5778.2 3458.3 3536.5 3597.7 3631.7 3638.3 6560.2 6581.6 6585.7 6611.6 6642.2 7033.7 7048.5 7048.9 7087.9 7092.5 6531.8 6548.5 6586.6 6634.9 6697.1 6699.2 6830.3 6837.9 6886.7 6936.0 6806.0 6819.0 6826.7 6866.9 6899.5 6566.0 6575.0 6639.4 6644.3 6661.5 5102.3 5110.1 5125.7 5131.2 5146.9 6517.9 6784.4 6791.4 6812.7 6817.8 6466.3 6602.5 6602.8 6612.4 6637.7 6595.4 6609.0 6625.9 6630.6 6696.9 6391.3 6416.2 6418.6 6493.6 6498.5 6574.6 6585.8 6631.5 6682.1 6747.6 6306.7 6420.6 6451.9 6483.5 6524.7 03:16:52 INFO - PID 27105 | 03:16:52 INFO - PID 27105 | [#5] yui.html Cycles:180 Average:26181.17 Median:20499.84 stddev:36974.02 (180.4%) stddev-sans-first:37029.08 03:16:52 INFO - PID 27105 | Values: 917.5 917.6 934.7 935.1 948.0 16316.6 17509.1 17577.2 22281.2 23634.3 20923.8 20990.9 21140.8 21693.9 22044.3 957.5 984.5 988.5 989.2 991.4 950.6 986.4 991.1 991.3 993.3 1050.6 1053.4 1053.5 1054.5 1055.8 1022.2 1024.0 1028.2 1030.6 1031.1 752.9 753.9 754.8 754.9 756.9 19073.5 19820.2 19933.6 19984.8 19996.6 22249.2 22261.1 22531.0 22538.8 22545.4 18452.8 18457.4 18515.4 18527.8 18566.6 3703.2 3783.7 3810.8 3817.4 3843.4 143020.3 143131.3 144514.0 146739.0 147660.3 17101.0 17139.3 17342.4 17461.8 17624.8 32288.4 32388.2 32487.4 32530.0 32548.3 8978.7 9006.7 9034.4 9086.2 9141.5 4239.4 4345.2 4399.6 4408.8 4461.3 5476.9 5519.1 5561.5 5608.5 5630.9 1059.1 1092.7 1102.1 1104.5 1105.6 648.7 652.2 652.9 654.2 694.3 12072.3 12417.0 12917.4 13101.9 13157.1 639.8 643.9 648.8 678.9 690.8 5767.3 5826.3 5855.2 5913.3 5922.9 123633.0 127179.0 127280.8 130119.3 130759.3 109209.0 110935.0 111178.0 112672.0 116256.4 90323.0 91352.0 94938.5 96668.0 96898.0 10758.4 11102.8 11128.7 11258.4 11377.6 42669.4 42768.6 43744.3 45198.3 45367.0 78015.0 79511.0 81559.8 83362.2 85994.6 19330.1 19437.8 19508.0 19637.9 19648.5 20050.8 20082.0 20179.6 20311.0 21741.0 18537.4 18576.4 18587.2 18872.7 20688.7 18916.1 18983.8 19455.2 19567.3 20219.0 18168.5 19330.8 19437.8 19697.7 19745.5 13196.1 13649.7 13657.0 13704.9 13706.6 14423.6 14658.3 14799.3 14893.7 14920.3 03:16:52 INFO - PID 27105 | -------- Summary: end -------- 03:16:52 INFO - PID 27105 | 03:16:53 INFO - TEST-INFO | 27105: exit 0 03:16:53 INFO - TEST-OK | dromaeo_css | took 1109881ms 03:16:53 INFO - TEST-START | kraken 03:16:53 INFO - Initialising browser for kraken test... 03:16:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49107/getInfo.html -profile /tmp/tmpAwFCXy/profile 03:16:53 INFO - TEST-INFO | started process 27468 (/builds/slave/test/build/application/firefox/firefox http://localhost:49107/getInfo.html) 03:16:58 INFO - TEST-INFO | 27468: exit 0 03:16:58 INFO - Browser initialized. 03:16:58 INFO - Running cycle 1/1 for kraken test... 03:16:58 INFO - Using env: {'DISPLAY': ':0', 03:16:58 INFO - 'HOME': '/home/cltbld', 03:16:58 INFO - 'JSGC_DISABLE_POISONING': '1', 03:16:58 INFO - 'LANG': 'en_US.UTF-8', 03:16:58 INFO - 'LANGUAGE': 'en_US:en', 03:16:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:16:58 INFO - 'LOGNAME': 'cltbld', 03:16:58 INFO - 'MAIL': '/var/mail/cltbld', 03:16:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:16:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:16:58 INFO - 'MOZ_CRASHREPORTER': '1', 03:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:16:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:16:58 INFO - 'MOZ_NO_REMOTE': '1', 03:16:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:16:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:16:58 INFO - 'NO_EM_RESTART': '1', 03:16:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:16:58 INFO - 'PWD': '/builds/slave/test', 03:16:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:16:58 INFO - 'RUST_BACKTRACE': 'full', 03:16:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:16:58 INFO - 'SHELL': '/bin/bash', 03:16:58 INFO - 'SHLVL': '1', 03:16:58 INFO - 'TERM': 'linux', 03:16:58 INFO - 'TMOUT': '86400', 03:16:58 INFO - 'USER': 'cltbld', 03:16:58 INFO - 'XDG_SESSION_COOKIE': '781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303', 03:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:16:58 INFO - '_': '/tools/buildbot/bin/python'} 03:16:58 INFO - TEST-INFO | started process 27701 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAwFCXy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:59 INFO - PID 27701 | 03:16:59 INFO - PID 27701 | (/builds/slave/test/build/application/firefox/firefox:27750): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:16:59 INFO - PID 27701 | 03:17:10 INFO - PID 27701 | RSS: Main: 152039424 03:17:10 INFO - PID 27701 | 03:17:12 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49107/tests/kraken/driver.html?testName=audio-beat-detection) 03:17:13 INFO - PID 27701 | RSS: Main: 158433280 03:17:13 INFO - PID 27701 | 03:17:16 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49107/tests/kraken/driver.html?testName=audio-dft) 03:17:16 INFO - PID 27701 | RSS: Main: 159301632 03:17:16 INFO - PID 27701 | 03:17:20 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49107/tests/kraken/driver.html?testName=audio-fft) 03:17:20 INFO - PID 27701 | RSS: Main: 154779648 03:17:20 INFO - PID 27701 | 03:17:22 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49107/tests/kraken/driver.html?testName=audio-oscillator) 03:17:23 INFO - PID 27701 | RSS: Main: 155557888 03:17:23 INFO - PID 27701 | 03:17:25 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49107/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:17:25 INFO - PID 27701 | RSS: Main: 155250688 03:17:25 INFO - PID 27701 | 03:17:29 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49107/tests/kraken/driver.html?testName=imaging-darkroom) 03:17:29 INFO - PID 27701 | RSS: Main: 157286400 03:17:29 INFO - PID 27701 | 03:17:33 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49107/tests/kraken/driver.html?testName=imaging-desaturate) 03:17:34 INFO - PID 27701 | RSS: Main: 158736384 03:17:34 INFO - PID 27701 | 03:17:37 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49107/tests/kraken/driver.html?testName=json-parse-financial) 03:17:37 INFO - PID 27701 | RSS: Main: 158994432 03:17:37 INFO - PID 27701 | 03:17:39 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49107/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:17:39 INFO - PID 27701 | RSS: Main: 158269440 03:17:39 INFO - PID 27701 | 03:17:41 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49107/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:17:42 INFO - PID 27701 | RSS: Main: 158437376 03:17:42 INFO - PID 27701 | 03:17:44 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49107/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:17:44 INFO - PID 27701 | RSS: Main: 155222016 03:17:44 INFO - PID 27701 | 03:17:47 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:17:48 INFO - PID 27701 | RSS: Main: 155246592 03:17:48 INFO - PID 27701 | 03:17:51 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:17:51 INFO - PID 27701 | RSS: Main: 155508736 03:17:51 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | Cycle 1(1): loaded http://localhost:49107/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49107/tests/kraken/driver.html?testName=ai-astar) 03:17:53 INFO - PID 27701 | RSS: Main: 156995584 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | __start_tp_report 03:17:53 INFO - PID 27701 | _x_x_mozilla_page_load 03:17:53 INFO - PID 27701 | _x_x_mozilla_page_load_details 03:17:53 INFO - PID 27701 | |i|pagename|runs| 03:17:53 INFO - PID 27701 | |0;ai-astar;105;106;105;110;108;106;106;106;105;106 03:17:53 INFO - PID 27701 | |1;audio-beat-detection;127;117;121;116;121;122;150;116;119;117 03:17:53 INFO - PID 27701 | |2;audio-dft;164;161;162;164;164;165;163;166;172;165 03:17:53 INFO - PID 27701 | |3;audio-fft;74;76;74;75;73;83;74;74;85;75 03:17:53 INFO - PID 27701 | |4;audio-oscillator;80;80;78;79;79;78;79;78;78;80 03:17:53 INFO - PID 27701 | |5;imaging-gaussian-blur;123;111;111;112;111;112;112;112;112;112 03:17:53 INFO - PID 27701 | |6;imaging-darkroom;151;148;148;148;149;149;148;149;148;148 03:17:53 INFO - PID 27701 | |7;imaging-desaturate;94;94;95;93;94;94;94;94;94;99 03:17:53 INFO - PID 27701 | |8;json-parse-financial;50;54;57;53;54;51;51;52;52;50 03:17:53 INFO - PID 27701 | |9;json-stringify-tinderbox;36;37;36;37;37;37;37;37;38;37 03:17:53 INFO - PID 27701 | |10;stanford-crypto-aes;75;83;73;75;73;78;75;77;74;81 03:17:53 INFO - PID 27701 | |11;stanford-crypto-ccm;109;122;112;110;110;120;112;110;111;122 03:17:53 INFO - PID 27701 | |12;stanford-crypto-pbkdf2;158;160;160;161;159;162;159;164;169;170 03:17:53 INFO - PID 27701 | |13;stanford-crypto-sha256-iterative;63;66;64;62;65;65;64;63;70;65 03:17:53 INFO - PID 27701 | __end_tp_report 03:17:53 INFO - PID 27701 | __start_cc_report 03:17:53 INFO - PID 27701 | _x_x_mozilla_cycle_collect,731 03:17:53 INFO - PID 27701 | __end_cc_report 03:17:53 INFO - PID 27701 | __startTimestamp1506939473602__endTimestamp 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | ------- Summary: start ------- 03:17:53 INFO - PID 27701 | Number of tests: 14 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#0] ai-astar Cycles:10 Average:106.30 Median:106.00 stddev:1.57 (1.5%) stddev-sans-first:1.59 03:17:53 INFO - PID 27701 | Values: 105.0 106.0 105.0 110.0 108.0 106.0 106.0 106.0 105.0 106.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#1] audio-beat-detection Cycles:10 Average:122.60 Median:121.00 stddev:10.21 (8.4%) stddev-sans-first:10.71 03:17:53 INFO - PID 27701 | Values: 127.0 117.0 121.0 116.0 121.0 122.0 150.0 116.0 119.0 117.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#2] audio-dft Cycles:10 Average:164.60 Median:164.50 stddev:2.99 (1.8%) stddev-sans-first:3.16 03:17:53 INFO - PID 27701 | Values: 164.0 161.0 162.0 164.0 164.0 165.0 163.0 166.0 172.0 165.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#3] audio-fft Cycles:10 Average:76.30 Median:75.00 stddev:4.16 (5.6%) stddev-sans-first:4.33 03:17:53 INFO - PID 27701 | Values: 74.0 76.0 74.0 75.0 73.0 83.0 74.0 74.0 85.0 75.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#4] audio-oscillator Cycles:10 Average:78.90 Median:79.00 stddev:0.88 (1.1%) stddev-sans-first:0.83 03:17:53 INFO - PID 27701 | Values: 80.0 80.0 78.0 79.0 79.0 78.0 79.0 78.0 78.0 80.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#5] imaging-gaussian-blur Cycles:10 Average:112.80 Median:112.00 stddev:3.61 (3.2%) stddev-sans-first:0.50 03:17:53 INFO - PID 27701 | Values: 123.0 111.0 111.0 112.0 111.0 112.0 112.0 112.0 112.0 112.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#6] imaging-darkroom Cycles:10 Average:148.60 Median:148.50 stddev:0.97 (0.7%) stddev-sans-first:0.50 03:17:53 INFO - PID 27701 | Values: 151.0 148.0 148.0 148.0 149.0 149.0 148.0 149.0 148.0 148.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.00 stddev:1.65 (1.8%) stddev-sans-first:1.74 03:17:53 INFO - PID 27701 | Values: 94.0 94.0 95.0 93.0 94.0 94.0 94.0 94.0 94.0 99.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#8] json-parse-financial Cycles:10 Average:52.40 Median:52.50 stddev:2.17 (4.1%) stddev-sans-first:2.12 03:17:53 INFO - PID 27701 | Values: 50.0 54.0 57.0 53.0 54.0 51.0 51.0 52.0 52.0 50.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#9] json-stringify-tinderbox Cycles:10 Average:36.90 Median:37.00 stddev:0.57 (1.5%) stddev-sans-first:0.50 03:17:53 INFO - PID 27701 | Values: 36.0 37.0 36.0 37.0 37.0 37.0 37.0 37.0 38.0 37.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#10] stanford-crypto-aes Cycles:10 Average:76.40 Median:76.00 stddev:3.37 (4.4%) stddev-sans-first:3.54 03:17:53 INFO - PID 27701 | Values: 75.0 83.0 73.0 75.0 73.0 78.0 75.0 77.0 74.0 81.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#11] stanford-crypto-ccm Cycles:10 Average:113.80 Median:112.00 stddev:5.31 (4.7%) stddev-sans-first:5.34 03:17:53 INFO - PID 27701 | Values: 109.0 122.0 112.0 110.0 110.0 120.0 112.0 110.0 111.0 122.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:161.50 stddev:4.21 (2.6%) stddev-sans-first:4.18 03:17:53 INFO - PID 27701 | Values: 158.0 160.0 160.0 161.0 159.0 162.0 159.0 164.0 169.0 170.0 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:65.00 stddev:2.21 (3.4%) stddev-sans-first:2.26 03:17:53 INFO - PID 27701 | Values: 63.0 66.0 64.0 62.0 65.0 65.0 64.0 63.0 70.0 65.0 03:17:53 INFO - PID 27701 | -------- Summary: end -------- 03:17:53 INFO - PID 27701 | 03:17:53 INFO - PID 27701 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:17:54 INFO - TEST-INFO | 27701: exit 0 03:17:54 INFO - TEST-OK | kraken | took 61153ms 03:17:54 INFO - SUITE-END | took 1171s 03:17:54 INFO - Completed test suite (00:19:31) 03:17:54 INFO - javascript benchmark 03:17:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8697.86039040164, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3168.603924509436, 3239.2549713565863, 3239.4492936200845, 3245.902622921312, 3249.23642943908, 6246.531510136735, 6360.840978975528, 6371.139896114023, 6452.354764523545, 6511.576747511414, 6313.337099604544, 6334.841628959279, 6354.237491501015, 6355.618662880231, 6366.554341196118, 5841.386654401269, 5863.736131874076, 5868.794592189285, 5885.0, 5895.263092113486, 5861.85345366365, 5880.76476940924, 5903.4686878180955, 5925.081612350093, 5977.222961015062, 5939.821805345847, 5958.612690175125, 5960.046392577188, 5961.582689211757, 5975.163477113208, 5884.735186916599, 5930.0511918093325, 5950.732217050243, 5990.311114221833, 6003.699815009276, 5710.229119068914, 5785.913211301834, 5816.360200377955, 5888.793892213795, 5899.174115623816, 6387.744490220385, 6436.420722135031, 6447.742090316382, 6448.806535803933, 6458.225012998423, 6870.381665650116, 6885.0, 6939.097917270773, 6959.860802783916, 6968.407685346701, 6867.141607299088, 6900.447964162855, 6977.092977912889, 6983.022376867242, 6985.755498557577, 6334.746610135589, 6423.903641445382, 6426.742930282783, 6426.807195784134, 6434.292227854932, 7041.331073548107, 7050.471214658921, 7082.043924070287, 7171.1394632644415, 7175.425965922818, 6533.738650454024, 6685.565438246498, 6689.063531105649, 6691.7323307068145, 6725.730970761213, 6763.628000460022, 6828.487863410165, 6924.480663950224, 6940.479464040217, 6961.825954351182, 6830.975353696886, 6848.178218613699, 6928.861422771516, 6942.7222911084, 6949.478789090838, 6861.965690171517, 6878.037074809531, 6887.552309099892, 6956.0, 6978.0230767692565, 5234.136367499396, 5329.786808527693, 5332.013577488177, 5334.493223143795, 5454.699991500426, 4691.3432119503295, 4722.0, 4753.382060332135, 4763.76181190939, 4802.543758342952, 5851.180834683148, 5884.499817515473, 5919.496842768327, 5945.435183657545, 5982.042873140364, 6903.6203008835, 6911.861762764817, 6950.339717726808, 7006.789796306119, 7037.296270372922, 5716.399778023248, 5756.107803290497, 5814.912776308359, 5908.852278692981, 5917.615355001911, 5917.082852157922, 5958.910616340759, 5959.165716799652, 5963.821085367446, 5999.340072592011, 6622.9006564901565, 6630.701618427182, 6645.3022432643875, 6747.257801641816, 6749.6962636681465, 6907.13660792401, 6910.654467276717, 6982.615956122462, 6997.895031574429, 7003.054587630605, 6447.742090316522, 6554.868902621825, 6621.867562648817, 6691.1636045494315, 6715.4291885190305, 6847.21257055445, 6875.209350924708, 6884.173899132136, 6894.034835123187, 6902.033715279765, 6850.828729281808, 6971.5468494547695, 7028.718851245892, 7054.153501579844, 7058.188308344606, 6491.740330386731, 6617.602943823386, 6693.799186024427, 6713.832154196184, 6809.693563789542, 6590.275069742421, 6617.239017513048, 6618.437432818265, 6641.535092543476, 6644.202695676549, 6586.901196481961, 6646.03632473279, 6650.534462587766, 6660.167479065117, 6711.295313991961, 6720.32796720324, 6728.428083612752, 6828.863422731617, 6831.760888368884, 6852.417544508573, 6507.967460162668, 6517.73929042833, 6581.703823327866, 6616.073749674953, 6633.270340262664], "value": 6268.600106995221, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [853.504967119071, 877.2981614708228, 877.675260153743, 878.7495563764328, 880.8898887639045, 41567.37648935268, 42204.36693449601, 42344.36483452751, 42429.0, 42429.0, 38778.61221387785, 38848.0, 38897.805510972474, 39029.0, 39107.60892391075, 7230.349268565828, 7364.226756190603, 7377.741779037735, 7379.59412232327, 7385.593792341419, 7233.529820561673, 7306.744263950763, 7311.013013243197, 7334.119905611307, 7343.853122937538, 7298.708051677927, 7807.219278072176, 8146.837063258731, 8164.306033987118, 8171.142030086845, 8040.678372865078, 8054.23484768946, 8054.355651547891, 8081.95959020207, 8085.474444161111, 7577.3180413762475, 7849.960750196213, 7933.682652693943, 7950.0, 7995.240452157036, 38830.223395532215, 39366.60633393687, 39396.0, 39397.0, 39492.80253598742, 3509.508668786372, 3543.3799085160254, 3558.87543935961, 3563.4476656118345, 3566.5363502744735, 32464.350712985608, 32464.837675811705, 32472.18819529507, 32492.35015299681, 32517.349653007044, 5249.186376111708, 5280.155175171954, 5287.339082614673, 5310.62825602208, 5357.249985002103, 194233.0, 194390.0, 195270.02364988226, 195315.0, 195904.0, 42661.78669106697, 43343.34984975228, 43344.349834751876, 49998.0, 50903.23645145326, 52691.73654131781, 52934.47065529296, 53001.67495812636, 53083.0, 53137.0, 16235.756463653055, 16307.83692163087, 16320.428784992706, 16372.0, 16423.343066277215, 13377.264250466318, 13399.665008374868, 13443.731125377437, 13466.797998030037, 13467.0, 14674.55976320712, 14704.33830477631, 14763.63090922714, 14782.704345913022, 14928.25358732059, 5814.273215848019, 5863.120531920246, 5904.321003084614, 5963.373845746221, 6031.215941927521, 1016.5577973581516, 1025.4718819807806, 1036.8237399642078, 1039.4231201683137, 1053.6101642392364, 22963.7703622965, 23243.651345229835, 23418.414539636644, 23559.528809423715, 23851.045958161827, 1124.9043831274432, 1143.8341440491083, 1150.8561429821273, 1153.7058050197145, 1154.8094564396781, 12146.271223726604, 12274.324912129918, 12287.095500197305, 12353.752924941451, 12368.381580920917, 207894.0, 210238.0, 214420.0, 222641.0, 222928.0, 53629.0, 54031.45968540343, 54231.186532202046, 54369.728151359785, 54380.0, 1417.496788640018, 1460.9634759131106, 1471.3526048538608, 1473.7641977283797, 1485.5988883001746, 22898.0, 22968.0, 22994.27753222933, 23343.88328058349, 23661.763382365956, 38223.23553528858, 38232.42651360288, 38411.61588384192, 38412.23175536418, 38456.423153652155, 193479.0, 193568.0, 193569.0, 193742.0, 194092.0, 32848.0, 33724.3255134891, 33916.0, 33935.32129357448, 33960.320793583494, 41790.582094178666, 42221.0, 43617.56382436135, 43720.78139609409, 43823.34264985964, 31874.681253187173, 32381.35237295288, 32686.182845429055, 32788.67211327856, 33106.33787324288, 31782.0, 31844.36311273808, 31954.36091278208, 32099.197520062186, 32342.514862277556, 35915.64084359123, 35985.82007089948, 36235.0, 36343.45484817677, 36664.0, 31205.37589248157, 31312.373752525276, 31409.743610255333, 31467.685323146474, 31831.363372732878, 23764.333531353, 23822.285331440085, 23886.64170037416, 24080.397990050387, 24243.75756242415], "value": 17272.02923942753, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [578.9855253618659, 585.6076428792709, 592.7628948420632, 594.9137375080611, 612.6997771092165, 21950.560988780217, 22682.319530414094, 22698.432539186528, 22701.20545780898, 24062.518749624996, 25799.0, 26214.606780898255, 26222.868885655593, 26225.868870655668, 26308.210753677373, 6759.662016899148, 6931.3068693130535, 6944.270851560589, 6949.235584085746, 6978.8953165702305, 6362.872742545146, 6687.699053542602, 6706.564073335242, 6796.558223715443, 6858.485613578977, 6647.335266473362, 6650.800475985729, 6650.833729156786, 6654.401103900648, 6657.866842663144, 6659.866802663943, 6666.23338316095, 6671.599704017726, 6676.732930682767, 6677.399034086907, 6171.197744293258, 6216.191895053659, 6217.96891015542, 6221.129041934133, 6227.0, 28725.85637071801, 29681.0, 29704.257393565116, 29743.405131897456, 30179.245518861986, 2062.9484262893397, 2107.6417009108486, 2112.8098471137673, 2117.819985301251, 2118.66101423773, 19236.326728564574, 19731.309404170785, 19757.308494202633, 19795.604087918306, 19842.801571984382, 2376.697018425129, 2421.1646981791255, 2439.1828737373003, 2458.7787099160983, 2461.6307553866886, 135453.0, 136246.0, 136479.999999999, 138335.0, 138510.0, 14414.13515189092, 15084.321205545777, 15233.923830380998, 15450.690986180438, 15631.296591653403, 23402.88298558496, 23894.402639933793, 24293.878530607588, 24330.0, 24403.755962440147, 10418.895811041944, 10573.154147668169, 10801.945990269998, 10846.78306433867, 10852.94573527143, 9769.120779129771, 9771.755706107262, 9885.802283954281, 9913.603455861685, 9933.602655893828, 9222.53887305632, 9526.142647161789, 9596.328257021942, 9605.711828645151, 9651.855222171673, 2658.8803503842373, 2671.6927553331616, 2672.2116975492277, 2677.718839521861, 2690.7712844408443, 952.9714108576753, 977.6431602465049, 989.7875102998913, 990.3265779270022, 1002.3634991780272, 19452.70820937687, 19708.356590383566, 19744.210231590863, 20290.289839855534, 20323.288684895957, 1041.1254546181244, 1056.7146870344964, 1058.4178701714027, 1058.5025038232018, 1058.9099926506178, 10860.022597966063, 10985.61550345734, 11003.17476189289, 11039.282446640942, 11122.61070862516, 458213.0, 460391.0, 474778.0, 485002.0, 487284.0, 114610.42694786639, 116932.41533792447, 117558.0, 119128.0, 120161.0, 1155.6013175454455, 1176.7764124816258, 1198.784218840617, 1206.1797977375293, 1213.362984433162, 17873.553161170556, 17894.821051789317, 18136.539543871888, 18225.54436139054, 18355.908220459347, 55069.724651376484, 55474.722626388226, 55835.72082139563, 56773.43226567681, 57051.0, 70047.02147413933, 70082.0, 70295.64852175706, 71831.64084179545, 72280.63859680668, 14498.202598857164, 14505.347259373142, 14619.19594422317, 14717.190554519177, 14774.33515491827, 21444.892775536024, 21558.6766198504, 21635.891820540797, 21679.0, 21849.890750546147, 17895.73156402629, 18216.180271887533, 18420.35528756487, 18442.72335914989, 18465.53836154107, 16236.350545978028, 16416.589585260463, 16457.25942332574, 16780.66438671244, 17124.229409676343, 17096.48710538686, 18136.455906322302, 18276.543086422946, 18312.90843545774, 18450.72323915169, 14377.85622143807, 14525.128492290496, 14545.272736363351, 14556.344964476411, 14564.781528277295, 17460.825391746428, 17569.121543923007, 17611.911940440215, 17635.647287054442, 17725.64548708993], "value": 12067.4435177832, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.1536047650457, 1418.375914597577, 1427.7715565509507, 1428.9356978935946, 1431.1627697796791, 14578.12531248126, 15331.693366132671, 15335.0, 15339.386424543005, 15349.155796431192, 14701.632459188526, 14841.851581484181, 14849.1090534568, 14852.777208341884, 14862.405503779863, 2666.4533770576986, 2793.748562629363, 2795.6086147939304, 2802.2293869185933, 2804.5092108880967, 2699.6085567592686, 2762.4475104978983, 2835.3053501892064, 2840.2757296889263, 2844.6159768431307, 2698.136596289188, 2731.508328500869, 2734.3847634282324, 2735.220462168282, 2737.7262273772662, 2716.375233696251, 2736.6852811926583, 2751.2296556964084, 2756.8208066475813, 2766.695663477016, 2712.620233167358, 2760.0, 2775.9583606245924, 2777.05580102764, 2779.277387879146, 14432.71134577313, 14837.851621483755, 14888.702225955529, 14937.551873443814, 14963.177025263607, 7963.522188668699, 8250.174982501761, 8261.297789687884, 8285.50286982783, 8305.750827475125, 5245.31810864589, 5576.525995290405, 5644.858878528029, 5698.772049118072, 5724.16999535065, 1824.6259516799157, 1871.419859843453, 1893.5856782602154, 1940.2239104358227, 1950.1711772496633, 25758.227253182245, 25872.74127258741, 25946.481070378675, 26150.73849261521, 26200.86899565509, 6898.896516552256, 6951.270116637781, 6995.370416662525, 7081.822954426078, 7139.92860071403, 17044.573885652957, 17080.316787328367, 17177.0, 17200.31198752061, 17261.56846078858, 7804.843903121906, 8137.633806478722, 8144.552049637208, 8156.062052863878, 8173.591320433955, 7222.277772222736, 7464.477486575996, 7494.21310762373, 7531.924680753232, 7553.509021913558, 5164.122099243138, 5311.654742441729, 5317.521423071865, 5339.626226164131, 5370.0, 1300.2003767682995, 1361.734461779963, 1503.9849601504063, 1545.3509525999107, 1548.6383265520171, 932.9300302477205, 941.110650435332, 941.2187884056217, 951.533748463248, 953.9141477267079, 7811.687532498637, 7881.211878812073, 7970.083440404312, 8019.799505012421, 8024.638891249908, 989.9950500247453, 993.3344659078434, 1004.4274763384947, 1018.1447584029451, 1019.7909428567126, 4736.64475164364, 4764.237721964469, 4821.156297647897, 4851.126797176542, 4921.2864134700985, 20177.798222017886, 21180.0, 21454.24910128138, 21568.676469852966, 21602.0, 20857.791422085887, 20952.89523552403, 21463.46341341479, 21582.460438488848, 21768.673469897964, 11652.417379131179, 11813.763724725457, 11854.229475084092, 11859.46632401544, 11962.940185299018, 8562.657493700182, 8600.268977137013, 8642.092580279232, 8644.308455323433, 8700.78248043804, 17818.376356827448, 18109.63780724352, 18110.637787244446, 18433.90783046076, 18498.260069597065, 18736.250549978395, 19110.80889191146, 19186.328478503186, 19264.0, 19301.32445364462, 10945.616903408343, 10978.231523793578, 11020.50407731638, 11070.5571777128, 11166.609168679057, 13687.315634218448, 13779.035467517579, 13868.514601988882, 13878.306084695523, 13888.791668125188, 11039.282446640942, 11290.943545282222, 11345.092392608736, 11363.37501437429, 11486.195966282614, 11105.888941110808, 11175.608853690406, 11366.659000230007, 11494.425278735862, 11615.0, 9942.552585133542, 10069.395836249849, 10111.494425278854, 10122.544485498023, 10126.987301269814, 7740.535567865946, 7755.806104847424, 7774.3003129716335, 7797.0, 7838.529688218725, 7329.450291228286, 7479.551226926402, 7491.887621685788, 7551.395888329031, 7567.0], "value": 6728.692668842757, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.31228497670645, 529.1507131054659, 534.4314437127031, 543.2801537962199, 544.3467838593687, 6306.486443253621, 6348.016057511093, 6392.808215753535, 6453.7741179058685, 6487.286398496168, 6181.134641150243, 6222.5021998240145, 6237.220347456568, 6277.372262773721, 6281.466075383586, 5201.843944681666, 5248.737563121847, 5251.50995898507, 5253.711045892474, 5261.263423120766, 4903.68126071806, 5222.138347172712, 5236.738163091849, 5266.289050978107, 5294.338207724035, 5000.19996800512, 5030.346055012861, 5069.670471419365, 5080.009398167359, 5091.439941606421, 5028.7485625718755, 5049.444561098277, 5082.847514574569, 5096.362954630671, 5146.095944500559, 4552.248878934972, 4596.678677267958, 4598.494165641777, 4620.792064357079, 4634.490206077328, 6277.748890044393, 6280.0, 6286.9056964145575, 6313.715882785285, 6318.842028949267, 5869.354371019184, 5878.970605146947, 5888.499477544414, 5928.347881733006, 5954.255718035246, 6563.671816409161, 6590.835229119262, 6639.800805975829, 6730.966345168292, 6751.864962700718, 4982.202847544376, 4983.476734942851, 4994.500549945013, 4999.175136102539, 5006.848424862265, 6716.764913228062, 6888.758893438706, 6983.895241571431, 7009.649517524154, 7023.89464158038, 5149.6910185389, 5168.8449346519665, 5169.896602067937, 5179.19722443022, 5188.351456067991, 5163.767630456563, 5203.569018519937, 5217.217417387422, 5247.501487358695, 5264.131418315935, 5774.88450230993, 5785.479306862402, 5807.912881306784, 5842.8539286517325, 5858.5898987070495, 6734.1278764709505, 6740.561863478858, 6744.325567443217, 6747.696353664097, 6807.897881531781, 1782.9910850445665, 1812.6465339258982, 1816.2553353125156, 1818.4453741608936, 1837.1824538080425, 1208.6926658017187, 1214.5870404062662, 1253.1729058821134, 1258.936198911918, 1269.181378011196, 5737.799177028784, 5742.368339482654, 5752.0, 5762.769489220468, 5814.622049566765, 6763.289854565298, 6856.177258728984, 6910.585364878123, 6914.55055421396, 6941.403477200171, 5739.426057394311, 5751.913721294184, 5767.596268261265, 5777.248957635565, 5778.237637520512, 3458.2564748578675, 3536.504889315498, 3597.686844301811, 3631.7094632429344, 3638.2541578976247, 6560.212774467094, 6581.638009909405, 6585.703643335965, 6611.570247933965, 6642.243468960424, 7033.683484243119, 7048.541844780073, 7048.859022819515, 7087.89368159478, 7092.503524753218, 6531.771388001397, 6548.541602087904, 6586.637734924625, 6634.947923954938, 6697.129373181553, 6699.196096468455, 6830.31696830313, 6837.9316206839285, 6886.655667216519, 6936.0, 6805.9659701701175, 6818.965905170442, 6826.726930922708, 6866.862662746617, 6899.517033807585, 6565.982272747732, 6574.967125164344, 6639.435647969976, 6644.335566443317, 6661.467082633475, 5102.285680004877, 5110.105731496898, 5125.666831655931, 5131.204663277198, 5146.922796157985, 6517.9348206517325, 6784.423324017514, 6791.363168569977, 6812.727490900308, 6817.829554261183, 6466.256380516301, 6602.481429271198, 6602.7689030883685, 6612.437942774918, 6637.668116594247, 6595.406413422815, 6609.0, 6625.933740662532, 6630.569013014138, 6696.933030669631, 6391.265004524354, 6416.197975253093, 6418.614883107029, 6493.610383377012, 6498.512611554247, 6574.567410058309, 6585.769498067736, 6631.535792494479, 6682.131322928182, 6747.628880411625, 6306.747730090745, 6420.597083545728, 6451.870962580816, 6483.513736469878, 6524.67376631157], "value": 5159.518136126023, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [917.5045475443261, 917.5870858113847, 934.7429456899356, 935.0836180543064, 948.0472125513858, 16316.59208519786, 17509.124543772825, 17577.209025593846, 22281.220157294498, 23634.290971270846, 20923.790762092376, 20990.89504552479, 21140.788592114073, 21693.891530542325, 22044.33866983989, 957.5020989085672, 984.517586382671, 988.4859872866106, 989.2234595842265, 991.415065111584, 950.6340059077236, 986.3687240166299, 991.1179050644931, 991.2714155096035, 993.3195760903768, 1050.6007717067503, 1053.3574519543072, 1053.4995876958421, 1054.520193312045, 1055.7730088031071, 1022.2435397805644, 1023.9948800255988, 1028.2236911132086, 1030.5826140413108, 1031.092638478142, 752.9171791102974, 753.8756105242628, 754.8339365339616, 754.9282818132268, 756.8751156059244, 19073.523161920923, 19820.207191712314, 19933.601327973505, 19984.80015199844, 19996.600067998705, 22249.22127725538, 22261.109555617917, 22531.0, 22538.77461225383, 22545.436364090863, 18452.815471845242, 18457.353992610195, 18515.44453666379, 18527.814721852745, 18566.62866742671, 3703.2038111805823, 3783.735138540303, 3810.771353718758, 3817.3701339278987, 3843.404272337792, 143020.28489857484, 143131.28434357865, 144514.0, 146739.0, 147660.26169869083, 17101.0, 17139.314427423014, 17342.393016244372, 17461.82538174602, 17624.823751762317, 32288.35423291568, 32388.190295242806, 32487.35025299481, 32530.0, 32548.349033019207, 8978.730638080868, 9006.684766033288, 9034.367594268339, 9086.227670647937, 9141.497217652959, 4239.42767726353, 4345.196138714348, 4399.560043995575, 4408.809621402268, 4461.330800379969, 5476.91784623231, 5519.089350257163, 5561.4716601922755, 5608.49523542875, 5630.943690563042, 1059.0891833023593, 1092.7049696582037, 1102.0522350778324, 1104.5140138339266, 1105.635140403681, 648.7275344355382, 652.1912828093225, 652.9477641788645, 654.2247436787422, 694.2848865668369, 12072.275663460045, 12417.0, 12917.418716157796, 13101.868981310065, 13157.144785588906, 639.7824739588563, 643.9420452159328, 648.8377905523619, 678.8947713104477, 690.7962151165418, 5767.279090113736, 5826.271716035496, 5855.151003104526, 5913.319968203712, 5922.873945639594, 123633.0, 127179.0, 127280.8179795494, 130119.34940325427, 130759.34620326837, 109209.0, 110935.0, 111178.0, 112672.0, 116256.41871790586, 90323.0, 91352.0, 94938.52530737303, 96667.99999999859, 96898.0, 10758.354498730101, 11102.777944440904, 11128.721781955515, 11258.437078145897, 11377.601783937522, 42669.35995960125, 42768.57231427646, 43744.343834841864, 45198.32202516899, 45367.0, 78015.0, 79511.0, 81559.7766033498, 83362.16637833786, 85994.57002714946, 19330.13014414383, 19437.8056219436, 19508.0, 19637.901810490857, 19648.508787280432, 20050.799492005477, 20082.0, 20179.59640807205, 20311.0, 21741.0, 18537.44387668372, 18576.44270671882, 18587.163577638767, 18872.716909246647, 20688.68966965527, 18916.14877330551, 18983.810161898204, 19455.221791128195, 19567.315143969892, 20219.0, 18168.454946351103, 19330.8066919329, 19437.805621944164, 19697.704534432283, 19745.506362341057, 13196.14225075367, 13649.658758531115, 13657.0, 13704.862951370758, 13706.588802335946, 14423.639409014859, 14658.340374682952, 14799.334029968893, 14893.702125957638, 14920.253987300375], "value": 9545.100744795336, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1411.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 105.0, 110.0, 108.0, 106.0, 106.0, 106.0, 105.0, 106.0], "value": 106.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 117.0, 121.0, 116.0, 121.0, 122.0, 150.0, 116.0, 119.0, 117.0], "value": 122.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 162.0, 164.0, 164.0, 165.0, 163.0, 166.0, 172.0, 165.0], "value": 164.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 75.0, 73.0, 83.0, 74.0, 74.0, 85.0, 75.0], "value": 76.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 78.0, 79.0, 79.0, 78.0, 79.0, 78.0, 78.0, 80.0], "value": 78.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 111.0, 111.0, 112.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 112.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 148.0, 148.0, 148.0, 149.0, 149.0, 148.0, 149.0, 148.0, 148.0], "value": 148.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 99.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 54.0, 57.0, 53.0, 54.0, 51.0, 51.0, 52.0, 52.0, 50.0], "value": 52.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [36.0, 37.0, 36.0, 37.0, 37.0, 37.0, 37.0, 37.0, 38.0, 37.0], "value": 36.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 83.0, 73.0, 75.0, 73.0, 78.0, 75.0, 77.0, 74.0, 81.0], "value": 76.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 122.0, 112.0, 110.0, 110.0, 120.0, 112.0, 110.0, 111.0, 122.0], "value": 113.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 160.0, 160.0, 161.0, 159.0, 162.0, 159.0, 164.0, 169.0, 170.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 64.0, 62.0, 65.0, 65.0, 64.0, 63.0, 70.0, 65.0], "value": 64.7, "unit": "score"}]}]} 03:17:54 INFO - Return code: 0 03:17:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:17:54 INFO - # TBPL SUCCESS # 03:17:54 INFO - Running post-action listener: _package_coverage_data 03:17:54 INFO - Running post-action listener: _resource_record_post_action 03:17:54 INFO - [mozharness: 2017-10-02 10:17:54.365776Z] Finished run-tests step (success) 03:17:54 INFO - Running post-run listener: _resource_record_post_run 03:17:54 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 0; Write bytes: 334290944; Read time: 0; Write time: 310612 03:17:54 INFO - TinderboxPrint: CPU usage
13.1% 03:17:54 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 03:17:54 INFO - TinderboxPrint: I/O write bytes / time
334,290,944 / 310,612 03:17:54 INFO - TinderboxPrint: CPU idle
8,234.3 (86.9%) 03:17:54 INFO - TinderboxPrint: CPU user
1,193.8 (12.6%) 03:17:54 INFO - TinderboxPrint: Swap in / out
0 / 0 03:17:54 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12718080; Read time: 0; Write time: 6572 03:17:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:17:54 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 319787008; Read time: 0; Write time: 303452 03:17:55 INFO - Running post-run listener: _upload_blobber_files 03:17:55 INFO - Blob upload gear active. 03:17:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:17:55 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 03:17:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:17:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:17:55 INFO - (blobuploader) - INFO - Open directory for files ... 03:17:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:17:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:17:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:56 INFO - (blobuploader) - INFO - Done attempting. 03:17:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:17:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:17:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:57 INFO - (blobuploader) - INFO - Done attempting. 03:17:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:17:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:17:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:58 INFO - (blobuploader) - INFO - Done attempting. 03:17:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:17:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:18:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:05 INFO - (blobuploader) - INFO - Done attempting. 03:18:05 INFO - (blobuploader) - INFO - Iteration through files over. 03:18:05 INFO - Return code: 0 03:18:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:18:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:18:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a6c7ac0d2ab4f1b32adddcbcce383de8a64adbe22bab45655e54c69d9bab43007ffc5a49ca5463e121c2ef317db8f319cd980a7976dea2e1047f404b00ef2db8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b9b336cbf9979968d8d0b2a767dd14fcf9094c9204177fc4a96b519a5c5d0116de290900662a472e463a6b51b010dacb89c2f80b0cc33164b82d1654f562e0b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84a7cd973e2f5c6ea7b7f4c9f19486374beaf63cc498f3b4fc7bdec833eb1f6ef2a1446adba51d22282e6048da1523143ee91afede0b7794bcb96226bb964701", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c7a87ef3dc0eb6154b39574beff6c1b0974f8461f3bf6bf2ee498165aaa8853728ad3936ad42f7e45026efe2e06498cb3f617556b4169750afa459bcc5480f2"} 03:18:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:18:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:18:05 INFO - Contents: 03:18:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a6c7ac0d2ab4f1b32adddcbcce383de8a64adbe22bab45655e54c69d9bab43007ffc5a49ca5463e121c2ef317db8f319cd980a7976dea2e1047f404b00ef2db8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b9b336cbf9979968d8d0b2a767dd14fcf9094c9204177fc4a96b519a5c5d0116de290900662a472e463a6b51b010dacb89c2f80b0cc33164b82d1654f562e0b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84a7cd973e2f5c6ea7b7f4c9f19486374beaf63cc498f3b4fc7bdec833eb1f6ef2a1446adba51d22282e6048da1523143ee91afede0b7794bcb96226bb964701", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c7a87ef3dc0eb6154b39574beff6c1b0974f8461f3bf6bf2ee498165aaa8853728ad3936ad42f7e45026efe2e06498cb3f617556b4169750afa459bcc5480f2"} 03:18:05 INFO - Running post-run listener: copy_logs_to_upload_dir 03:18:05 INFO - Copying logs to upload dir... 03:18:05 INFO - mkdir: /builds/slave/test/build/upload/logs 03:18:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.177566 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2017-10-02 03:18:05.257034) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 03:18:05.261127) ========= 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=781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303 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-Non-PGO/sha512/a6c7ac0d2ab4f1b32adddcbcce383de8a64adbe22bab45655e54c69d9bab43007ffc5a49ca5463e121c2ef317db8f319cd980a7976dea2e1047f404b00ef2db8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b9b336cbf9979968d8d0b2a767dd14fcf9094c9204177fc4a96b519a5c5d0116de290900662a472e463a6b51b010dacb89c2f80b0cc33164b82d1654f562e0b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84a7cd973e2f5c6ea7b7f4c9f19486374beaf63cc498f3b4fc7bdec833eb1f6ef2a1446adba51d22282e6048da1523143ee91afede0b7794bcb96226bb964701", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c7a87ef3dc0eb6154b39574beff6c1b0974f8461f3bf6bf2ee498165aaa8853728ad3936ad42f7e45026efe2e06498cb3f617556b4169750afa459bcc5480f2"} build_url:https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011381 build_url: 'https://queue.taskcluster.net/v1/task/PYtAjz9HRZeMO2pXV7pH9g/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a6c7ac0d2ab4f1b32adddcbcce383de8a64adbe22bab45655e54c69d9bab43007ffc5a49ca5463e121c2ef317db8f319cd980a7976dea2e1047f404b00ef2db8", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9b9b336cbf9979968d8d0b2a767dd14fcf9094c9204177fc4a96b519a5c5d0116de290900662a472e463a6b51b010dacb89c2f80b0cc33164b82d1654f562e0b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/84a7cd973e2f5c6ea7b7f4c9f19486374beaf63cc498f3b4fc7bdec833eb1f6ef2a1446adba51d22282e6048da1523143ee91afede0b7794bcb96226bb964701", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c7a87ef3dc0eb6154b39574beff6c1b0974f8461f3bf6bf2ee498165aaa8853728ad3936ad42f7e45026efe2e06498cb3f617556b4169750afa459bcc5480f2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-02 03:18:05.291222) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 03:18:05.291540) ========= 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=781b77c4c33858b1f599d705000001c1-1506937508.415067-1052742303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004612 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-02 03:18:05.374592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 03:18:05.376930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-02 03:18:05.377257) ========= ========= Total master_lag: 0.30 =========