builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-033 starttime: 1505469151.08 results: success (0) revision: f36106f3f52a9bb2769860d20568c589fc2498d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:31.076947) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:31.077418) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:31.077707) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:31.180888) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:31.181148) ========= 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=ea1537f79099159eb082e9be00000831-1505463697.177799-878862173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 02:52:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 120M=0s 2017-09-15 02:52:31 (120 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093151 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:31.296508) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:31.296802) ========= 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=ea1537f79099159eb082e9be00000831-1505463697.177799-878862173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019600 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:31.346546) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:31.346888) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f36106f3f52a9bb2769860d20568c589fc2498d0 --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 f36106f3f52a9bb2769860d20568c589fc2498d0 --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=ea1537f79099159eb082e9be00000831-1505463697.177799-878862173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 02:52:31,403 truncating revision to first 12 chars 2017-09-15 02:52:31,403 Setting DEBUG logging. 2017-09-15 02:52:31,403 attempt 1/10 2017-09-15 02:52:31,403 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f36106f3f52a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 02:52:31,782 unpacking tar archive at: mozilla-central-f36106f3f52a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.666236 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:32.030112) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:32.030383) ========= script_repo_revision: f36106f3f52a9bb2769860d20568c589fc2498d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:32.030752) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:32.031010) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 02:52:32.048095) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 23 secs) (at 2017-09-15 02:52:32.048362) ========= /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=ea1537f79099159eb082e9be00000831-1505463697.177799-878862173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:52:32 INFO - MultiFileLogger online at 20170915 02:52:32 in /builds/slave/test 02:52:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:52:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:52:32 INFO - {'append_to_log': False, 02:52:32 INFO - 'base_work_dir': '/builds/slave/test', 02:52:32 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:52:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:52:32 INFO - 'branch': 'Firefox-Non-PGO', 02:52:32 INFO - 'buildbot_json_path': 'buildprops.json', 02:52:32 INFO - 'code_coverage': False, 02:52:32 INFO - 'config_files': ('talos/linux_config.py',), 02:52:32 INFO - 'default_actions': ('clobber', 02:52:32 INFO - 'read-buildbot-config', 02:52:32 INFO - 'download-and-extract', 02:52:32 INFO - 'populate-webroot', 02:52:32 INFO - 'create-virtualenv', 02:52:32 INFO - 'install', 02:52:32 INFO - 'setup-mitmproxy', 02:52:32 INFO - 'run-tests'), 02:52:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:52:32 INFO - 'disable_ccov_upload': False, 02:52:32 INFO - 'disable_stylo': False, 02:52:32 INFO - 'download_minidump_stackwalk': True, 02:52:32 INFO - 'download_symbols': 'ondemand', 02:52:32 INFO - 'enable_stylo': False, 02:52:32 INFO - 'enable_webrender': False, 02:52:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:52:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:52:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:52:32 INFO - 'gecko_profile': False, 02:52:32 INFO - 'gecko_profile_interval': 0, 02:52:32 INFO - 'installer_path': 'installer.exe', 02:52:32 INFO - 'log_level': 'info', 02:52:32 INFO - 'log_name': 'talos', 02:52:32 INFO - 'log_to_console': True, 02:52:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:52:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:52:32 INFO - 'opt_config_files': (), 02:52:32 INFO - 'pip_index': False, 02:52:32 INFO - 'suite': 'dromaeojs-e10s', 02:52:32 INFO - 'system_bits': '32', 02:52:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:52:32 INFO - 'title': 'talos-linux64-ix-033', 02:52:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:52:32 INFO - 'use_talos_json': True, 02:52:32 INFO - 'verify': 'False', 02:52:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:52:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:52:32 INFO - 'work_dir': 'build'} 02:52:32 INFO - [mozharness: 2017-09-15 09:52:32.237844Z] Running clobber step. 02:52:32 INFO - Running pre-action listener: _resource_record_pre_action 02:52:32 INFO - Running main action method: clobber 02:52:32 INFO - rmtree: /builds/slave/test/build 02:52:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:52:33 INFO - Running post-action listener: _resource_record_post_action 02:52:33 INFO - [mozharness: 2017-09-15 09:52:33.238977Z] Finished clobber step (success) 02:52:33 INFO - [mozharness: 2017-09-15 09:52:33.239082Z] Running read-buildbot-config step. 02:52:33 INFO - Running pre-action listener: _resource_record_pre_action 02:52:33 INFO - Running main action method: read_buildbot_config 02:52:33 INFO - Using buildbot properties: 02:52:33 INFO - { 02:52:33 INFO - "project": "", 02:52:33 INFO - "product": "firefox", 02:52:33 INFO - "who": "archaeopteryx@coole-files.de", 02:52:33 INFO - "installer_path": "public/build/target.tar.bz2", 02:52:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:52:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:52:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 02:52:33 INFO - "stage_platform": "linux64", 02:52:33 INFO - "basedir": "/builds/slave/test", 02:52:33 INFO - "buildnumber": 1150, 02:52:33 INFO - "platform": "ubuntu64_hw", 02:52:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:52:33 INFO - "slavebuilddir": "test", 02:52:33 INFO - "taskId": "QKLWgZUsRUi1zahw4HqTbQ", 02:52:33 INFO - "branch": "mozilla-central", 02:52:33 INFO - "script_repo_revision": "production", 02:52:33 INFO - "revision": "f36106f3f52a9bb2769860d20568c589fc2498d0", 02:52:33 INFO - "slavename": "talos-linux64-ix-033", 02:52:33 INFO - "repo_path": "mozilla-central" 02:52:33 INFO - } 02:52:33 INFO - Finding installer, test and symbols from parent task. 02:52:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QKLWgZUsRUi1zahw4HqTbQ'}, attempt #1 02:52:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QKLWgZUsRUi1zahw4HqTbQ'}, attempt #1 02:52:34 INFO - Task dependencies: WmwENJ0_TWeFxfcKwYDDvw 02:52:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw'}, attempt #1 02:52:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.tar.bz2 02:52:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.test_packages.json 02:52:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.crashreporter-symbols.zip 02:52:34 INFO - Running post-action listener: _resource_record_post_action 02:52:34 INFO - [mozharness: 2017-09-15 09:52:34.683273Z] Finished read-buildbot-config step (success) 02:52:34 INFO - [mozharness: 2017-09-15 09:52:34.683491Z] Running download-and-extract step. 02:52:34 INFO - Running pre-action listener: _resource_record_pre_action 02:52:34 INFO - Running main action method: download_and_extract 02:52:34 INFO - mkdir: /builds/slave/test/build/tests 02:52:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:34 INFO - trying https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.test_packages.json 02:52:34 INFO - Downloading https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:52:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:52:35 INFO - Downloaded 989 bytes. 02:52:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:52:35 INFO - Using the following test package requirements: 02:52:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:52:35 INFO - u'common': [u'target.common.tests.zip'], 02:52:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:52:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:52:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:52:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:52:35 INFO - u'mozbase': [u'target.common.tests.zip'], 02:52:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:52:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:52:35 INFO - u'web-platform': [u'target.common.tests.zip', 02:52:35 INFO - u'target.web-platform.tests.tar.gz'], 02:52:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:52:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 02:52:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.common.tests.zip 02:52:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:52:35 INFO - Fetch https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.common.tests.zip into memory 02:52:37 INFO - Content-Length response header: 54736915 02:52:40 INFO - Bytes received: 54736915 02:52:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.talos.tests.zip 02:52:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:52:55 INFO - Fetch https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.talos.tests.zip into memory 02:52:56 INFO - Content-Length response header: 14500356 02:52:56 INFO - Bytes received: 14500356 02:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:52:56 INFO - trying https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.tar.bz2 02:52:56 INFO - Downloading https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:52:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:52:57 INFO - Downloaded 61423088 bytes. 02:52:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.tar.bz2 02:52:57 INFO - mkdir: /builds/slave/test/properties 02:52:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:52:57 INFO - Writing to file /builds/slave/test/properties/build_url 02:52:57 INFO - Contents: 02:52:57 INFO - build_url:https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.tar.bz2 02:52:57 INFO - Running post-action listener: _resource_record_post_action 02:52:57 INFO - Running post-action listener: find_tests_for_verification 02:52:57 INFO - Running post-action listener: set_extra_try_arguments 02:52:57 INFO - [mozharness: 2017-09-15 09:52:57.661487Z] Finished download-and-extract step (success) 02:52:57 INFO - [mozharness: 2017-09-15 09:52:57.661585Z] Running populate-webroot step. 02:52:57 INFO - Running pre-action listener: _resource_record_pre_action 02:52:57 INFO - Running main action method: populate_webroot 02:52:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:52:57 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['tresize', 02:52:57 INFO - 'tcanvasmark']}, 02:52:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:52:57 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['dromaeo_css', 02:52:57 INFO - 'kraken']}, 02:52:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:52:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:57 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:52:57 INFO - 'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:52:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:52:57 INFO - 'tests': ['damp', 'tps']}, 02:52:57 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:52:57 INFO - 'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['damp', 'tps']}, 02:52:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:52:57 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['dromaeo_dom']}, 02:52:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:52:57 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['basic_compositor_video', 02:52:57 INFO - 'glvideo']}, 02:52:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:52:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:52:57 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:52:57 INFO - 'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['ts_paint_webext', 02:52:57 INFO - 'tp5o_webext']}, 02:52:57 INFO - 'other-e10s': {'tests': ['a11yr', 02:52:57 INFO - 'ts_paint', 02:52:57 INFO - 'tpaint', 02:52:57 INFO - 'sessionrestore', 02:52:57 INFO - 'sessionrestore_many_windows', 02:52:57 INFO - 'sessionrestore_no_auto_restore', 02:52:57 INFO - 'tabpaint']}, 02:52:57 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['a11yr', 02:52:57 INFO - 'ts_paint', 02:52:57 INFO - 'tpaint', 02:52:57 INFO - 'sessionrestore', 02:52:57 INFO - 'sessionrestore_many_windows', 02:52:57 INFO - 'sessionrestore_no_auto_restore', 02:52:57 INFO - 'tabpaint']}, 02:52:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:52:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:52:57 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['bloom_basic_singleton']}, 02:52:57 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['bloom_basic']}, 02:52:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:52:57 INFO - 'tsvgr_opacity', 02:52:57 INFO - 'tart', 02:52:57 INFO - 'tscrollx', 02:52:57 INFO - 'tsvg_static']}, 02:52:57 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['tsvgx', 02:52:57 INFO - 'tsvgr_opacity', 02:52:57 INFO - 'tart', 02:52:57 INFO - 'tscrollx', 02:52:57 INFO - 'tsvg_static']}, 02:52:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:52:57 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:52:57 INFO - 'talos_options': ['--disable-stylo'], 02:52:57 INFO - 'tests': ['tp5o']}, 02:52:57 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:52:57 INFO - 'talos_options': ['--mitmproxy', 02:52:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:52:57 INFO - '--firstNonBlankPaint'], 02:52:57 INFO - 'tests': ['tp6_google', 02:52:57 INFO - 'tp6_youtube', 02:52:57 INFO - 'tp6_amazon', 02:52:57 INFO - 'tp6_facebook']}, 02:52:57 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:52:57 INFO - 'talos_options': ['--disable-stylo', 02:52:57 INFO - '--mitmproxy', 02:52:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:52:57 INFO - '--firstNonBlankPaint'], 02:52:57 INFO - 'tests': ['tp6_google', 02:52:57 INFO - 'tp6_youtube', 02:52:57 INFO - 'tp6_amazon', 02:52:57 INFO - 'tp6_facebook']}, 02:52:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:52:57 INFO - 'talos_options': ['--stylo-threads=1', 02:52:57 INFO - '--mitmproxy', 02:52:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:52:57 INFO - '--firstNonBlankPaint'], 02:52:57 INFO - 'tests': ['tp6_google', 02:52:57 INFO - 'tp6_youtube', 02:52:57 INFO - 'tp6_amazon', 02:52:57 INFO - 'tp6_facebook']}, 02:52:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:52:57 INFO - 'talos_options': ['--xperf_path', 02:52:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:57 INFO - 'tests': ['tp5n']}, 02:52:57 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:52:57 INFO - 'talos_options': ['--disable-stylo', 02:52:57 INFO - '--xperf_path', 02:52:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:52:57 INFO - 'tests': ['tp5n']}}} 02:52:57 INFO - Running post-action listener: _resource_record_post_action 02:52:57 INFO - [mozharness: 2017-09-15 09:52:57.676524Z] Finished populate-webroot step (success) 02:52:57 INFO - [mozharness: 2017-09-15 09:52:57.676613Z] Running create-virtualenv step. 02:52:57 INFO - Running pre-action listener: _resource_record_pre_action 02:52:57 INFO - Running main action method: create_virtualenv 02:52:57 INFO - Creating virtualenv /builds/slave/test/build/venv 02:52:57 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:52:57 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:52:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:52:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:52:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:53:00 INFO - Installing distribute.............................................................................................................................................................................................done. 02:53:06 INFO - Installing pip...............done. 02:53:06 INFO - Return code: 0 02:53:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:53:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:53:06 INFO - Reading from file tmpfile_stdout 02:53:06 INFO - Output received: 02:53:06 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:53:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:53:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:06 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 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:53:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:53:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:53:06 INFO - Using env: {'DISPLAY': ':0', 02:53:06 INFO - 'HOME': '/home/cltbld', 02:53:06 INFO - 'LANG': 'en_US.UTF-8', 02:53:06 INFO - 'LANGUAGE': 'en_US:en', 02:53:06 INFO - 'LOGNAME': 'cltbld', 02:53:06 INFO - 'MAIL': '/var/mail/cltbld', 02:53:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:06 INFO - 'MOZ_NO_REMOTE': '1', 02:53:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:53:06 INFO - 'NO_EM_RESTART': '1', 02:53:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:53:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:06 INFO - 'PWD': '/builds/slave/test', 02:53:06 INFO - 'SHELL': '/bin/bash', 02:53:06 INFO - 'SHLVL': '1', 02:53:06 INFO - 'TERM': 'linux', 02:53:06 INFO - 'TMOUT': '86400', 02:53:06 INFO - 'USER': 'cltbld', 02:53:06 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:53:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:06 INFO - '_': '/tools/buildbot/bin/python'} 02:54:21 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:54:21 INFO - Downloading/unpacking pip>=1.5 02:54:21 INFO - Running setup.py egg_info for package pip 02:54:21 INFO - warning: no files found matching 'pip/cacert.pem' 02:54:21 INFO - warning: no files found matching '*.html' under directory 'docs' 02:54:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:54:21 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:54:21 INFO - Installing collected packages: pip 02:54:21 INFO - Found existing installation: pip 1.1 02:54:21 INFO - Uninstalling pip: 02:54:21 INFO - Successfully uninstalled pip 02:54:21 INFO - Running setup.py install for pip 02:54:21 INFO - warning: no files found matching 'pip/cacert.pem' 02:54:21 INFO - warning: no files found matching '*.html' under directory 'docs' 02:54:21 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:54:21 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:54:21 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:54:21 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:54:21 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:54:21 INFO - Successfully installed pip 02:54:21 INFO - Cleaning up... 02:54:21 INFO - Return code: 0 02:54:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:54: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')]} 02:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54: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')]} 02:54:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54: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', '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 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:54: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', 'psutil>=3.1.1'] in /builds/slave/test/build 02:54: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 psutil>=3.1.1 02:54:21 INFO - Using env: {'DISPLAY': ':0', 02:54:21 INFO - 'HOME': '/home/cltbld', 02:54:21 INFO - 'LANG': 'en_US.UTF-8', 02:54:21 INFO - 'LANGUAGE': 'en_US:en', 02:54:21 INFO - 'LOGNAME': 'cltbld', 02:54:21 INFO - 'MAIL': '/var/mail/cltbld', 02:54:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:21 INFO - 'MOZ_NO_REMOTE': '1', 02:54:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:54:21 INFO - 'NO_EM_RESTART': '1', 02:54:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:54:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:21 INFO - 'PWD': '/builds/slave/test', 02:54:21 INFO - 'SHELL': '/bin/bash', 02:54:21 INFO - 'SHLVL': '1', 02:54:21 INFO - 'TERM': 'linux', 02:54:21 INFO - 'TMOUT': '86400', 02:54:21 INFO - 'USER': 'cltbld', 02:54:21 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:54:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:21 INFO - '_': '/tools/buildbot/bin/python'} 02:54:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:21 INFO - Downloading/unpacking psutil>=3.1.1 02:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:54:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:54:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:54:23 INFO - Installing collected packages: psutil 02:54:23 INFO - Running setup.py install for psutil 02:54:23 INFO - building 'psutil._psutil_linux' extension 02:54:23 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 02:54:23 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 02:54:23 INFO - building 'psutil._psutil_posix' extension 02:54:23 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 02:54:23 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 02:55:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:55:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:55:08 INFO - Successfully installed psutil 02:55:08 INFO - Cleaning up... 02:55:09 INFO - Return code: 0 02:55:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:55: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')]} 02:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55: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')]} 02:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:55: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:55: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 mozsystemmonitor==0.3 02:55:09 INFO - Using env: {'DISPLAY': ':0', 02:55:09 INFO - 'HOME': '/home/cltbld', 02:55:09 INFO - 'LANG': 'en_US.UTF-8', 02:55:09 INFO - 'LANGUAGE': 'en_US:en', 02:55:09 INFO - 'LOGNAME': 'cltbld', 02:55:09 INFO - 'MAIL': '/var/mail/cltbld', 02:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:09 INFO - 'MOZ_NO_REMOTE': '1', 02:55:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:55:09 INFO - 'NO_EM_RESTART': '1', 02:55:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:55:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:09 INFO - 'PWD': '/builds/slave/test', 02:55:09 INFO - 'SHELL': '/bin/bash', 02:55:09 INFO - 'SHLVL': '1', 02:55:09 INFO - 'TERM': 'linux', 02:55:09 INFO - 'TMOUT': '86400', 02:55:09 INFO - 'USER': 'cltbld', 02:55:09 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:09 INFO - '_': '/tools/buildbot/bin/python'} 02:55:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:55: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 02:55: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 02:55: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 02:55: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 02:55:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:55:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:55:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:55:10 INFO - Installing collected packages: mozsystemmonitor 02:55:10 INFO - Running setup.py install for mozsystemmonitor 02:55:11 INFO - Successfully installed mozsystemmonitor 02:55:11 INFO - Cleaning up... 02:55:12 INFO - Return code: 0 02:55:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:55: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')]} 02:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55: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')]} 02:55:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:12 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 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:55:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:55:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:55:12 INFO - Using env: {'DISPLAY': ':0', 02:55:12 INFO - 'HOME': '/home/cltbld', 02:55:12 INFO - 'LANG': 'en_US.UTF-8', 02:55:12 INFO - 'LANGUAGE': 'en_US:en', 02:55:12 INFO - 'LOGNAME': 'cltbld', 02:55:12 INFO - 'MAIL': '/var/mail/cltbld', 02:55:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:12 INFO - 'MOZ_NO_REMOTE': '1', 02:55:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:55:12 INFO - 'NO_EM_RESTART': '1', 02:55:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:55:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:12 INFO - 'PWD': '/builds/slave/test', 02:55:12 INFO - 'SHELL': '/bin/bash', 02:55:12 INFO - 'SHLVL': '1', 02:55:12 INFO - 'TERM': 'linux', 02:55:12 INFO - 'TMOUT': '86400', 02:55:12 INFO - 'USER': 'cltbld', 02:55:12 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:55:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:12 INFO - '_': '/tools/buildbot/bin/python'} 02:55:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:13 INFO - Downloading/unpacking jsonschema==2.5.1 02:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:55:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:14 INFO - Downloading functools32-3.2.3-2.tar.gz 02:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:55:14 INFO - warning: no files found matching '*.txt' 02:55:14 INFO - no previously-included directories found matching 'build' 02:55:14 INFO - no previously-included directories found matching 'dist' 02:55:14 INFO - no previously-included directories found matching '.git*' 02:55:14 INFO - Installing collected packages: jsonschema, functools32 02:55:14 INFO - Running setup.py install for functools32 02:55:14 INFO - warning: no files found matching '*.txt' 02:55:14 INFO - no previously-included directories found matching 'build' 02:55:14 INFO - no previously-included directories found matching 'dist' 02:55:14 INFO - no previously-included directories found matching '.git*' 02:55:14 INFO - Successfully installed jsonschema functools32 02:55:14 INFO - Cleaning up... 02:55:14 INFO - Return code: 0 02:55:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:14 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 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 02:55:14 INFO - Using env: {'DISPLAY': ':0', 02:55:14 INFO - 'HOME': '/home/cltbld', 02:55:14 INFO - 'LANG': 'en_US.UTF-8', 02:55:14 INFO - 'LANGUAGE': 'en_US:en', 02:55:14 INFO - 'LOGNAME': 'cltbld', 02:55:14 INFO - 'MAIL': '/var/mail/cltbld', 02:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:14 INFO - 'MOZ_NO_REMOTE': '1', 02:55:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:55:14 INFO - 'NO_EM_RESTART': '1', 02:55:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:14 INFO - 'PWD': '/builds/slave/test', 02:55:14 INFO - 'SHELL': '/bin/bash', 02:55:14 INFO - 'SHLVL': '1', 02:55:14 INFO - 'TERM': 'linux', 02:55:14 INFO - 'TMOUT': '86400', 02:55:14 INFO - 'USER': 'cltbld', 02:55:14 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:14 INFO - '_': '/tools/buildbot/bin/python'} 02:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:55:14 INFO - Cleaning up... 02:55:14 INFO - Return code: 0 02:55:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:14 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 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:55:14 INFO - Using env: {'DISPLAY': ':0', 02:55:14 INFO - 'HOME': '/home/cltbld', 02:55:14 INFO - 'LANG': 'en_US.UTF-8', 02:55:14 INFO - 'LANGUAGE': 'en_US:en', 02:55:14 INFO - 'LOGNAME': 'cltbld', 02:55:14 INFO - 'MAIL': '/var/mail/cltbld', 02:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:14 INFO - 'MOZ_NO_REMOTE': '1', 02:55:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:55:14 INFO - 'NO_EM_RESTART': '1', 02:55:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:14 INFO - 'PWD': '/builds/slave/test', 02:55:14 INFO - 'SHELL': '/bin/bash', 02:55:14 INFO - 'SHLVL': '1', 02:55:14 INFO - 'TERM': 'linux', 02:55:14 INFO - 'TMOUT': '86400', 02:55:14 INFO - 'USER': 'cltbld', 02:55:14 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:14 INFO - '_': '/tools/buildbot/bin/python'} 02:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:14 INFO - Downloading/unpacking blobuploader==1.2.4 02:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:16 INFO - Downloading blobuploader-1.2.4.tar.gz 02:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:55:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:55:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:16 INFO - Downloading docopt-0.6.1.tar.gz 02:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:55:16 INFO - Installing collected packages: blobuploader, requests, docopt 02:55:16 INFO - Running setup.py install for blobuploader 02:55:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:55:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:55:16 INFO - Running setup.py install for requests 02:55:17 INFO - Running setup.py install for docopt 02:55:17 INFO - Successfully installed blobuploader requests docopt 02:55:17 INFO - Cleaning up... 02:55:17 INFO - Return code: 0 02:55:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:17 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 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:55:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:55:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:55:17 INFO - Using env: {'DISPLAY': ':0', 02:55:17 INFO - 'HOME': '/home/cltbld', 02:55:17 INFO - 'LANG': 'en_US.UTF-8', 02:55:17 INFO - 'LANGUAGE': 'en_US:en', 02:55:17 INFO - 'LOGNAME': 'cltbld', 02:55:17 INFO - 'MAIL': '/var/mail/cltbld', 02:55:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:17 INFO - 'MOZ_NO_REMOTE': '1', 02:55:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:55:17 INFO - 'NO_EM_RESTART': '1', 02:55:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:55:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:17 INFO - 'PWD': '/builds/slave/test', 02:55:17 INFO - 'SHELL': '/bin/bash', 02:55:17 INFO - 'SHLVL': '1', 02:55:17 INFO - 'TERM': 'linux', 02:55:17 INFO - 'TMOUT': '86400', 02:55:17 INFO - 'USER': 'cltbld', 02:55:17 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:55:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:17 INFO - '_': '/tools/buildbot/bin/python'} 02:55:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:55:17 INFO - Running setup.py (path:/tmp/pip-MRP1ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:55:17 INFO - Running setup.py (path:/tmp/pip-mA0xgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:55:17 INFO - Running setup.py (path:/tmp/pip-17GLYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:55:17 INFO - Running setup.py (path:/tmp/pip-db2QdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:55:17 INFO - Running setup.py (path:/tmp/pip-vglLm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:55:17 INFO - Running setup.py (path:/tmp/pip-nvzUpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:55:17 INFO - Running setup.py (path:/tmp/pip-8SnbM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:55:17 INFO - Running setup.py (path:/tmp/pip-eGlSiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:55:17 INFO - Running setup.py (path:/tmp/pip-sz2bMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:55:18 INFO - Running setup.py (path:/tmp/pip-7Dy0fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:55:18 INFO - Running setup.py (path:/tmp/pip-j3MOHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:55:18 INFO - Running setup.py (path:/tmp/pip-Pvrn55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:55:18 INFO - Running setup.py (path:/tmp/pip-2tyohT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:55:18 INFO - Running setup.py (path:/tmp/pip-5m0eQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:18 INFO - Running setup.py (path:/tmp/pip-dyx8Kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:55:18 INFO - Running setup.py (path:/tmp/pip-CoIIp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:55:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:55:18 INFO - Running setup.py (path:/tmp/pip-R1Y8x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:55:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:55:18 INFO - Running setup.py install for manifestparser 02:55:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:55:18 INFO - Running setup.py install for mozcrash 02:55:18 INFO - Running setup.py install for mozdebug 02:55:18 INFO - Running setup.py install for mozdevice 02:55:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Running setup.py install for mozfile 02:55:19 INFO - Running setup.py install for mozhttpd 02:55:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Running setup.py install for mozinfo 02:55:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Running setup.py install for mozInstall 02:55:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Running setup.py install for mozleak 02:55:19 INFO - Running setup.py install for mozlog 02:55:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Running setup.py install for moznetwork 02:55:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Running setup.py install for mozprocess 02:55:19 INFO - Running setup.py install for mozprofile 02:55:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Running setup.py install for mozrunner 02:55:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:55:20 INFO - Running setup.py install for mozscreenshot 02:55:20 INFO - Running setup.py install for moztest 02:55:20 INFO - Running setup.py install for mozversion 02:55:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:55:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:55:20 INFO - Cleaning up... 02:55:20 INFO - Return code: 0 02:55:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:20 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 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:55:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:55:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:55:20 INFO - Using env: {'DISPLAY': ':0', 02:55:20 INFO - 'HOME': '/home/cltbld', 02:55:20 INFO - 'LANG': 'en_US.UTF-8', 02:55:20 INFO - 'LANGUAGE': 'en_US:en', 02:55:20 INFO - 'LOGNAME': 'cltbld', 02:55:20 INFO - 'MAIL': '/var/mail/cltbld', 02:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:20 INFO - 'MOZ_NO_REMOTE': '1', 02:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:55:20 INFO - 'NO_EM_RESTART': '1', 02:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:20 INFO - 'PWD': '/builds/slave/test', 02:55:20 INFO - 'SHELL': '/bin/bash', 02:55:20 INFO - 'SHLVL': '1', 02:55:20 INFO - 'TERM': 'linux', 02:55:20 INFO - 'TMOUT': '86400', 02:55:20 INFO - 'USER': 'cltbld', 02:55:20 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:20 INFO - '_': '/tools/buildbot/bin/python'} 02:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:55:20 INFO - Running setup.py (path:/tmp/pip-KVFNSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:55:20 INFO - Running setup.py (path:/tmp/pip-pd02mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:55:20 INFO - Running setup.py (path:/tmp/pip-_5Pmeo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:55:20 INFO - Running setup.py (path:/tmp/pip-DCyWJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:55:20 INFO - Running setup.py (path:/tmp/pip-Xc9GZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:55:20 INFO - Running setup.py (path:/tmp/pip-2vtVzY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:55:21 INFO - Running setup.py (path:/tmp/pip-gLg38U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:55:21 INFO - Running setup.py (path:/tmp/pip-Ni9au9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:55:21 INFO - Running setup.py (path:/tmp/pip-KNU9KB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:55:21 INFO - Running setup.py (path:/tmp/pip-7Vgike-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:55:21 INFO - Running setup.py (path:/tmp/pip-MfxA7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:55:21 INFO - Running setup.py (path:/tmp/pip-6eK6gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:55:21 INFO - Running setup.py (path:/tmp/pip-UlzwQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:55:21 INFO - Running setup.py (path:/tmp/pip-XtbWF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:21 INFO - Running setup.py (path:/tmp/pip-PoRj95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:55:21 INFO - Running setup.py (path:/tmp/pip-zvx1cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:55:21 INFO - Running setup.py (path:/tmp/pip-_oXsgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:55:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:22 INFO - Downloading blessings-1.6.tar.gz 02:55:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:55:22 INFO - Installing collected packages: blessings 02:55:22 INFO - Running setup.py install for blessings 02:55:23 INFO - Successfully installed blessings 02:55:23 INFO - Cleaning up... 02:55:23 INFO - Return code: 0 02:55:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:55:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:55:23 INFO - Reading from file tmpfile_stdout 02:55:23 INFO - Current package versions: 02:55:23 INFO - argparse == 1.2.1 02:55:23 INFO - blessings == 1.6 02:55:23 INFO - blobuploader == 1.2.4 02:55:23 INFO - docopt == 0.6.1 02:55:23 INFO - functools32 == 3.2.3-2 02:55:23 INFO - jsonschema == 2.5.1 02:55:23 INFO - manifestparser == 1.1 02:55:23 INFO - mozInstall == 1.13 02:55:23 INFO - mozcrash == 1.0 02:55:23 INFO - mozdebug == 0.1 02:55:23 INFO - mozdevice == 0.51 02:55:23 INFO - mozfile == 1.2 02:55:23 INFO - mozhttpd == 0.7 02:55:23 INFO - mozinfo == 0.10 02:55:23 INFO - mozleak == 0.1 02:55:23 INFO - mozlog == 3.5 02:55:23 INFO - moznetwork == 0.27 02:55:23 INFO - mozprocess == 0.25 02:55:23 INFO - mozprofile == 0.28 02:55:23 INFO - mozrunner == 6.13 02:55:23 INFO - mozscreenshot == 0.1 02:55:23 INFO - mozsystemmonitor == 0.3 02:55:23 INFO - moztest == 0.8 02:55:23 INFO - mozversion == 1.4 02:55:23 INFO - psutil == 3.1.1 02:55:23 INFO - requests == 1.2.3 02:55:23 INFO - wsgiref == 0.1.2 02:55:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:23 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 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:55:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:55:23 INFO - Using env: {'DISPLAY': ':0', 02:55:23 INFO - 'HOME': '/home/cltbld', 02:55:23 INFO - 'LANG': 'en_US.UTF-8', 02:55:23 INFO - 'LANGUAGE': 'en_US:en', 02:55:23 INFO - 'LOGNAME': 'cltbld', 02:55:23 INFO - 'MAIL': '/var/mail/cltbld', 02:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:23 INFO - 'MOZ_NO_REMOTE': '1', 02:55:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:55:23 INFO - 'NO_EM_RESTART': '1', 02:55:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:23 INFO - 'PWD': '/builds/slave/test', 02:55:23 INFO - 'SHELL': '/bin/bash', 02:55:23 INFO - 'SHLVL': '1', 02:55:23 INFO - 'TERM': 'linux', 02:55:23 INFO - 'TMOUT': '86400', 02:55:23 INFO - 'USER': 'cltbld', 02:55:23 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:23 INFO - '_': '/tools/buildbot/bin/python'} 02:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:55:23 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:55:24 INFO - Installing collected packages: simplejson 02:55:24 INFO - Running setup.py install for simplejson 02:55:24 INFO - building 'simplejson._speedups' extension 02:55:24 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 02:55:25 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 02:55:25 INFO - Successfully installed simplejson 02:55:25 INFO - Cleaning up... 02:55:25 INFO - Return code: 0 02:55:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:25 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 0x28291f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b95a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, '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': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 02:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:55:25 INFO - Using env: {'DISPLAY': ':0', 02:55:25 INFO - 'HOME': '/home/cltbld', 02:55:25 INFO - 'LANG': 'en_US.UTF-8', 02:55:25 INFO - 'LANGUAGE': 'en_US:en', 02:55:25 INFO - 'LOGNAME': 'cltbld', 02:55:25 INFO - 'MAIL': '/var/mail/cltbld', 02:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:25 INFO - 'MOZ_NO_REMOTE': '1', 02:55:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:55:25 INFO - 'NO_EM_RESTART': '1', 02:55:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:25 INFO - 'PWD': '/builds/slave/test', 02:55:25 INFO - 'SHELL': '/bin/bash', 02:55:25 INFO - 'SHLVL': '1', 02:55:25 INFO - 'TERM': 'linux', 02:55:25 INFO - 'TMOUT': '86400', 02:55:25 INFO - 'USER': 'cltbld', 02:55:25 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:25 INFO - '_': '/tools/buildbot/bin/python'} 02:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:55:26 INFO - Cleaning up... 02:55:26 INFO - Return code: 0 02:55:26 INFO - Running post-action listener: _resource_record_post_action 02:55:26 INFO - Running post-action listener: _start_resource_monitoring 02:55:26 INFO - Starting resource monitoring. 02:55:26 INFO - [mozharness: 2017-09-15 09:55:26.047593Z] Finished create-virtualenv step (success) 02:55:26 INFO - [mozharness: 2017-09-15 09:55:26.048121Z] Running install step. 02:55:26 INFO - Running pre-action listener: _resource_record_pre_action 02:55:26 INFO - Running main action method: install 02:55:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:55:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:55:26 INFO - Reading from file tmpfile_stdout 02:55:26 INFO - Detecting whether we're running mozinstall >=1.0... 02:55:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:55:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:55:26 INFO - Reading from file tmpfile_stdout 02:55:26 INFO - Output received: 02:55:26 INFO - Usage: mozinstall [options] installer 02:55:26 INFO - Options: 02:55:26 INFO - -h, --help show this help message and exit 02:55:26 INFO - -d DEST, --destination=DEST 02:55:26 INFO - Directory to install application into. [default: 02:55:26 INFO - "/builds/slave/test"] 02:55:26 INFO - --app=APP Application being installed. [default: firefox] 02:55:26 INFO - mkdir: /builds/slave/test/build/application 02:55:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:55:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:55:44 INFO - Reading from file tmpfile_stdout 02:55:44 INFO - Output received: 02:55:44 INFO - /builds/slave/test/build/application/firefox/firefox 02:55:44 INFO - Running post-action listener: _resource_record_post_action 02:55:44 INFO - [mozharness: 2017-09-15 09:55:44.640056Z] Finished install step (success) 02:55:44 INFO - [mozharness: 2017-09-15 09:55:44.640308Z] Running setup-mitmproxy step. 02:55:44 INFO - Running pre-action listener: _resource_record_pre_action 02:55:44 INFO - Running main action method: setup_mitmproxy 02:55:44 INFO - Skipping: mitmproxy is not required 02:55:44 INFO - Running post-action listener: _resource_record_post_action 02:55:44 INFO - [mozharness: 2017-09-15 09:55:44.641433Z] Finished setup-mitmproxy step (success) 02:55:44 INFO - [mozharness: 2017-09-15 09:55:44.641652Z] Running run-tests step. 02:55:44 INFO - Running pre-action listener: _resource_record_pre_action 02:55:44 INFO - Running pre-action listener: _set_gcov_prefix 02:55:44 INFO - Running main action method: run_tests 02:55:44 WARNING - Try message not found. 02:55:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:55:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:55:44 INFO - Python 2.7.3 02:55:44 INFO - Return code: 0 02:55:44 INFO - grabbing minidump binary from tooltool 02:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:55:44 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 0x2b99090>, '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 0x2b99d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b93f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:55:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:55:44 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 02:55:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:55:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:55:44 INFO - Return code: 0 02:55:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:55:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:55:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:55:44 INFO - ENV: RUST_BACKTRACE is now full 02:55:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:55:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:55:44 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/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:55:44 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/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:55:44 INFO - Using env: {'DISPLAY': ':0', 02:55:44 INFO - 'HOME': '/home/cltbld', 02:55:44 INFO - 'LANG': 'en_US.UTF-8', 02:55:44 INFO - 'LANGUAGE': 'en_US:en', 02:55:44 INFO - 'LOGNAME': 'cltbld', 02:55:44 INFO - 'MAIL': '/var/mail/cltbld', 02:55:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:55:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:44 INFO - 'MOZ_NO_REMOTE': '1', 02:55:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:55:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:55:44 INFO - 'NO_EM_RESTART': '1', 02:55:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:55:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:44 INFO - 'PWD': '/builds/slave/test', 02:55:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:55:44 INFO - 'RUST_BACKTRACE': 'full', 02:55:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:55:44 INFO - 'SHELL': '/bin/bash', 02:55:44 INFO - 'SHLVL': '1', 02:55:44 INFO - 'TERM': 'linux', 02:55:44 INFO - 'TMOUT': '86400', 02:55:44 INFO - 'USER': 'cltbld', 02:55:44 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:44 INFO - '_': '/tools/buildbot/bin/python'} 02:55:44 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/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:55:45 INFO - mozversion application_buildid: 20170915090318 02:55:45 INFO - mozversion application_changeset: f36106f3f52a9bb2769860d20568c589fc2498d0 02:55:45 INFO - mozversion application_display_name: Nightly 02:55:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:55:45 INFO - mozversion application_name: Firefox 02:55:45 INFO - mozversion application_remotingname: firefox 02:55:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:55:45 INFO - mozversion application_vendor: Mozilla 02:55:45 INFO - mozversion application_version: 57.0a1 02:55:45 INFO - mozversion platform_buildid: 20170915090318 02:55:45 INFO - mozversion platform_changeset: f36106f3f52a9bb2769860d20568c589fc2498d0 02:55:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:55:45 INFO - mozversion platform_version: 57.0a1 02:55:45 INFO - using testdate: 1505469345 02:55:45 INFO - actual date: 1505469345 02:55:45 INFO - starting webserver on 'localhost:46097' 02:55:45 INFO - SUITE-START | Running 2 tests 02:55:45 INFO - TEST-START | dromaeo_css 02:55:45 INFO - Initialising browser for dromaeo_css test... 02:55:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46097/getInfo.html -profile /tmp/tmpGi8I08/profile 02:55:45 INFO - TEST-INFO | started process 28753 (/builds/slave/test/build/application/firefox/firefox http://localhost:46097/getInfo.html) 02:56:58 INFO - TEST-INFO | 28753: killed by SIGKILL 02:58:15 INFO - Browser initialized. 02:58:15 INFO - Running cycle 1/1 for dromaeo_css test... 02:58:15 INFO - Using env: {'DISPLAY': ':0', 02:58:15 INFO - 'HOME': '/home/cltbld', 02:58:15 INFO - 'JSGC_DISABLE_POISONING': '1', 02:58:15 INFO - 'LANG': 'en_US.UTF-8', 02:58:15 INFO - 'LANGUAGE': 'en_US:en', 02:58:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:58:15 INFO - 'LOGNAME': 'cltbld', 02:58:15 INFO - 'MAIL': '/var/mail/cltbld', 02:58:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:58:15 INFO - 'MOZ_CRASHREPORTER': '1', 02:58:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:58:15 INFO - 'MOZ_NO_REMOTE': '1', 02:58:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:58:15 INFO - 'NO_EM_RESTART': '1', 02:58:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:58:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:15 INFO - 'PWD': '/builds/slave/test', 02:58:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:15 INFO - 'RUST_BACKTRACE': 'full', 02:58:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:58:15 INFO - 'SHELL': '/bin/bash', 02:58:15 INFO - 'SHLVL': '1', 02:58:15 INFO - 'TERM': 'linux', 02:58:15 INFO - 'TMOUT': '86400', 02:58:15 INFO - 'USER': 'cltbld', 02:58:15 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 02:58:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:15 INFO - '_': '/tools/buildbot/bin/python'} 02:58:15 INFO - TEST-INFO | started process 28980 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGi8I08/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:58:16 INFO - PID 28980 | 02:58:16 INFO - PID 28980 | (/builds/slave/test/build/application/firefox/firefox:29029): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:58:16 INFO - PID 28980 | 02:58:27 INFO - PID 28980 | RSS: Main: 182087680 02:58:27 INFO - PID 28980 | 03:01:28 INFO - PID 28980 | Cycle 1(1): loaded http://localhost:46097/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46097/tests/dromaeo/cssquery-ext.html) 03:01:29 INFO - PID 28980 | RSS: Main: 176099328 03:01:29 INFO - PID 28980 | 03:04:31 INFO - PID 28980 | Cycle 1(1): loaded http://localhost:46097/tests/dromaeo/cssquery-ext.html (next: http://localhost:46097/tests/dromaeo/cssquery-jquery.html) 03:04:31 INFO - PID 28980 | RSS: Main: 174415872 03:04:31 INFO - PID 28980 | 03:07:33 INFO - PID 28980 | Cycle 1(1): loaded http://localhost:46097/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46097/tests/dromaeo/cssquery-mootools.html) 03:07:34 INFO - PID 28980 | RSS: Main: 175624192 03:07:34 INFO - PID 28980 | 03:10:36 INFO - PID 28980 | Cycle 1(1): loaded http://localhost:46097/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46097/tests/dromaeo/cssquery-prototype.html) 03:10:36 INFO - PID 28980 | RSS: Main: 173428736 03:10:36 INFO - PID 28980 | 03:13:39 INFO - PID 28980 | Cycle 1(1): loaded http://localhost:46097/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46097/tests/dromaeo/cssquery-yui.html) 03:13:40 INFO - PID 28980 | RSS: Main: 175218688 03:13:40 INFO - PID 28980 | 03:16:42 INFO - PID 28980 | Cycle 1(1): loaded http://localhost:46097/tests/dromaeo/cssquery-yui.html (next: http://localhost:46097/tests/dromaeo/cssquery-dojo.html) 03:16:42 INFO - PID 28980 | RSS: Main: 179605504 03:16:42 INFO - PID 28980 | 03:16:42 INFO - PID 28980 | __start_tp_report 03:16:42 INFO - PID 28980 | _x_x_mozilla_page_load 03:16:42 INFO - PID 28980 | _x_x_mozilla_page_load_details 03:16:42 INFO - PID 28980 | |i|pagename|runs| 03:16:42 INFO - PID 28980 | |0;dojo.html;3155.857986390612;3173.8730450781964;3190.409774191773;3190.8723651053974;3195.169255993441;6194.907076393858;6339.619622822628;6359.523035772312;6401.935980640193;6423.454006409455;6282.497400207983;6306.779262725806;6320.620762754238;6321.367863213688;6334.683265836713;5760.942390576103;5772.191893134965;5775.10485874688;5795.449432303924;5913.763449462016;5858.238429004224;5879.941200587982;5893.970530147365;5923.259592550931;5956.7617295308355;5560.666360018371;5838.065909454488;5918.4673379395845;5922.822315330547;5931.762729490815;5703.77184912603;5740.54075673945;5770.13447982806;5816.331121920991;5849.824505264849;5777.59556831022;5790.33411157718;5838.7080645967535;5842.707864606795;5849.795257165979;6196.101565273055;6290.21372328459;6291.653959032297;6345.904811427832;6380.170577824853;6782.966085169592;6794.45644348451;6831.795046148623;6836.213835408892;6852.897206541906;6838.692258848314;6894.24163342032;6904.551204171713;6909.827254318632;6911.516193866431;6337.7781777637565;6345.80962571119;6379.457746091588;6386.457151142158;6430.260520040255;6841.17905851301;6926.549774264657;6930.826729331706;6986.720531178696;7104.928950710427;6527.02094685801;6588.73645054202;6612.504062195355;6614.173228346457;6625.834354141089;6675.299093595199;6702.564333318319;6748.527603067738;6767.661616919135;6825.9317406826285;6773;6788.083608712859;6843.076184715099;6860.416864566568;6864.862702745917;6805.319468053254;6842.521023528305;6887.931120688729;6892.620905850226;6910.930890691129;5227.712475813867;5292.761825717852;5311.973440132774;5334.839954801362;5339.893202135935;4636.11913736389;4669.976650116728;4700.764961751899;4731.408573928259;4751.263554149113;5839.941600584025;5888.646681199141;5895.616784908967;5906.763729450774;5908.409159084057;6858.862822743517;6874.621895795779;6926.238113807477;6939.959006149118;6940.7223711052;5740.770369185269;5816.069428891442;5829.183914252008;5869.178315035899;5908.556858235649;5749.741261643236;5749.88500229993;5767.826965191051;5770.538356931435;5771.451712087345;6513.055606937062;6564.671766411756;6574.605523668595;6594.7032383542855;6605.636689982001;6842.692078856364;6881.483888708267;6936.583804971718;6942.548734332252;6965.791026269321;6526.804195873941;6530.412262896362;6602.471802255904;6633.668316584248;6634.170728658918;6574.8356291093105;6646.202455705346;6650.534462587572;6688.498362622726;6732.596044237362;6728.158980127484;6786.69459874297;6803.659817009228;6820.795376138723;6827.52207345481;6580.5722628029025;6675.532712710064;6679.799606011828;6689.732410703517;6690.464762818866;6610.140681711347;6638.867222655424;6679.8664026718225;6701.966490167518;6719.596824190564;6386.393292637192;6552.312007239362;6581.473482121514;6640.767573134917;6651.33486651331;6481.189851268591;6578.375054369827;6593.208814942238;6615.636140012345;6684.632345220865;6382.648954307372;6416.967915160394;6441;6441.645709485836;6451.967740161269 03:16:42 INFO - PID 28980 | |1;ext.html;844.8563744163491;853.782285517193;862.6894318045504;896.5293221058944;897.3270047464404;39419.2116157677;40168.799156004214;40174.7991260044;40275;40311.19377612446;36570.63429365706;36702.26595468089;36727.08182295444;36781;36814.63185368146;6898.310168983112;7018.789436316919;7029.824254393656;7034.296570342976;7041;6953.6870840812035;7048.823779405505;7050.471214658895;7065.646717664122;7067.222605513415;7052.435805135602;7082.716691332341;7633.503822251564;7851.921480785205;7852.371810255195;7738.535687858718;7776.339011184056;7783.299503044753;7803.141654418009;7811.8437631247625;7559.20628334028;7681.3854891608535;7706.07527096752;7707.152213256537;7712.537247765152;36531;36750.63249367499;37466.81266593649;37487.250254994746;37693.81153094217;3514.7715398499017;3589.892303230907;3596.2088340565033;3597.5323207983056;3598.676119149289;31192.688073119207;31382.215444613837;31403.37193256145;31491;31518.52722209169;4947.975260123712;4958.652894297401;4983.775730092155;5001.724905130216;5008.874778130539;191019;194634;195403;198654;200707.99999999854;47825.282620760714;47859.76070119627;47980.280295795594;47986;48046.5195348042;49233.75383123133;49287.260691089665;49301.26048109281;49326;49331.26003109956;15921.761173582405;16226.864119511756;16268.186590670419;16289.511314660342;16338.183090845649;13436;13438.059335846398;13457.461701532025;13461.663458413617;13478.460861565623;13849.515266965607;13872.514461993782;13901.860981390057;14031.649208769863;14180.220087895059;5485.424030476743;5649.717514124277;5740.655560666373;5766.509846663081;5793.710314484343;977.7848873248016;1007.2244371833647;1007.5818535307911;1008.6368907193405;1013.189448441244;22908.4272893179;22910.885445572665;22984.885075574515;23182.072717091127;23238.883805580863;1111.7053980695123;1115.3921112993437;1115.642994241835;1115.9776804463866;1125.2460851229696;12025.977791887788;12057.819132713017;12130.69673258158;12193.573224937085;12323.322217278135;225933;226287;226418;226767;227320;50851.7457412718;50884.49115508798;51361;51615.48384516107;51621.22568161481;1427.5931359562476;1446.2840893757657;1453.3968403112801;1471.69830184814;1472.1314424489387;22951.196708115134;23049.423764406023;23053.65419518675;23157;23512.88243558771;36949.44575831311;37045.44431833579;37092.81453592715;37109.257814844095;37181.44227836639;189404;189480;189812;189870;189956;30992;31233.531497027107;31304.843475782476;34359.828200858836;34824.82587587046;42871.3569296467;43559.78220108879;43814.56185438232;44066.779666101465;44523.332150018425;32231.194220144684;32271.51592726064;32425.189370265933;32517.349653006335;32573.837130814194;31230;31635.36729265356;31857.203569910936;32012.67987320097;32221;34587.65412345945;34985.12537186489;35501.75743848953;35647.28705425929;35900.461493077106;30661.54007689931;31183.53224701586;31246.21884452816;31256.843715781277;31282.53076203813;22924.770752292265;22972.540549189256;23138.421539461648;23635.40911477227;23787.76212237925 03:16:42 INFO - PID 28980 | |2;jquery.html;550.4248060776488;564.1087082409792;573.9942600573993;576.0840263980272;582.6271186440677;21686.024128914196;22545.09819607218;22575.661365079515;22586.435339116488;22593;22115;24282.87858560709;24487.142949996753;24759.504809903792;24980.25059248221;6420.518461115424;6540.117084193621;6583.111279977214;6587.374199451068;6594.109795177662;6287.339829317924;6477.902831457532;6558.475321974231;6560.409563139317;6572.342765723437;6492.967535162317;6505.4145126938565;6508.121403610523;6514.3811337922825;6515.38103880133;6252.155958945529;6462.48300135988;6483.1247781549355;6539.4441472474655;6595.208574970986;6083.665398403086;6091.543134264948;6101.176341193971;6103;6107.389261073901;27687;28104;28121;28129.859350703115;28162;1915.3200613782046;1972.260402349119;1988.1848442138798;1995.980040199594;1996.2414282572674;18826.152823122993;18949.052547372714;19017.429477115707;19163.904180479007;19295.03524823753;2354.8940297686645;2359.9292021239216;2405.530921470314;2408.506256217471;2416.1784993102256;109022;115416;129927;131392;134841.3257933714;14144.968683925032;14206.147631142165;14466.493672721615;14640.121592704472;14755.55733328002;21877.67183492249;22083.447913802283;22105.115795368005;22119.778802212095;22139.667904981437;9801.31390802637;10129.290949633454;10140.645077422254;10151.492425378849;10152.593896244067;9349.813003740022;9391.765205869908;9397;9426.95286523563;9462.101100395452;8723.168134691787;8755;8764.824703506021;8933.776655583662;9036.502992335354;2551.311145990572;2578.097665816949;2618.319236998394;2633.2482555729307;2677.0630279402053;939.43633819709;955.2501286490071;957.4590356448584;975.5951280218629;981.6024510073431;18239.36162234342;18524.351647692267;18623.720644190347;18786.530336741416;18833.717494237597;1036.6112707734599;1039.0907955538903;1047.607147319755;1050.837120246363;1053.7629033467408;10107.797844043078;10211.468279757948;10434.947825260826;10473.581056757645;10636.989485998818;420104;421426;425124;429526;448808;102987;106428;107741.4612926946;109645.45177274062;111654;1002.368507840056;1059.7456610413444;1132.694172573417;1140.5551834784442;1178.587494376961;19291.61416771685;19706.31027914016;19768.604627907647;19797.60404791925;19972.50068748293;54585.18122228249;54645;55055.72472137614;56330;56415.15377269426;66322.66838665775;66984;67236.66381668061;67257.66371168113;67916.6604166976;13568.93215533916;13975.231362275172;14013.299335033003;14069.648258793612;14095.788563171765;21825.890870545547;21864.89067554652;21995.670064949358;22152.113915443202;22158.335249942527;17389;17710.203040862936;17852.285908563514;17895.284188632308;17971.640567188846;14209.928950355183;15330.233488325763;15518.301676424813;15613.921930390276;15648.687026259639;16912.746308805625;17003.91498042502;17125.314987400365;17144;18050.187741551923;13528.59414217575;13774.586762397144;13845.653858653613;13847.376868040761;13855.722885542435;17539.56151096182;17645.29418823233;17728.91135544314;17770.111494425484;17808.46574602764 03:16:42 INFO - PID 28980 | |3;mootools.html;1344.7310537892422;1384.5084994826836;1388.9027768056235;1389.1248513436533;1398.2659103970414;14677.266136693162;14965.401383944629;14986.775198372034;15043.623909402271;15289;14583.781243281359;14774.926125369386;14961;15058.096514209155;15060.39758409665;2797.860106994652;2806.4387122575463;2855.3860919902218;2857.771378289742;2858.5426331786916;2767.059199872043;2808.1435162275498;2815.53543665295;2821.7460428561426;2833.5891295762103;2764.986175069132;2822.5625028120676;2861.527847905093;2864.283929017746;2866.2261189478827;2857.2713957940696;2874.7987640865254;2880.006397792758;2894.52240380337;2907.854607269628;2763.350612606036;2789.4142230131697;2794.6087547743127;2797.776177905763;2801.551751719715;14595.197264150467;14945.252737363197;14966.850331496655;14972.47596334134;14992.925035374754;7741.806454838674;8018.6391612377;8020.839583208303;8037.316828069563;8037.477563958382;5264.2893209416625;5358.946410535923;5471;5512.476314750133;5539.086050801613;1774.4233124234675;1784.4557409989948;1802.5583731985616;1811.7644706188244;1823.4256209294163;26076.73923260781;26188.214353569423;26244.606330904862;26294.474110517873;26444.73555264442;6592.571482853599;6714.798556043326;6790.354916282945;6919.100516932766;6951.304869513109;16986.490405287863;16993;16995.23521441538;16998.150092495573;17005.48983530496;8187.26314631686;8449.32405407566;8451.36614753896;8451.619677114544;8453.492790432594;7395.4083673306;7518.173000969889;7524;7553.546787192786;7569.053868266466;5064.594832413398;5253.50091741284;5256.39551451588;5279.841604751864;5290.285811415487;1392.5195807446414;1417.5463851567606;1530.5790907500527;1533.8926275160632;1541.7533194688797;892.5537231384308;909.8180363927241;913.8400779863497;914.4421902639384;926.8192702422963;8116.228958248957;8193.180681931759;8198.877016844752;8211.876821847676;8246.134155913664;959.836827739286;962.451402700454;962.7304354780532;981.764376549623;982.7837875667342;5004.099262132779;5079.314292570456;5085.618578606619;5137.948620513822;5191.922121168185;20411.489712757004;20689.68965465519;20765.792342076387;21078.683819742717;21576.784232157792;20975.580488390147;21066.683999740017;21108.894455527625;21476.677849832264;21688;10641.095506882002;10652.520636571373;10676.946615266874;10687;10809.459527023617;7648.502847314907;8258.545779982158;8267.83464330722;8304.377171712267;8321.209485098905;17920.462386128438;18004.54988625295;18079.819201807815;18415.53961150982;18622.72065918985;18474.168662409957;18629.72055419197;18639.72040419422;18831.24675012984;18924.905375473034;10807.135429165804;10882;11043.613473528389;11065.889341106486;11080.279781814157;13542;13869.791953120914;13875.306234688427;13898.096623719426;13898.23559704226;9894.15899648538;9986.300958932805;10197.694069177936;10198.643047493302;10341.482925853526;11077.667669969915;11248.606298779503;11292.209545332076;11408.600698975495;11422.143339249424;9566.712998610054;9761.609535618496;9789.363691360039;9892.356996795184;9893.159081477866;6224.813255602339;6248.031555108783;6264.718087686157;6311.495080393649;6411.903821442589;6630.801075967729;6701.765438209834;6713.966430167818;6732.8653426930205;6776.830579235559 03:16:42 INFO - PID 28980 | |4;prototype.html;495.81015870834074;499.9950495539647;520.4535238000099;525.4246599973029;526.7182057599185;6317.273513545943;6359.55483116182;6365.29981702013;6409.391107844759;6437.517186211036;6258.217722784652;6290.276618188909;6298.149749783789;6337.8415539611415;6344.143540622014;5228.73856307185;5233.712145831978;5252.264682944391;5253.500917412859;5253.842384728464;5204.42751297357;5239;5263.789448422059;5286.497782710637;5288.709120998344;5092.166819944817;5111.6166287528395;5152.690838549699;5210.322658054448;5244.160934250521;4737.976310118444;4897.436794768611;4908.950910490885;4931.901361972758;5000.174971129759;4437.4896886858105;4446.221911165533;4454.866354009385;4469.3296005599095;4481.327800829869;5953.83900139473;6038.426349496791;6092.147099406086;6101.786437474667;6170.382961703838;5684.54523638108;5730.596003979052;5731.570132240098;5733;5743.712814359265;6526.836829079311;6545.574537655038;6556.672166391661;6584.30864759203;6601.306862779387;4610.124076425468;4788.640851936118;4823.951760482385;4838.588719958808;4846.321514987904;6687.297833727437;6723.193216813965;6755.560888542229;6825.897611535831;6862.9313706862795;4981.800727970913;4990.226514890198;4990.431841233535;5010.2735103409805;5013.696438925906;5025.874353141201;5026.195808670595;5027.824026159067;5050.3434553507805;5064.315912566346;5627.467639562214;5683.119116536944;5686.147077938343;5699.116636921284;5729.369769325371;6612.636305003175;6633.568818026813;6666.233383160901;6697.162854643169;6698.4832962694;1754.754334393186;1760.9559761006078;1762.5769815244248;1768.5321183417732;1781.3320004998125;1133.218079525125;1172.2263306217856;1212.2786941769632;1225.7609766095525;1233.1429656388902;5747.080467125324;5755.395683453177;5770.336411312669;5777.8555536111935;5791.276090488689;6613.6362500062;6694.824182140726;6743.797686069426;6777.93222067773;6788;5580.246666700024;5638.054871069475;5648.209250704905;5654.787183548201;5680.403557626472;3471.9063494998995;3474.947875781865;3522.9214763289783;3542.344666236703;3553.9387155449776;6251.562390632703;6351.650659213788;6356.173697419306;6393.2328120625825;6447.742090316428;6813.216480104851;6822.9317706823285;6835.965820170867;6843.213030501456;6855.520113592101;5800.216970708901;6373.362663733682;6457.483401327882;6465.547411681137;6524.64114473718;6480.546361754632;6557.868842623026;6587.242467116152;6684.264730879502;6696.631685257357;6462.967685161544;6493.188351456068;6544.54188206821;6549.4760419167305;6632.767853125118;6333.6516491591565;6404.519661025349;6407.679616019087;6431.421172094534;6437.25971513282;4900.26496025599;4930.40835099776;4963.404391472902;4967.279744437109;4994.076095922266;6475;6508.284088750139;6527.834000509654;6591;6804.761833336008;6421.389967953037;6490.961446168591;6501.9843848407145;6539.149910511603;6547.377999090078;6345.333739957238;6355.650439225887;6418.170821315903;6523.478121750154;6539;6305.936940630535;6387.712552935222;6393.8721225576155;6508.576942498722;6522.249941256816;6607.80176594703;6625.50308726857;6626.801195964129;6651.966740166268;6683.331666833278;6412.038194270896;6417;6431.421172094534;6438.742450301936;6464.450521705519 03:16:42 INFO - PID 28980 | |5;yui.html;890.8930928288606;898.1736802142029;917.756794449471;924.2975338742554;924.5100096948618;18989.52526186846;19797.901010494927;19809.60380792385;19829.603407931834;19849.3052743154;17730.468085957444;18039.63920721585;18342.16578342165;18524;18696.15867285972;960.9327347085689;984.4388698441891;990.3859607043643;993.7167907146464;994.2294721590789;968.4334664221439;984.1437948984394;987.0918754745651;989.1641562879355;990.9108180263775;1049.0401282826206;1051.059301924777;1052.0216198934988;1054.5834395413845;1056.0917610854658;1009.4001008401692;1041.6458404142552;1049.5486940615533;1049.8950104989478;1055.9630412935549;750.8573371059481;751.8909758085101;752.0749478141861;752.6537792615403;753.5629334985695;20018.69971950422;20026.1989520419;20196;20219.898900505403;20300.695489567668;21163.883611638867;22056.117755289768;22072;22303.21938732153;22390.104395824146;15731.134787586678;15991.520254392386;16131.193440328054;16133;16244.75632865508;3561.857525698969;3661.5789184243886;3664.633536646341;3666.17511060012;3681.834317455694;134731;135435;135494.3225283877;137028;138601.3069934654;15827.604309892346;16168.838311616968;16418.097004664618;16421.42525011619;16593;24983.500329993298;28409.715902840708;28452.430951380855;28521.144365669064;28636;8459.619317130744;8555.358348123915;8557.828843423096;8570.400071995025;8586.227239548345;3978.6817054635558;4088.6524645405475;4097.1191193893455;4097.262492751333;4099;5498.285222920995;5563.749631266603;5589.972050139723;5610.85972850682;5618.494335509824;966.4104896013566;982.6806287956442;985.6845809340942;991.5934466868549;998.9550470228712;675.2538445018282;689.6827459368646;691.339770519153;702.5293053654065;709.7303024850523;10642.521086551124;10916.399598022026;10959.726006849733;11101.44492775358;11218.495167717309;672.9065268937976;673.3468535520635;680.8025672555002;688.277308825731;700.5971566349369;5204.193350030751;5241.187615919539;5242.449542798027;5266.289050978069;5272;120852;122108.38945805214;122975;124145;126465.36767316288;101464;105258.47370763097;106362;106480.46759766151;106691;89511.55244223737;95894.52052739692;97277.51361243149;98771.50614246883;99877;9697.466639334903;9758.512074396394;9770.951145244228;9876.308658394131;9950.800983980134;42271.365929511696;42526.149477010906;42875.57124428716;42937.14125717406;43176;52715.47284527259;53260.73369633127;53922.19116713174;55683.44316556783;55987;13847.099938503965;13891.37488813026;14084.718305634035;14175.574732758034;14187.929060354632;14550.927245363706;14920.477783277533;15291.388344466543;15392.384304627689;15444;13837.861621383658;14002.159870407808;14026.438942442595;14206.07660502064;14215.644608884448;13450.529231476852;13589.116707413987;13628.591142265748;13665.521706740614;13712.245826479639;13347.265900375573;13370.732585348043;13486;13522.93238533801;13656.863431365558;11207.887921120684;11280.943595281971;11322.150838687296;11336.829947550958;11383.260088094248;11368.431578421212;11585.131115166565;11801.645950621494;11824.70438239051;12213.633590992284 03:16:42 INFO - PID 28980 | __end_tp_report 03:16:42 INFO - PID 28980 | __start_cc_report 03:16:42 INFO - PID 28980 | _x_x_mozilla_cycle_collect,371 03:16:42 INFO - PID 28980 | __end_cc_report 03:16:42 INFO - PID 28980 | __startTimestamp1505470602476__endTimestamp 03:16:42 INFO - PID 28980 | 03:16:42 INFO - PID 28980 | ------- Summary: start ------- 03:16:42 INFO - PID 28980 | Number of tests: 6 03:16:42 INFO - PID 28980 | 03:16:42 INFO - PID 28980 | [#0] dojo.html Cycles:165 Average:6247.79 Median:6513.06 stddev:756.09 (11.6%) stddev-sans-first:718.45 03:16:42 INFO - PID 28980 | Values: 3155.9 3173.9 3190.4 3190.9 3195.2 6194.9 6339.6 6359.5 6401.9 6423.5 6282.5 6306.8 6320.6 6321.4 6334.7 5760.9 5772.2 5775.1 5795.4 5913.8 5858.2 5879.9 5894.0 5923.3 5956.8 5560.7 5838.1 5918.5 5922.8 5931.8 5703.8 5740.5 5770.1 5816.3 5849.8 5777.6 5790.3 5838.7 5842.7 5849.8 6196.1 6290.2 6291.7 6345.9 6380.2 6783.0 6794.5 6831.8 6836.2 6852.9 6838.7 6894.2 6904.6 6909.8 6911.5 6337.8 6345.8 6379.5 6386.5 6430.3 6841.2 6926.5 6930.8 6986.7 7104.9 6527.0 6588.7 6612.5 6614.2 6625.8 6675.3 6702.6 6748.5 6767.7 6825.9 6773.0 6788.1 6843.1 6860.4 6864.9 6805.3 6842.5 6887.9 6892.6 6910.9 5227.7 5292.8 5312.0 5334.8 5339.9 4636.1 4670.0 4700.8 4731.4 4751.3 5839.9 5888.6 5895.6 5906.8 5908.4 6858.9 6874.6 6926.2 6940.0 6940.7 5740.8 5816.1 5829.2 5869.2 5908.6 5749.7 5749.9 5767.8 5770.5 5771.5 6513.1 6564.7 6574.6 6594.7 6605.6 6842.7 6881.5 6936.6 6942.5 6965.8 6526.8 6530.4 6602.5 6633.7 6634.2 6574.8 6646.2 6650.5 6688.5 6732.6 6728.2 6786.7 6803.7 6820.8 6827.5 6580.6 6675.5 6679.8 6689.7 6690.5 6610.1 6638.9 6679.9 6702.0 6719.6 6386.4 6552.3 6581.5 6640.8 6651.3 6481.2 6578.4 6593.2 6615.6 6684.6 6382.6 6417.0 6441.0 6441.6 6452.0 03:16:42 INFO - PID 28980 | 03:16:42 INFO - PID 28980 | [#1] ext.html Cycles:180 Average:36977.26 Median:19249.95 stddev:53010.53 (275.4%) stddev-sans-first:53089.81 03:16:42 INFO - PID 28980 | Values: 844.9 853.8 862.7 896.5 897.3 39419.2 40168.8 40174.8 40275.0 40311.2 36570.6 36702.3 36727.1 36781.0 36814.6 6898.3 7018.8 7029.8 7034.3 7041.0 6953.7 7048.8 7050.5 7065.6 7067.2 7052.4 7082.7 7633.5 7851.9 7852.4 7738.5 7776.3 7783.3 7803.1 7811.8 7559.2 7681.4 7706.1 7707.2 7712.5 36531.0 36750.6 37466.8 37487.3 37693.8 3514.8 3589.9 3596.2 3597.5 3598.7 31192.7 31382.2 31403.4 31491.0 31518.5 4948.0 4958.7 4983.8 5001.7 5008.9 191019.0 194634.0 195403.0 198654.0 200708.0 47825.3 47859.8 47980.3 47986.0 48046.5 49233.8 49287.3 49301.3 49326.0 49331.3 15921.8 16226.9 16268.2 16289.5 16338.2 13436.0 13438.1 13457.5 13461.7 13478.5 13849.5 13872.5 13901.9 14031.6 14180.2 5485.4 5649.7 5740.7 5766.5 5793.7 977.8 1007.2 1007.6 1008.6 1013.2 22908.4 22910.9 22984.9 23182.1 23238.9 1111.7 1115.4 1115.6 1116.0 1125.2 12026.0 12057.8 12130.7 12193.6 12323.3 225933.0 226287.0 226418.0 226767.0 227320.0 50851.7 50884.5 51361.0 51615.5 51621.2 1427.6 1446.3 1453.4 1471.7 1472.1 22951.2 23049.4 23053.7 23157.0 23512.9 36949.4 37045.4 37092.8 37109.3 37181.4 189404.0 189480.0 189812.0 189870.0 189956.0 30992.0 31233.5 31304.8 34359.8 34824.8 42871.4 43559.8 43814.6 44066.8 44523.3 32231.2 32271.5 32425.2 32517.3 32573.8 31230.0 31635.4 31857.2 32012.7 32221.0 34587.7 34985.1 35501.8 35647.3 35900.5 30661.5 31183.5 31246.2 31256.8 31282.5 22924.8 22972.5 23138.4 23635.4 23787.8 03:16:42 INFO - PID 28980 | 03:16:42 INFO - PID 28980 | [#2] jquery.html Cycles:180 Average:31970.38 Median:22110.06 stddev:72531.62 (328.0%) stddev-sans-first:72696.73 03:16:42 INFO - PID 28980 | Values: 550.4 564.1 574.0 576.1 582.6 21686.0 22545.1 22575.7 22586.4 22593.0 22115.0 24282.9 24487.1 24759.5 24980.3 6420.5 6540.1 6583.1 6587.4 6594.1 6287.3 6477.9 6558.5 6560.4 6572.3 6493.0 6505.4 6508.1 6514.4 6515.4 6252.2 6462.5 6483.1 6539.4 6595.2 6083.7 6091.5 6101.2 6103.0 6107.4 27687.0 28104.0 28121.0 28129.9 28162.0 1915.3 1972.3 1988.2 1996.0 1996.2 18826.2 18949.1 19017.4 19163.9 19295.0 2354.9 2359.9 2405.5 2408.5 2416.2 109022.0 115416.0 129927.0 131392.0 134841.3 14145.0 14206.1 14466.5 14640.1 14755.6 21877.7 22083.4 22105.1 22119.8 22139.7 9801.3 10129.3 10140.6 10151.5 10152.6 9349.8 9391.8 9397.0 9427.0 9462.1 8723.2 8755.0 8764.8 8933.8 9036.5 2551.3 2578.1 2618.3 2633.2 2677.1 939.4 955.3 957.5 975.6 981.6 18239.4 18524.4 18623.7 18786.5 18833.7 1036.6 1039.1 1047.6 1050.8 1053.8 10107.8 10211.5 10434.9 10473.6 10637.0 420104.0 421426.0 425124.0 429526.0 448808.0 102987.0 106428.0 107741.5 109645.5 111654.0 1002.4 1059.7 1132.7 1140.6 1178.6 19291.6 19706.3 19768.6 19797.6 19972.5 54585.2 54645.0 55055.7 56330.0 56415.2 66322.7 66984.0 67236.7 67257.7 67916.7 13568.9 13975.2 14013.3 14069.6 14095.8 21825.9 21864.9 21995.7 22152.1 22158.3 17389.0 17710.2 17852.3 17895.3 17971.6 14209.9 15330.2 15518.3 15613.9 15648.7 16912.7 17003.9 17125.3 17144.0 18050.2 13528.6 13774.6 13845.7 13847.4 13855.7 17539.6 17645.3 17728.9 17770.1 17808.5 03:16:42 INFO - PID 28980 | 03:16:42 INFO - PID 28980 | [#3] mootools.html Cycles:180 Average:9174.00 Median:2804.00 stddev:6528.21 (232.8%) stddev-sans-first:6520.01 03:16:42 INFO - PID 28980 | Values: 1344.7 1384.5 1388.9 1389.1 1398.3 14677.3 14965.4 14986.8 15043.6 15289.0 14583.8 14774.9 14961.0 15058.1 15060.4 2797.9 2806.4 2855.4 2857.8 2858.5 2767.1 2808.1 2815.5 2821.7 2833.6 2765.0 2822.6 2861.5 2864.3 2866.2 2857.3 2874.8 2880.0 2894.5 2907.9 2763.4 2789.4 2794.6 2797.8 2801.6 14595.2 14945.3 14966.9 14972.5 14992.9 7741.8 8018.6 8020.8 8037.3 8037.5 5264.3 5358.9 5471.0 5512.5 5539.1 1774.4 1784.5 1802.6 1811.8 1823.4 26076.7 26188.2 26244.6 26294.5 26444.7 6592.6 6714.8 6790.4 6919.1 6951.3 16986.5 16993.0 16995.2 16998.2 17005.5 8187.3 8449.3 8451.4 8451.6 8453.5 7395.4 7518.2 7524.0 7553.5 7569.1 5064.6 5253.5 5256.4 5279.8 5290.3 1392.5 1417.5 1530.6 1533.9 1541.8 892.6 909.8 913.8 914.4 926.8 8116.2 8193.2 8198.9 8211.9 8246.1 959.8 962.5 962.7 981.8 982.8 5004.1 5079.3 5085.6 5137.9 5191.9 20411.5 20689.7 20765.8 21078.7 21576.8 20975.6 21066.7 21108.9 21476.7 21688.0 10641.1 10652.5 10676.9 10687.0 10809.5 7648.5 8258.5 8267.8 8304.4 8321.2 17920.5 18004.5 18079.8 18415.5 18622.7 18474.2 18629.7 18639.7 18831.2 18924.9 10807.1 10882.0 11043.6 11065.9 11080.3 13542.0 13869.8 13875.3 13898.1 13898.2 9894.2 9986.3 10197.7 10198.6 10341.5 11077.7 11248.6 11292.2 11408.6 11422.1 9566.7 9761.6 9789.4 9892.4 9893.2 6224.8 6248.0 6264.7 6311.5 6411.9 6630.8 6701.8 6714.0 6732.9 6776.8 03:16:42 INFO - PID 28980 | 03:16:42 INFO - PID 28980 | [#4] prototype.html Cycles:180 Average:5503.83 Median:6210.97 stddev:1534.56 (24.7%) stddev-sans-first:1492.12 03:16:42 INFO - PID 28980 | Values: 495.8 500.0 520.5 525.4 526.7 6317.3 6359.6 6365.3 6409.4 6437.5 6258.2 6290.3 6298.1 6337.8 6344.1 5228.7 5233.7 5252.3 5253.5 5253.8 5204.4 5239.0 5263.8 5286.5 5288.7 5092.2 5111.6 5152.7 5210.3 5244.2 4738.0 4897.4 4909.0 4931.9 5000.2 4437.5 4446.2 4454.9 4469.3 4481.3 5953.8 6038.4 6092.1 6101.8 6170.4 5684.5 5730.6 5731.6 5733.0 5743.7 6526.8 6545.6 6556.7 6584.3 6601.3 4610.1 4788.6 4824.0 4838.6 4846.3 6687.3 6723.2 6755.6 6825.9 6862.9 4981.8 4990.2 4990.4 5010.3 5013.7 5025.9 5026.2 5027.8 5050.3 5064.3 5627.5 5683.1 5686.1 5699.1 5729.4 6612.6 6633.6 6666.2 6697.2 6698.5 1754.8 1761.0 1762.6 1768.5 1781.3 1133.2 1172.2 1212.3 1225.8 1233.1 5747.1 5755.4 5770.3 5777.9 5791.3 6613.6 6694.8 6743.8 6777.9 6788.0 5580.2 5638.1 5648.2 5654.8 5680.4 3471.9 3474.9 3522.9 3542.3 3553.9 6251.6 6351.7 6356.2 6393.2 6447.7 6813.2 6822.9 6836.0 6843.2 6855.5 5800.2 6373.4 6457.5 6465.5 6524.6 6480.5 6557.9 6587.2 6684.3 6696.6 6463.0 6493.2 6544.5 6549.5 6632.8 6333.7 6404.5 6407.7 6431.4 6437.3 4900.3 4930.4 4963.4 4967.3 4994.1 6475.0 6508.3 6527.8 6591.0 6804.8 6421.4 6491.0 6502.0 6539.1 6547.4 6345.3 6355.7 6418.2 6523.5 6539.0 6305.9 6387.7 6393.9 6508.6 6522.2 6607.8 6625.5 6626.8 6652.0 6683.3 6412.0 6417.0 6431.4 6438.7 6464.5 03:16:42 INFO - PID 28980 | 03:16:42 INFO - PID 28980 | [#5] yui.html Cycles:180 Average:23546.62 Median:21610.00 stddev:34861.73 (161.3%) stddev-sans-first:34918.02 03:16:42 INFO - PID 28980 | Values: 890.9 898.2 917.8 924.3 924.5 18989.5 19797.9 19809.6 19829.6 19849.3 17730.5 18039.6 18342.2 18524.0 18696.2 960.9 984.4 990.4 993.7 994.2 968.4 984.1 987.1 989.2 990.9 1049.0 1051.1 1052.0 1054.6 1056.1 1009.4 1041.6 1049.5 1049.9 1056.0 750.9 751.9 752.1 752.7 753.6 20018.7 20026.2 20196.0 20219.9 20300.7 21163.9 22056.1 22072.0 22303.2 22390.1 15731.1 15991.5 16131.2 16133.0 16244.8 3561.9 3661.6 3664.6 3666.2 3681.8 134731.0 135435.0 135494.3 137028.0 138601.3 15827.6 16168.8 16418.1 16421.4 16593.0 24983.5 28409.7 28452.4 28521.1 28636.0 8459.6 8555.4 8557.8 8570.4 8586.2 3978.7 4088.7 4097.1 4097.3 4099.0 5498.3 5563.7 5590.0 5610.9 5618.5 966.4 982.7 985.7 991.6 999.0 675.3 689.7 691.3 702.5 709.7 10642.5 10916.4 10959.7 11101.4 11218.5 672.9 673.3 680.8 688.3 700.6 5204.2 5241.2 5242.4 5266.3 5272.0 120852.0 122108.4 122975.0 124145.0 126465.4 101464.0 105258.5 106362.0 106480.5 106691.0 89511.6 95894.5 97277.5 98771.5 99877.0 9697.5 9758.5 9771.0 9876.3 9950.8 42271.4 42526.1 42875.6 42937.1 43176.0 52715.5 53260.7 53922.2 55683.4 55987.0 13847.1 13891.4 14084.7 14175.6 14187.9 14550.9 14920.5 15291.4 15392.4 15444.0 13837.9 14002.2 14026.4 14206.1 14215.6 13450.5 13589.1 13628.6 13665.5 13712.2 13347.3 13370.7 13486.0 13522.9 13656.9 11207.9 11280.9 11322.2 11336.8 11383.3 11368.4 11585.1 11801.6 11824.7 12213.6 03:16:42 INFO - PID 28980 | -------- Summary: end -------- 03:16:42 INFO - PID 28980 | 03:16:45 INFO - TEST-INFO | 28980: exit 0 03:16:45 INFO - TEST-OK | dromaeo_css | took 1260447ms 03:16:45 INFO - TEST-START | kraken 03:16:45 INFO - Initialising browser for kraken test... 03:16:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46097/getInfo.html -profile /tmp/tmpm8Q621/profile 03:16:45 INFO - TEST-INFO | started process 29351 (/builds/slave/test/build/application/firefox/firefox http://localhost:46097/getInfo.html) 03:17:17 INFO - TEST-INFO | 29351: exit 0 03:17:19 INFO - Browser initialized. 03:17:19 INFO - Running cycle 1/1 for kraken test... 03:17:19 INFO - Using env: {'DISPLAY': ':0', 03:17:19 INFO - 'HOME': '/home/cltbld', 03:17:19 INFO - 'JSGC_DISABLE_POISONING': '1', 03:17:19 INFO - 'LANG': 'en_US.UTF-8', 03:17:19 INFO - 'LANGUAGE': 'en_US:en', 03:17:19 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:17:19 INFO - 'LOGNAME': 'cltbld', 03:17:19 INFO - 'MAIL': '/var/mail/cltbld', 03:17:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:17:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:17:19 INFO - 'MOZ_CRASHREPORTER': '1', 03:17:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:19 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:17:19 INFO - 'MOZ_NO_REMOTE': '1', 03:17:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:17:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:17:19 INFO - 'NO_EM_RESTART': '1', 03:17:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:17:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:19 INFO - 'PWD': '/builds/slave/test', 03:17:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:17:19 INFO - 'RUST_BACKTRACE': 'full', 03:17:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:17:19 INFO - 'SHELL': '/bin/bash', 03:17:19 INFO - 'SHLVL': '1', 03:17:19 INFO - 'TERM': 'linux', 03:17:19 INFO - 'TMOUT': '86400', 03:17:19 INFO - 'USER': 'cltbld', 03:17:19 INFO - 'XDG_SESSION_COOKIE': 'ea1537f79099159eb082e9be00000831-1505463697.177799-878862173', 03:17:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:19 INFO - '_': '/tools/buildbot/bin/python'} 03:17:19 INFO - TEST-INFO | started process 29590 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm8Q621/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:20 INFO - PID 29590 | 03:17:20 INFO - PID 29590 | (/builds/slave/test/build/application/firefox/firefox:29639): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:17:20 INFO - PID 29590 | 03:17:30 INFO - PID 29590 | RSS: Main: 167882752 03:17:30 INFO - PID 29590 | 03:17:33 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46097/tests/kraken/driver.html?testName=audio-beat-detection) 03:17:34 INFO - PID 29590 | RSS: Main: 173158400 03:17:34 INFO - PID 29590 | 03:17:36 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46097/tests/kraken/driver.html?testName=audio-dft) 03:17:37 INFO - PID 29590 | RSS: Main: 173101056 03:17:37 INFO - PID 29590 | 03:17:40 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46097/tests/kraken/driver.html?testName=audio-fft) 03:17:40 INFO - PID 29590 | RSS: Main: 168607744 03:17:40 INFO - PID 29590 | 03:17:42 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46097/tests/kraken/driver.html?testName=audio-oscillator) 03:17:43 INFO - PID 29590 | RSS: Main: 169332736 03:17:43 INFO - PID 29590 | 03:17:45 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46097/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:17:46 INFO - PID 29590 | RSS: Main: 169603072 03:17:46 INFO - PID 29590 | 03:17:49 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46097/tests/kraken/driver.html?testName=imaging-darkroom) 03:17:50 INFO - PID 29590 | RSS: Main: 172613632 03:17:50 INFO - PID 29590 | 03:17:53 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46097/tests/kraken/driver.html?testName=imaging-desaturate) 03:17:54 INFO - PID 29590 | RSS: Main: 172785664 03:17:54 INFO - PID 29590 | 03:17:57 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46097/tests/kraken/driver.html?testName=json-parse-financial) 03:17:58 INFO - PID 29590 | RSS: Main: 172544000 03:17:58 INFO - PID 29590 | 03:17:59 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46097/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:18:00 INFO - PID 29590 | RSS: Main: 172605440 03:18:00 INFO - PID 29590 | 03:18:01 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46097/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:18:02 INFO - PID 29590 | RSS: Main: 172830720 03:18:02 INFO - PID 29590 | 03:18:11 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46097/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:18:11 INFO - PID 29590 | RSS: Main: 181829632 03:18:11 INFO - PID 29590 | 03:18:13 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:18:14 INFO - PID 29590 | RSS: Main: 182951936 03:18:14 INFO - PID 29590 | 03:19:35 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:19:36 INFO - PID 29590 | RSS: Main: 171905024 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | Cycle 1(1): loaded http://localhost:46097/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46097/tests/kraken/driver.html?testName=ai-astar) 03:19:36 INFO - PID 29590 | RSS: Main: 173375488 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | __start_tp_report 03:19:36 INFO - PID 29590 | _x_x_mozilla_page_load 03:19:36 INFO - PID 29590 | _x_x_mozilla_page_load_details 03:19:36 INFO - PID 29590 | |i|pagename|runs| 03:19:36 INFO - PID 29590 | |0;ai-astar;106;107;107;107;109;109;109;107;107;107 03:19:36 INFO - PID 29590 | |1;audio-beat-detection;116;115;120;115;117;117;121;117;117;118 03:19:36 INFO - PID 29590 | |2;audio-dft;163;164;164;165;162;162;165;166;167;167 03:19:36 INFO - PID 29590 | |3;audio-fft;74;74;74;76;76;73;82;75;81;74 03:19:36 INFO - PID 29590 | |4;audio-oscillator;79;79;79;79;78;78;78;77;78;77 03:19:36 INFO - PID 29590 | |5;imaging-gaussian-blur;114;113;114;113;115;113;114;114;114;114 03:19:36 INFO - PID 29590 | |6;imaging-darkroom;149;149;149;147;148;148;148;149;147;147 03:19:36 INFO - PID 29590 | |7;imaging-desaturate;95;93;93;94;93;94;95;94;94;96 03:19:36 INFO - PID 29590 | |8;json-parse-financial;56;56;54;54;54;55;54;55;54;55 03:19:36 INFO - PID 29590 | |9;json-stringify-tinderbox;38;39;40;39;39;38;38;39;38;39 03:19:36 INFO - PID 29590 | |10;stanford-crypto-aes;80;75;76;78;77;74;74;78;74;75 03:19:36 INFO - PID 29590 | |11;stanford-crypto-ccm;114;112;114;113;117;108;113;118;114;112 03:19:36 INFO - PID 29590 | |12;stanford-crypto-pbkdf2;167;167;167;164;168;173;172;168;172;171 03:19:36 INFO - PID 29590 | |13;stanford-crypto-sha256-iterative;64;64;62;65;64;67;66;67;66;63 03:19:36 INFO - PID 29590 | __end_tp_report 03:19:36 INFO - PID 29590 | __start_cc_report 03:19:36 INFO - PID 29590 | _x_x_mozilla_cycle_collect,918 03:19:36 INFO - PID 29590 | __end_cc_report 03:19:36 INFO - PID 29590 | __startTimestamp1505470764362__endTimestamp 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | ------- Summary: start ------- 03:19:36 INFO - PID 29590 | Number of tests: 14 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#0] ai-astar Cycles:10 Average:107.50 Median:107.00 stddev:1.08 (1.0%) stddev-sans-first:1.00 03:19:36 INFO - PID 29590 | Values: 106.0 107.0 107.0 107.0 109.0 109.0 109.0 107.0 107.0 107.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#1] audio-beat-detection Cycles:10 Average:117.30 Median:117.00 stddev:1.95 (1.7%) stddev-sans-first:2.01 03:19:36 INFO - PID 29590 | Values: 116.0 115.0 120.0 115.0 117.0 117.0 121.0 117.0 117.0 118.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#2] audio-dft Cycles:10 Average:164.50 Median:165.00 stddev:1.84 (1.1%) stddev-sans-first:1.87 03:19:36 INFO - PID 29590 | Values: 163.0 164.0 164.0 165.0 162.0 162.0 165.0 166.0 167.0 167.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#3] audio-fft Cycles:10 Average:75.90 Median:75.50 stddev:3.11 (4.1%) stddev-sans-first:3.22 03:19:36 INFO - PID 29590 | Values: 74.0 74.0 74.0 76.0 76.0 73.0 82.0 75.0 81.0 74.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:78.50 stddev:0.79 (1.0%) stddev-sans-first:0.78 03:19:36 INFO - PID 29590 | Values: 79.0 79.0 79.0 79.0 78.0 78.0 78.0 77.0 78.0 77.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 03:19:36 INFO - PID 29590 | Values: 114.0 113.0 114.0 113.0 115.0 113.0 114.0 114.0 114.0 114.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.50 stddev:0.88 (0.6%) stddev-sans-first:0.87 03:19:36 INFO - PID 29590 | Values: 149.0 149.0 149.0 147.0 148.0 148.0 148.0 149.0 147.0 147.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.99 (1.1%) stddev-sans-first:1.00 03:19:36 INFO - PID 29590 | Values: 95.0 93.0 93.0 94.0 93.0 94.0 95.0 94.0 94.0 96.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:0.82 (1.5%) stddev-sans-first:0.73 03:19:36 INFO - PID 29590 | Values: 56.0 56.0 54.0 54.0 54.0 55.0 54.0 55.0 54.0 55.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#9] json-stringify-tinderbox Cycles:10 Average:38.70 Median:39.00 stddev:0.67 (1.7%) stddev-sans-first:0.67 03:19:36 INFO - PID 29590 | Values: 38.0 39.0 40.0 39.0 39.0 38.0 38.0 39.0 38.0 39.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:76.50 stddev:2.08 (2.7%) stddev-sans-first:1.66 03:19:36 INFO - PID 29590 | Values: 80.0 75.0 76.0 78.0 77.0 74.0 74.0 78.0 74.0 75.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#11] stanford-crypto-ccm Cycles:10 Average:113.50 Median:114.00 stddev:2.76 (2.4%) stddev-sans-first:2.92 03:19:36 INFO - PID 29590 | Values: 114.0 112.0 114.0 113.0 117.0 108.0 113.0 118.0 114.0 112.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.50 stddev:2.92 (1.7%) stddev-sans-first:3.02 03:19:36 INFO - PID 29590 | Values: 167.0 167.0 167.0 164.0 168.0 173.0 172.0 168.0 172.0 171.0 03:19:36 INFO - PID 29590 | 03:19:36 INFO - PID 29590 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.50 stddev:1.69 (2.6%) stddev-sans-first:1.76 03:19:36 INFO - PID 29590 | Values: 64.0 64.0 62.0 65.0 64.0 67.0 66.0 67.0 66.0 63.0 03:19:36 INFO - PID 29590 | -------- Summary: end -------- 03:19:36 INFO - PID 29590 | 03:19:44 INFO - Browser shutdown timed out after 5 seconds, terminating process. 03:19:44 INFO - Terminating psutil.Process(pid=29590, name='firefox') 03:19:50 INFO - Unable to kill process 03:19:50 ERROR - Traceback (most recent call last): 03:19:50 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 147, in run_browser 03:19:50 INFO - return_code = context.kill_process() 03:19:50 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 53, in kill_process 03:19:50 INFO - return self.process.wait(3) 03:19:50 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/__init__.py", line 1102, in wait 03:19:50 INFO - return self._proc.wait(timeout) 03:19:50 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 709, in wrapper 03:19:50 INFO - return fun(self, *args, **kwargs) 03:19:50 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 851, in wait 03:19:50 INFO - raise TimeoutExpired(timeout, self.pid, self._name) 03:19:50 INFO - TimeoutExpired: psutil.TimeoutExpired timeout after 3 seconds (pid=29590, name='firefox') 03:19:50 INFO - Unable to detect exit code of the process 29590. 03:20:42 INFO - TEST-OK | kraken | took 237322ms 03:20:42 INFO - SUITE-END | took 1497s 03:20:43 INFO - Completed test suite (00:24:58) 03:20:43 INFO - javascript benchmark 03:20:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8383.784815894525, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3155.857986390612, 3173.8730450781964, 3190.409774191773, 3190.8723651053974, 3195.169255993441, 6194.907076393858, 6339.619622822628, 6359.523035772312, 6401.935980640193, 6423.454006409455, 6282.497400207983, 6306.779262725806, 6320.620762754238, 6321.367863213688, 6334.683265836713, 5760.942390576103, 5772.191893134965, 5775.10485874688, 5795.449432303924, 5913.763449462016, 5858.238429004224, 5879.941200587982, 5893.970530147365, 5923.259592550931, 5956.7617295308355, 5560.666360018371, 5838.065909454488, 5918.4673379395845, 5922.822315330547, 5931.762729490815, 5703.77184912603, 5740.54075673945, 5770.13447982806, 5816.331121920991, 5849.824505264849, 5777.59556831022, 5790.33411157718, 5838.7080645967535, 5842.707864606795, 5849.795257165979, 6196.101565273055, 6290.21372328459, 6291.653959032297, 6345.904811427832, 6380.170577824853, 6782.966085169592, 6794.45644348451, 6831.795046148623, 6836.213835408892, 6852.897206541906, 6838.692258848314, 6894.24163342032, 6904.551204171713, 6909.827254318632, 6911.516193866431, 6337.7781777637565, 6345.80962571119, 6379.457746091588, 6386.457151142158, 6430.260520040255, 6841.17905851301, 6926.549774264657, 6930.826729331706, 6986.720531178696, 7104.928950710427, 6527.02094685801, 6588.73645054202, 6612.504062195355, 6614.173228346457, 6625.834354141089, 6675.299093595199, 6702.564333318319, 6748.527603067738, 6767.661616919135, 6825.9317406826285, 6773.0, 6788.083608712859, 6843.076184715099, 6860.416864566568, 6864.862702745917, 6805.319468053254, 6842.521023528305, 6887.931120688729, 6892.620905850226, 6910.930890691129, 5227.712475813867, 5292.761825717852, 5311.973440132774, 5334.839954801362, 5339.893202135935, 4636.11913736389, 4669.976650116728, 4700.764961751899, 4731.408573928259, 4751.263554149113, 5839.941600584025, 5888.646681199141, 5895.616784908967, 5906.763729450774, 5908.409159084057, 6858.862822743517, 6874.621895795779, 6926.238113807477, 6939.959006149118, 6940.7223711052, 5740.770369185269, 5816.069428891442, 5829.183914252008, 5869.178315035899, 5908.556858235649, 5749.741261643236, 5749.88500229993, 5767.826965191051, 5770.538356931435, 5771.451712087345, 6513.055606937062, 6564.671766411756, 6574.605523668595, 6594.7032383542855, 6605.636689982001, 6842.692078856364, 6881.483888708267, 6936.583804971718, 6942.548734332252, 6965.791026269321, 6526.804195873941, 6530.412262896362, 6602.471802255904, 6633.668316584248, 6634.170728658918, 6574.8356291093105, 6646.202455705346, 6650.534462587572, 6688.498362622726, 6732.596044237362, 6728.158980127484, 6786.69459874297, 6803.659817009228, 6820.795376138723, 6827.52207345481, 6580.5722628029025, 6675.532712710064, 6679.799606011828, 6689.732410703517, 6690.464762818866, 6610.140681711347, 6638.867222655424, 6679.8664026718225, 6701.966490167518, 6719.596824190564, 6386.393292637192, 6552.312007239362, 6581.473482121514, 6640.767573134917, 6651.33486651331, 6481.189851268591, 6578.375054369827, 6593.208814942238, 6615.636140012345, 6684.632345220865, 6382.648954307372, 6416.967915160394, 6441.0, 6441.645709485836, 6451.967740161269], "value": 6189.772597092184, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.8563744163491, 853.782285517193, 862.6894318045504, 896.5293221058944, 897.3270047464404, 39419.2116157677, 40168.799156004214, 40174.7991260044, 40275.0, 40311.19377612446, 36570.63429365706, 36702.26595468089, 36727.08182295444, 36781.0, 36814.63185368146, 6898.310168983112, 7018.789436316919, 7029.824254393656, 7034.296570342976, 7041.0, 6953.6870840812035, 7048.823779405505, 7050.471214658895, 7065.646717664122, 7067.222605513415, 7052.435805135602, 7082.716691332341, 7633.503822251564, 7851.921480785205, 7852.371810255195, 7738.535687858718, 7776.339011184056, 7783.299503044753, 7803.141654418009, 7811.8437631247625, 7559.20628334028, 7681.3854891608535, 7706.07527096752, 7707.152213256537, 7712.537247765152, 36531.0, 36750.63249367499, 37466.81266593649, 37487.250254994746, 37693.81153094217, 3514.7715398499017, 3589.892303230907, 3596.2088340565033, 3597.5323207983056, 3598.676119149289, 31192.688073119207, 31382.215444613837, 31403.37193256145, 31491.0, 31518.52722209169, 4947.975260123712, 4958.652894297401, 4983.775730092155, 5001.724905130216, 5008.874778130539, 191019.0, 194634.0, 195403.0, 198654.0, 200707.99999999854, 47825.282620760714, 47859.76070119627, 47980.280295795594, 47986.0, 48046.5195348042, 49233.75383123133, 49287.260691089665, 49301.26048109281, 49326.0, 49331.26003109956, 15921.761173582405, 16226.864119511756, 16268.186590670419, 16289.511314660342, 16338.183090845649, 13436.0, 13438.059335846398, 13457.461701532025, 13461.663458413617, 13478.460861565623, 13849.515266965607, 13872.514461993782, 13901.860981390057, 14031.649208769863, 14180.220087895059, 5485.424030476743, 5649.717514124277, 5740.655560666373, 5766.509846663081, 5793.710314484343, 977.7848873248016, 1007.2244371833647, 1007.5818535307911, 1008.6368907193405, 1013.189448441244, 22908.4272893179, 22910.885445572665, 22984.885075574515, 23182.072717091127, 23238.883805580863, 1111.7053980695123, 1115.3921112993437, 1115.642994241835, 1115.9776804463866, 1125.2460851229696, 12025.977791887788, 12057.819132713017, 12130.69673258158, 12193.573224937085, 12323.322217278135, 225933.0, 226287.0, 226418.0, 226767.0, 227320.0, 50851.7457412718, 50884.49115508798, 51361.0, 51615.48384516107, 51621.22568161481, 1427.5931359562476, 1446.2840893757657, 1453.3968403112801, 1471.69830184814, 1472.1314424489387, 22951.196708115134, 23049.423764406023, 23053.65419518675, 23157.0, 23512.88243558771, 36949.44575831311, 37045.44431833579, 37092.81453592715, 37109.257814844095, 37181.44227836639, 189404.0, 189480.0, 189812.0, 189870.0, 189956.0, 30992.0, 31233.531497027107, 31304.843475782476, 34359.828200858836, 34824.82587587046, 42871.3569296467, 43559.78220108879, 43814.56185438232, 44066.779666101465, 44523.332150018425, 32231.194220144684, 32271.51592726064, 32425.189370265933, 32517.349653006335, 32573.837130814194, 31230.0, 31635.36729265356, 31857.203569910936, 32012.67987320097, 32221.0, 34587.65412345945, 34985.12537186489, 35501.75743848953, 35647.28705425929, 35900.461493077106, 30661.54007689931, 31183.53224701586, 31246.21884452816, 31256.843715781277, 31282.53076203813, 22924.770752292265, 22972.540549189256, 23138.421539461648, 23635.40911477227, 23787.76212237925], "value": 16880.958115234727, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.4248060776488, 564.1087082409792, 573.9942600573993, 576.0840263980272, 582.6271186440677, 21686.024128914196, 22545.09819607218, 22575.661365079515, 22586.435339116488, 22593.0, 22115.0, 24282.87858560709, 24487.142949996753, 24759.504809903792, 24980.25059248221, 6420.518461115424, 6540.117084193621, 6583.111279977214, 6587.374199451068, 6594.109795177662, 6287.339829317924, 6477.902831457532, 6558.475321974231, 6560.409563139317, 6572.342765723437, 6492.967535162317, 6505.4145126938565, 6508.121403610523, 6514.3811337922825, 6515.38103880133, 6252.155958945529, 6462.48300135988, 6483.1247781549355, 6539.4441472474655, 6595.208574970986, 6083.665398403086, 6091.543134264948, 6101.176341193971, 6103.0, 6107.389261073901, 27687.0, 28104.0, 28121.0, 28129.859350703115, 28162.0, 1915.3200613782046, 1972.260402349119, 1988.1848442138798, 1995.980040199594, 1996.2414282572674, 18826.152823122993, 18949.052547372714, 19017.429477115707, 19163.904180479007, 19295.03524823753, 2354.8940297686645, 2359.9292021239216, 2405.530921470314, 2408.506256217471, 2416.1784993102256, 109022.0, 115416.0, 129927.0, 131392.0, 134841.3257933714, 14144.968683925032, 14206.147631142165, 14466.493672721615, 14640.121592704472, 14755.55733328002, 21877.67183492249, 22083.447913802283, 22105.115795368005, 22119.778802212095, 22139.667904981437, 9801.31390802637, 10129.290949633454, 10140.645077422254, 10151.492425378849, 10152.593896244067, 9349.813003740022, 9391.765205869908, 9397.0, 9426.95286523563, 9462.101100395452, 8723.168134691787, 8755.0, 8764.824703506021, 8933.776655583662, 9036.502992335354, 2551.311145990572, 2578.097665816949, 2618.319236998394, 2633.2482555729307, 2677.0630279402053, 939.43633819709, 955.2501286490071, 957.4590356448584, 975.5951280218629, 981.6024510073431, 18239.36162234342, 18524.351647692267, 18623.720644190347, 18786.530336741416, 18833.717494237597, 1036.6112707734599, 1039.0907955538903, 1047.607147319755, 1050.837120246363, 1053.7629033467408, 10107.797844043078, 10211.468279757948, 10434.947825260826, 10473.581056757645, 10636.989485998818, 420104.0, 421426.0, 425124.0, 429526.0, 448808.0, 102987.0, 106428.0, 107741.4612926946, 109645.45177274062, 111654.0, 1002.368507840056, 1059.7456610413444, 1132.694172573417, 1140.5551834784442, 1178.587494376961, 19291.61416771685, 19706.31027914016, 19768.604627907647, 19797.60404791925, 19972.50068748293, 54585.18122228249, 54645.0, 55055.72472137614, 56330.0, 56415.15377269426, 66322.66838665775, 66984.0, 67236.66381668061, 67257.66371168113, 67916.6604166976, 13568.93215533916, 13975.231362275172, 14013.299335033003, 14069.648258793612, 14095.788563171765, 21825.890870545547, 21864.89067554652, 21995.670064949358, 22152.113915443202, 22158.335249942527, 17389.0, 17710.203040862936, 17852.285908563514, 17895.284188632308, 17971.640567188846, 14209.928950355183, 15330.233488325763, 15518.301676424813, 15613.921930390276, 15648.687026259639, 16912.746308805625, 17003.91498042502, 17125.314987400365, 17144.0, 18050.187741551923, 13528.59414217575, 13774.586762397144, 13845.653858653613, 13847.376868040761, 13855.722885542435, 17539.56151096182, 17645.29418823233, 17728.91135544314, 17770.111494425484, 17808.46574602764], "value": 11562.641314429085, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.7310537892422, 1384.5084994826836, 1388.9027768056235, 1389.1248513436533, 1398.2659103970414, 14677.266136693162, 14965.401383944629, 14986.775198372034, 15043.623909402271, 15289.0, 14583.781243281359, 14774.926125369386, 14961.0, 15058.096514209155, 15060.39758409665, 2797.860106994652, 2806.4387122575463, 2855.3860919902218, 2857.771378289742, 2858.5426331786916, 2767.059199872043, 2808.1435162275498, 2815.53543665295, 2821.7460428561426, 2833.5891295762103, 2764.986175069132, 2822.5625028120676, 2861.527847905093, 2864.283929017746, 2866.2261189478827, 2857.2713957940696, 2874.7987640865254, 2880.006397792758, 2894.52240380337, 2907.854607269628, 2763.350612606036, 2789.4142230131697, 2794.6087547743127, 2797.776177905763, 2801.551751719715, 14595.197264150467, 14945.252737363197, 14966.850331496655, 14972.47596334134, 14992.925035374754, 7741.806454838674, 8018.6391612377, 8020.839583208303, 8037.316828069563, 8037.477563958382, 5264.2893209416625, 5358.946410535923, 5471.0, 5512.476314750133, 5539.086050801613, 1774.4233124234675, 1784.4557409989948, 1802.5583731985616, 1811.7644706188244, 1823.4256209294163, 26076.73923260781, 26188.214353569423, 26244.606330904862, 26294.474110517873, 26444.73555264442, 6592.571482853599, 6714.798556043326, 6790.354916282945, 6919.100516932766, 6951.304869513109, 16986.490405287863, 16993.0, 16995.23521441538, 16998.150092495573, 17005.48983530496, 8187.26314631686, 8449.32405407566, 8451.36614753896, 8451.619677114544, 8453.492790432594, 7395.4083673306, 7518.173000969889, 7524.0, 7553.546787192786, 7569.053868266466, 5064.594832413398, 5253.50091741284, 5256.39551451588, 5279.841604751864, 5290.285811415487, 1392.5195807446414, 1417.5463851567606, 1530.5790907500527, 1533.8926275160632, 1541.7533194688797, 892.5537231384308, 909.8180363927241, 913.8400779863497, 914.4421902639384, 926.8192702422963, 8116.228958248957, 8193.180681931759, 8198.877016844752, 8211.876821847676, 8246.134155913664, 959.836827739286, 962.451402700454, 962.7304354780532, 981.764376549623, 982.7837875667342, 5004.099262132779, 5079.314292570456, 5085.618578606619, 5137.948620513822, 5191.922121168185, 20411.489712757004, 20689.68965465519, 20765.792342076387, 21078.683819742717, 21576.784232157792, 20975.580488390147, 21066.683999740017, 21108.894455527625, 21476.677849832264, 21688.0, 10641.095506882002, 10652.520636571373, 10676.946615266874, 10687.0, 10809.459527023617, 7648.502847314907, 8258.545779982158, 8267.83464330722, 8304.377171712267, 8321.209485098905, 17920.462386128438, 18004.54988625295, 18079.819201807815, 18415.53961150982, 18622.72065918985, 18474.168662409957, 18629.72055419197, 18639.72040419422, 18831.24675012984, 18924.905375473034, 10807.135429165804, 10882.0, 11043.613473528389, 11065.889341106486, 11080.279781814157, 13542.0, 13869.791953120914, 13875.306234688427, 13898.096623719426, 13898.23559704226, 9894.15899648538, 9986.300958932805, 10197.694069177936, 10198.643047493302, 10341.482925853526, 11077.667669969915, 11248.606298779503, 11292.209545332076, 11408.600698975495, 11422.143339249424, 9566.712998610054, 9761.609535618496, 9789.363691360039, 9892.356996795184, 9893.159081477866, 6224.813255602339, 6248.031555108783, 6264.718087686157, 6311.495080393649, 6411.903821442589, 6630.801075967729, 6701.765438209834, 6713.966430167818, 6732.8653426930205, 6776.830579235559], "value": 6610.3769041832775, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.81015870834074, 499.9950495539647, 520.4535238000099, 525.4246599973029, 526.7182057599185, 6317.273513545943, 6359.55483116182, 6365.29981702013, 6409.391107844759, 6437.517186211036, 6258.217722784652, 6290.276618188909, 6298.149749783789, 6337.8415539611415, 6344.143540622014, 5228.73856307185, 5233.712145831978, 5252.264682944391, 5253.500917412859, 5253.842384728464, 5204.42751297357, 5239.0, 5263.789448422059, 5286.497782710637, 5288.709120998344, 5092.166819944817, 5111.6166287528395, 5152.690838549699, 5210.322658054448, 5244.160934250521, 4737.976310118444, 4897.436794768611, 4908.950910490885, 4931.901361972758, 5000.174971129759, 4437.4896886858105, 4446.221911165533, 4454.866354009385, 4469.3296005599095, 4481.327800829869, 5953.83900139473, 6038.426349496791, 6092.147099406086, 6101.786437474667, 6170.382961703838, 5684.54523638108, 5730.596003979052, 5731.570132240098, 5733.0, 5743.712814359265, 6526.836829079311, 6545.574537655038, 6556.672166391661, 6584.30864759203, 6601.306862779387, 4610.124076425468, 4788.640851936118, 4823.951760482385, 4838.588719958808, 4846.321514987904, 6687.297833727437, 6723.193216813965, 6755.560888542229, 6825.897611535831, 6862.9313706862795, 4981.800727970913, 4990.226514890198, 4990.431841233535, 5010.2735103409805, 5013.696438925906, 5025.874353141201, 5026.195808670595, 5027.824026159067, 5050.3434553507805, 5064.315912566346, 5627.467639562214, 5683.119116536944, 5686.147077938343, 5699.116636921284, 5729.369769325371, 6612.636305003175, 6633.568818026813, 6666.233383160901, 6697.162854643169, 6698.4832962694, 1754.754334393186, 1760.9559761006078, 1762.5769815244248, 1768.5321183417732, 1781.3320004998125, 1133.218079525125, 1172.2263306217856, 1212.2786941769632, 1225.7609766095525, 1233.1429656388902, 5747.080467125324, 5755.395683453177, 5770.336411312669, 5777.8555536111935, 5791.276090488689, 6613.6362500062, 6694.824182140726, 6743.797686069426, 6777.93222067773, 6788.0, 5580.246666700024, 5638.054871069475, 5648.209250704905, 5654.787183548201, 5680.403557626472, 3471.9063494998995, 3474.947875781865, 3522.9214763289783, 3542.344666236703, 3553.9387155449776, 6251.562390632703, 6351.650659213788, 6356.173697419306, 6393.2328120625825, 6447.742090316428, 6813.216480104851, 6822.9317706823285, 6835.965820170867, 6843.213030501456, 6855.520113592101, 5800.216970708901, 6373.362663733682, 6457.483401327882, 6465.547411681137, 6524.64114473718, 6480.546361754632, 6557.868842623026, 6587.242467116152, 6684.264730879502, 6696.631685257357, 6462.967685161544, 6493.188351456068, 6544.54188206821, 6549.4760419167305, 6632.767853125118, 6333.6516491591565, 6404.519661025349, 6407.679616019087, 6431.421172094534, 6437.25971513282, 4900.26496025599, 4930.40835099776, 4963.404391472902, 4967.279744437109, 4994.076095922266, 6475.0, 6508.284088750139, 6527.834000509654, 6591.0, 6804.761833336008, 6421.389967953037, 6490.961446168591, 6501.9843848407145, 6539.149910511603, 6547.377999090078, 6345.333739957238, 6355.650439225887, 6418.170821315903, 6523.478121750154, 6539.0, 6305.936940630535, 6387.712552935222, 6393.8721225576155, 6508.576942498722, 6522.249941256816, 6607.80176594703, 6625.50308726857, 6626.801195964129, 6651.966740166268, 6683.331666833278, 6412.038194270896, 6417.0, 6431.421172094534, 6438.742450301936, 6464.450521705519], "value": 5050.243678071531, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [890.8930928288606, 898.1736802142029, 917.756794449471, 924.2975338742554, 924.5100096948618, 18989.52526186846, 19797.901010494927, 19809.60380792385, 19829.603407931834, 19849.3052743154, 17730.468085957444, 18039.63920721585, 18342.16578342165, 18524.0, 18696.15867285972, 960.9327347085689, 984.4388698441891, 990.3859607043643, 993.7167907146464, 994.2294721590789, 968.4334664221439, 984.1437948984394, 987.0918754745651, 989.1641562879355, 990.9108180263775, 1049.0401282826206, 1051.059301924777, 1052.0216198934988, 1054.5834395413845, 1056.0917610854658, 1009.4001008401692, 1041.6458404142552, 1049.5486940615533, 1049.8950104989478, 1055.9630412935549, 750.8573371059481, 751.8909758085101, 752.0749478141861, 752.6537792615403, 753.5629334985695, 20018.69971950422, 20026.1989520419, 20196.0, 20219.898900505403, 20300.695489567668, 21163.883611638867, 22056.117755289768, 22072.0, 22303.21938732153, 22390.104395824146, 15731.134787586678, 15991.520254392386, 16131.193440328054, 16133.0, 16244.75632865508, 3561.857525698969, 3661.5789184243886, 3664.633536646341, 3666.17511060012, 3681.834317455694, 134731.0, 135435.0, 135494.3225283877, 137028.0, 138601.3069934654, 15827.604309892346, 16168.838311616968, 16418.097004664618, 16421.42525011619, 16593.0, 24983.500329993298, 28409.715902840708, 28452.430951380855, 28521.144365669064, 28636.0, 8459.619317130744, 8555.358348123915, 8557.828843423096, 8570.400071995025, 8586.227239548345, 3978.6817054635558, 4088.6524645405475, 4097.1191193893455, 4097.262492751333, 4099.0, 5498.285222920995, 5563.749631266603, 5589.972050139723, 5610.85972850682, 5618.494335509824, 966.4104896013566, 982.6806287956442, 985.6845809340942, 991.5934466868549, 998.9550470228712, 675.2538445018282, 689.6827459368646, 691.339770519153, 702.5293053654065, 709.7303024850523, 10642.521086551124, 10916.399598022026, 10959.726006849733, 11101.44492775358, 11218.495167717309, 672.9065268937976, 673.3468535520635, 680.8025672555002, 688.277308825731, 700.5971566349369, 5204.193350030751, 5241.187615919539, 5242.449542798027, 5266.289050978069, 5272.0, 120852.0, 122108.38945805214, 122975.0, 124145.0, 126465.36767316288, 101464.0, 105258.47370763097, 106362.0, 106480.46759766151, 106691.0, 89511.55244223737, 95894.52052739692, 97277.51361243149, 98771.50614246883, 99877.0, 9697.466639334903, 9758.512074396394, 9770.951145244228, 9876.308658394131, 9950.800983980134, 42271.365929511696, 42526.149477010906, 42875.57124428716, 42937.14125717406, 43176.0, 52715.47284527259, 53260.73369633127, 53922.19116713174, 55683.44316556783, 55987.0, 13847.099938503965, 13891.37488813026, 14084.718305634035, 14175.574732758034, 14187.929060354632, 14550.927245363706, 14920.477783277533, 15291.388344466543, 15392.384304627689, 15444.0, 13837.861621383658, 14002.159870407808, 14026.438942442595, 14206.07660502064, 14215.644608884448, 13450.529231476852, 13589.116707413987, 13628.591142265748, 13665.521706740614, 13712.245826479639, 13347.265900375573, 13370.732585348043, 13486.0, 13522.93238533801, 13656.863431365558, 11207.887921120684, 11280.943595281971, 11322.150838687296, 11336.829947550958, 11383.260088094248, 11368.431578421212, 11585.131115166565, 11801.645950621494, 11824.70438239051, 12213.633590992284], "value": 8608.91790976283, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1416.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 107.0, 107.0, 109.0, 109.0, 109.0, 107.0, 107.0, 107.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 120.0, 115.0, 117.0, 117.0, 121.0, 117.0, 117.0, 118.0], "value": 117.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 164.0, 165.0, 162.0, 162.0, 165.0, 166.0, 167.0, 167.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 76.0, 76.0, 73.0, 82.0, 75.0, 81.0, 74.0], "value": 75.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 78.0, 78.0, 78.0, 77.0, 78.0, 77.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 115.0, 113.0, 114.0, 114.0, 114.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 147.0, 148.0, 148.0, 148.0, 149.0, 147.0, 147.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 93.0, 94.0, 93.0, 94.0, 95.0, 94.0, 94.0, 96.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 54.0, 54.0, 55.0, 54.0, 55.0, 54.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 40.0, 39.0, 39.0, 38.0, 38.0, 39.0, 38.0, 39.0], "value": 38.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 75.0, 76.0, 78.0, 77.0, 74.0, 74.0, 78.0, 74.0, 75.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 114.0, 113.0, 117.0, 108.0, 113.0, 118.0, 114.0, 112.0], "value": 113.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 167.0, 164.0, 168.0, 173.0, 172.0, 168.0, 172.0, 171.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 62.0, 65.0, 64.0, 67.0, 66.0, 67.0, 66.0, 63.0], "value": 64.8, "unit": "score"}]}]} 03:20:43 INFO - Return code: 0 03:20:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:20:43 ERROR - # TBPL SUCCESS # 03:20:43 INFO - Running post-action listener: _package_coverage_data 03:20:43 INFO - Running post-action listener: _resource_record_post_action 03:20:43 INFO - [mozharness: 2017-09-15 10:20:43.242629Z] Finished run-tests step (success) 03:20:43 INFO - Running post-run listener: _resource_record_post_run 03:20:43 INFO - Total resource usage - Wall time: 1516s; CPU: 15.0%; Read bytes: 4096; Write bytes: 482971648; Read time: 24; Write time: 24159004 03:20:43 INFO - TinderboxPrint: CPU usage
14.7% 03:20:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 24 03:20:43 INFO - TinderboxPrint: I/O write bytes / time
482,971,648 / 24,159,004 03:20:43 INFO - TinderboxPrint: CPU idle
10,312.5 (85.3%) 03:20:43 INFO - TinderboxPrint: CPU iowait
534.5 (4.4%) 03:20:43 INFO - TinderboxPrint: CPU user
1,202.8 (9.9%) 03:20:43 INFO - TinderboxPrint: Swap in / out
0 / 0 03:20:43 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 1818624; Read time: 0; Write time: 31660 03:20:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:20:43 INFO - run-tests - Wall time: 1499s; CPU: 15.0%; Read bytes: 0; Write bytes: 481153024; Read time: 0; Write time: 24127344 03:20:44 INFO - Running post-run listener: _upload_blobber_files 03:20:44 INFO - Blob upload gear active. 03:20:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:20:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:20:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:20:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:20:44 INFO - (blobuploader) - INFO - Open directory for files ... 03:20:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:20:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:20:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:45 INFO - (blobuploader) - INFO - Done attempting. 03:20:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:20:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:20:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:53 INFO - (blobuploader) - INFO - Done attempting. 03:20:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:20:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:54 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:20:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:54 INFO - (blobuploader) - INFO - Done attempting. 03:20:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:20:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:20:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:20:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:20:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:20:55 INFO - (blobuploader) - INFO - Done attempting. 03:20:55 INFO - (blobuploader) - INFO - Iteration through files over. 03:20:55 INFO - Return code: 0 03:20:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:20:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:20:55 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e34554e0700d5c0f1319bbe272c509f33efa74e274896b5ed09e26371ce3786786ead6fda4e286b019c55ac27d177e0b2ea4f7754e87a4aeed2e7d2a77501f2d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/012b3064c8ff34583c7ba2e3abcd6bed158946ca8359196cae4c711530e5b4aa37bbc0324322f17190e1d1be0da3f1c088178925114b5884a836eed89c6cb5de", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5e1b8e852dddfcdd13f32fdd49addfcf1676c3e62ef5495a6818ac7ef633fbd39b3c5140b9511d7a19a75330a73899f2ce7dc15bab41eea5bf5dab8618bc723", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d54ee17c2002c2b28a86e8809c709323a2c4ad0ed051417954589314f8beb7db3f2e11f2d91fa6216a1a4d5dad82c390e4b49137ffda7d2c97cc07ff8781556d"} 03:20:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:20:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:20:55 INFO - Contents: 03:20:55 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e34554e0700d5c0f1319bbe272c509f33efa74e274896b5ed09e26371ce3786786ead6fda4e286b019c55ac27d177e0b2ea4f7754e87a4aeed2e7d2a77501f2d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/012b3064c8ff34583c7ba2e3abcd6bed158946ca8359196cae4c711530e5b4aa37bbc0324322f17190e1d1be0da3f1c088178925114b5884a836eed89c6cb5de", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5e1b8e852dddfcdd13f32fdd49addfcf1676c3e62ef5495a6818ac7ef633fbd39b3c5140b9511d7a19a75330a73899f2ce7dc15bab41eea5bf5dab8618bc723", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d54ee17c2002c2b28a86e8809c709323a2c4ad0ed051417954589314f8beb7db3f2e11f2d91fa6216a1a4d5dad82c390e4b49137ffda7d2c97cc07ff8781556d"} 03:20:55 INFO - Running post-run listener: copy_logs_to_upload_dir 03:20:55 INFO - Copying logs to upload dir... 03:20:55 INFO - mkdir: /builds/slave/test/build/upload/logs 03:20:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1703.900613 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 23 secs) (at 2017-09-15 03:20:55.978697) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 03:20:55.981978) ========= 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=ea1537f79099159eb082e9be00000831-1505463697.177799-878862173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e34554e0700d5c0f1319bbe272c509f33efa74e274896b5ed09e26371ce3786786ead6fda4e286b019c55ac27d177e0b2ea4f7754e87a4aeed2e7d2a77501f2d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/012b3064c8ff34583c7ba2e3abcd6bed158946ca8359196cae4c711530e5b4aa37bbc0324322f17190e1d1be0da3f1c088178925114b5884a836eed89c6cb5de", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5e1b8e852dddfcdd13f32fdd49addfcf1676c3e62ef5495a6818ac7ef633fbd39b3c5140b9511d7a19a75330a73899f2ce7dc15bab41eea5bf5dab8618bc723", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d54ee17c2002c2b28a86e8809c709323a2c4ad0ed051417954589314f8beb7db3f2e11f2d91fa6216a1a4d5dad82c390e4b49137ffda7d2c97cc07ff8781556d"} build_url:https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011288 build_url: 'https://queue.taskcluster.net/v1/task/WmwENJ0_TWeFxfcKwYDDvw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e34554e0700d5c0f1319bbe272c509f33efa74e274896b5ed09e26371ce3786786ead6fda4e286b019c55ac27d177e0b2ea4f7754e87a4aeed2e7d2a77501f2d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/012b3064c8ff34583c7ba2e3abcd6bed158946ca8359196cae4c711530e5b4aa37bbc0324322f17190e1d1be0da3f1c088178925114b5884a836eed89c6cb5de", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c5e1b8e852dddfcdd13f32fdd49addfcf1676c3e62ef5495a6818ac7ef633fbd39b3c5140b9511d7a19a75330a73899f2ce7dc15bab41eea5bf5dab8618bc723", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d54ee17c2002c2b28a86e8809c709323a2c4ad0ed051417954589314f8beb7db3f2e11f2d91fa6216a1a4d5dad82c390e4b49137ffda7d2c97cc07ff8781556d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 03:20:56.009700) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:20:56.009990) ========= 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=ea1537f79099159eb082e9be00000831-1505463697.177799-878862173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 03:20:56.092463) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 03:20:56.092783) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 03:20:56.093076) ========= ========= Total master_lag: 0.19 =========