builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-038 starttime: 1505343752.38 results: success (0) revision: 8645a74bbbd06b67699317df1abf3897db0e43d5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:32.379860) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:32.380343) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:32.380638) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:32.402128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:32.402417) ========= 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=8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-13 16:02:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.1M=0s 2017-09-13 16:02:32 (42.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.214614 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:32.634002) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:32.634316) ========= 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=8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015920 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:32.690610) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:32.690953) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8645a74bbbd06b67699317df1abf3897db0e43d5 --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 8645a74bbbd06b67699317df1abf3897db0e43d5 --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=8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-13 16:02:32,771 truncating revision to first 12 chars 2017-09-13 16:02:32,772 Setting DEBUG logging. 2017-09-13 16:02:32,772 attempt 1/10 2017-09-13 16:02:32,772 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8645a74bbbd0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-13 16:02:33,212 unpacking tar archive at: mozilla-central-8645a74bbbd0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.728095 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:33.438475) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:33.438800) ========= script_repo_revision: 8645a74bbbd06b67699317df1abf3897db0e43d5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:33.439183) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:33.439488) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-13 16:02:33.452788) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-09-13 16:02:33.453072) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:02:33 INFO - MultiFileLogger online at 20170913 16:02:33 in /builds/slave/test 16:02:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:02:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:02:33 INFO - {'append_to_log': False, 16:02:33 INFO - 'base_work_dir': '/builds/slave/test', 16:02:33 INFO - 'blob_upload_branch': 'Firefox', 16:02:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:02:33 INFO - 'branch': 'Firefox', 16:02:33 INFO - 'buildbot_json_path': 'buildprops.json', 16:02:33 INFO - 'code_coverage': False, 16:02:33 INFO - 'config_files': ('talos/linux_config.py',), 16:02:33 INFO - 'default_actions': ('clobber', 16:02:33 INFO - 'read-buildbot-config', 16:02:33 INFO - 'download-and-extract', 16:02:33 INFO - 'populate-webroot', 16:02:33 INFO - 'create-virtualenv', 16:02:33 INFO - 'install', 16:02:33 INFO - 'setup-mitmproxy', 16:02:33 INFO - 'run-tests'), 16:02:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:02:33 INFO - 'disable_ccov_upload': False, 16:02:33 INFO - 'disable_stylo': False, 16:02:33 INFO - 'download_minidump_stackwalk': True, 16:02:33 INFO - 'download_symbols': 'ondemand', 16:02:33 INFO - 'enable_stylo': False, 16:02:33 INFO - 'enable_webrender': False, 16:02:33 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:02:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:02:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:02:33 INFO - 'gecko_profile': False, 16:02:33 INFO - 'gecko_profile_interval': 0, 16:02:33 INFO - 'installer_path': 'installer.exe', 16:02:33 INFO - 'log_level': 'info', 16:02:33 INFO - 'log_name': 'talos', 16:02:33 INFO - 'log_to_console': True, 16:02:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:02:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:02:33 INFO - 'opt_config_files': (), 16:02:33 INFO - 'pip_index': False, 16:02:33 INFO - 'suite': 'dromaeojs-e10s', 16:02:33 INFO - 'system_bits': '32', 16:02:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:02:33 INFO - 'title': 'talos-linux64-ix-038', 16:02:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:02:33 INFO - 'use_talos_json': True, 16:02:33 INFO - 'verify': 'False', 16:02:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:02:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:02:33 INFO - 'work_dir': 'build'} 16:02:33 INFO - [mozharness: 2017-09-13 23:02:33.768805Z] Running clobber step. 16:02:33 INFO - Running pre-action listener: _resource_record_pre_action 16:02:33 INFO - Running main action method: clobber 16:02:33 INFO - rmtree: /builds/slave/test/build 16:02:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:02:34 INFO - Running post-action listener: _resource_record_post_action 16:02:34 INFO - [mozharness: 2017-09-13 23:02:34.714573Z] Finished clobber step (success) 16:02:34 INFO - [mozharness: 2017-09-13 23:02:34.714669Z] Running read-buildbot-config step. 16:02:34 INFO - Running pre-action listener: _resource_record_pre_action 16:02:34 INFO - Running main action method: read_buildbot_config 16:02:34 INFO - Using buildbot properties: 16:02:34 INFO - { 16:02:34 INFO - "project": "", 16:02:34 INFO - "product": "firefox", 16:02:34 INFO - "who": "archaeopteryx@coole-files.de", 16:02:34 INFO - "installer_path": "public/build/target.tar.bz2", 16:02:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 16:02:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:02:34 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 16:02:34 INFO - "stage_platform": "linux64-qr", 16:02:34 INFO - "basedir": "/builds/slave/test", 16:02:34 INFO - "buildnumber": 49, 16:02:34 INFO - "platform": "ubuntu64_hw_qr", 16:02:34 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:02:34 INFO - "slavebuilddir": "test", 16:02:34 INFO - "taskId": "ZX1rV1kXRZmME2a5isxL4A", 16:02:34 INFO - "branch": "mozilla-central", 16:02:34 INFO - "script_repo_revision": "production", 16:02:34 INFO - "revision": "8645a74bbbd06b67699317df1abf3897db0e43d5", 16:02:34 INFO - "slavename": "talos-linux64-ix-038", 16:02:34 INFO - "repo_path": "mozilla-central" 16:02:34 INFO - } 16:02:34 INFO - Finding installer, test and symbols from parent task. 16:02:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZX1rV1kXRZmME2a5isxL4A'}, attempt #1 16:02:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZX1rV1kXRZmME2a5isxL4A'}, attempt #1 16:02:35 INFO - Task dependencies: A1V4MAzpSR2fT4tPLmTCBw 16:02:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw'}, attempt #1 16:02:36 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 16:02:36 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.test_packages.json 16:02:36 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.crashreporter-symbols.zip 16:02:36 INFO - Running post-action listener: _resource_record_post_action 16:02:36 INFO - [mozharness: 2017-09-13 23:02:36.113610Z] Finished read-buildbot-config step (success) 16:02:36 INFO - [mozharness: 2017-09-13 23:02:36.113833Z] Running download-and-extract step. 16:02:36 INFO - Running pre-action listener: _resource_record_pre_action 16:02:36 INFO - Running main action method: download_and_extract 16:02:36 INFO - mkdir: /builds/slave/test/build/tests 16:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:36 INFO - trying https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.test_packages.json 16:02:36 INFO - Downloading https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:02:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:02:36 INFO - Downloaded 989 bytes. 16:02:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:02:36 INFO - Using the following test package requirements: 16:02:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:02:36 INFO - u'common': [u'target.common.tests.zip'], 16:02:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:02:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:02:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:02:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:02:36 INFO - u'mozbase': [u'target.common.tests.zip'], 16:02:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:02:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:02:36 INFO - u'web-platform': [u'target.common.tests.zip', 16:02:36 INFO - u'target.web-platform.tests.tar.gz'], 16:02:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:02:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:02:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.common.tests.zip 16:02:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:02:36 INFO - Fetch https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.common.tests.zip into memory 16:02:37 INFO - Content-Length response header: 54572657 16:02:37 INFO - Bytes received: 54572657 16:02:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.talos.tests.zip 16:02:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:02:49 INFO - Fetch https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.talos.tests.zip into memory 16:02:50 INFO - Content-Length response header: 14500351 16:02:50 INFO - Bytes received: 14500351 16:02:50 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')]} 16:02:50 INFO - trying https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 16:02:50 INFO - Downloading https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:02:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:02:51 INFO - Downloaded 61287064 bytes. 16:02:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 16:02:51 INFO - mkdir: /builds/slave/test/properties 16:02:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:02:51 INFO - Writing to file /builds/slave/test/properties/build_url 16:02:51 INFO - Contents: 16:02:51 INFO - build_url:https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 16:02:51 INFO - Running post-action listener: _resource_record_post_action 16:02:51 INFO - Running post-action listener: find_tests_for_verification 16:02:51 INFO - Running post-action listener: set_extra_try_arguments 16:02:51 INFO - [mozharness: 2017-09-13 23:02:51.693919Z] Finished download-and-extract step (success) 16:02:51 INFO - [mozharness: 2017-09-13 23:02:51.694017Z] Running populate-webroot step. 16:02:51 INFO - Running pre-action listener: _resource_record_pre_action 16:02:51 INFO - Running main action method: populate_webroot 16:02:51 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:02:51 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['tresize', 16:02:51 INFO - 'tcanvasmark']}, 16:02:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:02:51 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['dromaeo_css', 16:02:51 INFO - 'kraken']}, 16:02:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:02:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:51 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:02:51 INFO - 'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:02:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:02:51 INFO - 'tests': ['damp', 'tps']}, 16:02:51 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:02:51 INFO - 'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['damp', 'tps']}, 16:02:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:02:51 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['dromaeo_dom']}, 16:02:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:02:51 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['basic_compositor_video', 16:02:51 INFO - 'glvideo']}, 16:02:51 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:02:51 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:02:51 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:02:51 INFO - 'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['ts_paint_webext', 16:02:51 INFO - 'tp5o_webext']}, 16:02:51 INFO - 'other-e10s': {'tests': ['a11yr', 16:02:51 INFO - 'ts_paint', 16:02:51 INFO - 'tpaint', 16:02:51 INFO - 'sessionrestore', 16:02:51 INFO - 'sessionrestore_many_windows', 16:02:51 INFO - 'sessionrestore_no_auto_restore', 16:02:51 INFO - 'tabpaint']}, 16:02:51 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['a11yr', 16:02:51 INFO - 'ts_paint', 16:02:51 INFO - 'tpaint', 16:02:51 INFO - 'sessionrestore', 16:02:51 INFO - 'sessionrestore_many_windows', 16:02:51 INFO - 'sessionrestore_no_auto_restore', 16:02:51 INFO - 'tabpaint']}, 16:02:51 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 16:02:51 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 16:02:51 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['bloom_basic_singleton']}, 16:02:51 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['bloom_basic']}, 16:02:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:02:51 INFO - 'tsvgr_opacity', 16:02:51 INFO - 'tart', 16:02:51 INFO - 'tscrollx', 16:02:51 INFO - 'tsvg_static']}, 16:02:51 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['tsvgx', 16:02:51 INFO - 'tsvgr_opacity', 16:02:51 INFO - 'tart', 16:02:51 INFO - 'tscrollx', 16:02:51 INFO - 'tsvg_static']}, 16:02:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:02:51 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:02:51 INFO - 'talos_options': ['--disable-stylo'], 16:02:51 INFO - 'tests': ['tp5o']}, 16:02:51 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:02:51 INFO - 'talos_options': ['--mitmproxy', 16:02:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:02:51 INFO - '--firstNonBlankPaint'], 16:02:51 INFO - 'tests': ['tp6_google', 16:02:51 INFO - 'tp6_youtube', 16:02:51 INFO - 'tp6_amazon', 16:02:51 INFO - 'tp6_facebook']}, 16:02:51 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:02:51 INFO - 'talos_options': ['--disable-stylo', 16:02:51 INFO - '--mitmproxy', 16:02:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:02:51 INFO - '--firstNonBlankPaint'], 16:02:51 INFO - 'tests': ['tp6_google', 16:02:51 INFO - 'tp6_youtube', 16:02:51 INFO - 'tp6_amazon', 16:02:51 INFO - 'tp6_facebook']}, 16:02:51 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:02:51 INFO - 'talos_options': ['--stylo-threads=1', 16:02:51 INFO - '--mitmproxy', 16:02:51 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:02:51 INFO - '--firstNonBlankPaint'], 16:02:51 INFO - 'tests': ['tp6_google', 16:02:51 INFO - 'tp6_youtube', 16:02:51 INFO - 'tp6_amazon', 16:02:51 INFO - 'tp6_facebook']}, 16:02:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:02:51 INFO - 'talos_options': ['--xperf_path', 16:02:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:51 INFO - 'tests': ['tp5n']}, 16:02:51 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:02:51 INFO - 'talos_options': ['--disable-stylo', 16:02:51 INFO - '--xperf_path', 16:02:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:02:51 INFO - 'tests': ['tp5n']}}} 16:02:51 INFO - Running post-action listener: _resource_record_post_action 16:02:51 INFO - [mozharness: 2017-09-13 23:02:51.708853Z] Finished populate-webroot step (success) 16:02:51 INFO - [mozharness: 2017-09-13 23:02:51.708942Z] Running create-virtualenv step. 16:02:51 INFO - Running pre-action listener: _resource_record_pre_action 16:02:51 INFO - Running main action method: create_virtualenv 16:02:51 INFO - Creating virtualenv /builds/slave/test/build/venv 16:02:51 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:02:51 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:02:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:02:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:02:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:02:53 INFO - Installing distribute.............................................................................................................................................................................................done. 16:02:54 INFO - Installing pip...............done. 16:02:54 INFO - Return code: 0 16:02:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:02:54 INFO - Reading from file tmpfile_stdout 16:02:54 INFO - Output received: 16:02:54 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:02:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:02:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:02:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:02:54 INFO - Using env: {'DISPLAY': ':0', 16:02:54 INFO - 'HOME': '/home/cltbld', 16:02:54 INFO - 'LANG': 'en_US.UTF-8', 16:02:54 INFO - 'LANGUAGE': 'en_US:en', 16:02:54 INFO - 'LOGNAME': 'cltbld', 16:02:54 INFO - 'MAIL': '/var/mail/cltbld', 16:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:54 INFO - 'MOZ_NO_REMOTE': '1', 16:02:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:54 INFO - 'NO_EM_RESTART': '1', 16:02:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:54 INFO - 'PWD': '/builds/slave/test', 16:02:54 INFO - 'SHELL': '/bin/bash', 16:02:54 INFO - 'SHLVL': '1', 16:02:54 INFO - 'TERM': 'linux', 16:02:54 INFO - 'TMOUT': '86400', 16:02:54 INFO - 'USER': 'cltbld', 16:02:54 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:54 INFO - '_': '/tools/buildbot/bin/python'} 16:02:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:02:56 INFO - Downloading/unpacking pip>=1.5 16:02:56 INFO - Running setup.py egg_info for package pip 16:02:56 INFO - warning: no files found matching 'pip/cacert.pem' 16:02:56 INFO - warning: no files found matching '*.html' under directory 'docs' 16:02:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:02:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:02:56 INFO - Installing collected packages: pip 16:02:56 INFO - Found existing installation: pip 1.1 16:02:56 INFO - Uninstalling pip: 16:02:56 INFO - Successfully uninstalled pip 16:02:56 INFO - Running setup.py install for pip 16:02:56 INFO - warning: no files found matching 'pip/cacert.pem' 16:02:56 INFO - warning: no files found matching '*.html' under directory 'docs' 16:02:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:02:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:02:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:02:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:02:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:02:56 INFO - Successfully installed pip 16:02:56 INFO - Cleaning up... 16:02:56 INFO - Return code: 0 16:02:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:02: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')]} 16:02:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:02: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')]} 16:02:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:02:56 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 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:02:56 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 16:02:56 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 16:02:56 INFO - Using env: {'DISPLAY': ':0', 16:02:56 INFO - 'HOME': '/home/cltbld', 16:02:56 INFO - 'LANG': 'en_US.UTF-8', 16:02:56 INFO - 'LANGUAGE': 'en_US:en', 16:02:56 INFO - 'LOGNAME': 'cltbld', 16:02:56 INFO - 'MAIL': '/var/mail/cltbld', 16:02:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:02:56 INFO - 'MOZ_NO_REMOTE': '1', 16:02:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:02:56 INFO - 'NO_EM_RESTART': '1', 16:02:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:02:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:02:56 INFO - 'PWD': '/builds/slave/test', 16:02:56 INFO - 'SHELL': '/bin/bash', 16:02:56 INFO - 'SHLVL': '1', 16:02:56 INFO - 'TERM': 'linux', 16:02:56 INFO - 'TMOUT': '86400', 16:02:56 INFO - 'USER': 'cltbld', 16:02:56 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:02:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:02:56 INFO - '_': '/tools/buildbot/bin/python'} 16:02:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:02:57 INFO - Downloading/unpacking psutil>=3.1.1 16:02:57 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 16:02:57 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 16:02:57 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 16:02:57 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 16:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:03:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:03:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:03:00 INFO - Installing collected packages: psutil 16:03:00 INFO - Running setup.py install for psutil 16:03:00 INFO - building 'psutil._psutil_linux' extension 16:03:00 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 16:03:02 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 16:03:06 INFO - building 'psutil._psutil_posix' extension 16:03:06 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 16:03:06 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 16:03:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:03:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:03:06 INFO - Successfully installed psutil 16:03:06 INFO - Cleaning up... 16:03:06 INFO - Return code: 0 16:03:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:03: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')]} 16:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03: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')]} 16:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:03: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:03: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 mozsystemmonitor==0.3 16:03:06 INFO - Using env: {'DISPLAY': ':0', 16:03:06 INFO - 'HOME': '/home/cltbld', 16:03:06 INFO - 'LANG': 'en_US.UTF-8', 16:03:06 INFO - 'LANGUAGE': 'en_US:en', 16:03:06 INFO - 'LOGNAME': 'cltbld', 16:03:06 INFO - 'MAIL': '/var/mail/cltbld', 16:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:06 INFO - 'MOZ_NO_REMOTE': '1', 16:03:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:06 INFO - 'NO_EM_RESTART': '1', 16:03:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:06 INFO - 'PWD': '/builds/slave/test', 16:03:06 INFO - 'SHELL': '/bin/bash', 16:03:06 INFO - 'SHLVL': '1', 16:03:06 INFO - 'TERM': 'linux', 16:03:06 INFO - 'TMOUT': '86400', 16:03:06 INFO - 'USER': 'cltbld', 16:03:06 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:06 INFO - '_': '/tools/buildbot/bin/python'} 16:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:03:06 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 16:03:06 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 16:03:06 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 16:03:06 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 16:03:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:03:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:03:08 INFO - Installing collected packages: mozsystemmonitor 16:03:08 INFO - Running setup.py install for mozsystemmonitor 16:03:08 INFO - Successfully installed mozsystemmonitor 16:03:08 INFO - Cleaning up... 16:03:08 INFO - Return code: 0 16:03:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:03:08 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')]} 16:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:08 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')]} 16:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:08 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 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:03:08 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 16:03:08 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 16:03:08 INFO - Using env: {'DISPLAY': ':0', 16:03:08 INFO - 'HOME': '/home/cltbld', 16:03:08 INFO - 'LANG': 'en_US.UTF-8', 16:03:08 INFO - 'LANGUAGE': 'en_US:en', 16:03:08 INFO - 'LOGNAME': 'cltbld', 16:03:08 INFO - 'MAIL': '/var/mail/cltbld', 16:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:08 INFO - 'MOZ_NO_REMOTE': '1', 16:03:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:08 INFO - 'NO_EM_RESTART': '1', 16:03:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:08 INFO - 'PWD': '/builds/slave/test', 16:03:08 INFO - 'SHELL': '/bin/bash', 16:03:08 INFO - 'SHLVL': '1', 16:03:08 INFO - 'TERM': 'linux', 16:03:08 INFO - 'TMOUT': '86400', 16:03:08 INFO - 'USER': 'cltbld', 16:03:08 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:08 INFO - '_': '/tools/buildbot/bin/python'} 16:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:08 INFO - Downloading/unpacking jsonschema==2.5.1 16:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:03:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:03: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 16:03: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 16:03: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 16:03: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 16:03:09 INFO - Downloading functools32-3.2.3-2.tar.gz 16:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:03:09 INFO - warning: no files found matching '*.txt' 16:03:09 INFO - no previously-included directories found matching 'build' 16:03:09 INFO - no previously-included directories found matching 'dist' 16:03:09 INFO - no previously-included directories found matching '.git*' 16:03:09 INFO - Installing collected packages: jsonschema, functools32 16:03:09 INFO - Running setup.py install for functools32 16:03:09 INFO - warning: no files found matching '*.txt' 16:03:09 INFO - no previously-included directories found matching 'build' 16:03:09 INFO - no previously-included directories found matching 'dist' 16:03:09 INFO - no previously-included directories found matching '.git*' 16:03:09 INFO - Successfully installed jsonschema functools32 16:03:09 INFO - Cleaning up... 16:03:09 INFO - Return code: 0 16:03:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:03: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')]} 16:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03: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')]} 16:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:03:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:03:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:03:09 INFO - Using env: {'DISPLAY': ':0', 16:03:09 INFO - 'HOME': '/home/cltbld', 16:03:09 INFO - 'LANG': 'en_US.UTF-8', 16:03:09 INFO - 'LANGUAGE': 'en_US:en', 16:03:09 INFO - 'LOGNAME': 'cltbld', 16:03:09 INFO - 'MAIL': '/var/mail/cltbld', 16:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:09 INFO - 'MOZ_NO_REMOTE': '1', 16:03:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:09 INFO - 'NO_EM_RESTART': '1', 16:03:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:09 INFO - 'PWD': '/builds/slave/test', 16:03:09 INFO - 'SHELL': '/bin/bash', 16:03:09 INFO - 'SHLVL': '1', 16:03:09 INFO - 'TERM': 'linux', 16:03:09 INFO - 'TMOUT': '86400', 16:03:09 INFO - 'USER': 'cltbld', 16:03:09 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:09 INFO - '_': '/tools/buildbot/bin/python'} 16:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:03:10 INFO - Cleaning up... 16:03:10 INFO - Return code: 0 16:03:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:03:10 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')]} 16:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:10 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')]} 16:03:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:10 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 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:03:10 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 16:03:10 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 16:03:10 INFO - Using env: {'DISPLAY': ':0', 16:03:10 INFO - 'HOME': '/home/cltbld', 16:03:10 INFO - 'LANG': 'en_US.UTF-8', 16:03:10 INFO - 'LANGUAGE': 'en_US:en', 16:03:10 INFO - 'LOGNAME': 'cltbld', 16:03:10 INFO - 'MAIL': '/var/mail/cltbld', 16:03:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:10 INFO - 'MOZ_NO_REMOTE': '1', 16:03:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:10 INFO - 'NO_EM_RESTART': '1', 16:03:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:10 INFO - 'PWD': '/builds/slave/test', 16:03:10 INFO - 'SHELL': '/bin/bash', 16:03:10 INFO - 'SHLVL': '1', 16:03:10 INFO - 'TERM': 'linux', 16:03:10 INFO - 'TMOUT': '86400', 16:03:10 INFO - 'USER': 'cltbld', 16:03:10 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:10 INFO - '_': '/tools/buildbot/bin/python'} 16:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:10 INFO - Downloading/unpacking blobuploader==1.2.4 16:03:10 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 16:03:10 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 16:03:10 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 16:03:10 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 16:03:11 INFO - Downloading blobuploader-1.2.4.tar.gz 16:03:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:03:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:03:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:11 INFO - Downloading docopt-0.6.1.tar.gz 16:03:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:03:11 INFO - Installing collected packages: blobuploader, requests, docopt 16:03:11 INFO - Running setup.py install for blobuploader 16:03:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:03:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:03:12 INFO - Running setup.py install for requests 16:03:12 INFO - Running setup.py install for docopt 16:03:12 INFO - Successfully installed blobuploader requests docopt 16:03:12 INFO - Cleaning up... 16:03:12 INFO - Return code: 0 16:03:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:03: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')]} 16:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03: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')]} 16:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:03:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:03:12 INFO - Using env: {'DISPLAY': ':0', 16:03:12 INFO - 'HOME': '/home/cltbld', 16:03:12 INFO - 'LANG': 'en_US.UTF-8', 16:03:12 INFO - 'LANGUAGE': 'en_US:en', 16:03:12 INFO - 'LOGNAME': 'cltbld', 16:03:12 INFO - 'MAIL': '/var/mail/cltbld', 16:03:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:12 INFO - 'MOZ_NO_REMOTE': '1', 16:03:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:12 INFO - 'NO_EM_RESTART': '1', 16:03:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:12 INFO - 'PWD': '/builds/slave/test', 16:03:12 INFO - 'SHELL': '/bin/bash', 16:03:12 INFO - 'SHLVL': '1', 16:03:12 INFO - 'TERM': 'linux', 16:03:12 INFO - 'TMOUT': '86400', 16:03:12 INFO - 'USER': 'cltbld', 16:03:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:12 INFO - '_': '/tools/buildbot/bin/python'} 16:03:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:03:12 INFO - Running setup.py (path:/tmp/pip-TNkiQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:03:12 INFO - Running setup.py (path:/tmp/pip-t37_7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:03:12 INFO - Running setup.py (path:/tmp/pip-rDSD7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:03:12 INFO - Running setup.py (path:/tmp/pip-FutT0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:03:13 INFO - Running setup.py (path:/tmp/pip-KFOfJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:03:13 INFO - Running setup.py (path:/tmp/pip-5d85Aq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:03:13 INFO - Running setup.py (path:/tmp/pip-uwqhCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:03:13 INFO - Running setup.py (path:/tmp/pip-CIADH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:03:13 INFO - Running setup.py (path:/tmp/pip-okZh8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:03:13 INFO - Running setup.py (path:/tmp/pip-ArmIAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:03:13 INFO - Running setup.py (path:/tmp/pip-CKo5zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:03:13 INFO - Running setup.py (path:/tmp/pip-LSCP5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:03:13 INFO - Running setup.py (path:/tmp/pip-hN8xBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:03:13 INFO - Running setup.py (path:/tmp/pip-57APhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:13 INFO - Running setup.py (path:/tmp/pip-GsHiaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:03:13 INFO - Running setup.py (path:/tmp/pip-8hD65C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:03:13 INFO - Running setup.py (path:/tmp/pip-joxTWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:03:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:03:13 INFO - Running setup.py install for manifestparser 16:03:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:03:14 INFO - Running setup.py install for mozcrash 16:03:14 INFO - Running setup.py install for mozdebug 16:03:14 INFO - Running setup.py install for mozdevice 16:03:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:03:14 INFO - Running setup.py install for mozfile 16:03:14 INFO - Running setup.py install for mozhttpd 16:03:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:03:14 INFO - Running setup.py install for mozinfo 16:03:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:03:14 INFO - Running setup.py install for mozInstall 16:03:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:03:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:03:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:03:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:03:14 INFO - Running setup.py install for mozleak 16:03:14 INFO - Running setup.py install for mozlog 16:03:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:03:14 INFO - Running setup.py install for moznetwork 16:03:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:03:15 INFO - Running setup.py install for mozprocess 16:03:15 INFO - Running setup.py install for mozprofile 16:03:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:03:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:03:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:03:15 INFO - Running setup.py install for mozrunner 16:03:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:03:15 INFO - Running setup.py install for mozscreenshot 16:03:15 INFO - Running setup.py install for moztest 16:03:15 INFO - Running setup.py install for mozversion 16:03:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:03:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:03:15 INFO - Cleaning up... 16:03:15 INFO - Return code: 0 16:03:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:03:15 INFO - Using env: {'DISPLAY': ':0', 16:03:15 INFO - 'HOME': '/home/cltbld', 16:03:15 INFO - 'LANG': 'en_US.UTF-8', 16:03:15 INFO - 'LANGUAGE': 'en_US:en', 16:03:15 INFO - 'LOGNAME': 'cltbld', 16:03:15 INFO - 'MAIL': '/var/mail/cltbld', 16:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:15 INFO - 'MOZ_NO_REMOTE': '1', 16:03:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:15 INFO - 'NO_EM_RESTART': '1', 16:03:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:15 INFO - 'PWD': '/builds/slave/test', 16:03:15 INFO - 'SHELL': '/bin/bash', 16:03:15 INFO - 'SHLVL': '1', 16:03:15 INFO - 'TERM': 'linux', 16:03:15 INFO - 'TMOUT': '86400', 16:03:15 INFO - 'USER': 'cltbld', 16:03:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:15 INFO - '_': '/tools/buildbot/bin/python'} 16:03:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:03:15 INFO - Running setup.py (path:/tmp/pip-54aGp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:03:15 INFO - Running setup.py (path:/tmp/pip-j899dE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:03:16 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)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:03:16 INFO - Running setup.py (path:/tmp/pip-8DhjM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:03:16 INFO - Running setup.py (path:/tmp/pip-4UQKRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:03:16 INFO - Running setup.py (path:/tmp/pip-D8HRsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:03:16 INFO - Running setup.py (path:/tmp/pip-xSO8r9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:03:16 INFO - Running setup.py (path:/tmp/pip-mbhAAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:03:16 INFO - Running setup.py (path:/tmp/pip-TiWXRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:03:16 INFO - Running setup.py (path:/tmp/pip-ath1bA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:03:16 INFO - Running setup.py (path:/tmp/pip-Nyqpic-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:03:16 INFO - Running setup.py (path:/tmp/pip-AlNJ9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:03:16 INFO - Running setup.py (path:/tmp/pip-24aQU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:03:16 INFO - Running setup.py (path:/tmp/pip-IeNKQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:03:16 INFO - Running setup.py (path:/tmp/pip-ufyEKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:16 INFO - Running setup.py (path:/tmp/pip-B1Z2Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:03:16 INFO - Running setup.py (path:/tmp/pip-s13zwf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:03:17 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)) 16:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:03:17 INFO - Running setup.py (path:/tmp/pip-RA1jX1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:03:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:18 INFO - Downloading blessings-1.6.tar.gz 16:03:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:03:18 INFO - Installing collected packages: blessings 16:03:18 INFO - Running setup.py install for blessings 16:03:18 INFO - Successfully installed blessings 16:03:18 INFO - Cleaning up... 16:03:18 INFO - Return code: 0 16:03:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:03:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:03:18 INFO - Reading from file tmpfile_stdout 16:03:18 INFO - Current package versions: 16:03:18 INFO - argparse == 1.2.1 16:03:18 INFO - blessings == 1.6 16:03:18 INFO - blobuploader == 1.2.4 16:03:18 INFO - docopt == 0.6.1 16:03:18 INFO - functools32 == 3.2.3-2 16:03:18 INFO - jsonschema == 2.5.1 16:03:18 INFO - manifestparser == 1.1 16:03:18 INFO - mozInstall == 1.13 16:03:18 INFO - mozcrash == 1.0 16:03:18 INFO - mozdebug == 0.1 16:03:18 INFO - mozdevice == 0.51 16:03:18 INFO - mozfile == 1.2 16:03:18 INFO - mozhttpd == 0.7 16:03:18 INFO - mozinfo == 0.10 16:03:18 INFO - mozleak == 0.1 16:03:18 INFO - mozlog == 3.5 16:03:18 INFO - moznetwork == 0.27 16:03:18 INFO - mozprocess == 0.25 16:03:18 INFO - mozprofile == 0.28 16:03:18 INFO - mozrunner == 6.13 16:03:18 INFO - mozscreenshot == 0.1 16:03:18 INFO - mozsystemmonitor == 0.3 16:03:18 INFO - moztest == 0.8 16:03:18 INFO - mozversion == 1.4 16:03:18 INFO - psutil == 3.1.1 16:03:18 INFO - requests == 1.2.3 16:03:18 INFO - wsgiref == 0.1.2 16:03:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:03:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:03:18 INFO - Using env: {'DISPLAY': ':0', 16:03:18 INFO - 'HOME': '/home/cltbld', 16:03:18 INFO - 'LANG': 'en_US.UTF-8', 16:03:18 INFO - 'LANGUAGE': 'en_US:en', 16:03:18 INFO - 'LOGNAME': 'cltbld', 16:03:18 INFO - 'MAIL': '/var/mail/cltbld', 16:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:18 INFO - 'MOZ_NO_REMOTE': '1', 16:03:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:18 INFO - 'NO_EM_RESTART': '1', 16:03:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:18 INFO - 'PWD': '/builds/slave/test', 16:03:18 INFO - 'SHELL': '/bin/bash', 16:03:18 INFO - 'SHLVL': '1', 16:03:18 INFO - 'TERM': 'linux', 16:03:18 INFO - 'TMOUT': '86400', 16:03:18 INFO - 'USER': 'cltbld', 16:03:18 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:18 INFO - '_': '/tools/buildbot/bin/python'} 16:03:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:03:18 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:03:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:03:20 INFO - Installing collected packages: simplejson 16:03:20 INFO - Running setup.py install for simplejson 16:03:20 INFO - building 'simplejson._speedups' extension 16:03:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 16:03:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 16:03:21 INFO - Successfully installed simplejson 16:03:21 INFO - Cleaning up... 16:03:21 INFO - Return code: 0 16:03:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:03: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')]} 16:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03: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')]} 16:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1de71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fb0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2153a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, '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': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', '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 16:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:03:21 INFO - Using env: {'DISPLAY': ':0', 16:03:21 INFO - 'HOME': '/home/cltbld', 16:03:21 INFO - 'LANG': 'en_US.UTF-8', 16:03:21 INFO - 'LANGUAGE': 'en_US:en', 16:03:21 INFO - 'LOGNAME': 'cltbld', 16:03:21 INFO - 'MAIL': '/var/mail/cltbld', 16:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:21 INFO - 'MOZ_NO_REMOTE': '1', 16:03:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:21 INFO - 'NO_EM_RESTART': '1', 16:03:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:21 INFO - 'PWD': '/builds/slave/test', 16:03:21 INFO - 'SHELL': '/bin/bash', 16:03:21 INFO - 'SHLVL': '1', 16:03:21 INFO - 'TERM': 'linux', 16:03:21 INFO - 'TMOUT': '86400', 16:03:21 INFO - 'USER': 'cltbld', 16:03:21 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:21 INFO - '_': '/tools/buildbot/bin/python'} 16:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:03:21 INFO - Cleaning up... 16:03:21 INFO - Return code: 0 16:03:21 INFO - Running post-action listener: _resource_record_post_action 16:03:21 INFO - Running post-action listener: _start_resource_monitoring 16:03:21 INFO - Starting resource monitoring. 16:03:21 INFO - [mozharness: 2017-09-13 23:03:21.414592Z] Finished create-virtualenv step (success) 16:03:21 INFO - [mozharness: 2017-09-13 23:03:21.415102Z] Running install step. 16:03:21 INFO - Running pre-action listener: _resource_record_pre_action 16:03:21 INFO - Running main action method: install 16:03:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:03:21 INFO - Reading from file tmpfile_stdout 16:03:21 INFO - Detecting whether we're running mozinstall >=1.0... 16:03:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:03:21 INFO - Reading from file tmpfile_stdout 16:03:21 INFO - Output received: 16:03:21 INFO - Usage: mozinstall [options] installer 16:03:21 INFO - Options: 16:03:21 INFO - -h, --help show this help message and exit 16:03:21 INFO - -d DEST, --destination=DEST 16:03:21 INFO - Directory to install application into. [default: 16:03:21 INFO - "/builds/slave/test"] 16:03:21 INFO - --app=APP Application being installed. [default: firefox] 16:03:21 INFO - mkdir: /builds/slave/test/build/application 16:03:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:03:39 INFO - Reading from file tmpfile_stdout 16:03:39 INFO - Output received: 16:03:39 INFO - /builds/slave/test/build/application/firefox/firefox 16:03:39 INFO - Running post-action listener: _resource_record_post_action 16:03:39 INFO - [mozharness: 2017-09-13 23:03:39.595174Z] Finished install step (success) 16:03:39 INFO - [mozharness: 2017-09-13 23:03:39.595427Z] Running setup-mitmproxy step. 16:03:39 INFO - Running pre-action listener: _resource_record_pre_action 16:03:39 INFO - Running main action method: setup_mitmproxy 16:03:39 INFO - Skipping: mitmproxy is not required 16:03:39 INFO - Running post-action listener: _resource_record_post_action 16:03:39 INFO - [mozharness: 2017-09-13 23:03:39.596559Z] Finished setup-mitmproxy step (success) 16:03:39 INFO - [mozharness: 2017-09-13 23:03:39.596779Z] Running run-tests step. 16:03:39 INFO - Running pre-action listener: _resource_record_pre_action 16:03:39 INFO - Running pre-action listener: _set_gcov_prefix 16:03:39 INFO - Running main action method: run_tests 16:03:39 WARNING - Try message not found. 16:03:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:03:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:03:39 INFO - Python 2.7.3 16:03:39 INFO - Return code: 0 16:03:39 INFO - grabbing minidump binary from tooltool 16:03:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:03:39 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2157090>, '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 0x2157d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2151f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:03:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:03:39 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:03:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:03:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:03:40 INFO - Return code: 0 16:03:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:03:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:03:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:03:40 INFO - ENV: RUST_BACKTRACE is now full 16:03:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:03:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:03:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 16:03:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 16:03:40 INFO - Using env: {'DISPLAY': ':0', 16:03:40 INFO - 'HOME': '/home/cltbld', 16:03:40 INFO - 'LANG': 'en_US.UTF-8', 16:03:40 INFO - 'LANGUAGE': 'en_US:en', 16:03:40 INFO - 'LOGNAME': 'cltbld', 16:03:40 INFO - 'MAIL': '/var/mail/cltbld', 16:03:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:03:40 INFO - 'MOZ_ACCELERATED': '1', 16:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:40 INFO - 'MOZ_NO_REMOTE': '1', 16:03:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:40 INFO - 'MOZ_WEBRENDER': '1', 16:03:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:40 INFO - 'NO_EM_RESTART': '1', 16:03:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:40 INFO - 'PWD': '/builds/slave/test', 16:03:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:40 INFO - 'RUST_BACKTRACE': 'full', 16:03:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:03:40 INFO - 'SHELL': '/bin/bash', 16:03:40 INFO - 'SHLVL': '1', 16:03:40 INFO - 'TERM': 'linux', 16:03:40 INFO - 'TMOUT': '86400', 16:03:40 INFO - 'USER': 'cltbld', 16:03:40 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:40 INFO - '_': '/tools/buildbot/bin/python'} 16:03:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 16:03:40 INFO - mozversion application_buildid: 20170913215920 16:03:40 INFO - mozversion application_changeset: 8645a74bbbd06b67699317df1abf3897db0e43d5 16:03:40 INFO - mozversion application_display_name: Nightly 16:03:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:03:40 INFO - mozversion application_name: Firefox 16:03:40 INFO - mozversion application_remotingname: firefox 16:03:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:03:40 INFO - mozversion application_vendor: Mozilla 16:03:40 INFO - mozversion application_version: 57.0a1 16:03:40 INFO - mozversion platform_buildid: 20170913215920 16:03:40 INFO - mozversion platform_changeset: 8645a74bbbd06b67699317df1abf3897db0e43d5 16:03:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:03:40 INFO - mozversion platform_version: 57.0a1 16:03:40 INFO - using testdate: 1505343820 16:03:40 INFO - actual date: 1505343820 16:03:40 INFO - starting webserver on 'localhost:44964' 16:03:40 INFO - SUITE-START | Running 2 tests 16:03:40 INFO - TEST-START | dromaeo_css 16:03:40 INFO - Initialising browser for dromaeo_css test... 16:03:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44964/getInfo.html -profile /tmp/tmppUtocr/profile 16:03:41 INFO - TEST-INFO | started process 3588 (/builds/slave/test/build/application/firefox/firefox http://localhost:44964/getInfo.html) 16:03:51 INFO - TEST-INFO | 3588: exit 0 16:03:51 INFO - Browser initialized. 16:03:51 INFO - Running cycle 1/1 for dromaeo_css test... 16:03:51 INFO - Using env: {'DISPLAY': ':0', 16:03:51 INFO - 'HOME': '/home/cltbld', 16:03:51 INFO - 'JSGC_DISABLE_POISONING': '1', 16:03:51 INFO - 'LANG': 'en_US.UTF-8', 16:03:51 INFO - 'LANGUAGE': 'en_US:en', 16:03:51 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:03:51 INFO - 'LOGNAME': 'cltbld', 16:03:51 INFO - 'MAIL': '/var/mail/cltbld', 16:03:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:03:51 INFO - 'MOZ_ACCELERATED': '1', 16:03:51 INFO - 'MOZ_CRASHREPORTER': '1', 16:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:51 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:03:51 INFO - 'MOZ_NO_REMOTE': '1', 16:03:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:51 INFO - 'MOZ_WEBRENDER': '1', 16:03:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:51 INFO - 'NO_EM_RESTART': '1', 16:03:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:51 INFO - 'PWD': '/builds/slave/test', 16:03:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:51 INFO - 'RUST_BACKTRACE': 'full', 16:03:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:03:51 INFO - 'SHELL': '/bin/bash', 16:03:51 INFO - 'SHLVL': '1', 16:03:51 INFO - 'TERM': 'linux', 16:03:51 INFO - 'TMOUT': '86400', 16:03:51 INFO - 'USER': 'cltbld', 16:03:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:51 INFO - '_': '/tools/buildbot/bin/python'} 16:03:51 INFO - TEST-INFO | started process 3835 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppUtocr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:03:52 INFO - PID 3835 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:03:52 INFO - PID 3835 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:03:52 INFO - PID 3835 | 16:03:52 INFO - PID 3835 | (/builds/slave/test/build/application/firefox/firefox:3895): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:03:52 INFO - PID 3835 | 16:04:02 INFO - PID 3835 | RSS: Main: 213839872 16:04:02 INFO - PID 3835 | 16:06:59 INFO - PID 3835 | Cycle 1(1): loaded http://localhost:44964/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44964/tests/dromaeo/cssquery-ext.html) 16:07:01 INFO - PID 3835 | RSS: Main: 228167680 16:07:01 INFO - PID 3835 | 16:10:03 INFO - PID 3835 | Cycle 1(1): loaded http://localhost:44964/tests/dromaeo/cssquery-ext.html (next: http://localhost:44964/tests/dromaeo/cssquery-jquery.html) 16:10:04 INFO - PID 3835 | RSS: Main: 224563200 16:10:04 INFO - PID 3835 | 16:13:06 INFO - PID 3835 | Cycle 1(1): loaded http://localhost:44964/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44964/tests/dromaeo/cssquery-mootools.html) 16:13:06 INFO - PID 3835 | RSS: Main: 226676736 16:13:06 INFO - PID 3835 | 16:16:08 INFO - PID 3835 | Cycle 1(1): loaded http://localhost:44964/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44964/tests/dromaeo/cssquery-prototype.html) 16:16:09 INFO - PID 3835 | RSS: Main: 225435648 16:16:09 INFO - PID 3835 | 16:19:13 INFO - PID 3835 | Cycle 1(1): loaded http://localhost:44964/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44964/tests/dromaeo/cssquery-yui.html) 16:19:13 INFO - PID 3835 | RSS: Main: 227098624 16:19:13 INFO - PID 3835 | 16:22:15 INFO - PID 3835 | Cycle 1(1): loaded http://localhost:44964/tests/dromaeo/cssquery-yui.html (next: http://localhost:44964/tests/dromaeo/cssquery-dojo.html) 16:22:16 INFO - PID 3835 | RSS: Main: 232509440 16:22:16 INFO - PID 3835 | 16:22:16 INFO - PID 3835 | __start_tp_report 16:22:16 INFO - PID 3835 | _x_x_mozilla_page_load 16:22:16 INFO - PID 3835 | _x_x_mozilla_page_load_details 16:22:16 INFO - PID 3835 | |i|pagename|runs| 16:22:16 INFO - PID 3835 | |0;dojo.html;2982.6719060903283;3110.813351198928;3130.358276553307;3141.8271995040272;3160.6997335253145;6266.028765541343;6314.652694101823;6350.269718982318;6392.552521323498;6394.200724909387;6135.079738039296;6235.220597425322;6249.53128515362;6268.059791031347;6290.339514350984;5728.99999999999;5755.884882302352;5821.6507009579345;5821.67980761058;5835.124731290319;5689.118186681071;5805.796797112102;5849.85375365615;5852.502537284336;5904.645721256738;5649.1243857202;5739.426057394269;5746.770129194827;5779.104238842986;5810.1865738796605;5786.71066446676;5803.158542011427;5827.883442331172;5877.823665290048;5889.116632505115;5628.212050312959;5675.3757086720425;5720.513756330717;5800.854978625526;5813.215215945877;6172.22847144107;6225.159603453521;6233.625982441068;6289.465395441393;6297.937020629826;6645.9667701661665;6679.532432729711;6681.498887583451;6688.699008544627;6788.08360871281;6599.6370199639005;6643.767468138641;6699.196096468406;6786.491013174032;6804.6257455839905;6160.507159427235;6168.167297414837;6194.4425001749605;6210.844728881814;6232.127502149658;6753.1220941277315;6803.115594972622;6814.693338799766;6829.795106146824;6846.178458585002;6464.903026454607;6474.838129046811;6531.771388001493;6543.640099794462;6579;6596.538242322991;6603.504737144734;6675.365840245266;6685.6322902240845;6687.732490700318;6484.902726459107;6580.045893345438;6617.9669101655145;6635.137432133888;6718.630475323904;6684.632345220963;6773.966130169416;6783.457323414115;6786.728530858711;6797.320267973262;5193.844184674465;5241.711705856214;5248;5252.5535329497425;5267.288915996368;4504.684672072989;4516.548345165457;4549;4580.564846339593;4596.6092882105395;5665.886682266414;5770.42295770428;5797.826065218051;5807.477327040587;5822.214001109796;6587;6693.1298931138645;6781.220159681601;6781.62701051437;6836.897446538306;5820.214271073351;5825.825225243249;5876.529877609781;5884.7940322088525;5915.970420147958;5830.591858569945;5831.067029275296;5893.263342082239;5893.823185304448;5895.616784908967;6217.347178546278;6375.6174629522375;6383.457406120532;6419.390157934989;6545.8363540910905;6661.233958094881;6702.128723266042;6716.966415167893;6732.495062870206;6752.93247067523;6305.5901366411035;6383.234011918599;6386.9680651596445;6489.837754056186;6492.902606461002;6565.934340656723;6653.833654158685;6682.665866706548;6682.69927853238;6688.2977287386075;6684.63234522106;6703.994400839913;6737.461003119641;6779.559328643622;6818.522703410714;6455.0640157176;6477.870442591027;6499.707513161825;6556.803295901131;6586.045023471665;6499.935000650122;6504.447121994494;6523.67381630907;6529.7061632227615;6539;6413.7113829876835;6463.095166676763;6471.482281417381;6599.472042236705;6617.2721000688925;6605.471562275103;6704.06143139951;6719;6721.529492935448;6742.561733487504;6391.392817682313;6399.136116624382;6410.391012853772;6488.772892948724;6493.188351456068 16:22:16 INFO - PID 3835 | |1;ext.html;810.9716159934403;821.1337039423413;825.950442973422;838.4466252273501;839.576014112873;40777.18445631089;41599.16801663965;41629.9592510187;41728.374074388834;41734.16531669369;38204.80897595515;38511.422328665096;38568.61431385685;38617.61382386175;38647.22705545894;6993.195782485016;7105.289471052879;7110.288971102874;7110.786676399691;7112.075430194068;6981.930180698204;7046.788596342118;7076.681549330293;7077.469189810759;7087.114110736158;7022;7649.6940122395035;7864.646090925922;7878.290953814156;7884.290413862751;7749.30256276935;7818.296353328228;7822.139564647884;7822.256885595859;7830.451868369217;7468.4025277977635;7771.883421748678;7851.136374998746;7854.882176767296;7863.252990965849;38309.23381532354;38702.612973870186;38724.61275387238;38792.418113728316;38806.80596597027;3551.5383000210068;3596.3706351388664;3598.892033239007;3601.981990090059;3606.04439823447;31644.36711265785;31704.841475792706;31737;31759.52360714591;31770.364592708247;5226.189940559219;5226.686398816083;5242.2136679498;5250.238715386286;5299.523042926116;186763;187581;188536;188790;189014.10985890104;42559.57440425618;43366.13267734628;48487.27269090967;48787.51212487901;48824.26763598549;52004.47995519996;52052.47947520476;52055;52167.73916130395;52200;16142.35430582787;16266.51200463988;16292.592685182964;16304.429344972868;16337.509874703777;13324.73350532984;13377.799333010013;13445.731085378238;13469.932650336686;13477.595672129655;13723.931380343034;13916.791248131287;14013.579592612034;14061.718765624835;14068.226247556278;5543.556515478752;5826.359100498942;5850.531957443394;5857.20927674767;5878.853028674317;1017.2980643356061;1019.7654539455966;1021.560728886571;1026.1739299863682;1036.3729943384294;22957.88521057384;23010.19464318741;23233.88383058074;23457.7654223459;23467.7653223469;1106.4965440724452;1112.4159816096615;1125.6060378867494;1131.9094472442184;1135.6649788312468;12022.819657705142;12057.758844823229;12079.75840483203;12166.878331216574;12188.634340969786;218491;219755;220932;223473;223515;52771.20843187355;53018.46981530212;53129;53208.73395632997;53576;1423.7366087273888;1442.8845692344587;1458.0158393084625;1467.4717101843328;1473.395907677847;22266.332010039725;22698.886505568025;22802.42993925165;22865;22981.54036919219;37480.250394992494;37651;37729.434058488594;37735.433968491045;37836.2432751349;188766;188880;190494;190787;191295;30452.390952181275;32868.67131328656;34602.30795384028;34636.30727385489;35208.823955880056;43429.56570434382;43591;43659.56340436555;44079;44406.3339049908;31077.844610776803;31602;31643.367132657677;31894.52158217582;31988.6801131995;31160.065198044096;31288.530672039484;31351;31754.206144846565;31841;34124;35380.292394152486;35398.115047124025;35431.82284088563;35889.82055089708;30032.84983575068;30076.699233007388;30398;30553.694463055086;31347.373052538365;22779.77220227843;23018.769812301663;23506.88246558756;23579.82100894914;23586.292411227692 16:22:16 INFO - PID 3835 | |2;jquery.html;560.266051472571;561.7415988645223;570.9200711900332;576.2623841482902;596.7791916990714;22386;23105.30684079476;23107.422314442127;23138.190163344287;23192.884035579842;22476.43808904778;22885.771142288573;23206.187783427584;23945.161919332826;24267.75732242677;6392.61644301341;6490.350964903496;6517.413432791048;6538.8692226155445;6566.179227596551;6413.518986076039;6515.31589183136;6517.804465866032;6521.967390163042;6522.021696745479;6228.688565571726;6533.673316334188;6563.9671801640925;6569.047488114221;6573.80278591643;6546.247181574132;6567.441767449749;6603.042558828991;6607.240167380764;6609.834754131137;5989.13157592151;6057.061155520902;6103.023516237381;6111.755529778803;6127.846803829895;29192.562111568343;29511.262218444495;29878.85060574705;29883.402331953457;29885.402291954255;2003.1987205118069;2021.5249416387137;2030.218365929128;2031.0860112949085;2032.0754056904073;19383.612327753508;19602.11790469432;19919.302824401075;19928.202871885107;19959.101840417217;2418.697662792151;2430.3559556717482;2466.703995520549;2476.182859656309;2483.7019557653016;114129;119067;132039;139751;144813;14677.77983330251;14826.406943722346;14871.764129924375;14908.776368354484;14957.476488322856;23163.305100847003;23190.42023949415;23249.535009299718;23276.534469310518;23323.766762332158;10058.094771470604;10174.4912754362;10310.432926188982;10318.122959548522;10432.113270372103;9567.808643827084;9617.615295388247;9656.32405731592;9771.657991970247;9790.75523111928;8832.381733278737;8918.464892106365;8979.730608081769;9246.537673116452;9356.92395374527;2582.811906522983;2586.6120081987474;2609.308533238693;2650.6686664166978;2731.9726802732116;929.363386080537;952.5713428956997;956.7129861041799;958.4584709639171;959.3140904253493;18560.81439185618;19141.80858191428;19473.415797526097;19474.22103115888;19720.506987325145;995.865558149655;1001.3591301567003;1006.7885743993843;1011.3830563356347;1012.8126296635146;10373.066424021874;10455.634052808115;10664.093552048007;10855.40295283767;10894.564217431373;465466;467527;478381;490938;491832;110898;113089.43455282836;115281;115738;115790.42104789423;1114.5931734916696;1178.0869825884836;1223.9877601223873;1230.194222784078;1242.2174030360816;20953.685694714288;21006.789932100484;21334.573308534054;21919.78080219241;21922;59791;60402;60817.175484735526;61974;62300.688496557224;79976;80270.5986470064;80394.59802700949;81402;82739.5863020681;19502.122404491547;19972;20163.596728065062;20197.697034544788;20216.292429764886;22361.66457503171;22476.77523224747;22564.548709026058;22623.547529049;22719.88640056789;16777.580560486087;16918.23867925971;17684.646307074043;17688.02715850589;17957.73063404024;15654.765178522559;15875;16281.837181628032;16356;16558.751618725488;17882.28470861151;18421.81578184201;18788.060596969823;18847.340343088475;18850.39771619373;19596.412107636792;19631.509212269808;19848.70226946568;19901;19963.401097967086;18010.279588816826;18055.097245137953;18099.366522171662;18107.909460452614;18216.72674909851 16:22:16 INFO - PID 3835 | |3;mootools.html;1291.9160254583453;1341.336038660863;1349.082623815805;1349.7502961952036;1356.3964036003981;14794.482193123244;15400.614984625376;15402.84597154028;15406;15427.151506667144;15335.923320383383;15352.232388380593;15483.69032619347;15496.225188740575;15497.070175789433;2636.7851005155617;2726.9590956135676;2728.0997270900557;2753.4768394005073;2754.394033312668;2657.3755167535614;2667.66654168229;2685.087070396065;2690.367763575558;2691.93668500942;2656.0969270448045;2701.068131494641;2726.6046423268713;2729.6314997475283;2729.6761070880607;2728.236093893713;2740.082072505713;2741.753242208201;2755.118362124121;2755.5040092783393;2237.765034671352;2309.884505774721;2320.6286994081033;2547.3758929062315;2620.0436840553252;12472.8129078063;14704.191269480174;15113.168775717331;15120.168390738505;15124.546263612;7989.121196668362;8243;8299.377546683963;8309.252167304972;8350.749477515685;5259.3951695555115;5372.898555796052;5473.863153421196;5483.972580137113;5511.421300763443;1895.1566552883971;1942.339604534451;1950.629380417716;1987.6620974434381;1989;26358.73641263582;26448.735512645013;26575.601365979524;26733.86633066822;26736.331591710365;6665.266820649725;6894.079694127411;6975;6980.162380514269;6989.7553585624255;17419.47741567755;17596.38412655551;17663.38178163758;17665.55836104082;17682.292708291523;7413.406927445899;7638.236176382317;7654.387648988068;7773.067231932091;7845.411594130349;7248.492605517563;7257.818554536178;7259.983602295682;7263.237360077116;7309.086364204474;5281.313429254173;5360.222767698662;5418.376886658006;5428.375736790241;5453.618246722771;1471.8748906342867;1486.7769834524909;1499.7450433426343;1518.187769543289;1537.3235776258412;831.3266254334009;863.9438436501607;875.2647775868301;886.2998231397253;893.7542175901549;7288.854222915618;7391.926080739231;7453.142888567776;7476;7521.398288136936;689.2521614048713;699.7655785311978;699.8180473076935;716.9784906452815;724.4204636290946;4949.307097006422;5080.847574572768;5091;5096.362954630671;5167.1474206755465;20099.39701808948;21270.787292127192;21538.676919846213;21736.021879015483;21942.670859937112;21339.35981920545;21528;21578;21720.89139554292;21810.563788724456;10144;10349.172066234683;10736.838947415796;10822;10835.620753273755;8001.559914204775;8158.4289099762445;8159.184081591793;8165.79585510367;8166.469179503313;17848.821511784718;17877.642447150723;18078.09609519545;18154.455366339032;18497.445076647724;18889.338873139375;19005.71491427602;19535.023248837217;19638.410847674593;19650.705239421135;10874.130069594255;11065.225434219527;11239.60661376848;11250.887491124984;11253.887461125283;13829.79255311151;14076.859231407554;14090.154590724589;14150.292485375485;14195.148291102567;10714.25000249975;10807;10993.340399576051;11054.668359949215;11193.720156995814;11098.833517497082;11219.046381057702;11344.602938897098;11382;11616.651500455;8541.273991710526;8717.65129394817;8813.383063185516;8817.382783205114;8821.955890220765;6552.3775241352;6619.470442364503;6622.801315960529;6678.565893216925;6717.596944183365;6662.500312476681;6826.8634627308165;6946.479014074066;7012.333828286304;7135.82160445993 16:22:16 INFO - PID 3835 | |4;prototype.html;496.9701817890927;503.3330836641451;529.1929807044255;531.1793279383352;536.1207619504014;6150.907736383958;6187;6199.357170349856;6224.221972253468;6241.750329986795;6041.879162416749;6086.239220097488;6143.907841382372;6203.844903877405;6213.440790328869;5112.284280200774;5131.897362052775;5145.459726728695;5151.94385151045;5153.742312884369;5168.948310516885;5178.741062946857;5185.55922746567;5199.844004679847;5234.842954711346;4678.368420263272;4820.831270905502;4865.805367785284;5075.644704870642;5172.534471897528;4852.878678033042;4875.658703890729;4879.999999999982;4885.8778530536665;4907.975460122677;4303.33298338758;4319.222539942809;4333.934990975138;4340.956590434087;4351.434313539251;5924.644521328734;5956.910646340309;5970.492508136814;5980.461758441717;5988.041913293852;5510.669359838383;5556.416576259475;5577.776888924479;5596.412376700469;5599.832005039855;6413.262474815409;6473.187507497908;6476.190476190476;6486.610803351767;6529.738810447577;4552.339910712928;4684.273937539687;4710.963588010666;4730.787114579852;4745.857624271277;6554.442872355856;6708.463322934153;6712.630805305706;6781.557520355584;6818.931810681879;4955.35580374549;4957.752112394366;4969.552740253323;4978.77595508203;4981.277714731416;4955.578775804025;4999.800007999712;5011.674241174312;5014.473480284591;5049.924251136236;5587.776488940478;5604.551635869121;5622.273197499209;5622.297212848394;5626.97186514073;6431.163948686641;6492.610443373318;6566.606003639797;6589;6589.86820263592;1722.0872937342979;1728.7234042553382;1733.7226043832927;1737.2529812180883;1743.8168992255885;1147.8048731715628;1201.6755476021426;1212.7877621416217;1221.5968730318973;1236.8824961628632;5512.3660779010925;5533.22534845122;5580.660641446024;5668.404817494104;5671.688057156814;6606.471482281406;6608.669566521655;6646.90029649546;6701.631410272482;6706.329367063255;5567.164925261243;5634.211210430543;5645.661260324394;5659.943400565941;5687.203791469197;3310.0069979006203;3441.3117376524797;3453.2230248193955;3477.3393055319407;3484.8257587121047;6203.7828675997025;6251;6307.810765676945;6338;6425.004124360731;6528.554313099004;6591.209054913441;6680.198376194888;6718.932810671929;6750.189977202669;6285.59143655661;6303.779367722108;6309.148264984168;6363.5545511813725;6395.52033597482;6503.804885853432;6539.365158710322;6543.934560654523;6659.667016649051;6717.395434410926;6505.796427660709;6593.274739778716;6626.469882409492;6635.734570617315;6655.2013758347375;6274.654893980693;6309.242890853127;6381.680915954276;6471.967640161769;6484.967575162094;4909.410870695539;4961.255811628284;4983.800647974185;5020.949790502194;5027.64806463544;6473.2232132144445;6493.837654058498;6616.4045235929;6641.734330626721;6683.198016238082;6148.354422785543;6238.625682459067;6353.14232578596;6437.324080971431;6479.181556023145;6301.590396624205;6321.905171422341;6395.744170233139;6403.711832967434;6449.193850768654;6228.750849965951;6316.210473690789;6337;6364.6181229126405;6368.203974503187;6331.778387756406;6448.129502517288;6448.322926092693;6461.935380646321;6498.74005039812;6162.568620196544;6266.185395898504;6294.968525157345;6308.810735677937;6313.842153946004 16:22:16 INFO - PID 3835 | |5;yui.html;866.7529754020105;886.5921676029026;902.1159263921356;903.6475774447962;915.062061387078;18493.167807448663;19109.235630574796;19128.42614721557;19199.616007679837;19222;18247.178876950537;18549.35077272296;18570.907145464287;18817.153228104733;18835.246590136412;965.8744363232787;987.4420952162042;989.4805227255677;991.538934395506;992.7815880506313;969.9369540979814;973.4013581647307;975.2100798353321;977.291463688827;979.2949076664826;983.8081574093019;1027.6454623155;1028.4292217819107;1047.350642601588;1047.4972013433558;1006.8791744990612;1011.7268337548894;1019.306871327497;1019.6533178719271;1020.7244044108087;743.0897150990048;749.3031480722925;753.3445902065183;753.8153152477623;754.3437209627605;19171.808281917143;20237.898810506;21019.789802101935;21072.789272107388;21132.366029019155;21830.345089647337;21972.121115155376;22292.777072229233;22358.441038973993;22494.88752556243;16483.423080192137;16612.667746645;16763.413280535122;16810.915945420318;16866.66266674672;3776.6601005909324;3780.243951209769;3781.054736315921;3788.5264341957254;3806.809659517013;140736.29631851878;144983;146055;146858;147745.99999999892;17342.653146937242;17455.476335709947;17615.911920440314;17663.911680441517;17733.911330443265;31133.8443307782;31639.84180079131;31904;32777.83611081977;32948.835255824044;8528.104549022388;8564.700235491728;8583.227509524173;8601.913980860236;8652.221300083023;4197.664186865043;4211.178820130045;4233.724807887477;4257.70196086271;4307.987622908614;5208.401033881152;5257.815976440883;5281.841544753664;5302.2046692996355;5346.91979620306;955.6416343871049;968.8934217236099;991.7966816802539;993.9254555908191;996.1532697207316;682.5120039172016;702.4661257444277;709.021550260637;714.5998240985065;721.0770214125822;11260.38067906273;11444.484998174936;11836.881631183578;11920.165588408729;12105.394730263451;643.6331291164086;662.0499583098269;668.5854770042605;672.4317951331118;674.1943377663742;5244.501772331622;5294.867628309246;5348.411674715778;5364.356277246756;5401.540869026177;121818;122084;122768;123734;123983;106747.4662626682;107770;108595.45702271438;110659;112306;93026.53486732658;93267;93309.53345233231;94506.5274673636;94801;9230.846144231971;9291.953540232256;9358.204552613104;9446.29152813528;9647.565859536478;43783.562164377945;44006.11987760291;44196.779016106;44344.55655443533;45305.77347113243;53257.93484130373;55562.44437555573;56231.15653265286;56343;56526.71736641291;15287.159206243763;15335.233238338262;15357.923210383877;15359.155246461552;15527.146006969726;17139.914300428918;17284;17285.56786080358;17452.73820892662;17534.298628054734;14186.148831070168;14350.21073840907;14410.351534181196;14550.054246473945;14591.781123282946;14339.354729037426;14440.927795360956;14448.27758612044;14494.710105798036;14667.486637968046;14911.03078299907;14911.701765964837;15227.543173704807;15234.695306094038;15438.45965391206;10762.784744304914;10914.836277456003;11021.118310535297;11110.277831940897;11160;11523.769524609628;11632.651020469399;11795.35125568102;11868.643940681794;12115.212511186744 16:22:16 INFO - PID 3835 | __end_tp_report 16:22:16 INFO - PID 3835 | __start_cc_report 16:22:16 INFO - PID 3835 | _x_x_mozilla_cycle_collect,380 16:22:16 INFO - PID 3835 | __end_cc_report 16:22:16 INFO - PID 3835 | __startTimestamp1505344936008__endTimestamp 16:22:16 INFO - PID 3835 | 16:22:16 INFO - PID 3835 | ------- Summary: start ------- 16:22:16 INFO - PID 3835 | Number of tests: 6 16:22:16 INFO - PID 3835 | 16:22:16 INFO - PID 3835 | [#0] dojo.html Cycles:165 Average:6164.41 Median:6399.14 stddev:740.43 (11.6%) stddev-sans-first:699.37 16:22:16 INFO - PID 3835 | Values: 2982.7 3110.8 3130.4 3141.8 3160.7 6266.0 6314.7 6350.3 6392.6 6394.2 6135.1 6235.2 6249.5 6268.1 6290.3 5729.0 5755.9 5821.7 5821.7 5835.1 5689.1 5805.8 5849.9 5852.5 5904.6 5649.1 5739.4 5746.8 5779.1 5810.2 5786.7 5803.2 5827.9 5877.8 5889.1 5628.2 5675.4 5720.5 5800.9 5813.2 6172.2 6225.2 6233.6 6289.5 6297.9 6646.0 6679.5 6681.5 6688.7 6788.1 6599.6 6643.8 6699.2 6786.5 6804.6 6160.5 6168.2 6194.4 6210.8 6232.1 6753.1 6803.1 6814.7 6829.8 6846.2 6464.9 6474.8 6531.8 6543.6 6579.0 6596.5 6603.5 6675.4 6685.6 6687.7 6484.9 6580.0 6618.0 6635.1 6718.6 6684.6 6774.0 6783.5 6786.7 6797.3 5193.8 5241.7 5248.0 5252.6 5267.3 4504.7 4516.5 4549.0 4580.6 4596.6 5665.9 5770.4 5797.8 5807.5 5822.2 6587.0 6693.1 6781.2 6781.6 6836.9 5820.2 5825.8 5876.5 5884.8 5916.0 5830.6 5831.1 5893.3 5893.8 5895.6 6217.3 6375.6 6383.5 6419.4 6545.8 6661.2 6702.1 6717.0 6732.5 6752.9 6305.6 6383.2 6387.0 6489.8 6492.9 6565.9 6653.8 6682.7 6682.7 6688.3 6684.6 6704.0 6737.5 6779.6 6818.5 6455.1 6477.9 6499.7 6556.8 6586.0 6499.9 6504.4 6523.7 6529.7 6539.0 6413.7 6463.1 6471.5 6599.5 6617.3 6605.5 6704.1 6719.0 6721.5 6742.6 6391.4 6399.1 6410.4 6488.8 6493.2 16:22:16 INFO - PID 3835 | 16:22:16 INFO - PID 3835 | [#1] ext.html Cycles:180 Average:36855.26 Median:35294.56 stddev:51877.45 (147.0%) stddev-sans-first:51952.38 16:22:16 INFO - PID 3835 | Values: 811.0 821.1 826.0 838.4 839.6 40777.2 41599.2 41630.0 41728.4 41734.2 38204.8 38511.4 38568.6 38617.6 38647.2 6993.2 7105.3 7110.3 7110.8 7112.1 6981.9 7046.8 7076.7 7077.5 7087.1 7022.0 7649.7 7864.6 7878.3 7884.3 7749.3 7818.3 7822.1 7822.3 7830.5 7468.4 7771.9 7851.1 7854.9 7863.3 38309.2 38702.6 38724.6 38792.4 38806.8 3551.5 3596.4 3598.9 3602.0 3606.0 31644.4 31704.8 31737.0 31759.5 31770.4 5226.2 5226.7 5242.2 5250.2 5299.5 186763.0 187581.0 188536.0 188790.0 189014.1 42559.6 43366.1 48487.3 48787.5 48824.3 52004.5 52052.5 52055.0 52167.7 52200.0 16142.4 16266.5 16292.6 16304.4 16337.5 13324.7 13377.8 13445.7 13469.9 13477.6 13723.9 13916.8 14013.6 14061.7 14068.2 5543.6 5826.4 5850.5 5857.2 5878.9 1017.3 1019.8 1021.6 1026.2 1036.4 22957.9 23010.2 23233.9 23457.8 23467.8 1106.5 1112.4 1125.6 1131.9 1135.7 12022.8 12057.8 12079.8 12166.9 12188.6 218491.0 219755.0 220932.0 223473.0 223515.0 52771.2 53018.5 53129.0 53208.7 53576.0 1423.7 1442.9 1458.0 1467.5 1473.4 22266.3 22698.9 22802.4 22865.0 22981.5 37480.3 37651.0 37729.4 37735.4 37836.2 188766.0 188880.0 190494.0 190787.0 191295.0 30452.4 32868.7 34602.3 34636.3 35208.8 43429.6 43591.0 43659.6 44079.0 44406.3 31077.8 31602.0 31643.4 31894.5 31988.7 31160.1 31288.5 31351.0 31754.2 31841.0 34124.0 35380.3 35398.1 35431.8 35889.8 30032.8 30076.7 30398.0 30553.7 31347.4 22779.8 23018.8 23506.9 23579.8 23586.3 16:22:16 INFO - PID 3835 | 16:22:16 INFO - PID 3835 | [#2] jquery.html Cycles:180 Average:34853.58 Median:22802.83 stddev:80694.08 (353.9%) stddev-sans-first:80879.37 16:22:16 INFO - PID 3835 | Values: 560.3 561.7 570.9 576.3 596.8 22386.0 23105.3 23107.4 23138.2 23192.9 22476.4 22885.8 23206.2 23945.2 24267.8 6392.6 6490.4 6517.4 6538.9 6566.2 6413.5 6515.3 6517.8 6522.0 6522.0 6228.7 6533.7 6564.0 6569.0 6573.8 6546.2 6567.4 6603.0 6607.2 6609.8 5989.1 6057.1 6103.0 6111.8 6127.8 29192.6 29511.3 29878.9 29883.4 29885.4 2003.2 2021.5 2030.2 2031.1 2032.1 19383.6 19602.1 19919.3 19928.2 19959.1 2418.7 2430.4 2466.7 2476.2 2483.7 114129.0 119067.0 132039.0 139751.0 144813.0 14677.8 14826.4 14871.8 14908.8 14957.5 23163.3 23190.4 23249.5 23276.5 23323.8 10058.1 10174.5 10310.4 10318.1 10432.1 9567.8 9617.6 9656.3 9771.7 9790.8 8832.4 8918.5 8979.7 9246.5 9356.9 2582.8 2586.6 2609.3 2650.7 2732.0 929.4 952.6 956.7 958.5 959.3 18560.8 19141.8 19473.4 19474.2 19720.5 995.9 1001.4 1006.8 1011.4 1012.8 10373.1 10455.6 10664.1 10855.4 10894.6 465466.0 467527.0 478381.0 490938.0 491832.0 110898.0 113089.4 115281.0 115738.0 115790.4 1114.6 1178.1 1224.0 1230.2 1242.2 20953.7 21006.8 21334.6 21919.8 21922.0 59791.0 60402.0 60817.2 61974.0 62300.7 79976.0 80270.6 80394.6 81402.0 82739.6 19502.1 19972.0 20163.6 20197.7 20216.3 22361.7 22476.8 22564.5 22623.5 22719.9 16777.6 16918.2 17684.6 17688.0 17957.7 15654.8 15875.0 16281.8 16356.0 16558.8 17882.3 18421.8 18788.1 18847.3 18850.4 19596.4 19631.5 19848.7 19901.0 19963.4 18010.3 18055.1 18099.4 18107.9 18216.7 16:22:16 INFO - PID 3835 | 16:22:16 INFO - PID 3835 | [#3] mootools.html Cycles:180 Average:9201.58 Median:14621.63 stddev:6689.30 (45.7%) stddev-sans-first:6681.66 16:22:16 INFO - PID 3835 | Values: 1291.9 1341.3 1349.1 1349.8 1356.4 14794.5 15400.6 15402.8 15406.0 15427.2 15335.9 15352.2 15483.7 15496.2 15497.1 2636.8 2727.0 2728.1 2753.5 2754.4 2657.4 2667.7 2685.1 2690.4 2691.9 2656.1 2701.1 2726.6 2729.6 2729.7 2728.2 2740.1 2741.8 2755.1 2755.5 2237.8 2309.9 2320.6 2547.4 2620.0 12472.8 14704.2 15113.2 15120.2 15124.5 7989.1 8243.0 8299.4 8309.3 8350.7 5259.4 5372.9 5473.9 5484.0 5511.4 1895.2 1942.3 1950.6 1987.7 1989.0 26358.7 26448.7 26575.6 26733.9 26736.3 6665.3 6894.1 6975.0 6980.2 6989.8 17419.5 17596.4 17663.4 17665.6 17682.3 7413.4 7638.2 7654.4 7773.1 7845.4 7248.5 7257.8 7260.0 7263.2 7309.1 5281.3 5360.2 5418.4 5428.4 5453.6 1471.9 1486.8 1499.7 1518.2 1537.3 831.3 863.9 875.3 886.3 893.8 7288.9 7391.9 7453.1 7476.0 7521.4 689.3 699.8 699.8 717.0 724.4 4949.3 5080.8 5091.0 5096.4 5167.1 20099.4 21270.8 21538.7 21736.0 21942.7 21339.4 21528.0 21578.0 21720.9 21810.6 10144.0 10349.2 10736.8 10822.0 10835.6 8001.6 8158.4 8159.2 8165.8 8166.5 17848.8 17877.6 18078.1 18154.5 18497.4 18889.3 19005.7 19535.0 19638.4 19650.7 10874.1 11065.2 11239.6 11250.9 11253.9 13829.8 14076.9 14090.2 14150.3 14195.1 10714.3 10807.0 10993.3 11054.7 11193.7 11098.8 11219.0 11344.6 11382.0 11616.7 8541.3 8717.7 8813.4 8817.4 8822.0 6552.4 6619.5 6622.8 6678.6 6717.6 6662.5 6826.9 6946.5 7012.3 7135.8 16:22:16 INFO - PID 3835 | 16:22:16 INFO - PID 3835 | [#4] prototype.html Cycles:180 Average:5424.41 Median:6064.06 stddev:1513.93 (25.0%) stddev-sans-first:1472.31 16:22:16 INFO - PID 3835 | Values: 497.0 503.3 529.2 531.2 536.1 6150.9 6187.0 6199.4 6224.2 6241.8 6041.9 6086.2 6143.9 6203.8 6213.4 5112.3 5131.9 5145.5 5151.9 5153.7 5168.9 5178.7 5185.6 5199.8 5234.8 4678.4 4820.8 4865.8 5075.6 5172.5 4852.9 4875.7 4880.0 4885.9 4908.0 4303.3 4319.2 4333.9 4341.0 4351.4 5924.6 5956.9 5970.5 5980.5 5988.0 5510.7 5556.4 5577.8 5596.4 5599.8 6413.3 6473.2 6476.2 6486.6 6529.7 4552.3 4684.3 4711.0 4730.8 4745.9 6554.4 6708.5 6712.6 6781.6 6818.9 4955.4 4957.8 4969.6 4978.8 4981.3 4955.6 4999.8 5011.7 5014.5 5049.9 5587.8 5604.6 5622.3 5622.3 5627.0 6431.2 6492.6 6566.6 6589.0 6589.9 1722.1 1728.7 1733.7 1737.3 1743.8 1147.8 1201.7 1212.8 1221.6 1236.9 5512.4 5533.2 5580.7 5668.4 5671.7 6606.5 6608.7 6646.9 6701.6 6706.3 5567.2 5634.2 5645.7 5659.9 5687.2 3310.0 3441.3 3453.2 3477.3 3484.8 6203.8 6251.0 6307.8 6338.0 6425.0 6528.6 6591.2 6680.2 6718.9 6750.2 6285.6 6303.8 6309.1 6363.6 6395.5 6503.8 6539.4 6543.9 6659.7 6717.4 6505.8 6593.3 6626.5 6635.7 6655.2 6274.7 6309.2 6381.7 6472.0 6485.0 4909.4 4961.3 4983.8 5020.9 5027.6 6473.2 6493.8 6616.4 6641.7 6683.2 6148.4 6238.6 6353.1 6437.3 6479.2 6301.6 6321.9 6395.7 6403.7 6449.2 6228.8 6316.2 6337.0 6364.6 6368.2 6331.8 6448.1 6448.3 6461.9 6498.7 6162.6 6266.2 6295.0 6308.8 6313.8 16:22:16 INFO - PID 3835 | 16:22:16 INFO - PID 3835 | [#5] yui.html Cycles:180 Average:24267.53 Median:22132.45 stddev:35796.10 (161.7%) stddev-sans-first:35853.39 16:22:16 INFO - PID 3835 | Values: 866.8 886.6 902.1 903.6 915.1 18493.2 19109.2 19128.4 19199.6 19222.0 18247.2 18549.4 18570.9 18817.2 18835.2 965.9 987.4 989.5 991.5 992.8 969.9 973.4 975.2 977.3 979.3 983.8 1027.6 1028.4 1047.4 1047.5 1006.9 1011.7 1019.3 1019.7 1020.7 743.1 749.3 753.3 753.8 754.3 19171.8 20237.9 21019.8 21072.8 21132.4 21830.3 21972.1 22292.8 22358.4 22494.9 16483.4 16612.7 16763.4 16810.9 16866.7 3776.7 3780.2 3781.1 3788.5 3806.8 140736.3 144983.0 146055.0 146858.0 147746.0 17342.7 17455.5 17615.9 17663.9 17733.9 31133.8 31639.8 31904.0 32777.8 32948.8 8528.1 8564.7 8583.2 8601.9 8652.2 4197.7 4211.2 4233.7 4257.7 4308.0 5208.4 5257.8 5281.8 5302.2 5346.9 955.6 968.9 991.8 993.9 996.2 682.5 702.5 709.0 714.6 721.1 11260.4 11444.5 11836.9 11920.2 12105.4 643.6 662.0 668.6 672.4 674.2 5244.5 5294.9 5348.4 5364.4 5401.5 121818.0 122084.0 122768.0 123734.0 123983.0 106747.5 107770.0 108595.5 110659.0 112306.0 93026.5 93267.0 93309.5 94506.5 94801.0 9230.8 9292.0 9358.2 9446.3 9647.6 43783.6 44006.1 44196.8 44344.6 45305.8 53257.9 55562.4 56231.2 56343.0 56526.7 15287.2 15335.2 15357.9 15359.2 15527.1 17139.9 17284.0 17285.6 17452.7 17534.3 14186.1 14350.2 14410.4 14550.1 14591.8 14339.4 14440.9 14448.3 14494.7 14667.5 14911.0 14911.7 15227.5 15234.7 15438.5 10762.8 10914.8 11021.1 11110.3 11160.0 11523.8 11632.7 11795.4 11868.6 12115.2 16:22:16 INFO - PID 3835 | -------- Summary: end -------- 16:22:16 INFO - PID 3835 | 16:22:17 INFO - TEST-INFO | 3835: exit 0 16:22:17 INFO - TEST-OK | dromaeo_css | took 1116063ms 16:22:17 INFO - TEST-START | kraken 16:22:17 INFO - Initialising browser for kraken test... 16:22:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44964/getInfo.html -profile /tmp/tmpYmXdMl/profile 16:22:17 INFO - TEST-INFO | started process 4221 (/builds/slave/test/build/application/firefox/firefox http://localhost:44964/getInfo.html) 16:22:23 INFO - TEST-INFO | 4221: exit 0 16:22:23 INFO - Browser initialized. 16:22:23 INFO - Running cycle 1/1 for kraken test... 16:22:23 INFO - Using env: {'DISPLAY': ':0', 16:22:23 INFO - 'HOME': '/home/cltbld', 16:22:23 INFO - 'JSGC_DISABLE_POISONING': '1', 16:22:23 INFO - 'LANG': 'en_US.UTF-8', 16:22:23 INFO - 'LANGUAGE': 'en_US:en', 16:22:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:22:23 INFO - 'LOGNAME': 'cltbld', 16:22:23 INFO - 'MAIL': '/var/mail/cltbld', 16:22:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:22:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:22:23 INFO - 'MOZ_ACCELERATED': '1', 16:22:23 INFO - 'MOZ_CRASHREPORTER': '1', 16:22:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:22:23 INFO - 'MOZ_NO_REMOTE': '1', 16:22:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:22:23 INFO - 'MOZ_WEBRENDER': '1', 16:22:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:23 INFO - 'NO_EM_RESTART': '1', 16:22:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:22:23 INFO - 'PWD': '/builds/slave/test', 16:22:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:22:23 INFO - 'RUST_BACKTRACE': 'full', 16:22:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:22:23 INFO - 'SHELL': '/bin/bash', 16:22:23 INFO - 'SHLVL': '1', 16:22:23 INFO - 'TERM': 'linux', 16:22:23 INFO - 'TMOUT': '86400', 16:22:23 INFO - 'USER': 'cltbld', 16:22:23 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953', 16:22:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:23 INFO - '_': '/tools/buildbot/bin/python'} 16:22:23 INFO - TEST-INFO | started process 4462 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYmXdMl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:22:24 INFO - PID 4462 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:22:24 INFO - PID 4462 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 16:22:24 INFO - PID 4462 | 16:22:24 INFO - PID 4462 | (/builds/slave/test/build/application/firefox/firefox:4521): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:22:24 INFO - PID 4462 | 16:22:35 INFO - PID 4462 | RSS: Main: 219766784 16:22:35 INFO - PID 4462 | 16:22:37 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44964/tests/kraken/driver.html?testName=audio-beat-detection) 16:22:38 INFO - PID 4462 | RSS: Main: 225894400 16:22:38 INFO - PID 4462 | 16:22:41 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44964/tests/kraken/driver.html?testName=audio-dft) 16:22:42 INFO - PID 4462 | RSS: Main: 226643968 16:22:42 INFO - PID 4462 | 16:22:45 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44964/tests/kraken/driver.html?testName=audio-fft) 16:22:45 INFO - PID 4462 | RSS: Main: 221331456 16:22:45 INFO - PID 4462 | 16:22:48 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44964/tests/kraken/driver.html?testName=audio-oscillator) 16:22:48 INFO - PID 4462 | RSS: Main: 221458432 16:22:48 INFO - PID 4462 | 16:22:50 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44964/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:22:51 INFO - PID 4462 | RSS: Main: 221302784 16:22:51 INFO - PID 4462 | 16:22:54 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44964/tests/kraken/driver.html?testName=imaging-darkroom) 16:22:55 INFO - PID 4462 | RSS: Main: 224501760 16:22:55 INFO - PID 4462 | 16:22:59 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44964/tests/kraken/driver.html?testName=imaging-desaturate) 16:22:59 INFO - PID 4462 | RSS: Main: 224923648 16:22:59 INFO - PID 4462 | 16:23:02 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44964/tests/kraken/driver.html?testName=json-parse-financial) 16:23:03 INFO - PID 4462 | RSS: Main: 225488896 16:23:03 INFO - PID 4462 | 16:23:05 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44964/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:23:05 INFO - PID 4462 | RSS: Main: 226099200 16:23:05 INFO - PID 4462 | 16:23:07 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44964/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:23:07 INFO - PID 4462 | RSS: Main: 225734656 16:23:07 INFO - PID 4462 | 16:23:10 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44964/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:23:10 INFO - PID 4462 | RSS: Main: 222486528 16:23:10 INFO - PID 4462 | 16:23:13 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44964/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:23:14 INFO - PID 4462 | RSS: Main: 221888512 16:23:14 INFO - PID 4462 | 16:23:17 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44964/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:23:17 INFO - PID 4462 | RSS: Main: 223186944 16:23:17 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | Cycle 1(1): loaded http://localhost:44964/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44964/tests/kraken/driver.html?testName=ai-astar) 16:23:19 INFO - PID 4462 | RSS: Main: 224534528 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | __start_tp_report 16:23:19 INFO - PID 4462 | _x_x_mozilla_page_load 16:23:19 INFO - PID 4462 | _x_x_mozilla_page_load_details 16:23:19 INFO - PID 4462 | |i|pagename|runs| 16:23:19 INFO - PID 4462 | |0;ai-astar;112;109;119;109;113;119;116;109;121;114 16:23:19 INFO - PID 4462 | |1;audio-beat-detection;118;122;132;142;127;131;125;142;119;122 16:23:19 INFO - PID 4462 | |2;audio-dft;162;172;170;163;168;169;167;169;166;161 16:23:19 INFO - PID 4462 | |3;audio-fft;78;88;91;80;75;75;77;75;73;82 16:23:19 INFO - PID 4462 | |4;audio-oscillator;84;81;81;86;81;85;84;85;84;83 16:23:19 INFO - PID 4462 | |5;imaging-gaussian-blur;116;115;115;114;115;115;115;127;116;118 16:23:19 INFO - PID 4462 | |6;imaging-darkroom;153;152;149;148;154;156;148;150;149;149 16:23:19 INFO - PID 4462 | |7;imaging-desaturate;95;95;94;101;100;95;95;95;95;102 16:23:19 INFO - PID 4462 | |8;json-parse-financial;52;55;58;53;54;55;60;54;56;54 16:23:19 INFO - PID 4462 | |9;json-stringify-tinderbox;37;38;38;42;39;42;38;43;50;39 16:23:19 INFO - PID 4462 | |10;stanford-crypto-aes;77;86;74;81;80;74;75;80;75;80 16:23:19 INFO - PID 4462 | |11;stanford-crypto-ccm;116;113;121;115;112;126;120;115;108;115 16:23:19 INFO - PID 4462 | |12;stanford-crypto-pbkdf2;171;181;175;167;168;175;169;170;177;179 16:23:19 INFO - PID 4462 | |13;stanford-crypto-sha256-iterative;65;64;64;65;69;77;64;68;77;69 16:23:19 INFO - PID 4462 | __end_tp_report 16:23:19 INFO - PID 4462 | __start_cc_report 16:23:19 INFO - PID 4462 | _x_x_mozilla_cycle_collect,940 16:23:19 INFO - PID 4462 | __end_cc_report 16:23:19 INFO - PID 4462 | __startTimestamp1505344999948__endTimestamp 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | ------- Summary: start ------- 16:23:19 INFO - PID 4462 | Number of tests: 14 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:4.51 (3.9%) stddev-sans-first:4.72 16:23:19 INFO - PID 4462 | Values: 112.0 109.0 119.0 109.0 113.0 119.0 116.0 109.0 121.0 114.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:129.00 stddev:8.69 (6.7%) stddev-sans-first:8.43 16:23:19 INFO - PID 4462 | Values: 118.0 122.0 132.0 142.0 127.0 131.0 125.0 142.0 119.0 122.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#2] audio-dft Cycles:10 Average:166.70 Median:168.50 stddev:3.65 (2.2%) stddev-sans-first:3.46 16:23:19 INFO - PID 4462 | Values: 162.0 172.0 170.0 163.0 168.0 169.0 167.0 169.0 166.0 161.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#3] audio-fft Cycles:10 Average:79.40 Median:79.00 stddev:5.99 (7.6%) stddev-sans-first:6.33 16:23:19 INFO - PID 4462 | Values: 78.0 88.0 91.0 80.0 75.0 75.0 77.0 75.0 73.0 82.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#4] audio-oscillator Cycles:10 Average:83.40 Median:84.00 stddev:1.84 (2.2%) stddev-sans-first:1.94 16:23:19 INFO - PID 4462 | Values: 84.0 81.0 81.0 86.0 81.0 85.0 84.0 85.0 84.0 83.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:115.50 stddev:3.81 (3.3%) stddev-sans-first:4.03 16:23:19 INFO - PID 4462 | Values: 116.0 115.0 115.0 114.0 115.0 115.0 115.0 127.0 116.0 118.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:151.00 stddev:2.78 (1.8%) stddev-sans-first:2.83 16:23:19 INFO - PID 4462 | Values: 153.0 152.0 149.0 148.0 154.0 156.0 148.0 150.0 149.0 149.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:95.00 stddev:3.02 (3.2%) stddev-sans-first:3.14 16:23:19 INFO - PID 4462 | Values: 95.0 95.0 94.0 101.0 100.0 95.0 95.0 95.0 95.0 102.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:2.38 (4.3%) stddev-sans-first:2.24 16:23:19 INFO - PID 4462 | Values: 52.0 55.0 58.0 53.0 54.0 55.0 60.0 54.0 56.0 54.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#9] json-stringify-tinderbox Cycles:10 Average:40.60 Median:40.50 stddev:3.89 (9.6%) stddev-sans-first:3.91 16:23:19 INFO - PID 4462 | Values: 37.0 38.0 38.0 42.0 39.0 42.0 38.0 43.0 50.0 39.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#10] stanford-crypto-aes Cycles:10 Average:78.20 Median:80.00 stddev:3.88 (4.9%) stddev-sans-first:4.09 16:23:19 INFO - PID 4462 | Values: 77.0 86.0 74.0 81.0 80.0 74.0 75.0 80.0 75.0 80.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#11] stanford-crypto-ccm Cycles:10 Average:116.10 Median:115.50 stddev:5.09 (4.4%) stddev-sans-first:5.40 16:23:19 INFO - PID 4462 | Values: 116.0 113.0 121.0 115.0 112.0 126.0 120.0 115.0 108.0 115.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.20 Median:175.00 stddev:4.87 (2.8%) stddev-sans-first:5.10 16:23:19 INFO - PID 4462 | Values: 171.0 181.0 175.0 167.0 168.0 175.0 169.0 170.0 177.0 179.0 16:23:19 INFO - PID 4462 | 16:23:19 INFO - PID 4462 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.50 stddev:5.05 (7.4%) stddev-sans-first:5.22 16:23:19 INFO - PID 4462 | Values: 65.0 64.0 64.0 65.0 69.0 77.0 64.0 68.0 77.0 69.0 16:23:19 INFO - PID 4462 | -------- Summary: end -------- 16:23:19 INFO - PID 4462 | 16:23:20 INFO - PID 4462 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:23:20 INFO - TEST-INFO | 4462: exit 0 16:23:21 INFO - TEST-OK | kraken | took 64014ms 16:23:21 INFO - SUITE-END | took 1180s 16:23:21 INFO - Completed test suite (00:19:40) 16:23:21 INFO - javascript benchmark 16:23:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8434.604058321842, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2982.6719060903283, 3110.813351198928, 3130.358276553307, 3141.8271995040272, 3160.6997335253145, 6266.028765541343, 6314.652694101823, 6350.269718982318, 6392.552521323498, 6394.200724909387, 6135.079738039296, 6235.220597425322, 6249.53128515362, 6268.059791031347, 6290.339514350984, 5728.99999999999, 5755.884882302352, 5821.6507009579345, 5821.67980761058, 5835.124731290319, 5689.118186681071, 5805.796797112102, 5849.85375365615, 5852.502537284336, 5904.645721256738, 5649.1243857202, 5739.426057394269, 5746.770129194827, 5779.104238842986, 5810.1865738796605, 5786.71066446676, 5803.158542011427, 5827.883442331172, 5877.823665290048, 5889.116632505115, 5628.212050312959, 5675.3757086720425, 5720.513756330717, 5800.854978625526, 5813.215215945877, 6172.22847144107, 6225.159603453521, 6233.625982441068, 6289.465395441393, 6297.937020629826, 6645.9667701661665, 6679.532432729711, 6681.498887583451, 6688.699008544627, 6788.08360871281, 6599.6370199639005, 6643.767468138641, 6699.196096468406, 6786.491013174032, 6804.6257455839905, 6160.507159427235, 6168.167297414837, 6194.4425001749605, 6210.844728881814, 6232.127502149658, 6753.1220941277315, 6803.115594972622, 6814.693338799766, 6829.795106146824, 6846.178458585002, 6464.903026454607, 6474.838129046811, 6531.771388001493, 6543.640099794462, 6579.0, 6596.538242322991, 6603.504737144734, 6675.365840245266, 6685.6322902240845, 6687.732490700318, 6484.902726459107, 6580.045893345438, 6617.9669101655145, 6635.137432133888, 6718.630475323904, 6684.632345220963, 6773.966130169416, 6783.457323414115, 6786.728530858711, 6797.320267973262, 5193.844184674465, 5241.711705856214, 5248.0, 5252.5535329497425, 5267.288915996368, 4504.684672072989, 4516.548345165457, 4549.0, 4580.564846339593, 4596.6092882105395, 5665.886682266414, 5770.42295770428, 5797.826065218051, 5807.477327040587, 5822.214001109796, 6587.0, 6693.1298931138645, 6781.220159681601, 6781.62701051437, 6836.897446538306, 5820.214271073351, 5825.825225243249, 5876.529877609781, 5884.7940322088525, 5915.970420147958, 5830.591858569945, 5831.067029275296, 5893.263342082239, 5893.823185304448, 5895.616784908967, 6217.347178546278, 6375.6174629522375, 6383.457406120532, 6419.390157934989, 6545.8363540910905, 6661.233958094881, 6702.128723266042, 6716.966415167893, 6732.495062870206, 6752.93247067523, 6305.5901366411035, 6383.234011918599, 6386.9680651596445, 6489.837754056186, 6492.902606461002, 6565.934340656723, 6653.833654158685, 6682.665866706548, 6682.69927853238, 6688.2977287386075, 6684.63234522106, 6703.994400839913, 6737.461003119641, 6779.559328643622, 6818.522703410714, 6455.0640157176, 6477.870442591027, 6499.707513161825, 6556.803295901131, 6586.045023471665, 6499.935000650122, 6504.447121994494, 6523.67381630907, 6529.7061632227615, 6539.0, 6413.7113829876835, 6463.095166676763, 6471.482281417381, 6599.472042236705, 6617.2721000688925, 6605.471562275103, 6704.06143139951, 6719.0, 6721.529492935448, 6742.561733487504, 6391.392817682313, 6399.136116624382, 6410.391012853772, 6488.772892948724, 6493.188351456068], "value": 6107.119522692227, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [810.9716159934403, 821.1337039423413, 825.950442973422, 838.4466252273501, 839.576014112873, 40777.18445631089, 41599.16801663965, 41629.9592510187, 41728.374074388834, 41734.16531669369, 38204.80897595515, 38511.422328665096, 38568.61431385685, 38617.61382386175, 38647.22705545894, 6993.195782485016, 7105.289471052879, 7110.288971102874, 7110.786676399691, 7112.075430194068, 6981.930180698204, 7046.788596342118, 7076.681549330293, 7077.469189810759, 7087.114110736158, 7022.0, 7649.6940122395035, 7864.646090925922, 7878.290953814156, 7884.290413862751, 7749.30256276935, 7818.296353328228, 7822.139564647884, 7822.256885595859, 7830.451868369217, 7468.4025277977635, 7771.883421748678, 7851.136374998746, 7854.882176767296, 7863.252990965849, 38309.23381532354, 38702.612973870186, 38724.61275387238, 38792.418113728316, 38806.80596597027, 3551.5383000210068, 3596.3706351388664, 3598.892033239007, 3601.981990090059, 3606.04439823447, 31644.36711265785, 31704.841475792706, 31737.0, 31759.52360714591, 31770.364592708247, 5226.189940559219, 5226.686398816083, 5242.2136679498, 5250.238715386286, 5299.523042926116, 186763.0, 187581.0, 188536.0, 188790.0, 189014.10985890104, 42559.57440425618, 43366.13267734628, 48487.27269090967, 48787.51212487901, 48824.26763598549, 52004.47995519996, 52052.47947520476, 52055.0, 52167.73916130395, 52200.0, 16142.35430582787, 16266.51200463988, 16292.592685182964, 16304.429344972868, 16337.509874703777, 13324.73350532984, 13377.799333010013, 13445.731085378238, 13469.932650336686, 13477.595672129655, 13723.931380343034, 13916.791248131287, 14013.579592612034, 14061.718765624835, 14068.226247556278, 5543.556515478752, 5826.359100498942, 5850.531957443394, 5857.20927674767, 5878.853028674317, 1017.2980643356061, 1019.7654539455966, 1021.560728886571, 1026.1739299863682, 1036.3729943384294, 22957.88521057384, 23010.19464318741, 23233.88383058074, 23457.7654223459, 23467.7653223469, 1106.4965440724452, 1112.4159816096615, 1125.6060378867494, 1131.9094472442184, 1135.6649788312468, 12022.819657705142, 12057.758844823229, 12079.75840483203, 12166.878331216574, 12188.634340969786, 218491.0, 219755.0, 220932.0, 223473.0, 223515.0, 52771.20843187355, 53018.46981530212, 53129.0, 53208.73395632997, 53576.0, 1423.7366087273888, 1442.8845692344587, 1458.0158393084625, 1467.4717101843328, 1473.395907677847, 22266.332010039725, 22698.886505568025, 22802.42993925165, 22865.0, 22981.54036919219, 37480.250394992494, 37651.0, 37729.434058488594, 37735.433968491045, 37836.2432751349, 188766.0, 188880.0, 190494.0, 190787.0, 191295.0, 30452.390952181275, 32868.67131328656, 34602.30795384028, 34636.30727385489, 35208.823955880056, 43429.56570434382, 43591.0, 43659.56340436555, 44079.0, 44406.3339049908, 31077.844610776803, 31602.0, 31643.367132657677, 31894.52158217582, 31988.6801131995, 31160.065198044096, 31288.530672039484, 31351.0, 31754.206144846565, 31841.0, 34124.0, 35380.292394152486, 35398.115047124025, 35431.82284088563, 35889.82055089708, 30032.84983575068, 30076.699233007388, 30398.0, 30553.694463055086, 31347.373052538365, 22779.77220227843, 23018.769812301663, 23506.88246558756, 23579.82100894914, 23586.292411227692], "value": 16976.572081624126, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.266051472571, 561.7415988645223, 570.9200711900332, 576.2623841482902, 596.7791916990714, 22386.0, 23105.30684079476, 23107.422314442127, 23138.190163344287, 23192.884035579842, 22476.43808904778, 22885.771142288573, 23206.187783427584, 23945.161919332826, 24267.75732242677, 6392.61644301341, 6490.350964903496, 6517.413432791048, 6538.8692226155445, 6566.179227596551, 6413.518986076039, 6515.31589183136, 6517.804465866032, 6521.967390163042, 6522.021696745479, 6228.688565571726, 6533.673316334188, 6563.9671801640925, 6569.047488114221, 6573.80278591643, 6546.247181574132, 6567.441767449749, 6603.042558828991, 6607.240167380764, 6609.834754131137, 5989.13157592151, 6057.061155520902, 6103.023516237381, 6111.755529778803, 6127.846803829895, 29192.562111568343, 29511.262218444495, 29878.85060574705, 29883.402331953457, 29885.402291954255, 2003.1987205118069, 2021.5249416387137, 2030.218365929128, 2031.0860112949085, 2032.0754056904073, 19383.612327753508, 19602.11790469432, 19919.302824401075, 19928.202871885107, 19959.101840417217, 2418.697662792151, 2430.3559556717482, 2466.703995520549, 2476.182859656309, 2483.7019557653016, 114129.0, 119067.0, 132039.0, 139751.0, 144813.0, 14677.77983330251, 14826.406943722346, 14871.764129924375, 14908.776368354484, 14957.476488322856, 23163.305100847003, 23190.42023949415, 23249.535009299718, 23276.534469310518, 23323.766762332158, 10058.094771470604, 10174.4912754362, 10310.432926188982, 10318.122959548522, 10432.113270372103, 9567.808643827084, 9617.615295388247, 9656.32405731592, 9771.657991970247, 9790.75523111928, 8832.381733278737, 8918.464892106365, 8979.730608081769, 9246.537673116452, 9356.92395374527, 2582.811906522983, 2586.6120081987474, 2609.308533238693, 2650.6686664166978, 2731.9726802732116, 929.363386080537, 952.5713428956997, 956.7129861041799, 958.4584709639171, 959.3140904253493, 18560.81439185618, 19141.80858191428, 19473.415797526097, 19474.22103115888, 19720.506987325145, 995.865558149655, 1001.3591301567003, 1006.7885743993843, 1011.3830563356347, 1012.8126296635146, 10373.066424021874, 10455.634052808115, 10664.093552048007, 10855.40295283767, 10894.564217431373, 465466.0, 467527.0, 478381.0, 490938.0, 491832.0, 110898.0, 113089.43455282836, 115281.0, 115738.0, 115790.42104789423, 1114.5931734916696, 1178.0869825884836, 1223.9877601223873, 1230.194222784078, 1242.2174030360816, 20953.685694714288, 21006.789932100484, 21334.573308534054, 21919.78080219241, 21922.0, 59791.0, 60402.0, 60817.175484735526, 61974.0, 62300.688496557224, 79976.0, 80270.5986470064, 80394.59802700949, 81402.0, 82739.5863020681, 19502.122404491547, 19972.0, 20163.596728065062, 20197.697034544788, 20216.292429764886, 22361.66457503171, 22476.77523224747, 22564.548709026058, 22623.547529049, 22719.88640056789, 16777.580560486087, 16918.23867925971, 17684.646307074043, 17688.02715850589, 17957.73063404024, 15654.765178522559, 15875.0, 16281.837181628032, 16356.0, 16558.751618725488, 17882.28470861151, 18421.81578184201, 18788.060596969823, 18847.340343088475, 18850.39771619373, 19596.412107636792, 19631.509212269808, 19848.70226946568, 19901.0, 19963.401097967086, 18010.279588816826, 18055.097245137953, 18099.366522171662, 18107.909460452614, 18216.72674909851], "value": 12178.627977943266, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1291.9160254583453, 1341.336038660863, 1349.082623815805, 1349.7502961952036, 1356.3964036003981, 14794.482193123244, 15400.614984625376, 15402.84597154028, 15406.0, 15427.151506667144, 15335.923320383383, 15352.232388380593, 15483.69032619347, 15496.225188740575, 15497.070175789433, 2636.7851005155617, 2726.9590956135676, 2728.0997270900557, 2753.4768394005073, 2754.394033312668, 2657.3755167535614, 2667.66654168229, 2685.087070396065, 2690.367763575558, 2691.93668500942, 2656.0969270448045, 2701.068131494641, 2726.6046423268713, 2729.6314997475283, 2729.6761070880607, 2728.236093893713, 2740.082072505713, 2741.753242208201, 2755.118362124121, 2755.5040092783393, 2237.765034671352, 2309.884505774721, 2320.6286994081033, 2547.3758929062315, 2620.0436840553252, 12472.8129078063, 14704.191269480174, 15113.168775717331, 15120.168390738505, 15124.546263612, 7989.121196668362, 8243.0, 8299.377546683963, 8309.252167304972, 8350.749477515685, 5259.3951695555115, 5372.898555796052, 5473.863153421196, 5483.972580137113, 5511.421300763443, 1895.1566552883971, 1942.339604534451, 1950.629380417716, 1987.6620974434381, 1989.0, 26358.73641263582, 26448.735512645013, 26575.601365979524, 26733.86633066822, 26736.331591710365, 6665.266820649725, 6894.079694127411, 6975.0, 6980.162380514269, 6989.7553585624255, 17419.47741567755, 17596.38412655551, 17663.38178163758, 17665.55836104082, 17682.292708291523, 7413.406927445899, 7638.236176382317, 7654.387648988068, 7773.067231932091, 7845.411594130349, 7248.492605517563, 7257.818554536178, 7259.983602295682, 7263.237360077116, 7309.086364204474, 5281.313429254173, 5360.222767698662, 5418.376886658006, 5428.375736790241, 5453.618246722771, 1471.8748906342867, 1486.7769834524909, 1499.7450433426343, 1518.187769543289, 1537.3235776258412, 831.3266254334009, 863.9438436501607, 875.2647775868301, 886.2998231397253, 893.7542175901549, 7288.854222915618, 7391.926080739231, 7453.142888567776, 7476.0, 7521.398288136936, 689.2521614048713, 699.7655785311978, 699.8180473076935, 716.9784906452815, 724.4204636290946, 4949.307097006422, 5080.847574572768, 5091.0, 5096.362954630671, 5167.1474206755465, 20099.39701808948, 21270.787292127192, 21538.676919846213, 21736.021879015483, 21942.670859937112, 21339.35981920545, 21528.0, 21578.0, 21720.89139554292, 21810.563788724456, 10144.0, 10349.172066234683, 10736.838947415796, 10822.0, 10835.620753273755, 8001.559914204775, 8158.4289099762445, 8159.184081591793, 8165.79585510367, 8166.469179503313, 17848.821511784718, 17877.642447150723, 18078.09609519545, 18154.455366339032, 18497.445076647724, 18889.338873139375, 19005.71491427602, 19535.023248837217, 19638.410847674593, 19650.705239421135, 10874.130069594255, 11065.225434219527, 11239.60661376848, 11250.887491124984, 11253.887461125283, 13829.79255311151, 14076.859231407554, 14090.154590724589, 14150.292485375485, 14195.148291102567, 10714.25000249975, 10807.0, 10993.340399576051, 11054.668359949215, 11193.720156995814, 11098.833517497082, 11219.046381057702, 11344.602938897098, 11382.0, 11616.651500455, 8541.273991710526, 8717.65129394817, 8813.383063185516, 8817.382783205114, 8821.955890220765, 6552.3775241352, 6619.470442364503, 6622.801315960529, 6678.565893216925, 6717.596944183365, 6662.500312476681, 6826.8634627308165, 6946.479014074066, 7012.333828286304, 7135.82160445993], "value": 6506.244321863255, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.9701817890927, 503.3330836641451, 529.1929807044255, 531.1793279383352, 536.1207619504014, 6150.907736383958, 6187.0, 6199.357170349856, 6224.221972253468, 6241.750329986795, 6041.879162416749, 6086.239220097488, 6143.907841382372, 6203.844903877405, 6213.440790328869, 5112.284280200774, 5131.897362052775, 5145.459726728695, 5151.94385151045, 5153.742312884369, 5168.948310516885, 5178.741062946857, 5185.55922746567, 5199.844004679847, 5234.842954711346, 4678.368420263272, 4820.831270905502, 4865.805367785284, 5075.644704870642, 5172.534471897528, 4852.878678033042, 4875.658703890729, 4879.999999999982, 4885.8778530536665, 4907.975460122677, 4303.33298338758, 4319.222539942809, 4333.934990975138, 4340.956590434087, 4351.434313539251, 5924.644521328734, 5956.910646340309, 5970.492508136814, 5980.461758441717, 5988.041913293852, 5510.669359838383, 5556.416576259475, 5577.776888924479, 5596.412376700469, 5599.832005039855, 6413.262474815409, 6473.187507497908, 6476.190476190476, 6486.610803351767, 6529.738810447577, 4552.339910712928, 4684.273937539687, 4710.963588010666, 4730.787114579852, 4745.857624271277, 6554.442872355856, 6708.463322934153, 6712.630805305706, 6781.557520355584, 6818.931810681879, 4955.35580374549, 4957.752112394366, 4969.552740253323, 4978.77595508203, 4981.277714731416, 4955.578775804025, 4999.800007999712, 5011.674241174312, 5014.473480284591, 5049.924251136236, 5587.776488940478, 5604.551635869121, 5622.273197499209, 5622.297212848394, 5626.97186514073, 6431.163948686641, 6492.610443373318, 6566.606003639797, 6589.0, 6589.86820263592, 1722.0872937342979, 1728.7234042553382, 1733.7226043832927, 1737.2529812180883, 1743.8168992255885, 1147.8048731715628, 1201.6755476021426, 1212.7877621416217, 1221.5968730318973, 1236.8824961628632, 5512.3660779010925, 5533.22534845122, 5580.660641446024, 5668.404817494104, 5671.688057156814, 6606.471482281406, 6608.669566521655, 6646.90029649546, 6701.631410272482, 6706.329367063255, 5567.164925261243, 5634.211210430543, 5645.661260324394, 5659.943400565941, 5687.203791469197, 3310.0069979006203, 3441.3117376524797, 3453.2230248193955, 3477.3393055319407, 3484.8257587121047, 6203.7828675997025, 6251.0, 6307.810765676945, 6338.0, 6425.004124360731, 6528.554313099004, 6591.209054913441, 6680.198376194888, 6718.932810671929, 6750.189977202669, 6285.59143655661, 6303.779367722108, 6309.148264984168, 6363.5545511813725, 6395.52033597482, 6503.804885853432, 6539.365158710322, 6543.934560654523, 6659.667016649051, 6717.395434410926, 6505.796427660709, 6593.274739778716, 6626.469882409492, 6635.734570617315, 6655.2013758347375, 6274.654893980693, 6309.242890853127, 6381.680915954276, 6471.967640161769, 6484.967575162094, 4909.410870695539, 4961.255811628284, 4983.800647974185, 5020.949790502194, 5027.64806463544, 6473.2232132144445, 6493.837654058498, 6616.4045235929, 6641.734330626721, 6683.198016238082, 6148.354422785543, 6238.625682459067, 6353.14232578596, 6437.324080971431, 6479.181556023145, 6301.590396624205, 6321.905171422341, 6395.744170233139, 6403.711832967434, 6449.193850768654, 6228.750849965951, 6316.210473690789, 6337.0, 6364.6181229126405, 6368.203974503187, 6331.778387756406, 6448.129502517288, 6448.322926092693, 6461.935380646321, 6498.74005039812, 6162.568620196544, 6266.185395898504, 6294.968525157345, 6308.810735677937, 6313.842153946004], "value": 4981.338563814595, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [866.7529754020105, 886.5921676029026, 902.1159263921356, 903.6475774447962, 915.062061387078, 18493.167807448663, 19109.235630574796, 19128.42614721557, 19199.616007679837, 19222.0, 18247.178876950537, 18549.35077272296, 18570.907145464287, 18817.153228104733, 18835.246590136412, 965.8744363232787, 987.4420952162042, 989.4805227255677, 991.538934395506, 992.7815880506313, 969.9369540979814, 973.4013581647307, 975.2100798353321, 977.291463688827, 979.2949076664826, 983.8081574093019, 1027.6454623155, 1028.4292217819107, 1047.350642601588, 1047.4972013433558, 1006.8791744990612, 1011.7268337548894, 1019.306871327497, 1019.6533178719271, 1020.7244044108087, 743.0897150990048, 749.3031480722925, 753.3445902065183, 753.8153152477623, 754.3437209627605, 19171.808281917143, 20237.898810506, 21019.789802101935, 21072.789272107388, 21132.366029019155, 21830.345089647337, 21972.121115155376, 22292.777072229233, 22358.441038973993, 22494.88752556243, 16483.423080192137, 16612.667746645, 16763.413280535122, 16810.915945420318, 16866.66266674672, 3776.6601005909324, 3780.243951209769, 3781.054736315921, 3788.5264341957254, 3806.809659517013, 140736.29631851878, 144983.0, 146055.0, 146858.0, 147745.99999999892, 17342.653146937242, 17455.476335709947, 17615.911920440314, 17663.911680441517, 17733.911330443265, 31133.8443307782, 31639.84180079131, 31904.0, 32777.83611081977, 32948.835255824044, 8528.104549022388, 8564.700235491728, 8583.227509524173, 8601.913980860236, 8652.221300083023, 4197.664186865043, 4211.178820130045, 4233.724807887477, 4257.70196086271, 4307.987622908614, 5208.401033881152, 5257.815976440883, 5281.841544753664, 5302.2046692996355, 5346.91979620306, 955.6416343871049, 968.8934217236099, 991.7966816802539, 993.9254555908191, 996.1532697207316, 682.5120039172016, 702.4661257444277, 709.021550260637, 714.5998240985065, 721.0770214125822, 11260.38067906273, 11444.484998174936, 11836.881631183578, 11920.165588408729, 12105.394730263451, 643.6331291164086, 662.0499583098269, 668.5854770042605, 672.4317951331118, 674.1943377663742, 5244.501772331622, 5294.867628309246, 5348.411674715778, 5364.356277246756, 5401.540869026177, 121818.0, 122084.0, 122768.0, 123734.0, 123983.0, 106747.4662626682, 107770.0, 108595.45702271438, 110659.0, 112306.0, 93026.53486732658, 93267.0, 93309.53345233231, 94506.5274673636, 94801.0, 9230.846144231971, 9291.953540232256, 9358.204552613104, 9446.29152813528, 9647.565859536478, 43783.562164377945, 44006.11987760291, 44196.779016106, 44344.55655443533, 45305.77347113243, 53257.93484130373, 55562.44437555573, 56231.15653265286, 56343.0, 56526.71736641291, 15287.159206243763, 15335.233238338262, 15357.923210383877, 15359.155246461552, 15527.146006969726, 17139.914300428918, 17284.0, 17285.56786080358, 17452.73820892662, 17534.298628054734, 14186.148831070168, 14350.21073840907, 14410.351534181196, 14550.054246473945, 14591.781123282946, 14339.354729037426, 14440.927795360956, 14448.27758612044, 14494.710105798036, 14667.486637968046, 14911.03078299907, 14911.701765964837, 15227.543173704807, 15234.695306094038, 15438.45965391206, 10762.784744304914, 10914.836277456003, 11021.118310535297, 11110.277831940897, 11160.0, 11523.769524609628, 11632.651020469399, 11795.35125568102, 11868.643940681794, 12115.212511186744], "value": 8798.330719401843, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1467.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 119.0, 109.0, 113.0, 119.0, 116.0, 109.0, 121.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 122.0, 132.0, 142.0, 127.0, 131.0, 125.0, 142.0, 119.0, 122.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 172.0, 170.0, 163.0, 168.0, 169.0, 167.0, 169.0, 166.0, 161.0], "value": 166.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 88.0, 91.0, 80.0, 75.0, 75.0, 77.0, 75.0, 73.0, 82.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 81.0, 86.0, 81.0, 85.0, 84.0, 85.0, 84.0, 83.0], "value": 83.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 115.0, 114.0, 115.0, 115.0, 115.0, 127.0, 116.0, 118.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 152.0, 149.0, 148.0, 154.0, 156.0, 148.0, 150.0, 149.0, 149.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 101.0, 100.0, 95.0, 95.0, 95.0, 95.0, 102.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 58.0, 53.0, 54.0, 55.0, 60.0, 54.0, 56.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 38.0, 42.0, 39.0, 42.0, 38.0, 43.0, 50.0, 39.0], "value": 40.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 86.0, 74.0, 81.0, 80.0, 74.0, 75.0, 80.0, 75.0, 80.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 113.0, 121.0, 115.0, 112.0, 126.0, 120.0, 115.0, 108.0, 115.0], "value": 116.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 181.0, 175.0, 167.0, 168.0, 175.0, 169.0, 170.0, 177.0, 179.0], "value": 173.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 65.0, 69.0, 77.0, 64.0, 68.0, 77.0, 69.0], "value": 68.2, "unit": "score"}]}]} 16:23:21 INFO - Return code: 0 16:23:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:23:21 INFO - # TBPL SUCCESS # 16:23:21 INFO - Running post-action listener: _package_coverage_data 16:23:21 INFO - Running post-action listener: _resource_record_post_action 16:23:21 INFO - [mozharness: 2017-09-13 23:23:21.236943Z] Finished run-tests step (success) 16:23:21 INFO - Running post-run listener: _resource_record_post_run 16:23:21 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 19230720; Write bytes: 395235328; Read time: 11244; Write time: 617292 16:23:21 INFO - TinderboxPrint: CPU usage
13.3% 16:23:21 INFO - TinderboxPrint: I/O read bytes / time
19,230,720 / 11,244 16:23:21 INFO - TinderboxPrint: I/O write bytes / time
395,235,328 / 617,292 16:23:21 INFO - TinderboxPrint: CPU idle
8,281.3 (86.7%) 16:23:21 INFO - TinderboxPrint: CPU user
1,202.7 (12.6%) 16:23:21 INFO - TinderboxPrint: Swap in / out
0 / 0 16:23:21 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 105971712; Read time: 0; Write time: 171584 16:23:21 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:23:21 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 17485824; Write bytes: 259903488; Read time: 10576; Write time: 338368 16:23:21 INFO - Running post-run listener: _upload_blobber_files 16:23:21 INFO - Blob upload gear active. 16:23:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:23:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:23:22 INFO - (blobuploader) - INFO - Open directory for files ... 16:23:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:23:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:23:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:23 INFO - (blobuploader) - INFO - Done attempting. 16:23:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:23:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:23:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:30 INFO - (blobuploader) - INFO - Done attempting. 16:23:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:23:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:23:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:31 INFO - (blobuploader) - INFO - Done attempting. 16:23:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:23:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:23:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:32 INFO - (blobuploader) - INFO - Done attempting. 16:23:32 INFO - (blobuploader) - INFO - Iteration through files over. 16:23:32 INFO - Return code: 0 16:23:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:23:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:23:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feca6ae750d019179944021b65cb3dc9dfc31e563fb95d000bc100e2302ad44bde3fd20007c1b3becb8ed30a666c26df772bb4ca1388af12853b0112e6b8a049", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66d3fa8b29cbb1bd6af083ff8547fbc0c4b6d50a130b1086c5ff4d10fe7d35ab3b79fc3a7cf412e99949190b1e16f4c31d0c0e29d6221835af6b16c1f9c44787", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1d2cd6e5f97cb173889ffdd8d5765bf995f323fe31252aa0165a3fb81fe18b4586bc69c1c3a7514e4b627ef0eaca49f64a43385cf1cbaa2d80a70329835681a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/283b6f945cdf6adf82ec24965af9135d504a5acba9d3208c7352449eaa0f022ba1776200df3ce524c7ee0dc4ab93089c12c8916b32a2e35da211011fe0e88e85"} 16:23:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:23:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:23:32 INFO - Contents: 16:23:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feca6ae750d019179944021b65cb3dc9dfc31e563fb95d000bc100e2302ad44bde3fd20007c1b3becb8ed30a666c26df772bb4ca1388af12853b0112e6b8a049", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66d3fa8b29cbb1bd6af083ff8547fbc0c4b6d50a130b1086c5ff4d10fe7d35ab3b79fc3a7cf412e99949190b1e16f4c31d0c0e29d6221835af6b16c1f9c44787", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1d2cd6e5f97cb173889ffdd8d5765bf995f323fe31252aa0165a3fb81fe18b4586bc69c1c3a7514e4b627ef0eaca49f64a43385cf1cbaa2d80a70329835681a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/283b6f945cdf6adf82ec24965af9135d504a5acba9d3208c7352449eaa0f022ba1776200df3ce524c7ee0dc4ab93089c12c8916b32a2e35da211011fe0e88e85"} 16:23:32 INFO - Running post-run listener: copy_logs_to_upload_dir 16:23:32 INFO - Copying logs to upload dir... 16:23:32 INFO - mkdir: /builds/slave/test/build/upload/logs 16:23:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.082311 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2017-09-13 16:23:32.576093) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 16:23:32.580771) ========= 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=8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feca6ae750d019179944021b65cb3dc9dfc31e563fb95d000bc100e2302ad44bde3fd20007c1b3becb8ed30a666c26df772bb4ca1388af12853b0112e6b8a049", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66d3fa8b29cbb1bd6af083ff8547fbc0c4b6d50a130b1086c5ff4d10fe7d35ab3b79fc3a7cf412e99949190b1e16f4c31d0c0e29d6221835af6b16c1f9c44787", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1d2cd6e5f97cb173889ffdd8d5765bf995f323fe31252aa0165a3fb81fe18b4586bc69c1c3a7514e4b627ef0eaca49f64a43385cf1cbaa2d80a70329835681a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/283b6f945cdf6adf82ec24965af9135d504a5acba9d3208c7352449eaa0f022ba1776200df3ce524c7ee0dc4ab93089c12c8916b32a2e35da211011fe0e88e85"} build_url:https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011433 build_url: 'https://queue.taskcluster.net/v1/task/A1V4MAzpSR2fT4tPLmTCBw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feca6ae750d019179944021b65cb3dc9dfc31e563fb95d000bc100e2302ad44bde3fd20007c1b3becb8ed30a666c26df772bb4ca1388af12853b0112e6b8a049", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/66d3fa8b29cbb1bd6af083ff8547fbc0c4b6d50a130b1086c5ff4d10fe7d35ab3b79fc3a7cf412e99949190b1e16f4c31d0c0e29d6221835af6b16c1f9c44787", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1d2cd6e5f97cb173889ffdd8d5765bf995f323fe31252aa0165a3fb81fe18b4586bc69c1c3a7514e4b627ef0eaca49f64a43385cf1cbaa2d80a70329835681a", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/283b6f945cdf6adf82ec24965af9135d504a5acba9d3208c7352449eaa0f022ba1776200df3ce524c7ee0dc4ab93089c12c8916b32a2e35da211011fe0e88e85"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-13 16:23:32.608450) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:23:32.608744) ========= 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=8783db0840cc980e80bb0bd400000236-1505343750.712316-528789953 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-13 16:23:32.694016) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 16:23:32.694307) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-13 16:23:32.696203) ========= ========= Total master_lag: 0.21 =========