builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-053 starttime: 1506143261.0 results: success (0) revision: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:41.003299) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:41.003737) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:41.004003) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:41.075435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:41.075771) ========= 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=920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-22 22:07:41-- 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% 110M=0s 2017-09-22 22:07:41 (110 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.340563 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:41.436787) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:41.437076) ========= 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=920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022686 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:41.492577) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:41.495889) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 --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 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 --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=920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-22 22:07:41,592 truncating revision to first 12 chars 2017-09-22 22:07:41,592 Setting DEBUG logging. 2017-09-22 22:07:41,592 attempt 1/10 2017-09-22 22:07:41,592 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9d1fcbc2fdd5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-22 22:07:41,995 unpacking tar archive at: mozilla-central-9d1fcbc2fdd5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.675916 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:42.214240) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:42.214506) ========= script_repo_revision: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:42.217893) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:42.218156) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-22 22:07:42.231141) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-22 22:07:42.231420) ========= /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=920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:07:42 INFO - MultiFileLogger online at 20170922 22:07:42 in /builds/slave/test 22:07:42 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 22:07:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:07:42 INFO - {'append_to_log': False, 22:07:42 INFO - 'base_work_dir': '/builds/slave/test', 22:07:42 INFO - 'blob_upload_branch': 'Firefox', 22:07:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:07:42 INFO - 'branch': 'Firefox', 22:07:42 INFO - 'buildbot_json_path': 'buildprops.json', 22:07:42 INFO - 'code_coverage': False, 22:07:42 INFO - 'config_files': ('talos/linux_config.py',), 22:07:42 INFO - 'default_actions': ('clobber', 22:07:42 INFO - 'read-buildbot-config', 22:07:42 INFO - 'download-and-extract', 22:07:42 INFO - 'populate-webroot', 22:07:42 INFO - 'create-virtualenv', 22:07:42 INFO - 'install', 22:07:42 INFO - 'setup-mitmproxy', 22:07:42 INFO - 'run-tests'), 22:07:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:07:42 INFO - 'disable_ccov_upload': False, 22:07:42 INFO - 'disable_stylo': False, 22:07:42 INFO - 'download_minidump_stackwalk': True, 22:07:42 INFO - 'download_symbols': 'ondemand', 22:07:42 INFO - 'enable_stylo': False, 22:07:42 INFO - 'enable_webrender': False, 22:07:42 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 22:07:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:07:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:07:42 INFO - 'gecko_profile': False, 22:07:42 INFO - 'gecko_profile_interval': 0, 22:07:42 INFO - 'installer_path': 'installer.exe', 22:07:42 INFO - 'log_level': 'info', 22:07:42 INFO - 'log_name': 'talos', 22:07:42 INFO - 'log_to_console': True, 22:07:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:07:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:07:42 INFO - 'opt_config_files': (), 22:07:42 INFO - 'pip_index': False, 22:07:42 INFO - 'suite': 'dromaeojs-e10s', 22:07:42 INFO - 'system_bits': '32', 22:07:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:07:42 INFO - 'title': 'talos-linux64-ix-053', 22:07:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:07:42 INFO - 'use_talos_json': True, 22:07:42 INFO - 'verify': 'False', 22:07:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:07:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:07:42 INFO - 'work_dir': 'build'} 22:07:42 INFO - [mozharness: 2017-09-23 05:07:42.448722Z] Running clobber step. 22:07:42 INFO - Running pre-action listener: _resource_record_pre_action 22:07:42 INFO - Running main action method: clobber 22:07:42 INFO - rmtree: /builds/slave/test/build 22:07:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:07:43 INFO - Running post-action listener: _resource_record_post_action 22:07:43 INFO - [mozharness: 2017-09-23 05:07:43.968199Z] Finished clobber step (success) 22:07:43 INFO - [mozharness: 2017-09-23 05:07:43.968298Z] Running read-buildbot-config step. 22:07:43 INFO - Running pre-action listener: _resource_record_pre_action 22:07:43 INFO - Running main action method: read_buildbot_config 22:07:43 INFO - Using buildbot properties: 22:07:43 INFO - { 22:07:43 INFO - "project": "", 22:07:43 INFO - "product": "firefox", 22:07:43 INFO - "who": "ffxbld@noreply.mozilla.org", 22:07:43 INFO - "installer_path": "public/build/target.tar.bz2", 22:07:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 22:07:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 22:07:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 22:07:43 INFO - "stage_platform": "linux64-pgo", 22:07:43 INFO - "basedir": "/builds/slave/test", 22:07:43 INFO - "buildnumber": 198, 22:07:43 INFO - "platform": "ubuntu64_hw", 22:07:43 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:07:43 INFO - "slavebuilddir": "test", 22:07:43 INFO - "taskId": "SnI52z03SBm-slF9PKd4Fw", 22:07:43 INFO - "branch": "mozilla-central", 22:07:43 INFO - "script_repo_revision": "production", 22:07:43 INFO - "revision": "9d1fcbc2fdd58011903aa1b987264d58dc1a06b5", 22:07:43 INFO - "slavename": "talos-linux64-ix-053", 22:07:43 INFO - "repo_path": "mozilla-central" 22:07:43 INFO - } 22:07:43 INFO - Finding installer, test and symbols from parent task. 22:07:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SnI52z03SBm-slF9PKd4Fw'}, attempt #1 22:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SnI52z03SBm-slF9PKd4Fw'}, attempt #1 22:07:44 INFO - Task dependencies: JtWjNlDyQMG39ykVhnRwEg 22:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg'}, attempt #1 22:07:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.tar.bz2 22:07:45 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.test_packages.json 22:07:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.crashreporter-symbols.zip 22:07:45 INFO - Running post-action listener: _resource_record_post_action 22:07:45 INFO - [mozharness: 2017-09-23 05:07:45.306957Z] Finished read-buildbot-config step (success) 22:07:45 INFO - [mozharness: 2017-09-23 05:07:45.307178Z] Running download-and-extract step. 22:07:45 INFO - Running pre-action listener: _resource_record_pre_action 22:07:45 INFO - Running main action method: download_and_extract 22:07:45 INFO - mkdir: /builds/slave/test/build/tests 22:07:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:07:45 INFO - trying https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.test_packages.json 22:07:45 INFO - Downloading https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 22:07:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 22:07:46 INFO - Downloaded 989 bytes. 22:07:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 22:07:46 INFO - Using the following test package requirements: 22:07:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 22:07:46 INFO - u'common': [u'target.common.tests.zip'], 22:07:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 22:07:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 22:07:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 22:07:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 22:07:46 INFO - u'mozbase': [u'target.common.tests.zip'], 22:07:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 22:07:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 22:07:46 INFO - u'web-platform': [u'target.common.tests.zip', 22:07:46 INFO - u'target.web-platform.tests.tar.gz'], 22:07:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 22:07:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 22:07:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.common.tests.zip 22:07:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.common.tests.zip'}, attempt #1 22:07:46 INFO - Fetch https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.common.tests.zip into memory 22:07:48 INFO - Content-Length response header: 55340279 22:07:48 INFO - Bytes received: 55340279 22:07:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.talos.tests.zip 22:07:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 22:07:58 INFO - Fetch https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.talos.tests.zip into memory 22:08:00 INFO - Content-Length response header: 14513823 22:08:00 INFO - Bytes received: 14513823 22:08:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:01 INFO - trying https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.tar.bz2 22:08:01 INFO - Downloading https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 22:08:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 22:08:03 INFO - Downloaded 62444747 bytes. 22:08:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.tar.bz2 22:08:04 INFO - mkdir: /builds/slave/test/properties 22:08:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:08:04 INFO - Writing to file /builds/slave/test/properties/build_url 22:08:04 INFO - Contents: 22:08:04 INFO - build_url:https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.tar.bz2 22:08:04 INFO - Running post-action listener: _resource_record_post_action 22:08:04 INFO - Running post-action listener: find_tests_for_verification 22:08:04 INFO - Running post-action listener: set_extra_try_arguments 22:08:04 INFO - [mozharness: 2017-09-23 05:08:04.122475Z] Finished download-and-extract step (success) 22:08:04 INFO - [mozharness: 2017-09-23 05:08:04.122575Z] Running populate-webroot step. 22:08:04 INFO - Running pre-action listener: _resource_record_pre_action 22:08:04 INFO - Running main action method: populate_webroot 22:08:04 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:08:04 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['tresize', 22:08:04 INFO - 'tcanvasmark']}, 22:08:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:08:04 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['dromaeo_css', 22:08:04 INFO - 'kraken']}, 22:08:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:08:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:04 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:08:04 INFO - 'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:08:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:08:04 INFO - 'tests': ['damp', 'tps']}, 22:08:04 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:08:04 INFO - 'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['damp', 'tps']}, 22:08:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:08:04 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['dromaeo_dom']}, 22:08:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:08:04 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['basic_compositor_video', 22:08:04 INFO - 'glvideo']}, 22:08:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 22:08:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 22:08:04 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:08:04 INFO - 'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['ts_paint_webext', 22:08:04 INFO - 'tp5o_webext']}, 22:08:04 INFO - 'other-e10s': {'tests': ['a11yr', 22:08:04 INFO - 'ts_paint', 22:08:04 INFO - 'tpaint', 22:08:04 INFO - 'sessionrestore', 22:08:04 INFO - 'sessionrestore_many_windows', 22:08:04 INFO - 'sessionrestore_no_auto_restore', 22:08:04 INFO - 'tabpaint']}, 22:08:04 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['a11yr', 22:08:04 INFO - 'ts_paint', 22:08:04 INFO - 'tpaint', 22:08:04 INFO - 'sessionrestore', 22:08:04 INFO - 'sessionrestore_many_windows', 22:08:04 INFO - 'sessionrestore_no_auto_restore', 22:08:04 INFO - 'tabpaint']}, 22:08:04 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 22:08:04 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 22:08:04 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['perf_reftest_singletons']}, 22:08:04 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['perf_reftest']}, 22:08:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:08:04 INFO - 'tsvgr_opacity', 22:08:04 INFO - 'tart', 22:08:04 INFO - 'tscrollx', 22:08:04 INFO - 'tsvg_static']}, 22:08:04 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['tsvgx', 22:08:04 INFO - 'tsvgr_opacity', 22:08:04 INFO - 'tart', 22:08:04 INFO - 'tscrollx', 22:08:04 INFO - 'tsvg_static']}, 22:08:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:08:04 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:08:04 INFO - 'talos_options': ['--disable-stylo'], 22:08:04 INFO - 'tests': ['tp5o']}, 22:08:04 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 22:08:04 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 22:08:04 INFO - 'talos_options': ['--mitmproxy', 22:08:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 22:08:04 INFO - '--firstNonBlankPaint'], 22:08:04 INFO - 'tests': ['tp6_google', 22:08:04 INFO - 'tp6_youtube', 22:08:04 INFO - 'tp6_amazon', 22:08:04 INFO - 'tp6_facebook']}, 22:08:04 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 22:08:04 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 22:08:04 INFO - 'talos_options': ['--disable-stylo', 22:08:04 INFO - '--mitmproxy', 22:08:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 22:08:04 INFO - '--firstNonBlankPaint'], 22:08:04 INFO - 'tests': ['tp6_google', 22:08:04 INFO - 'tp6_youtube', 22:08:04 INFO - 'tp6_amazon', 22:08:04 INFO - 'tp6_facebook']}, 22:08:04 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 22:08:04 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 22:08:04 INFO - 'talos_options': ['--stylo-threads=1', 22:08:04 INFO - '--mitmproxy', 22:08:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 22:08:04 INFO - '--firstNonBlankPaint'], 22:08:04 INFO - 'tests': ['tp6_google', 22:08:04 INFO - 'tp6_youtube', 22:08:04 INFO - 'tp6_amazon', 22:08:04 INFO - 'tp6_facebook']}, 22:08:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:08:04 INFO - 'talos_options': ['--xperf_path', 22:08:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:04 INFO - 'tests': ['tp5n']}, 22:08:04 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 22:08:04 INFO - 'talos_options': ['--disable-stylo', 22:08:04 INFO - '--xperf_path', 22:08:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:08:04 INFO - 'tests': ['tp5n']}}} 22:08:04 INFO - Running post-action listener: _resource_record_post_action 22:08:04 INFO - [mozharness: 2017-09-23 05:08:04.141359Z] Finished populate-webroot step (success) 22:08:04 INFO - [mozharness: 2017-09-23 05:08:04.141450Z] Running create-virtualenv step. 22:08:04 INFO - Running pre-action listener: _resource_record_pre_action 22:08:04 INFO - Running main action method: create_virtualenv 22:08:04 INFO - Creating virtualenv /builds/slave/test/build/venv 22:08:04 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:08:04 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 22:08:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 22:08:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:08:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:08:04 INFO - Installing distribute.............................................................................................................................................................................................done. 22:08:05 INFO - Installing pip...............done. 22:08:05 INFO - Return code: 0 22:08:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:08:05 INFO - Reading from file tmpfile_stdout 22:08:05 INFO - Output received: 22:08:05 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 22:08:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:08:05 INFO - Using env: {'DISPLAY': ':0', 22:08:05 INFO - 'HOME': '/home/cltbld', 22:08:05 INFO - 'LANG': 'en_US.UTF-8', 22:08:05 INFO - 'LANGUAGE': 'en_US:en', 22:08:05 INFO - 'LOGNAME': 'cltbld', 22:08:05 INFO - 'MAIL': '/var/mail/cltbld', 22:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:05 INFO - 'MOZ_NO_REMOTE': '1', 22:08:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:05 INFO - 'NO_EM_RESTART': '1', 22:08:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:05 INFO - 'PWD': '/builds/slave/test', 22:08:05 INFO - 'SHELL': '/bin/bash', 22:08:05 INFO - 'SHLVL': '1', 22:08:05 INFO - 'TERM': 'linux', 22:08:05 INFO - 'TMOUT': '86400', 22:08:05 INFO - 'USER': 'cltbld', 22:08:05 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:05 INFO - '_': '/tools/buildbot/bin/python'} 22:08:06 INFO - Ignoring indexes: http://pypi.python.org/simple/ 22:08:06 INFO - Downloading/unpacking pip>=1.5 22:08:06 INFO - Running setup.py egg_info for package pip 22:08:06 INFO - warning: no files found matching 'pip/cacert.pem' 22:08:06 INFO - warning: no files found matching '*.html' under directory 'docs' 22:08:06 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 22:08:06 INFO - no previously-included directories found matching 'docs/_build/_sources' 22:08:06 INFO - Installing collected packages: pip 22:08:06 INFO - Found existing installation: pip 1.1 22:08:06 INFO - Uninstalling pip: 22:08:06 INFO - Successfully uninstalled pip 22:08:06 INFO - Running setup.py install for pip 22:08:06 INFO - warning: no files found matching 'pip/cacert.pem' 22:08:06 INFO - warning: no files found matching '*.html' under directory 'docs' 22:08:06 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 22:08:06 INFO - no previously-included directories found matching 'docs/_build/_sources' 22:08:06 INFO - Installing pip script to /builds/slave/test/build/venv/bin 22:08:06 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 22:08:06 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 22:08:06 INFO - Successfully installed pip 22:08:06 INFO - Cleaning up... 22:08:06 INFO - Return code: 0 22:08:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08: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')]} 22:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08: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', '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 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08: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', 'psutil>=3.1.1'] in /builds/slave/test/build 22:08: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 psutil>=3.1.1 22:08:06 INFO - Using env: {'DISPLAY': ':0', 22:08:06 INFO - 'HOME': '/home/cltbld', 22:08:06 INFO - 'LANG': 'en_US.UTF-8', 22:08:06 INFO - 'LANGUAGE': 'en_US:en', 22:08:06 INFO - 'LOGNAME': 'cltbld', 22:08:06 INFO - 'MAIL': '/var/mail/cltbld', 22:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:06 INFO - 'MOZ_NO_REMOTE': '1', 22:08:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:06 INFO - 'NO_EM_RESTART': '1', 22:08:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:06 INFO - 'PWD': '/builds/slave/test', 22:08:06 INFO - 'SHELL': '/bin/bash', 22:08:06 INFO - 'SHLVL': '1', 22:08:06 INFO - 'TERM': 'linux', 22:08:06 INFO - 'TMOUT': '86400', 22:08:06 INFO - 'USER': 'cltbld', 22:08:06 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:06 INFO - '_': '/tools/buildbot/bin/python'} 22:08:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:06 INFO - Downloading/unpacking psutil>=3.1.1 22:08: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 22:08: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 22:08: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 22:08: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 22:08:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:08:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:07 INFO - Installing collected packages: psutil 22:08:07 INFO - Running setup.py install for psutil 22:08:07 INFO - building 'psutil._psutil_linux' extension 22:08:07 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 22:08:08 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 22:08:08 INFO - building 'psutil._psutil_posix' extension 22:08:08 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 22:08:08 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 22:08:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:08:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:08:08 INFO - Successfully installed psutil 22:08:08 INFO - Cleaning up... 22:08:08 INFO - Return code: 0 22:08:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08: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')]} 22:08:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:08: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 mozsystemmonitor==0.3 22:08:08 INFO - Using env: {'DISPLAY': ':0', 22:08:08 INFO - 'HOME': '/home/cltbld', 22:08:08 INFO - 'LANG': 'en_US.UTF-8', 22:08:08 INFO - 'LANGUAGE': 'en_US:en', 22:08:08 INFO - 'LOGNAME': 'cltbld', 22:08:08 INFO - 'MAIL': '/var/mail/cltbld', 22:08:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:08 INFO - 'MOZ_NO_REMOTE': '1', 22:08:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:08 INFO - 'NO_EM_RESTART': '1', 22:08:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:08 INFO - 'PWD': '/builds/slave/test', 22:08:08 INFO - 'SHELL': '/bin/bash', 22:08:08 INFO - 'SHLVL': '1', 22:08:08 INFO - 'TERM': 'linux', 22:08:08 INFO - 'TMOUT': '86400', 22:08:08 INFO - 'USER': 'cltbld', 22:08:08 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:08 INFO - '_': '/tools/buildbot/bin/python'} 22:08:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:08: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 22:08: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 22:08: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 22:08: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 22:08:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:08:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:08:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:08:09 INFO - Installing collected packages: mozsystemmonitor 22:08:09 INFO - Running setup.py install for mozsystemmonitor 22:08:09 INFO - Successfully installed mozsystemmonitor 22:08:09 INFO - Cleaning up... 22:08:10 INFO - Return code: 0 22:08:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08: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')]} 22:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08: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', '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 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:08: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 jsonschema==2.5.1 22:08:10 INFO - Using env: {'DISPLAY': ':0', 22:08:10 INFO - 'HOME': '/home/cltbld', 22:08:10 INFO - 'LANG': 'en_US.UTF-8', 22:08:10 INFO - 'LANGUAGE': 'en_US:en', 22:08:10 INFO - 'LOGNAME': 'cltbld', 22:08:10 INFO - 'MAIL': '/var/mail/cltbld', 22:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:10 INFO - 'MOZ_NO_REMOTE': '1', 22:08:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:10 INFO - 'NO_EM_RESTART': '1', 22:08:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:10 INFO - 'PWD': '/builds/slave/test', 22:08:10 INFO - 'SHELL': '/bin/bash', 22:08:10 INFO - 'SHLVL': '1', 22:08:10 INFO - 'TERM': 'linux', 22:08:10 INFO - 'TMOUT': '86400', 22:08:10 INFO - 'USER': 'cltbld', 22:08:10 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:10 INFO - '_': '/tools/buildbot/bin/python'} 22:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:10 INFO - Downloading/unpacking jsonschema==2.5.1 22:08: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 22:08: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 22:08: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 22:08: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 22:08:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:08:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:08: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 22:08: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 22:08: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 22:08: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 22:08:12 INFO - Downloading functools32-3.2.3-2.tar.gz 22:08:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:08:12 INFO - warning: no files found matching '*.txt' 22:08:12 INFO - no previously-included directories found matching 'build' 22:08:12 INFO - no previously-included directories found matching 'dist' 22:08:12 INFO - no previously-included directories found matching '.git*' 22:08:12 INFO - Installing collected packages: jsonschema, functools32 22:08:12 INFO - Running setup.py install for functools32 22:08:12 INFO - warning: no files found matching '*.txt' 22:08:12 INFO - no previously-included directories found matching 'build' 22:08:12 INFO - no previously-included directories found matching 'dist' 22:08:12 INFO - no previously-included directories found matching '.git*' 22:08:12 INFO - Successfully installed jsonschema functools32 22:08:12 INFO - Cleaning up... 22:08:12 INFO - Return code: 0 22:08:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08: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')]} 22:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:08:12 INFO - Using env: {'DISPLAY': ':0', 22:08:12 INFO - 'HOME': '/home/cltbld', 22:08:12 INFO - 'LANG': 'en_US.UTF-8', 22:08:12 INFO - 'LANGUAGE': 'en_US:en', 22:08:12 INFO - 'LOGNAME': 'cltbld', 22:08:12 INFO - 'MAIL': '/var/mail/cltbld', 22:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:12 INFO - 'MOZ_NO_REMOTE': '1', 22:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:12 INFO - 'NO_EM_RESTART': '1', 22:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:12 INFO - 'PWD': '/builds/slave/test', 22:08:12 INFO - 'SHELL': '/bin/bash', 22:08:12 INFO - 'SHLVL': '1', 22:08:12 INFO - 'TERM': 'linux', 22:08:12 INFO - 'TMOUT': '86400', 22:08:12 INFO - 'USER': 'cltbld', 22:08:12 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:12 INFO - '_': '/tools/buildbot/bin/python'} 22:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:08:12 INFO - Cleaning up... 22:08:12 INFO - Return code: 0 22:08:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08: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')]} 22:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:08:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:08:12 INFO - Using env: {'DISPLAY': ':0', 22:08:12 INFO - 'HOME': '/home/cltbld', 22:08:12 INFO - 'LANG': 'en_US.UTF-8', 22:08:12 INFO - 'LANGUAGE': 'en_US:en', 22:08:12 INFO - 'LOGNAME': 'cltbld', 22:08:12 INFO - 'MAIL': '/var/mail/cltbld', 22:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:12 INFO - 'MOZ_NO_REMOTE': '1', 22:08:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:12 INFO - 'NO_EM_RESTART': '1', 22:08:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:12 INFO - 'PWD': '/builds/slave/test', 22:08:12 INFO - 'SHELL': '/bin/bash', 22:08:12 INFO - 'SHLVL': '1', 22:08:12 INFO - 'TERM': 'linux', 22:08:12 INFO - 'TMOUT': '86400', 22:08:12 INFO - 'USER': 'cltbld', 22:08:12 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:12 INFO - '_': '/tools/buildbot/bin/python'} 22:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:12 INFO - Downloading/unpacking blobuploader==1.2.4 22:08:12 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 22:08:12 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 22:08:12 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 22:08:12 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 22:08:13 INFO - Downloading blobuploader-1.2.4.tar.gz 22:08:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:08:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:08:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:14 INFO - Downloading docopt-0.6.1.tar.gz 22:08:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:08:14 INFO - Installing collected packages: blobuploader, requests, docopt 22:08:14 INFO - Running setup.py install for blobuploader 22:08:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:08:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:08:14 INFO - Running setup.py install for requests 22:08:14 INFO - Running setup.py install for docopt 22:08:15 INFO - Successfully installed blobuploader requests docopt 22:08:15 INFO - Cleaning up... 22:08:15 INFO - Return code: 0 22:08:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08: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')]} 22:08:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:15 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 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08:15 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 22:08:15 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 22:08:15 INFO - Using env: {'DISPLAY': ':0', 22:08:15 INFO - 'HOME': '/home/cltbld', 22:08:15 INFO - 'LANG': 'en_US.UTF-8', 22:08:15 INFO - 'LANGUAGE': 'en_US:en', 22:08:15 INFO - 'LOGNAME': 'cltbld', 22:08:15 INFO - 'MAIL': '/var/mail/cltbld', 22:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:15 INFO - 'MOZ_NO_REMOTE': '1', 22:08:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:15 INFO - 'NO_EM_RESTART': '1', 22:08:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:15 INFO - 'PWD': '/builds/slave/test', 22:08:15 INFO - 'SHELL': '/bin/bash', 22:08:15 INFO - 'SHLVL': '1', 22:08:15 INFO - 'TERM': 'linux', 22:08:15 INFO - 'TMOUT': '86400', 22:08:15 INFO - 'USER': 'cltbld', 22:08:15 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:15 INFO - '_': '/tools/buildbot/bin/python'} 22:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:15 INFO - Running setup.py (path:/tmp/pip-NjpYtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:15 INFO - Running setup.py (path:/tmp/pip-ycwosU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:15 INFO - Running setup.py (path:/tmp/pip-gADvSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:15 INFO - Running setup.py (path:/tmp/pip-aSfwqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:15 INFO - Running setup.py (path:/tmp/pip-qxKu9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:15 INFO - Running setup.py (path:/tmp/pip-a4IB3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:15 INFO - Running setup.py (path:/tmp/pip-oLgFIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:15 INFO - Running setup.py (path:/tmp/pip-ZkL7ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:15 INFO - Running setup.py (path:/tmp/pip-f3dGk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:15 INFO - Running setup.py (path:/tmp/pip-Or7PyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:15 INFO - Running setup.py (path:/tmp/pip-UWx0w2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:16 INFO - Running setup.py (path:/tmp/pip-4EiUPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:16 INFO - Running setup.py (path:/tmp/pip-ugze7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:16 INFO - Running setup.py (path:/tmp/pip-C3NFB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:16 INFO - Running setup.py (path:/tmp/pip-hEHu9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:16 INFO - Running setup.py (path:/tmp/pip-GESajN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:16 INFO - Running setup.py (path:/tmp/pip-_GyHcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:08:16 INFO - Running setup.py install for manifestparser 22:08:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:08:16 INFO - Running setup.py install for mozcrash 22:08:16 INFO - Running setup.py install for mozdebug 22:08:16 INFO - Running setup.py install for mozdevice 22:08:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:08:16 INFO - Running setup.py install for mozfile 22:08:17 INFO - Running setup.py install for mozhttpd 22:08:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Running setup.py install for mozinfo 22:08:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Running setup.py install for mozInstall 22:08:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Running setup.py install for mozleak 22:08:17 INFO - Running setup.py install for mozlog 22:08:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Running setup.py install for moznetwork 22:08:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Running setup.py install for mozprocess 22:08:17 INFO - Running setup.py install for mozprofile 22:08:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Running setup.py install for mozrunner 22:08:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:08:17 INFO - Running setup.py install for mozscreenshot 22:08:18 INFO - Running setup.py install for moztest 22:08:18 INFO - Running setup.py install for mozversion 22:08:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:08:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:08:18 INFO - Cleaning up... 22:08:18 INFO - Return code: 0 22:08:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08: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')]} 22:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:18 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 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08:18 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 22:08:18 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 22:08:18 INFO - Using env: {'DISPLAY': ':0', 22:08:18 INFO - 'HOME': '/home/cltbld', 22:08:18 INFO - 'LANG': 'en_US.UTF-8', 22:08:18 INFO - 'LANGUAGE': 'en_US:en', 22:08:18 INFO - 'LOGNAME': 'cltbld', 22:08:18 INFO - 'MAIL': '/var/mail/cltbld', 22:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:18 INFO - 'MOZ_NO_REMOTE': '1', 22:08:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:18 INFO - 'NO_EM_RESTART': '1', 22:08:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:18 INFO - 'PWD': '/builds/slave/test', 22:08:18 INFO - 'SHELL': '/bin/bash', 22:08:18 INFO - 'SHLVL': '1', 22:08:18 INFO - 'TERM': 'linux', 22:08:18 INFO - 'TMOUT': '86400', 22:08:18 INFO - 'USER': 'cltbld', 22:08:18 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:18 INFO - '_': '/tools/buildbot/bin/python'} 22:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:08:18 INFO - Running setup.py (path:/tmp/pip-bbAgzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:08:18 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)) 22:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:08:18 INFO - Running setup.py (path:/tmp/pip-o7V2M6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:08:18 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)) 22:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:08:18 INFO - Running setup.py (path:/tmp/pip-ES3GIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:08:18 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)) 22:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:08:18 INFO - Running setup.py (path:/tmp/pip-CukrcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:08:18 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)) 22:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:08:18 INFO - Running setup.py (path:/tmp/pip-1La224-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:08:18 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)) 22:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:08:18 INFO - Running setup.py (path:/tmp/pip-lJCNUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:08:18 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)) 22:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:08:18 INFO - Running setup.py (path:/tmp/pip-yAfYyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:08:18 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)) 22:08:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:08:18 INFO - Running setup.py (path:/tmp/pip-BJbRm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:08:19 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)) 22:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:08:19 INFO - Running setup.py (path:/tmp/pip-9JbaS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:08:19 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)) 22:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:08:19 INFO - Running setup.py (path:/tmp/pip-w5HhIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:08:19 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)) 22:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:08:19 INFO - Running setup.py (path:/tmp/pip-waLzo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:08:19 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)) 22:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:08:19 INFO - Running setup.py (path:/tmp/pip-ZrwLur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:08:19 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)) 22:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:08:19 INFO - Running setup.py (path:/tmp/pip-H51mES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:08:19 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)) 22:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:08:19 INFO - Running setup.py (path:/tmp/pip-KJvqNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:08:19 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)) 22:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:19 INFO - Running setup.py (path:/tmp/pip-2zQ0_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:08:19 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)) 22:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:08:19 INFO - Running setup.py (path:/tmp/pip-I1aJBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:08:19 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)) 22:08:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:08:19 INFO - Running setup.py (path:/tmp/pip-OAtsMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:08:19 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)) 22:08:19 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)) 22:08:19 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)) 22:08:19 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)) 22:08:19 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)) 22:08:19 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)) 22:08:19 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)) 22:08:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:08:19 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 22:08:19 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 22:08:19 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 22:08:19 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 22:08:20 INFO - Downloading blessings-1.6.tar.gz 22:08:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:08:21 INFO - Installing collected packages: blessings 22:08:21 INFO - Running setup.py install for blessings 22:08:21 INFO - Successfully installed blessings 22:08:21 INFO - Cleaning up... 22:08:21 INFO - Return code: 0 22:08:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:08:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:08:21 INFO - Reading from file tmpfile_stdout 22:08:21 INFO - Current package versions: 22:08:21 INFO - argparse == 1.2.1 22:08:21 INFO - blessings == 1.6 22:08:21 INFO - blobuploader == 1.2.4 22:08:21 INFO - docopt == 0.6.1 22:08:21 INFO - functools32 == 3.2.3-2 22:08:21 INFO - jsonschema == 2.5.1 22:08:21 INFO - manifestparser == 1.1 22:08:21 INFO - mozInstall == 1.13 22:08:21 INFO - mozcrash == 1.0 22:08:21 INFO - mozdebug == 0.1 22:08:21 INFO - mozdevice == 0.51 22:08:21 INFO - mozfile == 1.2 22:08:21 INFO - mozhttpd == 0.7 22:08:21 INFO - mozinfo == 0.10 22:08:21 INFO - mozleak == 0.1 22:08:21 INFO - mozlog == 3.5 22:08:21 INFO - moznetwork == 0.27 22:08:21 INFO - mozprocess == 0.25 22:08:21 INFO - mozprofile == 0.28 22:08:21 INFO - mozrunner == 6.13 22:08:21 INFO - mozscreenshot == 0.1 22:08:21 INFO - mozsystemmonitor == 0.3 22:08:21 INFO - moztest == 0.8 22:08:21 INFO - mozversion == 1.4 22:08:21 INFO - psutil == 3.1.1 22:08:21 INFO - requests == 1.2.3 22:08:21 INFO - wsgiref == 0.1.2 22:08:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:08: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')]} 22:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08: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')]} 22:08:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:21 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 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08:21 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 22:08:21 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 22:08:21 INFO - Using env: {'DISPLAY': ':0', 22:08:21 INFO - 'HOME': '/home/cltbld', 22:08:21 INFO - 'LANG': 'en_US.UTF-8', 22:08:21 INFO - 'LANGUAGE': 'en_US:en', 22:08:21 INFO - 'LOGNAME': 'cltbld', 22:08:21 INFO - 'MAIL': '/var/mail/cltbld', 22:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:21 INFO - 'MOZ_NO_REMOTE': '1', 22:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:21 INFO - 'NO_EM_RESTART': '1', 22:08:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:21 INFO - 'PWD': '/builds/slave/test', 22:08:21 INFO - 'SHELL': '/bin/bash', 22:08:21 INFO - 'SHLVL': '1', 22:08:21 INFO - 'TERM': 'linux', 22:08:21 INFO - 'TMOUT': '86400', 22:08:21 INFO - 'USER': 'cltbld', 22:08:21 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:21 INFO - '_': '/tools/buildbot/bin/python'} 22:08:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:21 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)) 22:08:21 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)) 22:08:21 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)) 22:08:21 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)) 22:08:21 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)) 22:08:21 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)) 22:08:21 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)) 22:08:21 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)) 22:08:21 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)) 22:08:21 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 22:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:08:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:08:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 22:08:22 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)) 22:08:22 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)) 22:08:22 INFO - Installing collected packages: simplejson 22:08:22 INFO - Running setup.py install for simplejson 22:08:22 INFO - building 'simplejson._speedups' extension 22:08:22 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 22:08:23 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 22:08:23 INFO - Successfully installed simplejson 22:08:23 INFO - Cleaning up... 22:08:23 INFO - Return code: 0 22:08:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:08:23 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 0x1c8d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e55b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ffa9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, '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': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', '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 22:08:23 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 22:08:23 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 22:08:23 INFO - Using env: {'DISPLAY': ':0', 22:08:23 INFO - 'HOME': '/home/cltbld', 22:08:23 INFO - 'LANG': 'en_US.UTF-8', 22:08:23 INFO - 'LANGUAGE': 'en_US:en', 22:08:23 INFO - 'LOGNAME': 'cltbld', 22:08:23 INFO - 'MAIL': '/var/mail/cltbld', 22:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:23 INFO - 'MOZ_NO_REMOTE': '1', 22:08:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:23 INFO - 'NO_EM_RESTART': '1', 22:08:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:23 INFO - 'PWD': '/builds/slave/test', 22:08:23 INFO - 'SHELL': '/bin/bash', 22:08:23 INFO - 'SHLVL': '1', 22:08:23 INFO - 'TERM': 'linux', 22:08:23 INFO - 'TMOUT': '86400', 22:08:23 INFO - 'USER': 'cltbld', 22:08:23 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:23 INFO - '_': '/tools/buildbot/bin/python'} 22:08:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:08:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:08:24 INFO - Cleaning up... 22:08:24 INFO - Return code: 0 22:08:24 INFO - Running post-action listener: _resource_record_post_action 22:08:24 INFO - Running post-action listener: _start_resource_monitoring 22:08:24 INFO - Starting resource monitoring. 22:08:24 INFO - [mozharness: 2017-09-23 05:08:24.159045Z] Finished create-virtualenv step (success) 22:08:24 INFO - [mozharness: 2017-09-23 05:08:24.159561Z] Running install step. 22:08:24 INFO - Running pre-action listener: _resource_record_pre_action 22:08:24 INFO - Running main action method: install 22:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:08:24 INFO - Reading from file tmpfile_stdout 22:08:24 INFO - Detecting whether we're running mozinstall >=1.0... 22:08:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:08:24 INFO - Reading from file tmpfile_stdout 22:08:24 INFO - Output received: 22:08:24 INFO - Usage: mozinstall [options] installer 22:08:24 INFO - Options: 22:08:24 INFO - -h, --help show this help message and exit 22:08:24 INFO - -d DEST, --destination=DEST 22:08:24 INFO - Directory to install application into. [default: 22:08:24 INFO - "/builds/slave/test"] 22:08:24 INFO - --app=APP Application being installed. [default: firefox] 22:08:24 INFO - mkdir: /builds/slave/test/build/application 22:08:24 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'] 22:08:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 22:08:42 INFO - Reading from file tmpfile_stdout 22:08:42 INFO - Output received: 22:08:42 INFO - /builds/slave/test/build/application/firefox/firefox 22:08:42 INFO - Running post-action listener: _resource_record_post_action 22:08:42 INFO - [mozharness: 2017-09-23 05:08:42.114405Z] Finished install step (success) 22:08:42 INFO - [mozharness: 2017-09-23 05:08:42.114658Z] Running setup-mitmproxy step. 22:08:42 INFO - Running pre-action listener: _resource_record_pre_action 22:08:42 INFO - Running main action method: setup_mitmproxy 22:08:42 INFO - Skipping: mitmproxy is not required 22:08:42 INFO - Running post-action listener: _resource_record_post_action 22:08:42 INFO - [mozharness: 2017-09-23 05:08:42.115793Z] Finished setup-mitmproxy step (success) 22:08:42 INFO - [mozharness: 2017-09-23 05:08:42.116080Z] Running run-tests step. 22:08:42 INFO - Running pre-action listener: _resource_record_pre_action 22:08:42 INFO - Running pre-action listener: _set_gcov_prefix 22:08:42 INFO - Running main action method: run_tests 22:08:42 WARNING - Try message not found. 22:08:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:08:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:08:42 INFO - Python 2.7.3 22:08:42 INFO - Return code: 0 22:08:42 INFO - grabbing minidump binary from tooltool 22:08:42 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')]} 22:08:42 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 0x1ff7d60>, '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 0x1ff8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ff8ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:08:42 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 22:08:42 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 22:08:42 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 22:08:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:08:42 INFO - Return code: 0 22:08:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:08:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:08:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:08:42 INFO - ENV: RUST_BACKTRACE is now full 22:08:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:08:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:08:42 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/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 22:08:42 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/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-053 --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 22:08:42 INFO - Using env: {'DISPLAY': ':0', 22:08:42 INFO - 'HOME': '/home/cltbld', 22:08:42 INFO - 'LANG': 'en_US.UTF-8', 22:08:42 INFO - 'LANGUAGE': 'en_US:en', 22:08:42 INFO - 'LOGNAME': 'cltbld', 22:08:42 INFO - 'MAIL': '/var/mail/cltbld', 22:08:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:08:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:42 INFO - 'MOZ_NO_REMOTE': '1', 22:08:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:08:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:42 INFO - 'NO_EM_RESTART': '1', 22:08:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:42 INFO - 'PWD': '/builds/slave/test', 22:08:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:08:42 INFO - 'RUST_BACKTRACE': 'full', 22:08:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:08:42 INFO - 'SHELL': '/bin/bash', 22:08:42 INFO - 'SHLVL': '1', 22:08:42 INFO - 'TERM': 'linux', 22:08:42 INFO - 'TMOUT': '86400', 22:08:42 INFO - 'USER': 'cltbld', 22:08:42 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:42 INFO - '_': '/tools/buildbot/bin/python'} 22:08:42 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/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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 22:08:42 INFO - mozversion application_buildid: 20170923034137 22:08:42 INFO - mozversion application_changeset: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 22:08:42 INFO - mozversion application_display_name: Nightly 22:08:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:08:42 INFO - mozversion application_name: Firefox 22:08:42 INFO - mozversion application_remotingname: firefox 22:08:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:08:42 INFO - mozversion application_vendor: Mozilla 22:08:42 INFO - mozversion application_version: 58.0a1 22:08:42 INFO - mozversion platform_buildid: 20170923034137 22:08:42 INFO - mozversion platform_changeset: 9d1fcbc2fdd58011903aa1b987264d58dc1a06b5 22:08:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:08:42 INFO - mozversion platform_version: 58.0a1 22:08:42 INFO - using testdate: 1506143322 22:08:42 INFO - actual date: 1506143322 22:08:42 INFO - starting webserver on 'localhost:52005' 22:08:42 INFO - SUITE-START | Running 2 tests 22:08:42 INFO - TEST-START | dromaeo_css 22:08:42 INFO - Initialising browser for dromaeo_css test... 22:08:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52005/getInfo.html -profile /tmp/tmpo4yQ1t/profile 22:08:42 INFO - TEST-INFO | started process 4947 (/builds/slave/test/build/application/firefox/firefox http://localhost:52005/getInfo.html) 22:08:48 INFO - TEST-INFO | 4947: exit 0 22:08:48 INFO - Browser initialized. 22:08:48 INFO - Running cycle 1/1 for dromaeo_css test... 22:08:48 INFO - Using env: {'DISPLAY': ':0', 22:08:48 INFO - 'HOME': '/home/cltbld', 22:08:48 INFO - 'JSGC_DISABLE_POISONING': '1', 22:08:48 INFO - 'LANG': 'en_US.UTF-8', 22:08:48 INFO - 'LANGUAGE': 'en_US:en', 22:08:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 22:08:48 INFO - 'LOGNAME': 'cltbld', 22:08:48 INFO - 'MAIL': '/var/mail/cltbld', 22:08:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:08:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:08:48 INFO - 'MOZ_CRASHREPORTER': '1', 22:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:08:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 22:08:48 INFO - 'MOZ_NO_REMOTE': '1', 22:08:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:08:48 INFO - 'NO_EM_RESTART': '1', 22:08:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:08:48 INFO - 'PWD': '/builds/slave/test', 22:08:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:08:48 INFO - 'RUST_BACKTRACE': 'full', 22:08:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:08:48 INFO - 'SHELL': '/bin/bash', 22:08:48 INFO - 'SHLVL': '1', 22:08:48 INFO - 'TERM': 'linux', 22:08:48 INFO - 'TMOUT': '86400', 22:08:48 INFO - 'USER': 'cltbld', 22:08:48 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:08:48 INFO - '_': '/tools/buildbot/bin/python'} 22:08:48 INFO - TEST-INFO | started process 5184 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpo4yQ1t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 22:08:49 INFO - PID 5184 | 22:08:49 INFO - PID 5184 | (/builds/slave/test/build/application/firefox/firefox:5234): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 22:08:49 INFO - PID 5184 | 22:08:59 INFO - PID 5184 | RSS: Main: 144592896 22:08:59 INFO - PID 5184 | 22:11:57 INFO - PID 5184 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52005/tests/dromaeo/cssquery-ext.html) 22:11:58 INFO - PID 5184 | RSS: Main: 146337792 22:11:58 INFO - PID 5184 | 22:15:00 INFO - PID 5184 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-ext.html (next: http://localhost:52005/tests/dromaeo/cssquery-jquery.html) 22:15:00 INFO - PID 5184 | RSS: Main: 145981440 22:15:00 INFO - PID 5184 | 22:18:02 INFO - PID 5184 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52005/tests/dromaeo/cssquery-mootools.html) 22:18:02 INFO - PID 5184 | RSS: Main: 148549632 22:18:02 INFO - PID 5184 | 22:21:04 INFO - PID 5184 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52005/tests/dromaeo/cssquery-prototype.html) 22:21:04 INFO - PID 5184 | RSS: Main: 146309120 22:21:04 INFO - PID 5184 | 22:24:07 INFO - PID 5184 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52005/tests/dromaeo/cssquery-yui.html) 22:24:08 INFO - PID 5184 | RSS: Main: 148541440 22:24:08 INFO - PID 5184 | 22:27:10 INFO - PID 5184 | Cycle 1(1): loaded http://localhost:52005/tests/dromaeo/cssquery-yui.html (next: http://localhost:52005/tests/dromaeo/cssquery-dojo.html) 22:27:10 INFO - PID 5184 | RSS: Main: 150155264 22:27:10 INFO - PID 5184 | 22:27:10 INFO - PID 5184 | __start_tp_report 22:27:10 INFO - PID 5184 | _x_x_mozilla_page_load 22:27:10 INFO - PID 5184 | _x_x_mozilla_page_load_details 22:27:10 INFO - PID 5184 | |i|pagename|runs| 22:27:10 INFO - PID 5184 | |0;dojo.html;3829.1958688675377;3853.9036524086896;3862;3883.514560679915;3911.0417947602823;6300.497433716707;6315.557910946236;6353.269374021989;6401.935980640193;6438.9999999999945;6419.614823110616;6433.099366088752;6452.6773661316865;6472.158619379486;6501.707423165969;5595.8880822383735;5710.000749868756;5747.109198074305;5799.478046975772;5808.1287806829105;5713.428657134295;5777.45114214149;5841.269841269841;5845.357010728816;5852.473277405033;5663.235463212475;5702.087665973445;5734.39788822176;5740.425957404268;5750.166225897264;5639.097744360883;5738.713064346766;5764.654120752726;5773.769049238025;5818.010938140526;5702.600817942745;5710.600257981943;5746.339170995348;5784.248047753807;5789.768409263625;6348.269948955883;6385.265694445152;6405.167328247345;6430.292667806538;6445.129907462479;6838.521303508756;6870.519063665545;6930.653467326613;6939.959006149067;6952.478564107712;6802.795916122524;6827.2831352708245;6866.690998905061;6905.965470172668;6934.237233904267;6429.807105786834;6471.191101112361;6474.514411419163;6486.967565162144;6502.057201705727;7137;7197.820054498575;7201.675924583302;7202.063731714844;7217.639118044077;6789.219239787488;6821.93178068213;6839.829004274833;6842.213145488333;6920.8961865572055;6774.695138718769;6892.586444813327;6912.896306555405;6948.930510694829;7037.753678621326;6872.175338959357;6895.896561551581;6904.620245886525;6907.930920690829;6953.79138625842;6866.588004719732;6874.793756187322;6945.305469453116;6955.756548520778;6958.930410695828;5000.574951129195;5027.069992051409;5054.090263752486;5077.238414237894;5080.187170052774;4501.57235062675;4544.750038747835;4554.749488778083;4557.15692596864;4575.9771201143785;6292.842678933063;6298.937010629927;6363.459105975951;6366.872662546816;6370.522210834206;6823.727050917908;6967.93032069683;6992.475564332593;6997.825054373681;7019.89470157948;6254.6872656366995;6283.842903927347;6314.02132669437;6387.457066149429;6413.486921046305;5487.478689524489;5536.52939500147;5584.832455026356;5595.440455954372;5609.242752228397;7151.427885769126;7195.496315257882;7238.6018768968215;7239.782806515813;7243.529170603894;6822.488313376417;6826.965865170741;6875.862482750417;6909.619970901648;7009.894851577128;7166;7227.747028853965;7240.927590724025;7259.2377800330205;7261.636918154177;6763.5941843489545;6864.5881247125335;6889.965550172217;6962.756303529352;6978.092847929736;6829.795106146824;6840.794776156723;6842.452603791784;6843.315668433117;6843.418309443554;6659.034440006269;6735.730570777114;6754.324567543206;6761;6798.354156355138;6617.305182955721;6711.926091825285;6785.117934668461;6792.694328755119;6805.2854450283985;6635.4359879410795;6641;6728.562643428161;6735.090762746966;6740.090087838079;6684.364985326386;6703.798886033427;6761.019652150509;6761.222459417033;6769;6515.099425519398;6540.084388185753;6540.934590654033;6624.238212605612;6627.204735431779 22:27:10 INFO - PID 5184 | |1;ext.html;958.5590628310977;985.788055568053;1010.6159659329454;1010.8685870836795;1014.3102690170683;42100;42808.35787463183;42905.99999999996;42930.785346073266;43057.7847110764;40951.180976380456;42335.57664423355;43341.78329108358;43566.78216608921;43572;8357.373197010233;8466.44968077076;8494.532800695959;8510.106438823927;8543.871841922377;8291.668333266662;8371.623276952552;8399.622017009218;8424.283935865427;8431.114732953043;8389.496630202177;8400.873986890201;8415.537145456998;8425.410221284512;8442.957785211065;8338.749837504885;8347.499150050986;8360.163983601622;8413.326933845277;8413.789655258668;8248.8350232995;8286.958565207196;8296.460730052528;8301.29438997686;8303.626336814797;38798;39190.80404597987;39314.80342598297;39321;39332.606673933464;3713.498677678506;3751.624837516254;3757.887263382103;3768.4724138620613;3778.886633400975;31926.680733192603;32008;32051.51922721161;32082.518762218584;32153.83923080393;5211.24436956643;5430.8913821723345;5447.101228297326;5477.561795056426;5589.636673616202;210478;210482.99999999846;210927;211318;212563;50646;50671.74664126656;50701.74649126731;50703.492965069876;50706.23940640893;63243.683781580796;64357.67821160864;64366.35633643697;64430.677846611405;64455;16290.918545407198;16930.746038809426;17063.06153161588;17072.82927170737;17125.314987400365;13167.60497185086;13233.404496797668;13246.072774905857;13257;13275;16099.356025759073;16101;16102.19489025544;16128.11295378742;16207.918960405123;6225.595336303126;6324.683765811691;6402.167718196604;6417.96791016042;6422.357764223634;1095.3044816541394;1109;1109.2180013090751;1114.1421105748527;1117.1286396610656;25667.35831604225;25752.74247257541;25963.480730385287;25994;26117.73882261153;1179.5458748381934;1207.9818802717966;1214.1440284599337;1220.1641875315438;1220.993895030519;13485.797713034312;13613.591592252249;13683.863161368457;13820.516281930084;13988.160710357412;284685;286573;287055;287957;287995;55823.16265256024;56265.15602265969;59600.403995960354;60520;61039;1481.1187343530582;1522.7335216337096;1525.2450037231863;1525.5880912153877;1533.9846601534066;29960.550591741576;30318.69681303247;30377.392452151274;30624.540631890093;30653.080407587808;41265.79367103145;41400.37899431571;41420.792896035324;41553.58446415497;41702.58297416987;253404;253514;255238;255308;256013;36449.27101458009;36569.45145822762;36666.26667466689;36804.89585312445;36874.63125368712;42917.78541107274;45274;45484;45498;45516;32753.672463275063;33070.83464582758;33273.33453330968;33324;33335.33329333448;33080.172995675304;33103.66896331006;33576.664233357355;33978.66021339755;34134.487982679784;38225.23549529049;38309;38361.23277534378;38629.613703863724;38825.805870970464;32218.67781322157;32464.35071298608;32626.83686581552;32860.67139328576;33531.16172095717;24328.51342973166;25325.493490130462;25390.746092538837;25620.23139305824;25709.871450642626 22:27:10 INFO - PID 5184 | |2;jquery.html;627.3444250757957;640.1997503121096;642.6369101457676;644.4715333426592;646.9159009328787;23297.650535241984;23491;23586;24472.38819029523;24474.632880506786;26104;26506.60240096395;26510.33724156897;26523.469530609374;26558.867205663944;6542.738290468375;6671.166104236982;6814.1482314710665;6855.177378714561;6887.552309099892;6757.7972660820005;6810.6935187916415;6812.897806532906;6814.114165158522;6815.31846815317;6711.832204194885;6728.764493242737;6728.831779205535;6759.7296108155615;6776.356246156632;6641.203055633307;6755.459563234929;6768.153980752406;6789.456843452536;6797.354251346139;6254.405831446005;6290.591111577733;6312.368763123697;6320.431161195468;6336.556441049129;27896.30259243515;29535.70464295351;29648;29680.70319296801;29735.702642973727;2232.3303009097367;2263.219189379661;2263.660450932357;2270.8637481750984;2278.840481166319;19892.403227903185;20176.798232017638;20812.375628731163;20816.895915520327;20847.895760521253;2552.7319631438804;2566.8844901979455;2585.702644195923;2600.4409052053898;2604.361931326818;141215;147809;158309;164361;164746;15369.923150384177;15517.612059698373;15866.60333491672;16296.511104666879;16907;26294.86852565725;26382.472350552882;26412.603810942852;26456.73543264581;26474.20577382643;10650.786984260272;10657.680269591925;10686.786264274671;10785.730356740987;10897.727556811144;9584.568694408768;9627.02645957577;9677.322587418814;9679;9741.172000379906;9929.30494865367;9971;10005.949970250102;10011.449370284705;10038.849417258747;2803.7756979441597;2958.7306026840033;2975.137210209015;2998.505246634281;3069.9386012280074;1021.1371391174446;1040.4069680282319;1041.9843702344472;1042.4839704346246;1057.6351158850184;20281;20756.169753210004;21091.578168436547;21545.56908861814;21733.130674773147;1108.4402376799665;1115.403259256294;1122.1023181454802;1123.9494222759995;1128.0806142993433;11419.543218271176;11577.6526704199;11582.88417115835;11587.246828956091;11734.354610496333;567285;585217;588276;611633;617270;125694.37152814177;127201;128423.35788320999;129517;131201;1195.874433184503;1212.9541108986573;1226.6994586326407;1233.6669099343128;1233.7039110613389;18225.635487290445;18503.259869605066;18603.72094418612;18695.158717858;18733.34433294828;63626.68186659037;64051.679741600994;64947.35052649413;64969.67515162394;65226.347736522024;82164.17835821565;82766.58616706877;82846.58576707078;82954;83089;14900.552983410515;14937.178455185069;15191.316390762222;15320.770188447405;15330.77003844921;23115.422114447272;23231.88384058069;23486.882565587064;23833.761662383848;23968.880155599112;19005;19253.422397328104;19526.8047319525;19532.023398830286;19598.902005489883;16809.91595042017;17258.482245532654;17481.650366992842;17505;17605.559861003578;16488;16624.916875415547;16699.499015029567;19846.305379311656;20121.59756804885;12742.42659080325;13408.798868016793;13445.72265634763;13448.663783405493;13466.057375983588;16218.837811621734;16333.754993674867;18965.810341896955;19288.80711192926;19596 22:27:10 INFO - PID 5184 | |3;mootools.html;1383.474279773686;1440.3230481673609;1443.2206608431447;1444.6243976566102;1450.7243623711495;16636.16819159042;17231.483055508354;17266.740998885027;17272;17273.481795546137;16646.916765416187;16818.99999999997;16839;16850.241739121768;16879.156042197872;2783.151138902634;2940.279631490288;2942.7792915531313;2943.5731818886247;2945.425641999811;2909.6799352071257;2971.153221331921;2971.28689114612;2972.970270297291;2973.04862444018;2874.0946601820465;2980.5827184194122;2984.552317152423;2985.0746268656676;2985.2387641151477;2867.0825335892523;2884.956725649117;2903.4048020155924;2906.258903979493;2906.985465072661;2808.887644494218;2884.740373366386;2910.9126726198247;2915.6938521455154;2917.4748545261846;16901.070441125732;16982.81120321578;16989;17029;17093;8680.262177714902;8994.10058994102;9011.954940225258;9030.322725795591;9038.5932633031;5572.331320241557;5755.971220143914;5893.616914900561;5917.940820591782;5965.791197308116;1931.8261356477844;1963.803619638039;1972.3195497553322;2015.7581090269261;2059.660156074246;31431;31655.20861978446;31739.84130079335;31744.841275793475;31780.841095794374;7165.032720582654;7226;7320.341169294683;7407.740729074457;7424.703011879572;20250.088746006466;20282.391528254175;20328.69506957397;20359.185632574532;20394.592108157754;8148.83702325962;8276.08963014068;8285.047219569706;8286.129956354496;8319.875201871977;7320.63396830167;7549.962250188823;7932.881006784787;7948.880766788503;7953.761387158394;5614.522765564972;5752.28096487939;5773.88452230953;5805.709714514257;5880.26496687914;1632.926518306679;1637.4760076775556;1664.4340924085868;1666.899986000844;1675.5308513616208;975.770693886936;980.7401038724744;984.9556769945226;992.389680346596;1008.8133695266399;8520.57397130141;8618.569071546397;8642.30861531076;8703.303735701129;8831.249343805848;747.6261869065468;767.056520479813;776.9922300777033;781.5935713429099;793.3375631347774;5856.180134781134;5939.4357536034;6124.5712800103565;6144.446999770043;6268.498520118471;24216;24672;24797.380065498506;24943.62584561233;25206.49587008286;23467.530649386918;24126;24382;24768;25041.749582504308;12428.937855310847;12440.81338779919;12504.437300321508;12568.685782855502;12604;9272.768180795534;9292.16370526656;9328.766780830261;9345.392549484262;9429.811403772022;20566.588668226254;20851.716574146758;21426.785732143104;21452.89273553622;21514.569708606054;21776.782232177477;21853.67219491677;21858.234961776263;21875.234366797085;22320.330390088322;12248.571300004458;12309.44607492647;12402.13185077072;12508.687282818002;12538.373081346079;15787.763183552486;16092;16103.194840258175;16109.355625775306;16135.354585816905;11947;11998.76002479963;12162.148649594443;12449.751004980031;12454.937725311316;12321.445534951128;12328.445219965302;12354.320512371905;12403.441845116811;12466.5013399463;10620.362778233331;10880.67357979262;10984.06635435997;10988;10999.61501347449;7372.189059203376;7504.549727016397;7511.436642251962;7512.812179695551;7526.397888169043;7880.448368614371;8101.918980810116;8104.513729176032;8186.345092392475;8214.671413143407 22:27:10 INFO - PID 5184 | |4;prototype.html;561.938186799452;580.2804522392235;580.4195804195807;582.9970850145748;594.7918228619983;6307.905381419282;6337.429631333179;6390.584612000217;6422.614643121416;6443.194600674916;6243.438090571848;6255.124282600428;6271.404216599427;6293.339199384068;6312.905306420407;5145.922811157817;5175.146100893348;5211.45279745627;5238.633295669305;5239.659422137568;5117.257997590347;5135.460776618457;5173.198154286091;5180.352455943007;5276.894462110775;5248.343957005374;5249.632525723201;5252.422233554306;5271.130263506517;5282.23407605897;5128.5897128229835;5200.869978250536;5214.8696282592855;5222.3472065991755;5262.894892013711;4864.781084851154;4906.975465122688;4923.931948581604;4933.925991110136;4943.703377797344;6186.195794546725;6189.845253868644;6222.657753823538;6252.468540174091;6302.1177035215105;6169.475594574466;6191.380861913818;6249.687515624246;6259.780907668255;6263.561550691454;6743.730250790011;6855.760048398332;6857.382835544825;6871.381575658214;6881.449484041264;5280;5470.56235501159;5546.278983732089;5549.1676248563035;5578.358488773762;6766.627835469048;6885.483588730815;6900.654967251618;6933.272006439302;6951.443884489178;5692.57305702074;5712.714364281853;5726.911886741505;5736.368999410061;5755.884882302331;5735.426457354231;5777.8555536111935;5798.768049278066;5812.82561523155;5822.854428639233;6127.509799216052;6180.724216165439;6186.10301506279;6242.65665388408;6263.530235232376;6738;6791;6814.420774234147;6836.923384476906;6871.244163142051;2013.9295124670566;2015.4860510570004;2018.2028098901146;2020.756959774649;2031.339814560274;1462.3711803924202;1508.2232650185163;1539.6535779449757;1543.8070241219848;1556.9922150389402;6130.325664176937;6157.168782214433;6234.438900498886;6257.2491301044165;6281.528885333618;6718.529702920847;6739.797806065826;6755.324467553303;6778.356056174655;6839.179298484214;6131.96934015336;6167.383261673796;6178.505719542516;6216.067589861557;6223.190985171898;3540.079579309398;3558.8042657654078;3572.4284114541547;3587.1749497615697;3603.4955106285142;7045.436365090781;7075.752348667772;7092.680829362691;7112.466565007645;7140.892886606705;6859.9314006860295;6891.75878844238;6983.114559069023;7019.964900175466;7040.295970402919;7052.400545953549;7058.9294107059295;7077.5399599025895;7112.751053713096;7150.856982860418;6775.559588626724;6790.219124800711;6808;6828.760993365208;6884.965575172092;6765.357291057342;6767.6651555213375;6775.186977562724;6805.829854253683;6824.488163387667;7048.189458212371;7055.611941343275;7098.716051358095;7122.786316410516;7139.3574578286125;5691.7438715257085;5748.109043098327;5776.19133321327;5788.392218816964;5804.390538993345;6531.216254049545;6723.428508576826;6821.249662537018;6837.89743153863;6887.690053947685;6454.451371633464;6525.1517302750335;6532.183477065367;6581.769638062837;6586.901196481961;6477.902831457438;6502.934970650233;6511.120998665309;6537.248216455168;6582.091193554007;6454.1609590752905;6478.28738838718;6495;6496.317886622026;6501.512386570931;6310.6529140895855;6602.966985165235;6617.503687223574;6628.933710662832;6688.83277918056;6378.394052564999;6395.20059992501;6473.967630161819;6541.313162117909;6556.63938483388 22:27:10 INFO - PID 5184 | |5;yui.html;953.7329547726639;967.8257913575558;981.2885657898023;1000.9949950250248;1006.8691070160878;20469.48826279344;20789.168433262672;20790.99999999998;20796.58406831866;20797.688034679493;17415.390461333856;19068.61862762744;19573.41279761606;19574.804251957477;19590.412287631392;1036.1969473657919;1040.2510192661273;1053.6364954090827;1053.8050460664763;1054.5676272728183;1056.646023582097;1065.9553637435317;1067.5783065689047;1067.8611780468527;1068.6366635343982;1098.1215027977587;1116.7654792493586;1129.4578602270915;1136.9260998035143;1138.954441822326;1118.2954738514725;1119.8264269038311;1119.960801371948;1120.0591503137364;1121.2487633285718;793.0523024985145;811.5536454949754;814.2549567146054;816.7345612675845;817.5054092274207;20068.79931200684;20133.496662583406;20168.89071101089;20175.293864714666;20205.595888082156;23330.300090997298;23474.17840375596;24030.759692403026;24259.87870060656;24352;17798.733019004725;17996;18147.274109035756;18212.726809097872;18226.27094916202;4061.5735347788354;4081.4490043844;4086.12148388095;4088.509378874554;4096.836126554934;133308;159688;161393;164130.1793491025;164363;18379.26482940694;18445.169967351234;18450;18821.623567528848;18961.620767584573;34497.31005379929;35147.121321967155;35162.47256291158;35199.4720079199;35355.82322088373;9399.483028433502;9442.055794420503;9453.338266321429;9454.905450945402;9474.715758527254;4235.428217190702;4282.507511636201;4296.355546667963;4303.634191093792;4304.096139810675;6083;6191.783287584913;6194.721237544321;6198.6280823150755;6208.627482351073;1230.477047254913;1249.2067537113817;1264.759695657822;1265.367316341829;1270.2315099364937;720.114259460866;734.8860926556392;739.981500462482;751.1136858506906;765.659281619674;13287.468501259842;13645.317734113254;13748.862511374959;14723.852761472463;14870.776938345933;752.6086435053851;766.6473422225838;767.2327672327673;775.8254392761584;776.8795836645329;6554.80335589933;6596.736130554724;6738.528303018742;6771.356721111487;6784.864302714017;150144.2492787551;151850;153138;154613;156057;112228;133189.3340533291;137481.999999998;138808;141866;119861.40069299597;120056;120550.39724801495;121850;121997;14998.175100369584;15293.388264469297;15340.616484587974;15377.69244615079;15445.922770386076;54409.18386224289;54533.45466545284;54859;55133.1730024058;56664;72977.6351118241;74205.62897185479;74636.62681686557;76312.23687763051;76816.61591692005;18553.35063272779;18963.810361896205;19108.044597770335;19253.80746192576;19315.323963661205;21968.780312197312;22113.336599902028;22260.22089226869;22301.330960071224;22409.21567745121;20827.687584686544;20877.26929557458;21001.789982099985;21045.789542104994;21111.894440528315;19956.401307960783;20034.499137521096;20086.79913200849;20201.292954746514;20255.797442025392;20406;20472.385828425173;20612.07245673978;20774.58450831005;20779.376618701463;15226.23868806533;15677.921610391875;15744.370225190863;16101;16164.838351616334;15846.366145354057;16052.839471605135;16233.665642255986;16464.588385289986;16501.09243991592 22:27:10 INFO - PID 5184 | __end_tp_report 22:27:10 INFO - PID 5184 | __start_cc_report 22:27:10 INFO - PID 5184 | _x_x_mozilla_cycle_collect,281 22:27:10 INFO - PID 5184 | __end_cc_report 22:27:10 INFO - PID 5184 | __startTimestamp1506144430292__endTimestamp 22:27:10 INFO - PID 5184 | 22:27:10 INFO - PID 5184 | ------- Summary: start ------- 22:27:10 INFO - PID 5184 | Number of tests: 6 22:27:10 INFO - PID 5184 | 22:27:10 INFO - PID 5184 | [#0] dojo.html Cycles:165 Average:6369.63 Median:6641.00 stddev:770.06 (11.6%) stddev-sans-first:746.18 22:27:10 INFO - PID 5184 | Values: 3829.2 3853.9 3862.0 3883.5 3911.0 6300.5 6315.6 6353.3 6401.9 6439.0 6419.6 6433.1 6452.7 6472.2 6501.7 5595.9 5710.0 5747.1 5799.5 5808.1 5713.4 5777.5 5841.3 5845.4 5852.5 5663.2 5702.1 5734.4 5740.4 5750.2 5639.1 5738.7 5764.7 5773.8 5818.0 5702.6 5710.6 5746.3 5784.2 5789.8 6348.3 6385.3 6405.2 6430.3 6445.1 6838.5 6870.5 6930.7 6940.0 6952.5 6802.8 6827.3 6866.7 6906.0 6934.2 6429.8 6471.2 6474.5 6487.0 6502.1 7137.0 7197.8 7201.7 7202.1 7217.6 6789.2 6821.9 6839.8 6842.2 6920.9 6774.7 6892.6 6912.9 6948.9 7037.8 6872.2 6895.9 6904.6 6907.9 6953.8 6866.6 6874.8 6945.3 6955.8 6958.9 5000.6 5027.1 5054.1 5077.2 5080.2 4501.6 4544.8 4554.7 4557.2 4576.0 6292.8 6298.9 6363.5 6366.9 6370.5 6823.7 6967.9 6992.5 6997.8 7019.9 6254.7 6283.8 6314.0 6387.5 6413.5 5487.5 5536.5 5584.8 5595.4 5609.2 7151.4 7195.5 7238.6 7239.8 7243.5 6822.5 6827.0 6875.9 6909.6 7009.9 7166.0 7227.7 7240.9 7259.2 7261.6 6763.6 6864.6 6890.0 6962.8 6978.1 6829.8 6840.8 6842.5 6843.3 6843.4 6659.0 6735.7 6754.3 6761.0 6798.4 6617.3 6711.9 6785.1 6792.7 6805.3 6635.4 6641.0 6728.6 6735.1 6740.1 6684.4 6703.8 6761.0 6761.2 6769.0 6515.1 6540.1 6540.9 6624.2 6627.2 22:27:10 INFO - PID 5184 | 22:27:10 INFO - PID 5184 | [#1] ext.html Cycles:180 Average:42995.12 Median:36735.58 stddev:65798.59 (179.1%) stddev-sans-first:65907.46 22:27:10 INFO - PID 5184 | Values: 958.6 985.8 1010.6 1010.9 1014.3 42100.0 42808.4 42906.0 42930.8 43057.8 40951.2 42335.6 43341.8 43566.8 43572.0 8357.4 8466.4 8494.5 8510.1 8543.9 8291.7 8371.6 8399.6 8424.3 8431.1 8389.5 8400.9 8415.5 8425.4 8443.0 8338.7 8347.5 8360.2 8413.3 8413.8 8248.8 8287.0 8296.5 8301.3 8303.6 38798.0 39190.8 39314.8 39321.0 39332.6 3713.5 3751.6 3757.9 3768.5 3778.9 31926.7 32008.0 32051.5 32082.5 32153.8 5211.2 5430.9 5447.1 5477.6 5589.6 210478.0 210483.0 210927.0 211318.0 212563.0 50646.0 50671.7 50701.7 50703.5 50706.2 63243.7 64357.7 64366.4 64430.7 64455.0 16290.9 16930.7 17063.1 17072.8 17125.3 13167.6 13233.4 13246.1 13257.0 13275.0 16099.4 16101.0 16102.2 16128.1 16207.9 6225.6 6324.7 6402.2 6418.0 6422.4 1095.3 1109.0 1109.2 1114.1 1117.1 25667.4 25752.7 25963.5 25994.0 26117.7 1179.5 1208.0 1214.1 1220.2 1221.0 13485.8 13613.6 13683.9 13820.5 13988.2 284685.0 286573.0 287055.0 287957.0 287995.0 55823.2 56265.2 59600.4 60520.0 61039.0 1481.1 1522.7 1525.2 1525.6 1534.0 29960.6 30318.7 30377.4 30624.5 30653.1 41265.8 41400.4 41420.8 41553.6 41702.6 253404.0 253514.0 255238.0 255308.0 256013.0 36449.3 36569.5 36666.3 36804.9 36874.6 42917.8 45274.0 45484.0 45498.0 45516.0 32753.7 33070.8 33273.3 33324.0 33335.3 33080.2 33103.7 33576.7 33978.7 34134.5 38225.2 38309.0 38361.2 38629.6 38825.8 32218.7 32464.4 32626.8 32860.7 33531.2 24328.5 25325.5 25390.7 25620.2 25709.9 22:27:10 INFO - PID 5184 | 22:27:10 INFO - PID 5184 | [#2] jquery.html Cycles:180 Average:39415.75 Median:2267.26 stddev:99741.17 (4399.2%) stddev-sans-first:99978.45 22:27:10 INFO - PID 5184 | Values: 627.3 640.2 642.6 644.5 646.9 23297.7 23491.0 23586.0 24472.4 24474.6 26104.0 26506.6 26510.3 26523.5 26558.9 6542.7 6671.2 6814.1 6855.2 6887.6 6757.8 6810.7 6812.9 6814.1 6815.3 6711.8 6728.8 6728.8 6759.7 6776.4 6641.2 6755.5 6768.2 6789.5 6797.4 6254.4 6290.6 6312.4 6320.4 6336.6 27896.3 29535.7 29648.0 29680.7 29735.7 2232.3 2263.2 2263.7 2270.9 2278.8 19892.4 20176.8 20812.4 20816.9 20847.9 2552.7 2566.9 2585.7 2600.4 2604.4 141215.0 147809.0 158309.0 164361.0 164746.0 15369.9 15517.6 15866.6 16296.5 16907.0 26294.9 26382.5 26412.6 26456.7 26474.2 10650.8 10657.7 10686.8 10785.7 10897.7 9584.6 9627.0 9677.3 9679.0 9741.2 9929.3 9971.0 10005.9 10011.4 10038.8 2803.8 2958.7 2975.1 2998.5 3069.9 1021.1 1040.4 1042.0 1042.5 1057.6 20281.0 20756.2 21091.6 21545.6 21733.1 1108.4 1115.4 1122.1 1123.9 1128.1 11419.5 11577.7 11582.9 11587.2 11734.4 567285.0 585217.0 588276.0 611633.0 617270.0 125694.4 127201.0 128423.4 129517.0 131201.0 1195.9 1213.0 1226.7 1233.7 1233.7 18225.6 18503.3 18603.7 18695.2 18733.3 63626.7 64051.7 64947.4 64969.7 65226.3 82164.2 82766.6 82846.6 82954.0 83089.0 14900.6 14937.2 15191.3 15320.8 15330.8 23115.4 23231.9 23486.9 23833.8 23968.9 19005.0 19253.4 19526.8 19532.0 19598.9 16809.9 17258.5 17481.7 17505.0 17605.6 16488.0 16624.9 16699.5 19846.3 20121.6 12742.4 13408.8 13445.7 13448.7 13466.1 16218.8 16333.8 18965.8 19288.8 19596.0 22:27:10 INFO - PID 5184 | 22:27:10 INFO - PID 5184 | [#3] mootools.html Cycles:180 Average:10459.35 Median:2884.85 stddev:7786.38 (269.9%) stddev-sans-first:7778.36 22:27:10 INFO - PID 5184 | Values: 1383.5 1440.3 1443.2 1444.6 1450.7 16636.2 17231.5 17266.7 17272.0 17273.5 16646.9 16819.0 16839.0 16850.2 16879.2 2783.2 2940.3 2942.8 2943.6 2945.4 2909.7 2971.2 2971.3 2973.0 2973.0 2874.1 2980.6 2984.6 2985.1 2985.2 2867.1 2885.0 2903.4 2906.3 2907.0 2808.9 2884.7 2910.9 2915.7 2917.5 16901.1 16982.8 16989.0 17029.0 17093.0 8680.3 8994.1 9012.0 9030.3 9038.6 5572.3 5756.0 5893.6 5917.9 5965.8 1931.8 1963.8 1972.3 2015.8 2059.7 31431.0 31655.2 31739.8 31744.8 31780.8 7165.0 7226.0 7320.3 7407.7 7424.7 20250.1 20282.4 20328.7 20359.2 20394.6 8148.8 8276.1 8285.0 8286.1 8319.9 7320.6 7550.0 7932.9 7948.9 7953.8 5614.5 5752.3 5773.9 5805.7 5880.3 1632.9 1637.5 1664.4 1666.9 1675.5 975.8 980.7 985.0 992.4 1008.8 8520.6 8618.6 8642.3 8703.3 8831.2 747.6 767.1 777.0 781.6 793.3 5856.2 5939.4 6124.6 6144.4 6268.5 24216.0 24672.0 24797.4 24943.6 25206.5 23467.5 24126.0 24382.0 24768.0 25041.7 12428.9 12440.8 12504.4 12568.7 12604.0 9272.8 9292.2 9328.8 9345.4 9429.8 20566.6 20851.7 21426.8 21452.9 21514.6 21776.8 21853.7 21858.2 21875.2 22320.3 12248.6 12309.4 12402.1 12508.7 12538.4 15787.8 16092.0 16103.2 16109.4 16135.4 11947.0 11998.8 12162.1 12449.8 12454.9 12321.4 12328.4 12354.3 12403.4 12466.5 10620.4 10880.7 10984.1 10988.0 10999.6 7372.2 7504.5 7511.4 7512.8 7526.4 7880.4 8101.9 8104.5 8186.3 8214.7 22:27:10 INFO - PID 5184 | 22:27:10 INFO - PID 5184 | [#4] prototype.html Cycles:180 Average:5789.28 Median:6276.47 stddev:1537.83 (24.5%) stddev-sans-first:1491.26 22:27:10 INFO - PID 5184 | Values: 561.9 580.3 580.4 583.0 594.8 6307.9 6337.4 6390.6 6422.6 6443.2 6243.4 6255.1 6271.4 6293.3 6312.9 5145.9 5175.1 5211.5 5238.6 5239.7 5117.3 5135.5 5173.2 5180.4 5276.9 5248.3 5249.6 5252.4 5271.1 5282.2 5128.6 5200.9 5214.9 5222.3 5262.9 4864.8 4907.0 4923.9 4933.9 4943.7 6186.2 6189.8 6222.7 6252.5 6302.1 6169.5 6191.4 6249.7 6259.8 6263.6 6743.7 6855.8 6857.4 6871.4 6881.4 5280.0 5470.6 5546.3 5549.2 5578.4 6766.6 6885.5 6900.7 6933.3 6951.4 5692.6 5712.7 5726.9 5736.4 5755.9 5735.4 5777.9 5798.8 5812.8 5822.9 6127.5 6180.7 6186.1 6242.7 6263.5 6738.0 6791.0 6814.4 6836.9 6871.2 2013.9 2015.5 2018.2 2020.8 2031.3 1462.4 1508.2 1539.7 1543.8 1557.0 6130.3 6157.2 6234.4 6257.2 6281.5 6718.5 6739.8 6755.3 6778.4 6839.2 6132.0 6167.4 6178.5 6216.1 6223.2 3540.1 3558.8 3572.4 3587.2 3603.5 7045.4 7075.8 7092.7 7112.5 7140.9 6859.9 6891.8 6983.1 7020.0 7040.3 7052.4 7058.9 7077.5 7112.8 7150.9 6775.6 6790.2 6808.0 6828.8 6885.0 6765.4 6767.7 6775.2 6805.8 6824.5 7048.2 7055.6 7098.7 7122.8 7139.4 5691.7 5748.1 5776.2 5788.4 5804.4 6531.2 6723.4 6821.2 6837.9 6887.7 6454.5 6525.2 6532.2 6581.8 6586.9 6477.9 6502.9 6511.1 6537.2 6582.1 6454.2 6478.3 6495.0 6496.3 6501.5 6310.7 6603.0 6617.5 6628.9 6688.8 6378.4 6395.2 6474.0 6541.3 6556.6 22:27:10 INFO - PID 5184 | 22:27:10 INFO - PID 5184 | [#5] yui.html Cycles:180 Average:29246.59 Median:20110.15 stddev:42702.46 (212.3%) stddev-sans-first:42769.40 22:27:10 INFO - PID 5184 | Values: 953.7 967.8 981.3 1001.0 1006.9 20469.5 20789.2 20791.0 20796.6 20797.7 17415.4 19068.6 19573.4 19574.8 19590.4 1036.2 1040.3 1053.6 1053.8 1054.6 1056.6 1066.0 1067.6 1067.9 1068.6 1098.1 1116.8 1129.5 1136.9 1139.0 1118.3 1119.8 1120.0 1120.1 1121.2 793.1 811.6 814.3 816.7 817.5 20068.8 20133.5 20168.9 20175.3 20205.6 23330.3 23474.2 24030.8 24259.9 24352.0 17798.7 17996.0 18147.3 18212.7 18226.3 4061.6 4081.4 4086.1 4088.5 4096.8 133308.0 159688.0 161393.0 164130.2 164363.0 18379.3 18445.2 18450.0 18821.6 18961.6 34497.3 35147.1 35162.5 35199.5 35355.8 9399.5 9442.1 9453.3 9454.9 9474.7 4235.4 4282.5 4296.4 4303.6 4304.1 6083.0 6191.8 6194.7 6198.6 6208.6 1230.5 1249.2 1264.8 1265.4 1270.2 720.1 734.9 740.0 751.1 765.7 13287.5 13645.3 13748.9 14723.9 14870.8 752.6 766.6 767.2 775.8 776.9 6554.8 6596.7 6738.5 6771.4 6784.9 150144.2 151850.0 153138.0 154613.0 156057.0 112228.0 133189.3 137482.0 138808.0 141866.0 119861.4 120056.0 120550.4 121850.0 121997.0 14998.2 15293.4 15340.6 15377.7 15445.9 54409.2 54533.5 54859.0 55133.2 56664.0 72977.6 74205.6 74636.6 76312.2 76816.6 18553.4 18963.8 19108.0 19253.8 19315.3 21968.8 22113.3 22260.2 22301.3 22409.2 20827.7 20877.3 21001.8 21045.8 21111.9 19956.4 20034.5 20086.8 20201.3 20255.8 20406.0 20472.4 20612.1 20774.6 20779.4 15226.2 15677.9 15744.4 16101.0 16164.8 15846.4 16052.8 16233.7 16464.6 16501.1 22:27:10 INFO - PID 5184 | -------- Summary: end -------- 22:27:10 INFO - PID 5184 | 22:27:11 INFO - TEST-INFO | 5184: exit 0 22:27:11 INFO - TEST-OK | dromaeo_css | took 1108545ms 22:27:11 INFO - TEST-START | kraken 22:27:11 INFO - Initialising browser for kraken test... 22:27:11 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52005/getInfo.html -profile /tmp/tmp8i5Egv/profile 22:27:11 INFO - TEST-INFO | started process 5563 (/builds/slave/test/build/application/firefox/firefox http://localhost:52005/getInfo.html) 22:27:16 INFO - TEST-INFO | 5563: exit 0 22:27:16 INFO - Browser initialized. 22:27:16 INFO - Running cycle 1/1 for kraken test... 22:27:16 INFO - Using env: {'DISPLAY': ':0', 22:27:16 INFO - 'HOME': '/home/cltbld', 22:27:16 INFO - 'JSGC_DISABLE_POISONING': '1', 22:27:16 INFO - 'LANG': 'en_US.UTF-8', 22:27:16 INFO - 'LANGUAGE': 'en_US:en', 22:27:16 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 22:27:16 INFO - 'LOGNAME': 'cltbld', 22:27:16 INFO - 'MAIL': '/var/mail/cltbld', 22:27:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:27:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:27:16 INFO - 'MOZ_CRASHREPORTER': '1', 22:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:27:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 22:27:16 INFO - 'MOZ_NO_REMOTE': '1', 22:27:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:27:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:27:16 INFO - 'NO_EM_RESTART': '1', 22:27:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:27:16 INFO - 'PWD': '/builds/slave/test', 22:27:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:27:16 INFO - 'RUST_BACKTRACE': 'full', 22:27:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 22:27:16 INFO - 'SHELL': '/bin/bash', 22:27:16 INFO - 'SHLVL': '1', 22:27:16 INFO - 'TERM': 'linux', 22:27:16 INFO - 'TMOUT': '86400', 22:27:16 INFO - 'USER': 'cltbld', 22:27:16 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742', 22:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:27:16 INFO - '_': '/tools/buildbot/bin/python'} 22:27:16 INFO - TEST-INFO | started process 5802 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8i5Egv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 22:27:17 INFO - PID 5802 | 22:27:17 INFO - PID 5802 | (/builds/slave/test/build/application/firefox/firefox:5851): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 22:27:17 INFO - PID 5802 | 22:27:27 INFO - PID 5802 | RSS: Main: 145117184 22:27:27 INFO - PID 5802 | 22:27:29 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52005/tests/kraken/driver.html?testName=audio-beat-detection) 22:27:30 INFO - PID 5802 | RSS: Main: 151777280 22:27:30 INFO - PID 5802 | 22:27:33 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52005/tests/kraken/driver.html?testName=audio-dft) 22:27:33 INFO - PID 5802 | RSS: Main: 152510464 22:27:33 INFO - PID 5802 | 22:27:36 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52005/tests/kraken/driver.html?testName=audio-fft) 22:27:37 INFO - PID 5802 | RSS: Main: 147890176 22:27:37 INFO - PID 5802 | 22:27:39 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52005/tests/kraken/driver.html?testName=audio-oscillator) 22:27:40 INFO - PID 5802 | RSS: Main: 149143552 22:27:40 INFO - PID 5802 | 22:27:42 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52005/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:27:42 INFO - PID 5802 | RSS: Main: 148774912 22:27:42 INFO - PID 5802 | 22:27:45 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52005/tests/kraken/driver.html?testName=imaging-darkroom) 22:27:46 INFO - PID 5802 | RSS: Main: 148733952 22:27:46 INFO - PID 5802 | 22:27:49 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52005/tests/kraken/driver.html?testName=imaging-desaturate) 22:27:50 INFO - PID 5802 | RSS: Main: 150110208 22:27:50 INFO - PID 5802 | 22:27:53 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52005/tests/kraken/driver.html?testName=json-parse-financial) 22:27:54 INFO - PID 5802 | RSS: Main: 152010752 22:27:54 INFO - PID 5802 | 22:27:55 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52005/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:27:56 INFO - PID 5802 | RSS: Main: 152092672 22:27:56 INFO - PID 5802 | 22:27:57 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:27:58 INFO - PID 5802 | RSS: Main: 152076288 22:27:58 INFO - PID 5802 | 22:28:00 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:28:00 INFO - PID 5802 | RSS: Main: 148762624 22:28:00 INFO - PID 5802 | 22:28:03 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:28:03 INFO - PID 5802 | RSS: Main: 148660224 22:28:03 INFO - PID 5802 | 22:28:06 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:28:07 INFO - PID 5802 | RSS: Main: 149073920 22:28:07 INFO - PID 5802 | 22:28:08 INFO - PID 5802 | Cycle 1(1): loaded http://localhost:52005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52005/tests/kraken/driver.html?testName=ai-astar) 22:28:09 INFO - PID 5802 | RSS: Main: 150880256 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | __start_tp_report 22:28:09 INFO - PID 5802 | _x_x_mozilla_page_load 22:28:09 INFO - PID 5802 | _x_x_mozilla_page_load_details 22:28:09 INFO - PID 5802 | |i|pagename|runs| 22:28:09 INFO - PID 5802 | |0;ai-astar;97;104;99;105;105;106;106;97;105;106 22:28:09 INFO - PID 5802 | |1;audio-beat-detection;112;111;112;113;113;113;143;115;114;113 22:28:09 INFO - PID 5802 | |2;audio-dft;161;163;161;162;162;161;162;163;161;164 22:28:09 INFO - PID 5802 | |3;audio-fft;74;73;73;74;75;74;76;77;75;75 22:28:09 INFO - PID 5802 | |4;audio-oscillator;78;78;86;78;77;77;77;77;78;77 22:28:09 INFO - PID 5802 | |5;imaging-gaussian-blur;114;113;114;115;113;114;114;114;113;114 22:28:09 INFO - PID 5802 | |6;imaging-darkroom;150;149;152;149;150;149;148;149;149;149 22:28:09 INFO - PID 5802 | |7;imaging-desaturate;94;94;93;93;94;94;94;94;94;94 22:28:09 INFO - PID 5802 | |8;json-parse-financial;43;43;45;44;46;43;46;45;44;43 22:28:09 INFO - PID 5802 | |9;json-stringify-tinderbox;37;36;37;37;36;36;37;36;39;39 22:28:09 INFO - PID 5802 | |10;stanford-crypto-aes;74;73;72;69;70;70;73;72;81;72 22:28:09 INFO - PID 5802 | |11;stanford-crypto-ccm;105;104;104;107;104;111;114;106;108;101 22:28:09 INFO - PID 5802 | |12;stanford-crypto-pbkdf2;154;154;158;150;149;148;153;151;152;156 22:28:09 INFO - PID 5802 | |13;stanford-crypto-sha256-iterative;65;58;61;60;69;61;75;59;61;59 22:28:09 INFO - PID 5802 | __end_tp_report 22:28:09 INFO - PID 5802 | __start_cc_report 22:28:09 INFO - PID 5802 | _x_x_mozilla_cycle_collect,708 22:28:09 INFO - PID 5802 | __end_cc_report 22:28:09 INFO - PID 5802 | __startTimestamp1506144489200__endTimestamp 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | ------- Summary: start ------- 22:28:09 INFO - PID 5802 | Number of tests: 14 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#0] ai-astar Cycles:10 Average:103.00 Median:106.00 stddev:3.77 (3.6%) stddev-sans-first:3.32 22:28:09 INFO - PID 5802 | Values: 97.0 104.0 99.0 105.0 105.0 106.0 106.0 97.0 105.0 106.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#1] audio-beat-detection Cycles:10 Average:115.90 Median:113.00 stddev:9.59 (8.5%) stddev-sans-first:10.06 22:28:09 INFO - PID 5802 | Values: 112.0 111.0 112.0 113.0 113.0 113.0 143.0 115.0 114.0 113.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 22:28:09 INFO - PID 5802 | Values: 161.0 163.0 161.0 162.0 162.0 161.0 162.0 163.0 161.0 164.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#3] audio-fft Cycles:10 Average:74.60 Median:75.00 stddev:1.26 (1.7%) stddev-sans-first:1.32 22:28:09 INFO - PID 5802 | Values: 74.0 73.0 73.0 74.0 75.0 74.0 76.0 77.0 75.0 75.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:78.00 stddev:2.75 (3.5%) stddev-sans-first:2.92 22:28:09 INFO - PID 5802 | Values: 78.0 78.0 86.0 78.0 77.0 77.0 77.0 77.0 78.0 77.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#5] imaging-gaussian-blur Cycles:10 Average:113.80 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 22:28:09 INFO - PID 5802 | Values: 114.0 113.0 114.0 115.0 113.0 114.0 114.0 114.0 113.0 114.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#6] imaging-darkroom Cycles:10 Average:149.40 Median:149.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 22:28:09 INFO - PID 5802 | Values: 150.0 149.0 152.0 149.0 150.0 149.0 148.0 149.0 149.0 149.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:28:09 INFO - PID 5802 | Values: 94.0 94.0 93.0 93.0 94.0 94.0 94.0 94.0 94.0 94.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#8] json-parse-financial Cycles:10 Average:44.20 Median:44.50 stddev:1.23 (2.8%) stddev-sans-first:1.22 22:28:09 INFO - PID 5802 | Values: 43.0 43.0 45.0 44.0 46.0 43.0 46.0 45.0 44.0 43.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:1.15 (3.1%) stddev-sans-first:1.22 22:28:09 INFO - PID 5802 | Values: 37.0 36.0 37.0 37.0 36.0 36.0 37.0 36.0 39.0 39.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:72.50 stddev:3.34 (4.6%) stddev-sans-first:3.50 22:28:09 INFO - PID 5802 | Values: 74.0 73.0 72.0 69.0 70.0 70.0 73.0 72.0 81.0 72.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#11] stanford-crypto-ccm Cycles:10 Average:106.40 Median:106.50 stddev:3.81 (3.6%) stddev-sans-first:4.00 22:28:09 INFO - PID 5802 | Values: 105.0 104.0 104.0 107.0 104.0 111.0 114.0 106.0 108.0 101.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:153.50 stddev:3.14 (2.0%) stddev-sans-first:3.28 22:28:09 INFO - PID 5802 | Values: 154.0 154.0 158.0 150.0 149.0 148.0 153.0 151.0 152.0 156.0 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.80 Median:61.00 stddev:5.39 (8.8%) stddev-sans-first:5.66 22:28:09 INFO - PID 5802 | Values: 65.0 58.0 61.0 60.0 69.0 61.0 75.0 59.0 61.0 59.0 22:28:09 INFO - PID 5802 | -------- Summary: end -------- 22:28:09 INFO - PID 5802 | 22:28:09 INFO - PID 5802 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 22:28:09 INFO - TEST-INFO | 5802: exit 0 22:28:09 INFO - TEST-OK | kraken | took 58777ms 22:28:09 INFO - SUITE-END | took 1167s 22:28:09 INFO - Completed test suite (00:19:27) 22:28:09 INFO - javascript benchmark 22:28:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9210.328913509851, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3829.1958688675377, 3853.9036524086896, 3862.0, 3883.514560679915, 3911.0417947602823, 6300.497433716707, 6315.557910946236, 6353.269374021989, 6401.935980640193, 6438.9999999999945, 6419.614823110616, 6433.099366088752, 6452.6773661316865, 6472.158619379486, 6501.707423165969, 5595.8880822383735, 5710.000749868756, 5747.109198074305, 5799.478046975772, 5808.1287806829105, 5713.428657134295, 5777.45114214149, 5841.269841269841, 5845.357010728816, 5852.473277405033, 5663.235463212475, 5702.087665973445, 5734.39788822176, 5740.425957404268, 5750.166225897264, 5639.097744360883, 5738.713064346766, 5764.654120752726, 5773.769049238025, 5818.010938140526, 5702.600817942745, 5710.600257981943, 5746.339170995348, 5784.248047753807, 5789.768409263625, 6348.269948955883, 6385.265694445152, 6405.167328247345, 6430.292667806538, 6445.129907462479, 6838.521303508756, 6870.519063665545, 6930.653467326613, 6939.959006149067, 6952.478564107712, 6802.795916122524, 6827.2831352708245, 6866.690998905061, 6905.965470172668, 6934.237233904267, 6429.807105786834, 6471.191101112361, 6474.514411419163, 6486.967565162144, 6502.057201705727, 7137.0, 7197.820054498575, 7201.675924583302, 7202.063731714844, 7217.639118044077, 6789.219239787488, 6821.93178068213, 6839.829004274833, 6842.213145488333, 6920.8961865572055, 6774.695138718769, 6892.586444813327, 6912.896306555405, 6948.930510694829, 7037.753678621326, 6872.175338959357, 6895.896561551581, 6904.620245886525, 6907.930920690829, 6953.79138625842, 6866.588004719732, 6874.793756187322, 6945.305469453116, 6955.756548520778, 6958.930410695828, 5000.574951129195, 5027.069992051409, 5054.090263752486, 5077.238414237894, 5080.187170052774, 4501.57235062675, 4544.750038747835, 4554.749488778083, 4557.15692596864, 4575.9771201143785, 6292.842678933063, 6298.937010629927, 6363.459105975951, 6366.872662546816, 6370.522210834206, 6823.727050917908, 6967.93032069683, 6992.475564332593, 6997.825054373681, 7019.89470157948, 6254.6872656366995, 6283.842903927347, 6314.02132669437, 6387.457066149429, 6413.486921046305, 5487.478689524489, 5536.52939500147, 5584.832455026356, 5595.440455954372, 5609.242752228397, 7151.427885769126, 7195.496315257882, 7238.6018768968215, 7239.782806515813, 7243.529170603894, 6822.488313376417, 6826.965865170741, 6875.862482750417, 6909.619970901648, 7009.894851577128, 7166.0, 7227.747028853965, 7240.927590724025, 7259.2377800330205, 7261.636918154177, 6763.5941843489545, 6864.5881247125335, 6889.965550172217, 6962.756303529352, 6978.092847929736, 6829.795106146824, 6840.794776156723, 6842.452603791784, 6843.315668433117, 6843.418309443554, 6659.034440006269, 6735.730570777114, 6754.324567543206, 6761.0, 6798.354156355138, 6617.305182955721, 6711.926091825285, 6785.117934668461, 6792.694328755119, 6805.2854450283985, 6635.4359879410795, 6641.0, 6728.562643428161, 6735.090762746966, 6740.090087838079, 6684.364985326386, 6703.798886033427, 6761.019652150509, 6761.222459417033, 6769.0, 6515.099425519398, 6540.084388185753, 6540.934590654033, 6624.238212605612, 6627.204735431779], "value": 6315.918911422518, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [958.5590628310977, 985.788055568053, 1010.6159659329454, 1010.8685870836795, 1014.3102690170683, 42100.0, 42808.35787463183, 42905.99999999996, 42930.785346073266, 43057.7847110764, 40951.180976380456, 42335.57664423355, 43341.78329108358, 43566.78216608921, 43572.0, 8357.373197010233, 8466.44968077076, 8494.532800695959, 8510.106438823927, 8543.871841922377, 8291.668333266662, 8371.623276952552, 8399.622017009218, 8424.283935865427, 8431.114732953043, 8389.496630202177, 8400.873986890201, 8415.537145456998, 8425.410221284512, 8442.957785211065, 8338.749837504885, 8347.499150050986, 8360.163983601622, 8413.326933845277, 8413.789655258668, 8248.8350232995, 8286.958565207196, 8296.460730052528, 8301.29438997686, 8303.626336814797, 38798.0, 39190.80404597987, 39314.80342598297, 39321.0, 39332.606673933464, 3713.498677678506, 3751.624837516254, 3757.887263382103, 3768.4724138620613, 3778.886633400975, 31926.680733192603, 32008.0, 32051.51922721161, 32082.518762218584, 32153.83923080393, 5211.24436956643, 5430.8913821723345, 5447.101228297326, 5477.561795056426, 5589.636673616202, 210478.0, 210482.99999999846, 210927.0, 211318.0, 212563.0, 50646.0, 50671.74664126656, 50701.74649126731, 50703.492965069876, 50706.23940640893, 63243.683781580796, 64357.67821160864, 64366.35633643697, 64430.677846611405, 64455.0, 16290.918545407198, 16930.746038809426, 17063.06153161588, 17072.82927170737, 17125.314987400365, 13167.60497185086, 13233.404496797668, 13246.072774905857, 13257.0, 13275.0, 16099.356025759073, 16101.0, 16102.19489025544, 16128.11295378742, 16207.918960405123, 6225.595336303126, 6324.683765811691, 6402.167718196604, 6417.96791016042, 6422.357764223634, 1095.3044816541394, 1109.0, 1109.2180013090751, 1114.1421105748527, 1117.1286396610656, 25667.35831604225, 25752.74247257541, 25963.480730385287, 25994.0, 26117.73882261153, 1179.5458748381934, 1207.9818802717966, 1214.1440284599337, 1220.1641875315438, 1220.993895030519, 13485.797713034312, 13613.591592252249, 13683.863161368457, 13820.516281930084, 13988.160710357412, 284685.0, 286573.0, 287055.0, 287957.0, 287995.0, 55823.16265256024, 56265.15602265969, 59600.403995960354, 60520.0, 61039.0, 1481.1187343530582, 1522.7335216337096, 1525.2450037231863, 1525.5880912153877, 1533.9846601534066, 29960.550591741576, 30318.69681303247, 30377.392452151274, 30624.540631890093, 30653.080407587808, 41265.79367103145, 41400.37899431571, 41420.792896035324, 41553.58446415497, 41702.58297416987, 253404.0, 253514.0, 255238.0, 255308.0, 256013.0, 36449.27101458009, 36569.45145822762, 36666.26667466689, 36804.89585312445, 36874.63125368712, 42917.78541107274, 45274.0, 45484.0, 45498.0, 45516.0, 32753.672463275063, 33070.83464582758, 33273.33453330968, 33324.0, 33335.33329333448, 33080.172995675304, 33103.66896331006, 33576.664233357355, 33978.66021339755, 34134.487982679784, 38225.23549529049, 38309.0, 38361.23277534378, 38629.613703863724, 38825.805870970464, 32218.67781322157, 32464.35071298608, 32626.83686581552, 32860.67139328576, 33531.16172095717, 24328.51342973166, 25325.493490130462, 25390.746092538837, 25620.23139305824, 25709.871450642626], "value": 18768.058169276155, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [627.3444250757957, 640.1997503121096, 642.6369101457676, 644.4715333426592, 646.9159009328787, 23297.650535241984, 23491.0, 23586.0, 24472.38819029523, 24474.632880506786, 26104.0, 26506.60240096395, 26510.33724156897, 26523.469530609374, 26558.867205663944, 6542.738290468375, 6671.166104236982, 6814.1482314710665, 6855.177378714561, 6887.552309099892, 6757.7972660820005, 6810.6935187916415, 6812.897806532906, 6814.114165158522, 6815.31846815317, 6711.832204194885, 6728.764493242737, 6728.831779205535, 6759.7296108155615, 6776.356246156632, 6641.203055633307, 6755.459563234929, 6768.153980752406, 6789.456843452536, 6797.354251346139, 6254.405831446005, 6290.591111577733, 6312.368763123697, 6320.431161195468, 6336.556441049129, 27896.30259243515, 29535.70464295351, 29648.0, 29680.70319296801, 29735.702642973727, 2232.3303009097367, 2263.219189379661, 2263.660450932357, 2270.8637481750984, 2278.840481166319, 19892.403227903185, 20176.798232017638, 20812.375628731163, 20816.895915520327, 20847.895760521253, 2552.7319631438804, 2566.8844901979455, 2585.702644195923, 2600.4409052053898, 2604.361931326818, 141215.0, 147809.0, 158309.0, 164361.0, 164746.0, 15369.923150384177, 15517.612059698373, 15866.60333491672, 16296.511104666879, 16907.0, 26294.86852565725, 26382.472350552882, 26412.603810942852, 26456.73543264581, 26474.20577382643, 10650.786984260272, 10657.680269591925, 10686.786264274671, 10785.730356740987, 10897.727556811144, 9584.568694408768, 9627.02645957577, 9677.322587418814, 9679.0, 9741.172000379906, 9929.30494865367, 9971.0, 10005.949970250102, 10011.449370284705, 10038.849417258747, 2803.7756979441597, 2958.7306026840033, 2975.137210209015, 2998.505246634281, 3069.9386012280074, 1021.1371391174446, 1040.4069680282319, 1041.9843702344472, 1042.4839704346246, 1057.6351158850184, 20281.0, 20756.169753210004, 21091.578168436547, 21545.56908861814, 21733.130674773147, 1108.4402376799665, 1115.403259256294, 1122.1023181454802, 1123.9494222759995, 1128.0806142993433, 11419.543218271176, 11577.6526704199, 11582.88417115835, 11587.246828956091, 11734.354610496333, 567285.0, 585217.0, 588276.0, 611633.0, 617270.0, 125694.37152814177, 127201.0, 128423.35788320999, 129517.0, 131201.0, 1195.874433184503, 1212.9541108986573, 1226.6994586326407, 1233.6669099343128, 1233.7039110613389, 18225.635487290445, 18503.259869605066, 18603.72094418612, 18695.158717858, 18733.34433294828, 63626.68186659037, 64051.679741600994, 64947.35052649413, 64969.67515162394, 65226.347736522024, 82164.17835821565, 82766.58616706877, 82846.58576707078, 82954.0, 83089.0, 14900.552983410515, 14937.178455185069, 15191.316390762222, 15320.770188447405, 15330.77003844921, 23115.422114447272, 23231.88384058069, 23486.882565587064, 23833.761662383848, 23968.880155599112, 19005.0, 19253.422397328104, 19526.8047319525, 19532.023398830286, 19598.902005489883, 16809.91595042017, 17258.482245532654, 17481.650366992842, 17505.0, 17605.559861003578, 16488.0, 16624.916875415547, 16699.499015029567, 19846.305379311656, 20121.59756804885, 12742.42659080325, 13408.798868016793, 13445.72265634763, 13448.663783405493, 13466.057375983588, 16218.837811621734, 16333.754993674867, 18965.810341896955, 19288.80711192926, 19596.0], "value": 12677.696168207569, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.474279773686, 1440.3230481673609, 1443.2206608431447, 1444.6243976566102, 1450.7243623711495, 16636.16819159042, 17231.483055508354, 17266.740998885027, 17272.0, 17273.481795546137, 16646.916765416187, 16818.99999999997, 16839.0, 16850.241739121768, 16879.156042197872, 2783.151138902634, 2940.279631490288, 2942.7792915531313, 2943.5731818886247, 2945.425641999811, 2909.6799352071257, 2971.153221331921, 2971.28689114612, 2972.970270297291, 2973.04862444018, 2874.0946601820465, 2980.5827184194122, 2984.552317152423, 2985.0746268656676, 2985.2387641151477, 2867.0825335892523, 2884.956725649117, 2903.4048020155924, 2906.258903979493, 2906.985465072661, 2808.887644494218, 2884.740373366386, 2910.9126726198247, 2915.6938521455154, 2917.4748545261846, 16901.070441125732, 16982.81120321578, 16989.0, 17029.0, 17093.0, 8680.262177714902, 8994.10058994102, 9011.954940225258, 9030.322725795591, 9038.5932633031, 5572.331320241557, 5755.971220143914, 5893.616914900561, 5917.940820591782, 5965.791197308116, 1931.8261356477844, 1963.803619638039, 1972.3195497553322, 2015.7581090269261, 2059.660156074246, 31431.0, 31655.20861978446, 31739.84130079335, 31744.841275793475, 31780.841095794374, 7165.032720582654, 7226.0, 7320.341169294683, 7407.740729074457, 7424.703011879572, 20250.088746006466, 20282.391528254175, 20328.69506957397, 20359.185632574532, 20394.592108157754, 8148.83702325962, 8276.08963014068, 8285.047219569706, 8286.129956354496, 8319.875201871977, 7320.63396830167, 7549.962250188823, 7932.881006784787, 7948.880766788503, 7953.761387158394, 5614.522765564972, 5752.28096487939, 5773.88452230953, 5805.709714514257, 5880.26496687914, 1632.926518306679, 1637.4760076775556, 1664.4340924085868, 1666.899986000844, 1675.5308513616208, 975.770693886936, 980.7401038724744, 984.9556769945226, 992.389680346596, 1008.8133695266399, 8520.57397130141, 8618.569071546397, 8642.30861531076, 8703.303735701129, 8831.249343805848, 747.6261869065468, 767.056520479813, 776.9922300777033, 781.5935713429099, 793.3375631347774, 5856.180134781134, 5939.4357536034, 6124.5712800103565, 6144.446999770043, 6268.498520118471, 24216.0, 24672.0, 24797.380065498506, 24943.62584561233, 25206.49587008286, 23467.530649386918, 24126.0, 24382.0, 24768.0, 25041.749582504308, 12428.937855310847, 12440.81338779919, 12504.437300321508, 12568.685782855502, 12604.0, 9272.768180795534, 9292.16370526656, 9328.766780830261, 9345.392549484262, 9429.811403772022, 20566.588668226254, 20851.716574146758, 21426.785732143104, 21452.89273553622, 21514.569708606054, 21776.782232177477, 21853.67219491677, 21858.234961776263, 21875.234366797085, 22320.330390088322, 12248.571300004458, 12309.44607492647, 12402.13185077072, 12508.687282818002, 12538.373081346079, 15787.763183552486, 16092.0, 16103.194840258175, 16109.355625775306, 16135.354585816905, 11947.0, 11998.76002479963, 12162.148649594443, 12449.751004980031, 12454.937725311316, 12321.445534951128, 12328.445219965302, 12354.320512371905, 12403.441845116811, 12466.5013399463, 10620.362778233331, 10880.67357979262, 10984.06635435997, 10988.0, 10999.61501347449, 7372.189059203376, 7504.549727016397, 7511.436642251962, 7512.812179695551, 7526.397888169043, 7880.448368614371, 8101.918980810116, 8104.513729176032, 8186.345092392475, 8214.671413143407], "value": 7293.601334042136, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [561.938186799452, 580.2804522392235, 580.4195804195807, 582.9970850145748, 594.7918228619983, 6307.905381419282, 6337.429631333179, 6390.584612000217, 6422.614643121416, 6443.194600674916, 6243.438090571848, 6255.124282600428, 6271.404216599427, 6293.339199384068, 6312.905306420407, 5145.922811157817, 5175.146100893348, 5211.45279745627, 5238.633295669305, 5239.659422137568, 5117.257997590347, 5135.460776618457, 5173.198154286091, 5180.352455943007, 5276.894462110775, 5248.343957005374, 5249.632525723201, 5252.422233554306, 5271.130263506517, 5282.23407605897, 5128.5897128229835, 5200.869978250536, 5214.8696282592855, 5222.3472065991755, 5262.894892013711, 4864.781084851154, 4906.975465122688, 4923.931948581604, 4933.925991110136, 4943.703377797344, 6186.195794546725, 6189.845253868644, 6222.657753823538, 6252.468540174091, 6302.1177035215105, 6169.475594574466, 6191.380861913818, 6249.687515624246, 6259.780907668255, 6263.561550691454, 6743.730250790011, 6855.760048398332, 6857.382835544825, 6871.381575658214, 6881.449484041264, 5280.0, 5470.56235501159, 5546.278983732089, 5549.1676248563035, 5578.358488773762, 6766.627835469048, 6885.483588730815, 6900.654967251618, 6933.272006439302, 6951.443884489178, 5692.57305702074, 5712.714364281853, 5726.911886741505, 5736.368999410061, 5755.884882302331, 5735.426457354231, 5777.8555536111935, 5798.768049278066, 5812.82561523155, 5822.854428639233, 6127.509799216052, 6180.724216165439, 6186.10301506279, 6242.65665388408, 6263.530235232376, 6738.0, 6791.0, 6814.420774234147, 6836.923384476906, 6871.244163142051, 2013.9295124670566, 2015.4860510570004, 2018.2028098901146, 2020.756959774649, 2031.339814560274, 1462.3711803924202, 1508.2232650185163, 1539.6535779449757, 1543.8070241219848, 1556.9922150389402, 6130.325664176937, 6157.168782214433, 6234.438900498886, 6257.2491301044165, 6281.528885333618, 6718.529702920847, 6739.797806065826, 6755.324467553303, 6778.356056174655, 6839.179298484214, 6131.96934015336, 6167.383261673796, 6178.505719542516, 6216.067589861557, 6223.190985171898, 3540.079579309398, 3558.8042657654078, 3572.4284114541547, 3587.1749497615697, 3603.4955106285142, 7045.436365090781, 7075.752348667772, 7092.680829362691, 7112.466565007645, 7140.892886606705, 6859.9314006860295, 6891.75878844238, 6983.114559069023, 7019.964900175466, 7040.295970402919, 7052.400545953549, 7058.9294107059295, 7077.5399599025895, 7112.751053713096, 7150.856982860418, 6775.559588626724, 6790.219124800711, 6808.0, 6828.760993365208, 6884.965575172092, 6765.357291057342, 6767.6651555213375, 6775.186977562724, 6805.829854253683, 6824.488163387667, 7048.189458212371, 7055.611941343275, 7098.716051358095, 7122.786316410516, 7139.3574578286125, 5691.7438715257085, 5748.109043098327, 5776.19133321327, 5788.392218816964, 5804.390538993345, 6531.216254049545, 6723.428508576826, 6821.249662537018, 6837.89743153863, 6887.690053947685, 6454.451371633464, 6525.1517302750335, 6532.183477065367, 6581.769638062837, 6586.901196481961, 6477.902831457438, 6502.934970650233, 6511.120998665309, 6537.248216455168, 6582.091193554007, 6454.1609590752905, 6478.28738838718, 6495.0, 6496.317886622026, 6501.512386570931, 6310.6529140895855, 6602.966985165235, 6617.503687223574, 6628.933710662832, 6688.83277918056, 6378.394052564999, 6395.20059992501, 6473.967630161819, 6541.313162117909, 6556.63938483388], "value": 5359.85320847599, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [953.7329547726639, 967.8257913575558, 981.2885657898023, 1000.9949950250248, 1006.8691070160878, 20469.48826279344, 20789.168433262672, 20790.99999999998, 20796.58406831866, 20797.688034679493, 17415.390461333856, 19068.61862762744, 19573.41279761606, 19574.804251957477, 19590.412287631392, 1036.1969473657919, 1040.2510192661273, 1053.6364954090827, 1053.8050460664763, 1054.5676272728183, 1056.646023582097, 1065.9553637435317, 1067.5783065689047, 1067.8611780468527, 1068.6366635343982, 1098.1215027977587, 1116.7654792493586, 1129.4578602270915, 1136.9260998035143, 1138.954441822326, 1118.2954738514725, 1119.8264269038311, 1119.960801371948, 1120.0591503137364, 1121.2487633285718, 793.0523024985145, 811.5536454949754, 814.2549567146054, 816.7345612675845, 817.5054092274207, 20068.79931200684, 20133.496662583406, 20168.89071101089, 20175.293864714666, 20205.595888082156, 23330.300090997298, 23474.17840375596, 24030.759692403026, 24259.87870060656, 24352.0, 17798.733019004725, 17996.0, 18147.274109035756, 18212.726809097872, 18226.27094916202, 4061.5735347788354, 4081.4490043844, 4086.12148388095, 4088.509378874554, 4096.836126554934, 133308.0, 159688.0, 161393.0, 164130.1793491025, 164363.0, 18379.26482940694, 18445.169967351234, 18450.0, 18821.623567528848, 18961.620767584573, 34497.31005379929, 35147.121321967155, 35162.47256291158, 35199.4720079199, 35355.82322088373, 9399.483028433502, 9442.055794420503, 9453.338266321429, 9454.905450945402, 9474.715758527254, 4235.428217190702, 4282.507511636201, 4296.355546667963, 4303.634191093792, 4304.096139810675, 6083.0, 6191.783287584913, 6194.721237544321, 6198.6280823150755, 6208.627482351073, 1230.477047254913, 1249.2067537113817, 1264.759695657822, 1265.367316341829, 1270.2315099364937, 720.114259460866, 734.8860926556392, 739.981500462482, 751.1136858506906, 765.659281619674, 13287.468501259842, 13645.317734113254, 13748.862511374959, 14723.852761472463, 14870.776938345933, 752.6086435053851, 766.6473422225838, 767.2327672327673, 775.8254392761584, 776.8795836645329, 6554.80335589933, 6596.736130554724, 6738.528303018742, 6771.356721111487, 6784.864302714017, 150144.2492787551, 151850.0, 153138.0, 154613.0, 156057.0, 112228.0, 133189.3340533291, 137481.999999998, 138808.0, 141866.0, 119861.40069299597, 120056.0, 120550.39724801495, 121850.0, 121997.0, 14998.175100369584, 15293.388264469297, 15340.616484587974, 15377.69244615079, 15445.922770386076, 54409.18386224289, 54533.45466545284, 54859.0, 55133.1730024058, 56664.0, 72977.6351118241, 74205.62897185479, 74636.62681686557, 76312.23687763051, 76816.61591692005, 18553.35063272779, 18963.810361896205, 19108.044597770335, 19253.80746192576, 19315.323963661205, 21968.780312197312, 22113.336599902028, 22260.22089226869, 22301.330960071224, 22409.21567745121, 20827.687584686544, 20877.26929557458, 21001.789982099985, 21045.789542104994, 21111.894440528315, 19956.401307960783, 20034.499137521096, 20086.79913200849, 20201.292954746514, 20255.797442025392, 20406.0, 20472.385828425173, 20612.07245673978, 20774.58450831005, 20779.376618701463, 15226.23868806533, 15677.921610391875, 15744.370225190863, 16101.0, 16164.838351616334, 15846.366145354057, 16052.839471605135, 16233.665642255986, 16464.588385289986, 16501.09243991592], "value": 10390.443648778744, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1366.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 104.0, 99.0, 105.0, 105.0, 106.0, 106.0, 97.0, 105.0, 106.0], "value": 103.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 112.0, 113.0, 113.0, 113.0, 143.0, 115.0, 114.0, 113.0], "value": 115.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 161.0, 162.0, 162.0, 161.0, 162.0, 163.0, 161.0, 164.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 74.0, 75.0, 74.0, 76.0, 77.0, 75.0, 75.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 86.0, 78.0, 77.0, 77.0, 77.0, 77.0, 78.0, 77.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 115.0, 113.0, 114.0, 114.0, 114.0, 113.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 152.0, 149.0, 150.0, 149.0, 148.0, 149.0, 149.0, 149.0], "value": 149.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [43.0, 43.0, 45.0, 44.0, 46.0, 43.0, 46.0, 45.0, 44.0, 43.0], "value": 44.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 36.0, 37.0, 37.0, 36.0, 36.0, 37.0, 36.0, 39.0, 39.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 72.0, 69.0, 70.0, 70.0, 73.0, 72.0, 81.0, 72.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 104.0, 104.0, 107.0, 104.0, 111.0, 114.0, 106.0, 108.0, 101.0], "value": 106.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 158.0, 150.0, 149.0, 148.0, 153.0, 151.0, 152.0, 156.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 58.0, 61.0, 60.0, 69.0, 61.0, 75.0, 59.0, 61.0, 59.0], "value": 62.8, "unit": "score"}]}]} 22:28:09 INFO - Return code: 0 22:28:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:28:09 INFO - # TBPL SUCCESS # 22:28:09 INFO - Running post-action listener: _package_coverage_data 22:28:09 INFO - Running post-action listener: _resource_record_post_action 22:28:09 INFO - [mozharness: 2017-09-23 05:28:09.989118Z] Finished run-tests step (success) 22:28:09 INFO - Running post-run listener: _resource_record_post_run 22:28:10 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 331075584; Read time: 16; Write time: 175812 22:28:10 INFO - TinderboxPrint: CPU usage
13.1% 22:28:10 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:28:10 INFO - TinderboxPrint: I/O write bytes / time
331,075,584 / 175,812 22:28:10 INFO - TinderboxPrint: CPU idle
8,212.7 (86.9%) 22:28:10 INFO - TinderboxPrint: CPU user
1,187.6 (12.6%) 22:28:10 INFO - TinderboxPrint: Swap in / out
0 / 0 22:28:10 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12558336; Read time: 0; Write time: 1012 22:28:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 22:28:10 INFO - run-tests - Wall time: 1168s; CPU: 13.0%; Read bytes: 4096; Write bytes: 317902848; Read time: 16; Write time: 174800 22:28:10 INFO - Running post-run listener: _upload_blobber_files 22:28:10 INFO - Blob upload gear active. 22:28:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:28:10 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 22:28:10 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'] 22:28:10 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 22:28:11 INFO - (blobuploader) - INFO - Open directory for files ... 22:28:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:28:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:28:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:12 INFO - (blobuploader) - INFO - Done attempting. 22:28:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:28:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:28:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:12 INFO - (blobuploader) - INFO - Done attempting. 22:28:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:28:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:28:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:14 INFO - (blobuploader) - INFO - Done attempting. 22:28:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:28:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:28:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:28:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:28:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:28:17 INFO - (blobuploader) - INFO - Done attempting. 22:28:17 INFO - (blobuploader) - INFO - Iteration through files over. 22:28:17 INFO - Return code: 0 22:28:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:28:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:28:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d6151664144a33a6e001827350e7d592875b41cabd94f37e7ade457f513b9c37354ef0f23dd954f9826c0a3313895541077f60b55fc021e577724b011a56e89", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47b16e59681d4b4e4c4f21362e56f5e2378ea5f37308e1868c1128caf44c3c68c0449dcaa963c86d440849ba3925a84af4d002901bd67cf9bd632d9824732af4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bda6ec51021b1dd7a227bb52190dae8fef9b7056f56cf2a12a8837e5a7d6ffb5313dae63f12fff8dbe3c00af51df2ac0d58e2b3f9a7379c56c393c7132302a1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c531e5bde1e02232c3f139a5261805bb6bd603a693ec8700df34ecf81fab4e0ea421525379575caee9185fcc4745f3db204014d0fbcebbe30b615b61331e4d2"} 22:28:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:28:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:28:17 INFO - Contents: 22:28:17 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d6151664144a33a6e001827350e7d592875b41cabd94f37e7ade457f513b9c37354ef0f23dd954f9826c0a3313895541077f60b55fc021e577724b011a56e89", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47b16e59681d4b4e4c4f21362e56f5e2378ea5f37308e1868c1128caf44c3c68c0449dcaa963c86d440849ba3925a84af4d002901bd67cf9bd632d9824732af4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bda6ec51021b1dd7a227bb52190dae8fef9b7056f56cf2a12a8837e5a7d6ffb5313dae63f12fff8dbe3c00af51df2ac0d58e2b3f9a7379c56c393c7132302a1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c531e5bde1e02232c3f139a5261805bb6bd603a693ec8700df34ecf81fab4e0ea421525379575caee9185fcc4745f3db204014d0fbcebbe30b615b61331e4d2"} 22:28:17 INFO - Running post-run listener: copy_logs_to_upload_dir 22:28:17 INFO - Copying logs to upload dir... 22:28:17 INFO - mkdir: /builds/slave/test/build/upload/logs 22:28:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.354564 ========= master_lag: 0.26 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-22 22:28:17.848505) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 22:28:17.856114) ========= 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=920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d6151664144a33a6e001827350e7d592875b41cabd94f37e7ade457f513b9c37354ef0f23dd954f9826c0a3313895541077f60b55fc021e577724b011a56e89", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47b16e59681d4b4e4c4f21362e56f5e2378ea5f37308e1868c1128caf44c3c68c0449dcaa963c86d440849ba3925a84af4d002901bd67cf9bd632d9824732af4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bda6ec51021b1dd7a227bb52190dae8fef9b7056f56cf2a12a8837e5a7d6ffb5313dae63f12fff8dbe3c00af51df2ac0d58e2b3f9a7379c56c393c7132302a1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c531e5bde1e02232c3f139a5261805bb6bd603a693ec8700df34ecf81fab4e0ea421525379575caee9185fcc4745f3db204014d0fbcebbe30b615b61331e4d2"} build_url:https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011411 build_url: 'https://queue.taskcluster.net/v1/task/JtWjNlDyQMG39ykVhnRwEg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d6151664144a33a6e001827350e7d592875b41cabd94f37e7ade457f513b9c37354ef0f23dd954f9826c0a3313895541077f60b55fc021e577724b011a56e89", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/47b16e59681d4b4e4c4f21362e56f5e2378ea5f37308e1868c1128caf44c3c68c0449dcaa963c86d440849ba3925a84af4d002901bd67cf9bd632d9824732af4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2bda6ec51021b1dd7a227bb52190dae8fef9b7056f56cf2a12a8837e5a7d6ffb5313dae63f12fff8dbe3c00af51df2ac0d58e2b3f9a7379c56c393c7132302a1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c531e5bde1e02232c3f139a5261805bb6bd603a693ec8700df34ecf81fab4e0ea421525379575caee9185fcc4745f3db204014d0fbcebbe30b615b61331e4d2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-22 22:28:17.892598) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 22:28:17.892960) ========= 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=920470bf432669ad7e3a5387000001ca-1506140274.94631-1530131742 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004594 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-22 22:28:17.946693) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 22:28:17.947006) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-22 22:28:17.947328) ========= ========= Total master_lag: 0.43 =========