builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-003 starttime: 1506536070.62 results: success (0) revision: 6100472d3aa833dff22a4edb0934fe600f43ddb8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:30.623392) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:30.624312) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:30.624980) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:30.673608) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:30.674102) ========= 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=7de08804244eb36560b44971000001c3-1506535348.39616-1440211374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-27 11:14:30-- 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% 226M=0s 2017-09-27 11:14:31 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.222745 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:30.948062) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:30.948621) ========= 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=7de08804244eb36560b44971000001c3-1506535348.39616-1440211374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017545 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:31.000613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 11:14:31.000990) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6100472d3aa833dff22a4edb0934fe600f43ddb8 --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 6100472d3aa833dff22a4edb0934fe600f43ddb8 --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=7de08804244eb36560b44971000001c3-1506535348.39616-1440211374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-27 11:14:31,240 truncating revision to first 12 chars 2017-09-27 11:14:31,240 Setting DEBUG logging. 2017-09-27 11:14:31,240 attempt 1/10 2017-09-27 11:14:31,240 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6100472d3aa8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-27 11:14:32,463 unpacking tar archive at: mozilla-central-6100472d3aa8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.620737 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-27 11:14:32.638870) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:32.639219) ========= script_repo_revision: 6100472d3aa833dff22a4edb0934fe600f43ddb8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:32.639653) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:32.639969) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-27 11:14:32.653497) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-27 11:14:32.653998) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=7de08804244eb36560b44971000001c3-1506535348.39616-1440211374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:14:33 INFO - MultiFileLogger online at 20170927 11:14:33 in /builds/slave/test 11:14:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:14:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:14:33 INFO - {'append_to_log': False, 11:14:33 INFO - 'base_work_dir': '/builds/slave/test', 11:14:33 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:14:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:14:33 INFO - 'branch': 'Firefox-Non-PGO', 11:14:33 INFO - 'buildbot_json_path': 'buildprops.json', 11:14:33 INFO - 'code_coverage': False, 11:14:33 INFO - 'config_files': ('talos/linux_config.py',), 11:14:33 INFO - 'default_actions': ('clobber', 11:14:33 INFO - 'read-buildbot-config', 11:14:33 INFO - 'download-and-extract', 11:14:33 INFO - 'populate-webroot', 11:14:33 INFO - 'create-virtualenv', 11:14:33 INFO - 'install', 11:14:33 INFO - 'setup-mitmproxy', 11:14:33 INFO - 'run-tests'), 11:14:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:14:33 INFO - 'disable_ccov_upload': False, 11:14:33 INFO - 'disable_stylo': False, 11:14:33 INFO - 'download_minidump_stackwalk': True, 11:14:33 INFO - 'download_symbols': 'ondemand', 11:14:33 INFO - 'enable_stylo': False, 11:14:33 INFO - 'enable_webrender': False, 11:14:33 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:14:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:14:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:14:33 INFO - 'gecko_profile': False, 11:14:33 INFO - 'gecko_profile_interval': 0, 11:14:33 INFO - 'installer_path': 'installer.exe', 11:14:33 INFO - 'log_level': 'info', 11:14:33 INFO - 'log_name': 'talos', 11:14:33 INFO - 'log_to_console': True, 11:14:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:14:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:14:33 INFO - 'opt_config_files': (), 11:14:33 INFO - 'pip_index': False, 11:14:33 INFO - 'suite': 'dromaeojs-e10s', 11:14:33 INFO - 'system_bits': '32', 11:14:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:14:33 INFO - 'title': 'talos-linux64-ix-003', 11:14:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:14:33 INFO - 'use_talos_json': True, 11:14:33 INFO - 'verify': 'False', 11:14:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:14:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:14:33 INFO - 'work_dir': 'build'} 11:14:33 INFO - [mozharness: 2017-09-27 18:14:33.122966Z] Running clobber step. 11:14:33 INFO - Running pre-action listener: _resource_record_pre_action 11:14:33 INFO - Running main action method: clobber 11:14:33 INFO - rmtree: /builds/slave/test/build 11:14:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:14:34 INFO - Running post-action listener: _resource_record_post_action 11:14:34 INFO - [mozharness: 2017-09-27 18:14:34.110762Z] Finished clobber step (success) 11:14:34 INFO - [mozharness: 2017-09-27 18:14:34.110859Z] Running read-buildbot-config step. 11:14:34 INFO - Running pre-action listener: _resource_record_pre_action 11:14:34 INFO - Running main action method: read_buildbot_config 11:14:34 INFO - Using buildbot properties: 11:14:34 INFO - { 11:14:34 INFO - "project": "", 11:14:34 INFO - "product": "firefox", 11:14:34 INFO - "who": "ryanvm@gmail.com", 11:14:34 INFO - "installer_path": "public/build/target.tar.bz2", 11:14:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:14:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:14:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:14:34 INFO - "stage_platform": "linux64", 11:14:34 INFO - "basedir": "/builds/slave/test", 11:14:34 INFO - "buildnumber": 1180, 11:14:34 INFO - "platform": "ubuntu64_hw", 11:14:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:14:34 INFO - "slavebuilddir": "test", 11:14:34 INFO - "taskId": "RjuZxnikSlK1lq0k4i31_w", 11:14:34 INFO - "branch": "mozilla-central", 11:14:34 INFO - "script_repo_revision": "production", 11:14:34 INFO - "revision": "6100472d3aa833dff22a4edb0934fe600f43ddb8", 11:14:34 INFO - "slavename": "talos-linux64-ix-003", 11:14:34 INFO - "repo_path": "mozilla-central" 11:14:34 INFO - } 11:14:34 INFO - Finding installer, test and symbols from parent task. 11:14:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RjuZxnikSlK1lq0k4i31_w'}, attempt #1 11:14:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RjuZxnikSlK1lq0k4i31_w'}, attempt #1 11:14:35 INFO - Task dependencies: bGmhYXXHRU6bnifb1xql2Q 11:14:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q'}, attempt #1 11:14:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.tar.bz2 11:14:35 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.test_packages.json 11:14:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.crashreporter-symbols.zip 11:14:35 INFO - Running post-action listener: _resource_record_post_action 11:14:35 INFO - [mozharness: 2017-09-27 18:14:35.544552Z] Finished read-buildbot-config step (success) 11:14:35 INFO - [mozharness: 2017-09-27 18:14:35.544769Z] Running download-and-extract step. 11:14:35 INFO - Running pre-action listener: _resource_record_pre_action 11:14:35 INFO - Running main action method: download_and_extract 11:14:35 INFO - mkdir: /builds/slave/test/build/tests 11:14:35 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')]} 11:14:35 INFO - trying https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.test_packages.json 11:14:35 INFO - Downloading https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:14:36 INFO - Downloaded 989 bytes. 11:14:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:14:36 INFO - Using the following test package requirements: 11:14:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:14:36 INFO - u'common': [u'target.common.tests.zip'], 11:14:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:14:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:14:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:14:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:14:36 INFO - u'mozbase': [u'target.common.tests.zip'], 11:14:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:14:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:14:36 INFO - u'web-platform': [u'target.common.tests.zip', 11:14:36 INFO - u'target.web-platform.tests.tar.gz'], 11:14:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:14:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 11:14:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.common.tests.zip 11:14:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:14:36 INFO - Fetch https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.common.tests.zip into memory 11:14:38 INFO - Content-Length response header: 54946033 11:14:38 INFO - Bytes received: 54946033 11:14:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.talos.tests.zip 11:14:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:14:50 INFO - Fetch https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.talos.tests.zip into memory 11:14:50 INFO - Content-Length response header: 14513665 11:14:50 INFO - Bytes received: 14513665 11:14:51 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')]} 11:14:51 INFO - trying https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.tar.bz2 11:14:51 INFO - Downloading https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:14:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:14:53 INFO - Downloaded 61680180 bytes. 11:14:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.tar.bz2 11:14:53 INFO - mkdir: /builds/slave/test/properties 11:14:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:14:53 INFO - Writing to file /builds/slave/test/properties/build_url 11:14:53 INFO - Contents: 11:14:53 INFO - build_url:https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.tar.bz2 11:14:53 INFO - Running post-action listener: _resource_record_post_action 11:14:53 INFO - Running post-action listener: find_tests_for_verification 11:14:53 INFO - Running post-action listener: set_extra_try_arguments 11:14:53 INFO - [mozharness: 2017-09-27 18:14:53.294049Z] Finished download-and-extract step (success) 11:14:53 INFO - [mozharness: 2017-09-27 18:14:53.294147Z] Running populate-webroot step. 11:14:53 INFO - Running pre-action listener: _resource_record_pre_action 11:14:53 INFO - Running main action method: populate_webroot 11:14:53 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:14:53 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['tresize', 11:14:53 INFO - 'tcanvasmark']}, 11:14:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:14:53 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['dromaeo_css', 11:14:53 INFO - 'kraken']}, 11:14:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:14:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:14:53 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:14:53 INFO - 'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:14:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:14:53 INFO - 'tests': ['damp', 'tps']}, 11:14:53 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:14:53 INFO - 'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['damp', 'tps']}, 11:14:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:14:53 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['dromaeo_dom']}, 11:14:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:14:53 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['basic_compositor_video', 11:14:53 INFO - 'glvideo']}, 11:14:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:14:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:14:53 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:14:53 INFO - 'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['ts_paint_webext', 11:14:53 INFO - 'tp5o_webext']}, 11:14:53 INFO - 'other-e10s': {'tests': ['a11yr', 11:14:53 INFO - 'ts_paint', 11:14:53 INFO - 'tpaint', 11:14:53 INFO - 'sessionrestore', 11:14:53 INFO - 'sessionrestore_many_windows', 11:14:53 INFO - 'sessionrestore_no_auto_restore', 11:14:53 INFO - 'tabpaint']}, 11:14:53 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['a11yr', 11:14:53 INFO - 'ts_paint', 11:14:53 INFO - 'tpaint', 11:14:53 INFO - 'sessionrestore', 11:14:53 INFO - 'sessionrestore_many_windows', 11:14:53 INFO - 'sessionrestore_no_auto_restore', 11:14:53 INFO - 'tabpaint']}, 11:14:53 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 11:14:53 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 11:14:53 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['perf_reftest_singletons']}, 11:14:53 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['perf_reftest']}, 11:14:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:14:53 INFO - 'tsvgr_opacity', 11:14:53 INFO - 'tart', 11:14:53 INFO - 'tscrollx', 11:14:53 INFO - 'tsvg_static']}, 11:14:53 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['tsvgx', 11:14:53 INFO - 'tsvgr_opacity', 11:14:53 INFO - 'tart', 11:14:53 INFO - 'tscrollx', 11:14:53 INFO - 'tsvg_static']}, 11:14:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:14:53 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:14:53 INFO - 'talos_options': ['--disable-stylo'], 11:14:53 INFO - 'tests': ['tp5o']}, 11:14:53 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:14:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:14:53 INFO - 'talos_options': ['--mitmproxy', 11:14:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:14:53 INFO - '--firstNonBlankPaint'], 11:14:53 INFO - 'tests': ['tp6_google', 11:14:53 INFO - 'tp6_youtube', 11:14:53 INFO - 'tp6_amazon', 11:14:53 INFO - 'tp6_facebook']}, 11:14:53 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:14:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:14:53 INFO - 'talos_options': ['--disable-stylo', 11:14:53 INFO - '--mitmproxy', 11:14:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:14:53 INFO - '--firstNonBlankPaint'], 11:14:53 INFO - 'tests': ['tp6_google', 11:14:53 INFO - 'tp6_youtube', 11:14:53 INFO - 'tp6_amazon', 11:14:53 INFO - 'tp6_facebook']}, 11:14:53 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:14:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 11:14:53 INFO - 'talos_options': ['--stylo-threads=1', 11:14:53 INFO - '--mitmproxy', 11:14:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:14:53 INFO - '--firstNonBlankPaint'], 11:14:53 INFO - 'tests': ['tp6_google', 11:14:53 INFO - 'tp6_youtube', 11:14:53 INFO - 'tp6_amazon', 11:14:53 INFO - 'tp6_facebook']}, 11:14:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:14:53 INFO - 'talos_options': ['--xperf_path', 11:14:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:14:53 INFO - 'tests': ['tp5n']}, 11:14:53 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 11:14:53 INFO - 'talos_options': ['--disable-stylo', 11:14:53 INFO - '--xperf_path', 11:14:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:14:53 INFO - 'tests': ['tp5n']}}} 11:14:53 INFO - Running post-action listener: _resource_record_post_action 11:14:53 INFO - [mozharness: 2017-09-27 18:14:53.309762Z] Finished populate-webroot step (success) 11:14:53 INFO - [mozharness: 2017-09-27 18:14:53.309851Z] Running create-virtualenv step. 11:14:53 INFO - Running pre-action listener: _resource_record_pre_action 11:14:53 INFO - Running main action method: create_virtualenv 11:14:53 INFO - Creating virtualenv /builds/slave/test/build/venv 11:14:53 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:14:53 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:14:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:14:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:14:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:14:54 INFO - Installing distribute.............................................................................................................................................................................................done. 11:14:54 INFO - Installing pip...............done. 11:14:54 INFO - Return code: 0 11:14:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:14:54 INFO - Reading from file tmpfile_stdout 11:14:54 INFO - Output received: 11:14:54 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:14:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:14:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:14:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:14:54 INFO - Using env: {'DISPLAY': ':0', 11:14:54 INFO - 'HOME': '/home/cltbld', 11:14:54 INFO - 'LANG': 'en_US.UTF-8', 11:14:54 INFO - 'LANGUAGE': 'en_US:en', 11:14:54 INFO - 'LOGNAME': 'cltbld', 11:14:54 INFO - 'MAIL': '/var/mail/cltbld', 11:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:54 INFO - 'MOZ_NO_REMOTE': '1', 11:14:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:14:54 INFO - 'NO_EM_RESTART': '1', 11:14:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:54 INFO - 'PWD': '/builds/slave/test', 11:14:54 INFO - 'SHELL': '/bin/bash', 11:14:54 INFO - 'SHLVL': '1', 11:14:54 INFO - 'TERM': 'linux', 11:14:54 INFO - 'TMOUT': '86400', 11:14:54 INFO - 'USER': 'cltbld', 11:14:54 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:54 INFO - '_': '/tools/buildbot/bin/python'} 11:14:56 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:14:56 INFO - Downloading/unpacking pip>=1.5 11:14:56 INFO - Running setup.py egg_info for package pip 11:14:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:14:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:14:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:14:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:14:56 INFO - Installing collected packages: pip 11:14:56 INFO - Found existing installation: pip 1.1 11:14:56 INFO - Uninstalling pip: 11:14:56 INFO - Successfully uninstalled pip 11:14:56 INFO - Running setup.py install for pip 11:14:56 INFO - warning: no files found matching 'pip/cacert.pem' 11:14:56 INFO - warning: no files found matching '*.html' under directory 'docs' 11:14:56 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:14:56 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:14:56 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:14:56 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:14:56 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:14:56 INFO - Successfully installed pip 11:14:56 INFO - Cleaning up... 11:14:56 INFO - Return code: 0 11:14:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:14:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:14:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:14:56 INFO - Using env: {'DISPLAY': ':0', 11:14:56 INFO - 'HOME': '/home/cltbld', 11:14:56 INFO - 'LANG': 'en_US.UTF-8', 11:14:56 INFO - 'LANGUAGE': 'en_US:en', 11:14:56 INFO - 'LOGNAME': 'cltbld', 11:14:56 INFO - 'MAIL': '/var/mail/cltbld', 11:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:56 INFO - 'MOZ_NO_REMOTE': '1', 11:14:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:14:56 INFO - 'NO_EM_RESTART': '1', 11:14:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:56 INFO - 'PWD': '/builds/slave/test', 11:14:56 INFO - 'SHELL': '/bin/bash', 11:14:56 INFO - 'SHLVL': '1', 11:14:56 INFO - 'TERM': 'linux', 11:14:56 INFO - 'TMOUT': '86400', 11:14:56 INFO - 'USER': 'cltbld', 11:14:56 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:56 INFO - '_': '/tools/buildbot/bin/python'} 11:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:56 INFO - Downloading/unpacking psutil>=3.1.1 11:14:56 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 11:14:56 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 11:14:56 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 11:14:56 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 11:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:14:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:14:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:14:57 INFO - Installing collected packages: psutil 11:14:58 INFO - Running setup.py install for psutil 11:14:58 INFO - building 'psutil._psutil_linux' extension 11:14:58 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 11:14:59 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 11:14:59 INFO - building 'psutil._psutil_posix' extension 11:14:59 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 11:15:00 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 11:15:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:00 INFO - Successfully installed psutil 11:15:00 INFO - Cleaning up... 11:15:00 INFO - Return code: 0 11:15:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:15:00 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')]} 11:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:00 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')]} 11:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:00 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 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:15:00 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 11:15:00 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 11:15:00 INFO - Using env: {'DISPLAY': ':0', 11:15:00 INFO - 'HOME': '/home/cltbld', 11:15:00 INFO - 'LANG': 'en_US.UTF-8', 11:15:00 INFO - 'LANGUAGE': 'en_US:en', 11:15:00 INFO - 'LOGNAME': 'cltbld', 11:15:00 INFO - 'MAIL': '/var/mail/cltbld', 11:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:00 INFO - 'MOZ_NO_REMOTE': '1', 11:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:00 INFO - 'NO_EM_RESTART': '1', 11:15:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:00 INFO - 'PWD': '/builds/slave/test', 11:15:00 INFO - 'SHELL': '/bin/bash', 11:15:00 INFO - 'SHLVL': '1', 11:15:00 INFO - 'TERM': 'linux', 11:15:00 INFO - 'TMOUT': '86400', 11:15:00 INFO - 'USER': 'cltbld', 11:15:00 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:00 INFO - '_': '/tools/buildbot/bin/python'} 11:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:15:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:15:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:15:01 INFO - Installing collected packages: mozsystemmonitor 11:15:01 INFO - Running setup.py install for mozsystemmonitor 11:15:01 INFO - Successfully installed mozsystemmonitor 11:15:01 INFO - Cleaning up... 11:15:01 INFO - Return code: 0 11:15:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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')]} 11:15:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:01 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 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:15:01 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 11:15:01 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 11:15:01 INFO - Using env: {'DISPLAY': ':0', 11:15:01 INFO - 'HOME': '/home/cltbld', 11:15:01 INFO - 'LANG': 'en_US.UTF-8', 11:15:01 INFO - 'LANGUAGE': 'en_US:en', 11:15:01 INFO - 'LOGNAME': 'cltbld', 11:15:01 INFO - 'MAIL': '/var/mail/cltbld', 11:15:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:01 INFO - 'MOZ_NO_REMOTE': '1', 11:15:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:01 INFO - 'NO_EM_RESTART': '1', 11:15:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:01 INFO - 'PWD': '/builds/slave/test', 11:15:01 INFO - 'SHELL': '/bin/bash', 11:15:01 INFO - 'SHLVL': '1', 11:15:01 INFO - 'TERM': 'linux', 11:15:01 INFO - 'TMOUT': '86400', 11:15:01 INFO - 'USER': 'cltbld', 11:15:01 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:01 INFO - '_': '/tools/buildbot/bin/python'} 11:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:01 INFO - Downloading/unpacking jsonschema==2.5.1 11:15:01 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 11:15:01 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 11:15:01 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 11:15:01 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 11:15:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:15:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:03 INFO - Downloading functools32-3.2.3-2.tar.gz 11:15:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:15:03 INFO - warning: no files found matching '*.txt' 11:15:03 INFO - no previously-included directories found matching 'build' 11:15:03 INFO - no previously-included directories found matching 'dist' 11:15:03 INFO - no previously-included directories found matching '.git*' 11:15:03 INFO - Installing collected packages: jsonschema, functools32 11:15:03 INFO - Running setup.py install for functools32 11:15:03 INFO - warning: no files found matching '*.txt' 11:15:03 INFO - no previously-included directories found matching 'build' 11:15:03 INFO - no previously-included directories found matching 'dist' 11:15:03 INFO - no previously-included directories found matching '.git*' 11:15:03 INFO - Successfully installed jsonschema functools32 11:15:03 INFO - Cleaning up... 11:15:03 INFO - Return code: 0 11:15:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:15:03 INFO - Using env: {'DISPLAY': ':0', 11:15:03 INFO - 'HOME': '/home/cltbld', 11:15:03 INFO - 'LANG': 'en_US.UTF-8', 11:15:03 INFO - 'LANGUAGE': 'en_US:en', 11:15:03 INFO - 'LOGNAME': 'cltbld', 11:15:03 INFO - 'MAIL': '/var/mail/cltbld', 11:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:03 INFO - 'MOZ_NO_REMOTE': '1', 11:15:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:03 INFO - 'NO_EM_RESTART': '1', 11:15:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:03 INFO - 'PWD': '/builds/slave/test', 11:15:03 INFO - 'SHELL': '/bin/bash', 11:15:03 INFO - 'SHLVL': '1', 11:15:03 INFO - 'TERM': 'linux', 11:15:03 INFO - 'TMOUT': '86400', 11:15:03 INFO - 'USER': 'cltbld', 11:15:03 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:03 INFO - '_': '/tools/buildbot/bin/python'} 11:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:15:03 INFO - Cleaning up... 11:15:03 INFO - Return code: 0 11:15:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:15:03 INFO - Using env: {'DISPLAY': ':0', 11:15:03 INFO - 'HOME': '/home/cltbld', 11:15:03 INFO - 'LANG': 'en_US.UTF-8', 11:15:03 INFO - 'LANGUAGE': 'en_US:en', 11:15:03 INFO - 'LOGNAME': 'cltbld', 11:15:03 INFO - 'MAIL': '/var/mail/cltbld', 11:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:03 INFO - 'MOZ_NO_REMOTE': '1', 11:15:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:03 INFO - 'NO_EM_RESTART': '1', 11:15:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:03 INFO - 'PWD': '/builds/slave/test', 11:15:03 INFO - 'SHELL': '/bin/bash', 11:15:03 INFO - 'SHLVL': '1', 11:15:03 INFO - 'TERM': 'linux', 11:15:03 INFO - 'TMOUT': '86400', 11:15:03 INFO - 'USER': 'cltbld', 11:15:03 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:03 INFO - '_': '/tools/buildbot/bin/python'} 11:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:03 INFO - Downloading/unpacking blobuploader==1.2.4 11:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:05 INFO - Downloading blobuploader-1.2.4.tar.gz 11:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:15:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:15:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:05 INFO - Downloading docopt-0.6.1.tar.gz 11:15:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:15:05 INFO - Installing collected packages: blobuploader, requests, docopt 11:15:05 INFO - Running setup.py install for blobuploader 11:15:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:15:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:15:05 INFO - Running setup.py install for requests 11:15:06 INFO - Running setup.py install for docopt 11:15:06 INFO - Successfully installed blobuploader requests docopt 11:15:06 INFO - Cleaning up... 11:15:06 INFO - Return code: 0 11:15:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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')]} 11:15:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:06 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 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:15:06 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 11:15:06 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 11:15:06 INFO - Using env: {'DISPLAY': ':0', 11:15:06 INFO - 'HOME': '/home/cltbld', 11:15:06 INFO - 'LANG': 'en_US.UTF-8', 11:15:06 INFO - 'LANGUAGE': 'en_US:en', 11:15:06 INFO - 'LOGNAME': 'cltbld', 11:15:06 INFO - 'MAIL': '/var/mail/cltbld', 11:15:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:06 INFO - 'MOZ_NO_REMOTE': '1', 11:15:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:06 INFO - 'NO_EM_RESTART': '1', 11:15:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:06 INFO - 'PWD': '/builds/slave/test', 11:15:06 INFO - 'SHELL': '/bin/bash', 11:15:06 INFO - 'SHLVL': '1', 11:15:06 INFO - 'TERM': 'linux', 11:15:06 INFO - 'TMOUT': '86400', 11:15:06 INFO - 'USER': 'cltbld', 11:15:06 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:06 INFO - '_': '/tools/buildbot/bin/python'} 11:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:15:06 INFO - Running setup.py (path:/tmp/pip-ONVQ40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:15:06 INFO - Running setup.py (path:/tmp/pip-b7_utm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:15:06 INFO - Running setup.py (path:/tmp/pip-bpFqqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:15:06 INFO - Running setup.py (path:/tmp/pip-1xMeKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:15:06 INFO - Running setup.py (path:/tmp/pip-taXEtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:15:06 INFO - Running setup.py (path:/tmp/pip-VDw0HE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:15:06 INFO - Running setup.py (path:/tmp/pip-5NgSG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:15:06 INFO - Running setup.py (path:/tmp/pip-c7KktV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:15:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:15:06 INFO - Running setup.py (path:/tmp/pip-o2PJZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:15:07 INFO - Running setup.py (path:/tmp/pip-SdDsqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:15:07 INFO - Running setup.py (path:/tmp/pip-2I22q7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:15:07 INFO - Running setup.py (path:/tmp/pip-ClM0eo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:15:07 INFO - Running setup.py (path:/tmp/pip-S33gFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:15:07 INFO - Running setup.py (path:/tmp/pip-i_mdaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:07 INFO - Running setup.py (path:/tmp/pip-fKmOdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:15:07 INFO - Running setup.py (path:/tmp/pip-0xGqAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:15:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:15:07 INFO - Running setup.py (path:/tmp/pip-CaZ1ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:15:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:15:07 INFO - Running setup.py install for manifestparser 11:15:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:15:07 INFO - Running setup.py install for mozcrash 11:15:07 INFO - Running setup.py install for mozdebug 11:15:07 INFO - Running setup.py install for mozdevice 11:15:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Running setup.py install for mozfile 11:15:08 INFO - Running setup.py install for mozhttpd 11:15:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Running setup.py install for mozinfo 11:15:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Running setup.py install for mozInstall 11:15:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Running setup.py install for mozleak 11:15:08 INFO - Running setup.py install for mozlog 11:15:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Running setup.py install for moznetwork 11:15:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Running setup.py install for mozprocess 11:15:08 INFO - Running setup.py install for mozprofile 11:15:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:15:08 INFO - Running setup.py install for mozrunner 11:15:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:15:09 INFO - Running setup.py install for mozscreenshot 11:15:09 INFO - Running setup.py install for moztest 11:15:09 INFO - Running setup.py install for mozversion 11:15:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:15:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:15:09 INFO - Cleaning up... 11:15:09 INFO - Return code: 0 11:15:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:09 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 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:15:09 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 11:15:09 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 11:15:09 INFO - Using env: {'DISPLAY': ':0', 11:15:09 INFO - 'HOME': '/home/cltbld', 11:15:09 INFO - 'LANG': 'en_US.UTF-8', 11:15:09 INFO - 'LANGUAGE': 'en_US:en', 11:15:09 INFO - 'LOGNAME': 'cltbld', 11:15:09 INFO - 'MAIL': '/var/mail/cltbld', 11:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:09 INFO - 'MOZ_NO_REMOTE': '1', 11:15:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:09 INFO - 'NO_EM_RESTART': '1', 11:15:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:09 INFO - 'PWD': '/builds/slave/test', 11:15:09 INFO - 'SHELL': '/bin/bash', 11:15:09 INFO - 'SHLVL': '1', 11:15:09 INFO - 'TERM': 'linux', 11:15:09 INFO - 'TMOUT': '86400', 11:15:09 INFO - 'USER': 'cltbld', 11:15:09 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:09 INFO - '_': '/tools/buildbot/bin/python'} 11:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:15:09 INFO - Running setup.py (path:/tmp/pip-6Yvr3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:15:09 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)) 11:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:15:09 INFO - Running setup.py (path:/tmp/pip-GCsn5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:15:09 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)) 11:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:15:09 INFO - Running setup.py (path:/tmp/pip-k5oHPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:15:09 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)) 11:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:15:09 INFO - Running setup.py (path:/tmp/pip-xcyfYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:15:09 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)) 11:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:15:09 INFO - Running setup.py (path:/tmp/pip-BYTm0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:15:09 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)) 11:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:15:09 INFO - Running setup.py (path:/tmp/pip-5srCN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:15:09 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)) 11:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:15:10 INFO - Running setup.py (path:/tmp/pip-4EbuQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:15:10 INFO - Running setup.py (path:/tmp/pip-_0KXyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:15:10 INFO - Running setup.py (path:/tmp/pip-j2VE7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:15:10 INFO - Running setup.py (path:/tmp/pip-hl4RFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:15:10 INFO - Running setup.py (path:/tmp/pip-xTcNEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:15:10 INFO - Running setup.py (path:/tmp/pip-ftnYBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:15:10 INFO - Running setup.py (path:/tmp/pip-udsQa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:15:10 INFO - Running setup.py (path:/tmp/pip-8mMnBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:10 INFO - Running setup.py (path:/tmp/pip-ljTzU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:15:10 INFO - Running setup.py (path:/tmp/pip-Ei2K9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:15:10 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)) 11:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:15:10 INFO - Running setup.py (path:/tmp/pip-utdwZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:15:10 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)) 11:15:10 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)) 11:15:10 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)) 11:15:10 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)) 11:15:10 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)) 11:15:10 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)) 11:15:10 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)) 11:15:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:15: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 11:15: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 11:15: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 11:15: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 11:15:11 INFO - Downloading blessings-1.6.tar.gz 11:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:15:12 INFO - Installing collected packages: blessings 11:15:12 INFO - Running setup.py install for blessings 11:15:12 INFO - Successfully installed blessings 11:15:12 INFO - Cleaning up... 11:15:12 INFO - Return code: 0 11:15:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:15:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:12 INFO - Reading from file tmpfile_stdout 11:15:12 INFO - Current package versions: 11:15:12 INFO - argparse == 1.2.1 11:15:12 INFO - blessings == 1.6 11:15:12 INFO - blobuploader == 1.2.4 11:15:12 INFO - docopt == 0.6.1 11:15:12 INFO - functools32 == 3.2.3-2 11:15:12 INFO - jsonschema == 2.5.1 11:15:12 INFO - manifestparser == 1.1 11:15:12 INFO - mozInstall == 1.13 11:15:12 INFO - mozcrash == 1.0 11:15:12 INFO - mozdebug == 0.1 11:15:12 INFO - mozdevice == 0.51 11:15:12 INFO - mozfile == 1.2 11:15:12 INFO - mozhttpd == 0.7 11:15:12 INFO - mozinfo == 0.10 11:15:12 INFO - mozleak == 0.1 11:15:12 INFO - mozlog == 3.5 11:15:12 INFO - moznetwork == 0.27 11:15:12 INFO - mozprocess == 0.25 11:15:12 INFO - mozprofile == 0.28 11:15:12 INFO - mozrunner == 6.13 11:15:12 INFO - mozscreenshot == 0.1 11:15:12 INFO - mozsystemmonitor == 0.3 11:15:12 INFO - moztest == 0.8 11:15:12 INFO - mozversion == 1.4 11:15:12 INFO - psutil == 3.1.1 11:15:12 INFO - requests == 1.2.3 11:15:12 INFO - wsgiref == 0.1.2 11:15:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15: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')]} 11:15:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:12 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 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:15:12 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 11:15:12 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 11:15:12 INFO - Using env: {'DISPLAY': ':0', 11:15:12 INFO - 'HOME': '/home/cltbld', 11:15:12 INFO - 'LANG': 'en_US.UTF-8', 11:15:12 INFO - 'LANGUAGE': 'en_US:en', 11:15:12 INFO - 'LOGNAME': 'cltbld', 11:15:12 INFO - 'MAIL': '/var/mail/cltbld', 11:15:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:12 INFO - 'MOZ_NO_REMOTE': '1', 11:15:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:12 INFO - 'NO_EM_RESTART': '1', 11:15:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:12 INFO - 'PWD': '/builds/slave/test', 11:15:12 INFO - 'SHELL': '/bin/bash', 11:15:12 INFO - 'SHLVL': '1', 11:15:12 INFO - 'TERM': 'linux', 11:15:12 INFO - 'TMOUT': '86400', 11:15:12 INFO - 'USER': 'cltbld', 11:15:12 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:12 INFO - '_': '/tools/buildbot/bin/python'} 11:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:12 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)) 11:15:12 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)) 11:15:12 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)) 11:15:12 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)) 11:15:12 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)) 11:15:12 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)) 11:15:12 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)) 11:15:12 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)) 11:15:12 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)) 11:15:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:15: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 11:15: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 11:15: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 11:15: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 11:15:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:15:13 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)) 11:15:13 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)) 11:15:13 INFO - Installing collected packages: simplejson 11:15:13 INFO - Running setup.py install for simplejson 11:15:13 INFO - building 'simplejson._speedups' extension 11:15:13 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 11:15:14 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 11:15:14 INFO - Successfully installed simplejson 11:15:14 INFO - Cleaning up... 11:15:14 INFO - Return code: 0 11:15:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:15:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bb11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d79b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f1e9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, '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': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', '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 11:15:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:15:14 INFO - Using env: {'DISPLAY': ':0', 11:15:14 INFO - 'HOME': '/home/cltbld', 11:15:14 INFO - 'LANG': 'en_US.UTF-8', 11:15:14 INFO - 'LANGUAGE': 'en_US:en', 11:15:14 INFO - 'LOGNAME': 'cltbld', 11:15:14 INFO - 'MAIL': '/var/mail/cltbld', 11:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:14 INFO - 'MOZ_NO_REMOTE': '1', 11:15:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:14 INFO - 'NO_EM_RESTART': '1', 11:15:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:14 INFO - 'PWD': '/builds/slave/test', 11:15:14 INFO - 'SHELL': '/bin/bash', 11:15:14 INFO - 'SHLVL': '1', 11:15:14 INFO - 'TERM': 'linux', 11:15:14 INFO - 'TMOUT': '86400', 11:15:14 INFO - 'USER': 'cltbld', 11:15:14 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:14 INFO - '_': '/tools/buildbot/bin/python'} 11:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:15:15 INFO - Cleaning up... 11:15:15 INFO - Return code: 0 11:15:15 INFO - Running post-action listener: _resource_record_post_action 11:15:15 INFO - Running post-action listener: _start_resource_monitoring 11:15:15 INFO - Starting resource monitoring. 11:15:15 INFO - [mozharness: 2017-09-27 18:15:15.159400Z] Finished create-virtualenv step (success) 11:15:15 INFO - [mozharness: 2017-09-27 18:15:15.160415Z] Running install step. 11:15:15 INFO - Running pre-action listener: _resource_record_pre_action 11:15:15 INFO - Running main action method: install 11:15:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:15 INFO - Reading from file tmpfile_stdout 11:15:15 INFO - Detecting whether we're running mozinstall >=1.0... 11:15:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:15:15 INFO - Reading from file tmpfile_stdout 11:15:15 INFO - Output received: 11:15:15 INFO - Usage: mozinstall [options] installer 11:15:15 INFO - Options: 11:15:15 INFO - -h, --help show this help message and exit 11:15:15 INFO - -d DEST, --destination=DEST 11:15:15 INFO - Directory to install application into. [default: 11:15:15 INFO - "/builds/slave/test"] 11:15:15 INFO - --app=APP Application being installed. [default: firefox] 11:15:15 INFO - mkdir: /builds/slave/test/build/application 11:15:15 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'] 11:15:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:15:33 INFO - Reading from file tmpfile_stdout 11:15:33 INFO - Output received: 11:15:33 INFO - /builds/slave/test/build/application/firefox/firefox 11:15:33 INFO - Running post-action listener: _resource_record_post_action 11:15:33 INFO - [mozharness: 2017-09-27 18:15:33.373078Z] Finished install step (success) 11:15:33 INFO - [mozharness: 2017-09-27 18:15:33.373419Z] Running setup-mitmproxy step. 11:15:33 INFO - Running pre-action listener: _resource_record_pre_action 11:15:33 INFO - Running main action method: setup_mitmproxy 11:15:33 INFO - Skipping: mitmproxy is not required 11:15:33 INFO - Running post-action listener: _resource_record_post_action 11:15:33 INFO - [mozharness: 2017-09-27 18:15:33.375018Z] Finished setup-mitmproxy step (success) 11:15:33 INFO - [mozharness: 2017-09-27 18:15:33.375447Z] Running run-tests step. 11:15:33 INFO - Running pre-action listener: _resource_record_pre_action 11:15:33 INFO - Running pre-action listener: _set_gcov_prefix 11:15:33 INFO - Running main action method: run_tests 11:15:33 WARNING - Try message not found. 11:15:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:15:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:15:33 INFO - Python 2.7.3 11:15:33 INFO - Return code: 0 11:15:33 INFO - grabbing minidump binary from tooltool 11:15:33 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')]} 11:15:33 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 0x1f1bd60>, '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 0x1f1ca60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f1cef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:15:33 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 11:15:33 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 11:15:33 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 11:15:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:15:33 INFO - Return code: 0 11:15:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:15:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:15:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:15:33 INFO - ENV: RUST_BACKTRACE is now full 11:15:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:15:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:15:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 11:15:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 11:15:33 INFO - Using env: {'DISPLAY': ':0', 11:15:33 INFO - 'HOME': '/home/cltbld', 11:15:33 INFO - 'LANG': 'en_US.UTF-8', 11:15:33 INFO - 'LANGUAGE': 'en_US:en', 11:15:33 INFO - 'LOGNAME': 'cltbld', 11:15:33 INFO - 'MAIL': '/var/mail/cltbld', 11:15:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:15:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:15:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:33 INFO - 'MOZ_NO_REMOTE': '1', 11:15:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:15:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:33 INFO - 'NO_EM_RESTART': '1', 11:15:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:33 INFO - 'PWD': '/builds/slave/test', 11:15:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:15:33 INFO - 'RUST_BACKTRACE': 'full', 11:15:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:15:33 INFO - 'SHELL': '/bin/bash', 11:15:33 INFO - 'SHLVL': '1', 11:15:33 INFO - 'TERM': 'linux', 11:15:33 INFO - 'TMOUT': '86400', 11:15:33 INFO - 'USER': 'cltbld', 11:15:33 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:33 INFO - '_': '/tools/buildbot/bin/python'} 11:15:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 11:15:33 INFO - mozversion application_buildid: 20170927172229 11:15:33 INFO - mozversion application_changeset: 6100472d3aa833dff22a4edb0934fe600f43ddb8 11:15:33 INFO - mozversion application_display_name: Nightly 11:15:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:15:33 INFO - mozversion application_name: Firefox 11:15:33 INFO - mozversion application_remotingname: firefox 11:15:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:15:33 INFO - mozversion application_vendor: Mozilla 11:15:33 INFO - mozversion application_version: 58.0a1 11:15:33 INFO - mozversion platform_buildid: 20170927172229 11:15:33 INFO - mozversion platform_changeset: 6100472d3aa833dff22a4edb0934fe600f43ddb8 11:15:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:15:33 INFO - mozversion platform_version: 58.0a1 11:15:33 INFO - using testdate: 1506536133 11:15:33 INFO - actual date: 1506536133 11:15:33 INFO - starting webserver on 'localhost:39120' 11:15:33 INFO - SUITE-START | Running 2 tests 11:15:33 INFO - TEST-START | dromaeo_css 11:15:33 INFO - Initialising browser for dromaeo_css test... 11:15:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39120/getInfo.html -profile /tmp/tmphHsEL3/profile 11:15:34 INFO - TEST-INFO | started process 3579 (/builds/slave/test/build/application/firefox/firefox http://localhost:39120/getInfo.html) 11:15:42 INFO - TEST-INFO | 3579: exit 0 11:15:42 INFO - Browser initialized. 11:15:42 INFO - Running cycle 1/1 for dromaeo_css test... 11:15:42 INFO - Using env: {'DISPLAY': ':0', 11:15:42 INFO - 'HOME': '/home/cltbld', 11:15:42 INFO - 'JSGC_DISABLE_POISONING': '1', 11:15:42 INFO - 'LANG': 'en_US.UTF-8', 11:15:42 INFO - 'LANGUAGE': 'en_US:en', 11:15:42 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:15:42 INFO - 'LOGNAME': 'cltbld', 11:15:42 INFO - 'MAIL': '/var/mail/cltbld', 11:15:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:15:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:15:42 INFO - 'MOZ_CRASHREPORTER': '1', 11:15:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:42 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:15:42 INFO - 'MOZ_NO_REMOTE': '1', 11:15:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:15:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:15:42 INFO - 'NO_EM_RESTART': '1', 11:15:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:15:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:42 INFO - 'PWD': '/builds/slave/test', 11:15:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:15:42 INFO - 'RUST_BACKTRACE': 'full', 11:15:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:15:42 INFO - 'SHELL': '/bin/bash', 11:15:42 INFO - 'SHLVL': '1', 11:15:42 INFO - 'TERM': 'linux', 11:15:42 INFO - 'TMOUT': '86400', 11:15:42 INFO - 'USER': 'cltbld', 11:15:42 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:15:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:42 INFO - '_': '/tools/buildbot/bin/python'} 11:15:42 INFO - TEST-INFO | started process 3815 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphHsEL3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:15:43 INFO - PID 3815 | 11:15:43 INFO - PID 3815 | (/builds/slave/test/build/application/firefox/firefox:3864): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:15:43 INFO - PID 3815 | 11:15:53 INFO - PID 3815 | RSS: Main: 151904256 11:15:53 INFO - PID 3815 | 11:18:50 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:39120/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39120/tests/dromaeo/cssquery-ext.html) 11:18:52 INFO - PID 3815 | RSS: Main: 152297472 11:18:52 INFO - PID 3815 | 11:21:53 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:39120/tests/dromaeo/cssquery-ext.html (next: http://localhost:39120/tests/dromaeo/cssquery-jquery.html) 11:21:54 INFO - PID 3815 | RSS: Main: 152588288 11:21:54 INFO - PID 3815 | 11:24:56 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:39120/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39120/tests/dromaeo/cssquery-mootools.html) 11:24:56 INFO - PID 3815 | RSS: Main: 154537984 11:24:56 INFO - PID 3815 | 11:27:58 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:39120/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39120/tests/dromaeo/cssquery-prototype.html) 11:27:59 INFO - PID 3815 | RSS: Main: 152846336 11:27:59 INFO - PID 3815 | 11:31:02 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:39120/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39120/tests/dromaeo/cssquery-yui.html) 11:31:02 INFO - PID 3815 | RSS: Main: 154005504 11:31:02 INFO - PID 3815 | 11:34:04 INFO - PID 3815 | Cycle 1(1): loaded http://localhost:39120/tests/dromaeo/cssquery-yui.html (next: http://localhost:39120/tests/dromaeo/cssquery-dojo.html) 11:34:04 INFO - PID 3815 | RSS: Main: 157040640 11:34:04 INFO - PID 3815 | 11:34:04 INFO - PID 3815 | __start_tp_report 11:34:04 INFO - PID 3815 | _x_x_mozilla_page_load 11:34:04 INFO - PID 3815 | _x_x_mozilla_page_load_details 11:34:04 INFO - PID 3815 | |i|pagename|runs| 11:34:04 INFO - PID 3815 | |0;dojo.html;3203.7757356985007;3214.82318472484;3230.806151630904;3246.4481038223507;3255.039763269835;6348.36516348365;6442.484601231902;6483.481321494275;6501.869962600746;6505.381988711077;6248.593841400306;6343.397377249165;6356.173697419329;6373.713182906768;6397.360263973612;5837.678927658988;5906.084556893688;5907.4978626816555;5928.081147422156;5941.28704555454;5886.617369870966;5929.28848538176;5941.88116237675;5952.077427998667;5987.940120598803;5777.913331300034;5806.622569532988;5872.295324561037;5894.705264736767;5942.168096466498;5860.296764388258;5866.941330586682;5950;5953.166556682025;5954.255718035246;5742.74157662902;5769;5821.126830975388;5863.091220860773;5895.793647222326;6358.42774150324;6372.681365931685;6378.872422551523;6399.488040956712;6447.6131432114225;6807.795766126975;6831.487638427088;6917.412019978308;6928.757493487754;6930.965345173292;6709.36261055199;6893.931060689379;6902.24075351716;6994.230634630187;6995.790126296169;6292.685365731695;6335.429811317003;6404.615723056585;6408;6414.935850641527;7025.89461158083;7064.32888875556;7107.111611048619;7125.465590080661;7135.215126336099;6713.395794378529;6724.361185687352;6730.831729206809;6818.8636227276165;6822.795316140524;6705.530612857053;6774.627395493197;6881.862362752717;6957.339052789976;6971.860562788817;6696.363845434676;6702.296258892844;6706.228783689938;6714.832129196711;6782.253952065269;6778.2205046420295;6845.8973115403305;6876.862462750717;6883.3116688330765;6887.311268873173;5181.455947125498;5253.55344795689;5284.260203571503;5338.3060202173465;5348.197770334481;4617.838375656903;4640.605548528345;4681.836135735233;4691.319758634979;4693.967327188013;5887.1463637772285;5898.76404943806;5928.496077833346;5957.821265362046;5958.582899197101;6656.70044847983;6702.731890724316;6800.931990680129;6825.965870170618;6846.349596788297;5887.4406931341455;5922.526197904157;5942.8811423771285;5943.554233432424;5949.345571987078;5841.2990441146485;5919.230500034968;5922.585419020714;5933.258342707161;5952.077427998667;6620.668966551653;6637.966810165918;6726.764563240263;6745.0556922030955;6751.426128779109;6699.196096468455;6771.830704232433;6840.657967101525;6853.588784672936;6954.999999999899;6557.770478033246;6639.966800166161;6665.800025999228;6681.198256209092;6689.1972963244725;6709.194896612437;6713.966430167818;6799.048133261446;6801.863962720817;6814.2163651180745;6645.60126392418;6738.258791533026;6755.42578880781;6859.451243900576;6893.586384816927;6495.675216239264;6514.902276465762;6517.152770139852;6521.380468855451;6526.67366631657;6567.080608714688;6577.2436169841085;6656.700448479927;6666.833329166616;6670.366315200048;6511.218653761579;6614.702338394687;6620.271770105188;6624.6025238485845;6625.933740662725;6547.1161393213215;6655.534112612264;6702.631355275506;6722.058911752456;6748.595084294958;6299.244090709144;6361.459275961595;6401;6408.198975128109;6507.804765857032 11:34:04 INFO - PID 3815 | |1;ext.html;855.944363616365;863.499170481121;877.9209871111599;886.4459712679575;902.9593668284929;39574.40638390423;40583.188336233296;40874.79562602183;40956.38565421521;41104.38343424843;36805.63194368056;36946;37171.25657486849;37205.62794372055;37226.44160337597;7276.9272307276915;7365.005724227241;7405.296496832819;7420.035395398618;7426.183119856812;7152.892706609405;7226.674799634003;7230.349268565828;7234.312740289664;7236;7763.184865589116;8002.159773223786;8007.51954882706;8008.2392172743785;8030.39772017101;7923.12845586985;7982.760517184493;7985.720499782537;7992.040955085398;7999.480033797814;7659.655315510815;7882.842343153162;7898.447108702336;7904.209579042107;7969.362451003905;38028.239435211144;38332.425013624816;38412.42381364254;38415.27131279099;38445;3194.7603929705365;3221.098092534094;3237.077432931603;3238.6599407062154;3243.108145260049;30946.226344341347;30997.22506937322;31081.378372432424;31112.377752445052;31127.22181945447;5196.038732834438;5196.76614552346;5234.816781412632;5266.894662106774;5277.366715994067;182198;183227;183540;183609.0819545907;183993;45417.77291113523;45510.31734523985;45609;45769.31346029812;45819.31271030937;47444;47560.286595701094;47579.5242047582;47581.524184758404;47618.5238147621;15669.686606267811;15909.443169489245;16037;16045.598860028593;16075.276612552463;13241.139325943783;13258.602241932758;13271.203727776365;13275.601731948056;13283.535076272285;13297.867021329663;13311.467541298432;13405.262710550818;13457.125286856322;13561.796573051412;5515.310586176728;5530.751116199781;5635.746391412396;5656.60403771732;5740.613038307335;1012.9594816207269;1018.1701912940929;1022.8619136416488;1029.5006921642996;1037.5953378182514;22539.436514086952;22555.210567630053;22672.546549068928;22872;23010.424739381648;1114.0753174865006;1122.8764835868049;1126.6957921361186;1128.966131016071;1129.8870112988636;11760.88239117615;11954.16320857549;12012.159148859497;12068.939655301843;12070.27578345302;217376;217551;217704;218486;218701;49724;52928.20607690888;54333.45666543284;54342.45657543374;54381.72809135929;1457.7303198908237;1471.4261438039173;1480.6002379357512;1485.442958890416;1485.4949317231976;26541.60187597149;26917.596236056866;26935.461290774467;27085.458290834467;27515.86242068777;37798.62201378061;37804.81097594494;37830.054248644;37839.62160378361;37867.431988520744;140567;140744;140796;141113;141365;32386;33458.83270583729;33524.32951340911;33571.66428335783;33585.83207083949;39497.80251098726;40943.59056409517;41203.38194927138;41244.587554125275;41461.58538414577;30839.691603083684;30904.227394315323;31106.688933111283;31421.528677069404;31423.52864707077;30797;30919.381612368077;31206.843965780026;31275.218119547193;31770.52344214885;35351;35409.29181416409;35423;35673.82163089168;35707.28585428329;30790.076297711104;30872;30873.934423779156;30985.535216972214;30994.69005309918;22581.548369032855;22940.77059229453;22941.199952097533;23880.40298992539;23920.40198995039 11:34:04 INFO - PID 3815 | |2;jquery.html;591.1025607798963;596.6479776931609;601.2184160591233;612.6446660936657;614.4930432393276;21817;22848.65727014094;23371;23388.649170262437;23434;26291.079812206575;26784.59823102655;27044.18867433975;27092.18723438295;27102.45795084097;7325.3040961108745;7490.925090749104;7491.475596708233;7495.66269517873;7499.100107987049;6989.650517474131;7303.89044164338;7321.670524826369;7330.853382932338;7353.77938661841;7284.4172466202845;7284.635768211595;7287.927120728805;7293.963530182342;7294.598797066159;7266.164391095039;7276.235995220479;7276.272372762734;7277.3086556777025;7294.963525182393;7106.538075025107;7113.608751518665;7125.287471252885;7139.143302803697;7140.428765698732;29764.55353169704;30019.249518761986;31404.685953140634;31659.52510712318;31824.204394890083;2063.2366024570856;2091.801278878504;2094.089071253994;2094.6020256151437;2096.9580608387746;20821.167153314;21241;21275.255366062112;21314.254001110043;21452.24917127893;2186.10369748404;2232.620454522735;2388.617821148608;2438.3904023994;2567;137109;142085;142190.57809421877;142233.2888335562;142419.28790355983;20873.79126208749;21547;21636.78363216379;22244.3326700196;22761.88619056894;42988.14023719508;43279.13441731148;43318.566814331454;43323.35014974778;43354;11158.274712143848;11471.197016208785;11516.942415288038;11581.131415143898;11597.246178998506;8291.751247462586;8661.263792577702;9398.95300523493;9473.952630236943;9804.509774511389;10907.618233361794;11355.432228388547;11561.595344162914;11838.585649502225;11994;4028.959710402917;4063.878083657495;4137.565555616678;4281.529031806499;4751.809927602926;964.7781010367655;991.4150651115732;1002.0730823987782;1004.8743907011624;1007.6473234367912;19049.61900761977;19374.80625193758;19538.023098845002;19559.31542396009;19761.40715778529;1043.7964596903682;1052.2634156090767;1058.2380685906135;1060.5598676549146;1064.797688439194;10529.84205236922;10702.571897124184;10715.284156715548;11023.267900691755;11143.888561114447;474696;474757;477096;477467;483598;115355;117055.41472292584;117201.41399292949;117479;118259.40870295766;1141.2296699727651;1147.5065721739732;1170.1107158559555;1178.8113901775846;1181.6041626055369;17341.306347746453;17350.21924013397;17361.739573906652;17672;17902.641947161243;54644.18033729418;55753.442465576445;56082.439175609354;57293.71353143208;57993;67079;69576.65211673909;70456.29543704497;70794.64602676954;71078.64460677664;14985;15322.923385383001;16382.508524744277;19380.90309548443;20322.59354812925;22361.552768944854;22582.435439113495;22730.20444284442;22761;22832.31503054911;17964.91017544904;18216.089195539906;18219.54451138732;18242.543936401697;18326.908365458086;16169.43406980797;16245.59386015359;16958.915205423895;17036.74444883303;17231.483055508354;18139.183736732142;18296.451106466826;18375.356862509747;18427.17077731532;18471.353502627342;19415.3204637837;19519.707204391663;19564.217431303157;19607.31374401889;19707.60584788325;16648.66702665964;18007.459776206735;18205.635887282446;18256;18262.36081737133 11:34:04 INFO - PID 3815 | |3;mootools.html;1314.9539766108187;1421.7156568686262;1423.501774378967;1424;1426.9286535673214;15653.139077350741;16068.276927538289;16217.189140542985;16234.51296461105;16250.593735156626;15722.135282559455;15859.286332115054;15862;15891.28489217985;15893.602659933507;2710.7424794644476;2778.8749506272165;2798.706135855726;2801.3836955869674;2802.0893209706824;2716.0493827160435;2731.754142127208;2752.160591019748;2752.1881045091745;2753.022676949684;2659.6808382993972;2693.757561819436;2797.5104356737593;2798.664160300767;2800.355918138819;2846.6584009918733;2857.5285077962217;2889.8844046238123;2890.7398334150025;2890.9421811563757;2553.885075171603;2700.7434293742062;2706;2708.9458210835674;2720.659917510311;15410.229488525642;15581.610459738484;15617.453389131439;15618;15636.843631563766;8038.196180381973;8403.5378054207;8418.579071046423;8437.114103019218;8438.957805210935;5399;5434.076207044812;5468.835934921959;5650.378458369576;5699.544036477072;1980.1683293016968;1980.4058782365378;1981.2075169931998;1982.6926826341796;2030.6953956906434;27196.728032719617;27202.72797272022;27263.182104536896;27322.45355092907;27435.45129097407;7047.577145371294;7079.92920070803;7135.250798666169;7172.46206534512;7235.710571577183;18009.549761256072;18015.18931648079;18029.18868650914;18032;18076.457706268833;7895.092064412551;8167.060788009336;8171.877421838677;8171.877421838677;8172.6730930762105;7429.851402972018;7438.6280685965485;7494.21310762373;7622.771316860503;7635.885461718079;5169.06956747789;5277.551408130275;5297.205419187076;5317.282166907496;5540;1478.7042591481747;1511.780791785185;1519.4985654733912;1520.3994422203389;1531.9846801532065;887.8401887660154;899.9775005624781;903.7695387676232;910.2626872233512;913.2100732866023;7494.850102998019;7600.923990760132;7648.65581048843;7702.422318326036;7797.94727711763;725.7786375155523;735.5219107580009;748.655618415535;751.6955632968657;754.5171090502083;4708.529147085264;5086.821961231339;5193.792248310101;5238.135707608202;5323.840284791462;21182.364529064154;22422.887885560467;22449.551008979728;22617.321480355615;22691;20936.685949710765;22072.779272207074;22160.778392216194;22358;22441.32676019722;10116.241281903887;10568.788624227625;10570.788584228272;10572.89427105719;10586.311889727143;7414.658509177176;7479.326860582573;7537.2462753724185;7543.585102819397;7556.282153195438;17636.206370713604;18255.452336429928;18429.81570184281;18444.723329150343;18748.81251187471;19485.8051419484;19578.51053723668;19595.804041959967;19602.50993725168;20105;10508.369497830156;11452.141089418494;11466.140039497239;11483;11485.942570287096;13852.445902163801;14119.64700882486;14200.360983755962;14224.431022758974;14562.271886405426;11655.59205427806;11692.88307116952;11695.473703683185;11737.47181376823;11889.167758256839;11455.942720286344;11879.76240475203;11936.76126477483;11942.044636428891;11956.34240116802;10078.294519383573;10120.342177758423;10241.692749217535;10295.588176472857;10302.433366164933;6988.825279368057;7090.822729431806;7188.640567971476;7195.100612423447;7278.126624804845;7708.113566939649;7757.92242077572;7804.336631386169;7808;7876.645550950336 11:34:04 INFO - PID 3815 | |4;prototype.html;508.4585859488663;532.2043544900374;536.3804805449706;537.760696490062;543.4836904940305;6200.224971878515;6212;6227.595206311598;6246.437820596146;6310.621362718229;6085.695715214244;6090.056041313593;6133.417325354096;6145.846353841156;6157.107219453176;5079.476157152858;5085.1863701807715;5093.974530127344;5136.357955255593;5142.151544995071;4866.902661946777;4999.250112483121;5122.180451127821;5141.845744627649;5156.355455568054;4924.335214745999;4937.259411088329;4954.826581069663;4988.201887697969;4994.750262486879;4894.314795928537;4898.779554920002;4939.60483161348;4959.132151873426;4981.505548335521;4340.197063543223;4482.21561226784;4482.798274077674;4494.348319493655;4494.415725955638;6015.969920150415;6045.848853778647;6055.394460553954;6060.575759696823;6061.969690151565;5657.8019769308285;5657.971710141464;5675;5680.289963754531;5684.772609095631;6448.258450278231;6451.387118223761;6457.903131453032;6459.192600924885;6499.5775274607;4879.609631229493;4890.315355850184;4906.215005599122;4920.188168952119;4921.680090794122;6537.1501704778075;6639.236487803916;6663.366980136928;6713.597184168917;6751.223609284946;4970.950290497121;4998.975005124951;5000.374953130859;5013.573846223112;5014.724190169502;5024.2463630454995;5056.57019153376;5060.266261392151;5060.949390506122;5080.161773307437;5587.916181257285;5612.635178713371;5612.747426365823;5616.213730077792;5623.718814059363;6468.708908099147;6473.708683109271;6480.060391243243;6501.382368674968;6501.804945851632;1741.4427383237496;1754.649070185968;1761.647670465912;1766.258171567919;1768.8496477799274;1172.6716519374588;1235.734317121823;1235.8393408856791;1240.4542001519303;1246.3207551884245;5653.491185793298;5657.830265092054;5665.716714164358;5670.193254339046;5698.800541981011;6571.835704107436;6585.703643336062;6600.537962342686;6620.635865027373;6651.966740166365;5634.436556344414;5636.577256705764;5644.266245388073;5653.575981801381;5671.517920976763;3453.5149885548954;3473.6873681368797;3503.667151620592;3524.823758812049;3535.9292814144087;6283.277423096311;6346.460550853137;6362.204724409449;6367.522435817332;6370.6814659267775;6137.693115344215;6493.155889734398;6597.30728273534;6667.233268174125;6670.433013193836;6256.249250090018;6279.717412716439;6316.463100636405;6361.61830290184;6444.516661250331;6450.838729031811;6470.255920569007;6505.967470162619;6640.369164929324;6644.4020038196795;6461.8061458156335;6492.805215843532;6518.869622607616;6534.150560427114;6542.738290468328;6235.314115447388;6308.274548426806;6310.6213627182515;6340.270868850142;6374.1953540442055;4916.8770780730765;4941.802327906844;4943.209086546135;4966.379202599675;4985.651004429655;6201.286852012076;6552.737890484327;6570.901436478361;6571.572847765071;6598.241202261801;6178.876422471615;6330.3669633036325;6385.712642931172;6398.648074355955;6426.4537514311805;6322.462590679844;6329.9050514243245;6369.299377068612;6394.584352017104;6434.163558737521;6193.566450348612;6200.410960958701;6255.499560035278;6285.182926219562;6311.936880631135;6348.650824204713;6382.32985536512;6389.16940797712;6396.776112836028;6479.643619600967;6131.96934015345;6196.442320191204;6224.190855188796;6277.869983403031;6287.937120628735 11:34:04 INFO - PID 3815 | |5;yui.html;863.1756672377876;866.2117473099481;871.9869201961972;914.7255823253024;941.9293552983526;19023.52441188971;19834.107465164067;20376.694349584748;20478.488037799063;20500;17575.38486152985;17792.91103544484;17925.10374481274;17939.91030044848;18012.279508819665;973.2214228617096;994.2791476179784;998.0717932322937;998.1166667499243;998.2862253488754;961.8364877970762;988.234118558116;1000.4297550396241;1000.7448100734339;1001.35412658835;974.4202199691173;1066.1364294921138;1066.4188017530464;1066.536056815284;1067.9946600266987;1005.1355833982769;1014.3660212367271;1016.6949915025463;1016.8576399304103;1017.0846238385475;741.8664640364732;746.4438992950287;748.3788455581908;749.8912657664663;750.5684231566817;19597.824130552213;20419.79580204194;20579.279725209544;20702.275420360216;20714.896425517927;22129;22136.114555417764;22684.77315226843;22715.659265111037;22718;17463.301467941266;17885.7317140243;17900.731489027676;17901.552461188443;17919.283228670836;3761.849526018956;3786.129190286222;3817.4464702618243;3830.3871380579253;3832.8658496952753;124824;125056;125419.3729031349;126759.36620316839;127076.36461817725;17028.829711702972;17115.400960966308;17185.31258749661;17191;17317.30730770755;30884.53673194904;31243.37513249722;32171.034868953968;32378.67621323804;32430.189245268586;8326.750197494084;8405.873911891327;8428.873566896502;8435.283000944988;8445.366597505212;4107.158032603309;4156.355764856452;4191.895202619959;4230.471191101113;4250;5325.227841962894;5426.647267927651;5428.077226871442;5430.049741295257;5443.727813609304;931.7810314576069;970.7573106723319;978.9314747967717;981.1415011864618;984.227381505529;581.3488892440494;638.8243233110932;654.2149420695184;668.7993601919404;668.8561959178703;11898.881011189951;11936.224145430519;12279.229601214636;12924.159929604546;12936.611901642966;638.2213699286863;644.3910504573133;650.1742786660914;654.7839213059679;658.1444122640548;5780.595358324961;5842.386549412336;5869.325027621878;5967.035051464066;6035.366286539938;115840;116499;116620;117448.41275793566;120988;97646;97751;98205;98958.01041989632;100555;90671.54664226636;91408;92101.53949230211;93078.53460732652;93111;10990.835137472784;11208.383538905438;11213.87398329466;11251.549938002388;11377.54489820398;42262.57737422586;42769.358459622505;43297.78351108224;44156.779216103714;44388;54393.72803135959;55193.72403138119;55303.72348138234;55662.16506752321;57097.429025709214;15852.52442426729;15883.285252163449;15902;15969.281382338055;16028.27872745706;17634.470965871045;17836;17868.731969020217;17905.37331193454;18172.09139542991;15814.446494372642;15948.521544353687;16091.517254482384;16128.435504757277;16129.516114516584;15481.767773483632;15582.766258505904;15666.216689165723;15718.764218536502;15845.841541584898;14887.330070147085;15185.772213416587;15215.315310810818;15317.6170595736;15318.310676019382;10356.844647330447;10454.215933804842;10490;10612.416317102634;10889.074428673652;10987.560497580007;11208.439578021229;11265.26775759573;11294;11383 11:34:04 INFO - PID 3815 | __end_tp_report 11:34:04 INFO - PID 3815 | __start_cc_report 11:34:04 INFO - PID 3815 | _x_x_mozilla_cycle_collect,287 11:34:04 INFO - PID 3815 | __end_cc_report 11:34:04 INFO - PID 3815 | __startTimestamp1506537244501__endTimestamp 11:34:04 INFO - PID 3815 | 11:34:04 INFO - PID 3815 | ------- Summary: start ------- 11:34:04 INFO - PID 3815 | Number of tests: 6 11:34:04 INFO - PID 3815 | 11:34:04 INFO - PID 3815 | [#0] dojo.html Cycles:165 Average:6282.05 Median:6514.90 stddev:750.95 (11.5%) stddev-sans-first:713.38 11:34:04 INFO - PID 3815 | Values: 3203.8 3214.8 3230.8 3246.4 3255.0 6348.4 6442.5 6483.5 6501.9 6505.4 6248.6 6343.4 6356.2 6373.7 6397.4 5837.7 5906.1 5907.5 5928.1 5941.3 5886.6 5929.3 5941.9 5952.1 5987.9 5777.9 5806.6 5872.3 5894.7 5942.2 5860.3 5866.9 5950.0 5953.2 5954.3 5742.7 5769.0 5821.1 5863.1 5895.8 6358.4 6372.7 6378.9 6399.5 6447.6 6807.8 6831.5 6917.4 6928.8 6931.0 6709.4 6893.9 6902.2 6994.2 6995.8 6292.7 6335.4 6404.6 6408.0 6414.9 7025.9 7064.3 7107.1 7125.5 7135.2 6713.4 6724.4 6730.8 6818.9 6822.8 6705.5 6774.6 6881.9 6957.3 6971.9 6696.4 6702.3 6706.2 6714.8 6782.3 6778.2 6845.9 6876.9 6883.3 6887.3 5181.5 5253.6 5284.3 5338.3 5348.2 4617.8 4640.6 4681.8 4691.3 4694.0 5887.1 5898.8 5928.5 5957.8 5958.6 6656.7 6702.7 6800.9 6826.0 6846.3 5887.4 5922.5 5942.9 5943.6 5949.3 5841.3 5919.2 5922.6 5933.3 5952.1 6620.7 6638.0 6726.8 6745.1 6751.4 6699.2 6771.8 6840.7 6853.6 6955.0 6557.8 6640.0 6665.8 6681.2 6689.2 6709.2 6714.0 6799.0 6801.9 6814.2 6645.6 6738.3 6755.4 6859.5 6893.6 6495.7 6514.9 6517.2 6521.4 6526.7 6567.1 6577.2 6656.7 6666.8 6670.4 6511.2 6614.7 6620.3 6624.6 6625.9 6547.1 6655.5 6702.6 6722.1 6748.6 6299.2 6361.5 6401.0 6408.2 6507.8 11:34:04 INFO - PID 3815 | 11:34:04 INFO - PID 3815 | [#1] ext.html Cycles:180 Average:35024.75 Median:18350.97 stddev:47483.24 (258.8%) stddev-sans-first:47547.13 11:34:04 INFO - PID 3815 | Values: 855.9 863.5 877.9 886.4 903.0 39574.4 40583.2 40874.8 40956.4 41104.4 36805.6 36946.0 37171.3 37205.6 37226.4 7276.9 7365.0 7405.3 7420.0 7426.2 7152.9 7226.7 7230.3 7234.3 7236.0 7763.2 8002.2 8007.5 8008.2 8030.4 7923.1 7982.8 7985.7 7992.0 7999.5 7659.7 7882.8 7898.4 7904.2 7969.4 38028.2 38332.4 38412.4 38415.3 38445.0 3194.8 3221.1 3237.1 3238.7 3243.1 30946.2 30997.2 31081.4 31112.4 31127.2 5196.0 5196.8 5234.8 5266.9 5277.4 182198.0 183227.0 183540.0 183609.1 183993.0 45417.8 45510.3 45609.0 45769.3 45819.3 47444.0 47560.3 47579.5 47581.5 47618.5 15669.7 15909.4 16037.0 16045.6 16075.3 13241.1 13258.6 13271.2 13275.6 13283.5 13297.9 13311.5 13405.3 13457.1 13561.8 5515.3 5530.8 5635.7 5656.6 5740.6 1013.0 1018.2 1022.9 1029.5 1037.6 22539.4 22555.2 22672.5 22872.0 23010.4 1114.1 1122.9 1126.7 1129.0 1129.9 11760.9 11954.2 12012.2 12068.9 12070.3 217376.0 217551.0 217704.0 218486.0 218701.0 49724.0 52928.2 54333.5 54342.5 54381.7 1457.7 1471.4 1480.6 1485.4 1485.5 26541.6 26917.6 26935.5 27085.5 27515.9 37798.6 37804.8 37830.1 37839.6 37867.4 140567.0 140744.0 140796.0 141113.0 141365.0 32386.0 33458.8 33524.3 33571.7 33585.8 39497.8 40943.6 41203.4 41244.6 41461.6 30839.7 30904.2 31106.7 31421.5 31423.5 30797.0 30919.4 31206.8 31275.2 31770.5 35351.0 35409.3 35423.0 35673.8 35707.3 30790.1 30872.0 30873.9 30985.5 30994.7 22581.5 22940.8 22941.2 23880.4 23920.4 11:34:04 INFO - PID 3815 | 11:34:04 INFO - PID 3815 | [#2] jquery.html Cycles:180 Average:35791.74 Median:21726.89 stddev:80595.13 (370.9%) stddev-sans-first:80777.89 11:34:04 INFO - PID 3815 | Values: 591.1 596.6 601.2 612.6 614.5 21817.0 22848.7 23371.0 23388.6 23434.0 26291.1 26784.6 27044.2 27092.2 27102.5 7325.3 7490.9 7491.5 7495.7 7499.1 6989.7 7303.9 7321.7 7330.9 7353.8 7284.4 7284.6 7287.9 7294.0 7294.6 7266.2 7276.2 7276.3 7277.3 7295.0 7106.5 7113.6 7125.3 7139.1 7140.4 29764.6 30019.2 31404.7 31659.5 31824.2 2063.2 2091.8 2094.1 2094.6 2097.0 20821.2 21241.0 21275.3 21314.3 21452.2 2186.1 2232.6 2388.6 2438.4 2567.0 137109.0 142085.0 142190.6 142233.3 142419.3 20873.8 21547.0 21636.8 22244.3 22761.9 42988.1 43279.1 43318.6 43323.4 43354.0 11158.3 11471.2 11516.9 11581.1 11597.2 8291.8 8661.3 9399.0 9474.0 9804.5 10907.6 11355.4 11561.6 11838.6 11994.0 4029.0 4063.9 4137.6 4281.5 4751.8 964.8 991.4 1002.1 1004.9 1007.6 19049.6 19374.8 19538.0 19559.3 19761.4 1043.8 1052.3 1058.2 1060.6 1064.8 10529.8 10702.6 10715.3 11023.3 11143.9 474696.0 474757.0 477096.0 477467.0 483598.0 115355.0 117055.4 117201.4 117479.0 118259.4 1141.2 1147.5 1170.1 1178.8 1181.6 17341.3 17350.2 17361.7 17672.0 17902.6 54644.2 55753.4 56082.4 57293.7 57993.0 67079.0 69576.7 70456.3 70794.6 71078.6 14985.0 15322.9 16382.5 19380.9 20322.6 22361.6 22582.4 22730.2 22761.0 22832.3 17964.9 18216.1 18219.5 18242.5 18326.9 16169.4 16245.6 16958.9 17036.7 17231.5 18139.2 18296.5 18375.4 18427.2 18471.4 19415.3 19519.7 19564.2 19607.3 19707.6 16648.7 18007.5 18205.6 18256.0 18262.4 11:34:04 INFO - PID 3815 | 11:34:04 INFO - PID 3815 | [#3] mootools.html Cycles:180 Average:9509.56 Median:2709.84 stddev:6875.80 (253.7%) stddev-sans-first:6867.52 11:34:04 INFO - PID 3815 | Values: 1315.0 1421.7 1423.5 1424.0 1426.9 15653.1 16068.3 16217.2 16234.5 16250.6 15722.1 15859.3 15862.0 15891.3 15893.6 2710.7 2778.9 2798.7 2801.4 2802.1 2716.0 2731.8 2752.2 2752.2 2753.0 2659.7 2693.8 2797.5 2798.7 2800.4 2846.7 2857.5 2889.9 2890.7 2890.9 2553.9 2700.7 2706.0 2708.9 2720.7 15410.2 15581.6 15617.5 15618.0 15636.8 8038.2 8403.5 8418.6 8437.1 8439.0 5399.0 5434.1 5468.8 5650.4 5699.5 1980.2 1980.4 1981.2 1982.7 2030.7 27196.7 27202.7 27263.2 27322.5 27435.5 7047.6 7079.9 7135.3 7172.5 7235.7 18009.5 18015.2 18029.2 18032.0 18076.5 7895.1 8167.1 8171.9 8171.9 8172.7 7429.9 7438.6 7494.2 7622.8 7635.9 5169.1 5277.6 5297.2 5317.3 5540.0 1478.7 1511.8 1519.5 1520.4 1532.0 887.8 900.0 903.8 910.3 913.2 7494.9 7600.9 7648.7 7702.4 7797.9 725.8 735.5 748.7 751.7 754.5 4708.5 5086.8 5193.8 5238.1 5323.8 21182.4 22422.9 22449.6 22617.3 22691.0 20936.7 22072.8 22160.8 22358.0 22441.3 10116.2 10568.8 10570.8 10572.9 10586.3 7414.7 7479.3 7537.2 7543.6 7556.3 17636.2 18255.5 18429.8 18444.7 18748.8 19485.8 19578.5 19595.8 19602.5 20105.0 10508.4 11452.1 11466.1 11483.0 11485.9 13852.4 14119.6 14200.4 14224.4 14562.3 11655.6 11692.9 11695.5 11737.5 11889.2 11455.9 11879.8 11936.8 11942.0 11956.3 10078.3 10120.3 10241.7 10295.6 10302.4 6988.8 7090.8 7188.6 7195.1 7278.1 7708.1 7757.9 7804.3 7808.0 7876.6 11:34:04 INFO - PID 3815 | 11:34:04 INFO - PID 3815 | [#4] prototype.html Cycles:180 Average:5425.28 Median:6087.88 stddev:1489.16 (24.5%) stddev-sans-first:1446.88 11:34:04 INFO - PID 3815 | Values: 508.5 532.2 536.4 537.8 543.5 6200.2 6212.0 6227.6 6246.4 6310.6 6085.7 6090.1 6133.4 6145.8 6157.1 5079.5 5085.2 5094.0 5136.4 5142.2 4866.9 4999.3 5122.2 5141.8 5156.4 4924.3 4937.3 4954.8 4988.2 4994.8 4894.3 4898.8 4939.6 4959.1 4981.5 4340.2 4482.2 4482.8 4494.3 4494.4 6016.0 6045.8 6055.4 6060.6 6062.0 5657.8 5658.0 5675.0 5680.3 5684.8 6448.3 6451.4 6457.9 6459.2 6499.6 4879.6 4890.3 4906.2 4920.2 4921.7 6537.2 6639.2 6663.4 6713.6 6751.2 4971.0 4999.0 5000.4 5013.6 5014.7 5024.2 5056.6 5060.3 5060.9 5080.2 5587.9 5612.6 5612.7 5616.2 5623.7 6468.7 6473.7 6480.1 6501.4 6501.8 1741.4 1754.6 1761.6 1766.3 1768.8 1172.7 1235.7 1235.8 1240.5 1246.3 5653.5 5657.8 5665.7 5670.2 5698.8 6571.8 6585.7 6600.5 6620.6 6652.0 5634.4 5636.6 5644.3 5653.6 5671.5 3453.5 3473.7 3503.7 3524.8 3535.9 6283.3 6346.5 6362.2 6367.5 6370.7 6137.7 6493.2 6597.3 6667.2 6670.4 6256.2 6279.7 6316.5 6361.6 6444.5 6450.8 6470.3 6506.0 6640.4 6644.4 6461.8 6492.8 6518.9 6534.2 6542.7 6235.3 6308.3 6310.6 6340.3 6374.2 4916.9 4941.8 4943.2 4966.4 4985.7 6201.3 6552.7 6570.9 6571.6 6598.2 6178.9 6330.4 6385.7 6398.6 6426.5 6322.5 6329.9 6369.3 6394.6 6434.2 6193.6 6200.4 6255.5 6285.2 6311.9 6348.7 6382.3 6389.2 6396.8 6479.6 6132.0 6196.4 6224.2 6277.9 6287.9 11:34:04 INFO - PID 3815 | 11:34:04 INFO - PID 3815 | [#5] yui.html Cycles:180 Average:23394.65 Median:20640.78 stddev:32739.08 (158.6%) stddev-sans-first:32787.21 11:34:04 INFO - PID 3815 | Values: 863.2 866.2 872.0 914.7 941.9 19023.5 19834.1 20376.7 20478.5 20500.0 17575.4 17792.9 17925.1 17939.9 18012.3 973.2 994.3 998.1 998.1 998.3 961.8 988.2 1000.4 1000.7 1001.4 974.4 1066.1 1066.4 1066.5 1068.0 1005.1 1014.4 1016.7 1016.9 1017.1 741.9 746.4 748.4 749.9 750.6 19597.8 20419.8 20579.3 20702.3 20714.9 22129.0 22136.1 22684.8 22715.7 22718.0 17463.3 17885.7 17900.7 17901.6 17919.3 3761.8 3786.1 3817.4 3830.4 3832.9 124824.0 125056.0 125419.4 126759.4 127076.4 17028.8 17115.4 17185.3 17191.0 17317.3 30884.5 31243.4 32171.0 32378.7 32430.2 8326.8 8405.9 8428.9 8435.3 8445.4 4107.2 4156.4 4191.9 4230.5 4250.0 5325.2 5426.6 5428.1 5430.0 5443.7 931.8 970.8 978.9 981.1 984.2 581.3 638.8 654.2 668.8 668.9 11898.9 11936.2 12279.2 12924.2 12936.6 638.2 644.4 650.2 654.8 658.1 5780.6 5842.4 5869.3 5967.0 6035.4 115840.0 116499.0 116620.0 117448.4 120988.0 97646.0 97751.0 98205.0 98958.0 100555.0 90671.5 91408.0 92101.5 93078.5 93111.0 10990.8 11208.4 11213.9 11251.5 11377.5 42262.6 42769.4 43297.8 44156.8 44388.0 54393.7 55193.7 55303.7 55662.2 57097.4 15852.5 15883.3 15902.0 15969.3 16028.3 17634.5 17836.0 17868.7 17905.4 18172.1 15814.4 15948.5 16091.5 16128.4 16129.5 15481.8 15582.8 15666.2 15718.8 15845.8 14887.3 15185.8 15215.3 15317.6 15318.3 10356.8 10454.2 10490.0 10612.4 10889.1 10987.6 11208.4 11265.3 11294.0 11383.0 11:34:04 INFO - PID 3815 | -------- Summary: end -------- 11:34:04 INFO - PID 3815 | 11:34:05 INFO - TEST-INFO | 3815: exit 0 11:34:05 INFO - TEST-OK | dromaeo_css | took 1111254ms 11:34:05 INFO - TEST-START | kraken 11:34:05 INFO - Initialising browser for kraken test... 11:34:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39120/getInfo.html -profile /tmp/tmpyIvJ6D/profile 11:34:05 INFO - TEST-INFO | started process 4186 (/builds/slave/test/build/application/firefox/firefox http://localhost:39120/getInfo.html) 11:34:10 INFO - TEST-INFO | 4186: exit 0 11:34:10 INFO - Browser initialized. 11:34:10 INFO - Running cycle 1/1 for kraken test... 11:34:10 INFO - Using env: {'DISPLAY': ':0', 11:34:10 INFO - 'HOME': '/home/cltbld', 11:34:10 INFO - 'JSGC_DISABLE_POISONING': '1', 11:34:10 INFO - 'LANG': 'en_US.UTF-8', 11:34:10 INFO - 'LANGUAGE': 'en_US:en', 11:34:10 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:34:10 INFO - 'LOGNAME': 'cltbld', 11:34:10 INFO - 'MAIL': '/var/mail/cltbld', 11:34:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:34:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:34:10 INFO - 'MOZ_CRASHREPORTER': '1', 11:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:34:10 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:34:10 INFO - 'MOZ_NO_REMOTE': '1', 11:34:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:34:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:34:10 INFO - 'NO_EM_RESTART': '1', 11:34:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:34:10 INFO - 'PWD': '/builds/slave/test', 11:34:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:34:10 INFO - 'RUST_BACKTRACE': 'full', 11:34:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:34:10 INFO - 'SHELL': '/bin/bash', 11:34:10 INFO - 'SHLVL': '1', 11:34:10 INFO - 'TERM': 'linux', 11:34:10 INFO - 'TMOUT': '86400', 11:34:10 INFO - 'USER': 'cltbld', 11:34:10 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1506535348.39616-1440211374', 11:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:34:10 INFO - '_': '/tools/buildbot/bin/python'} 11:34:10 INFO - TEST-INFO | started process 4414 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyIvJ6D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:34:11 INFO - PID 4414 | 11:34:11 INFO - PID 4414 | (/builds/slave/test/build/application/firefox/firefox:4463): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:34:11 INFO - PID 4414 | 11:34:21 INFO - PID 4414 | RSS: Main: 153772032 11:34:21 INFO - PID 4414 | 11:34:24 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39120/tests/kraken/driver.html?testName=audio-beat-detection) 11:34:25 INFO - PID 4414 | RSS: Main: 159514624 11:34:25 INFO - PID 4414 | 11:34:28 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39120/tests/kraken/driver.html?testName=audio-dft) 11:34:28 INFO - PID 4414 | RSS: Main: 159522816 11:34:28 INFO - PID 4414 | 11:34:31 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39120/tests/kraken/driver.html?testName=audio-fft) 11:34:32 INFO - PID 4414 | RSS: Main: 155889664 11:34:32 INFO - PID 4414 | 11:34:34 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39120/tests/kraken/driver.html?testName=audio-oscillator) 11:34:34 INFO - PID 4414 | RSS: Main: 156303360 11:34:34 INFO - PID 4414 | 11:34:37 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39120/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:34:37 INFO - PID 4414 | RSS: Main: 156397568 11:34:37 INFO - PID 4414 | 11:34:40 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39120/tests/kraken/driver.html?testName=imaging-darkroom) 11:34:41 INFO - PID 4414 | RSS: Main: 159035392 11:34:41 INFO - PID 4414 | 11:34:45 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39120/tests/kraken/driver.html?testName=imaging-desaturate) 11:34:45 INFO - PID 4414 | RSS: Main: 159887360 11:34:45 INFO - PID 4414 | 11:34:48 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39120/tests/kraken/driver.html?testName=json-parse-financial) 11:34:49 INFO - PID 4414 | RSS: Main: 159674368 11:34:49 INFO - PID 4414 | 11:34:51 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39120/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:34:51 INFO - PID 4414 | RSS: Main: 159797248 11:34:51 INFO - PID 4414 | 11:34:53 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39120/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:34:53 INFO - PID 4414 | RSS: Main: 159731712 11:34:53 INFO - PID 4414 | 11:34:55 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39120/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:34:56 INFO - PID 4414 | RSS: Main: 156708864 11:34:56 INFO - PID 4414 | 11:34:59 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39120/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:34:59 INFO - PID 4414 | RSS: Main: 157089792 11:34:59 INFO - PID 4414 | 11:35:02 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39120/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:35:03 INFO - PID 4414 | RSS: Main: 157212672 11:35:03 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | Cycle 1(1): loaded http://localhost:39120/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39120/tests/kraken/driver.html?testName=ai-astar) 11:35:05 INFO - PID 4414 | RSS: Main: 158380032 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | __start_tp_report 11:35:05 INFO - PID 4414 | _x_x_mozilla_page_load 11:35:05 INFO - PID 4414 | _x_x_mozilla_page_load_details 11:35:05 INFO - PID 4414 | |i|pagename|runs| 11:35:05 INFO - PID 4414 | |0;ai-astar;109;106;109;108;108;108;107;107;110;107 11:35:05 INFO - PID 4414 | |1;audio-beat-detection;126;118;119;116;117;117;121;119;117;120 11:35:05 INFO - PID 4414 | |2;audio-dft;162;174;163;162;162;163;162;166;176;163 11:35:05 INFO - PID 4414 | |3;audio-fft;76;77;75;74;74;74;74;74;74;75 11:35:05 INFO - PID 4414 | |4;audio-oscillator;79;80;80;78;77;78;82;79;78;78 11:35:05 INFO - PID 4414 | |5;imaging-gaussian-blur;113;114;116;111;114;114;113;113;116;118 11:35:05 INFO - PID 4414 | |6;imaging-darkroom;150;148;148;148;149;148;148;150;149;148 11:35:05 INFO - PID 4414 | |7;imaging-desaturate;94;94;94;94;96;98;95;95;94;95 11:35:05 INFO - PID 4414 | |8;json-parse-financial;64;57;53;55;56;58;59;61;55;53 11:35:05 INFO - PID 4414 | |9;json-stringify-tinderbox;38;38;39;38;39;38;40;39;43;38 11:35:05 INFO - PID 4414 | |10;stanford-crypto-aes;74;82;80;72;71;75;74;73;79;74 11:35:05 INFO - PID 4414 | |11;stanford-crypto-ccm;109;114;110;110;112;110;112;112;117;108 11:35:05 INFO - PID 4414 | |12;stanford-crypto-pbkdf2;167;164;173;174;163;163;166;165;176;169 11:35:05 INFO - PID 4414 | |13;stanford-crypto-sha256-iterative;69;65;68;62;63;69;69;78;65;66 11:35:05 INFO - PID 4414 | __end_tp_report 11:35:05 INFO - PID 4414 | __start_cc_report 11:35:05 INFO - PID 4414 | _x_x_mozilla_cycle_collect,746 11:35:05 INFO - PID 4414 | __end_cc_report 11:35:05 INFO - PID 4414 | __startTimestamp1506537305438__endTimestamp 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | ------- Summary: start ------- 11:35:05 INFO - PID 4414 | Number of tests: 14 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#0] ai-astar Cycles:10 Average:107.90 Median:108.00 stddev:1.20 (1.1%) stddev-sans-first:1.20 11:35:05 INFO - PID 4414 | Values: 109.0 106.0 109.0 108.0 108.0 108.0 107.0 107.0 110.0 107.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#1] audio-beat-detection Cycles:10 Average:119.00 Median:119.00 stddev:2.91 (2.4%) stddev-sans-first:1.64 11:35:05 INFO - PID 4414 | Values: 126.0 118.0 119.0 116.0 117.0 117.0 121.0 119.0 117.0 120.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#2] audio-dft Cycles:10 Average:165.30 Median:163.00 stddev:5.27 (3.2%) stddev-sans-first:5.45 11:35:05 INFO - PID 4414 | Values: 162.0 174.0 163.0 162.0 162.0 163.0 162.0 166.0 176.0 163.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#3] audio-fft Cycles:10 Average:74.70 Median:74.50 stddev:1.06 (1.4%) stddev-sans-first:1.01 11:35:05 INFO - PID 4414 | Values: 76.0 77.0 75.0 74.0 74.0 74.0 74.0 74.0 74.0 75.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#4] audio-oscillator Cycles:10 Average:78.90 Median:79.00 stddev:1.45 (1.8%) stddev-sans-first:1.54 11:35:05 INFO - PID 4414 | Values: 79.0 80.0 80.0 78.0 77.0 78.0 82.0 79.0 78.0 78.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:1.99 (1.7%) stddev-sans-first:2.06 11:35:05 INFO - PID 4414 | Values: 113.0 114.0 116.0 111.0 114.0 114.0 113.0 113.0 116.0 118.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#6] imaging-darkroom Cycles:10 Average:148.60 Median:148.50 stddev:0.84 (0.6%) stddev-sans-first:0.73 11:35:05 INFO - PID 4414 | Values: 150.0 148.0 148.0 148.0 149.0 148.0 148.0 150.0 149.0 148.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:1.29 (1.4%) stddev-sans-first:1.32 11:35:05 INFO - PID 4414 | Values: 94.0 94.0 94.0 94.0 96.0 98.0 95.0 95.0 94.0 95.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.50 stddev:3.51 (6.1%) stddev-sans-first:2.69 11:35:05 INFO - PID 4414 | Values: 64.0 57.0 53.0 55.0 56.0 58.0 59.0 61.0 55.0 53.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#9] json-stringify-tinderbox Cycles:10 Average:39.00 Median:39.00 stddev:1.56 (4.0%) stddev-sans-first:1.62 11:35:05 INFO - PID 4414 | Values: 38.0 38.0 39.0 38.0 39.0 38.0 40.0 39.0 43.0 38.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:74.50 stddev:3.66 (4.9%) stddev-sans-first:3.84 11:35:05 INFO - PID 4414 | Values: 74.0 82.0 80.0 72.0 71.0 75.0 74.0 73.0 79.0 74.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#11] stanford-crypto-ccm Cycles:10 Average:111.40 Median:112.00 stddev:2.63 (2.4%) stddev-sans-first:2.65 11:35:05 INFO - PID 4414 | Values: 109.0 114.0 110.0 110.0 112.0 110.0 112.0 112.0 117.0 108.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.00 stddev:4.78 (2.8%) stddev-sans-first:5.06 11:35:05 INFO - PID 4414 | Values: 167.0 164.0 173.0 174.0 163.0 163.0 166.0 165.0 176.0 169.0 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.50 stddev:4.50 (6.6%) stddev-sans-first:4.74 11:35:05 INFO - PID 4414 | Values: 69.0 65.0 68.0 62.0 63.0 69.0 69.0 78.0 65.0 66.0 11:35:05 INFO - PID 4414 | -------- Summary: end -------- 11:35:05 INFO - PID 4414 | 11:35:05 INFO - PID 4414 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:35:06 INFO - TEST-INFO | 4414: exit 0 11:35:06 INFO - TEST-OK | kraken | took 60884ms 11:35:06 INFO - SUITE-END | took 1172s 11:35:06 INFO - Completed test suite (00:19:32) 11:35:06 INFO - javascript benchmark 11:35:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8593.044246614227, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3203.7757356985007, 3214.82318472484, 3230.806151630904, 3246.4481038223507, 3255.039763269835, 6348.36516348365, 6442.484601231902, 6483.481321494275, 6501.869962600746, 6505.381988711077, 6248.593841400306, 6343.397377249165, 6356.173697419329, 6373.713182906768, 6397.360263973612, 5837.678927658988, 5906.084556893688, 5907.4978626816555, 5928.081147422156, 5941.28704555454, 5886.617369870966, 5929.28848538176, 5941.88116237675, 5952.077427998667, 5987.940120598803, 5777.913331300034, 5806.622569532988, 5872.295324561037, 5894.705264736767, 5942.168096466498, 5860.296764388258, 5866.941330586682, 5950.0, 5953.166556682025, 5954.255718035246, 5742.74157662902, 5769.0, 5821.126830975388, 5863.091220860773, 5895.793647222326, 6358.42774150324, 6372.681365931685, 6378.872422551523, 6399.488040956712, 6447.6131432114225, 6807.795766126975, 6831.487638427088, 6917.412019978308, 6928.757493487754, 6930.965345173292, 6709.36261055199, 6893.931060689379, 6902.24075351716, 6994.230634630187, 6995.790126296169, 6292.685365731695, 6335.429811317003, 6404.615723056585, 6408.0, 6414.935850641527, 7025.89461158083, 7064.32888875556, 7107.111611048619, 7125.465590080661, 7135.215126336099, 6713.395794378529, 6724.361185687352, 6730.831729206809, 6818.8636227276165, 6822.795316140524, 6705.530612857053, 6774.627395493197, 6881.862362752717, 6957.339052789976, 6971.860562788817, 6696.363845434676, 6702.296258892844, 6706.228783689938, 6714.832129196711, 6782.253952065269, 6778.2205046420295, 6845.8973115403305, 6876.862462750717, 6883.3116688330765, 6887.311268873173, 5181.455947125498, 5253.55344795689, 5284.260203571503, 5338.3060202173465, 5348.197770334481, 4617.838375656903, 4640.605548528345, 4681.836135735233, 4691.319758634979, 4693.967327188013, 5887.1463637772285, 5898.76404943806, 5928.496077833346, 5957.821265362046, 5958.582899197101, 6656.70044847983, 6702.731890724316, 6800.931990680129, 6825.965870170618, 6846.349596788297, 5887.4406931341455, 5922.526197904157, 5942.8811423771285, 5943.554233432424, 5949.345571987078, 5841.2990441146485, 5919.230500034968, 5922.585419020714, 5933.258342707161, 5952.077427998667, 6620.668966551653, 6637.966810165918, 6726.764563240263, 6745.0556922030955, 6751.426128779109, 6699.196096468455, 6771.830704232433, 6840.657967101525, 6853.588784672936, 6954.999999999899, 6557.770478033246, 6639.966800166161, 6665.800025999228, 6681.198256209092, 6689.1972963244725, 6709.194896612437, 6713.966430167818, 6799.048133261446, 6801.863962720817, 6814.2163651180745, 6645.60126392418, 6738.258791533026, 6755.42578880781, 6859.451243900576, 6893.586384816927, 6495.675216239264, 6514.902276465762, 6517.152770139852, 6521.380468855451, 6526.67366631657, 6567.080608714688, 6577.2436169841085, 6656.700448479927, 6666.833329166616, 6670.366315200048, 6511.218653761579, 6614.702338394687, 6620.271770105188, 6624.6025238485845, 6625.933740662725, 6547.1161393213215, 6655.534112612264, 6702.631355275506, 6722.058911752456, 6748.595084294958, 6299.244090709144, 6361.459275961595, 6401.0, 6408.198975128109, 6507.804765857032], "value": 6225.075318077338, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [855.944363616365, 863.499170481121, 877.9209871111599, 886.4459712679575, 902.9593668284929, 39574.40638390423, 40583.188336233296, 40874.79562602183, 40956.38565421521, 41104.38343424843, 36805.63194368056, 36946.0, 37171.25657486849, 37205.62794372055, 37226.44160337597, 7276.9272307276915, 7365.005724227241, 7405.296496832819, 7420.035395398618, 7426.183119856812, 7152.892706609405, 7226.674799634003, 7230.349268565828, 7234.312740289664, 7236.0, 7763.184865589116, 8002.159773223786, 8007.51954882706, 8008.2392172743785, 8030.39772017101, 7923.12845586985, 7982.760517184493, 7985.720499782537, 7992.040955085398, 7999.480033797814, 7659.655315510815, 7882.842343153162, 7898.447108702336, 7904.209579042107, 7969.362451003905, 38028.239435211144, 38332.425013624816, 38412.42381364254, 38415.27131279099, 38445.0, 3194.7603929705365, 3221.098092534094, 3237.077432931603, 3238.6599407062154, 3243.108145260049, 30946.226344341347, 30997.22506937322, 31081.378372432424, 31112.377752445052, 31127.22181945447, 5196.038732834438, 5196.76614552346, 5234.816781412632, 5266.894662106774, 5277.366715994067, 182198.0, 183227.0, 183540.0, 183609.0819545907, 183993.0, 45417.77291113523, 45510.31734523985, 45609.0, 45769.31346029812, 45819.31271030937, 47444.0, 47560.286595701094, 47579.5242047582, 47581.524184758404, 47618.5238147621, 15669.686606267811, 15909.443169489245, 16037.0, 16045.598860028593, 16075.276612552463, 13241.139325943783, 13258.602241932758, 13271.203727776365, 13275.601731948056, 13283.535076272285, 13297.867021329663, 13311.467541298432, 13405.262710550818, 13457.125286856322, 13561.796573051412, 5515.310586176728, 5530.751116199781, 5635.746391412396, 5656.60403771732, 5740.613038307335, 1012.9594816207269, 1018.1701912940929, 1022.8619136416488, 1029.5006921642996, 1037.5953378182514, 22539.436514086952, 22555.210567630053, 22672.546549068928, 22872.0, 23010.424739381648, 1114.0753174865006, 1122.8764835868049, 1126.6957921361186, 1128.966131016071, 1129.8870112988636, 11760.88239117615, 11954.16320857549, 12012.159148859497, 12068.939655301843, 12070.27578345302, 217376.0, 217551.0, 217704.0, 218486.0, 218701.0, 49724.0, 52928.20607690888, 54333.45666543284, 54342.45657543374, 54381.72809135929, 1457.7303198908237, 1471.4261438039173, 1480.6002379357512, 1485.442958890416, 1485.4949317231976, 26541.60187597149, 26917.596236056866, 26935.461290774467, 27085.458290834467, 27515.86242068777, 37798.62201378061, 37804.81097594494, 37830.054248644, 37839.62160378361, 37867.431988520744, 140567.0, 140744.0, 140796.0, 141113.0, 141365.0, 32386.0, 33458.83270583729, 33524.32951340911, 33571.66428335783, 33585.83207083949, 39497.80251098726, 40943.59056409517, 41203.38194927138, 41244.587554125275, 41461.58538414577, 30839.691603083684, 30904.227394315323, 31106.688933111283, 31421.528677069404, 31423.52864707077, 30797.0, 30919.381612368077, 31206.843965780026, 31275.218119547193, 31770.52344214885, 35351.0, 35409.29181416409, 35423.0, 35673.82163089168, 35707.28585428329, 30790.076297711104, 30872.0, 30873.934423779156, 30985.535216972214, 30994.69005309918, 22581.548369032855, 22940.77059229453, 22941.199952097533, 23880.40298992539, 23920.40198995039], "value": 16753.37980401399, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.1025607798963, 596.6479776931609, 601.2184160591233, 612.6446660936657, 614.4930432393276, 21817.0, 22848.65727014094, 23371.0, 23388.649170262437, 23434.0, 26291.079812206575, 26784.59823102655, 27044.18867433975, 27092.18723438295, 27102.45795084097, 7325.3040961108745, 7490.925090749104, 7491.475596708233, 7495.66269517873, 7499.100107987049, 6989.650517474131, 7303.89044164338, 7321.670524826369, 7330.853382932338, 7353.77938661841, 7284.4172466202845, 7284.635768211595, 7287.927120728805, 7293.963530182342, 7294.598797066159, 7266.164391095039, 7276.235995220479, 7276.272372762734, 7277.3086556777025, 7294.963525182393, 7106.538075025107, 7113.608751518665, 7125.287471252885, 7139.143302803697, 7140.428765698732, 29764.55353169704, 30019.249518761986, 31404.685953140634, 31659.52510712318, 31824.204394890083, 2063.2366024570856, 2091.801278878504, 2094.089071253994, 2094.6020256151437, 2096.9580608387746, 20821.167153314, 21241.0, 21275.255366062112, 21314.254001110043, 21452.24917127893, 2186.10369748404, 2232.620454522735, 2388.617821148608, 2438.3904023994, 2567.0, 137109.0, 142085.0, 142190.57809421877, 142233.2888335562, 142419.28790355983, 20873.79126208749, 21547.0, 21636.78363216379, 22244.3326700196, 22761.88619056894, 42988.14023719508, 43279.13441731148, 43318.566814331454, 43323.35014974778, 43354.0, 11158.274712143848, 11471.197016208785, 11516.942415288038, 11581.131415143898, 11597.246178998506, 8291.751247462586, 8661.263792577702, 9398.95300523493, 9473.952630236943, 9804.509774511389, 10907.618233361794, 11355.432228388547, 11561.595344162914, 11838.585649502225, 11994.0, 4028.959710402917, 4063.878083657495, 4137.565555616678, 4281.529031806499, 4751.809927602926, 964.7781010367655, 991.4150651115732, 1002.0730823987782, 1004.8743907011624, 1007.6473234367912, 19049.61900761977, 19374.80625193758, 19538.023098845002, 19559.31542396009, 19761.40715778529, 1043.7964596903682, 1052.2634156090767, 1058.2380685906135, 1060.5598676549146, 1064.797688439194, 10529.84205236922, 10702.571897124184, 10715.284156715548, 11023.267900691755, 11143.888561114447, 474696.0, 474757.0, 477096.0, 477467.0, 483598.0, 115355.0, 117055.41472292584, 117201.41399292949, 117479.0, 118259.40870295766, 1141.2296699727651, 1147.5065721739732, 1170.1107158559555, 1178.8113901775846, 1181.6041626055369, 17341.306347746453, 17350.21924013397, 17361.739573906652, 17672.0, 17902.641947161243, 54644.18033729418, 55753.442465576445, 56082.439175609354, 57293.71353143208, 57993.0, 67079.0, 69576.65211673909, 70456.29543704497, 70794.64602676954, 71078.64460677664, 14985.0, 15322.923385383001, 16382.508524744277, 19380.90309548443, 20322.59354812925, 22361.552768944854, 22582.435439113495, 22730.20444284442, 22761.0, 22832.31503054911, 17964.91017544904, 18216.089195539906, 18219.54451138732, 18242.543936401697, 18326.908365458086, 16169.43406980797, 16245.59386015359, 16958.915205423895, 17036.74444883303, 17231.483055508354, 18139.183736732142, 18296.451106466826, 18375.356862509747, 18427.17077731532, 18471.353502627342, 19415.3204637837, 19519.707204391663, 19564.217431303157, 19607.31374401889, 19707.60584788325, 16648.66702665964, 18007.459776206735, 18205.635887282446, 18256.0, 18262.36081737133], "value": 13005.540283918623, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1314.9539766108187, 1421.7156568686262, 1423.501774378967, 1424.0, 1426.9286535673214, 15653.139077350741, 16068.276927538289, 16217.189140542985, 16234.51296461105, 16250.593735156626, 15722.135282559455, 15859.286332115054, 15862.0, 15891.28489217985, 15893.602659933507, 2710.7424794644476, 2778.8749506272165, 2798.706135855726, 2801.3836955869674, 2802.0893209706824, 2716.0493827160435, 2731.754142127208, 2752.160591019748, 2752.1881045091745, 2753.022676949684, 2659.6808382993972, 2693.757561819436, 2797.5104356737593, 2798.664160300767, 2800.355918138819, 2846.6584009918733, 2857.5285077962217, 2889.8844046238123, 2890.7398334150025, 2890.9421811563757, 2553.885075171603, 2700.7434293742062, 2706.0, 2708.9458210835674, 2720.659917510311, 15410.229488525642, 15581.610459738484, 15617.453389131439, 15618.0, 15636.843631563766, 8038.196180381973, 8403.5378054207, 8418.579071046423, 8437.114103019218, 8438.957805210935, 5399.0, 5434.076207044812, 5468.835934921959, 5650.378458369576, 5699.544036477072, 1980.1683293016968, 1980.4058782365378, 1981.2075169931998, 1982.6926826341796, 2030.6953956906434, 27196.728032719617, 27202.72797272022, 27263.182104536896, 27322.45355092907, 27435.45129097407, 7047.577145371294, 7079.92920070803, 7135.250798666169, 7172.46206534512, 7235.710571577183, 18009.549761256072, 18015.18931648079, 18029.18868650914, 18032.0, 18076.457706268833, 7895.092064412551, 8167.060788009336, 8171.877421838677, 8171.877421838677, 8172.6730930762105, 7429.851402972018, 7438.6280685965485, 7494.21310762373, 7622.771316860503, 7635.885461718079, 5169.06956747789, 5277.551408130275, 5297.205419187076, 5317.282166907496, 5540.0, 1478.7042591481747, 1511.780791785185, 1519.4985654733912, 1520.3994422203389, 1531.9846801532065, 887.8401887660154, 899.9775005624781, 903.7695387676232, 910.2626872233512, 913.2100732866023, 7494.850102998019, 7600.923990760132, 7648.65581048843, 7702.422318326036, 7797.94727711763, 725.7786375155523, 735.5219107580009, 748.655618415535, 751.6955632968657, 754.5171090502083, 4708.529147085264, 5086.821961231339, 5193.792248310101, 5238.135707608202, 5323.840284791462, 21182.364529064154, 22422.887885560467, 22449.551008979728, 22617.321480355615, 22691.0, 20936.685949710765, 22072.779272207074, 22160.778392216194, 22358.0, 22441.32676019722, 10116.241281903887, 10568.788624227625, 10570.788584228272, 10572.89427105719, 10586.311889727143, 7414.658509177176, 7479.326860582573, 7537.2462753724185, 7543.585102819397, 7556.282153195438, 17636.206370713604, 18255.452336429928, 18429.81570184281, 18444.723329150343, 18748.81251187471, 19485.8051419484, 19578.51053723668, 19595.804041959967, 19602.50993725168, 20105.0, 10508.369497830156, 11452.141089418494, 11466.140039497239, 11483.0, 11485.942570287096, 13852.445902163801, 14119.64700882486, 14200.360983755962, 14224.431022758974, 14562.271886405426, 11655.59205427806, 11692.88307116952, 11695.473703683185, 11737.47181376823, 11889.167758256839, 11455.942720286344, 11879.76240475203, 11936.76126477483, 11942.044636428891, 11956.34240116802, 10078.294519383573, 10120.342177758423, 10241.692749217535, 10295.588176472857, 10302.433366164933, 6988.825279368057, 7090.822729431806, 7188.640567971476, 7195.100612423447, 7278.126624804845, 7708.113566939649, 7757.92242077572, 7804.336631386169, 7808.0, 7876.645550950336], "value": 6733.842767952319, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.4585859488663, 532.2043544900374, 536.3804805449706, 537.760696490062, 543.4836904940305, 6200.224971878515, 6212.0, 6227.595206311598, 6246.437820596146, 6310.621362718229, 6085.695715214244, 6090.056041313593, 6133.417325354096, 6145.846353841156, 6157.107219453176, 5079.476157152858, 5085.1863701807715, 5093.974530127344, 5136.357955255593, 5142.151544995071, 4866.902661946777, 4999.250112483121, 5122.180451127821, 5141.845744627649, 5156.355455568054, 4924.335214745999, 4937.259411088329, 4954.826581069663, 4988.201887697969, 4994.750262486879, 4894.314795928537, 4898.779554920002, 4939.60483161348, 4959.132151873426, 4981.505548335521, 4340.197063543223, 4482.21561226784, 4482.798274077674, 4494.348319493655, 4494.415725955638, 6015.969920150415, 6045.848853778647, 6055.394460553954, 6060.575759696823, 6061.969690151565, 5657.8019769308285, 5657.971710141464, 5675.0, 5680.289963754531, 5684.772609095631, 6448.258450278231, 6451.387118223761, 6457.903131453032, 6459.192600924885, 6499.5775274607, 4879.609631229493, 4890.315355850184, 4906.215005599122, 4920.188168952119, 4921.680090794122, 6537.1501704778075, 6639.236487803916, 6663.366980136928, 6713.597184168917, 6751.223609284946, 4970.950290497121, 4998.975005124951, 5000.374953130859, 5013.573846223112, 5014.724190169502, 5024.2463630454995, 5056.57019153376, 5060.266261392151, 5060.949390506122, 5080.161773307437, 5587.916181257285, 5612.635178713371, 5612.747426365823, 5616.213730077792, 5623.718814059363, 6468.708908099147, 6473.708683109271, 6480.060391243243, 6501.382368674968, 6501.804945851632, 1741.4427383237496, 1754.649070185968, 1761.647670465912, 1766.258171567919, 1768.8496477799274, 1172.6716519374588, 1235.734317121823, 1235.8393408856791, 1240.4542001519303, 1246.3207551884245, 5653.491185793298, 5657.830265092054, 5665.716714164358, 5670.193254339046, 5698.800541981011, 6571.835704107436, 6585.703643336062, 6600.537962342686, 6620.635865027373, 6651.966740166365, 5634.436556344414, 5636.577256705764, 5644.266245388073, 5653.575981801381, 5671.517920976763, 3453.5149885548954, 3473.6873681368797, 3503.667151620592, 3524.823758812049, 3535.9292814144087, 6283.277423096311, 6346.460550853137, 6362.204724409449, 6367.522435817332, 6370.6814659267775, 6137.693115344215, 6493.155889734398, 6597.30728273534, 6667.233268174125, 6670.433013193836, 6256.249250090018, 6279.717412716439, 6316.463100636405, 6361.61830290184, 6444.516661250331, 6450.838729031811, 6470.255920569007, 6505.967470162619, 6640.369164929324, 6644.4020038196795, 6461.8061458156335, 6492.805215843532, 6518.869622607616, 6534.150560427114, 6542.738290468328, 6235.314115447388, 6308.274548426806, 6310.6213627182515, 6340.270868850142, 6374.1953540442055, 4916.8770780730765, 4941.802327906844, 4943.209086546135, 4966.379202599675, 4985.651004429655, 6201.286852012076, 6552.737890484327, 6570.901436478361, 6571.572847765071, 6598.241202261801, 6178.876422471615, 6330.3669633036325, 6385.712642931172, 6398.648074355955, 6426.4537514311805, 6322.462590679844, 6329.9050514243245, 6369.299377068612, 6394.584352017104, 6434.163558737521, 6193.566450348612, 6200.410960958701, 6255.499560035278, 6285.182926219562, 6311.936880631135, 6348.650824204713, 6382.32985536512, 6389.16940797712, 6396.776112836028, 6479.643619600967, 6131.96934015345, 6196.442320191204, 6224.190855188796, 6277.869983403031, 6287.937120628735], "value": 4993.469750046634, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [863.1756672377876, 866.2117473099481, 871.9869201961972, 914.7255823253024, 941.9293552983526, 19023.52441188971, 19834.107465164067, 20376.694349584748, 20478.488037799063, 20500.0, 17575.38486152985, 17792.91103544484, 17925.10374481274, 17939.91030044848, 18012.279508819665, 973.2214228617096, 994.2791476179784, 998.0717932322937, 998.1166667499243, 998.2862253488754, 961.8364877970762, 988.234118558116, 1000.4297550396241, 1000.7448100734339, 1001.35412658835, 974.4202199691173, 1066.1364294921138, 1066.4188017530464, 1066.536056815284, 1067.9946600266987, 1005.1355833982769, 1014.3660212367271, 1016.6949915025463, 1016.8576399304103, 1017.0846238385475, 741.8664640364732, 746.4438992950287, 748.3788455581908, 749.8912657664663, 750.5684231566817, 19597.824130552213, 20419.79580204194, 20579.279725209544, 20702.275420360216, 20714.896425517927, 22129.0, 22136.114555417764, 22684.77315226843, 22715.659265111037, 22718.0, 17463.301467941266, 17885.7317140243, 17900.731489027676, 17901.552461188443, 17919.283228670836, 3761.849526018956, 3786.129190286222, 3817.4464702618243, 3830.3871380579253, 3832.8658496952753, 124824.0, 125056.0, 125419.3729031349, 126759.36620316839, 127076.36461817725, 17028.829711702972, 17115.400960966308, 17185.31258749661, 17191.0, 17317.30730770755, 30884.53673194904, 31243.37513249722, 32171.034868953968, 32378.67621323804, 32430.189245268586, 8326.750197494084, 8405.873911891327, 8428.873566896502, 8435.283000944988, 8445.366597505212, 4107.158032603309, 4156.355764856452, 4191.895202619959, 4230.471191101113, 4250.0, 5325.227841962894, 5426.647267927651, 5428.077226871442, 5430.049741295257, 5443.727813609304, 931.7810314576069, 970.7573106723319, 978.9314747967717, 981.1415011864618, 984.227381505529, 581.3488892440494, 638.8243233110932, 654.2149420695184, 668.7993601919404, 668.8561959178703, 11898.881011189951, 11936.224145430519, 12279.229601214636, 12924.159929604546, 12936.611901642966, 638.2213699286863, 644.3910504573133, 650.1742786660914, 654.7839213059679, 658.1444122640548, 5780.595358324961, 5842.386549412336, 5869.325027621878, 5967.035051464066, 6035.366286539938, 115840.0, 116499.0, 116620.0, 117448.41275793566, 120988.0, 97646.0, 97751.0, 98205.0, 98958.01041989632, 100555.0, 90671.54664226636, 91408.0, 92101.53949230211, 93078.53460732652, 93111.0, 10990.835137472784, 11208.383538905438, 11213.87398329466, 11251.549938002388, 11377.54489820398, 42262.57737422586, 42769.358459622505, 43297.78351108224, 44156.779216103714, 44388.0, 54393.72803135959, 55193.72403138119, 55303.72348138234, 55662.16506752321, 57097.429025709214, 15852.52442426729, 15883.285252163449, 15902.0, 15969.281382338055, 16028.27872745706, 17634.470965871045, 17836.0, 17868.731969020217, 17905.37331193454, 18172.09139542991, 15814.446494372642, 15948.521544353687, 16091.517254482384, 16128.435504757277, 16129.516114516584, 15481.767773483632, 15582.766258505904, 15666.216689165723, 15718.764218536502, 15845.841541584898, 14887.330070147085, 15185.772213416587, 15215.315310810818, 15317.6170595736, 15318.310676019382, 10356.844647330447, 10454.215933804842, 10490.0, 10612.416317102634, 10889.074428673652, 10987.560497580007, 11208.439578021229, 11265.26775759573, 11294.0, 11383.0], "value": 8827.03704766283, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1421.8000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 109.0, 108.0, 108.0, 108.0, 107.0, 107.0, 110.0, 107.0], "value": 107.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 118.0, 119.0, 116.0, 117.0, 117.0, 121.0, 119.0, 117.0, 120.0], "value": 119.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 174.0, 163.0, 162.0, 162.0, 163.0, 162.0, 166.0, 176.0, 163.0], "value": 165.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 75.0, 74.0, 74.0, 74.0, 74.0, 74.0, 74.0, 75.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 80.0, 78.0, 77.0, 78.0, 82.0, 79.0, 78.0, 78.0], "value": 78.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 116.0, 111.0, 114.0, 114.0, 113.0, 113.0, 116.0, 118.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 148.0, 148.0, 148.0, 149.0, 148.0, 148.0, 150.0, 149.0, 148.0], "value": 148.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 96.0, 98.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 57.0, 53.0, 55.0, 56.0, 58.0, 59.0, 61.0, 55.0, 53.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 38.0, 39.0, 38.0, 40.0, 39.0, 43.0, 38.0], "value": 39.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 82.0, 80.0, 72.0, 71.0, 75.0, 74.0, 73.0, 79.0, 74.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 114.0, 110.0, 110.0, 112.0, 110.0, 112.0, 112.0, 117.0, 108.0], "value": 111.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 173.0, 174.0, 163.0, 163.0, 166.0, 165.0, 176.0, 169.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 68.0, 62.0, 63.0, 69.0, 69.0, 78.0, 65.0, 66.0], "value": 67.4, "unit": "score"}]}]} 11:35:06 INFO - Return code: 0 11:35:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:35:06 INFO - # TBPL SUCCESS # 11:35:06 INFO - Running post-action listener: _package_coverage_data 11:35:06 INFO - Running post-action listener: _resource_record_post_action 11:35:06 INFO - [mozharness: 2017-09-27 18:35:06.697328Z] Finished run-tests step (success) 11:35:06 INFO - Running post-run listener: _resource_record_post_run 11:35:06 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 9498624; Write bytes: 402956288; Read time: 2892; Write time: 410540 11:35:06 INFO - TinderboxPrint: CPU usage
13.1% 11:35:06 INFO - TinderboxPrint: I/O read bytes / time
9,498,624 / 2,892 11:35:06 INFO - TinderboxPrint: I/O write bytes / time
402,956,288 / 410,540 11:35:06 INFO - TinderboxPrint: CPU idle
8,248.2 (86.9%) 11:35:06 INFO - TinderboxPrint: CPU user
1,192.8 (12.6%) 11:35:06 INFO - TinderboxPrint: Swap in / out
0 / 0 11:35:06 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 86716416; Read time: 0; Write time: 52228 11:35:06 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:35:06 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 7266304; Write bytes: 270508032; Read time: 2448; Write time: 308384 11:35:07 INFO - Running post-run listener: _upload_blobber_files 11:35:07 INFO - Blob upload gear active. 11:35:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:35:07 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 11:35:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:35:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:35:07 INFO - (blobuploader) - INFO - Open directory for files ... 11:35:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:35:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:35:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:08 INFO - (blobuploader) - INFO - Done attempting. 11:35:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:35:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:35:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:09 INFO - (blobuploader) - INFO - Done attempting. 11:35:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:35:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:35:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:10 INFO - (blobuploader) - INFO - Done attempting. 11:35:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:35:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:35:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:17 INFO - (blobuploader) - INFO - Done attempting. 11:35:17 INFO - (blobuploader) - INFO - Iteration through files over. 11:35:17 INFO - Return code: 0 11:35:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:35:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:35:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48e945c5b3a34d76f0a041ecd2b6e7fa3b544e848fa5aae6618bce4bc9929f1471bc9345fccfd76ffde2a3bcd4b5b42e267ed0b7572a09fc39c5a21ad551a8c5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9060bb5dc8d8a3d9678d2a489a6f1811de3908a95d359c0d86a338df8f3091aeaddf11965e14813a397d8bf0d5f3f4045a6474da74febda06bd128ce44c8939", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b3c540900ae103e7d74ce6669072256b9ebec4e5638b3af6bba2d9a716379fd55638d5a8334dbe6d772b1a3c14221ac73286d9b3beda4878a27a6ed3ff486f1e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af50be828e53656008e217281c6d8dcffd67d32ca365f0f694bcea79f6c3937e3a9501877642411ab37010fbc757405842c96c76261521b10a00ba6896fd168a"} 11:35:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:35:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:35:17 INFO - Contents: 11:35:17 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48e945c5b3a34d76f0a041ecd2b6e7fa3b544e848fa5aae6618bce4bc9929f1471bc9345fccfd76ffde2a3bcd4b5b42e267ed0b7572a09fc39c5a21ad551a8c5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9060bb5dc8d8a3d9678d2a489a6f1811de3908a95d359c0d86a338df8f3091aeaddf11965e14813a397d8bf0d5f3f4045a6474da74febda06bd128ce44c8939", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b3c540900ae103e7d74ce6669072256b9ebec4e5638b3af6bba2d9a716379fd55638d5a8334dbe6d772b1a3c14221ac73286d9b3beda4878a27a6ed3ff486f1e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af50be828e53656008e217281c6d8dcffd67d32ca365f0f694bcea79f6c3937e3a9501877642411ab37010fbc757405842c96c76261521b10a00ba6896fd168a"} 11:35:17 INFO - Running post-run listener: copy_logs_to_upload_dir 11:35:17 INFO - Copying logs to upload dir... 11:35:17 INFO - mkdir: /builds/slave/test/build/upload/logs 11:35:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.588814 ========= master_lag: 0.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-27 11:35:17.456736) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 11:35:17.461877) ========= 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=7de08804244eb36560b44971000001c3-1506535348.39616-1440211374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48e945c5b3a34d76f0a041ecd2b6e7fa3b544e848fa5aae6618bce4bc9929f1471bc9345fccfd76ffde2a3bcd4b5b42e267ed0b7572a09fc39c5a21ad551a8c5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9060bb5dc8d8a3d9678d2a489a6f1811de3908a95d359c0d86a338df8f3091aeaddf11965e14813a397d8bf0d5f3f4045a6474da74febda06bd128ce44c8939", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b3c540900ae103e7d74ce6669072256b9ebec4e5638b3af6bba2d9a716379fd55638d5a8334dbe6d772b1a3c14221ac73286d9b3beda4878a27a6ed3ff486f1e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af50be828e53656008e217281c6d8dcffd67d32ca365f0f694bcea79f6c3937e3a9501877642411ab37010fbc757405842c96c76261521b10a00ba6896fd168a"} build_url:https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011314 build_url: 'https://queue.taskcluster.net/v1/task/bGmhYXXHRU6bnifb1xql2Q/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/48e945c5b3a34d76f0a041ecd2b6e7fa3b544e848fa5aae6618bce4bc9929f1471bc9345fccfd76ffde2a3bcd4b5b42e267ed0b7572a09fc39c5a21ad551a8c5", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a9060bb5dc8d8a3d9678d2a489a6f1811de3908a95d359c0d86a338df8f3091aeaddf11965e14813a397d8bf0d5f3f4045a6474da74febda06bd128ce44c8939", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b3c540900ae103e7d74ce6669072256b9ebec4e5638b3af6bba2d9a716379fd55638d5a8334dbe6d772b1a3c14221ac73286d9b3beda4878a27a6ed3ff486f1e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/af50be828e53656008e217281c6d8dcffd67d32ca365f0f694bcea79f6c3937e3a9501877642411ab37010fbc757405842c96c76261521b10a00ba6896fd168a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-27 11:35:17.490230) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:35:17.490562) ========= 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=7de08804244eb36560b44971000001c3-1506535348.39616-1440211374 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-27 11:35:17.574338) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 11:35:17.574649) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-27 11:35:17.574975) ========= ========= Total master_lag: 0.41 =========