builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-059 starttime: 1506454925.49 results: success (0) revision: 39aaf54972cb11a63815a96b532786133baa95bc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:05.491815) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:05.492548) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:05.492834) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:05.555863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:05.556113) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-26 12:42:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 68.7M=0s 2017-09-26 12:42:05 (68.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.398157 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:05.978685) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:05.979013) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018319 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:06.031666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:06.032412) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39aaf54972cb11a63815a96b532786133baa95bc --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 39aaf54972cb11a63815a96b532786133baa95bc --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-26 12:42:06,027 truncating revision to first 12 chars 2017-09-26 12:42:06,028 Setting DEBUG logging. 2017-09-26 12:42:06,028 attempt 1/10 2017-09-26 12:42:06,028 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39aaf54972cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-26 12:42:06,308 unpacking tar archive at: mozilla-central-39aaf54972cb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.540820 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:06.595775) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:06.596537) ========= script_repo_revision: 39aaf54972cb11a63815a96b532786133baa95bc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:06.597311) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:06.597626) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-26 12:42:06.611830) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-26 12:42:06.612471) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:42:06 INFO - MultiFileLogger online at 20170926 12:42:06 in /builds/slave/test 12:42:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:42:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:42:06 INFO - {'append_to_log': False, 12:42:06 INFO - 'base_work_dir': '/builds/slave/test', 12:42:06 INFO - 'blob_upload_branch': 'Firefox', 12:42:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:42:06 INFO - 'branch': 'Firefox', 12:42:06 INFO - 'buildbot_json_path': 'buildprops.json', 12:42:06 INFO - 'code_coverage': False, 12:42:06 INFO - 'config_files': ('talos/linux_config.py',), 12:42:06 INFO - 'default_actions': ('clobber', 12:42:06 INFO - 'read-buildbot-config', 12:42:06 INFO - 'download-and-extract', 12:42:06 INFO - 'populate-webroot', 12:42:06 INFO - 'create-virtualenv', 12:42:06 INFO - 'install', 12:42:06 INFO - 'setup-mitmproxy', 12:42:06 INFO - 'run-tests'), 12:42:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:42:06 INFO - 'disable_ccov_upload': False, 12:42:06 INFO - 'disable_stylo': False, 12:42:06 INFO - 'download_minidump_stackwalk': True, 12:42:06 INFO - 'download_symbols': 'ondemand', 12:42:06 INFO - 'enable_stylo': False, 12:42:06 INFO - 'enable_webrender': False, 12:42:06 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:42:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:42:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:42:06 INFO - 'gecko_profile': False, 12:42:06 INFO - 'gecko_profile_interval': 0, 12:42:06 INFO - 'installer_path': 'installer.exe', 12:42:06 INFO - 'log_level': 'info', 12:42:06 INFO - 'log_name': 'talos', 12:42:06 INFO - 'log_to_console': True, 12:42:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:42:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:42:06 INFO - 'opt_config_files': (), 12:42:06 INFO - 'pip_index': False, 12:42:06 INFO - 'suite': 'dromaeojs-e10s', 12:42:06 INFO - 'system_bits': '32', 12:42:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:42:06 INFO - 'title': 'talos-linux64-ix-059', 12:42:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:42:06 INFO - 'use_talos_json': True, 12:42:06 INFO - 'verify': 'False', 12:42:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:42:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:42:06 INFO - 'work_dir': 'build'} 12:42:06 INFO - [mozharness: 2017-09-26 19:42:06.751427Z] Running clobber step. 12:42:06 INFO - Running pre-action listener: _resource_record_pre_action 12:42:06 INFO - Running main action method: clobber 12:42:06 INFO - rmtree: /builds/slave/test/build 12:42:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:42:07 INFO - Running post-action listener: _resource_record_post_action 12:42:07 INFO - [mozharness: 2017-09-26 19:42:07.752621Z] Finished clobber step (success) 12:42:07 INFO - [mozharness: 2017-09-26 19:42:07.752717Z] Running read-buildbot-config step. 12:42:07 INFO - Running pre-action listener: _resource_record_pre_action 12:42:07 INFO - Running main action method: read_buildbot_config 12:42:07 INFO - Using buildbot properties: 12:42:07 INFO - { 12:42:07 INFO - "project": "", 12:42:07 INFO - "product": "firefox", 12:42:07 INFO - "who": "ryanvm@gmail.com", 12:42:07 INFO - "installer_path": "public/build/target.tar.bz2", 12:42:07 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 12:42:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:42:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 12:42:07 INFO - "stage_platform": "linux64-pgo", 12:42:07 INFO - "basedir": "/builds/slave/test", 12:42:07 INFO - "buildnumber": 207, 12:42:07 INFO - "platform": "ubuntu64_hw", 12:42:07 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 12:42:07 INFO - "slavebuilddir": "test", 12:42:07 INFO - "taskId": "SzqdIuPfTRObLVaVL048Kg", 12:42:07 INFO - "branch": "mozilla-central", 12:42:07 INFO - "script_repo_revision": "production", 12:42:07 INFO - "revision": "39aaf54972cb11a63815a96b532786133baa95bc", 12:42:07 INFO - "slavename": "talos-linux64-ix-059", 12:42:07 INFO - "repo_path": "mozilla-central" 12:42:07 INFO - } 12:42:07 INFO - Finding installer, test and symbols from parent task. 12:42:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SzqdIuPfTRObLVaVL048Kg'}, attempt #1 12:42:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SzqdIuPfTRObLVaVL048Kg'}, attempt #1 12:42:08 INFO - Task dependencies: JSqdHGsuRK2AZcN3JGZ2xg 12:42:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg'}, attempt #1 12:42:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.tar.bz2 12:42:09 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.test_packages.json 12:42:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.crashreporter-symbols.zip 12:42:09 INFO - Running post-action listener: _resource_record_post_action 12:42:09 INFO - [mozharness: 2017-09-26 19:42:09.090504Z] Finished read-buildbot-config step (success) 12:42:09 INFO - [mozharness: 2017-09-26 19:42:09.090721Z] Running download-and-extract step. 12:42:09 INFO - Running pre-action listener: _resource_record_pre_action 12:42:09 INFO - Running main action method: download_and_extract 12:42:09 INFO - mkdir: /builds/slave/test/build/tests 12:42: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')]} 12:42:09 INFO - trying https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.test_packages.json 12:42:09 INFO - Downloading https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:42:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:42:09 INFO - Downloaded 989 bytes. 12:42:09 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:42:09 INFO - Using the following test package requirements: 12:42:09 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:42:09 INFO - u'common': [u'target.common.tests.zip'], 12:42:09 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:42:09 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:42:09 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:42:09 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:42:09 INFO - u'mozbase': [u'target.common.tests.zip'], 12:42:09 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:42:09 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:42:09 INFO - u'web-platform': [u'target.common.tests.zip', 12:42:09 INFO - u'target.web-platform.tests.tar.gz'], 12:42:09 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:42:09 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:42:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.common.tests.zip 12:42:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:42:09 INFO - Fetch https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.common.tests.zip into memory 12:42:10 INFO - Content-Length response header: 55347503 12:42:10 INFO - Bytes received: 55347503 12:42:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.talos.tests.zip 12:42:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:42:21 INFO - Fetch https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.talos.tests.zip into memory 12:42:22 INFO - Content-Length response header: 14513823 12:42:22 INFO - Bytes received: 14513823 12:42:22 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')]} 12:42:22 INFO - trying https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.tar.bz2 12:42:22 INFO - Downloading https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:42:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:42:23 INFO - Downloaded 62313077 bytes. 12:42:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.tar.bz2 12:42:23 INFO - mkdir: /builds/slave/test/properties 12:42:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:42:23 INFO - Writing to file /builds/slave/test/properties/build_url 12:42:23 INFO - Contents: 12:42:23 INFO - build_url:https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.tar.bz2 12:42:23 INFO - Running post-action listener: _resource_record_post_action 12:42:23 INFO - Running post-action listener: find_tests_for_verification 12:42:23 INFO - Running post-action listener: set_extra_try_arguments 12:42:23 INFO - [mozharness: 2017-09-26 19:42:23.870820Z] Finished download-and-extract step (success) 12:42:23 INFO - [mozharness: 2017-09-26 19:42:23.870918Z] Running populate-webroot step. 12:42:23 INFO - Running pre-action listener: _resource_record_pre_action 12:42:23 INFO - Running main action method: populate_webroot 12:42:23 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:42:23 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['tresize', 12:42:23 INFO - 'tcanvasmark']}, 12:42:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:42:23 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['dromaeo_css', 12:42:23 INFO - 'kraken']}, 12:42:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:42:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:42:23 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:42:23 INFO - 'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:42:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:42:23 INFO - 'tests': ['damp', 'tps']}, 12:42:23 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:42:23 INFO - 'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['damp', 'tps']}, 12:42:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:42:23 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['dromaeo_dom']}, 12:42:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:42:23 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['basic_compositor_video', 12:42:23 INFO - 'glvideo']}, 12:42:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:42:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:42:23 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:42:23 INFO - 'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['ts_paint_webext', 12:42:23 INFO - 'tp5o_webext']}, 12:42:23 INFO - 'other-e10s': {'tests': ['a11yr', 12:42:23 INFO - 'ts_paint', 12:42:23 INFO - 'tpaint', 12:42:23 INFO - 'sessionrestore', 12:42:23 INFO - 'sessionrestore_many_windows', 12:42:23 INFO - 'sessionrestore_no_auto_restore', 12:42:23 INFO - 'tabpaint']}, 12:42:23 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['a11yr', 12:42:23 INFO - 'ts_paint', 12:42:23 INFO - 'tpaint', 12:42:23 INFO - 'sessionrestore', 12:42:23 INFO - 'sessionrestore_many_windows', 12:42:23 INFO - 'sessionrestore_no_auto_restore', 12:42:23 INFO - 'tabpaint']}, 12:42:23 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 12:42:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:42:23 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['perf_reftest_singletons']}, 12:42:23 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['perf_reftest']}, 12:42:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:42:23 INFO - 'tsvgr_opacity', 12:42:23 INFO - 'tart', 12:42:23 INFO - 'tscrollx', 12:42:23 INFO - 'tsvg_static']}, 12:42:23 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['tsvgx', 12:42:23 INFO - 'tsvgr_opacity', 12:42:23 INFO - 'tart', 12:42:23 INFO - 'tscrollx', 12:42:23 INFO - 'tsvg_static']}, 12:42:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:42:23 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:42:23 INFO - 'talos_options': ['--disable-stylo'], 12:42:23 INFO - 'tests': ['tp5o']}, 12:42:23 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:42:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:42:23 INFO - 'talos_options': ['--mitmproxy', 12:42:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:42:23 INFO - '--firstNonBlankPaint'], 12:42:23 INFO - 'tests': ['tp6_google', 12:42:23 INFO - 'tp6_youtube', 12:42:23 INFO - 'tp6_amazon', 12:42:23 INFO - 'tp6_facebook']}, 12:42:23 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:42:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:42:23 INFO - 'talos_options': ['--disable-stylo', 12:42:23 INFO - '--mitmproxy', 12:42:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:42:23 INFO - '--firstNonBlankPaint'], 12:42:23 INFO - 'tests': ['tp6_google', 12:42:23 INFO - 'tp6_youtube', 12:42:23 INFO - 'tp6_amazon', 12:42:23 INFO - 'tp6_facebook']}, 12:42:23 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:42:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 12:42:23 INFO - 'talos_options': ['--stylo-threads=1', 12:42:23 INFO - '--mitmproxy', 12:42:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:42:23 INFO - '--firstNonBlankPaint'], 12:42:23 INFO - 'tests': ['tp6_google', 12:42:23 INFO - 'tp6_youtube', 12:42:23 INFO - 'tp6_amazon', 12:42:23 INFO - 'tp6_facebook']}, 12:42:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:42:23 INFO - 'talos_options': ['--xperf_path', 12:42:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:42:23 INFO - 'tests': ['tp5n']}, 12:42:23 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:42:23 INFO - 'talos_options': ['--disable-stylo', 12:42:23 INFO - '--xperf_path', 12:42:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:42:23 INFO - 'tests': ['tp5n']}}} 12:42:23 INFO - Running post-action listener: _resource_record_post_action 12:42:23 INFO - [mozharness: 2017-09-26 19:42:23.886260Z] Finished populate-webroot step (success) 12:42:23 INFO - [mozharness: 2017-09-26 19:42:23.886348Z] Running create-virtualenv step. 12:42:23 INFO - Running pre-action listener: _resource_record_pre_action 12:42:23 INFO - Running main action method: create_virtualenv 12:42:23 INFO - Creating virtualenv /builds/slave/test/build/venv 12:42:23 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:42:23 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:42:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:42:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:42:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:42:24 INFO - Installing distribute.............................................................................................................................................................................................done. 12:42:24 INFO - Installing pip...............done. 12:42:24 INFO - Return code: 0 12:42:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:42:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:42:24 INFO - Reading from file tmpfile_stdout 12:42:24 INFO - Output received: 12:42:24 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:42:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:42:24 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')]} 12:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:24 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')]} 12:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:24 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:24 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 12:42:24 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 12:42:24 INFO - Using env: {'DISPLAY': ':0', 12:42:24 INFO - 'HOME': '/home/cltbld', 12:42:24 INFO - 'LANG': 'en_US.UTF-8', 12:42:24 INFO - 'LANGUAGE': 'en_US:en', 12:42:24 INFO - 'LOGNAME': 'cltbld', 12:42:24 INFO - 'MAIL': '/var/mail/cltbld', 12:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:24 INFO - 'MOZ_NO_REMOTE': '1', 12:42:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:24 INFO - 'NO_EM_RESTART': '1', 12:42:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:24 INFO - 'PWD': '/builds/slave/test', 12:42:24 INFO - 'SHELL': '/bin/bash', 12:42:24 INFO - 'SHLVL': '1', 12:42:24 INFO - 'TERM': 'linux', 12:42:24 INFO - 'TMOUT': '86400', 12:42:24 INFO - 'USER': 'cltbld', 12:42:24 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:24 INFO - '_': '/tools/buildbot/bin/python'} 12:42:26 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:42:26 INFO - Downloading/unpacking pip>=1.5 12:42:26 INFO - Running setup.py egg_info for package pip 12:42:26 INFO - warning: no files found matching 'pip/cacert.pem' 12:42:26 INFO - warning: no files found matching '*.html' under directory 'docs' 12:42:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:42:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:42:26 INFO - Installing collected packages: pip 12:42:26 INFO - Found existing installation: pip 1.1 12:42:26 INFO - Uninstalling pip: 12:42:26 INFO - Successfully uninstalled pip 12:42:26 INFO - Running setup.py install for pip 12:42:26 INFO - warning: no files found matching 'pip/cacert.pem' 12:42:26 INFO - warning: no files found matching '*.html' under directory 'docs' 12:42:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:42:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:42:26 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:42:26 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:42:26 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:42:26 INFO - Successfully installed pip 12:42:26 INFO - Cleaning up... 12:42:26 INFO - Return code: 0 12:42:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:42:26 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')]} 12:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:26 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')]} 12:42:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:26 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:26 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 12:42:26 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 12:42:26 INFO - Using env: {'DISPLAY': ':0', 12:42:26 INFO - 'HOME': '/home/cltbld', 12:42:26 INFO - 'LANG': 'en_US.UTF-8', 12:42:26 INFO - 'LANGUAGE': 'en_US:en', 12:42:26 INFO - 'LOGNAME': 'cltbld', 12:42:26 INFO - 'MAIL': '/var/mail/cltbld', 12:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:26 INFO - 'MOZ_NO_REMOTE': '1', 12:42:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:26 INFO - 'NO_EM_RESTART': '1', 12:42:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:26 INFO - 'PWD': '/builds/slave/test', 12:42:26 INFO - 'SHELL': '/bin/bash', 12:42:26 INFO - 'SHLVL': '1', 12:42:26 INFO - 'TERM': 'linux', 12:42:26 INFO - 'TMOUT': '86400', 12:42:26 INFO - 'USER': 'cltbld', 12:42:26 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:26 INFO - '_': '/tools/buildbot/bin/python'} 12:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:26 INFO - Downloading/unpacking psutil>=3.1.1 12:42:26 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 12:42:26 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 12:42:26 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 12:42:26 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 12:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:42:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:42:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:42:27 INFO - Installing collected packages: psutil 12:42:27 INFO - Running setup.py install for psutil 12:42:27 INFO - building 'psutil._psutil_linux' extension 12:42:27 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 12:42:27 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 12:42:27 INFO - building 'psutil._psutil_posix' extension 12:42:27 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 12:42:27 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 12:42:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:42:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:42:28 INFO - Successfully installed psutil 12:42:28 INFO - Cleaning up... 12:42:28 INFO - Return code: 0 12:42:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:42:28 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')]} 12:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:28 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')]} 12:42:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:28 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:28 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 12:42:28 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 12:42:28 INFO - Using env: {'DISPLAY': ':0', 12:42:28 INFO - 'HOME': '/home/cltbld', 12:42:28 INFO - 'LANG': 'en_US.UTF-8', 12:42:28 INFO - 'LANGUAGE': 'en_US:en', 12:42:28 INFO - 'LOGNAME': 'cltbld', 12:42:28 INFO - 'MAIL': '/var/mail/cltbld', 12:42:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:28 INFO - 'MOZ_NO_REMOTE': '1', 12:42:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:28 INFO - 'NO_EM_RESTART': '1', 12:42:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:28 INFO - 'PWD': '/builds/slave/test', 12:42:28 INFO - 'SHELL': '/bin/bash', 12:42:28 INFO - 'SHLVL': '1', 12:42:28 INFO - 'TERM': 'linux', 12:42:28 INFO - 'TMOUT': '86400', 12:42:28 INFO - 'USER': 'cltbld', 12:42:28 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:28 INFO - '_': '/tools/buildbot/bin/python'} 12:42:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:42:28 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 12:42:28 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 12:42:28 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 12:42:28 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 12:42:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:42:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:42:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:42:29 INFO - Installing collected packages: mozsystemmonitor 12:42:29 INFO - Running setup.py install for mozsystemmonitor 12:42:29 INFO - Successfully installed mozsystemmonitor 12:42:29 INFO - Cleaning up... 12:42:30 INFO - Return code: 0 12:42:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:42:30 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')]} 12:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:30 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')]} 12:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:30 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:30 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 12:42:30 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 12:42:30 INFO - Using env: {'DISPLAY': ':0', 12:42:30 INFO - 'HOME': '/home/cltbld', 12:42:30 INFO - 'LANG': 'en_US.UTF-8', 12:42:30 INFO - 'LANGUAGE': 'en_US:en', 12:42:30 INFO - 'LOGNAME': 'cltbld', 12:42:30 INFO - 'MAIL': '/var/mail/cltbld', 12:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:30 INFO - 'MOZ_NO_REMOTE': '1', 12:42:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:30 INFO - 'NO_EM_RESTART': '1', 12:42:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:30 INFO - 'PWD': '/builds/slave/test', 12:42:30 INFO - 'SHELL': '/bin/bash', 12:42:30 INFO - 'SHLVL': '1', 12:42:30 INFO - 'TERM': 'linux', 12:42:30 INFO - 'TMOUT': '86400', 12:42:30 INFO - 'USER': 'cltbld', 12:42:30 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:30 INFO - '_': '/tools/buildbot/bin/python'} 12:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:30 INFO - Downloading/unpacking jsonschema==2.5.1 12:42:30 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 12:42:30 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 12:42:30 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 12:42:30 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 12:42:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:42:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:42:32 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 12:42:32 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 12:42:32 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 12:42:32 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 12:42:33 INFO - Downloading functools32-3.2.3-2.tar.gz 12:42:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:42:33 INFO - warning: no files found matching '*.txt' 12:42:33 INFO - no previously-included directories found matching 'build' 12:42:33 INFO - no previously-included directories found matching 'dist' 12:42:33 INFO - no previously-included directories found matching '.git*' 12:42:33 INFO - Installing collected packages: jsonschema, functools32 12:42:33 INFO - Running setup.py install for functools32 12:42:33 INFO - warning: no files found matching '*.txt' 12:42:33 INFO - no previously-included directories found matching 'build' 12:42:33 INFO - no previously-included directories found matching 'dist' 12:42:33 INFO - no previously-included directories found matching '.git*' 12:42:33 INFO - Successfully installed jsonschema functools32 12:42:33 INFO - Cleaning up... 12:42:33 INFO - Return code: 0 12:42:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:42: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')]} 12:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42: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')]} 12:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:33 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:33 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 12:42:33 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 12:42:33 INFO - Using env: {'DISPLAY': ':0', 12:42:33 INFO - 'HOME': '/home/cltbld', 12:42:33 INFO - 'LANG': 'en_US.UTF-8', 12:42:33 INFO - 'LANGUAGE': 'en_US:en', 12:42:33 INFO - 'LOGNAME': 'cltbld', 12:42:33 INFO - 'MAIL': '/var/mail/cltbld', 12:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:33 INFO - 'MOZ_NO_REMOTE': '1', 12:42:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:33 INFO - 'NO_EM_RESTART': '1', 12:42:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:33 INFO - 'PWD': '/builds/slave/test', 12:42:33 INFO - 'SHELL': '/bin/bash', 12:42:33 INFO - 'SHLVL': '1', 12:42:33 INFO - 'TERM': 'linux', 12:42:33 INFO - 'TMOUT': '86400', 12:42:33 INFO - 'USER': 'cltbld', 12:42:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:33 INFO - '_': '/tools/buildbot/bin/python'} 12:42:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:42:33 INFO - Cleaning up... 12:42:33 INFO - Return code: 0 12:42:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:42: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')]} 12:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42: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')]} 12:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:33 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:33 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 12:42:33 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 12:42:33 INFO - Using env: {'DISPLAY': ':0', 12:42:33 INFO - 'HOME': '/home/cltbld', 12:42:33 INFO - 'LANG': 'en_US.UTF-8', 12:42:33 INFO - 'LANGUAGE': 'en_US:en', 12:42:33 INFO - 'LOGNAME': 'cltbld', 12:42:33 INFO - 'MAIL': '/var/mail/cltbld', 12:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:33 INFO - 'MOZ_NO_REMOTE': '1', 12:42:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:33 INFO - 'NO_EM_RESTART': '1', 12:42:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:33 INFO - 'PWD': '/builds/slave/test', 12:42:33 INFO - 'SHELL': '/bin/bash', 12:42:33 INFO - 'SHLVL': '1', 12:42:33 INFO - 'TERM': 'linux', 12:42:33 INFO - 'TMOUT': '86400', 12:42:33 INFO - 'USER': 'cltbld', 12:42:33 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:33 INFO - '_': '/tools/buildbot/bin/python'} 12:42:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:33 INFO - Downloading/unpacking blobuploader==1.2.4 12:42:33 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 12:42:33 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 12:42:33 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 12:42:33 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 12:42:34 INFO - Downloading blobuploader-1.2.4.tar.gz 12:42:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:42:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:42:34 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 12:42:34 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 12:42:34 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 12:42:34 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 12:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:42:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:42:35 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 12:42:35 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 12:42:35 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 12:42:35 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 12:42:35 INFO - Downloading docopt-0.6.1.tar.gz 12:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:42:35 INFO - Installing collected packages: blobuploader, requests, docopt 12:42:35 INFO - Running setup.py install for blobuploader 12:42:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:42:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:42:35 INFO - Running setup.py install for requests 12:42:35 INFO - Running setup.py install for docopt 12:42:35 INFO - Successfully installed blobuploader requests docopt 12:42:35 INFO - Cleaning up... 12:42:36 INFO - Return code: 0 12:42:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:36 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:36 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 12:42:36 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 12:42:36 INFO - Using env: {'DISPLAY': ':0', 12:42:36 INFO - 'HOME': '/home/cltbld', 12:42:36 INFO - 'LANG': 'en_US.UTF-8', 12:42:36 INFO - 'LANGUAGE': 'en_US:en', 12:42:36 INFO - 'LOGNAME': 'cltbld', 12:42:36 INFO - 'MAIL': '/var/mail/cltbld', 12:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:36 INFO - 'MOZ_NO_REMOTE': '1', 12:42:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:36 INFO - 'NO_EM_RESTART': '1', 12:42:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:36 INFO - 'PWD': '/builds/slave/test', 12:42:36 INFO - 'SHELL': '/bin/bash', 12:42:36 INFO - 'SHLVL': '1', 12:42:36 INFO - 'TERM': 'linux', 12:42:36 INFO - 'TMOUT': '86400', 12:42:36 INFO - 'USER': 'cltbld', 12:42:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:36 INFO - '_': '/tools/buildbot/bin/python'} 12:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:42:36 INFO - Running setup.py (path:/tmp/pip-hO6UMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:42:36 INFO - Running setup.py (path:/tmp/pip-xyO7DH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:42:36 INFO - Running setup.py (path:/tmp/pip-JS9hFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:42:36 INFO - Running setup.py (path:/tmp/pip-nC22gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:42:36 INFO - Running setup.py (path:/tmp/pip-7UBc5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:42:36 INFO - Running setup.py (path:/tmp/pip-Z5jGeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:42:36 INFO - Running setup.py (path:/tmp/pip-e4Lal3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:42:36 INFO - Running setup.py (path:/tmp/pip-6h7JKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:42:36 INFO - Running setup.py (path:/tmp/pip-UgD32D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:42:36 INFO - Running setup.py (path:/tmp/pip-M3IFJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:42:36 INFO - Running setup.py (path:/tmp/pip-YUlZHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:42:37 INFO - Running setup.py (path:/tmp/pip-ribDVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:42:37 INFO - Running setup.py (path:/tmp/pip-Vez2aF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:42:37 INFO - Running setup.py (path:/tmp/pip-XhojkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:37 INFO - Running setup.py (path:/tmp/pip-JmDf5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:42:37 INFO - Running setup.py (path:/tmp/pip-UcO0ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:37 INFO - Running setup.py (path:/tmp/pip-a2AiOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:42:37 INFO - Running setup.py install for manifestparser 12:42:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:42:37 INFO - Running setup.py install for mozcrash 12:42:37 INFO - Running setup.py install for mozdebug 12:42:37 INFO - Running setup.py install for mozdevice 12:42:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:42:37 INFO - Running setup.py install for mozfile 12:42:37 INFO - Running setup.py install for mozhttpd 12:42:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Running setup.py install for mozinfo 12:42:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Running setup.py install for mozInstall 12:42:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Running setup.py install for mozleak 12:42:38 INFO - Running setup.py install for mozlog 12:42:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Running setup.py install for moznetwork 12:42:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Running setup.py install for mozprocess 12:42:38 INFO - Running setup.py install for mozprofile 12:42:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Running setup.py install for mozrunner 12:42:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:42:38 INFO - Running setup.py install for mozscreenshot 12:42:38 INFO - Running setup.py install for moztest 12:42:39 INFO - Running setup.py install for mozversion 12:42:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:42:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:42:39 INFO - Cleaning up... 12:42:39 INFO - Return code: 0 12:42:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:39 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:39 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 12:42:39 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 12:42:39 INFO - Using env: {'DISPLAY': ':0', 12:42:39 INFO - 'HOME': '/home/cltbld', 12:42:39 INFO - 'LANG': 'en_US.UTF-8', 12:42:39 INFO - 'LANGUAGE': 'en_US:en', 12:42:39 INFO - 'LOGNAME': 'cltbld', 12:42:39 INFO - 'MAIL': '/var/mail/cltbld', 12:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:39 INFO - 'MOZ_NO_REMOTE': '1', 12:42:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:39 INFO - 'NO_EM_RESTART': '1', 12:42:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:39 INFO - 'PWD': '/builds/slave/test', 12:42:39 INFO - 'SHELL': '/bin/bash', 12:42:39 INFO - 'SHLVL': '1', 12:42:39 INFO - 'TERM': 'linux', 12:42:39 INFO - 'TMOUT': '86400', 12:42:39 INFO - 'USER': 'cltbld', 12:42:39 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:39 INFO - '_': '/tools/buildbot/bin/python'} 12:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:42:39 INFO - Running setup.py (path:/tmp/pip-KoXXvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:42:39 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)) 12:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:42:39 INFO - Running setup.py (path:/tmp/pip-ADsKhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:42:39 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)) 12:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:42:39 INFO - Running setup.py (path:/tmp/pip-jZmNlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:42:39 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)) 12:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:42:39 INFO - Running setup.py (path:/tmp/pip-huHdgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:42:39 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)) 12:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:42:39 INFO - Running setup.py (path:/tmp/pip-1DlEpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:42:39 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)) 12:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:42:39 INFO - Running setup.py (path:/tmp/pip-O3zu23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:42:39 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)) 12:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:42:39 INFO - Running setup.py (path:/tmp/pip-yiCO4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:42:39 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)) 12:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:42:39 INFO - Running setup.py (path:/tmp/pip-INvZs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:42:39 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)) 12:42:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:42:39 INFO - Running setup.py (path:/tmp/pip-32DqdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:42:40 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)) 12:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:42:40 INFO - Running setup.py (path:/tmp/pip-GMayos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:42:40 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)) 12:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:42:40 INFO - Running setup.py (path:/tmp/pip-747QyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:42:40 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)) 12:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:42:40 INFO - Running setup.py (path:/tmp/pip-YYqZky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:42:40 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)) 12:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:42:40 INFO - Running setup.py (path:/tmp/pip-N_mC69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:42:40 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)) 12:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:42:40 INFO - Running setup.py (path:/tmp/pip-OMUCZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:42:40 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)) 12:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:40 INFO - Running setup.py (path:/tmp/pip-RrTyWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:42:40 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)) 12:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:42:40 INFO - Running setup.py (path:/tmp/pip-a6dphU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:42:40 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)) 12:42:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:42:40 INFO - Running setup.py (path:/tmp/pip-kiKk1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:42:40 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)) 12:42:40 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)) 12:42:40 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)) 12:42:40 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)) 12:42:40 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)) 12:42:40 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)) 12:42:40 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)) 12:42:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:42:40 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 12:42:40 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 12:42:40 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 12:42:40 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 12:42:41 INFO - Downloading blessings-1.6.tar.gz 12:42:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:42:41 INFO - Installing collected packages: blessings 12:42:41 INFO - Running setup.py install for blessings 12:42:41 INFO - Successfully installed blessings 12:42:41 INFO - Cleaning up... 12:42:41 INFO - Return code: 0 12:42:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:42:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:42 INFO - Reading from file tmpfile_stdout 12:42:42 INFO - Current package versions: 12:42:42 INFO - argparse == 1.2.1 12:42:42 INFO - blessings == 1.6 12:42:42 INFO - blobuploader == 1.2.4 12:42:42 INFO - docopt == 0.6.1 12:42:42 INFO - functools32 == 3.2.3-2 12:42:42 INFO - jsonschema == 2.5.1 12:42:42 INFO - manifestparser == 1.1 12:42:42 INFO - mozInstall == 1.13 12:42:42 INFO - mozcrash == 1.0 12:42:42 INFO - mozdebug == 0.1 12:42:42 INFO - mozdevice == 0.51 12:42:42 INFO - mozfile == 1.2 12:42:42 INFO - mozhttpd == 0.7 12:42:42 INFO - mozinfo == 0.10 12:42:42 INFO - mozleak == 0.1 12:42:42 INFO - mozlog == 3.5 12:42:42 INFO - moznetwork == 0.27 12:42:42 INFO - mozprocess == 0.25 12:42:42 INFO - mozprofile == 0.28 12:42:42 INFO - mozrunner == 6.13 12:42:42 INFO - mozscreenshot == 0.1 12:42:42 INFO - mozsystemmonitor == 0.3 12:42:42 INFO - moztest == 0.8 12:42:42 INFO - mozversion == 1.4 12:42:42 INFO - psutil == 3.1.1 12:42:42 INFO - requests == 1.2.3 12:42:42 INFO - wsgiref == 0.1.2 12:42:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:42 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:42 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 12:42:42 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 12:42:42 INFO - Using env: {'DISPLAY': ':0', 12:42:42 INFO - 'HOME': '/home/cltbld', 12:42:42 INFO - 'LANG': 'en_US.UTF-8', 12:42:42 INFO - 'LANGUAGE': 'en_US:en', 12:42:42 INFO - 'LOGNAME': 'cltbld', 12:42:42 INFO - 'MAIL': '/var/mail/cltbld', 12:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:42 INFO - 'MOZ_NO_REMOTE': '1', 12:42:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:42 INFO - 'NO_EM_RESTART': '1', 12:42:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:42 INFO - 'PWD': '/builds/slave/test', 12:42:42 INFO - 'SHELL': '/bin/bash', 12:42:42 INFO - 'SHLVL': '1', 12:42:42 INFO - 'TERM': 'linux', 12:42:42 INFO - 'TMOUT': '86400', 12:42:42 INFO - 'USER': 'cltbld', 12:42:42 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:42 INFO - '_': '/tools/buildbot/bin/python'} 12:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:42 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)) 12:42:42 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)) 12:42:42 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)) 12:42:42 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)) 12:42:42 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)) 12:42:42 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)) 12:42:42 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)) 12:42:42 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)) 12:42:42 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)) 12:42:42 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:42:42 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 12:42:42 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 12:42:42 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 12:42:42 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 12:42:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:42:43 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)) 12:42:43 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)) 12:42:43 INFO - Installing collected packages: simplejson 12:42:43 INFO - Running setup.py install for simplejson 12:42:43 INFO - building 'simplejson._speedups' extension 12:42:43 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 12:42:44 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 12:42:44 INFO - Successfully installed simplejson 12:42:44 INFO - Cleaning up... 12:42:44 INFO - Return code: 0 12:42:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:42:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:42:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:42:44 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 0x1ac61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8eb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e339c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', '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 12:42:44 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 12:42:44 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 12:42:44 INFO - Using env: {'DISPLAY': ':0', 12:42:44 INFO - 'HOME': '/home/cltbld', 12:42:44 INFO - 'LANG': 'en_US.UTF-8', 12:42:44 INFO - 'LANGUAGE': 'en_US:en', 12:42:44 INFO - 'LOGNAME': 'cltbld', 12:42:44 INFO - 'MAIL': '/var/mail/cltbld', 12:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:42:44 INFO - 'MOZ_NO_REMOTE': '1', 12:42:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:42:44 INFO - 'NO_EM_RESTART': '1', 12:42:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:42:44 INFO - 'PWD': '/builds/slave/test', 12:42:44 INFO - 'SHELL': '/bin/bash', 12:42:44 INFO - 'SHLVL': '1', 12:42:44 INFO - 'TERM': 'linux', 12:42:44 INFO - 'TMOUT': '86400', 12:42:44 INFO - 'USER': 'cltbld', 12:42:44 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:42:44 INFO - '_': '/tools/buildbot/bin/python'} 12:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:42:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:42:44 INFO - Cleaning up... 12:42:44 INFO - Return code: 0 12:42:44 INFO - Running post-action listener: _resource_record_post_action 12:42:44 INFO - Running post-action listener: _start_resource_monitoring 12:42:44 INFO - Starting resource monitoring. 12:42:44 INFO - [mozharness: 2017-09-26 19:42:44.924459Z] Finished create-virtualenv step (success) 12:42:44 INFO - [mozharness: 2017-09-26 19:42:44.924984Z] Running install step. 12:42:44 INFO - Running pre-action listener: _resource_record_pre_action 12:42:44 INFO - Running main action method: install 12:42:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:42:45 INFO - Reading from file tmpfile_stdout 12:42:45 INFO - Detecting whether we're running mozinstall >=1.0... 12:42:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:42:45 INFO - Reading from file tmpfile_stdout 12:42:45 INFO - Output received: 12:42:45 INFO - Usage: mozinstall [options] installer 12:42:45 INFO - Options: 12:42:45 INFO - -h, --help show this help message and exit 12:42:45 INFO - -d DEST, --destination=DEST 12:42:45 INFO - Directory to install application into. [default: 12:42:45 INFO - "/builds/slave/test"] 12:42:45 INFO - --app=APP Application being installed. [default: firefox] 12:42:45 INFO - mkdir: /builds/slave/test/build/application 12:42:45 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'] 12:42:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:43:03 INFO - Reading from file tmpfile_stdout 12:43:03 INFO - Output received: 12:43:03 INFO - /builds/slave/test/build/application/firefox/firefox 12:43:03 INFO - Running post-action listener: _resource_record_post_action 12:43:03 INFO - [mozharness: 2017-09-26 19:43:03.033450Z] Finished install step (success) 12:43:03 INFO - [mozharness: 2017-09-26 19:43:03.033561Z] Running setup-mitmproxy step. 12:43:03 INFO - Running pre-action listener: _resource_record_pre_action 12:43:03 INFO - Running main action method: setup_mitmproxy 12:43:03 INFO - Skipping: mitmproxy is not required 12:43:03 INFO - Running post-action listener: _resource_record_post_action 12:43:03 INFO - [mozharness: 2017-09-26 19:43:03.034044Z] Finished setup-mitmproxy step (success) 12:43:03 INFO - [mozharness: 2017-09-26 19:43:03.034136Z] Running run-tests step. 12:43:03 INFO - Running pre-action listener: _resource_record_pre_action 12:43:03 INFO - Running pre-action listener: _set_gcov_prefix 12:43:03 INFO - Running main action method: run_tests 12:43:03 WARNING - Try message not found. 12:43:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:43:03 INFO - Python 2.7.3 12:43:03 INFO - Return code: 0 12:43:03 INFO - grabbing minidump binary from tooltool 12:43: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')]} 12:43:03 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 0x1e30d60>, '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 0x1e31a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e31ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:43:03 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 12:43:03 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 12:43:03 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 12:43:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:43:03 INFO - Return code: 0 12:43:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:43:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:43:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:43:03 INFO - ENV: RUST_BACKTRACE is now full 12:43:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:43:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:43:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:43:03 INFO - Using env: {'DISPLAY': ':0', 12:43:03 INFO - 'HOME': '/home/cltbld', 12:43:03 INFO - 'LANG': 'en_US.UTF-8', 12:43:03 INFO - 'LANGUAGE': 'en_US:en', 12:43:03 INFO - 'LOGNAME': 'cltbld', 12:43:03 INFO - 'MAIL': '/var/mail/cltbld', 12:43:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:43:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:03 INFO - 'MOZ_NO_REMOTE': '1', 12:43:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:43:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:03 INFO - 'NO_EM_RESTART': '1', 12:43:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:03 INFO - 'PWD': '/builds/slave/test', 12:43:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:43:03 INFO - 'RUST_BACKTRACE': 'full', 12:43:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:43:03 INFO - 'SHELL': '/bin/bash', 12:43:03 INFO - 'SHLVL': '1', 12:43:03 INFO - 'TERM': 'linux', 12:43:03 INFO - 'TMOUT': '86400', 12:43:03 INFO - 'USER': 'cltbld', 12:43:03 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:03 INFO - '_': '/tools/buildbot/bin/python'} 12:43:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:43:03 INFO - mozversion application_buildid: 20170926181431 12:43:03 INFO - mozversion application_changeset: 39aaf54972cb11a63815a96b532786133baa95bc 12:43:03 INFO - mozversion application_display_name: Nightly 12:43:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:43:03 INFO - mozversion application_name: Firefox 12:43:03 INFO - mozversion application_remotingname: firefox 12:43:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:43:03 INFO - mozversion application_vendor: Mozilla 12:43:03 INFO - mozversion application_version: 58.0a1 12:43:03 INFO - mozversion platform_buildid: 20170926181431 12:43:03 INFO - mozversion platform_changeset: 39aaf54972cb11a63815a96b532786133baa95bc 12:43:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:43:03 INFO - mozversion platform_version: 58.0a1 12:43:03 INFO - using testdate: 1506454983 12:43:03 INFO - actual date: 1506454983 12:43:03 INFO - starting webserver on 'localhost:51412' 12:43:03 INFO - SUITE-START | Running 2 tests 12:43:03 INFO - TEST-START | dromaeo_css 12:43:03 INFO - Initialising browser for dromaeo_css test... 12:43:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51412/getInfo.html -profile /tmp/tmpZBorr7/profile 12:43:03 INFO - TEST-INFO | started process 13154 (/builds/slave/test/build/application/firefox/firefox http://localhost:51412/getInfo.html) 12:43:08 INFO - TEST-INFO | 13154: exit 0 12:43:08 INFO - Browser initialized. 12:43:08 INFO - Running cycle 1/1 for dromaeo_css test... 12:43:08 INFO - Using env: {'DISPLAY': ':0', 12:43:08 INFO - 'HOME': '/home/cltbld', 12:43:08 INFO - 'JSGC_DISABLE_POISONING': '1', 12:43:08 INFO - 'LANG': 'en_US.UTF-8', 12:43:08 INFO - 'LANGUAGE': 'en_US:en', 12:43:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:43:08 INFO - 'LOGNAME': 'cltbld', 12:43:08 INFO - 'MAIL': '/var/mail/cltbld', 12:43:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:43:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:43:08 INFO - 'MOZ_CRASHREPORTER': '1', 12:43:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:43:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:43:08 INFO - 'MOZ_NO_REMOTE': '1', 12:43:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:43:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:43:08 INFO - 'NO_EM_RESTART': '1', 12:43:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:43:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:43:08 INFO - 'PWD': '/builds/slave/test', 12:43:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:43:08 INFO - 'RUST_BACKTRACE': 'full', 12:43:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:43:08 INFO - 'SHELL': '/bin/bash', 12:43:08 INFO - 'SHLVL': '1', 12:43:08 INFO - 'TERM': 'linux', 12:43:08 INFO - 'TMOUT': '86400', 12:43:08 INFO - 'USER': 'cltbld', 12:43:08 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 12:43:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:43:08 INFO - '_': '/tools/buildbot/bin/python'} 12:43:08 INFO - TEST-INFO | started process 13390 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZBorr7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:43:09 INFO - PID 13390 | 12:43:09 INFO - PID 13390 | (/builds/slave/test/build/application/firefox/firefox:13439): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:43:09 INFO - PID 13390 | 12:43:19 INFO - PID 13390 | RSS: Main: 141316096 12:43:19 INFO - PID 13390 | 12:46:17 INFO - PID 13390 | Cycle 1(1): loaded http://localhost:51412/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51412/tests/dromaeo/cssquery-ext.html) 12:46:18 INFO - PID 13390 | RSS: Main: 146206720 12:46:18 INFO - PID 13390 | 12:49:20 INFO - PID 13390 | Cycle 1(1): loaded http://localhost:51412/tests/dromaeo/cssquery-ext.html (next: http://localhost:51412/tests/dromaeo/cssquery-jquery.html) 12:49:20 INFO - PID 13390 | RSS: Main: 147095552 12:49:20 INFO - PID 13390 | 12:52:22 INFO - PID 13390 | Cycle 1(1): loaded http://localhost:51412/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51412/tests/dromaeo/cssquery-mootools.html) 12:52:22 INFO - PID 13390 | RSS: Main: 148221952 12:52:22 INFO - PID 13390 | 12:55:24 INFO - PID 13390 | Cycle 1(1): loaded http://localhost:51412/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51412/tests/dromaeo/cssquery-prototype.html) 12:55:25 INFO - PID 13390 | RSS: Main: 145440768 12:55:25 INFO - PID 13390 | 12:58:28 INFO - PID 13390 | Cycle 1(1): loaded http://localhost:51412/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51412/tests/dromaeo/cssquery-yui.html) 12:58:28 INFO - PID 13390 | RSS: Main: 148553728 12:58:28 INFO - PID 13390 | 13:01:30 INFO - PID 13390 | Cycle 1(1): loaded http://localhost:51412/tests/dromaeo/cssquery-yui.html (next: http://localhost:51412/tests/dromaeo/cssquery-dojo.html) 13:01:30 INFO - PID 13390 | RSS: Main: 151146496 13:01:30 INFO - PID 13390 | 13:01:30 INFO - PID 13390 | __start_tp_report 13:01:30 INFO - PID 13390 | _x_x_mozilla_page_load 13:01:30 INFO - PID 13390 | _x_x_mozilla_page_load_details 13:01:30 INFO - PID 13390 | |i|pagename|runs| 13:01:30 INFO - PID 13390 | |0;dojo.html;3863.903402414939;3910.432987216853;3919.7844118573503;3925.3915643075334;3975.04598896265;6229.781957631484;6238.719257633406;6278.058291256314;6323.5257355698395;6400.231972163341;6279.372062793718;6302.558820882528;6361.68191590421;6390.776322828702;6451.935480645192;5639.830805075834;5669.688167150805;5686.914696279559;5722.341930677963;5735.1970724098655;5597.216389705444;5704.65772053676;5709.74306156224;5747.166660834177;5778.104393818944;5709.800156994527;5729.856753581152;5740.827775166752;5774.538036957054;5782.884342313151;5592.189132575794;5621.437856214387;5667.3482549506925;5679.034564124109;5717.599768016241;5611.2144299798065;5663.745131469093;5673.007223735871;5686.317641882959;5687.061634830248;6198.876022479571;6245.063240513914;6261.906071408933;6315.24217093947;6335.11308416822;6738.326167383271;6780.898286525706;6795.38841504267;6800.251972283045;6900.6204658744255;6805.251422343587;6892.793216203522;6901.516893817435;6909;6909.861802763967;6264.87470250597;6311.211098612674;6399.456046236029;6405.743770249184;6412.35876412355;6994.300569942965;7026.1217347831525;7030.402415794715;7036;7043.225245223021;6747.493937954673;6750.0212469192675;6753.628550429676;6754;6756.966215168893;6772.69522871472;6812.48906332027;6874.65626718662;6898.413634841095;6901.033855260268;6784.626845523544;6859.142607174103;6870.519063665495;6884;6899.0341352210735;6770.255271920085;6806.5235433520165;6815.897761533581;6883.827904302432;6895.138107736533;4961.7271050092595;5022.271770593244;5025.698458092526;5026.52248036436;5041.747912604428;4532.864014079583;4549.681522293407;4585.95414045862;4597.3333866589155;4609.055143695535;6147.200863887755;6230.719617617218;6238.937610623926;6239.344868788712;6242.032484964838;6689.096971908827;6739.191297044386;6809.523333366716;6836.418904393083;6838.247792742794;6183.659898705614;6252.562320637512;6255.812325630239;6258.624482531062;6276.058591211355;5472.863178420492;5490.835274941758;5541.168824676331;5550.306211723535;5556.472135147154;7028.226895041541;7037.4018208451835;7042.753503627348;7097.7160913562875;7147.9285207148305;6676.0987266718375;6776.728930842711;6795.524313298021;6862.931370686429;6906.482013849082;7072.504924655224;7074.327938845801;7124.430045596237;7165.211826699163;7179.461540384387;6651.16860392451;6762.289959554176;6773.187217533928;6815.897761533482;6850.965745171242;6766.221884483348;6820.863582728417;6836.760713375008;6872.793816185523;6879.140107486564;6601.10885030534;6605.1083103780375;6690.431313338421;6693.933060669331;6695.7321707133115;6478.6760661967655;6566.376194261537;6583.934160658332;6670.266270710315;6679.198496180489;6504.837379065561;6527.706253218712;6528.249251336157;6532.608043517404;6539.6403197824575;6516.641584712696;6601.768938087144;6681.832954176184;6700;6706.899396509154;6418.358164183544;6418.422341989243;6440.098386225838;6494.058361537456;6513 13:01:30 INFO - PID 13390 | |1;ext.html;960.6109525642113;971.8153550825344;998.381003777658;1020.3265844542603;1020.3265844542603;43424.13151736967;45237;45285.547144528544;45297;45368.09263814726;42333.36499952503;42606;42827;42848.78575607125;42902.78548607253;7580.962095189517;7701.3453856422275;7710.807229819271;7714.922850771477;7719.266669666401;7618.847623047535;7716.305532502074;7730.0723913130505;7732.265434783687;7759.806004849867;8490.150984901522;8687.174718401742;8701.17338852808;8718.825623487526;8719.825603487927;8577.871331930026;8646.567671616425;8669;8676.783080423009;8677.175668311564;8362.749117526484;8486.19381158789;8527.147285271485;8541.914580854174;8563;42436.363454548205;42539.36190957138;42696.57303426988;42813.14373712539;42834.78582607098;3766.453864189677;3822.8661996830256;3828.7894165820867;3830.080780612661;3834.213986132836;33288.50067248993;33728.49407258893;33765.49351759726;33847;34005.149871253416;5176.482351764794;5221.686698798047;5245.606579506552;5253.658512196695;5305.204219367087;229210;229836;230785;230857;231092.9999999983;55074.44925550773;55437.16844247259;55565;55685.72157139269;55733.721331393084;55568;55943;56168;56280.155797663065;56302.71848640813;15015;15431;15507.534773956799;15519.689606207812;15555.377784888704;12160.513579456723;12195.39023048844;12200.572979945659;12219.266843989211;12268;15708.764368534481;15742.134182619837;15759.448419305269;15875.920620397055;15876;6193.442590166816;6199.659018753921;6310.652914089677;6355.173827402408;6374.266959299647;1085.3650614390513;1093.6008356949656;1097.1551905033239;1102.327580176092;1135.5117299561102;26163.869180653975;26292.21123366302;26304.079357222407;26358;26493.33766655849;1194.6595220362328;1195.8744331845205;1210.328267811373;1220.8351872497276;1226.8221107939298;13698.45206191741;13875.334959804888;14020.859791401956;14044.648883777783;14263.42946282137;282370;282638;283240;283512;284133;50365.49634503608;51407;59204.99999999914;59495.70252148712;59855.40144598585;1496.5435542159528;1517.1731406383547;1519.3011214841076;1521.9543413697606;1536.853998870128;30738.385232295677;30834.69165308318;31097.37805243928;31107.844460778455;31437.899673511318;38804.41793373158;39095.218095637356;43014.56985430231;43286.35070473883;43291.56708432875;249426;250231;250601;250780;250830;37698.43452348272;38528;38566;38693;38991.83024509269;47142.764286178346;47332;47343.52656473391;47416;48089.75955120202;34769;34901.8254908734;35625;35764.642353577176;36001.459978099825;34543.827280863436;34992.47511287282;35161.8241908799;35168.29663406769;35211;39903.40144897887;40247;41182.38226426666;41344;41388.586114139674;34295.657043429244;34362;34586.30827383488;34590.48114278237;34603.826980864935;25242.495150097264;25596.488070238862;25726.485470290863;26023.73976260289;26614.201573952814 13:01:30 INFO - PID 13390 | |2;jquery.html;592.6947621974681;601.2304250559285;613.245707779431;622.2750495672542;636.8598908240185;23601.17395891144;24057.639135412985;24400.145994890183;24457.021719131237;24473.632895506536;24536;25940.870295648543;26055.47889042218;26074;26077.348066298353;6593.769218077368;6746.156730408699;6751.696173672172;6778.152730908637;6794.490413219004;6580.736770529173;6696.564723292995;6718;6997.44020478363;7021.473389495783;6955;6964.721411143548;6970.721171153148;6972.895406568906;6980.511364204508;6651;6901.068355772007;6920.273371296017;6946.2706415826115;6973.930260697379;6450.548461607689;6454.483641308684;6468.353164683494;6469.51478639104;6477.967610161919;30329.090127296215;30853;30881.845590772125;30902.38195236105;31026.689733102605;2235.7093577834776;2266.3767463947383;2268.5462907418582;2275.9317220483413;2276.408133885185;18741.250349985985;20659.483512912146;20711.27510537139;20993.790062099488;21037.474063148544;2467;2484.6521486991837;2524.873756312177;2545.1346542175565;2582.83211591246;137563.31218343944;143241;155829;161233.99999999884;161365;16175.43385981485;16349.591260218589;16368.508944731677;16954.660906781795;17359.826401736074;26531.602025969627;26562.335941601228;26563.867180663972;26579.069732559266;26653;10729.731756705989;10768.623098191525;10794.73013174677;10887.618933337295;11073.05879000294;9801.803963920824;9802.411855288705;9807.803843923082;9883.604655813831;9929.3545919515;9658.565364558612;10013.49932503372;10119.898801012043;10231.641892533726;10279.766428028684;2983.23927398512;2987.581738556604;3007.5187969925146;3029.5910052143117;3101.550275210082;1035.4874337203132;1040.9635662751768;1043.8173319669027;1046.199657262195;1052.6263176572359;20369;20891.68662470064;20923;21130.471738206666;21173.576528469344;1101.3446999035561;1107.8892110789018;1112.4437781109445;1118.3513562133942;1119.3451830679144;11290.322580645023;11576.131790115607;11611.76776464466;11627.476763545661;11662.650120496399;552271;568751;571819;584331;657268;128899.35550322189;129318;130429.0435643466;133061.33469332592;134072;961.5721004153224;971.7949742319614;998.1914649134225;1117.1621284036971;1244.975100498003;21362.57274854525;22045.889770551046;22149.1140354384;22216.222432214796;22306.888465557568;61593.38406615877;63064.684676576326;63363.683181583794;63977;64976;77528.61235694011;78139;78461;79592.60203698944;80037.59981200057;15351.923240384174;15554.611134721723;15669.216539173223;15786.9210653946;15896.761548577011;23976;24074.398140045938;24174.153904613253;24448.14431494889;24511.632325514776;19425.8057419424;19492.122854471298;19600;19984.20063197456;20039.799602003794;17153;17320.740188896925;17895.642087157925;17988.640227195643;17995.82004179994;19377;19684.606307874048;19692.60614787725;19705.113269902602;19896.60206795885;15321.080735155927;15331.310091045707;15426.537203883901;15432.074075555503;15435.768463473281;19690.21239150418;19744.30894918671;19794.80205197987;19917.203311867364;19979.001049947736 13:01:30 INFO - PID 13390 | |3;mootools.html;1444.6316189371712;1511.1386509689482;1515.2120897133484;1533.5092770313497;1534.4399294257598;16824.915875420622;17502;17550.12249387532;17560.034198119098;17565;17043.233054512544;17139;17162.828371716278;17176.883502572324;17233.310667573314;2808.3961948181136;2853.85730713464;2867.4981878171343;2893.0742162508004;2904.114245155227;2847.8718457669456;2854.7288007639345;2860.8569571521443;2862.527682932314;2869.985650071747;2731.945361092777;2803.0609745735205;2806.789490788199;2811.2550174203852;2812.9578056329174;2683.7182095879944;2748.2717079973822;2748.3403983044127;2754.5179593571147;2756.476269508787;2580.329114430243;2642.4318771464036;2646.192911162095;2649.2846931328627;2655.8273712208643;16572.66854662912;16918.66162676752;16920.407785727562;16936.830631693647;16951.57621059471;8287.295579875718;8578.270846978014;8647.870281945776;8647.913520864837;8651.740447786577;5655.123455864347;5781.306243250795;5872.207252020965;5930.940690593082;6103.87792244157;1981.7027445883089;2033.166401775265;2043.6423625865564;2087.425957861585;2089.676100204471;28203.57694634582;28792.42415151685;29746.40507189844;30551.69448305533;30633.69366306353;8001.239882211181;8018.639161237758;8057.59712014397;8211.876821847676;8306.335493160532;23197.652035219486;23292.65061024086;23302.3009309721;23333.18333858307;23359.41601459977;9272.072792720808;9278.118578735144;9289.303302252358;9292.907070929205;9296.814063718688;7785.766427007199;7963.243491868263;8165.877511837327;8225.383096267804;8238.464499807493;6173.104899789595;6191.535634827406;6297.2128483939505;6505.512086593524;6518.185226846645;1921.9519512012032;1930.5849242412949;1941.3787587971997;1960.8333291670083;1976.00211892996;971.3977334052792;981.9263555233387;983.7983213441228;988.0415996483399;1012.4532752313816;8400.537970411686;8521.233089022018;8555.871661925075;8613.65545378178;8715.738527844174;779.3025242408127;793.6785601831267;810.6554714246422;810.8256724804078;821.6549049399266;5042;5069.188929771219;5113.463086375877;5179.378474583074;5180.326557547494;24375;24477.14329998477;25370.74629253721;25374.619380709304;25397.11110111137;25085.4982900341;25339.366515837253;25358.11246606397;25409.364765881;25462.23613291604;11051.944740276247;12729.172603780724;13056.21662700241;13137.868621314046;13175.407106680223;9978.650747224103;10198.796024079329;10212;10425.426601536989;10507.789844203226;20975;21579.892100539397;21820.236291729714;21863.89068054713;22164.88917555402;21945.780542194374;22111.33665990023;22117.225897093525;22645;22798;13124.475020999053;13303.66740831487;13321.733565328444;13441.798373024609;13465.798013030007;15366.692666146839;15536.223188840739;15546.455874044354;15551.688966220838;15570.455034073753;12001.1599188056;12192.390380480763;12311.630651080482;12320.445579949102;12515.12394132402;11973.102017348909;11985.760284794429;12218.144729868824;12228.571999979958;12315.507379704712;10820.729481763019;11061.834072488758;11239.66281011571;11246.381449020055;11281.548738050386;8023.157568455228;8134.674613015413;8147.551884646165;8181.386396020442;8191.631376588187;8256.876146857921;8268.357210602553;8276.668933242603;8302.128276531119;8350.665973361241 13:01:30 INFO - PID 13390 | |4;prototype.html;536.1609081787003;570.0878594249205;571.3258355140933;571.7855804073472;577.9422057794221;6307.716152773125;6352.380143063524;6397.8080657580285;6455.644939528336;6476.607529381188;6228.937710622892;6333.778317758879;6351.809445716624;6372.139761132245;6375.203099612537;5221.895562088756;5230.764615592308;5231.215317702337;5231.776494989634;5246.973765131188;4917.336159618441;5186.662866913657;5191.428942816287;5193.429252520008;5247.606429517782;4941.851744447672;4984.177610694231;5009.44896061433;5030.446650868402;5030.547250747432;4991.850244492671;5016.548510634024;5045.268436076767;5054;5066.97466512667;4562.657800664963;4568.086382723435;4569;4576.1762882681105;4584.12901548708;6262.968685156545;6271.717772700194;6288.308286088527;6291.748330066792;6316.557840951135;5799.21710569072;5868.2664666916635;5949.286085669747;5962.850928726816;5972.6715030673295;6661.16735408074;6688.498362622823;6695.799126026227;6720.3279672032895;6723.495737819683;5104.157813960692;5124.897502049938;5129.204973229155;5138.409082955471;5144.897102057976;6669;6788.728450862009;6832.897506537406;6844.1444819397575;6978.651067446607;5231.764570594332;5236.842894713165;5251.186066159751;5277.656952298088;5279.736013199325;5309;5322.627416080837;5327.227552004938;5342.1452567589;5377.892442151135;5894.381089985575;5925.881482370329;5956;5960.344362120163;5969.98510253258;6578.407943285128;6635.900461493082;6720.4287635550545;6729.149483891969;6757.290484499155;1792.0681245752137;1813.3834496271331;1840.0983518076314;1840.4386662068198;1844.7601811764384;1256.1395444120806;1312.193001304191;1322.4644019172251;1326.3434599873124;1329.5014369611395;5880.470757631833;5961.344252132262;6082.878342433127;6130.172426722335;6163.96918015416;6636.800895973129;6667.866642667216;6690.799276021728;6764.526483146133;6816.7614133505085;6103.23709536308;6203.875922481525;6219.689015549205;6291.905621415683;6324.26868147795;3533.823308834548;3612.223371975038;3626.5829429615405;3630.3102410541915;3640.399334109842;6846.809021113296;7039;7133.464990125762;7140.428765698732;7156.24859389767;6832.760853370208;6952.652367381611;6969.407600353925;6978.232394436608;7007.4394048476;6955.374016338655;6984;7057.223705392403;7061.752838650623;7156.713731450684;6614.404703576702;6723.327667233433;6811.182658081062;6825.488088393291;6874.106366172366;6682.198136223684;6802.591844489347;6812.693428795816;6837.521373504006;6848.3494068063455;7031.367176953894;7052.788416347517;7055.647217638995;7067.858642827217;7139.464540159405;5237.114638729978;5245.763940622757;5265.6050796189675;5274.498922602346;5304.496072873071;6865.622390768555;6899.75850845218;6903.75836845708;6949.7567585134275;6974.6512674367095;6397.232332120176;6482.314598204595;6621.735130594722;6642.136522252076;6654.800355989328;6507.446867016357;6571.474282057519;6579.769708060387;6629.370209830058;6651.671914829771;6472.385123413268;6517.152770139852;6586.1438013058;6598.074145883192;6652.90020649681;6694.631795251308;6721.126253587003;6737.966310168418;6745.426638735762;6785.660716964033;6437.420632143129;6452.580582262326;6531.412172904462;6551.279359270381;6599 13:01:30 INFO - PID 13390 | |5;yui.html;947.3557980573216;956.000978976969;983.8819341678995;987.9654212102578;993.423814187771;20677.793222067776;21222.681659775077;21244;21275.787242127593;21291.935403229836;19288.421347359603;19428.41714748556;19475;19664.803351966475;19848.40454786359;1042.9217808664362;1068.8289873620222;1070.8447275145138;1071.3197119828887;1074.511097450658;1023.2019025160387;1054.398992574233;1064.8402739589046;1070.684148176286;1078.3044936016265;1149.5574203931506;1150.1948635954823;1151.2862025544132;1152.066825871043;1158.646612783105;1044.5143008501084;1134.2117228526135;1140.823172408278;1140.925839820409;1146.3694967767694;809.9838003239961;813.4712436916052;814.9429539932207;815.0586073085536;815.4006804998321;19228.230870765292;19269.132889020028;19440.611187776165;19456.416307510797;19549.902250488798;22497.887510562505;23001.654975175385;23227.187048453394;23238;23241.53516929652;16680.33278668852;16956.74564881528;17739.29042838285;17894.194761235776;17898;4021.77880216588;4050.797460126982;4050.9594904050878;4052.675785937118;4054.2499637567144;161181;162571;163372;165305;168796;16931.66136677284;16979.91510042442;17002.574935626708;17016.234269457906;17109.14454277281;32743.18142046468;33032.174195645304;33499.66500335014;33595.328093437995;33625.49561756576;8600;8622.827543449095;8641.783955401166;8659.437136586102;8681.348898832613;4598.678092533491;4646.140464014168;4690.03854209893;4716.245400735866;4716.764161791897;6092.969535152384;6218.129461875341;6248;6265.874682506415;6290.339514351019;1122.9887701123048;1151.2977083978767;1163.116031815826;1179.4515550269166;1184.1710802438329;707.345705222667;729.6424751871544;732.3299181249098;744.0587656614388;754.1553460124695;12813.551525696555;12948.28784416866;13084.018698597643;13554;13832.40927293374;734.2217249715319;746.9887951680729;751.9135299440525;752.6048824367142;764.5259938837956;5724.856878577985;5787.913181302284;5824.708764561755;5899.528037756969;5979.730912108966;142335;142388;142578.2871085638;143483;144260;110726;120356.3982180101;122028;124433;128003;103613.48193258986;104282.47858760657;105802;106770.46614766876;108210;14135.222562759147;14455.638609034859;14690.706185876435;14806.851931480547;14871.256437178314;51149.744251278506;51797.74101129621;51873.22190167075;52038;52986.73506632442;66101.66949165224;66791.66604166948;67128.3287167122;68803.65598172178;69451;19795.703064453755;20144.294949676692;20223.59552808965;20335.898320508895;20453.181872724923;22802.315930522112;23092;23229.954652041037;23283;23494.53010939806;20370;20700.482487937923;20879;21048.26331078405;21086.683699744823;20989.265375711777;21173.6823947644;21241;21275.255366062112;21645.891770541046;19994.600107997467;20185.59628807445;20256.79743202608;20459.795402046384;20574.177032919113;13361.732765344834;13608.727825443239;13701.657458563217;13923.164610123426;13929.860701392856;13880.305984700926;13962.58112256634;14048.859511405164;14121;14265.786013210018 13:01:30 INFO - PID 13390 | __end_tp_report 13:01:30 INFO - PID 13390 | __start_cc_report 13:01:30 INFO - PID 13390 | _x_x_mozilla_cycle_collect,275 13:01:30 INFO - PID 13390 | __end_cc_report 13:01:30 INFO - PID 13390 | __startTimestamp1506456090663__endTimestamp 13:01:30 INFO - PID 13390 | 13:01:30 INFO - PID 13390 | ------- Summary: start ------- 13:01:30 INFO - PID 13390 | Number of tests: 6 13:01:30 INFO - PID 13390 | 13:01:30 INFO - PID 13390 | [#0] dojo.html Cycles:165 Average:6288.43 Median:6516.64 stddev:735.34 (11.3%) stddev-sans-first:712.57 13:01:30 INFO - PID 13390 | Values: 3863.9 3910.4 3919.8 3925.4 3975.0 6229.8 6238.7 6278.1 6323.5 6400.2 6279.4 6302.6 6361.7 6390.8 6451.9 5639.8 5669.7 5686.9 5722.3 5735.2 5597.2 5704.7 5709.7 5747.2 5778.1 5709.8 5729.9 5740.8 5774.5 5782.9 5592.2 5621.4 5667.3 5679.0 5717.6 5611.2 5663.7 5673.0 5686.3 5687.1 6198.9 6245.1 6261.9 6315.2 6335.1 6738.3 6780.9 6795.4 6800.3 6900.6 6805.3 6892.8 6901.5 6909.0 6909.9 6264.9 6311.2 6399.5 6405.7 6412.4 6994.3 7026.1 7030.4 7036.0 7043.2 6747.5 6750.0 6753.6 6754.0 6757.0 6772.7 6812.5 6874.7 6898.4 6901.0 6784.6 6859.1 6870.5 6884.0 6899.0 6770.3 6806.5 6815.9 6883.8 6895.1 4961.7 5022.3 5025.7 5026.5 5041.7 4532.9 4549.7 4586.0 4597.3 4609.1 6147.2 6230.7 6238.9 6239.3 6242.0 6689.1 6739.2 6809.5 6836.4 6838.2 6183.7 6252.6 6255.8 6258.6 6276.1 5472.9 5490.8 5541.2 5550.3 5556.5 7028.2 7037.4 7042.8 7097.7 7147.9 6676.1 6776.7 6795.5 6862.9 6906.5 7072.5 7074.3 7124.4 7165.2 7179.5 6651.2 6762.3 6773.2 6815.9 6851.0 6766.2 6820.9 6836.8 6872.8 6879.1 6601.1 6605.1 6690.4 6693.9 6695.7 6478.7 6566.4 6583.9 6670.3 6679.2 6504.8 6527.7 6528.2 6532.6 6539.6 6516.6 6601.8 6681.8 6700.0 6706.9 6418.4 6418.4 6440.1 6494.1 6513.0 13:01:30 INFO - PID 13390 | 13:01:30 INFO - PID 13390 | [#1] ext.html Cycles:180 Average:43630.51 Median:3829.44 stddev:66428.64 (1734.7%) stddev-sans-first:66537.73 13:01:30 INFO - PID 13390 | Values: 960.6 971.8 998.4 1020.3 1020.3 43424.1 45237.0 45285.5 45297.0 45368.1 42333.4 42606.0 42827.0 42848.8 42902.8 7581.0 7701.3 7710.8 7714.9 7719.3 7618.8 7716.3 7730.1 7732.3 7759.8 8490.2 8687.2 8701.2 8718.8 8719.8 8577.9 8646.6 8669.0 8676.8 8677.2 8362.7 8486.2 8527.1 8541.9 8563.0 42436.4 42539.4 42696.6 42813.1 42834.8 3766.5 3822.9 3828.8 3830.1 3834.2 33288.5 33728.5 33765.5 33847.0 34005.1 5176.5 5221.7 5245.6 5253.7 5305.2 229210.0 229836.0 230785.0 230857.0 231093.0 55074.4 55437.2 55565.0 55685.7 55733.7 55568.0 55943.0 56168.0 56280.2 56302.7 15015.0 15431.0 15507.5 15519.7 15555.4 12160.5 12195.4 12200.6 12219.3 12268.0 15708.8 15742.1 15759.4 15875.9 15876.0 6193.4 6199.7 6310.7 6355.2 6374.3 1085.4 1093.6 1097.2 1102.3 1135.5 26163.9 26292.2 26304.1 26358.0 26493.3 1194.7 1195.9 1210.3 1220.8 1226.8 13698.5 13875.3 14020.9 14044.6 14263.4 282370.0 282638.0 283240.0 283512.0 284133.0 50365.5 51407.0 59205.0 59495.7 59855.4 1496.5 1517.2 1519.3 1522.0 1536.9 30738.4 30834.7 31097.4 31107.8 31437.9 38804.4 39095.2 43014.6 43286.4 43291.6 249426.0 250231.0 250601.0 250780.0 250830.0 37698.4 38528.0 38566.0 38693.0 38991.8 47142.8 47332.0 47343.5 47416.0 48089.8 34769.0 34901.8 35625.0 35764.6 36001.5 34543.8 34992.5 35161.8 35168.3 35211.0 39903.4 40247.0 41182.4 41344.0 41388.6 34295.7 34362.0 34586.3 34590.5 34603.8 25242.5 25596.5 25726.5 26023.7 26614.2 13:01:30 INFO - PID 13390 | 13:01:30 INFO - PID 13390 | [#2] jquery.html Cycles:180 Average:39424.39 Median:12938.79 stddev:98619.06 (762.2%) stddev-sans-first:98852.61 13:01:30 INFO - PID 13390 | Values: 592.7 601.2 613.2 622.3 636.9 23601.2 24057.6 24400.1 24457.0 24473.6 24536.0 25940.9 26055.5 26074.0 26077.3 6593.8 6746.2 6751.7 6778.2 6794.5 6580.7 6696.6 6718.0 6997.4 7021.5 6955.0 6964.7 6970.7 6972.9 6980.5 6651.0 6901.1 6920.3 6946.3 6973.9 6450.5 6454.5 6468.4 6469.5 6478.0 30329.1 30853.0 30881.8 30902.4 31026.7 2235.7 2266.4 2268.5 2275.9 2276.4 18741.3 20659.5 20711.3 20993.8 21037.5 2467.0 2484.7 2524.9 2545.1 2582.8 137563.3 143241.0 155829.0 161234.0 161365.0 16175.4 16349.6 16368.5 16954.7 17359.8 26531.6 26562.3 26563.9 26579.1 26653.0 10729.7 10768.6 10794.7 10887.6 11073.1 9801.8 9802.4 9807.8 9883.6 9929.4 9658.6 10013.5 10119.9 10231.6 10279.8 2983.2 2987.6 3007.5 3029.6 3101.6 1035.5 1041.0 1043.8 1046.2 1052.6 20369.0 20891.7 20923.0 21130.5 21173.6 1101.3 1107.9 1112.4 1118.4 1119.3 11290.3 11576.1 11611.8 11627.5 11662.7 552271.0 568751.0 571819.0 584331.0 657268.0 128899.4 129318.0 130429.0 133061.3 134072.0 961.6 971.8 998.2 1117.2 1245.0 21362.6 22045.9 22149.1 22216.2 22306.9 61593.4 63064.7 63363.7 63977.0 64976.0 77528.6 78139.0 78461.0 79592.6 80037.6 15351.9 15554.6 15669.2 15786.9 15896.8 23976.0 24074.4 24174.2 24448.1 24511.6 19425.8 19492.1 19600.0 19984.2 20039.8 17153.0 17320.7 17895.6 17988.6 17995.8 19377.0 19684.6 19692.6 19705.1 19896.6 15321.1 15331.3 15426.5 15432.1 15435.8 19690.2 19744.3 19794.8 19917.2 19979.0 13:01:30 INFO - PID 13390 | 13:01:30 INFO - PID 13390 | [#3] mootools.html Cycles:180 Average:10651.33 Median:2611.38 stddev:7853.11 (300.7%) stddev-sans-first:7844.68 13:01:30 INFO - PID 13390 | Values: 1444.6 1511.1 1515.2 1533.5 1534.4 16824.9 17502.0 17550.1 17560.0 17565.0 17043.2 17139.0 17162.8 17176.9 17233.3 2808.4 2853.9 2867.5 2893.1 2904.1 2847.9 2854.7 2860.9 2862.5 2870.0 2731.9 2803.1 2806.8 2811.3 2813.0 2683.7 2748.3 2748.3 2754.5 2756.5 2580.3 2642.4 2646.2 2649.3 2655.8 16572.7 16918.7 16920.4 16936.8 16951.6 8287.3 8578.3 8647.9 8647.9 8651.7 5655.1 5781.3 5872.2 5930.9 6103.9 1981.7 2033.2 2043.6 2087.4 2089.7 28203.6 28792.4 29746.4 30551.7 30633.7 8001.2 8018.6 8057.6 8211.9 8306.3 23197.7 23292.7 23302.3 23333.2 23359.4 9272.1 9278.1 9289.3 9292.9 9296.8 7785.8 7963.2 8165.9 8225.4 8238.5 6173.1 6191.5 6297.2 6505.5 6518.2 1922.0 1930.6 1941.4 1960.8 1976.0 971.4 981.9 983.8 988.0 1012.5 8400.5 8521.2 8555.9 8613.7 8715.7 779.3 793.7 810.7 810.8 821.7 5042.0 5069.2 5113.5 5179.4 5180.3 24375.0 24477.1 25370.7 25374.6 25397.1 25085.5 25339.4 25358.1 25409.4 25462.2 11051.9 12729.2 13056.2 13137.9 13175.4 9978.7 10198.8 10212.0 10425.4 10507.8 20975.0 21579.9 21820.2 21863.9 22164.9 21945.8 22111.3 22117.2 22645.0 22798.0 13124.5 13303.7 13321.7 13441.8 13465.8 15366.7 15536.2 15546.5 15551.7 15570.5 12001.2 12192.4 12311.6 12320.4 12515.1 11973.1 11985.8 12218.1 12228.6 12315.5 10820.7 11061.8 11239.7 11246.4 11281.5 8023.2 8134.7 8147.6 8181.4 8191.6 8256.9 8268.4 8276.7 8302.1 8350.7 13:01:30 INFO - PID 13390 | 13:01:30 INFO - PID 13390 | [#4] prototype.html Cycles:180 Average:5692.07 Median:6320.41 stddev:1582.48 (25.0%) stddev-sans-first:1538.87 13:01:30 INFO - PID 13390 | Values: 536.2 570.1 571.3 571.8 577.9 6307.7 6352.4 6397.8 6455.6 6476.6 6228.9 6333.8 6351.8 6372.1 6375.2 5221.9 5230.8 5231.2 5231.8 5247.0 4917.3 5186.7 5191.4 5193.4 5247.6 4941.9 4984.2 5009.4 5030.4 5030.5 4991.9 5016.5 5045.3 5054.0 5067.0 4562.7 4568.1 4569.0 4576.2 4584.1 6263.0 6271.7 6288.3 6291.7 6316.6 5799.2 5868.3 5949.3 5962.9 5972.7 6661.2 6688.5 6695.8 6720.3 6723.5 5104.2 5124.9 5129.2 5138.4 5144.9 6669.0 6788.7 6832.9 6844.1 6978.7 5231.8 5236.8 5251.2 5277.7 5279.7 5309.0 5322.6 5327.2 5342.1 5377.9 5894.4 5925.9 5956.0 5960.3 5970.0 6578.4 6635.9 6720.4 6729.1 6757.3 1792.1 1813.4 1840.1 1840.4 1844.8 1256.1 1312.2 1322.5 1326.3 1329.5 5880.5 5961.3 6082.9 6130.2 6164.0 6636.8 6667.9 6690.8 6764.5 6816.8 6103.2 6203.9 6219.7 6291.9 6324.3 3533.8 3612.2 3626.6 3630.3 3640.4 6846.8 7039.0 7133.5 7140.4 7156.2 6832.8 6952.7 6969.4 6978.2 7007.4 6955.4 6984.0 7057.2 7061.8 7156.7 6614.4 6723.3 6811.2 6825.5 6874.1 6682.2 6802.6 6812.7 6837.5 6848.3 7031.4 7052.8 7055.6 7067.9 7139.5 5237.1 5245.8 5265.6 5274.5 5304.5 6865.6 6899.8 6903.8 6949.8 6974.7 6397.2 6482.3 6621.7 6642.1 6654.8 6507.4 6571.5 6579.8 6629.4 6651.7 6472.4 6517.2 6586.1 6598.1 6652.9 6694.6 6721.1 6738.0 6745.4 6785.7 6437.4 6452.6 6531.4 6551.3 6599.0 13:01:30 INFO - PID 13390 | 13:01:30 INFO - PID 13390 | [#5] yui.html Cycles:180 Average:27893.88 Median:20296.35 stddev:40678.07 (200.4%) stddev-sans-first:40741.87 13:01:30 INFO - PID 13390 | Values: 947.4 956.0 983.9 988.0 993.4 20677.8 21222.7 21244.0 21275.8 21291.9 19288.4 19428.4 19475.0 19664.8 19848.4 1042.9 1068.8 1070.8 1071.3 1074.5 1023.2 1054.4 1064.8 1070.7 1078.3 1149.6 1150.2 1151.3 1152.1 1158.6 1044.5 1134.2 1140.8 1140.9 1146.4 810.0 813.5 814.9 815.1 815.4 19228.2 19269.1 19440.6 19456.4 19549.9 22497.9 23001.7 23227.2 23238.0 23241.5 16680.3 16956.7 17739.3 17894.2 17898.0 4021.8 4050.8 4051.0 4052.7 4054.2 161181.0 162571.0 163372.0 165305.0 168796.0 16931.7 16979.9 17002.6 17016.2 17109.1 32743.2 33032.2 33499.7 33595.3 33625.5 8600.0 8622.8 8641.8 8659.4 8681.3 4598.7 4646.1 4690.0 4716.2 4716.8 6093.0 6218.1 6248.0 6265.9 6290.3 1123.0 1151.3 1163.1 1179.5 1184.2 707.3 729.6 732.3 744.1 754.2 12813.6 12948.3 13084.0 13554.0 13832.4 734.2 747.0 751.9 752.6 764.5 5724.9 5787.9 5824.7 5899.5 5979.7 142335.0 142388.0 142578.3 143483.0 144260.0 110726.0 120356.4 122028.0 124433.0 128003.0 103613.5 104282.5 105802.0 106770.5 108210.0 14135.2 14455.6 14690.7 14806.9 14871.3 51149.7 51797.7 51873.2 52038.0 52986.7 66101.7 66791.7 67128.3 68803.7 69451.0 19795.7 20144.3 20223.6 20335.9 20453.2 22802.3 23092.0 23230.0 23283.0 23494.5 20370.0 20700.5 20879.0 21048.3 21086.7 20989.3 21173.7 21241.0 21275.3 21645.9 19994.6 20185.6 20256.8 20459.8 20574.2 13361.7 13608.7 13701.7 13923.2 13929.9 13880.3 13962.6 14048.9 14121.0 14265.8 13:01:30 INFO - PID 13390 | -------- Summary: end -------- 13:01:30 INFO - PID 13390 | 13:01:31 INFO - TEST-INFO | 13390: exit 0 13:01:31 INFO - TEST-OK | dromaeo_css | took 1107910ms 13:01:31 INFO - TEST-START | kraken 13:01:31 INFO - Initialising browser for kraken test... 13:01:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51412/getInfo.html -profile /tmp/tmpstDBN4/profile 13:01:31 INFO - TEST-INFO | started process 13765 (/builds/slave/test/build/application/firefox/firefox http://localhost:51412/getInfo.html) 13:01:35 INFO - TEST-INFO | 13765: exit 0 13:01:36 INFO - Browser initialized. 13:01:36 INFO - Running cycle 1/1 for kraken test... 13:01:36 INFO - Using env: {'DISPLAY': ':0', 13:01:36 INFO - 'HOME': '/home/cltbld', 13:01:36 INFO - 'JSGC_DISABLE_POISONING': '1', 13:01:36 INFO - 'LANG': 'en_US.UTF-8', 13:01:36 INFO - 'LANGUAGE': 'en_US:en', 13:01:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 13:01:36 INFO - 'LOGNAME': 'cltbld', 13:01:36 INFO - 'MAIL': '/var/mail/cltbld', 13:01:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:01:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:01:36 INFO - 'MOZ_CRASHREPORTER': '1', 13:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 13:01:36 INFO - 'MOZ_NO_REMOTE': '1', 13:01:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:01:36 INFO - 'NO_EM_RESTART': '1', 13:01:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:36 INFO - 'PWD': '/builds/slave/test', 13:01:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:01:36 INFO - 'RUST_BACKTRACE': 'full', 13:01:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:01:36 INFO - 'SHELL': '/bin/bash', 13:01:36 INFO - 'SHLVL': '1', 13:01:36 INFO - 'TERM': 'linux', 13:01:36 INFO - 'TMOUT': '86400', 13:01:36 INFO - 'USER': 'cltbld', 13:01:36 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185', 13:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:36 INFO - '_': '/tools/buildbot/bin/python'} 13:01:36 INFO - TEST-INFO | started process 13999 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpstDBN4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 13:01:36 INFO - PID 13999 | 13:01:36 INFO - PID 13999 | (/builds/slave/test/build/application/firefox/firefox:14049): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 13:01:36 INFO - PID 13999 | 13:01:47 INFO - PID 13999 | RSS: Main: 147009536 13:01:47 INFO - PID 13999 | 13:01:49 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51412/tests/kraken/driver.html?testName=audio-beat-detection) 13:01:50 INFO - PID 13999 | RSS: Main: 153116672 13:01:50 INFO - PID 13999 | 13:01:53 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51412/tests/kraken/driver.html?testName=audio-dft) 13:01:53 INFO - PID 13999 | RSS: Main: 153247744 13:01:53 INFO - PID 13999 | 13:01:56 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51412/tests/kraken/driver.html?testName=audio-fft) 13:01:57 INFO - PID 13999 | RSS: Main: 150151168 13:01:57 INFO - PID 13999 | 13:01:59 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51412/tests/kraken/driver.html?testName=audio-oscillator) 13:02:00 INFO - PID 13999 | RSS: Main: 150753280 13:02:00 INFO - PID 13999 | 13:02:02 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51412/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:02:02 INFO - PID 13999 | RSS: Main: 150605824 13:02:02 INFO - PID 13999 | 13:02:05 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51412/tests/kraken/driver.html?testName=imaging-darkroom) 13:02:06 INFO - PID 13999 | RSS: Main: 152596480 13:02:06 INFO - PID 13999 | 13:02:09 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51412/tests/kraken/driver.html?testName=imaging-desaturate) 13:02:10 INFO - PID 13999 | RSS: Main: 153374720 13:02:10 INFO - PID 13999 | 13:02:13 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51412/tests/kraken/driver.html?testName=json-parse-financial) 13:02:14 INFO - PID 13999 | RSS: Main: 153235456 13:02:14 INFO - PID 13999 | 13:02:15 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51412/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:02:16 INFO - PID 13999 | RSS: Main: 153313280 13:02:16 INFO - PID 13999 | 13:02:17 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51412/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:02:18 INFO - PID 13999 | RSS: Main: 153497600 13:02:18 INFO - PID 13999 | 13:02:20 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51412/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:02:21 INFO - PID 13999 | RSS: Main: 150372352 13:02:21 INFO - PID 13999 | 13:02:23 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51412/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:02:24 INFO - PID 13999 | RSS: Main: 150036480 13:02:24 INFO - PID 13999 | 13:02:26 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51412/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:02:27 INFO - PID 13999 | RSS: Main: 150458368 13:02:27 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | Cycle 1(1): loaded http://localhost:51412/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51412/tests/kraken/driver.html?testName=ai-astar) 13:02:29 INFO - PID 13999 | RSS: Main: 152272896 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | __start_tp_report 13:02:29 INFO - PID 13999 | _x_x_mozilla_page_load 13:02:29 INFO - PID 13999 | _x_x_mozilla_page_load_details 13:02:29 INFO - PID 13999 | |i|pagename|runs| 13:02:29 INFO - PID 13999 | |0;ai-astar;106;107;106;106;105;105;115;105;108;108 13:02:29 INFO - PID 13999 | |1;audio-beat-detection;113;122;113;111;115;116;116;112;113;113 13:02:29 INFO - PID 13999 | |2;audio-dft;163;164;163;163;163;162;163;162;170;167 13:02:29 INFO - PID 13999 | |3;audio-fft;73;73;73;73;74;74;74;73;74;76 13:02:29 INFO - PID 13999 | |4;audio-oscillator;79;78;79;82;77;78;78;79;77;83 13:02:29 INFO - PID 13999 | |5;imaging-gaussian-blur;110;114;113;114;113;115;115;113;116;110 13:02:29 INFO - PID 13999 | |6;imaging-darkroom;151;151;150;151;151;150;150;150;150;152 13:02:29 INFO - PID 13999 | |7;imaging-desaturate;95;94;95;96;95;95;95;96;95;100 13:02:29 INFO - PID 13999 | |8;json-parse-financial;46;50;45;45;46;47;46;44;51;48 13:02:29 INFO - PID 13999 | |9;json-stringify-tinderbox;40;40;40;39;40;40;41;40;40;42 13:02:29 INFO - PID 13999 | |10;stanford-crypto-aes;71;70;70;73;72;73;74;70;71;73 13:02:29 INFO - PID 13999 | |11;stanford-crypto-ccm;104;107;108;107;108;108;107;104;109;108 13:02:29 INFO - PID 13999 | |12;stanford-crypto-pbkdf2;153;151;157;153;151;151;154;150;154;156 13:02:29 INFO - PID 13999 | |13;stanford-crypto-sha256-iterative;62;60;59;63;64;62;60;59;59;63 13:02:29 INFO - PID 13999 | __end_tp_report 13:02:29 INFO - PID 13999 | __start_cc_report 13:02:29 INFO - PID 13999 | _x_x_mozilla_cycle_collect,722 13:02:29 INFO - PID 13999 | __end_cc_report 13:02:29 INFO - PID 13999 | __startTimestamp1506456149315__endTimestamp 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | ------- Summary: start ------- 13:02:29 INFO - PID 13999 | Number of tests: 14 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#0] ai-astar Cycles:10 Average:107.10 Median:106.50 stddev:3.00 (2.8%) stddev-sans-first:3.15 13:02:29 INFO - PID 13999 | Values: 106.0 107.0 106.0 106.0 105.0 105.0 115.0 105.0 108.0 108.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#1] audio-beat-detection Cycles:10 Average:114.40 Median:114.00 stddev:3.13 (2.7%) stddev-sans-first:3.28 13:02:29 INFO - PID 13999 | Values: 113.0 122.0 113.0 111.0 115.0 116.0 116.0 112.0 113.0 113.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#2] audio-dft Cycles:10 Average:164.00 Median:163.00 stddev:2.54 (1.6%) stddev-sans-first:2.67 13:02:29 INFO - PID 13999 | Values: 163.0 164.0 163.0 163.0 163.0 162.0 163.0 162.0 170.0 167.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#3] audio-fft Cycles:10 Average:73.70 Median:74.00 stddev:0.95 (1.3%) stddev-sans-first:0.97 13:02:29 INFO - PID 13999 | Values: 73.0 73.0 73.0 73.0 74.0 74.0 74.0 73.0 74.0 76.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#4] audio-oscillator Cycles:10 Average:79.00 Median:79.00 stddev:2.00 (2.5%) stddev-sans-first:2.12 13:02:29 INFO - PID 13999 | Values: 79.0 78.0 79.0 82.0 77.0 78.0 78.0 79.0 77.0 83.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:114.00 stddev:2.00 (1.8%) stddev-sans-first:1.73 13:02:29 INFO - PID 13999 | Values: 110.0 114.0 113.0 114.0 113.0 115.0 115.0 113.0 116.0 110.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#6] imaging-darkroom Cycles:10 Average:150.60 Median:151.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 13:02:29 INFO - PID 13999 | Values: 151.0 151.0 150.0 151.0 151.0 150.0 150.0 150.0 150.0 152.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:95.00 stddev:1.65 (1.7%) stddev-sans-first:1.73 13:02:29 INFO - PID 13999 | Values: 95.0 94.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 100.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#8] json-parse-financial Cycles:10 Average:46.80 Median:46.50 stddev:2.25 (4.8%) stddev-sans-first:2.37 13:02:29 INFO - PID 13999 | Values: 46.0 50.0 45.0 45.0 46.0 47.0 46.0 44.0 51.0 48.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#9] json-stringify-tinderbox Cycles:10 Average:40.20 Median:40.00 stddev:0.79 (2.0%) stddev-sans-first:0.83 13:02:29 INFO - PID 13999 | Values: 40.0 40.0 40.0 39.0 40.0 40.0 41.0 40.0 40.0 42.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#10] stanford-crypto-aes Cycles:10 Average:71.70 Median:72.50 stddev:1.49 (2.1%) stddev-sans-first:1.56 13:02:29 INFO - PID 13999 | Values: 71.0 70.0 70.0 73.0 72.0 73.0 74.0 70.0 71.0 73.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#11] stanford-crypto-ccm Cycles:10 Average:107.00 Median:108.00 stddev:1.70 (1.6%) stddev-sans-first:1.41 13:02:29 INFO - PID 13999 | Values: 104.0 107.0 108.0 107.0 108.0 108.0 107.0 104.0 109.0 108.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.00 Median:153.50 stddev:2.31 (1.5%) stddev-sans-first:2.45 13:02:29 INFO - PID 13999 | Values: 153.0 151.0 157.0 153.0 151.0 151.0 154.0 150.0 154.0 156.0 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:62.00 stddev:1.91 (3.1%) stddev-sans-first:2.00 13:02:29 INFO - PID 13999 | Values: 62.0 60.0 59.0 63.0 64.0 62.0 60.0 59.0 59.0 63.0 13:02:29 INFO - PID 13999 | -------- Summary: end -------- 13:02:29 INFO - PID 13999 | 13:02:29 INFO - PID 13999 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 13:02:29 INFO - TEST-INFO | 13999: exit 0 13:02:29 INFO - TEST-OK | kraken | took 58594ms 13:02:29 INFO - SUITE-END | took 1166s 13:02:29 INFO - Completed test suite (00:19:26) 13:02:29 INFO - javascript benchmark 13:02:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9166.513250375658, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3863.903402414939, 3910.432987216853, 3919.7844118573503, 3925.3915643075334, 3975.04598896265, 6229.781957631484, 6238.719257633406, 6278.058291256314, 6323.5257355698395, 6400.231972163341, 6279.372062793718, 6302.558820882528, 6361.68191590421, 6390.776322828702, 6451.935480645192, 5639.830805075834, 5669.688167150805, 5686.914696279559, 5722.341930677963, 5735.1970724098655, 5597.216389705444, 5704.65772053676, 5709.74306156224, 5747.166660834177, 5778.104393818944, 5709.800156994527, 5729.856753581152, 5740.827775166752, 5774.538036957054, 5782.884342313151, 5592.189132575794, 5621.437856214387, 5667.3482549506925, 5679.034564124109, 5717.599768016241, 5611.2144299798065, 5663.745131469093, 5673.007223735871, 5686.317641882959, 5687.061634830248, 6198.876022479571, 6245.063240513914, 6261.906071408933, 6315.24217093947, 6335.11308416822, 6738.326167383271, 6780.898286525706, 6795.38841504267, 6800.251972283045, 6900.6204658744255, 6805.251422343587, 6892.793216203522, 6901.516893817435, 6909.0, 6909.861802763967, 6264.87470250597, 6311.211098612674, 6399.456046236029, 6405.743770249184, 6412.35876412355, 6994.300569942965, 7026.1217347831525, 7030.402415794715, 7036.0, 7043.225245223021, 6747.493937954673, 6750.0212469192675, 6753.628550429676, 6754.0, 6756.966215168893, 6772.69522871472, 6812.48906332027, 6874.65626718662, 6898.413634841095, 6901.033855260268, 6784.626845523544, 6859.142607174103, 6870.519063665495, 6884.0, 6899.0341352210735, 6770.255271920085, 6806.5235433520165, 6815.897761533581, 6883.827904302432, 6895.138107736533, 4961.7271050092595, 5022.271770593244, 5025.698458092526, 5026.52248036436, 5041.747912604428, 4532.864014079583, 4549.681522293407, 4585.95414045862, 4597.3333866589155, 4609.055143695535, 6147.200863887755, 6230.719617617218, 6238.937610623926, 6239.344868788712, 6242.032484964838, 6689.096971908827, 6739.191297044386, 6809.523333366716, 6836.418904393083, 6838.247792742794, 6183.659898705614, 6252.562320637512, 6255.812325630239, 6258.624482531062, 6276.058591211355, 5472.863178420492, 5490.835274941758, 5541.168824676331, 5550.306211723535, 5556.472135147154, 7028.226895041541, 7037.4018208451835, 7042.753503627348, 7097.7160913562875, 7147.9285207148305, 6676.0987266718375, 6776.728930842711, 6795.524313298021, 6862.931370686429, 6906.482013849082, 7072.504924655224, 7074.327938845801, 7124.430045596237, 7165.211826699163, 7179.461540384387, 6651.16860392451, 6762.289959554176, 6773.187217533928, 6815.897761533482, 6850.965745171242, 6766.221884483348, 6820.863582728417, 6836.760713375008, 6872.793816185523, 6879.140107486564, 6601.10885030534, 6605.1083103780375, 6690.431313338421, 6693.933060669331, 6695.7321707133115, 6478.6760661967655, 6566.376194261537, 6583.934160658332, 6670.266270710315, 6679.198496180489, 6504.837379065561, 6527.706253218712, 6528.249251336157, 6532.608043517404, 6539.6403197824575, 6516.641584712696, 6601.768938087144, 6681.832954176184, 6700.0, 6706.899396509154, 6418.358164183544, 6418.422341989243, 6440.098386225838, 6494.058361537456, 6513.0], "value": 6239.368605439563, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [960.6109525642113, 971.8153550825344, 998.381003777658, 1020.3265844542603, 1020.3265844542603, 43424.13151736967, 45237.0, 45285.547144528544, 45297.0, 45368.09263814726, 42333.36499952503, 42606.0, 42827.0, 42848.78575607125, 42902.78548607253, 7580.962095189517, 7701.3453856422275, 7710.807229819271, 7714.922850771477, 7719.266669666401, 7618.847623047535, 7716.305532502074, 7730.0723913130505, 7732.265434783687, 7759.806004849867, 8490.150984901522, 8687.174718401742, 8701.17338852808, 8718.825623487526, 8719.825603487927, 8577.871331930026, 8646.567671616425, 8669.0, 8676.783080423009, 8677.175668311564, 8362.749117526484, 8486.19381158789, 8527.147285271485, 8541.914580854174, 8563.0, 42436.363454548205, 42539.36190957138, 42696.57303426988, 42813.14373712539, 42834.78582607098, 3766.453864189677, 3822.8661996830256, 3828.7894165820867, 3830.080780612661, 3834.213986132836, 33288.50067248993, 33728.49407258893, 33765.49351759726, 33847.0, 34005.149871253416, 5176.482351764794, 5221.686698798047, 5245.606579506552, 5253.658512196695, 5305.204219367087, 229210.0, 229836.0, 230785.0, 230857.0, 231092.9999999983, 55074.44925550773, 55437.16844247259, 55565.0, 55685.72157139269, 55733.721331393084, 55568.0, 55943.0, 56168.0, 56280.155797663065, 56302.71848640813, 15015.0, 15431.0, 15507.534773956799, 15519.689606207812, 15555.377784888704, 12160.513579456723, 12195.39023048844, 12200.572979945659, 12219.266843989211, 12268.0, 15708.764368534481, 15742.134182619837, 15759.448419305269, 15875.920620397055, 15876.0, 6193.442590166816, 6199.659018753921, 6310.652914089677, 6355.173827402408, 6374.266959299647, 1085.3650614390513, 1093.6008356949656, 1097.1551905033239, 1102.327580176092, 1135.5117299561102, 26163.869180653975, 26292.21123366302, 26304.079357222407, 26358.0, 26493.33766655849, 1194.6595220362328, 1195.8744331845205, 1210.328267811373, 1220.8351872497276, 1226.8221107939298, 13698.45206191741, 13875.334959804888, 14020.859791401956, 14044.648883777783, 14263.42946282137, 282370.0, 282638.0, 283240.0, 283512.0, 284133.0, 50365.49634503608, 51407.0, 59204.99999999914, 59495.70252148712, 59855.40144598585, 1496.5435542159528, 1517.1731406383547, 1519.3011214841076, 1521.9543413697606, 1536.853998870128, 30738.385232295677, 30834.69165308318, 31097.37805243928, 31107.844460778455, 31437.899673511318, 38804.41793373158, 39095.218095637356, 43014.56985430231, 43286.35070473883, 43291.56708432875, 249426.0, 250231.0, 250601.0, 250780.0, 250830.0, 37698.43452348272, 38528.0, 38566.0, 38693.0, 38991.83024509269, 47142.764286178346, 47332.0, 47343.52656473391, 47416.0, 48089.75955120202, 34769.0, 34901.8254908734, 35625.0, 35764.642353577176, 36001.459978099825, 34543.827280863436, 34992.47511287282, 35161.8241908799, 35168.29663406769, 35211.0, 39903.40144897887, 40247.0, 41182.38226426666, 41344.0, 41388.586114139674, 34295.657043429244, 34362.0, 34586.30827383488, 34590.48114278237, 34603.826980864935, 25242.495150097264, 25596.488070238862, 25726.485470290863, 26023.73976260289, 26614.201573952814], "value": 18887.07973502994, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.6947621974681, 601.2304250559285, 613.245707779431, 622.2750495672542, 636.8598908240185, 23601.17395891144, 24057.639135412985, 24400.145994890183, 24457.021719131237, 24473.632895506536, 24536.0, 25940.870295648543, 26055.47889042218, 26074.0, 26077.348066298353, 6593.769218077368, 6746.156730408699, 6751.696173672172, 6778.152730908637, 6794.490413219004, 6580.736770529173, 6696.564723292995, 6718.0, 6997.44020478363, 7021.473389495783, 6955.0, 6964.721411143548, 6970.721171153148, 6972.895406568906, 6980.511364204508, 6651.0, 6901.068355772007, 6920.273371296017, 6946.2706415826115, 6973.930260697379, 6450.548461607689, 6454.483641308684, 6468.353164683494, 6469.51478639104, 6477.967610161919, 30329.090127296215, 30853.0, 30881.845590772125, 30902.38195236105, 31026.689733102605, 2235.7093577834776, 2266.3767463947383, 2268.5462907418582, 2275.9317220483413, 2276.408133885185, 18741.250349985985, 20659.483512912146, 20711.27510537139, 20993.790062099488, 21037.474063148544, 2467.0, 2484.6521486991837, 2524.873756312177, 2545.1346542175565, 2582.83211591246, 137563.31218343944, 143241.0, 155829.0, 161233.99999999884, 161365.0, 16175.43385981485, 16349.591260218589, 16368.508944731677, 16954.660906781795, 17359.826401736074, 26531.602025969627, 26562.335941601228, 26563.867180663972, 26579.069732559266, 26653.0, 10729.731756705989, 10768.623098191525, 10794.73013174677, 10887.618933337295, 11073.05879000294, 9801.803963920824, 9802.411855288705, 9807.803843923082, 9883.604655813831, 9929.3545919515, 9658.565364558612, 10013.49932503372, 10119.898801012043, 10231.641892533726, 10279.766428028684, 2983.23927398512, 2987.581738556604, 3007.5187969925146, 3029.5910052143117, 3101.550275210082, 1035.4874337203132, 1040.9635662751768, 1043.8173319669027, 1046.199657262195, 1052.6263176572359, 20369.0, 20891.68662470064, 20923.0, 21130.471738206666, 21173.576528469344, 1101.3446999035561, 1107.8892110789018, 1112.4437781109445, 1118.3513562133942, 1119.3451830679144, 11290.322580645023, 11576.131790115607, 11611.76776464466, 11627.476763545661, 11662.650120496399, 552271.0, 568751.0, 571819.0, 584331.0, 657268.0, 128899.35550322189, 129318.0, 130429.0435643466, 133061.33469332592, 134072.0, 961.5721004153224, 971.7949742319614, 998.1914649134225, 1117.1621284036971, 1244.975100498003, 21362.57274854525, 22045.889770551046, 22149.1140354384, 22216.222432214796, 22306.888465557568, 61593.38406615877, 63064.684676576326, 63363.683181583794, 63977.0, 64976.0, 77528.61235694011, 78139.0, 78461.0, 79592.60203698944, 80037.59981200057, 15351.923240384174, 15554.611134721723, 15669.216539173223, 15786.9210653946, 15896.761548577011, 23976.0, 24074.398140045938, 24174.153904613253, 24448.14431494889, 24511.632325514776, 19425.8057419424, 19492.122854471298, 19600.0, 19984.20063197456, 20039.799602003794, 17153.0, 17320.740188896925, 17895.642087157925, 17988.640227195643, 17995.82004179994, 19377.0, 19684.606307874048, 19692.60614787725, 19705.113269902602, 19896.60206795885, 15321.080735155927, 15331.310091045707, 15426.537203883901, 15432.074075555503, 15435.768463473281, 19690.21239150418, 19744.30894918671, 19794.80205197987, 19917.203311867364, 19979.001049947736], "value": 12856.968911780077, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.6316189371712, 1511.1386509689482, 1515.2120897133484, 1533.5092770313497, 1534.4399294257598, 16824.915875420622, 17502.0, 17550.12249387532, 17560.034198119098, 17565.0, 17043.233054512544, 17139.0, 17162.828371716278, 17176.883502572324, 17233.310667573314, 2808.3961948181136, 2853.85730713464, 2867.4981878171343, 2893.0742162508004, 2904.114245155227, 2847.8718457669456, 2854.7288007639345, 2860.8569571521443, 2862.527682932314, 2869.985650071747, 2731.945361092777, 2803.0609745735205, 2806.789490788199, 2811.2550174203852, 2812.9578056329174, 2683.7182095879944, 2748.2717079973822, 2748.3403983044127, 2754.5179593571147, 2756.476269508787, 2580.329114430243, 2642.4318771464036, 2646.192911162095, 2649.2846931328627, 2655.8273712208643, 16572.66854662912, 16918.66162676752, 16920.407785727562, 16936.830631693647, 16951.57621059471, 8287.295579875718, 8578.270846978014, 8647.870281945776, 8647.913520864837, 8651.740447786577, 5655.123455864347, 5781.306243250795, 5872.207252020965, 5930.940690593082, 6103.87792244157, 1981.7027445883089, 2033.166401775265, 2043.6423625865564, 2087.425957861585, 2089.676100204471, 28203.57694634582, 28792.42415151685, 29746.40507189844, 30551.69448305533, 30633.69366306353, 8001.239882211181, 8018.639161237758, 8057.59712014397, 8211.876821847676, 8306.335493160532, 23197.652035219486, 23292.65061024086, 23302.3009309721, 23333.18333858307, 23359.41601459977, 9272.072792720808, 9278.118578735144, 9289.303302252358, 9292.907070929205, 9296.814063718688, 7785.766427007199, 7963.243491868263, 8165.877511837327, 8225.383096267804, 8238.464499807493, 6173.104899789595, 6191.535634827406, 6297.2128483939505, 6505.512086593524, 6518.185226846645, 1921.9519512012032, 1930.5849242412949, 1941.3787587971997, 1960.8333291670083, 1976.00211892996, 971.3977334052792, 981.9263555233387, 983.7983213441228, 988.0415996483399, 1012.4532752313816, 8400.537970411686, 8521.233089022018, 8555.871661925075, 8613.65545378178, 8715.738527844174, 779.3025242408127, 793.6785601831267, 810.6554714246422, 810.8256724804078, 821.6549049399266, 5042.0, 5069.188929771219, 5113.463086375877, 5179.378474583074, 5180.326557547494, 24375.0, 24477.14329998477, 25370.74629253721, 25374.619380709304, 25397.11110111137, 25085.4982900341, 25339.366515837253, 25358.11246606397, 25409.364765881, 25462.23613291604, 11051.944740276247, 12729.172603780724, 13056.21662700241, 13137.868621314046, 13175.407106680223, 9978.650747224103, 10198.796024079329, 10212.0, 10425.426601536989, 10507.789844203226, 20975.0, 21579.892100539397, 21820.236291729714, 21863.89068054713, 22164.88917555402, 21945.780542194374, 22111.33665990023, 22117.225897093525, 22645.0, 22798.0, 13124.475020999053, 13303.66740831487, 13321.733565328444, 13441.798373024609, 13465.798013030007, 15366.692666146839, 15536.223188840739, 15546.455874044354, 15551.688966220838, 15570.455034073753, 12001.1599188056, 12192.390380480763, 12311.630651080482, 12320.445579949102, 12515.12394132402, 11973.102017348909, 11985.760284794429, 12218.144729868824, 12228.571999979958, 12315.507379704712, 10820.729481763019, 11061.834072488758, 11239.66281011571, 11246.381449020055, 11281.548738050386, 8023.157568455228, 8134.674613015413, 8147.551884646165, 8181.386396020442, 8191.631376588187, 8256.876146857921, 8268.357210602553, 8276.668933242603, 8302.128276531119, 8350.665973361241], "value": 7417.74897468324, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [536.1609081787003, 570.0878594249205, 571.3258355140933, 571.7855804073472, 577.9422057794221, 6307.716152773125, 6352.380143063524, 6397.8080657580285, 6455.644939528336, 6476.607529381188, 6228.937710622892, 6333.778317758879, 6351.809445716624, 6372.139761132245, 6375.203099612537, 5221.895562088756, 5230.764615592308, 5231.215317702337, 5231.776494989634, 5246.973765131188, 4917.336159618441, 5186.662866913657, 5191.428942816287, 5193.429252520008, 5247.606429517782, 4941.851744447672, 4984.177610694231, 5009.44896061433, 5030.446650868402, 5030.547250747432, 4991.850244492671, 5016.548510634024, 5045.268436076767, 5054.0, 5066.97466512667, 4562.657800664963, 4568.086382723435, 4569.0, 4576.1762882681105, 4584.12901548708, 6262.968685156545, 6271.717772700194, 6288.308286088527, 6291.748330066792, 6316.557840951135, 5799.21710569072, 5868.2664666916635, 5949.286085669747, 5962.850928726816, 5972.6715030673295, 6661.16735408074, 6688.498362622823, 6695.799126026227, 6720.3279672032895, 6723.495737819683, 5104.157813960692, 5124.897502049938, 5129.204973229155, 5138.409082955471, 5144.897102057976, 6669.0, 6788.728450862009, 6832.897506537406, 6844.1444819397575, 6978.651067446607, 5231.764570594332, 5236.842894713165, 5251.186066159751, 5277.656952298088, 5279.736013199325, 5309.0, 5322.627416080837, 5327.227552004938, 5342.1452567589, 5377.892442151135, 5894.381089985575, 5925.881482370329, 5956.0, 5960.344362120163, 5969.98510253258, 6578.407943285128, 6635.900461493082, 6720.4287635550545, 6729.149483891969, 6757.290484499155, 1792.0681245752137, 1813.3834496271331, 1840.0983518076314, 1840.4386662068198, 1844.7601811764384, 1256.1395444120806, 1312.193001304191, 1322.4644019172251, 1326.3434599873124, 1329.5014369611395, 5880.470757631833, 5961.344252132262, 6082.878342433127, 6130.172426722335, 6163.96918015416, 6636.800895973129, 6667.866642667216, 6690.799276021728, 6764.526483146133, 6816.7614133505085, 6103.23709536308, 6203.875922481525, 6219.689015549205, 6291.905621415683, 6324.26868147795, 3533.823308834548, 3612.223371975038, 3626.5829429615405, 3630.3102410541915, 3640.399334109842, 6846.809021113296, 7039.0, 7133.464990125762, 7140.428765698732, 7156.24859389767, 6832.760853370208, 6952.652367381611, 6969.407600353925, 6978.232394436608, 7007.4394048476, 6955.374016338655, 6984.0, 7057.223705392403, 7061.752838650623, 7156.713731450684, 6614.404703576702, 6723.327667233433, 6811.182658081062, 6825.488088393291, 6874.106366172366, 6682.198136223684, 6802.591844489347, 6812.693428795816, 6837.521373504006, 6848.3494068063455, 7031.367176953894, 7052.788416347517, 7055.647217638995, 7067.858642827217, 7139.464540159405, 5237.114638729978, 5245.763940622757, 5265.6050796189675, 5274.498922602346, 5304.496072873071, 6865.622390768555, 6899.75850845218, 6903.75836845708, 6949.7567585134275, 6974.6512674367095, 6397.232332120176, 6482.314598204595, 6621.735130594722, 6642.136522252076, 6654.800355989328, 6507.446867016357, 6571.474282057519, 6579.769708060387, 6629.370209830058, 6651.671914829771, 6472.385123413268, 6517.152770139852, 6586.1438013058, 6598.074145883192, 6652.90020649681, 6694.631795251308, 6721.126253587003, 6737.966310168418, 6745.426638735762, 6785.660716964033, 6437.420632143129, 6452.580582262326, 6531.412172904462, 6551.279359270381, 6599.0], "value": 5236.32306139007, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [947.3557980573216, 956.000978976969, 983.8819341678995, 987.9654212102578, 993.423814187771, 20677.793222067776, 21222.681659775077, 21244.0, 21275.787242127593, 21291.935403229836, 19288.421347359603, 19428.41714748556, 19475.0, 19664.803351966475, 19848.40454786359, 1042.9217808664362, 1068.8289873620222, 1070.8447275145138, 1071.3197119828887, 1074.511097450658, 1023.2019025160387, 1054.398992574233, 1064.8402739589046, 1070.684148176286, 1078.3044936016265, 1149.5574203931506, 1150.1948635954823, 1151.2862025544132, 1152.066825871043, 1158.646612783105, 1044.5143008501084, 1134.2117228526135, 1140.823172408278, 1140.925839820409, 1146.3694967767694, 809.9838003239961, 813.4712436916052, 814.9429539932207, 815.0586073085536, 815.4006804998321, 19228.230870765292, 19269.132889020028, 19440.611187776165, 19456.416307510797, 19549.902250488798, 22497.887510562505, 23001.654975175385, 23227.187048453394, 23238.0, 23241.53516929652, 16680.33278668852, 16956.74564881528, 17739.29042838285, 17894.194761235776, 17898.0, 4021.77880216588, 4050.797460126982, 4050.9594904050878, 4052.675785937118, 4054.2499637567144, 161181.0, 162571.0, 163372.0, 165305.0, 168796.0, 16931.66136677284, 16979.91510042442, 17002.574935626708, 17016.234269457906, 17109.14454277281, 32743.18142046468, 33032.174195645304, 33499.66500335014, 33595.328093437995, 33625.49561756576, 8600.0, 8622.827543449095, 8641.783955401166, 8659.437136586102, 8681.348898832613, 4598.678092533491, 4646.140464014168, 4690.03854209893, 4716.245400735866, 4716.764161791897, 6092.969535152384, 6218.129461875341, 6248.0, 6265.874682506415, 6290.339514351019, 1122.9887701123048, 1151.2977083978767, 1163.116031815826, 1179.4515550269166, 1184.1710802438329, 707.345705222667, 729.6424751871544, 732.3299181249098, 744.0587656614388, 754.1553460124695, 12813.551525696555, 12948.28784416866, 13084.018698597643, 13554.0, 13832.40927293374, 734.2217249715319, 746.9887951680729, 751.9135299440525, 752.6048824367142, 764.5259938837956, 5724.856878577985, 5787.913181302284, 5824.708764561755, 5899.528037756969, 5979.730912108966, 142335.0, 142388.0, 142578.2871085638, 143483.0, 144260.0, 110726.0, 120356.3982180101, 122028.0, 124433.0, 128003.0, 103613.48193258986, 104282.47858760657, 105802.0, 106770.46614766876, 108210.0, 14135.222562759147, 14455.638609034859, 14690.706185876435, 14806.851931480547, 14871.256437178314, 51149.744251278506, 51797.74101129621, 51873.22190167075, 52038.0, 52986.73506632442, 66101.66949165224, 66791.66604166948, 67128.3287167122, 68803.65598172178, 69451.0, 19795.703064453755, 20144.294949676692, 20223.59552808965, 20335.898320508895, 20453.181872724923, 22802.315930522112, 23092.0, 23229.954652041037, 23283.0, 23494.53010939806, 20370.0, 20700.482487937923, 20879.0, 21048.26331078405, 21086.683699744823, 20989.265375711777, 21173.6823947644, 21241.0, 21275.255366062112, 21645.891770541046, 19994.600107997467, 20185.59628807445, 20256.79743202608, 20459.795402046384, 20574.177032919113, 13361.732765344834, 13608.727825443239, 13701.657458563217, 13923.164610123426, 13929.860701392856, 13880.305984700926, 13962.58112256634, 14048.859511405164, 14121.0, 14265.786013210018], "value": 10079.88860457787, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1377.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 107.0, 106.0, 106.0, 105.0, 105.0, 115.0, 105.0, 108.0, 108.0], "value": 107.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 122.0, 113.0, 111.0, 115.0, 116.0, 116.0, 112.0, 113.0, 113.0], "value": 114.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 163.0, 163.0, 162.0, 163.0, 162.0, 170.0, 167.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 74.0, 74.0, 74.0, 73.0, 74.0, 76.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 79.0, 82.0, 77.0, 78.0, 78.0, 79.0, 77.0, 83.0], "value": 79.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 113.0, 114.0, 113.0, 115.0, 115.0, 113.0, 116.0, 110.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 150.0, 151.0, 151.0, 150.0, 150.0, 150.0, 150.0, 152.0], "value": 150.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 100.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 50.0, 45.0, 45.0, 46.0, 47.0, 46.0, 44.0, 51.0, 48.0], "value": 46.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 40.0, 40.0, 39.0, 40.0, 40.0, 41.0, 40.0, 40.0, 42.0], "value": 40.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 70.0, 73.0, 72.0, 73.0, 74.0, 70.0, 71.0, 73.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 107.0, 108.0, 107.0, 108.0, 108.0, 107.0, 104.0, 109.0, 108.0], "value": 107.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 157.0, 153.0, 151.0, 151.0, 154.0, 150.0, 154.0, 156.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 59.0, 63.0, 64.0, 62.0, 60.0, 59.0, 59.0, 63.0], "value": 61.1, "unit": "score"}]}]} 13:02:30 INFO - Return code: 0 13:02:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:02:30 INFO - # TBPL SUCCESS # 13:02:30 INFO - Running post-action listener: _package_coverage_data 13:02:30 INFO - Running post-action listener: _resource_record_post_action 13:02:30 INFO - [mozharness: 2017-09-26 20:02:30.132989Z] Finished run-tests step (success) 13:02:30 INFO - Running post-run listener: _resource_record_post_run 13:02:30 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 329908224; Read time: 0; Write time: 226456 13:02:30 INFO - TinderboxPrint: CPU usage
13.1% 13:02:30 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 13:02:30 INFO - TinderboxPrint: I/O write bytes / time
329,908,224 / 226,456 13:02:30 INFO - TinderboxPrint: CPU idle
8,210.6 (86.9%) 13:02:30 INFO - TinderboxPrint: CPU user
1,187.8 (12.6%) 13:02:30 INFO - TinderboxPrint: Swap in / out
0 / 0 13:02:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10330112; Read time: 0; Write time: 1436 13:02:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:02:30 INFO - run-tests - Wall time: 1167s; CPU: 13.0%; Read bytes: 0; Write bytes: 319160320; Read time: 0; Write time: 224964 13:02:30 INFO - Running post-run listener: _upload_blobber_files 13:02:30 INFO - Blob upload gear active. 13:02:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:02:30 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 13:02:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:02:31 INFO - (blobuploader) - INFO - Open directory for files ... 13:02:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:02:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:02:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:32 INFO - (blobuploader) - INFO - Done attempting. 13:02:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:02:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:02:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:39 INFO - (blobuploader) - INFO - Done attempting. 13:02:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:02:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:02:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:41 INFO - (blobuploader) - INFO - Done attempting. 13:02:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:02:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:02:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:02:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:02:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:02:42 INFO - (blobuploader) - INFO - Done attempting. 13:02:42 INFO - (blobuploader) - INFO - Iteration through files over. 13:02:42 INFO - Return code: 0 13:02:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:02:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:02:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5082bf7e31de2fd8f8eca79aa43c303af93eacd936e2235a305bb8f9323b7405ea33102feb08bcc794cdd62492e53d820271d46574ab694efac2c6f6ce3cb654", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c971fbdfbb8547856e68bce34f71661c2df77cc274b62c498f2699dbed0fcc9a606901a9c9443cf6c392100ab1b9069780ecde5fcfc18b390246e16fd10f62", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5d6acff8ee53bd31663bf4d4b07d669702cc3aa20bfbc731c85b0ff7fc56f1fdc2ee6084079c06fb3e511eec0646722dd4cf7aca533f6d8e02bd12afa81dc79", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5bc06dcf4d0d86c72992ef7a773a18a38fb72eef2580b0d78014c11ed2c3b5a216b237539591e7656b4eb134a6f327b121c30bf69e1e5b1f65b2dbfaa6bfe2"} 13:02:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:02:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:02:42 INFO - Contents: 13:02:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5082bf7e31de2fd8f8eca79aa43c303af93eacd936e2235a305bb8f9323b7405ea33102feb08bcc794cdd62492e53d820271d46574ab694efac2c6f6ce3cb654", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c971fbdfbb8547856e68bce34f71661c2df77cc274b62c498f2699dbed0fcc9a606901a9c9443cf6c392100ab1b9069780ecde5fcfc18b390246e16fd10f62", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5d6acff8ee53bd31663bf4d4b07d669702cc3aa20bfbc731c85b0ff7fc56f1fdc2ee6084079c06fb3e511eec0646722dd4cf7aca533f6d8e02bd12afa81dc79", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5bc06dcf4d0d86c72992ef7a773a18a38fb72eef2580b0d78014c11ed2c3b5a216b237539591e7656b4eb134a6f327b121c30bf69e1e5b1f65b2dbfaa6bfe2"} 13:02:42 INFO - Running post-run listener: copy_logs_to_upload_dir 13:02:42 INFO - Copying logs to upload dir... 13:02:42 INFO - mkdir: /builds/slave/test/build/upload/logs 13:02:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.651343 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2017-09-26 13:02:42.297503) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 13:02:42.301201) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5082bf7e31de2fd8f8eca79aa43c303af93eacd936e2235a305bb8f9323b7405ea33102feb08bcc794cdd62492e53d820271d46574ab694efac2c6f6ce3cb654", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c971fbdfbb8547856e68bce34f71661c2df77cc274b62c498f2699dbed0fcc9a606901a9c9443cf6c392100ab1b9069780ecde5fcfc18b390246e16fd10f62", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5d6acff8ee53bd31663bf4d4b07d669702cc3aa20bfbc731c85b0ff7fc56f1fdc2ee6084079c06fb3e511eec0646722dd4cf7aca533f6d8e02bd12afa81dc79", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5bc06dcf4d0d86c72992ef7a773a18a38fb72eef2580b0d78014c11ed2c3b5a216b237539591e7656b4eb134a6f327b121c30bf69e1e5b1f65b2dbfaa6bfe2"} build_url:https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011227 build_url: 'https://queue.taskcluster.net/v1/task/JSqdHGsuRK2AZcN3JGZ2xg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5082bf7e31de2fd8f8eca79aa43c303af93eacd936e2235a305bb8f9323b7405ea33102feb08bcc794cdd62492e53d820271d46574ab694efac2c6f6ce3cb654", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/80c971fbdfbb8547856e68bce34f71661c2df77cc274b62c498f2699dbed0fcc9a606901a9c9443cf6c392100ab1b9069780ecde5fcfc18b390246e16fd10f62", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a5d6acff8ee53bd31663bf4d4b07d669702cc3aa20bfbc731c85b0ff7fc56f1fdc2ee6084079c06fb3e511eec0646722dd4cf7aca533f6d8e02bd12afa81dc79", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a5bc06dcf4d0d86c72992ef7a773a18a38fb72eef2580b0d78014c11ed2c3b5a216b237539591e7656b4eb134a6f327b121c30bf69e1e5b1f65b2dbfaa6bfe2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-26 13:02:42.330806) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 13:02:42.331222) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3a3620fb28aea1604ab609ef000001d2-1506454162.989162-641650185 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-26 13:02:42.416681) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 13:02:42.417240) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-26 13:02:42.417758) ========= ========= Total master_lag: 0.21 =========