builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-059 starttime: 1506638856.85 results: success (0) revision: 307a7a34013060a6a1e87dfbb911f058d0781a2e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:36.851565) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:36.851911) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:36.852176) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:36.872878) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:36.873131) ========= 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=3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-28 15:47:36-- 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% 39.5M=0s 2017-09-28 15:47:36 (39.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126899 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:37.016720) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:37.016994) ========= 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=3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021450 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:37.065915) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:37.066202) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 307a7a34013060a6a1e87dfbb911f058d0781a2e --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 307a7a34013060a6a1e87dfbb911f058d0781a2e --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=3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-28 15:47:37,108 truncating revision to first 12 chars 2017-09-28 15:47:37,108 Setting DEBUG logging. 2017-09-28 15:47:37,108 attempt 1/10 2017-09-28 15:47:37,109 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/307a7a340130?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-28 15:47:37,728 unpacking tar archive at: mozilla-central-307a7a340130/testing/mozharness/ program finished with exit code 0 elapsedTime=0.915444 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:38.000179) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:38.000478) ========= script_repo_revision: 307a7a34013060a6a1e87dfbb911f058d0781a2e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:38.003875) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:38.004136) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-28 15:47:38.021891) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-28 15:47:38.022154) ========= /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=3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:47:38 INFO - MultiFileLogger online at 20170928 15:47:38 in /builds/slave/test 15:47:38 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 15:47:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:47:38 INFO - {'append_to_log': False, 15:47:38 INFO - 'base_work_dir': '/builds/slave/test', 15:47:38 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:47:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:47:38 INFO - 'branch': 'Firefox-Non-PGO', 15:47:38 INFO - 'buildbot_json_path': 'buildprops.json', 15:47:38 INFO - 'code_coverage': False, 15:47:38 INFO - 'config_files': ('talos/linux_config.py',), 15:47:38 INFO - 'default_actions': ('clobber', 15:47:38 INFO - 'read-buildbot-config', 15:47:38 INFO - 'download-and-extract', 15:47:38 INFO - 'populate-webroot', 15:47:38 INFO - 'create-virtualenv', 15:47:38 INFO - 'install', 15:47:38 INFO - 'setup-mitmproxy', 15:47:38 INFO - 'run-tests'), 15:47:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:47:38 INFO - 'disable_ccov_upload': False, 15:47:38 INFO - 'disable_stylo': False, 15:47:38 INFO - 'download_minidump_stackwalk': True, 15:47:38 INFO - 'download_symbols': 'ondemand', 15:47:38 INFO - 'enable_stylo': False, 15:47:38 INFO - 'enable_webrender': False, 15:47:38 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:47:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:47:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:47:38 INFO - 'gecko_profile': False, 15:47:38 INFO - 'gecko_profile_interval': 0, 15:47:38 INFO - 'installer_path': 'installer.exe', 15:47:38 INFO - 'log_level': 'info', 15:47:38 INFO - 'log_name': 'talos', 15:47:38 INFO - 'log_to_console': True, 15:47:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:47:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:47:38 INFO - 'opt_config_files': (), 15:47:38 INFO - 'pip_index': False, 15:47:38 INFO - 'suite': 'dromaeojs-e10s', 15:47:38 INFO - 'system_bits': '32', 15:47:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:47:38 INFO - 'title': 'talos-linux64-ix-059', 15:47:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:47:38 INFO - 'use_talos_json': True, 15:47:38 INFO - 'verify': 'False', 15:47:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:47:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:47:38 INFO - 'work_dir': 'build'} 15:47:38 INFO - [mozharness: 2017-09-28 22:47:38.203709Z] Running clobber step. 15:47:38 INFO - Running pre-action listener: _resource_record_pre_action 15:47:38 INFO - Running main action method: clobber 15:47:38 INFO - rmtree: /builds/slave/test/build 15:47:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:47:39 INFO - Running post-action listener: _resource_record_post_action 15:47:39 INFO - [mozharness: 2017-09-28 22:47:39.208798Z] Finished clobber step (success) 15:47:39 INFO - [mozharness: 2017-09-28 22:47:39.208895Z] Running read-buildbot-config step. 15:47:39 INFO - Running pre-action listener: _resource_record_pre_action 15:47:39 INFO - Running main action method: read_buildbot_config 15:47:39 INFO - Using buildbot properties: 15:47:39 INFO - { 15:47:39 INFO - "project": "", 15:47:39 INFO - "product": "firefox", 15:47:39 INFO - "who": "ryanvm@gmail.com", 15:47:39 INFO - "installer_path": "public/build/target.tar.bz2", 15:47:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:47:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:47:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:47:39 INFO - "stage_platform": "linux64", 15:47:39 INFO - "basedir": "/builds/slave/test", 15:47:39 INFO - "buildnumber": 879, 15:47:39 INFO - "platform": "ubuntu64_hw", 15:47:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:47:39 INFO - "slavebuilddir": "test", 15:47:39 INFO - "taskId": "Qr6LTH0ZSe6R0LzFV5nVOA", 15:47:39 INFO - "branch": "mozilla-central", 15:47:39 INFO - "script_repo_revision": "production", 15:47:39 INFO - "revision": "307a7a34013060a6a1e87dfbb911f058d0781a2e", 15:47:39 INFO - "slavename": "talos-linux64-ix-059", 15:47:39 INFO - "repo_path": "mozilla-central" 15:47:39 INFO - } 15:47:39 INFO - Finding installer, test and symbols from parent task. 15:47:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qr6LTH0ZSe6R0LzFV5nVOA'}, attempt #1 15:47:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Qr6LTH0ZSe6R0LzFV5nVOA'}, attempt #1 15:47:40 INFO - Task dependencies: CK_o3hL6TtqucDlJLtKu5Q 15:47:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q'}, attempt #1 15:47:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.tar.bz2 15:47:40 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.test_packages.json 15:47:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.crashreporter-symbols.zip 15:47:40 INFO - Running post-action listener: _resource_record_post_action 15:47:40 INFO - [mozharness: 2017-09-28 22:47:40.696792Z] Finished read-buildbot-config step (success) 15:47:40 INFO - [mozharness: 2017-09-28 22:47:40.697079Z] Running download-and-extract step. 15:47:40 INFO - Running pre-action listener: _resource_record_pre_action 15:47:40 INFO - Running main action method: download_and_extract 15:47:40 INFO - mkdir: /builds/slave/test/build/tests 15:47:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:47:40 INFO - trying https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.test_packages.json 15:47:40 INFO - Downloading https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:47:41 INFO - Downloaded 989 bytes. 15:47:41 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:47:41 INFO - Using the following test package requirements: 15:47:41 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:47:41 INFO - u'common': [u'target.common.tests.zip'], 15:47:41 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:47:41 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:47:41 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:47:41 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:47:41 INFO - u'mozbase': [u'target.common.tests.zip'], 15:47:41 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:47:41 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:47:41 INFO - u'web-platform': [u'target.common.tests.zip', 15:47:41 INFO - u'target.web-platform.tests.tar.gz'], 15:47:41 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:47:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:47:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.common.tests.zip 15:47:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:47:41 INFO - Fetch https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.common.tests.zip into memory 15:47:45 INFO - Content-Length response header: 55262926 15:47:45 INFO - Bytes received: 55262926 15:47:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.talos.tests.zip 15:47:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:47:55 INFO - Fetch https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.talos.tests.zip into memory 15:47:57 INFO - Content-Length response header: 14521162 15:47:57 INFO - Bytes received: 14521162 15:47:57 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')]} 15:47:57 INFO - trying https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.tar.bz2 15:47:57 INFO - Downloading https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:47:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:48:01 INFO - Downloaded 61687896 bytes. 15:48:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.tar.bz2 15:48:01 INFO - mkdir: /builds/slave/test/properties 15:48:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:48:01 INFO - Writing to file /builds/slave/test/properties/build_url 15:48:01 INFO - Contents: 15:48:01 INFO - build_url:https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.tar.bz2 15:48:01 INFO - Running post-action listener: _resource_record_post_action 15:48:01 INFO - Running post-action listener: find_tests_for_verification 15:48:01 INFO - Running post-action listener: set_extra_try_arguments 15:48:01 INFO - [mozharness: 2017-09-28 22:48:01.442140Z] Finished download-and-extract step (success) 15:48:01 INFO - [mozharness: 2017-09-28 22:48:01.442263Z] Running populate-webroot step. 15:48:01 INFO - Running pre-action listener: _resource_record_pre_action 15:48:01 INFO - Running main action method: populate_webroot 15:48:01 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:48:01 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['tresize', 15:48:01 INFO - 'tcanvasmark']}, 15:48:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:48:01 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['dromaeo_css', 15:48:01 INFO - 'kraken']}, 15:48:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:48:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:01 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:01 INFO - 'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:48:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:48:01 INFO - 'tests': ['damp', 'tps']}, 15:48:01 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:01 INFO - 'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['damp', 'tps']}, 15:48:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:48:01 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['dromaeo_dom']}, 15:48:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:48:01 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['basic_compositor_video', 15:48:01 INFO - 'glvideo']}, 15:48:01 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:48:01 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:48:01 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:01 INFO - 'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['ts_paint_webext', 15:48:01 INFO - 'tp5o_webext']}, 15:48:01 INFO - 'other-e10s': {'tests': ['a11yr', 15:48:01 INFO - 'ts_paint', 15:48:01 INFO - 'tpaint', 15:48:01 INFO - 'sessionrestore', 15:48:01 INFO - 'sessionrestore_many_windows', 15:48:01 INFO - 'sessionrestore_no_auto_restore', 15:48:01 INFO - 'tabpaint', 15:48:01 INFO - 'cpstartup']}, 15:48:01 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['a11yr', 15:48:01 INFO - 'ts_paint', 15:48:01 INFO - 'tpaint', 15:48:01 INFO - 'sessionrestore', 15:48:01 INFO - 'sessionrestore_many_windows', 15:48:01 INFO - 'sessionrestore_no_auto_restore', 15:48:01 INFO - 'tabpaint', 15:48:01 INFO - 'cpstartup']}, 15:48:01 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 15:48:01 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:48:01 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['perf_reftest_singletons']}, 15:48:01 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['perf_reftest']}, 15:48:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:48:01 INFO - 'tsvgr_opacity', 15:48:01 INFO - 'tart', 15:48:01 INFO - 'tscrollx', 15:48:01 INFO - 'tsvg_static']}, 15:48:01 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['tsvgx', 15:48:01 INFO - 'tsvgr_opacity', 15:48:01 INFO - 'tart', 15:48:01 INFO - 'tscrollx', 15:48:01 INFO - 'tsvg_static']}, 15:48:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:48:01 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:01 INFO - 'talos_options': ['--disable-stylo'], 15:48:01 INFO - 'tests': ['tp5o']}, 15:48:01 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:48:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:48:01 INFO - 'talos_options': ['--mitmproxy', 15:48:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:48:01 INFO - '--firstNonBlankPaint'], 15:48:01 INFO - 'tests': ['tp6_google', 15:48:01 INFO - 'tp6_youtube', 15:48:01 INFO - 'tp6_amazon', 15:48:01 INFO - 'tp6_facebook']}, 15:48:01 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:48:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:48:01 INFO - 'talos_options': ['--disable-stylo', 15:48:01 INFO - '--mitmproxy', 15:48:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:48:01 INFO - '--firstNonBlankPaint'], 15:48:01 INFO - 'tests': ['tp6_google', 15:48:01 INFO - 'tp6_youtube', 15:48:01 INFO - 'tp6_amazon', 15:48:01 INFO - 'tp6_facebook']}, 15:48:01 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:48:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:48:01 INFO - 'talos_options': ['--stylo-threads=1', 15:48:01 INFO - '--mitmproxy', 15:48:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:48:01 INFO - '--firstNonBlankPaint'], 15:48:01 INFO - 'tests': ['tp6_google', 15:48:01 INFO - 'tp6_youtube', 15:48:01 INFO - 'tp6_amazon', 15:48:01 INFO - 'tp6_facebook']}, 15:48:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:48:01 INFO - 'talos_options': ['--xperf_path', 15:48:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:01 INFO - 'tests': ['tp5n']}, 15:48:01 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:48:01 INFO - 'talos_options': ['--disable-stylo', 15:48:01 INFO - '--xperf_path', 15:48:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:48:01 INFO - 'tests': ['tp5n']}}} 15:48:01 INFO - Running post-action listener: _resource_record_post_action 15:48:01 INFO - [mozharness: 2017-09-28 22:48:01.476056Z] Finished populate-webroot step (success) 15:48:01 INFO - [mozharness: 2017-09-28 22:48:01.476163Z] Running create-virtualenv step. 15:48:01 INFO - Running pre-action listener: _resource_record_pre_action 15:48:01 INFO - Running main action method: create_virtualenv 15:48:01 INFO - Creating virtualenv /builds/slave/test/build/venv 15:48:01 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:48:01 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:48:01 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:48:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:48:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:48:02 INFO - Installing distribute.............................................................................................................................................................................................done. 15:48:02 INFO - Installing pip...............done. 15:48:02 INFO - Return code: 0 15:48:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:48:02 INFO - Reading from file tmpfile_stdout 15:48:02 INFO - Output received: 15:48:02 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:48:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:48:02 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')]} 15:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:02 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')]} 15:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:02 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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:02 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 15:48:02 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 15:48:02 INFO - Using env: {'DISPLAY': ':0', 15:48:02 INFO - 'HOME': '/home/cltbld', 15:48:02 INFO - 'LANG': 'en_US.UTF-8', 15:48:02 INFO - 'LANGUAGE': 'en_US:en', 15:48:02 INFO - 'LOGNAME': 'cltbld', 15:48:02 INFO - 'MAIL': '/var/mail/cltbld', 15:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:02 INFO - 'MOZ_NO_REMOTE': '1', 15:48:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:02 INFO - 'NO_EM_RESTART': '1', 15:48:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:02 INFO - 'PWD': '/builds/slave/test', 15:48:02 INFO - 'SHELL': '/bin/bash', 15:48:02 INFO - 'SHLVL': '1', 15:48:02 INFO - 'TERM': 'linux', 15:48:02 INFO - 'TMOUT': '86400', 15:48:02 INFO - 'USER': 'cltbld', 15:48:02 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:02 INFO - '_': '/tools/buildbot/bin/python'} 15:48:03 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:48:03 INFO - Downloading/unpacking pip>=1.5 15:48:03 INFO - Running setup.py egg_info for package pip 15:48:03 INFO - warning: no files found matching 'pip/cacert.pem' 15:48:03 INFO - warning: no files found matching '*.html' under directory 'docs' 15:48:03 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:48:03 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:48:03 INFO - Installing collected packages: pip 15:48:03 INFO - Found existing installation: pip 1.1 15:48:03 INFO - Uninstalling pip: 15:48:03 INFO - Successfully uninstalled pip 15:48:03 INFO - Running setup.py install for pip 15:48:03 INFO - warning: no files found matching 'pip/cacert.pem' 15:48:03 INFO - warning: no files found matching '*.html' under directory 'docs' 15:48:03 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:48:03 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:48:03 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:48:03 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:48:03 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:48:03 INFO - Successfully installed pip 15:48:03 INFO - Cleaning up... 15:48:03 INFO - Return code: 0 15:48:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:03 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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:03 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 15:48:03 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 15:48:03 INFO - Using env: {'DISPLAY': ':0', 15:48:03 INFO - 'HOME': '/home/cltbld', 15:48:03 INFO - 'LANG': 'en_US.UTF-8', 15:48:03 INFO - 'LANGUAGE': 'en_US:en', 15:48:03 INFO - 'LOGNAME': 'cltbld', 15:48:03 INFO - 'MAIL': '/var/mail/cltbld', 15:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:03 INFO - 'MOZ_NO_REMOTE': '1', 15:48:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:03 INFO - 'NO_EM_RESTART': '1', 15:48:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:03 INFO - 'PWD': '/builds/slave/test', 15:48:03 INFO - 'SHELL': '/bin/bash', 15:48:03 INFO - 'SHLVL': '1', 15:48:03 INFO - 'TERM': 'linux', 15:48:03 INFO - 'TMOUT': '86400', 15:48:03 INFO - 'USER': 'cltbld', 15:48:03 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:03 INFO - '_': '/tools/buildbot/bin/python'} 15:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:03 INFO - Downloading/unpacking psutil>=3.1.1 15:48:03 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 15:48:03 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 15:48:03 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 15:48:03 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 15:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:48:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:05 INFO - Installing collected packages: psutil 15:48:05 INFO - Running setup.py install for psutil 15:48:05 INFO - building 'psutil._psutil_linux' extension 15:48:05 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 15:48:05 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 15:48:05 INFO - building 'psutil._psutil_posix' extension 15:48:05 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 15:48:05 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 15:48:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:48:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:48:05 INFO - Successfully installed psutil 15:48:05 INFO - Cleaning up... 15:48:05 INFO - Return code: 0 15:48:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:48:05 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')]} 15:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:05 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')]} 15:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:05 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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:05 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 15:48:05 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 15:48:05 INFO - Using env: {'DISPLAY': ':0', 15:48:05 INFO - 'HOME': '/home/cltbld', 15:48:05 INFO - 'LANG': 'en_US.UTF-8', 15:48:05 INFO - 'LANGUAGE': 'en_US:en', 15:48:05 INFO - 'LOGNAME': 'cltbld', 15:48:05 INFO - 'MAIL': '/var/mail/cltbld', 15:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:05 INFO - 'MOZ_NO_REMOTE': '1', 15:48:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:05 INFO - 'NO_EM_RESTART': '1', 15:48:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:05 INFO - 'PWD': '/builds/slave/test', 15:48:05 INFO - 'SHELL': '/bin/bash', 15:48:05 INFO - 'SHLVL': '1', 15:48:05 INFO - 'TERM': 'linux', 15:48:05 INFO - 'TMOUT': '86400', 15:48:05 INFO - 'USER': 'cltbld', 15:48:05 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:05 INFO - '_': '/tools/buildbot/bin/python'} 15:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:48:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:48:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:48:07 INFO - Installing collected packages: mozsystemmonitor 15:48:07 INFO - Running setup.py install for mozsystemmonitor 15:48:07 INFO - Successfully installed mozsystemmonitor 15:48:07 INFO - Cleaning up... 15:48:07 INFO - Return code: 0 15:48:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:07 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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:07 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 15:48:07 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 15:48:07 INFO - Using env: {'DISPLAY': ':0', 15:48:07 INFO - 'HOME': '/home/cltbld', 15:48:07 INFO - 'LANG': 'en_US.UTF-8', 15:48:07 INFO - 'LANGUAGE': 'en_US:en', 15:48:07 INFO - 'LOGNAME': 'cltbld', 15:48:07 INFO - 'MAIL': '/var/mail/cltbld', 15:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:07 INFO - 'MOZ_NO_REMOTE': '1', 15:48:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:07 INFO - 'NO_EM_RESTART': '1', 15:48:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:07 INFO - 'PWD': '/builds/slave/test', 15:48:07 INFO - 'SHELL': '/bin/bash', 15:48:07 INFO - 'SHLVL': '1', 15:48:07 INFO - 'TERM': 'linux', 15:48:07 INFO - 'TMOUT': '86400', 15:48:07 INFO - 'USER': 'cltbld', 15:48:07 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:07 INFO - '_': '/tools/buildbot/bin/python'} 15:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:07 INFO - Downloading/unpacking jsonschema==2.5.1 15:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:48:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:48:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:48:08 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 15:48:08 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 15:48:08 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 15:48:08 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 15:48:08 INFO - Downloading functools32-3.2.3-2.tar.gz 15:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:48:08 INFO - warning: no files found matching '*.txt' 15:48:08 INFO - no previously-included directories found matching 'build' 15:48:08 INFO - no previously-included directories found matching 'dist' 15:48:08 INFO - no previously-included directories found matching '.git*' 15:48:08 INFO - Installing collected packages: jsonschema, functools32 15:48:09 INFO - Running setup.py install for functools32 15:48:09 INFO - warning: no files found matching '*.txt' 15:48:09 INFO - no previously-included directories found matching 'build' 15:48:09 INFO - no previously-included directories found matching 'dist' 15:48:09 INFO - no previously-included directories found matching '.git*' 15:48:09 INFO - Successfully installed jsonschema functools32 15:48:09 INFO - Cleaning up... 15:48:09 INFO - Return code: 0 15:48:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:48:09 INFO - Using env: {'DISPLAY': ':0', 15:48:09 INFO - 'HOME': '/home/cltbld', 15:48:09 INFO - 'LANG': 'en_US.UTF-8', 15:48:09 INFO - 'LANGUAGE': 'en_US:en', 15:48:09 INFO - 'LOGNAME': 'cltbld', 15:48:09 INFO - 'MAIL': '/var/mail/cltbld', 15:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:09 INFO - 'MOZ_NO_REMOTE': '1', 15:48:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:09 INFO - 'NO_EM_RESTART': '1', 15:48:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:09 INFO - 'PWD': '/builds/slave/test', 15:48:09 INFO - 'SHELL': '/bin/bash', 15:48:09 INFO - 'SHLVL': '1', 15:48:09 INFO - 'TERM': 'linux', 15:48:09 INFO - 'TMOUT': '86400', 15:48:09 INFO - 'USER': 'cltbld', 15:48:09 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:09 INFO - '_': '/tools/buildbot/bin/python'} 15:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:48:09 INFO - Cleaning up... 15:48:09 INFO - Return code: 0 15:48:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:48:09 INFO - Using env: {'DISPLAY': ':0', 15:48:09 INFO - 'HOME': '/home/cltbld', 15:48:09 INFO - 'LANG': 'en_US.UTF-8', 15:48:09 INFO - 'LANGUAGE': 'en_US:en', 15:48:09 INFO - 'LOGNAME': 'cltbld', 15:48:09 INFO - 'MAIL': '/var/mail/cltbld', 15:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:09 INFO - 'MOZ_NO_REMOTE': '1', 15:48:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:09 INFO - 'NO_EM_RESTART': '1', 15:48:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:09 INFO - 'PWD': '/builds/slave/test', 15:48:09 INFO - 'SHELL': '/bin/bash', 15:48:09 INFO - 'SHLVL': '1', 15:48:09 INFO - 'TERM': 'linux', 15:48:09 INFO - 'TMOUT': '86400', 15:48:09 INFO - 'USER': 'cltbld', 15:48:09 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:09 INFO - '_': '/tools/buildbot/bin/python'} 15:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:09 INFO - Downloading/unpacking blobuploader==1.2.4 15:48:09 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 15:48:09 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 15:48:09 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 15:48:09 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 15:48:11 INFO - Downloading blobuploader-1.2.4.tar.gz 15:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:48:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:48:11 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 15:48:11 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 15:48:11 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 15:48:11 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 15:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:48:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:48:11 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 15:48:11 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 15:48:11 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 15:48:11 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 15:48:11 INFO - Downloading docopt-0.6.1.tar.gz 15:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:48:11 INFO - Installing collected packages: blobuploader, requests, docopt 15:48:11 INFO - Running setup.py install for blobuploader 15:48:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:48:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:48:11 INFO - Running setup.py install for requests 15:48:12 INFO - Running setup.py install for docopt 15:48:12 INFO - Successfully installed blobuploader requests docopt 15:48:12 INFO - Cleaning up... 15:48:12 INFO - Return code: 0 15:48:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:12 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')]} 15:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:12 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')]} 15:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:12 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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:12 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 15:48:12 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 15:48:12 INFO - Using env: {'DISPLAY': ':0', 15:48:12 INFO - 'HOME': '/home/cltbld', 15:48:12 INFO - 'LANG': 'en_US.UTF-8', 15:48:12 INFO - 'LANGUAGE': 'en_US:en', 15:48:12 INFO - 'LOGNAME': 'cltbld', 15:48:12 INFO - 'MAIL': '/var/mail/cltbld', 15:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:12 INFO - 'MOZ_NO_REMOTE': '1', 15:48:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:12 INFO - 'NO_EM_RESTART': '1', 15:48:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:12 INFO - 'PWD': '/builds/slave/test', 15:48:12 INFO - 'SHELL': '/bin/bash', 15:48:12 INFO - 'SHLVL': '1', 15:48:12 INFO - 'TERM': 'linux', 15:48:12 INFO - 'TMOUT': '86400', 15:48:12 INFO - 'USER': 'cltbld', 15:48:12 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:12 INFO - '_': '/tools/buildbot/bin/python'} 15:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:12 INFO - Running setup.py (path:/tmp/pip-uPdkHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:12 INFO - Running setup.py (path:/tmp/pip-cWHe6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:12 INFO - Running setup.py (path:/tmp/pip-Qc1n8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:12 INFO - Running setup.py (path:/tmp/pip-zoWSJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:12 INFO - Running setup.py (path:/tmp/pip-A6VIcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:12 INFO - Running setup.py (path:/tmp/pip-zEgyqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:13 INFO - Running setup.py (path:/tmp/pip-wt3e9c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:13 INFO - Running setup.py (path:/tmp/pip-Gt_Vgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:13 INFO - Running setup.py (path:/tmp/pip-pDhq6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:13 INFO - Running setup.py (path:/tmp/pip-n51XTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:13 INFO - Running setup.py (path:/tmp/pip-aa4ZYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:13 INFO - Running setup.py (path:/tmp/pip-NWit2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:13 INFO - Running setup.py (path:/tmp/pip-ulv7Gr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:13 INFO - Running setup.py (path:/tmp/pip-yrEKNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:13 INFO - Running setup.py (path:/tmp/pip-WWS3zG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:13 INFO - Running setup.py (path:/tmp/pip-OU_SrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:13 INFO - Running setup.py (path:/tmp/pip-2Cp1a0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:48:13 INFO - Running setup.py install for manifestparser 15:48:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:48:13 INFO - Running setup.py install for mozcrash 15:48:14 INFO - Running setup.py install for mozdebug 15:48:14 INFO - Running setup.py install for mozdevice 15:48:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:48:14 INFO - Running setup.py install for mozfile 15:48:14 INFO - Running setup.py install for mozhttpd 15:48:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:48:14 INFO - Running setup.py install for mozinfo 15:48:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:48:14 INFO - Running setup.py install for mozInstall 15:48:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:48:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:48:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:48:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:48:14 INFO - Running setup.py install for mozleak 15:48:14 INFO - Running setup.py install for mozlog 15:48:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:48:14 INFO - Running setup.py install for moznetwork 15:48:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:48:14 INFO - Running setup.py install for mozprocess 15:48:15 INFO - Running setup.py install for mozprofile 15:48:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:48:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:48:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:48:15 INFO - Running setup.py install for mozrunner 15:48:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:48:15 INFO - Running setup.py install for mozscreenshot 15:48:15 INFO - Running setup.py install for moztest 15:48:15 INFO - Running setup.py install for mozversion 15:48:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:48:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:48:15 INFO - Cleaning up... 15:48:15 INFO - Return code: 0 15:48:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:15 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')]} 15:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:15 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')]} 15:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:15 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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:15 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 15:48:15 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 15:48:15 INFO - Using env: {'DISPLAY': ':0', 15:48:15 INFO - 'HOME': '/home/cltbld', 15:48:15 INFO - 'LANG': 'en_US.UTF-8', 15:48:15 INFO - 'LANGUAGE': 'en_US:en', 15:48:15 INFO - 'LOGNAME': 'cltbld', 15:48:15 INFO - 'MAIL': '/var/mail/cltbld', 15:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:15 INFO - 'MOZ_NO_REMOTE': '1', 15:48:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:15 INFO - 'NO_EM_RESTART': '1', 15:48:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:15 INFO - 'PWD': '/builds/slave/test', 15:48:15 INFO - 'SHELL': '/bin/bash', 15:48:15 INFO - 'SHLVL': '1', 15:48:15 INFO - 'TERM': 'linux', 15:48:15 INFO - 'TMOUT': '86400', 15:48:15 INFO - 'USER': 'cltbld', 15:48:15 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:15 INFO - '_': '/tools/buildbot/bin/python'} 15:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:48:15 INFO - Running setup.py (path:/tmp/pip-DbvSnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:48:15 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)) 15:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:48:15 INFO - Running setup.py (path:/tmp/pip-VWQL5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:48:15 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)) 15:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:48:15 INFO - Running setup.py (path:/tmp/pip-cjnIQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:48:16 INFO - Running setup.py (path:/tmp/pip-T81JAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:48:16 INFO - Running setup.py (path:/tmp/pip-70vXXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:48:16 INFO - Running setup.py (path:/tmp/pip-RJ8p0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:48:16 INFO - Running setup.py (path:/tmp/pip-0KLNw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:48:16 INFO - Running setup.py (path:/tmp/pip-E86m_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:48:16 INFO - Running setup.py (path:/tmp/pip-o281pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:48:16 INFO - Running setup.py (path:/tmp/pip-x9labA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:48:16 INFO - Running setup.py (path:/tmp/pip-Rw8QXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:48:16 INFO - Running setup.py (path:/tmp/pip-zLpEog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:48:16 INFO - Running setup.py (path:/tmp/pip-Zfth_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:48:16 INFO - Running setup.py (path:/tmp/pip-1PsmQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:16 INFO - Running setup.py (path:/tmp/pip-27P2KQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:48:16 INFO - Running setup.py (path:/tmp/pip-0EKtKR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:48:16 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)) 15:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:48:16 INFO - Running setup.py (path:/tmp/pip-Mq6bjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:48:17 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)) 15:48:17 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)) 15:48:17 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)) 15:48:17 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)) 15:48:17 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)) 15:48:17 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)) 15:48:17 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)) 15:48:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:48:17 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 15:48:17 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 15:48:17 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 15:48:17 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 15:48:18 INFO - Downloading blessings-1.6.tar.gz 15:48:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:48:18 INFO - Installing collected packages: blessings 15:48:18 INFO - Running setup.py install for blessings 15:48:18 INFO - Successfully installed blessings 15:48:18 INFO - Cleaning up... 15:48:18 INFO - Return code: 0 15:48:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:48:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:18 INFO - Reading from file tmpfile_stdout 15:48:18 INFO - Current package versions: 15:48:18 INFO - argparse == 1.2.1 15:48:18 INFO - blessings == 1.6 15:48:18 INFO - blobuploader == 1.2.4 15:48:18 INFO - docopt == 0.6.1 15:48:18 INFO - functools32 == 3.2.3-2 15:48:18 INFO - jsonschema == 2.5.1 15:48:18 INFO - manifestparser == 1.1 15:48:18 INFO - mozInstall == 1.13 15:48:18 INFO - mozcrash == 1.0 15:48:18 INFO - mozdebug == 0.1 15:48:18 INFO - mozdevice == 0.51 15:48:18 INFO - mozfile == 1.2 15:48:18 INFO - mozhttpd == 0.7 15:48:18 INFO - mozinfo == 0.10 15:48:18 INFO - mozleak == 0.1 15:48:18 INFO - mozlog == 3.5 15:48:18 INFO - moznetwork == 0.27 15:48:18 INFO - mozprocess == 0.25 15:48:18 INFO - mozprofile == 0.28 15:48:18 INFO - mozrunner == 6.13 15:48:18 INFO - mozscreenshot == 0.1 15:48:18 INFO - mozsystemmonitor == 0.3 15:48:18 INFO - moztest == 0.8 15:48:18 INFO - mozversion == 1.4 15:48:18 INFO - psutil == 3.1.1 15:48:18 INFO - requests == 1.2.3 15:48:18 INFO - wsgiref == 0.1.2 15:48:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:48:18 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')]} 15:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:18 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')]} 15:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:18 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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:18 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 15:48:18 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 15:48:18 INFO - Using env: {'DISPLAY': ':0', 15:48:18 INFO - 'HOME': '/home/cltbld', 15:48:18 INFO - 'LANG': 'en_US.UTF-8', 15:48:18 INFO - 'LANGUAGE': 'en_US:en', 15:48:18 INFO - 'LOGNAME': 'cltbld', 15:48:18 INFO - 'MAIL': '/var/mail/cltbld', 15:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:18 INFO - 'MOZ_NO_REMOTE': '1', 15:48:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:18 INFO - 'NO_EM_RESTART': '1', 15:48:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:18 INFO - 'PWD': '/builds/slave/test', 15:48:18 INFO - 'SHELL': '/bin/bash', 15:48:18 INFO - 'SHLVL': '1', 15:48:18 INFO - 'TERM': 'linux', 15:48:18 INFO - 'TMOUT': '86400', 15:48:18 INFO - 'USER': 'cltbld', 15:48:18 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:18 INFO - '_': '/tools/buildbot/bin/python'} 15:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:18 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)) 15:48:18 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)) 15:48:18 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)) 15:48:18 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)) 15:48:18 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)) 15:48:18 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)) 15:48:18 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)) 15:48:18 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)) 15:48:18 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)) 15:48:18 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:48:18 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 15:48:18 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 15:48:18 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 15:48:18 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 15:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:48:20 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)) 15:48:20 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)) 15:48:20 INFO - Installing collected packages: simplejson 15:48:20 INFO - Running setup.py install for simplejson 15:48:20 INFO - building 'simplejson._speedups' extension 15:48:20 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 15:48:20 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 15:48:21 INFO - Successfully installed simplejson 15:48:21 INFO - Cleaning up... 15:48:21 INFO - Return code: 0 15:48:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:48:21 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')]} 15:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:21 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')]} 15:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:21 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 0x18eb1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab3b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c589c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, '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': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', '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 15:48:21 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 15:48:21 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 15:48:21 INFO - Using env: {'DISPLAY': ':0', 15:48:21 INFO - 'HOME': '/home/cltbld', 15:48:21 INFO - 'LANG': 'en_US.UTF-8', 15:48:21 INFO - 'LANGUAGE': 'en_US:en', 15:48:21 INFO - 'LOGNAME': 'cltbld', 15:48:21 INFO - 'MAIL': '/var/mail/cltbld', 15:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:21 INFO - 'MOZ_NO_REMOTE': '1', 15:48:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:21 INFO - 'NO_EM_RESTART': '1', 15:48:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:21 INFO - 'PWD': '/builds/slave/test', 15:48:21 INFO - 'SHELL': '/bin/bash', 15:48:21 INFO - 'SHLVL': '1', 15:48:21 INFO - 'TERM': 'linux', 15:48:21 INFO - 'TMOUT': '86400', 15:48:21 INFO - 'USER': 'cltbld', 15:48:21 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:21 INFO - '_': '/tools/buildbot/bin/python'} 15:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:48:21 INFO - Cleaning up... 15:48:21 INFO - Return code: 0 15:48:21 INFO - Running post-action listener: _resource_record_post_action 15:48:21 INFO - Running post-action listener: _start_resource_monitoring 15:48:21 INFO - Starting resource monitoring. 15:48:21 INFO - [mozharness: 2017-09-28 22:48:21.317293Z] Finished create-virtualenv step (success) 15:48:21 INFO - [mozharness: 2017-09-28 22:48:21.317813Z] Running install step. 15:48:21 INFO - Running pre-action listener: _resource_record_pre_action 15:48:21 INFO - Running main action method: install 15:48:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:48:21 INFO - Reading from file tmpfile_stdout 15:48:21 INFO - Detecting whether we're running mozinstall >=1.0... 15:48:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:48:21 INFO - Reading from file tmpfile_stdout 15:48:21 INFO - Output received: 15:48:21 INFO - Usage: mozinstall [options] installer 15:48:21 INFO - Options: 15:48:21 INFO - -h, --help show this help message and exit 15:48:21 INFO - -d DEST, --destination=DEST 15:48:21 INFO - Directory to install application into. [default: 15:48:21 INFO - "/builds/slave/test"] 15:48:21 INFO - --app=APP Application being installed. [default: firefox] 15:48:21 INFO - mkdir: /builds/slave/test/build/application 15:48:21 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'] 15:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:48:39 INFO - Reading from file tmpfile_stdout 15:48:39 INFO - Output received: 15:48:39 INFO - /builds/slave/test/build/application/firefox/firefox 15:48:39 INFO - Running post-action listener: _resource_record_post_action 15:48:39 INFO - [mozharness: 2017-09-28 22:48:39.446060Z] Finished install step (success) 15:48:39 INFO - [mozharness: 2017-09-28 22:48:39.446309Z] Running setup-mitmproxy step. 15:48:39 INFO - Running pre-action listener: _resource_record_pre_action 15:48:39 INFO - Running main action method: setup_mitmproxy 15:48:39 INFO - Skipping: mitmproxy is not required 15:48:39 INFO - Running post-action listener: _resource_record_post_action 15:48:39 INFO - [mozharness: 2017-09-28 22:48:39.447422Z] Finished setup-mitmproxy step (success) 15:48:39 INFO - [mozharness: 2017-09-28 22:48:39.447655Z] Running run-tests step. 15:48:39 INFO - Running pre-action listener: _resource_record_pre_action 15:48:39 INFO - Running pre-action listener: _set_gcov_prefix 15:48:39 INFO - Running main action method: run_tests 15:48:39 WARNING - Try message not found. 15:48:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:48:39 INFO - Python 2.7.3 15:48:39 INFO - Return code: 0 15:48:39 INFO - grabbing minidump binary from tooltool 15:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:48:39 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 0x1c55d60>, '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 0x1c56a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c56ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:48:39 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 15:48:39 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 15:48:39 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 15:48:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:48:39 INFO - Return code: 0 15:48:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:48:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:48:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:48:39 INFO - ENV: RUST_BACKTRACE is now full 15:48:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:48:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:48:39 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/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 15:48:39 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/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 15:48:39 INFO - Using env: {'DISPLAY': ':0', 15:48:39 INFO - 'HOME': '/home/cltbld', 15:48:39 INFO - 'LANG': 'en_US.UTF-8', 15:48:39 INFO - 'LANGUAGE': 'en_US:en', 15:48:39 INFO - 'LOGNAME': 'cltbld', 15:48:39 INFO - 'MAIL': '/var/mail/cltbld', 15:48:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:48:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:39 INFO - 'MOZ_NO_REMOTE': '1', 15:48:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:48:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:39 INFO - 'NO_EM_RESTART': '1', 15:48:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:39 INFO - 'PWD': '/builds/slave/test', 15:48:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:48:39 INFO - 'RUST_BACKTRACE': 'full', 15:48:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:48:39 INFO - 'SHELL': '/bin/bash', 15:48:39 INFO - 'SHLVL': '1', 15:48:39 INFO - 'TERM': 'linux', 15:48:39 INFO - 'TMOUT': '86400', 15:48:39 INFO - 'USER': 'cltbld', 15:48:39 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:39 INFO - '_': '/tools/buildbot/bin/python'} 15:48:39 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/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 15:48:39 INFO - mozversion application_buildid: 20170928215608 15:48:39 INFO - mozversion application_changeset: 307a7a34013060a6a1e87dfbb911f058d0781a2e 15:48:39 INFO - mozversion application_display_name: Nightly 15:48:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:48:39 INFO - mozversion application_name: Firefox 15:48:39 INFO - mozversion application_remotingname: firefox 15:48:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:48:39 INFO - mozversion application_vendor: Mozilla 15:48:39 INFO - mozversion application_version: 58.0a1 15:48:39 INFO - mozversion platform_buildid: 20170928215608 15:48:39 INFO - mozversion platform_changeset: 307a7a34013060a6a1e87dfbb911f058d0781a2e 15:48:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:48:39 INFO - mozversion platform_version: 58.0a1 15:48:39 INFO - using testdate: 1506638919 15:48:39 INFO - actual date: 1506638919 15:48:39 INFO - starting webserver on 'localhost:34758' 15:48:39 INFO - SUITE-START | Running 2 tests 15:48:39 INFO - TEST-START | dromaeo_css 15:48:39 INFO - Initialising browser for dromaeo_css test... 15:48:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34758/getInfo.html -profile /tmp/tmpxfkNSl/profile 15:48:39 INFO - TEST-INFO | started process 30984 (/builds/slave/test/build/application/firefox/firefox http://localhost:34758/getInfo.html) 15:48:45 INFO - TEST-INFO | 30984: exit 0 15:48:45 INFO - Browser initialized. 15:48:45 INFO - Running cycle 1/1 for dromaeo_css test... 15:48:45 INFO - Using env: {'DISPLAY': ':0', 15:48:45 INFO - 'HOME': '/home/cltbld', 15:48:45 INFO - 'JSGC_DISABLE_POISONING': '1', 15:48:45 INFO - 'LANG': 'en_US.UTF-8', 15:48:45 INFO - 'LANGUAGE': 'en_US:en', 15:48:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:48:45 INFO - 'LOGNAME': 'cltbld', 15:48:45 INFO - 'MAIL': '/var/mail/cltbld', 15:48:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:48:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:48:45 INFO - 'MOZ_CRASHREPORTER': '1', 15:48:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:48:45 INFO - 'MOZ_NO_REMOTE': '1', 15:48:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:48:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:45 INFO - 'NO_EM_RESTART': '1', 15:48:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:45 INFO - 'PWD': '/builds/slave/test', 15:48:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:48:45 INFO - 'RUST_BACKTRACE': 'full', 15:48:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:48:45 INFO - 'SHELL': '/bin/bash', 15:48:45 INFO - 'SHLVL': '1', 15:48:45 INFO - 'TERM': 'linux', 15:48:45 INFO - 'TMOUT': '86400', 15:48:45 INFO - 'USER': 'cltbld', 15:48:45 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 15:48:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:45 INFO - '_': '/tools/buildbot/bin/python'} 15:48:45 INFO - TEST-INFO | started process 31218 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxfkNSl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:45 INFO - PID 31218 | 15:48:45 INFO - PID 31218 | (/builds/slave/test/build/application/firefox/firefox:31268): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:48:45 INFO - PID 31218 | 15:48:56 INFO - PID 31218 | RSS: Main: 153862144 15:48:56 INFO - PID 31218 | 15:51:53 INFO - PID 31218 | Cycle 1(1): loaded http://localhost:34758/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34758/tests/dromaeo/cssquery-ext.html) 15:51:54 INFO - PID 31218 | RSS: Main: 150466560 15:51:54 INFO - PID 31218 | 15:54:56 INFO - PID 31218 | Cycle 1(1): loaded http://localhost:34758/tests/dromaeo/cssquery-ext.html (next: http://localhost:34758/tests/dromaeo/cssquery-jquery.html) 15:54:57 INFO - PID 31218 | RSS: Main: 152166400 15:54:57 INFO - PID 31218 | 15:57:58 INFO - PID 31218 | Cycle 1(1): loaded http://localhost:34758/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34758/tests/dromaeo/cssquery-mootools.html) 15:57:59 INFO - PID 31218 | RSS: Main: 153239552 15:57:59 INFO - PID 31218 | 16:01:01 INFO - PID 31218 | Cycle 1(1): loaded http://localhost:34758/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34758/tests/dromaeo/cssquery-prototype.html) 16:01:01 INFO - PID 31218 | RSS: Main: 151646208 16:01:01 INFO - PID 31218 | 16:04:05 INFO - PID 31218 | Cycle 1(1): loaded http://localhost:34758/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34758/tests/dromaeo/cssquery-yui.html) 16:04:05 INFO - PID 31218 | RSS: Main: 155701248 16:04:05 INFO - PID 31218 | 16:07:07 INFO - PID 31218 | Cycle 1(1): loaded http://localhost:34758/tests/dromaeo/cssquery-yui.html (next: http://localhost:34758/tests/dromaeo/cssquery-dojo.html) 16:07:07 INFO - PID 31218 | RSS: Main: 156426240 16:07:07 INFO - PID 31218 | 16:07:07 INFO - PID 31218 | __start_tp_report 16:07:07 INFO - PID 31218 | _x_x_mozilla_page_load 16:07:07 INFO - PID 31218 | _x_x_mozilla_page_load_details 16:07:07 INFO - PID 31218 | |i|pagename|runs| 16:07:07 INFO - PID 31218 | |0;dojo.html;3177.285110850059;3200.247941733693;3205.5031470122126;3209.8716051357947;3209.983950080249;6107.816765497031;6154.938450615493;6200.441960223579;6280.120783090371;6296.811095667126;6164.506839452844;6179.9691001544925;6184;6212.565120441571;6226.657533835637;5718.542516598662;5740.856478588037;5741.712914354286;5746.281714785652;5772.624779389347;5798.565107616924;5804.9419505805035;5808.651480911159;5816.243888294516;5893.5579831512805;5789.363170051291;5810.070388737803;5827.883442331151;5844.678542680151;5851.76592936282;1152.6301201253398;5692.886142277172;5698.800541981011;5710.971445142789;5830.5044071254;5680.772769089232;5688.63023903445;5714.228579141846;5776.682282474462;5777.913331300034;6142.539309551801;6153.323134455206;6180.9381906180815;6276.843078923018;6278.30938596754;6614.305497922697;6624.53628246023;6644.867102657968;6703.530752847302;6715.46276297895;6668.783740870115;6688.83277918051;6704.7988560343265;6778.491613129035;6825.044493770876;6046.96976515119;6108.480779133734;6154.907676384858;6172.69136543171;6187.87624247517;6700.128983232149;6742.089817874622;6772.119624448888;6801.693923773442;6810.046593476917;6454.225492940784;6469.967650161813;6519.7392104315295;6526.086347911296;6554.770583029571;6455.709493072823;6553.934460655428;6575.835604109936;6676.933230667728;6684.799456016327;6491.8701625967215;6501.349865013555;6513.837154071186;6522.282548919615;6526.641034743135;6582.979638156094;6604.042413849965;6647.833804154935;6670.232923213893;6727.730890764315;5301.284326615934;5365.409804921455;5377.166539186433;5378.435264297193;5378.784848606012;4553.385292985471;4651.62786977041;4670.1126785910565;4673.088747694235;4707.03505781314;5671.404502527258;5715.599908006483;5726.055200891889;5736.569757268222;5737.454941780524;6566.179227596551;6646.767363142267;6688;6700.865982680417;6701.966490167615;5712.685802280915;5714;5786.768529258782;5789.913151302734;5795.594308398456;5772.076467765221;5786.594938354275;5833.270841144857;5849.093390524476;5862.882742345129;6312.6212427253595;6397.424231819065;6497.480201583862;6546.247181574085;6584.11114499546;6680.766173183915;6713.429358504484;6722.29415911332;6770.289119642367;6770.93229067703;6488.416042556192;6491.642959637265;6543.738250469928;6570.77002304917;6582.0785090086465;6438.6136831790245;6540.803775886731;6593.637349945799;6629.7679581214425;6644.302348253364;6615.272320044887;6618.07346971434;6732.831679208059;6741.730330786713;6776.457883369417;6386.9680651596445;6565;6565.835854103686;6590.637514936724;6603.273639899511;6494.155759751202;6520.934790652223;6587.538872278895;6590.736370545124;6614.735410583523;6319.115323854756;6372.58578192416;6376.968115159394;6385.808425747235;6515.28331883502;6423.646699431576;6436.098946147449;6496.6751662415745;6537.084808126771;6547.934520654732;6283.8114856554375;6329.430351268408;6334.1765570475545;6376.872462550816;6386.425221730066 16:07:07 INFO - PID 31218 | |1;ext.html;855.3542075732821;877.7981064355198;904.7602385367878;905.2712566384058;908.028409601726;39974.80012599937;41055.384169237484;41061.58938410615;41097;41112.794436027776;36474.63525364746;41724.58275417245;41913.58086419143;41946.58053419465;41950.37074443886;7594.4683872128835;7644.770656880302;7690;7690.923090769104;7692.538447693129;6790.015447760072;6836.7607133750325;6839.726410943556;6846.452283817308;6849.794506164823;6836.726530938781;6881.931180688179;6890.724371025153;6892.4141447976735;6897.586144831301;6852.177738671367;6920.896186557231;6944.236134025253;7054.611996340199;7163.570185788843;7788.571628560427;7833.412494062911;7845.607719613997;7852.646630901623;7857.764267071997;37779;37878.43182352267;37950.620493795264;38063.238735225415;38126.80936595299;3590.982045089784;3595.8381872815526;3622.855085796565;3637.108908317453;3639.909002274964;31334.843325783455;31684;31821;31917.8404107978;31955.84022079898;5198;5220.712860792655;5244.16093425054;5282.154855223184;5282.735863206824;184560;185237;197225;199900;202108.99999999852;49289;49660;49865.75067124641;49951;50033.49966500361;51888.22167667488;52089.47910520922;52488.212676809875;52514.737426312626;52610;13680.384382702803;13866.514671986431;13872.653183670489;13903.30483475822;13969.650758731113;11128.109751219883;11129.387883666313;11149.665510034712;11167.553297867995;11172.497237624326;12828.230306181658;13021.088523803242;13035.02237332185;13066.738665226641;13148;5049.6212784041345;5090.643654944192;5194.35070616173;5246.160614301693;5262.447443018428;976.5410257179115;992.9573947355248;1003.3327836988467;1021.4739409204117;1031.742064483879;22794.65808012881;22837.429064273525;23024.539509210055;23190.65214021791;23299;1099.0987390339844;1102.8786833448314;1106.43571778392;1117.2067831839486;1118.3345909184022;11936.164468487123;12075.939620301842;12125.939370303093;12266.080043996735;12274.693132671577;223575;226907;227137;227222;227340;52748.73625631847;52760.208596871074;52772.472275276756;52786.73606632019;52881.206781898305;1398.6223719595653;1417.170954991321;1438.8345340286;1445.075151902783;1447.3993292783377;21940.561188775817;22179.667304990762;22285.325760908145;22560.097596095973;22687;36812.26375472422;36820.63179368279;36858.44712329264;36886.446703298934;36946.26107477889;190104;190296;190413;190802;190936;30486.170829750285;31014.22464438407;31027.224319391295;33605.663943360254;33930.151746206546;37214;37361;37423.43864841975;43234.783826081926;43515;31919;32205.677943221206;32225.51661725123;32416.189595260308;32558.186045349055;31605.36789264248;31869;31953.840230798698;32311.51532726964;32713;34414.82792586021;35395.82302088473;35790.10524736902;36093.819530902176;36109.63890361063;31336.68663313429;31480.842595786875;31494.685053150093;31572;32079.839600801846;22973.540529189657;23547.646785298577;23666.645000325352;23959.760402395754;24118.87940560286 16:07:07 INFO - PID 31218 | |2;jquery.html;573.796302312679;588.1534762652254;592.2448877680959;599.9790007349742;614.6005096687153;22368.664470032963;23422.29733108005;23426.180083697072;23549.293521194348;23571.528569428643;20529.486762830937;21894.343169704895;21902.342929712093;21914.12343506258;22205.888970555166;6733.898991515119;6899.44804415648;6919.723211071576;6940.375366217039;6945.965270173642;6402.935970640304;6700.530962832604;6833.965830170842;6848.554843935153;6944.375006249436;6630.005499175114;6695.49783766217;6699.1291132152755;6700.966495167518;6713.597184168941;6373.872522549546;6676.499262555328;6677.499187560904;6706.228783689865;6711.9664401678165;6189.412005859436;6193.783217587363;6202.100695399142;6216.191895053659;6220.875582488325;28542.286442838886;29089.563656545168;29132.27169320763;29208;29220.707792922225;2076.8338532917332;2109.9261525846523;2123.6708310211943;2126.712893759338;2128.3402145334994;19150.808491915042;19481.902590486956;19908;20019.699704504445;20174.092165852573;2486.5648511510412;2502.8373155745;2564.9615255771178;2571.742825717432;2573.395252115751;122339;128283;128353.35823320823;138918;142522;19978.800211997986;21061;21405;21635.350939471842;22069.558608828054;40934.38598421026;41094.178116437506;41152.79423602923;41214.79392603018;41240.793796030826;10167.389956602627;10278;10290.331128476626;10377.429241391645;10409.5836166554;8292.58537073156;8316.792080197922;8324.791880202922;8384.958075209584;8413.70552030676;11015;11258.38078905668;11519.596814111466;11527.308361498337;11726.355050472135;3634.309481198564;3770.547534295902;3881.573026967031;4308.46144231971;4308.719933204332;960.4525420510242;969.1568335547977;982.9262805289632;984.0503913723148;988.2884323287221;19438.70841937372;19460.80539194618;19603.607927841364;19612.509687257647;20177.59644807125;1038.7455073506887;1039.2569312941284;1040.6773900090996;1048.6329784575337;1060.8726952765717;10735.731606709738;10824;11092.057175140204;11104;11195.2723073;395029;402523;404447;408040;409806;104076.47961760142;105070.47464762781;106020;109624;110305;1041.9895801042044;1049.490996866519;1149.3621040322535;1164.4294295795169;1168.415792103931;20278.391648250577;20327;20500.282490112775;20539.38381848547;20567;56493.43506565046;56568.43431565632;57003.714981424826;57164.42835571591;58247;69885.65057174681;70479.29520704727;70492.64753676199;71790.64104679444;71899;19565.51086222856;19687.901560492104;20049.899750501154;20203.494912627302;20250.59498810045;22702;22881;22921;22961.770382295963;23285.53428931446;18070.36753713614;18123.36568220106;18455;18464.907675461538;18487.352942646943;16422.671546568763;16441.95561061615;17055.914720426317;17059;17135.314587416364;17877.28490860351;18591.814081859007;18858.24567017304;19003.714944276122;19065.714014290075;19502.90248548748;19857.702134467705;19896.50258743543;19960;20196;16658.250378733228;16840.07379594134;16841.66316673635;16904.915475422546;17066.829331706525 16:07:07 INFO - PID 31218 | |3;mootools.html;1306.1379489536905;1424.0743516714135;1428.5999920022384;1430.2848575712137;1430.4492770283439;15224.467143649974;15934.681306373866;15949.043057416564;15981.68036639268;15983.120928348937;15490.225488725548;15731.292091855865;15738.84261157391;15761.133137677423;15769;2770.5844123381553;2799.3281612413025;2810.943781124376;2818.25316291183;2837.6311079559628;2802.761765249956;2803.551431770917;2827.604135421043;2835.262831663772;2842;2777.5555911054234;2828.1232817826435;2829.221963959907;2830.2641313258596;2831.5186418308836;2792.9581056284173;2793.622860913771;2811.7188281171925;2811.7328853758963;2816.746492815636;2584.496023275462;2753.9586906196223;2760.751532362077;2782.9025984090663;2799.818011829245;15251.161186134757;15254.313555890012;15335.61660958485;15371.615709607237;15399.153046582433;7778.5721785301785;8102.068262149869;8105.473144245664;8129.5528745918955;8137.552434616094;5160.303359046518;5229.973850130763;5455.754491047873;5573.8606534836545;5673.574481913873;1878.2768634075978;1885.3401309541687;1917.2043601905193;1935.2839449403812;1944.7860735319105;26089.347766305804;26446.338841528923;26845.865770671022;26985.46029079427;27070.864645676647;6766.477542552984;6770.187577490733;6777.186737591521;6911.5853048817235;6992.860142797115;17492.737608935877;17585.472435826945;17585.56036099082;17599.472015839547;17651.823481765274;7437.256274372519;7625.199354067854;7625.6568454419685;7628.923710762932;7632.961835190899;6912.447004239649;7003.7548685795755;7054.047703560056;7180;7193.2447093055525;5181;5344.5724342052545;5361.892762144736;5391.56867450603;5397.2443857859935;1487.5611694550134;1511.5389806109022;1523.771434284844;1525.85504377084;1544.4054039194766;922.9446233226028;933.5985526223653;944.5891037398616;950.3727539823683;954.2604481526872;7885.2114788520685;7921.801954951173;7923.801904952422;7941.324987376138;8001.239882211181;700.6601798127904;741.3105811595268;745.5303159009898;748.27043632459;756.5460723565817;4826.372571565674;4910.508949105132;4921.606271498272;4996.425411077773;5490.450954904558;19890.204391824453;21930.671039934412;21944.780552194592;21946.341609751733;21952;21220.36338909835;21310.36068917935;21355.46611334729;21446.356609301747;21842.344729658136;9892.950535247277;10206.387616743019;10617.150627949746;11139.610113645984;11592.536298548132;8710.82578348442;8820.073892241222;9057.77355566116;9060.637574496946;9121.635134594542;17805.376811811526;18206.99723030492;18264.08679566043;18395.08024598738;18784.591155663107;18665.346712864986;18727.250909963448;18730.71903921415;18954.905225473784;19275.22899084021;11014.339139651647;11077.778444431227;11170.27393219438;11241.381724005258;11324.6036388726;13862.306884655929;13943.16341019542;13952.37214325378;13975.860241397455;14001.369938353002;10664.413457259925;10935.726606834893;10994.505247263733;11126.49930753102;11195.60815371458;10693.518791654238;11160.330380177214;11246.943765281121;11417.60038398652;11529.481173347052;10155.7461063474;10382.273240873066;10390.844137338097;10392.480375981322;10403.58385664565;7397.963010184914;7412.221716719882;7437.256274372519;7474.327310542078;7518.28576285252;7266.96366518164;7339.74310899116;7400.740974065882;7415.592142432218;7516.586587737727 16:07:07 INFO - PID 31218 | |4;prototype.html;505.46420793958407;526.4104203292312;537.8144540133658;542.1569459490493;556.5714399912068;6002.879942401155;6023.728932198051;6085.7565697372165;6134.110553969677;6269.592476489036;6042;6065.878682426349;6076.969615151918;6078.726457309421;6169.81490555284;5086.79652813887;5141.614378921578;5144.794208231676;5155.7422128893595;5175.8188463403785;4811.615070794328;4968.403791545002;4980.576650984671;4995.275685025669;5133.307003554528;4914.336564563791;4924.99037697273;4929.605631549485;4949.529794669501;4959.479254678243;4875.24433712775;4961.8511444656715;4964.404271487409;4978.153713868651;4984.626153038536;4442.133783912139;4444.266695995157;4449.488308844459;4459.35339375792;4498.212812757754;6033.396660333975;6035.547333949971;6069.0592958091565;6081.908771368433;6091.451769340781;5601.131824567198;5623.606347555673;5644.74598643062;5672.461116193955;5723.828285151452;6331.2085989251345;6402.48780097591;6407.551471397004;6447.226332840042;6486.772962946275;4857.2714092886;4864.975675121637;4886.609071274325;4896.167651499253;4900.803967841318;6656.667166641649;6734.3602357775935;6766.391024807742;6768.49236307274;6791.932080679179;4945.23348880924;4953.356063711766;5003.574696150795;5010.323606313174;5015.14742493777;5030.622703297267;5032.7921298888;5054;5077.949220507748;5100.362454693163;5544.805931792368;5640.605157638925;5651.321841379061;5664.178694089416;5688.687122208235;6209.653242730107;6296.811095667137;6391.840203994937;6436.16329877113;6458.903116453257;1693.5604735974323;1713.6915355236176;1720.9225584848712;1725.7238841785254;1736.5050960476203;1191.4876603060593;1197.383347575999;1208;1210.4189988805422;1211.8182272659171;5534.398832548695;5581.469760372758;5615.8876822464135;5641.576881733804;5685.573581981285;6405.494708743441;6460.967695161588;6530.706118224692;6589.538732288694;6597.571157874723;5640.182173584807;5677.098172112389;5692.857678558069;5717.714114294268;5741.971290143522;3509.4560343146854;3512.5960514540643;3554.5556805399324;3554.7156227501737;3562.7327950403305;6469.385408386196;6480.569786330127;6518.902216466757;6525.641089740015;6531.118299029666;6795.1845778506895;6818.2159051708695;6840.521163518506;6846.486513511407;6862.656867156622;6305.684715764193;6328.905066424008;6335.208098987627;6376.29860715321;6401.167848179707;6576.210854697467;6604.240512341143;6620.536562440776;6631.176426482756;6640.601563906181;6486.740530378732;6550.934490655032;6591.472682185509;6592.307807680317;6610.636414997221;6438.291787903233;6443.779324542432;6453.806385808433;6469.159009328757;6499.447546958372;4911.189653707242;4919.081558192236;5012.273220383097;5016.698998060128;5032.094223039888;6246.6876656167915;6467.159269294962;6631.352755984286;6635;6639.800805975829;6151.876962460637;6230.6884655766125;6245.9063114052415;6317.273513546001;6425.453836423956;6282.403171698681;6296.212973378328;6297.244330680348;6310.368963103653;6445.935540644534;6206.224221972254;6206.565540412308;6226.661267827439;6361.709784477492;6375.139356186855;6390.7443702251385;6461.870762584816;6464.450521705708;6481.384268494486;6494.155759751202;6047.576669633084;6181.196444462191;6202.34875338083;6282.780102696383;6294.370562943669 16:07:07 INFO - PID 31218 | |5;yui.html;903.9728808135756;906.5603182456512;936.7002559181061;937.7046230437412;946.9005754395786;17768.378106766264;18554.90722546387;18606.441806745785;18675.906620466914;18744.25022999082;17491.91254043731;17621.207045682942;17930.10349482527;18323.17545710443;18430.907845460788;941.7645588602867;958.6932181701858;963.7060696487569;966.840471322231;967.4630580028081;924.8474001789697;974.1719538392381;984.0651381187901;985.9556319965619;986.595495846703;1041.1358572384902;1063.5958335832372;1064.1061508332998;1067.6370034188374;1068.5832525315134;1055.7255113670424;1058.6136060338;1060.1148041385452;1063.3407287481734;1065.733566608348;702.5644100657574;733.3912852332551;747.7495039161887;748.7529115391908;768.3315515501494;19638;19804.504887377785;19819.80180198194;19861.21249087589;19918.203271869108;21492.89253553738;21748.565028699497;22350.105995760314;22528.88735556328;22540.43648908774;17540.29838806446;17663.29346826138;17759.467215983543;17836.821631783645;17966.730499042525;3730.8880733578035;3752.5121734174654;3755.4179102239195;3756.8309426075893;3761.8683346082757;130749.77000919952;132479.33760331233;132952;134481;134566;16373.836261637469;16699.499015029567;16789.328426863034;16989.745153822703;17060.402885898933;31441;32257.193570160463;32335.191620209684;32748;33045.83477082599;8296.377771667148;8365.163483651708;8366.58167091643;8423;8448.704295349633;4200.684948628865;4213.5154457237195;4237.851675191354;4256.723312984646;4291.914161716748;5257.631965762436;5336.17289320156;5366.436524164985;5374.381946076173;5393.352797664305;908.2506931781306;936.9812603747887;946.1437399153776;953.2183609440192;954.7374472020111;632.4782054804805;640.4748106552676;645.1484041065748;666.2138676361843;667.6594936582378;12021.819672704916;12077.275403390897;12149.453274602663;12687.429065692066;12898.548550800864;641.8042497038448;654.3979538824293;654.7217432591129;657.9309172536911;662.7050962321721;5482.945170548243;5602.551795856321;5635.971820140873;5670.262865827499;5673.432656734293;125169;125306;126425.36787316189;128051;129460.3526982359;104424.47787761013;105744;107032;109627.45186274177;112623.43688281506;92800.53599731959;94171.52914235523;96272.51863740776;97444.51277743708;98431.50784246177;10311.793764124826;10405.323653962469;10516.106130978953;10522.053015228666;10623.681289561326;44313.77843110764;44476.777616111714;45575.544244558456;46679.29981050355;46833.531664682916;56175;56285;56380.43619563752;57936.71031644983;57965.710171448874;15092.924535377253;15447.61380965485;15489.30298136564;15567.299471524035;15614.687706246039;17191.484255472355;17439.56401089932;17552.82447175512;17614.383496577557;17807.46577602674;15260.923695381452;15518.37926482928;15529;15582.84417155814;15644.92177539105;14838.851611483748;14845.92577037108;14928.477503287333;14964.326605302569;15051.698966020836;14538.781918271447;14573.125612463286;14639.853601464276;14753.336099875745;14782.63043423923;10456.424896630759;10533.683989480327;10774.946125269324;10850.945745271223;10911.618093367011;11955.64133076009;12028.819567706316;12139.635810925687;12167.917864312876;12365.258084514959 16:07:07 INFO - PID 31218 | __end_tp_report 16:07:07 INFO - PID 31218 | __start_cc_report 16:07:07 INFO - PID 31218 | _x_x_mozilla_cycle_collect,293 16:07:07 INFO - PID 31218 | __end_cc_report 16:07:07 INFO - PID 31218 | __startTimestamp1506640027756__endTimestamp 16:07:07 INFO - PID 31218 | 16:07:07 INFO - PID 31218 | ------- Summary: start ------- 16:07:07 INFO - PID 31218 | Number of tests: 6 16:07:07 INFO - PID 31218 | 16:07:07 INFO - PID 31218 | [#0] dojo.html Cycles:165 Average:6108.81 Median:6376.97 stddev:805.42 (12.6%) stddev-sans-first:774.36 16:07:07 INFO - PID 31218 | Values: 3177.3 3200.2 3205.5 3209.9 3210.0 6107.8 6154.9 6200.4 6280.1 6296.8 6164.5 6180.0 6184.0 6212.6 6226.7 5718.5 5740.9 5741.7 5746.3 5772.6 5798.6 5804.9 5808.7 5816.2 5893.6 5789.4 5810.1 5827.9 5844.7 5851.8 1152.6 5692.9 5698.8 5711.0 5830.5 5680.8 5688.6 5714.2 5776.7 5777.9 6142.5 6153.3 6180.9 6276.8 6278.3 6614.3 6624.5 6644.9 6703.5 6715.5 6668.8 6688.8 6704.8 6778.5 6825.0 6047.0 6108.5 6154.9 6172.7 6187.9 6700.1 6742.1 6772.1 6801.7 6810.0 6454.2 6470.0 6519.7 6526.1 6554.8 6455.7 6553.9 6575.8 6676.9 6684.8 6491.9 6501.3 6513.8 6522.3 6526.6 6583.0 6604.0 6647.8 6670.2 6727.7 5301.3 5365.4 5377.2 5378.4 5378.8 4553.4 4651.6 4670.1 4673.1 4707.0 5671.4 5715.6 5726.1 5736.6 5737.5 6566.2 6646.8 6688.0 6700.9 6702.0 5712.7 5714.0 5786.8 5789.9 5795.6 5772.1 5786.6 5833.3 5849.1 5862.9 6312.6 6397.4 6497.5 6546.2 6584.1 6680.8 6713.4 6722.3 6770.3 6770.9 6488.4 6491.6 6543.7 6570.8 6582.1 6438.6 6540.8 6593.6 6629.8 6644.3 6615.3 6618.1 6732.8 6741.7 6776.5 6387.0 6565.0 6565.8 6590.6 6603.3 6494.2 6520.9 6587.5 6590.7 6614.7 6319.1 6372.6 6377.0 6385.8 6515.3 6423.6 6436.1 6496.7 6537.1 6547.9 6283.8 6329.4 6334.2 6376.9 6386.4 16:07:07 INFO - PID 31218 | 16:07:07 INFO - PID 31218 | [#1] ext.html Cycles:180 Average:36971.13 Median:19690.54 stddev:53018.63 (269.3%) stddev-sans-first:53098.01 16:07:07 INFO - PID 31218 | Values: 855.4 877.8 904.8 905.3 908.0 39974.8 41055.4 41061.6 41097.0 41112.8 36474.6 41724.6 41913.6 41946.6 41950.4 7594.5 7644.8 7690.0 7690.9 7692.5 6790.0 6836.8 6839.7 6846.5 6849.8 6836.7 6881.9 6890.7 6892.4 6897.6 6852.2 6920.9 6944.2 7054.6 7163.6 7788.6 7833.4 7845.6 7852.6 7857.8 37779.0 37878.4 37950.6 38063.2 38126.8 3591.0 3595.8 3622.9 3637.1 3639.9 31334.8 31684.0 31821.0 31917.8 31955.8 5198.0 5220.7 5244.2 5282.2 5282.7 184560.0 185237.0 197225.0 199900.0 202109.0 49289.0 49660.0 49865.8 49951.0 50033.5 51888.2 52089.5 52488.2 52514.7 52610.0 13680.4 13866.5 13872.7 13903.3 13969.7 11128.1 11129.4 11149.7 11167.6 11172.5 12828.2 13021.1 13035.0 13066.7 13148.0 5049.6 5090.6 5194.4 5246.2 5262.4 976.5 993.0 1003.3 1021.5 1031.7 22794.7 22837.4 23024.5 23190.7 23299.0 1099.1 1102.9 1106.4 1117.2 1118.3 11936.2 12075.9 12125.9 12266.1 12274.7 223575.0 226907.0 227137.0 227222.0 227340.0 52748.7 52760.2 52772.5 52786.7 52881.2 1398.6 1417.2 1438.8 1445.1 1447.4 21940.6 22179.7 22285.3 22560.1 22687.0 36812.3 36820.6 36858.4 36886.4 36946.3 190104.0 190296.0 190413.0 190802.0 190936.0 30486.2 31014.2 31027.2 33605.7 33930.2 37214.0 37361.0 37423.4 43234.8 43515.0 31919.0 32205.7 32225.5 32416.2 32558.2 31605.4 31869.0 31953.8 32311.5 32713.0 34414.8 35395.8 35790.1 36093.8 36109.6 31336.7 31480.8 31494.7 31572.0 32079.8 22973.5 23547.6 23666.6 23959.8 24118.9 16:07:07 INFO - PID 31218 | 16:07:07 INFO - PID 31218 | [#2] jquery.html Cycles:180 Average:32981.03 Median:21898.34 stddev:68859.76 (314.5%) stddev-sans-first:69009.94 16:07:07 INFO - PID 31218 | Values: 573.8 588.2 592.2 600.0 614.6 22368.7 23422.3 23426.2 23549.3 23571.5 20529.5 21894.3 21902.3 21914.1 22205.9 6733.9 6899.4 6919.7 6940.4 6946.0 6402.9 6700.5 6834.0 6848.6 6944.4 6630.0 6695.5 6699.1 6701.0 6713.6 6373.9 6676.5 6677.5 6706.2 6712.0 6189.4 6193.8 6202.1 6216.2 6220.9 28542.3 29089.6 29132.3 29208.0 29220.7 2076.8 2109.9 2123.7 2126.7 2128.3 19150.8 19481.9 19908.0 20019.7 20174.1 2486.6 2502.8 2565.0 2571.7 2573.4 122339.0 128283.0 128353.4 138918.0 142522.0 19978.8 21061.0 21405.0 21635.4 22069.6 40934.4 41094.2 41152.8 41214.8 41240.8 10167.4 10278.0 10290.3 10377.4 10409.6 8292.6 8316.8 8324.8 8385.0 8413.7 11015.0 11258.4 11519.6 11527.3 11726.4 3634.3 3770.5 3881.6 4308.5 4308.7 960.5 969.2 982.9 984.1 988.3 19438.7 19460.8 19603.6 19612.5 20177.6 1038.7 1039.3 1040.7 1048.6 1060.9 10735.7 10824.0 11092.1 11104.0 11195.3 395029.0 402523.0 404447.0 408040.0 409806.0 104076.5 105070.5 106020.0 109624.0 110305.0 1042.0 1049.5 1149.4 1164.4 1168.4 20278.4 20327.0 20500.3 20539.4 20567.0 56493.4 56568.4 57003.7 57164.4 58247.0 69885.7 70479.3 70492.6 71790.6 71899.0 19565.5 19687.9 20049.9 20203.5 20250.6 22702.0 22881.0 22921.0 22961.8 23285.5 18070.4 18123.4 18455.0 18464.9 18487.4 16422.7 16442.0 17055.9 17059.0 17135.3 17877.3 18591.8 18858.2 19003.7 19065.7 19502.9 19857.7 19896.5 19960.0 20196.0 16658.3 16840.1 16841.7 16904.9 17066.8 16:07:07 INFO - PID 31218 | 16:07:07 INFO - PID 31218 | [#3] mootools.html Cycles:180 Average:9365.04 Median:2757.36 stddev:6688.99 (242.6%) stddev-sans-first:6680.35 16:07:07 INFO - PID 31218 | Values: 1306.1 1424.1 1428.6 1430.3 1430.4 15224.5 15934.7 15949.0 15981.7 15983.1 15490.2 15731.3 15738.8 15761.1 15769.0 2770.6 2799.3 2810.9 2818.3 2837.6 2802.8 2803.6 2827.6 2835.3 2842.0 2777.6 2828.1 2829.2 2830.3 2831.5 2793.0 2793.6 2811.7 2811.7 2816.7 2584.5 2754.0 2760.8 2782.9 2799.8 15251.2 15254.3 15335.6 15371.6 15399.2 7778.6 8102.1 8105.5 8129.6 8137.6 5160.3 5230.0 5455.8 5573.9 5673.6 1878.3 1885.3 1917.2 1935.3 1944.8 26089.3 26446.3 26845.9 26985.5 27070.9 6766.5 6770.2 6777.2 6911.6 6992.9 17492.7 17585.5 17585.6 17599.5 17651.8 7437.3 7625.2 7625.7 7628.9 7633.0 6912.4 7003.8 7054.0 7180.0 7193.2 5181.0 5344.6 5361.9 5391.6 5397.2 1487.6 1511.5 1523.8 1525.9 1544.4 922.9 933.6 944.6 950.4 954.3 7885.2 7921.8 7923.8 7941.3 8001.2 700.7 741.3 745.5 748.3 756.5 4826.4 4910.5 4921.6 4996.4 5490.5 19890.2 21930.7 21944.8 21946.3 21952.0 21220.4 21310.4 21355.5 21446.4 21842.3 9893.0 10206.4 10617.2 11139.6 11592.5 8710.8 8820.1 9057.8 9060.6 9121.6 17805.4 18207.0 18264.1 18395.1 18784.6 18665.3 18727.3 18730.7 18954.9 19275.2 11014.3 11077.8 11170.3 11241.4 11324.6 13862.3 13943.2 13952.4 13975.9 14001.4 10664.4 10935.7 10994.5 11126.5 11195.6 10693.5 11160.3 11246.9 11417.6 11529.5 10155.7 10382.3 10390.8 10392.5 10403.6 7398.0 7412.2 7437.3 7474.3 7518.3 7267.0 7339.7 7400.7 7415.6 7516.6 16:07:07 INFO - PID 31218 | 16:07:07 INFO - PID 31218 | [#4] prototype.html Cycles:180 Average:5434.35 Median:6056.73 stddev:1503.00 (24.8%) stddev-sans-first:1460.98 16:07:07 INFO - PID 31218 | Values: 505.5 526.4 537.8 542.2 556.6 6002.9 6023.7 6085.8 6134.1 6269.6 6042.0 6065.9 6077.0 6078.7 6169.8 5086.8 5141.6 5144.8 5155.7 5175.8 4811.6 4968.4 4980.6 4995.3 5133.3 4914.3 4925.0 4929.6 4949.5 4959.5 4875.2 4961.9 4964.4 4978.2 4984.6 4442.1 4444.3 4449.5 4459.4 4498.2 6033.4 6035.5 6069.1 6081.9 6091.5 5601.1 5623.6 5644.7 5672.5 5723.8 6331.2 6402.5 6407.6 6447.2 6486.8 4857.3 4865.0 4886.6 4896.2 4900.8 6656.7 6734.4 6766.4 6768.5 6791.9 4945.2 4953.4 5003.6 5010.3 5015.1 5030.6 5032.8 5054.0 5077.9 5100.4 5544.8 5640.6 5651.3 5664.2 5688.7 6209.7 6296.8 6391.8 6436.2 6458.9 1693.6 1713.7 1720.9 1725.7 1736.5 1191.5 1197.4 1208.0 1210.4 1211.8 5534.4 5581.5 5615.9 5641.6 5685.6 6405.5 6461.0 6530.7 6589.5 6597.6 5640.2 5677.1 5692.9 5717.7 5742.0 3509.5 3512.6 3554.6 3554.7 3562.7 6469.4 6480.6 6518.9 6525.6 6531.1 6795.2 6818.2 6840.5 6846.5 6862.7 6305.7 6328.9 6335.2 6376.3 6401.2 6576.2 6604.2 6620.5 6631.2 6640.6 6486.7 6550.9 6591.5 6592.3 6610.6 6438.3 6443.8 6453.8 6469.2 6499.4 4911.2 4919.1 5012.3 5016.7 5032.1 6246.7 6467.2 6631.4 6635.0 6639.8 6151.9 6230.7 6245.9 6317.3 6425.5 6282.4 6296.2 6297.2 6310.4 6445.9 6206.2 6206.6 6226.7 6361.7 6375.1 6390.7 6461.9 6464.5 6481.4 6494.2 6047.6 6181.2 6202.3 6282.8 6294.4 16:07:07 INFO - PID 31218 | 16:07:07 INFO - PID 31218 | [#5] yui.html Cycles:180 Average:24179.60 Median:19721.25 stddev:35067.38 (177.8%) stddev-sans-first:35122.20 16:07:07 INFO - PID 31218 | Values: 904.0 906.6 936.7 937.7 946.9 17768.4 18554.9 18606.4 18675.9 18744.3 17491.9 17621.2 17930.1 18323.2 18430.9 941.8 958.7 963.7 966.8 967.5 924.8 974.2 984.1 986.0 986.6 1041.1 1063.6 1064.1 1067.6 1068.6 1055.7 1058.6 1060.1 1063.3 1065.7 702.6 733.4 747.7 748.8 768.3 19638.0 19804.5 19819.8 19861.2 19918.2 21492.9 21748.6 22350.1 22528.9 22540.4 17540.3 17663.3 17759.5 17836.8 17966.7 3730.9 3752.5 3755.4 3756.8 3761.9 130749.8 132479.3 132952.0 134481.0 134566.0 16373.8 16699.5 16789.3 16989.7 17060.4 31441.0 32257.2 32335.2 32748.0 33045.8 8296.4 8365.2 8366.6 8423.0 8448.7 4200.7 4213.5 4237.9 4256.7 4291.9 5257.6 5336.2 5366.4 5374.4 5393.4 908.3 937.0 946.1 953.2 954.7 632.5 640.5 645.1 666.2 667.7 12021.8 12077.3 12149.5 12687.4 12898.5 641.8 654.4 654.7 657.9 662.7 5482.9 5602.6 5636.0 5670.3 5673.4 125169.0 125306.0 126425.4 128051.0 129460.4 104424.5 105744.0 107032.0 109627.5 112623.4 92800.5 94171.5 96272.5 97444.5 98431.5 10311.8 10405.3 10516.1 10522.1 10623.7 44313.8 44476.8 45575.5 46679.3 46833.5 56175.0 56285.0 56380.4 57936.7 57965.7 15092.9 15447.6 15489.3 15567.3 15614.7 17191.5 17439.6 17552.8 17614.4 17807.5 15260.9 15518.4 15529.0 15582.8 15644.9 14838.9 14845.9 14928.5 14964.3 15051.7 14538.8 14573.1 14639.9 14753.3 14782.6 10456.4 10533.7 10774.9 10850.9 10911.6 11955.6 12028.8 12139.6 12167.9 12365.3 16:07:07 INFO - PID 31218 | -------- Summary: end -------- 16:07:07 INFO - PID 31218 | 16:07:08 INFO - TEST-INFO | 31218: exit 0 16:07:08 INFO - TEST-OK | dromaeo_css | took 1108493ms 16:07:08 INFO - TEST-START | kraken 16:07:08 INFO - Initialising browser for kraken test... 16:07:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34758/getInfo.html -profile /tmp/tmpTHyZFm/profile 16:07:08 INFO - TEST-INFO | started process 31588 (/builds/slave/test/build/application/firefox/firefox http://localhost:34758/getInfo.html) 16:07:13 INFO - TEST-INFO | 31588: exit 0 16:07:13 INFO - Browser initialized. 16:07:13 INFO - Running cycle 1/1 for kraken test... 16:07:13 INFO - Using env: {'DISPLAY': ':0', 16:07:13 INFO - 'HOME': '/home/cltbld', 16:07:13 INFO - 'JSGC_DISABLE_POISONING': '1', 16:07:13 INFO - 'LANG': 'en_US.UTF-8', 16:07:13 INFO - 'LANGUAGE': 'en_US:en', 16:07:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:07:13 INFO - 'LOGNAME': 'cltbld', 16:07:13 INFO - 'MAIL': '/var/mail/cltbld', 16:07:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:07:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:07:13 INFO - 'MOZ_CRASHREPORTER': '1', 16:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:07:13 INFO - 'MOZ_NO_REMOTE': '1', 16:07:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:07:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:13 INFO - 'NO_EM_RESTART': '1', 16:07:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:07:13 INFO - 'PWD': '/builds/slave/test', 16:07:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:07:13 INFO - 'RUST_BACKTRACE': 'full', 16:07:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:07:13 INFO - 'SHELL': '/bin/bash', 16:07:13 INFO - 'SHLVL': '1', 16:07:13 INFO - 'TERM': 'linux', 16:07:13 INFO - 'TMOUT': '86400', 16:07:13 INFO - 'USER': 'cltbld', 16:07:13 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109', 16:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:13 INFO - '_': '/tools/buildbot/bin/python'} 16:07:13 INFO - TEST-INFO | started process 31820 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTHyZFm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:13 INFO - PID 31820 | 16:07:13 INFO - PID 31820 | (/builds/slave/test/build/application/firefox/firefox:31869): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:07:13 INFO - PID 31820 | 16:07:24 INFO - PID 31820 | RSS: Main: 153604096 16:07:24 INFO - PID 31820 | 16:07:26 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34758/tests/kraken/driver.html?testName=audio-beat-detection) 16:07:27 INFO - PID 31820 | RSS: Main: 160878592 16:07:27 INFO - PID 31820 | 16:07:30 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34758/tests/kraken/driver.html?testName=audio-dft) 16:07:31 INFO - PID 31820 | RSS: Main: 160854016 16:07:31 INFO - PID 31820 | 16:07:34 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34758/tests/kraken/driver.html?testName=audio-fft) 16:07:34 INFO - PID 31820 | RSS: Main: 156254208 16:07:34 INFO - PID 31820 | 16:07:36 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34758/tests/kraken/driver.html?testName=audio-oscillator) 16:07:37 INFO - PID 31820 | RSS: Main: 156811264 16:07:37 INFO - PID 31820 | 16:07:39 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34758/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:07:40 INFO - PID 31820 | RSS: Main: 156708864 16:07:40 INFO - PID 31820 | 16:07:43 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34758/tests/kraken/driver.html?testName=imaging-darkroom) 16:07:43 INFO - PID 31820 | RSS: Main: 159227904 16:07:43 INFO - PID 31820 | 16:07:47 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34758/tests/kraken/driver.html?testName=imaging-desaturate) 16:07:48 INFO - PID 31820 | RSS: Main: 159502336 16:07:48 INFO - PID 31820 | 16:07:51 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34758/tests/kraken/driver.html?testName=json-parse-financial) 16:07:51 INFO - PID 31820 | RSS: Main: 159363072 16:07:51 INFO - PID 31820 | 16:07:53 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34758/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:07:54 INFO - PID 31820 | RSS: Main: 159256576 16:07:54 INFO - PID 31820 | 16:07:55 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34758/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:07:56 INFO - PID 31820 | RSS: Main: 160018432 16:07:56 INFO - PID 31820 | 16:07:58 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34758/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:07:59 INFO - PID 31820 | RSS: Main: 156528640 16:07:59 INFO - PID 31820 | 16:08:01 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34758/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:08:02 INFO - PID 31820 | RSS: Main: 157102080 16:08:02 INFO - PID 31820 | 16:08:05 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34758/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:08:05 INFO - PID 31820 | RSS: Main: 157614080 16:08:05 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | Cycle 1(1): loaded http://localhost:34758/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34758/tests/kraken/driver.html?testName=ai-astar) 16:08:07 INFO - PID 31820 | RSS: Main: 158781440 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | __start_tp_report 16:08:07 INFO - PID 31820 | _x_x_mozilla_page_load 16:08:07 INFO - PID 31820 | _x_x_mozilla_page_load_details 16:08:07 INFO - PID 31820 | |i|pagename|runs| 16:08:07 INFO - PID 31820 | |0;ai-astar;106;106;109;106;107;107;108;109;107;108 16:08:07 INFO - PID 31820 | |1;audio-beat-detection;117;122;115;120;119;116;118;119;121;146 16:08:07 INFO - PID 31820 | |2;audio-dft;164;162;164;163;161;163;163;164;170;166 16:08:07 INFO - PID 31820 | |3;audio-fft;74;75;73;74;74;78;75;74;75;74 16:08:07 INFO - PID 31820 | |4;audio-oscillator;84;92;77;79;84;80;80;80;80;79 16:08:07 INFO - PID 31820 | |5;imaging-gaussian-blur;115;116;117;115;115;114;115;114;114;116 16:08:07 INFO - PID 31820 | |6;imaging-darkroom;150;149;148;147;148;149;149;154;148;148 16:08:07 INFO - PID 31820 | |7;imaging-desaturate;99;98;94;95;95;95;94;94;94;94 16:08:07 INFO - PID 31820 | |8;json-parse-financial;52;51;52;50;50;53;49;49;55;50 16:08:07 INFO - PID 31820 | |9;json-stringify-tinderbox;37;37;37;37;38;37;38;36;38;37 16:08:07 INFO - PID 31820 | |10;stanford-crypto-aes;72;70;73;74;71;71;72;73;74;83 16:08:07 INFO - PID 31820 | |11;stanford-crypto-ccm;112;111;115;121;111;113;115;106;115;113 16:08:07 INFO - PID 31820 | |12;stanford-crypto-pbkdf2;170;168;160;164;165;162;158;162;170;168 16:08:07 INFO - PID 31820 | |13;stanford-crypto-sha256-iterative;70;65;66;63;63;67;63;64;65;63 16:08:07 INFO - PID 31820 | __end_tp_report 16:08:07 INFO - PID 31820 | __start_cc_report 16:08:07 INFO - PID 31820 | _x_x_mozilla_cycle_collect,767 16:08:07 INFO - PID 31820 | __end_cc_report 16:08:07 INFO - PID 31820 | __startTimestamp1506640087728__endTimestamp 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | ------- Summary: start ------- 16:08:07 INFO - PID 31820 | Number of tests: 14 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#0] ai-astar Cycles:10 Average:107.30 Median:107.50 stddev:1.16 (1.1%) stddev-sans-first:1.13 16:08:07 INFO - PID 31820 | Values: 106.0 106.0 109.0 106.0 107.0 107.0 108.0 109.0 107.0 108.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:119.50 stddev:8.94 (7.5%) stddev-sans-first:9.35 16:08:07 INFO - PID 31820 | Values: 117.0 122.0 115.0 120.0 119.0 116.0 118.0 119.0 121.0 146.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.00 stddev:2.49 (1.5%) stddev-sans-first:2.65 16:08:07 INFO - PID 31820 | Values: 164.0 162.0 164.0 163.0 161.0 163.0 163.0 164.0 170.0 166.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#3] audio-fft Cycles:10 Average:74.60 Median:74.50 stddev:1.35 (1.8%) stddev-sans-first:1.41 16:08:07 INFO - PID 31820 | Values: 74.0 75.0 73.0 74.0 74.0 78.0 75.0 74.0 75.0 74.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:80.00 stddev:4.28 (5.3%) stddev-sans-first:4.44 16:08:07 INFO - PID 31820 | Values: 84.0 92.0 77.0 79.0 84.0 80.0 80.0 80.0 80.0 79.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:115.00 stddev:0.99 (0.9%) stddev-sans-first:1.05 16:08:07 INFO - PID 31820 | Values: 115.0 116.0 117.0 115.0 115.0 114.0 115.0 114.0 114.0 116.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#6] imaging-darkroom Cycles:10 Average:149.00 Median:149.00 stddev:1.94 (1.3%) stddev-sans-first:2.03 16:08:07 INFO - PID 31820 | Values: 150.0 149.0 148.0 147.0 148.0 149.0 149.0 154.0 148.0 148.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:1.81 (1.9%) stddev-sans-first:1.30 16:08:07 INFO - PID 31820 | Values: 99.0 98.0 94.0 95.0 95.0 95.0 94.0 94.0 94.0 94.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:1.91 (3.7%) stddev-sans-first:2.00 16:08:07 INFO - PID 31820 | Values: 52.0 51.0 52.0 50.0 50.0 53.0 49.0 49.0 55.0 50.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#9] json-stringify-tinderbox Cycles:10 Average:37.20 Median:37.00 stddev:0.63 (1.7%) stddev-sans-first:0.67 16:08:07 INFO - PID 31820 | Values: 37.0 37.0 37.0 37.0 38.0 37.0 38.0 36.0 38.0 37.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:3.65 (5.0%) stddev-sans-first:3.84 16:08:07 INFO - PID 31820 | Values: 72.0 70.0 73.0 74.0 71.0 71.0 72.0 73.0 74.0 83.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#11] stanford-crypto-ccm Cycles:10 Average:113.20 Median:114.00 stddev:3.85 (3.4%) stddev-sans-first:4.06 16:08:07 INFO - PID 31820 | Values: 112.0 111.0 115.0 121.0 111.0 113.0 115.0 106.0 115.0 113.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:166.50 stddev:4.22 (2.5%) stddev-sans-first:4.01 16:08:07 INFO - PID 31820 | Values: 170.0 168.0 160.0 164.0 165.0 162.0 158.0 162.0 170.0 168.0 16:08:07 INFO - PID 31820 | 16:08:07 INFO - PID 31820 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:2.28 (3.5%) stddev-sans-first:1.50 16:08:07 INFO - PID 31820 | Values: 70.0 65.0 66.0 63.0 63.0 67.0 63.0 64.0 65.0 63.0 16:08:07 INFO - PID 31820 | -------- Summary: end -------- 16:08:07 INFO - PID 31820 | 16:08:08 INFO - PID 31820 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:08:08 INFO - TEST-INFO | 31820: exit 0 16:08:08 INFO - TEST-OK | kraken | took 60083ms 16:08:08 INFO - SUITE-END | took 1168s 16:08:08 INFO - Completed test suite (00:19:29) 16:08:08 INFO - javascript benchmark 16:08:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8493.629084946644, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3177.285110850059, 3200.247941733693, 3205.5031470122126, 3209.8716051357947, 3209.983950080249, 6107.816765497031, 6154.938450615493, 6200.441960223579, 6280.120783090371, 6296.811095667126, 6164.506839452844, 6179.9691001544925, 6184.0, 6212.565120441571, 6226.657533835637, 5718.542516598662, 5740.856478588037, 5741.712914354286, 5746.281714785652, 5772.624779389347, 5798.565107616924, 5804.9419505805035, 5808.651480911159, 5816.243888294516, 5893.5579831512805, 5789.363170051291, 5810.070388737803, 5827.883442331151, 5844.678542680151, 5851.76592936282, 1152.6301201253398, 5692.886142277172, 5698.800541981011, 5710.971445142789, 5830.5044071254, 5680.772769089232, 5688.63023903445, 5714.228579141846, 5776.682282474462, 5777.913331300034, 6142.539309551801, 6153.323134455206, 6180.9381906180815, 6276.843078923018, 6278.30938596754, 6614.305497922697, 6624.53628246023, 6644.867102657968, 6703.530752847302, 6715.46276297895, 6668.783740870115, 6688.83277918051, 6704.7988560343265, 6778.491613129035, 6825.044493770876, 6046.96976515119, 6108.480779133734, 6154.907676384858, 6172.69136543171, 6187.87624247517, 6700.128983232149, 6742.089817874622, 6772.119624448888, 6801.693923773442, 6810.046593476917, 6454.225492940784, 6469.967650161813, 6519.7392104315295, 6526.086347911296, 6554.770583029571, 6455.709493072823, 6553.934460655428, 6575.835604109936, 6676.933230667728, 6684.799456016327, 6491.8701625967215, 6501.349865013555, 6513.837154071186, 6522.282548919615, 6526.641034743135, 6582.979638156094, 6604.042413849965, 6647.833804154935, 6670.232923213893, 6727.730890764315, 5301.284326615934, 5365.409804921455, 5377.166539186433, 5378.435264297193, 5378.784848606012, 4553.385292985471, 4651.62786977041, 4670.1126785910565, 4673.088747694235, 4707.03505781314, 5671.404502527258, 5715.599908006483, 5726.055200891889, 5736.569757268222, 5737.454941780524, 6566.179227596551, 6646.767363142267, 6688.0, 6700.865982680417, 6701.966490167615, 5712.685802280915, 5714.0, 5786.768529258782, 5789.913151302734, 5795.594308398456, 5772.076467765221, 5786.594938354275, 5833.270841144857, 5849.093390524476, 5862.882742345129, 6312.6212427253595, 6397.424231819065, 6497.480201583862, 6546.247181574085, 6584.11114499546, 6680.766173183915, 6713.429358504484, 6722.29415911332, 6770.289119642367, 6770.93229067703, 6488.416042556192, 6491.642959637265, 6543.738250469928, 6570.77002304917, 6582.0785090086465, 6438.6136831790245, 6540.803775886731, 6593.637349945799, 6629.7679581214425, 6644.302348253364, 6615.272320044887, 6618.07346971434, 6732.831679208059, 6741.730330786713, 6776.457883369417, 6386.9680651596445, 6565.0, 6565.835854103686, 6590.637514936724, 6603.273639899511, 6494.155759751202, 6520.934790652223, 6587.538872278895, 6590.736370545124, 6614.735410583523, 6319.115323854756, 6372.58578192416, 6376.968115159394, 6385.808425747235, 6515.28331883502, 6423.646699431576, 6436.098946147449, 6496.6751662415745, 6537.084808126771, 6547.934520654732, 6283.8114856554375, 6329.430351268408, 6334.1765570475545, 6376.872462550816, 6386.425221730066], "value": 6052.930012601673, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [855.3542075732821, 877.7981064355198, 904.7602385367878, 905.2712566384058, 908.028409601726, 39974.80012599937, 41055.384169237484, 41061.58938410615, 41097.0, 41112.794436027776, 36474.63525364746, 41724.58275417245, 41913.58086419143, 41946.58053419465, 41950.37074443886, 7594.4683872128835, 7644.770656880302, 7690.0, 7690.923090769104, 7692.538447693129, 6790.015447760072, 6836.7607133750325, 6839.726410943556, 6846.452283817308, 6849.794506164823, 6836.726530938781, 6881.931180688179, 6890.724371025153, 6892.4141447976735, 6897.586144831301, 6852.177738671367, 6920.896186557231, 6944.236134025253, 7054.611996340199, 7163.570185788843, 7788.571628560427, 7833.412494062911, 7845.607719613997, 7852.646630901623, 7857.764267071997, 37779.0, 37878.43182352267, 37950.620493795264, 38063.238735225415, 38126.80936595299, 3590.982045089784, 3595.8381872815526, 3622.855085796565, 3637.108908317453, 3639.909002274964, 31334.843325783455, 31684.0, 31821.0, 31917.8404107978, 31955.84022079898, 5198.0, 5220.712860792655, 5244.16093425054, 5282.154855223184, 5282.735863206824, 184560.0, 185237.0, 197225.0, 199900.0, 202108.99999999852, 49289.0, 49660.0, 49865.75067124641, 49951.0, 50033.49966500361, 51888.22167667488, 52089.47910520922, 52488.212676809875, 52514.737426312626, 52610.0, 13680.384382702803, 13866.514671986431, 13872.653183670489, 13903.30483475822, 13969.650758731113, 11128.109751219883, 11129.387883666313, 11149.665510034712, 11167.553297867995, 11172.497237624326, 12828.230306181658, 13021.088523803242, 13035.02237332185, 13066.738665226641, 13148.0, 5049.6212784041345, 5090.643654944192, 5194.35070616173, 5246.160614301693, 5262.447443018428, 976.5410257179115, 992.9573947355248, 1003.3327836988467, 1021.4739409204117, 1031.742064483879, 22794.65808012881, 22837.429064273525, 23024.539509210055, 23190.65214021791, 23299.0, 1099.0987390339844, 1102.8786833448314, 1106.43571778392, 1117.2067831839486, 1118.3345909184022, 11936.164468487123, 12075.939620301842, 12125.939370303093, 12266.080043996735, 12274.693132671577, 223575.0, 226907.0, 227137.0, 227222.0, 227340.0, 52748.73625631847, 52760.208596871074, 52772.472275276756, 52786.73606632019, 52881.206781898305, 1398.6223719595653, 1417.170954991321, 1438.8345340286, 1445.075151902783, 1447.3993292783377, 21940.561188775817, 22179.667304990762, 22285.325760908145, 22560.097596095973, 22687.0, 36812.26375472422, 36820.63179368279, 36858.44712329264, 36886.446703298934, 36946.26107477889, 190104.0, 190296.0, 190413.0, 190802.0, 190936.0, 30486.170829750285, 31014.22464438407, 31027.224319391295, 33605.663943360254, 33930.151746206546, 37214.0, 37361.0, 37423.43864841975, 43234.783826081926, 43515.0, 31919.0, 32205.677943221206, 32225.51661725123, 32416.189595260308, 32558.186045349055, 31605.36789264248, 31869.0, 31953.840230798698, 32311.51532726964, 32713.0, 34414.82792586021, 35395.82302088473, 35790.10524736902, 36093.819530902176, 36109.63890361063, 31336.68663313429, 31480.842595786875, 31494.685053150093, 31572.0, 32079.839600801846, 22973.540529189657, 23547.646785298577, 23666.645000325352, 23959.760402395754, 24118.87940560286], "value": 16694.641609487564, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.796302312679, 588.1534762652254, 592.2448877680959, 599.9790007349742, 614.6005096687153, 22368.664470032963, 23422.29733108005, 23426.180083697072, 23549.293521194348, 23571.528569428643, 20529.486762830937, 21894.343169704895, 21902.342929712093, 21914.12343506258, 22205.888970555166, 6733.898991515119, 6899.44804415648, 6919.723211071576, 6940.375366217039, 6945.965270173642, 6402.935970640304, 6700.530962832604, 6833.965830170842, 6848.554843935153, 6944.375006249436, 6630.005499175114, 6695.49783766217, 6699.1291132152755, 6700.966495167518, 6713.597184168941, 6373.872522549546, 6676.499262555328, 6677.499187560904, 6706.228783689865, 6711.9664401678165, 6189.412005859436, 6193.783217587363, 6202.100695399142, 6216.191895053659, 6220.875582488325, 28542.286442838886, 29089.563656545168, 29132.27169320763, 29208.0, 29220.707792922225, 2076.8338532917332, 2109.9261525846523, 2123.6708310211943, 2126.712893759338, 2128.3402145334994, 19150.808491915042, 19481.902590486956, 19908.0, 20019.699704504445, 20174.092165852573, 2486.5648511510412, 2502.8373155745, 2564.9615255771178, 2571.742825717432, 2573.395252115751, 122339.0, 128283.0, 128353.35823320823, 138918.0, 142522.0, 19978.800211997986, 21061.0, 21405.0, 21635.350939471842, 22069.558608828054, 40934.38598421026, 41094.178116437506, 41152.79423602923, 41214.79392603018, 41240.793796030826, 10167.389956602627, 10278.0, 10290.331128476626, 10377.429241391645, 10409.5836166554, 8292.58537073156, 8316.792080197922, 8324.791880202922, 8384.958075209584, 8413.70552030676, 11015.0, 11258.38078905668, 11519.596814111466, 11527.308361498337, 11726.355050472135, 3634.309481198564, 3770.547534295902, 3881.573026967031, 4308.46144231971, 4308.719933204332, 960.4525420510242, 969.1568335547977, 982.9262805289632, 984.0503913723148, 988.2884323287221, 19438.70841937372, 19460.80539194618, 19603.607927841364, 19612.509687257647, 20177.59644807125, 1038.7455073506887, 1039.2569312941284, 1040.6773900090996, 1048.6329784575337, 1060.8726952765717, 10735.731606709738, 10824.0, 11092.057175140204, 11104.0, 11195.2723073, 395029.0, 402523.0, 404447.0, 408040.0, 409806.0, 104076.47961760142, 105070.47464762781, 106020.0, 109624.0, 110305.0, 1041.9895801042044, 1049.490996866519, 1149.3621040322535, 1164.4294295795169, 1168.415792103931, 20278.391648250577, 20327.0, 20500.282490112775, 20539.38381848547, 20567.0, 56493.43506565046, 56568.43431565632, 57003.714981424826, 57164.42835571591, 58247.0, 69885.65057174681, 70479.29520704727, 70492.64753676199, 71790.64104679444, 71899.0, 19565.51086222856, 19687.901560492104, 20049.899750501154, 20203.494912627302, 20250.59498810045, 22702.0, 22881.0, 22921.0, 22961.770382295963, 23285.53428931446, 18070.36753713614, 18123.36568220106, 18455.0, 18464.907675461538, 18487.352942646943, 16422.671546568763, 16441.95561061615, 17055.914720426317, 17059.0, 17135.314587416364, 17877.28490860351, 18591.814081859007, 18858.24567017304, 19003.714944276122, 19065.714014290075, 19502.90248548748, 19857.702134467705, 19896.50258743543, 19960.0, 20196.0, 16658.250378733228, 16840.07379594134, 16841.66316673635, 16904.915475422546, 17066.829331706525], "value": 12603.868276694617, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.1379489536905, 1424.0743516714135, 1428.5999920022384, 1430.2848575712137, 1430.4492770283439, 15224.467143649974, 15934.681306373866, 15949.043057416564, 15981.68036639268, 15983.120928348937, 15490.225488725548, 15731.292091855865, 15738.84261157391, 15761.133137677423, 15769.0, 2770.5844123381553, 2799.3281612413025, 2810.943781124376, 2818.25316291183, 2837.6311079559628, 2802.761765249956, 2803.551431770917, 2827.604135421043, 2835.262831663772, 2842.0, 2777.5555911054234, 2828.1232817826435, 2829.221963959907, 2830.2641313258596, 2831.5186418308836, 2792.9581056284173, 2793.622860913771, 2811.7188281171925, 2811.7328853758963, 2816.746492815636, 2584.496023275462, 2753.9586906196223, 2760.751532362077, 2782.9025984090663, 2799.818011829245, 15251.161186134757, 15254.313555890012, 15335.61660958485, 15371.615709607237, 15399.153046582433, 7778.5721785301785, 8102.068262149869, 8105.473144245664, 8129.5528745918955, 8137.552434616094, 5160.303359046518, 5229.973850130763, 5455.754491047873, 5573.8606534836545, 5673.574481913873, 1878.2768634075978, 1885.3401309541687, 1917.2043601905193, 1935.2839449403812, 1944.7860735319105, 26089.347766305804, 26446.338841528923, 26845.865770671022, 26985.46029079427, 27070.864645676647, 6766.477542552984, 6770.187577490733, 6777.186737591521, 6911.5853048817235, 6992.860142797115, 17492.737608935877, 17585.472435826945, 17585.56036099082, 17599.472015839547, 17651.823481765274, 7437.256274372519, 7625.199354067854, 7625.6568454419685, 7628.923710762932, 7632.961835190899, 6912.447004239649, 7003.7548685795755, 7054.047703560056, 7180.0, 7193.2447093055525, 5181.0, 5344.5724342052545, 5361.892762144736, 5391.56867450603, 5397.2443857859935, 1487.5611694550134, 1511.5389806109022, 1523.771434284844, 1525.85504377084, 1544.4054039194766, 922.9446233226028, 933.5985526223653, 944.5891037398616, 950.3727539823683, 954.2604481526872, 7885.2114788520685, 7921.801954951173, 7923.801904952422, 7941.324987376138, 8001.239882211181, 700.6601798127904, 741.3105811595268, 745.5303159009898, 748.27043632459, 756.5460723565817, 4826.372571565674, 4910.508949105132, 4921.606271498272, 4996.425411077773, 5490.450954904558, 19890.204391824453, 21930.671039934412, 21944.780552194592, 21946.341609751733, 21952.0, 21220.36338909835, 21310.36068917935, 21355.46611334729, 21446.356609301747, 21842.344729658136, 9892.950535247277, 10206.387616743019, 10617.150627949746, 11139.610113645984, 11592.536298548132, 8710.82578348442, 8820.073892241222, 9057.77355566116, 9060.637574496946, 9121.635134594542, 17805.376811811526, 18206.99723030492, 18264.08679566043, 18395.08024598738, 18784.591155663107, 18665.346712864986, 18727.250909963448, 18730.71903921415, 18954.905225473784, 19275.22899084021, 11014.339139651647, 11077.778444431227, 11170.27393219438, 11241.381724005258, 11324.6036388726, 13862.306884655929, 13943.16341019542, 13952.37214325378, 13975.860241397455, 14001.369938353002, 10664.413457259925, 10935.726606834893, 10994.505247263733, 11126.49930753102, 11195.60815371458, 10693.518791654238, 11160.330380177214, 11246.943765281121, 11417.60038398652, 11529.481173347052, 10155.7461063474, 10382.273240873066, 10390.844137338097, 10392.480375981322, 10403.58385664565, 7397.963010184914, 7412.221716719882, 7437.256274372519, 7474.327310542078, 7518.28576285252, 7266.96366518164, 7339.74310899116, 7400.740974065882, 7415.592142432218, 7516.586587737727], "value": 6680.7013321790355, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.46420793958407, 526.4104203292312, 537.8144540133658, 542.1569459490493, 556.5714399912068, 6002.879942401155, 6023.728932198051, 6085.7565697372165, 6134.110553969677, 6269.592476489036, 6042.0, 6065.878682426349, 6076.969615151918, 6078.726457309421, 6169.81490555284, 5086.79652813887, 5141.614378921578, 5144.794208231676, 5155.7422128893595, 5175.8188463403785, 4811.615070794328, 4968.403791545002, 4980.576650984671, 4995.275685025669, 5133.307003554528, 4914.336564563791, 4924.99037697273, 4929.605631549485, 4949.529794669501, 4959.479254678243, 4875.24433712775, 4961.8511444656715, 4964.404271487409, 4978.153713868651, 4984.626153038536, 4442.133783912139, 4444.266695995157, 4449.488308844459, 4459.35339375792, 4498.212812757754, 6033.396660333975, 6035.547333949971, 6069.0592958091565, 6081.908771368433, 6091.451769340781, 5601.131824567198, 5623.606347555673, 5644.74598643062, 5672.461116193955, 5723.828285151452, 6331.2085989251345, 6402.48780097591, 6407.551471397004, 6447.226332840042, 6486.772962946275, 4857.2714092886, 4864.975675121637, 4886.609071274325, 4896.167651499253, 4900.803967841318, 6656.667166641649, 6734.3602357775935, 6766.391024807742, 6768.49236307274, 6791.932080679179, 4945.23348880924, 4953.356063711766, 5003.574696150795, 5010.323606313174, 5015.14742493777, 5030.622703297267, 5032.7921298888, 5054.0, 5077.949220507748, 5100.362454693163, 5544.805931792368, 5640.605157638925, 5651.321841379061, 5664.178694089416, 5688.687122208235, 6209.653242730107, 6296.811095667137, 6391.840203994937, 6436.16329877113, 6458.903116453257, 1693.5604735974323, 1713.6915355236176, 1720.9225584848712, 1725.7238841785254, 1736.5050960476203, 1191.4876603060593, 1197.383347575999, 1208.0, 1210.4189988805422, 1211.8182272659171, 5534.398832548695, 5581.469760372758, 5615.8876822464135, 5641.576881733804, 5685.573581981285, 6405.494708743441, 6460.967695161588, 6530.706118224692, 6589.538732288694, 6597.571157874723, 5640.182173584807, 5677.098172112389, 5692.857678558069, 5717.714114294268, 5741.971290143522, 3509.4560343146854, 3512.5960514540643, 3554.5556805399324, 3554.7156227501737, 3562.7327950403305, 6469.385408386196, 6480.569786330127, 6518.902216466757, 6525.641089740015, 6531.118299029666, 6795.1845778506895, 6818.2159051708695, 6840.521163518506, 6846.486513511407, 6862.656867156622, 6305.684715764193, 6328.905066424008, 6335.208098987627, 6376.29860715321, 6401.167848179707, 6576.210854697467, 6604.240512341143, 6620.536562440776, 6631.176426482756, 6640.601563906181, 6486.740530378732, 6550.934490655032, 6591.472682185509, 6592.307807680317, 6610.636414997221, 6438.291787903233, 6443.779324542432, 6453.806385808433, 6469.159009328757, 6499.447546958372, 4911.189653707242, 4919.081558192236, 5012.273220383097, 5016.698998060128, 5032.094223039888, 6246.6876656167915, 6467.159269294962, 6631.352755984286, 6635.0, 6639.800805975829, 6151.876962460637, 6230.6884655766125, 6245.9063114052415, 6317.273513546001, 6425.453836423956, 6282.403171698681, 6296.212973378328, 6297.244330680348, 6310.368963103653, 6445.935540644534, 6206.224221972254, 6206.565540412308, 6226.661267827439, 6361.709784477492, 6375.139356186855, 6390.7443702251385, 6461.870762584816, 6464.450521705708, 6481.384268494486, 6494.155759751202, 6047.576669633084, 6181.196444462191, 6202.34875338083, 6282.780102696383, 6294.370562943669], "value": 4996.392389872492, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [903.9728808135756, 906.5603182456512, 936.7002559181061, 937.7046230437412, 946.9005754395786, 17768.378106766264, 18554.90722546387, 18606.441806745785, 18675.906620466914, 18744.25022999082, 17491.91254043731, 17621.207045682942, 17930.10349482527, 18323.17545710443, 18430.907845460788, 941.7645588602867, 958.6932181701858, 963.7060696487569, 966.840471322231, 967.4630580028081, 924.8474001789697, 974.1719538392381, 984.0651381187901, 985.9556319965619, 986.595495846703, 1041.1358572384902, 1063.5958335832372, 1064.1061508332998, 1067.6370034188374, 1068.5832525315134, 1055.7255113670424, 1058.6136060338, 1060.1148041385452, 1063.3407287481734, 1065.733566608348, 702.5644100657574, 733.3912852332551, 747.7495039161887, 748.7529115391908, 768.3315515501494, 19638.0, 19804.504887377785, 19819.80180198194, 19861.21249087589, 19918.203271869108, 21492.89253553738, 21748.565028699497, 22350.105995760314, 22528.88735556328, 22540.43648908774, 17540.29838806446, 17663.29346826138, 17759.467215983543, 17836.821631783645, 17966.730499042525, 3730.8880733578035, 3752.5121734174654, 3755.4179102239195, 3756.8309426075893, 3761.8683346082757, 130749.77000919952, 132479.33760331233, 132952.0, 134481.0, 134566.0, 16373.836261637469, 16699.499015029567, 16789.328426863034, 16989.745153822703, 17060.402885898933, 31441.0, 32257.193570160463, 32335.191620209684, 32748.0, 33045.83477082599, 8296.377771667148, 8365.163483651708, 8366.58167091643, 8423.0, 8448.704295349633, 4200.684948628865, 4213.5154457237195, 4237.851675191354, 4256.723312984646, 4291.914161716748, 5257.631965762436, 5336.17289320156, 5366.436524164985, 5374.381946076173, 5393.352797664305, 908.2506931781306, 936.9812603747887, 946.1437399153776, 953.2183609440192, 954.7374472020111, 632.4782054804805, 640.4748106552676, 645.1484041065748, 666.2138676361843, 667.6594936582378, 12021.819672704916, 12077.275403390897, 12149.453274602663, 12687.429065692066, 12898.548550800864, 641.8042497038448, 654.3979538824293, 654.7217432591129, 657.9309172536911, 662.7050962321721, 5482.945170548243, 5602.551795856321, 5635.971820140873, 5670.262865827499, 5673.432656734293, 125169.0, 125306.0, 126425.36787316189, 128051.0, 129460.3526982359, 104424.47787761013, 105744.0, 107032.0, 109627.45186274177, 112623.43688281506, 92800.53599731959, 94171.52914235523, 96272.51863740776, 97444.51277743708, 98431.50784246177, 10311.793764124826, 10405.323653962469, 10516.106130978953, 10522.053015228666, 10623.681289561326, 44313.77843110764, 44476.777616111714, 45575.544244558456, 46679.29981050355, 46833.531664682916, 56175.0, 56285.0, 56380.43619563752, 57936.71031644983, 57965.710171448874, 15092.924535377253, 15447.61380965485, 15489.30298136564, 15567.299471524035, 15614.687706246039, 17191.484255472355, 17439.56401089932, 17552.82447175512, 17614.383496577557, 17807.46577602674, 15260.923695381452, 15518.37926482928, 15529.0, 15582.84417155814, 15644.92177539105, 14838.851611483748, 14845.92577037108, 14928.477503287333, 14964.326605302569, 15051.698966020836, 14538.781918271447, 14573.125612463286, 14639.853601464276, 14753.336099875745, 14782.63043423923, 10456.424896630759, 10533.683989480327, 10774.946125269324, 10850.945745271223, 10911.618093367011, 11955.64133076009, 12028.819567706316, 12139.635810925687, 12167.917864312876, 12365.258084514959], "value": 8830.961975263868, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1412.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 106.0, 109.0, 106.0, 107.0, 107.0, 108.0, 109.0, 107.0, 108.0], "value": 107.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 122.0, 115.0, 120.0, 119.0, 116.0, 118.0, 119.0, 121.0, 146.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 164.0, 163.0, 161.0, 163.0, 163.0, 164.0, 170.0, 166.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 74.0, 74.0, 78.0, 75.0, 74.0, 75.0, 74.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 92.0, 77.0, 79.0, 84.0, 80.0, 80.0, 80.0, 80.0, 79.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 116.0, 117.0, 115.0, 115.0, 114.0, 115.0, 114.0, 114.0, 116.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 148.0, 147.0, 148.0, 149.0, 149.0, 154.0, 148.0, 148.0], "value": 149.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 98.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 50.0, 50.0, 53.0, 49.0, 49.0, 55.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 37.0, 38.0, 37.0, 38.0, 36.0, 38.0, 37.0], "value": 37.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 73.0, 74.0, 71.0, 71.0, 72.0, 73.0, 74.0, 83.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 115.0, 121.0, 111.0, 113.0, 115.0, 106.0, 115.0, 113.0], "value": 113.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 160.0, 164.0, 165.0, 162.0, 158.0, 162.0, 170.0, 168.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 66.0, 63.0, 63.0, 67.0, 63.0, 64.0, 65.0, 63.0], "value": 64.9, "unit": "score"}]}]} 16:08:08 INFO - Return code: 0 16:08:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:08:08 INFO - # TBPL SUCCESS # 16:08:08 INFO - Running post-action listener: _package_coverage_data 16:08:08 INFO - Running post-action listener: _resource_record_post_action 16:08:08 INFO - [mozharness: 2017-09-28 23:08:08.986177Z] Finished run-tests step (success) 16:08:08 INFO - Running post-run listener: _resource_record_post_run 16:08:09 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 343048192; Read time: 12; Write time: 252636 16:08:09 INFO - TinderboxPrint: CPU usage
13.1% 16:08:09 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:08:09 INFO - TinderboxPrint: I/O write bytes / time
343,048,192 / 252,636 16:08:09 INFO - TinderboxPrint: CPU idle
8,221.8 (86.9%) 16:08:09 INFO - TinderboxPrint: CPU user
1,192.8 (12.6%) 16:08:09 INFO - TinderboxPrint: Swap in / out
0 / 0 16:08:09 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13094912; Read time: 0; Write time: 2512 16:08:09 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:08:09 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 329478144; Read time: 0; Write time: 250100 16:08:09 INFO - Running post-run listener: _upload_blobber_files 16:08:09 INFO - Blob upload gear active. 16:08:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:08:09 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 16:08:09 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'] 16:08:09 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 16:08:10 INFO - (blobuploader) - INFO - Open directory for files ... 16:08:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:08:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:08:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:10 INFO - (blobuploader) - INFO - Done attempting. 16:08:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:08:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:08:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:18 INFO - (blobuploader) - INFO - Done attempting. 16:08:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:08:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:08:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:19 INFO - (blobuploader) - INFO - Done attempting. 16:08:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:08:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:21 INFO - (blobuploader) - INFO - Done attempting. 16:08:21 INFO - (blobuploader) - INFO - Iteration through files over. 16:08:21 INFO - Return code: 0 16:08:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:08:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:08:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7fdd361e0508700e1a59f55ac06c0870a79405805bfb5127e60591a745f16cb742bda9899d88db57ebbd562722a520bd26fb2b2b31b2d668d7867fa7ed0d616", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ae9a64fbd1388cd491d4029ae6a0939cc98ec58d3c671ff44fc555a34db44e3e0ddba87cd8008972a09c5e63e5c9fef3cefbda2a083d5bf6726f1226b5345a6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/40ad2cb33fcbcfca705919eafd51e3a2949170aab6002c42a4adb548dcc45e7fdb546381621fe3315b9a517a5cb1fdba2841bb00d4a513d339478b343142d84a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f4eaa97ebd507c544f42c89310af8896b9b679658a1138745108d76ac3e9c75902586fe8eb07c6f9f44d262978d0293e2874fd5d61a518208e16660ebc3f71b"} 16:08:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:08:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:08:21 INFO - Contents: 16:08:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7fdd361e0508700e1a59f55ac06c0870a79405805bfb5127e60591a745f16cb742bda9899d88db57ebbd562722a520bd26fb2b2b31b2d668d7867fa7ed0d616", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ae9a64fbd1388cd491d4029ae6a0939cc98ec58d3c671ff44fc555a34db44e3e0ddba87cd8008972a09c5e63e5c9fef3cefbda2a083d5bf6726f1226b5345a6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/40ad2cb33fcbcfca705919eafd51e3a2949170aab6002c42a4adb548dcc45e7fdb546381621fe3315b9a517a5cb1fdba2841bb00d4a513d339478b343142d84a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f4eaa97ebd507c544f42c89310af8896b9b679658a1138745108d76ac3e9c75902586fe8eb07c6f9f44d262978d0293e2874fd5d61a518208e16660ebc3f71b"} 16:08:21 INFO - Running post-run listener: copy_logs_to_upload_dir 16:08:21 INFO - Copying logs to upload dir... 16:08:21 INFO - mkdir: /builds/slave/test/build/upload/logs 16:08:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.133511 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2017-09-28 16:08:21.204171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 16:08:21.208966) ========= 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=3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109 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/c7fdd361e0508700e1a59f55ac06c0870a79405805bfb5127e60591a745f16cb742bda9899d88db57ebbd562722a520bd26fb2b2b31b2d668d7867fa7ed0d616", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ae9a64fbd1388cd491d4029ae6a0939cc98ec58d3c671ff44fc555a34db44e3e0ddba87cd8008972a09c5e63e5c9fef3cefbda2a083d5bf6726f1226b5345a6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/40ad2cb33fcbcfca705919eafd51e3a2949170aab6002c42a4adb548dcc45e7fdb546381621fe3315b9a517a5cb1fdba2841bb00d4a513d339478b343142d84a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f4eaa97ebd507c544f42c89310af8896b9b679658a1138745108d76ac3e9c75902586fe8eb07c6f9f44d262978d0293e2874fd5d61a518208e16660ebc3f71b"} build_url:https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011373 build_url: 'https://queue.taskcluster.net/v1/task/CK_o3hL6TtqucDlJLtKu5Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7fdd361e0508700e1a59f55ac06c0870a79405805bfb5127e60591a745f16cb742bda9899d88db57ebbd562722a520bd26fb2b2b31b2d668d7867fa7ed0d616", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8ae9a64fbd1388cd491d4029ae6a0939cc98ec58d3c671ff44fc555a34db44e3e0ddba87cd8008972a09c5e63e5c9fef3cefbda2a083d5bf6726f1226b5345a6", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/40ad2cb33fcbcfca705919eafd51e3a2949170aab6002c42a4adb548dcc45e7fdb546381621fe3315b9a517a5cb1fdba2841bb00d4a513d339478b343142d84a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f4eaa97ebd507c544f42c89310af8896b9b679658a1138745108d76ac3e9c75902586fe8eb07c6f9f44d262978d0293e2874fd5d61a518208e16660ebc3f71b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-28 16:08:21.240360) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:08:21.240798) ========= 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=3a3620fb28aea1604ab609ef000001d2-1506634432.550090-777073109 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004538 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-28 16:08:21.323383) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 16:08:21.325766) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-28 16:08:21.326059) ========= ========= Total master_lag: 0.21 =========