builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-061 starttime: 1504635913.77 results: success (0) revision: 973e8b890a62aee4b3170558ac3b608928162ef6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:13.770319) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:13.770677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:13.771168) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:13.808510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:13.808799) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-05 11:25:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 29.7M=0s 2017-09-05 11:25:14 (29.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.332432 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.161650) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.161975) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019776 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.211221) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.211558) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 973e8b890a62aee4b3170558ac3b608928162ef6 --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 973e8b890a62aee4b3170558ac3b608928162ef6 --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=516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-05 11:25:14,178 truncating revision to first 12 chars 2017-09-05 11:25:14,178 Setting DEBUG logging. 2017-09-05 11:25:14,178 attempt 1/10 2017-09-05 11:25:14,178 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/973e8b890a62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-05 11:25:14,450 unpacking tar archive at: mozilla-central-973e8b890a62/testing/mozharness/ program finished with exit code 0 elapsedTime=0.581180 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.807103) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.807440) ========= script_repo_revision: 973e8b890a62aee4b3170558ac3b608928162ef6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.807813) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.808095) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-05 11:25:14.823550) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-05 11:25:14.823900) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:25:14 INFO - MultiFileLogger online at 20170905 11:25:14 in /builds/slave/test 11:25:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 11:25:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:25:14 INFO - {'append_to_log': False, 11:25:14 INFO - 'base_work_dir': '/builds/slave/test', 11:25:14 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 11:25:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:25:14 INFO - 'branch': 'Firefox-Non-PGO', 11:25:14 INFO - 'buildbot_json_path': 'buildprops.json', 11:25:14 INFO - 'code_coverage': False, 11:25:14 INFO - 'config_files': ('talos/linux_config.py',), 11:25:14 INFO - 'default_actions': ('clobber', 11:25:14 INFO - 'read-buildbot-config', 11:25:14 INFO - 'download-and-extract', 11:25:14 INFO - 'populate-webroot', 11:25:14 INFO - 'create-virtualenv', 11:25:14 INFO - 'install', 11:25:14 INFO - 'setup-mitmproxy', 11:25:14 INFO - 'run-tests'), 11:25:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:25:14 INFO - 'disable_ccov_upload': False, 11:25:14 INFO - 'disable_stylo': False, 11:25:14 INFO - 'download_minidump_stackwalk': True, 11:25:14 INFO - 'download_symbols': 'ondemand', 11:25:14 INFO - 'enable_stylo': False, 11:25:14 INFO - 'enable_webrender': False, 11:25:14 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 11:25:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:25:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:25:14 INFO - 'gecko_profile': False, 11:25:14 INFO - 'gecko_profile_interval': 0, 11:25:14 INFO - 'installer_path': 'installer.exe', 11:25:14 INFO - 'log_level': 'info', 11:25:14 INFO - 'log_name': 'talos', 11:25:14 INFO - 'log_to_console': True, 11:25:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:25:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:25:14 INFO - 'opt_config_files': (), 11:25:14 INFO - 'pip_index': False, 11:25:14 INFO - 'suite': 'dromaeojs-e10s', 11:25:14 INFO - 'system_bits': '32', 11:25:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:25:14 INFO - 'title': 'talos-linux64-ix-061', 11:25:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:25:14 INFO - 'use_talos_json': True, 11:25:14 INFO - 'verify': 'False', 11:25:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:25:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:25:14 INFO - 'work_dir': 'build'} 11:25:14 INFO - [mozharness: 2017-09-05 18:25:14.936972Z] Running clobber step. 11:25:14 INFO - Running pre-action listener: _resource_record_pre_action 11:25:14 INFO - Running main action method: clobber 11:25:14 INFO - rmtree: /builds/slave/test/build 11:25:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:25:15 INFO - Running post-action listener: _resource_record_post_action 11:25:15 INFO - [mozharness: 2017-09-05 18:25:15.952627Z] Finished clobber step (success) 11:25:15 INFO - [mozharness: 2017-09-05 18:25:15.952724Z] Running read-buildbot-config step. 11:25:15 INFO - Running pre-action listener: _resource_record_pre_action 11:25:15 INFO - Running main action method: read_buildbot_config 11:25:15 INFO - Using buildbot properties: 11:25:15 INFO - { 11:25:15 INFO - "project": "", 11:25:15 INFO - "product": "firefox", 11:25:15 INFO - "who": "ffxbld@noreply.mozilla.org", 11:25:15 INFO - "installer_path": "public/build/target.tar.bz2", 11:25:15 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 11:25:15 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:25:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 11:25:15 INFO - "stage_platform": "linux64", 11:25:15 INFO - "basedir": "/builds/slave/test", 11:25:15 INFO - "buildnumber": 1116, 11:25:15 INFO - "platform": "ubuntu64_hw", 11:25:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:25:15 INFO - "slavebuilddir": "test", 11:25:15 INFO - "taskId": "Rln-VjQ3Q0i20p29o7XzLw", 11:25:15 INFO - "branch": "mozilla-central", 11:25:15 INFO - "script_repo_revision": "production", 11:25:15 INFO - "revision": "973e8b890a62aee4b3170558ac3b608928162ef6", 11:25:15 INFO - "slavename": "talos-linux64-ix-061", 11:25:15 INFO - "repo_path": "mozilla-central" 11:25:15 INFO - } 11:25:15 INFO - Finding installer, test and symbols from parent task. 11:25:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rln-VjQ3Q0i20p29o7XzLw'}, attempt #1 11:25:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rln-VjQ3Q0i20p29o7XzLw'}, attempt #1 11:25:16 INFO - Task dependencies: TJbnw1eAT3m2u_5HiPuYfA 11:25:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA'}, attempt #1 11:25:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 11:25:17 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.test_packages.json 11:25:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.crashreporter-symbols.zip 11:25:17 INFO - Running post-action listener: _resource_record_post_action 11:25:17 INFO - [mozharness: 2017-09-05 18:25:17.307835Z] Finished read-buildbot-config step (success) 11:25:17 INFO - [mozharness: 2017-09-05 18:25:17.308051Z] Running download-and-extract step. 11:25:17 INFO - Running pre-action listener: _resource_record_pre_action 11:25:17 INFO - Running main action method: download_and_extract 11:25:17 INFO - mkdir: /builds/slave/test/build/tests 11:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:17 INFO - trying https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.test_packages.json 11:25:17 INFO - Downloading https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:25:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:25:17 INFO - Downloaded 989 bytes. 11:25:17 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:25:17 INFO - Using the following test package requirements: 11:25:17 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:25:17 INFO - u'common': [u'target.common.tests.zip'], 11:25:17 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:25:17 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:25:17 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:25:17 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:25:17 INFO - u'mozbase': [u'target.common.tests.zip'], 11:25:17 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:25:17 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:25:17 INFO - u'web-platform': [u'target.common.tests.zip', 11:25:17 INFO - u'target.web-platform.tests.tar.gz'], 11:25:17 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:25:17 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:25:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip 11:25:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:25:17 INFO - Fetch https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip into memory 11:25:19 INFO - Content-Length response header: 55022217 11:25:19 INFO - Bytes received: 55022217 11:25:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:25:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip 11:25:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:25:30 INFO - Fetch https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.common.tests.zip into memory 11:25:31 INFO - Content-Length response header: 55022217 11:25:31 INFO - Bytes received: 55022217 11:25:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.talos.tests.zip 11:25:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:25:43 INFO - Fetch https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.talos.tests.zip into memory 11:25:44 INFO - Content-Length response header: 14496650 11:25:44 INFO - Bytes received: 14496650 11:25: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')]} 11:25:44 INFO - trying https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 11:25:44 INFO - Downloading https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:25:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:25:45 INFO - Downloaded 62644193 bytes. 11:25:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 11:25:45 INFO - mkdir: /builds/slave/test/properties 11:25:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:25:45 INFO - Writing to file /builds/slave/test/properties/build_url 11:25:45 INFO - Contents: 11:25:45 INFO - build_url:https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 11:25:45 INFO - Running post-action listener: _resource_record_post_action 11:25:45 INFO - Running post-action listener: find_tests_for_verification 11:25:45 INFO - Running post-action listener: set_extra_try_arguments 11:25:45 INFO - [mozharness: 2017-09-05 18:25:45.974401Z] Finished download-and-extract step (success) 11:25:45 INFO - [mozharness: 2017-09-05 18:25:45.974496Z] Running populate-webroot step. 11:25:45 INFO - Running pre-action listener: _resource_record_pre_action 11:25:45 INFO - Running main action method: populate_webroot 11:25:45 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:25:45 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:25:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:25:45 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:25:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:25:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:25:45 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:45 INFO - 'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:25:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:25:45 INFO - 'tests': ['damp', 'tps']}, 11:25:45 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:45 INFO - 'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['damp', 'tps']}, 11:25:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:25:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:25:45 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:25:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:25:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:25:45 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:45 INFO - 'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:25:45 INFO - 'other-e10s': {'tests': ['a11yr', 11:25:45 INFO - 'ts_paint', 11:25:45 INFO - 'tpaint', 11:25:45 INFO - 'sessionrestore', 11:25:45 INFO - 'sessionrestore_many_windows', 11:25:45 INFO - 'sessionrestore_no_auto_restore', 11:25:45 INFO - 'tabpaint']}, 11:25:45 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['a11yr', 11:25:45 INFO - 'ts_paint', 11:25:45 INFO - 'tpaint', 11:25:45 INFO - 'sessionrestore', 11:25:45 INFO - 'sessionrestore_many_windows', 11:25:45 INFO - 'sessionrestore_no_auto_restore', 11:25:45 INFO - 'tabpaint']}, 11:25:45 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:25:45 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:25:45 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['bloom_basic_singleton']}, 11:25:45 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['bloom_basic']}, 11:25:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:25:45 INFO - 'tsvgr_opacity', 11:25:45 INFO - 'tart', 11:25:45 INFO - 'tscrollx', 11:25:45 INFO - 'tsvg_static']}, 11:25:45 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['tsvgx', 11:25:45 INFO - 'tsvgr_opacity', 11:25:45 INFO - 'tart', 11:25:45 INFO - 'tscrollx', 11:25:45 INFO - 'tsvg_static']}, 11:25:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:25:45 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:45 INFO - 'talos_options': ['--enable-stylo'], 11:25:45 INFO - 'tests': ['tp5o']}, 11:25:45 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:25:45 INFO - 'talos_options': ['--mitmproxy', 11:25:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:25:45 INFO - '--firstNonBlankPaint'], 11:25:45 INFO - 'tests': ['tp6_google', 11:25:45 INFO - 'tp6_youtube', 11:25:45 INFO - 'tp6_amazon', 11:25:45 INFO - 'tp6_facebook']}, 11:25:45 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:25:45 INFO - 'talos_options': ['--enable-stylo', 11:25:45 INFO - '--mitmproxy', 11:25:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:25:45 INFO - '--firstNonBlankPaint'], 11:25:45 INFO - 'tests': ['tp6_google', 11:25:45 INFO - 'tp6_youtube', 11:25:45 INFO - 'tp6_amazon', 11:25:45 INFO - 'tp6_facebook']}, 11:25:45 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:25:45 INFO - 'talos_options': ['--enable-stylo', 11:25:45 INFO - '--stylo-threads=1', 11:25:45 INFO - '--mitmproxy', 11:25:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:25:45 INFO - '--firstNonBlankPaint'], 11:25:45 INFO - 'tests': ['tp6_google', 11:25:45 INFO - 'tp6_youtube', 11:25:45 INFO - 'tp6_amazon', 11:25:45 INFO - 'tp6_facebook']}, 11:25:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:25:45 INFO - 'talos_options': ['--xperf_path', 11:25:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:25:45 INFO - 'tests': ['tp5n']}, 11:25:45 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 11:25:45 INFO - 'talos_options': ['--enable-stylo', 11:25:45 INFO - '--xperf_path', 11:25:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:25:45 INFO - 'tests': ['tp5n']}}} 11:25:45 INFO - Running post-action listener: _resource_record_post_action 11:25:45 INFO - [mozharness: 2017-09-05 18:25:45.988718Z] Finished populate-webroot step (success) 11:25:45 INFO - [mozharness: 2017-09-05 18:25:45.988807Z] Running create-virtualenv step. 11:25:45 INFO - Running pre-action listener: _resource_record_pre_action 11:25:45 INFO - Running main action method: create_virtualenv 11:25:45 INFO - Creating virtualenv /builds/slave/test/build/venv 11:25:45 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:25:45 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 11:25:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:25:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:25:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:25:46 INFO - Installing distribute.............................................................................................................................................................................................done. 11:25:46 INFO - Installing pip...............done. 11:25:46 INFO - Return code: 0 11:25:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:25:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:25:46 INFO - Reading from file tmpfile_stdout 11:25:46 INFO - Output received: 11:25:46 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 11:25:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:46 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 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:25:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:25:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:25:46 INFO - Using env: {'DISPLAY': ':0', 11:25:46 INFO - 'HOME': '/home/cltbld', 11:25:46 INFO - 'LANG': 'en_US.UTF-8', 11:25:46 INFO - 'LANGUAGE': 'en_US:en', 11:25:46 INFO - 'LOGNAME': 'cltbld', 11:25:46 INFO - 'MAIL': '/var/mail/cltbld', 11:25:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:46 INFO - 'MOZ_NO_REMOTE': '1', 11:25:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:46 INFO - 'NO_EM_RESTART': '1', 11:25:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:46 INFO - 'PWD': '/builds/slave/test', 11:25:46 INFO - 'SHELL': '/bin/bash', 11:25:46 INFO - 'SHLVL': '1', 11:25:46 INFO - 'TERM': 'linux', 11:25:46 INFO - 'TMOUT': '86400', 11:25:46 INFO - 'USER': 'cltbld', 11:25:46 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:25:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:46 INFO - '_': '/tools/buildbot/bin/python'} 11:25:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 11:25:48 INFO - Downloading/unpacking pip>=1.5 11:25:48 INFO - Running setup.py egg_info for package pip 11:25:48 INFO - warning: no files found matching 'pip/cacert.pem' 11:25:48 INFO - warning: no files found matching '*.html' under directory 'docs' 11:25:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:25:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:25:48 INFO - Installing collected packages: pip 11:25:48 INFO - Found existing installation: pip 1.1 11:25:48 INFO - Uninstalling pip: 11:25:48 INFO - Successfully uninstalled pip 11:25:48 INFO - Running setup.py install for pip 11:25:48 INFO - warning: no files found matching 'pip/cacert.pem' 11:25:48 INFO - warning: no files found matching '*.html' under directory 'docs' 11:25:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 11:25:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 11:25:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 11:25:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 11:25:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 11:25:48 INFO - Successfully installed pip 11:25:48 INFO - Cleaning up... 11:25:48 INFO - Return code: 0 11:25:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:48 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 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:25:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:25:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:25:48 INFO - Using env: {'DISPLAY': ':0', 11:25:48 INFO - 'HOME': '/home/cltbld', 11:25:48 INFO - 'LANG': 'en_US.UTF-8', 11:25:48 INFO - 'LANGUAGE': 'en_US:en', 11:25:48 INFO - 'LOGNAME': 'cltbld', 11:25:48 INFO - 'MAIL': '/var/mail/cltbld', 11:25:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:48 INFO - 'MOZ_NO_REMOTE': '1', 11:25:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:48 INFO - 'NO_EM_RESTART': '1', 11:25:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:48 INFO - 'PWD': '/builds/slave/test', 11:25:48 INFO - 'SHELL': '/bin/bash', 11:25:48 INFO - 'SHLVL': '1', 11:25:48 INFO - 'TERM': 'linux', 11:25:48 INFO - 'TMOUT': '86400', 11:25:48 INFO - 'USER': 'cltbld', 11:25:48 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:25:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:48 INFO - '_': '/tools/buildbot/bin/python'} 11:25:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:48 INFO - Downloading/unpacking psutil>=3.1.1 11:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:25:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:25:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:25:49 INFO - Installing collected packages: psutil 11:25:49 INFO - Running setup.py install for psutil 11:25:49 INFO - building 'psutil._psutil_linux' extension 11:25:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:25:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:25:49 INFO - building 'psutil._psutil_posix' extension 11:25:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:25:50 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:25:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:25:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:25:50 INFO - Successfully installed psutil 11:25:50 INFO - Cleaning up... 11:25:50 INFO - Return code: 0 11:25:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:50 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 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:25:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:25:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:25:50 INFO - Using env: {'DISPLAY': ':0', 11:25:50 INFO - 'HOME': '/home/cltbld', 11:25:50 INFO - 'LANG': 'en_US.UTF-8', 11:25:50 INFO - 'LANGUAGE': 'en_US:en', 11:25:50 INFO - 'LOGNAME': 'cltbld', 11:25:50 INFO - 'MAIL': '/var/mail/cltbld', 11:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:50 INFO - 'MOZ_NO_REMOTE': '1', 11:25:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:50 INFO - 'NO_EM_RESTART': '1', 11:25:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:50 INFO - 'PWD': '/builds/slave/test', 11:25:50 INFO - 'SHELL': '/bin/bash', 11:25:50 INFO - 'SHLVL': '1', 11:25:50 INFO - 'TERM': 'linux', 11:25:50 INFO - 'TMOUT': '86400', 11:25:50 INFO - 'USER': 'cltbld', 11:25:50 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:50 INFO - '_': '/tools/buildbot/bin/python'} 11:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:25:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:25:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:25:51 INFO - Installing collected packages: mozsystemmonitor 11:25:51 INFO - Running setup.py install for mozsystemmonitor 11:25:51 INFO - Successfully installed mozsystemmonitor 11:25:51 INFO - Cleaning up... 11:25:51 INFO - Return code: 0 11:25:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:51 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 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:25:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:25:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:25:51 INFO - Using env: {'DISPLAY': ':0', 11:25:51 INFO - 'HOME': '/home/cltbld', 11:25:51 INFO - 'LANG': 'en_US.UTF-8', 11:25:51 INFO - 'LANGUAGE': 'en_US:en', 11:25:51 INFO - 'LOGNAME': 'cltbld', 11:25:51 INFO - 'MAIL': '/var/mail/cltbld', 11:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:51 INFO - 'MOZ_NO_REMOTE': '1', 11:25:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:51 INFO - 'NO_EM_RESTART': '1', 11:25:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:51 INFO - 'PWD': '/builds/slave/test', 11:25:51 INFO - 'SHELL': '/bin/bash', 11:25:51 INFO - 'SHLVL': '1', 11:25:51 INFO - 'TERM': 'linux', 11:25:51 INFO - 'TMOUT': '86400', 11:25:51 INFO - 'USER': 'cltbld', 11:25:51 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:51 INFO - '_': '/tools/buildbot/bin/python'} 11:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:51 INFO - Downloading/unpacking jsonschema==2.5.1 11:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:25:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:54 INFO - Downloading functools32-3.2.3-2.tar.gz 11:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:25:54 INFO - warning: no files found matching '*.txt' 11:25:54 INFO - no previously-included directories found matching 'build' 11:25:54 INFO - no previously-included directories found matching 'dist' 11:25:54 INFO - no previously-included directories found matching '.git*' 11:25:54 INFO - Installing collected packages: jsonschema, functools32 11:25:54 INFO - Running setup.py install for functools32 11:25:54 INFO - warning: no files found matching '*.txt' 11:25:54 INFO - no previously-included directories found matching 'build' 11:25:54 INFO - no previously-included directories found matching 'dist' 11:25:54 INFO - no previously-included directories found matching '.git*' 11:25:54 INFO - Successfully installed jsonschema functools32 11:25:54 INFO - Cleaning up... 11:25:54 INFO - Return code: 0 11:25:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:25:54 INFO - Using env: {'DISPLAY': ':0', 11:25:54 INFO - 'HOME': '/home/cltbld', 11:25:54 INFO - 'LANG': 'en_US.UTF-8', 11:25:54 INFO - 'LANGUAGE': 'en_US:en', 11:25:54 INFO - 'LOGNAME': 'cltbld', 11:25:54 INFO - 'MAIL': '/var/mail/cltbld', 11:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:54 INFO - 'MOZ_NO_REMOTE': '1', 11:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:54 INFO - 'NO_EM_RESTART': '1', 11:25:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:54 INFO - 'PWD': '/builds/slave/test', 11:25:54 INFO - 'SHELL': '/bin/bash', 11:25:54 INFO - 'SHLVL': '1', 11:25:54 INFO - 'TERM': 'linux', 11:25:54 INFO - 'TMOUT': '86400', 11:25:54 INFO - 'USER': 'cltbld', 11:25:54 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:54 INFO - '_': '/tools/buildbot/bin/python'} 11:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:25:54 INFO - Cleaning up... 11:25:54 INFO - Return code: 0 11:25:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:25:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:25:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:25:54 INFO - Using env: {'DISPLAY': ':0', 11:25:54 INFO - 'HOME': '/home/cltbld', 11:25:54 INFO - 'LANG': 'en_US.UTF-8', 11:25:54 INFO - 'LANGUAGE': 'en_US:en', 11:25:54 INFO - 'LOGNAME': 'cltbld', 11:25:54 INFO - 'MAIL': '/var/mail/cltbld', 11:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:54 INFO - 'MOZ_NO_REMOTE': '1', 11:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:54 INFO - 'NO_EM_RESTART': '1', 11:25:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:54 INFO - 'PWD': '/builds/slave/test', 11:25:54 INFO - 'SHELL': '/bin/bash', 11:25:54 INFO - 'SHLVL': '1', 11:25:54 INFO - 'TERM': 'linux', 11:25:54 INFO - 'TMOUT': '86400', 11:25:54 INFO - 'USER': 'cltbld', 11:25:54 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:54 INFO - '_': '/tools/buildbot/bin/python'} 11:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:54 INFO - Downloading/unpacking blobuploader==1.2.4 11:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:55 INFO - Downloading blobuploader-1.2.4.tar.gz 11:25:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:25:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:25:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:25:56 INFO - Downloading docopt-0.6.1.tar.gz 11:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:25:56 INFO - Installing collected packages: blobuploader, requests, docopt 11:25:56 INFO - Running setup.py install for blobuploader 11:25:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:25:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:25:56 INFO - Running setup.py install for requests 11:25:56 INFO - Running setup.py install for docopt 11:25:56 INFO - Successfully installed blobuploader requests docopt 11:25:56 INFO - Cleaning up... 11:25:57 INFO - Return code: 0 11:25:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:57 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 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:25:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:25:57 INFO - Using env: {'DISPLAY': ':0', 11:25:57 INFO - 'HOME': '/home/cltbld', 11:25:57 INFO - 'LANG': 'en_US.UTF-8', 11:25:57 INFO - 'LANGUAGE': 'en_US:en', 11:25:57 INFO - 'LOGNAME': 'cltbld', 11:25:57 INFO - 'MAIL': '/var/mail/cltbld', 11:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:57 INFO - 'MOZ_NO_REMOTE': '1', 11:25:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:57 INFO - 'NO_EM_RESTART': '1', 11:25:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:57 INFO - 'PWD': '/builds/slave/test', 11:25:57 INFO - 'SHELL': '/bin/bash', 11:25:57 INFO - 'SHLVL': '1', 11:25:57 INFO - 'TERM': 'linux', 11:25:57 INFO - 'TMOUT': '86400', 11:25:57 INFO - 'USER': 'cltbld', 11:25:57 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:57 INFO - '_': '/tools/buildbot/bin/python'} 11:25:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:25:57 INFO - Running setup.py (path:/tmp/pip-6JJfKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:25:57 INFO - Running setup.py (path:/tmp/pip-JryWGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:25:57 INFO - Running setup.py (path:/tmp/pip-p7tvLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:25:57 INFO - Running setup.py (path:/tmp/pip-_4oKq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:25:57 INFO - Running setup.py (path:/tmp/pip-WaHG3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:25:57 INFO - Running setup.py (path:/tmp/pip-0DZgwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:25:57 INFO - Running setup.py (path:/tmp/pip-AwO3kv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:25:57 INFO - Running setup.py (path:/tmp/pip-f2TunF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:25:57 INFO - Running setup.py (path:/tmp/pip-OHNFvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:25:57 INFO - Running setup.py (path:/tmp/pip-NbNTFB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:25:57 INFO - Running setup.py (path:/tmp/pip-NZ4jrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:25:58 INFO - Running setup.py (path:/tmp/pip-e0VACK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:25:58 INFO - Running setup.py (path:/tmp/pip-CXHhbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:25:58 INFO - Running setup.py (path:/tmp/pip-bPVxRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:58 INFO - Running setup.py (path:/tmp/pip-sfHGk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:25:58 INFO - Running setup.py (path:/tmp/pip-JzAPio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:25:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:25:58 INFO - Running setup.py (path:/tmp/pip-WBvdEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:25:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:25:58 INFO - Running setup.py install for manifestparser 11:25:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:25:58 INFO - Running setup.py install for mozcrash 11:25:58 INFO - Running setup.py install for mozdebug 11:25:58 INFO - Running setup.py install for mozdevice 11:25:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:25:58 INFO - Running setup.py install for mozfile 11:25:58 INFO - Running setup.py install for mozhttpd 11:25:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Running setup.py install for mozinfo 11:25:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Running setup.py install for mozInstall 11:25:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Running setup.py install for mozleak 11:25:59 INFO - Running setup.py install for mozlog 11:25:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Running setup.py install for moznetwork 11:25:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Running setup.py install for mozprocess 11:25:59 INFO - Running setup.py install for mozprofile 11:25:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Running setup.py install for mozrunner 11:25:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:25:59 INFO - Running setup.py install for mozscreenshot 11:25:59 INFO - Running setup.py install for moztest 11:26:00 INFO - Running setup.py install for mozversion 11:26:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:26:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:26:00 INFO - Cleaning up... 11:26:00 INFO - Return code: 0 11:26:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:00 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 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:26:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:26:00 INFO - Using env: {'DISPLAY': ':0', 11:26:00 INFO - 'HOME': '/home/cltbld', 11:26:00 INFO - 'LANG': 'en_US.UTF-8', 11:26:00 INFO - 'LANGUAGE': 'en_US:en', 11:26:00 INFO - 'LOGNAME': 'cltbld', 11:26:00 INFO - 'MAIL': '/var/mail/cltbld', 11:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:00 INFO - 'MOZ_NO_REMOTE': '1', 11:26:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:00 INFO - 'NO_EM_RESTART': '1', 11:26:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:00 INFO - 'PWD': '/builds/slave/test', 11:26:00 INFO - 'SHELL': '/bin/bash', 11:26:00 INFO - 'SHLVL': '1', 11:26:00 INFO - 'TERM': 'linux', 11:26:00 INFO - 'TMOUT': '86400', 11:26:00 INFO - 'USER': 'cltbld', 11:26:00 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:00 INFO - '_': '/tools/buildbot/bin/python'} 11:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:26:00 INFO - Running setup.py (path:/tmp/pip-flbDD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:26:00 INFO - Running setup.py (path:/tmp/pip-7DP7RR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:26:00 INFO - Running setup.py (path:/tmp/pip-Caoeyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:26:00 INFO - Running setup.py (path:/tmp/pip-zC7Kw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:26:00 INFO - Running setup.py (path:/tmp/pip-LZGoYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:26:00 INFO - Running setup.py (path:/tmp/pip-liMm0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:26:00 INFO - Running setup.py (path:/tmp/pip-plGC0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:26:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:26:00 INFO - Running setup.py (path:/tmp/pip-OU6va0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:26:01 INFO - Running setup.py (path:/tmp/pip-Zr1YNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:26:01 INFO - Running setup.py (path:/tmp/pip-6TP_ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:26:01 INFO - Running setup.py (path:/tmp/pip-DCijpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:26:01 INFO - Running setup.py (path:/tmp/pip-SdfRju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:26:01 INFO - Running setup.py (path:/tmp/pip-gHzpzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:26:01 INFO - Running setup.py (path:/tmp/pip-h0ujAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:01 INFO - Running setup.py (path:/tmp/pip-Vl9UMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:26:01 INFO - Running setup.py (path:/tmp/pip-umd3sH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:26:01 INFO - Running setup.py (path:/tmp/pip-lfFM4g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:26:01 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:26:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:02 INFO - Downloading blessings-1.6.tar.gz 11:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:26:02 INFO - Installing collected packages: blessings 11:26:02 INFO - Running setup.py install for blessings 11:26:02 INFO - Successfully installed blessings 11:26:02 INFO - Cleaning up... 11:26:03 INFO - Return code: 0 11:26:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:26:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:03 INFO - Reading from file tmpfile_stdout 11:26:03 INFO - Current package versions: 11:26:03 INFO - argparse == 1.2.1 11:26:03 INFO - blessings == 1.6 11:26:03 INFO - blobuploader == 1.2.4 11:26:03 INFO - docopt == 0.6.1 11:26:03 INFO - functools32 == 3.2.3-2 11:26:03 INFO - jsonschema == 2.5.1 11:26:03 INFO - manifestparser == 1.1 11:26:03 INFO - mozInstall == 1.13 11:26:03 INFO - mozcrash == 1.0 11:26:03 INFO - mozdebug == 0.1 11:26:03 INFO - mozdevice == 0.51 11:26:03 INFO - mozfile == 1.2 11:26:03 INFO - mozhttpd == 0.7 11:26:03 INFO - mozinfo == 0.10 11:26:03 INFO - mozleak == 0.1 11:26:03 INFO - mozlog == 3.5 11:26:03 INFO - moznetwork == 0.27 11:26:03 INFO - mozprocess == 0.25 11:26:03 INFO - mozprofile == 0.28 11:26:03 INFO - mozrunner == 6.13 11:26:03 INFO - mozscreenshot == 0.1 11:26:03 INFO - mozsystemmonitor == 0.3 11:26:03 INFO - moztest == 0.8 11:26:03 INFO - mozversion == 1.4 11:26:03 INFO - psutil == 3.1.1 11:26:03 INFO - requests == 1.2.3 11:26:03 INFO - wsgiref == 0.1.2 11:26:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:03 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 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:26:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:26:03 INFO - Using env: {'DISPLAY': ':0', 11:26:03 INFO - 'HOME': '/home/cltbld', 11:26:03 INFO - 'LANG': 'en_US.UTF-8', 11:26:03 INFO - 'LANGUAGE': 'en_US:en', 11:26:03 INFO - 'LOGNAME': 'cltbld', 11:26:03 INFO - 'MAIL': '/var/mail/cltbld', 11:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:03 INFO - 'MOZ_NO_REMOTE': '1', 11:26:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:03 INFO - 'NO_EM_RESTART': '1', 11:26:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:03 INFO - 'PWD': '/builds/slave/test', 11:26:03 INFO - 'SHELL': '/bin/bash', 11:26:03 INFO - 'SHLVL': '1', 11:26:03 INFO - 'TERM': 'linux', 11:26:03 INFO - 'TMOUT': '86400', 11:26:03 INFO - 'USER': 'cltbld', 11:26:03 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:03 INFO - '_': '/tools/buildbot/bin/python'} 11:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:26:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:26:03 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:26:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:26:04 INFO - Installing collected packages: simplejson 11:26:04 INFO - Running setup.py install for simplejson 11:26:04 INFO - building 'simplejson._speedups' extension 11:26:04 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:26:05 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:26:05 INFO - Successfully installed simplejson 11:26:05 INFO - Cleaning up... 11:26:05 INFO - Return code: 0 11:26:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:26:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c59a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfca60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, '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': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:26:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:26:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:26:05 INFO - Using env: {'DISPLAY': ':0', 11:26:05 INFO - 'HOME': '/home/cltbld', 11:26:05 INFO - 'LANG': 'en_US.UTF-8', 11:26:05 INFO - 'LANGUAGE': 'en_US:en', 11:26:05 INFO - 'LOGNAME': 'cltbld', 11:26:05 INFO - 'MAIL': '/var/mail/cltbld', 11:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:05 INFO - 'MOZ_NO_REMOTE': '1', 11:26:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:05 INFO - 'NO_EM_RESTART': '1', 11:26:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:05 INFO - 'PWD': '/builds/slave/test', 11:26:05 INFO - 'SHELL': '/bin/bash', 11:26:05 INFO - 'SHLVL': '1', 11:26:05 INFO - 'TERM': 'linux', 11:26:05 INFO - 'TMOUT': '86400', 11:26:05 INFO - 'USER': 'cltbld', 11:26:05 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:05 INFO - '_': '/tools/buildbot/bin/python'} 11:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:26:05 INFO - Cleaning up... 11:26:05 INFO - Return code: 0 11:26:05 INFO - Running post-action listener: _resource_record_post_action 11:26:05 INFO - Running post-action listener: _start_resource_monitoring 11:26:05 INFO - Starting resource monitoring. 11:26:05 INFO - [mozharness: 2017-09-05 18:26:05.915000Z] Finished create-virtualenv step (success) 11:26:05 INFO - [mozharness: 2017-09-05 18:26:05.915499Z] Running install step. 11:26:05 INFO - Running pre-action listener: _resource_record_pre_action 11:26:05 INFO - Running main action method: install 11:26:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:26:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:26:06 INFO - Reading from file tmpfile_stdout 11:26:06 INFO - Detecting whether we're running mozinstall >=1.0... 11:26:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:26:06 INFO - Reading from file tmpfile_stdout 11:26:06 INFO - Output received: 11:26:06 INFO - Usage: mozinstall [options] installer 11:26:06 INFO - Options: 11:26:06 INFO - -h, --help show this help message and exit 11:26:06 INFO - -d DEST, --destination=DEST 11:26:06 INFO - Directory to install application into. [default: 11:26:06 INFO - "/builds/slave/test"] 11:26:06 INFO - --app=APP Application being installed. [default: firefox] 11:26:06 INFO - mkdir: /builds/slave/test/build/application 11:26:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:26:22 INFO - Reading from file tmpfile_stdout 11:26:22 INFO - Output received: 11:26:22 INFO - /builds/slave/test/build/application/firefox/firefox 11:26:22 INFO - Running post-action listener: _resource_record_post_action 11:26:22 INFO - [mozharness: 2017-09-05 18:26:22.759316Z] Finished install step (success) 11:26:22 INFO - [mozharness: 2017-09-05 18:26:22.759566Z] Running setup-mitmproxy step. 11:26:22 INFO - Running pre-action listener: _resource_record_pre_action 11:26:22 INFO - Running main action method: setup_mitmproxy 11:26:22 INFO - Skipping: mitmproxy is not required 11:26:22 INFO - Running post-action listener: _resource_record_post_action 11:26:22 INFO - [mozharness: 2017-09-05 18:26:22.760668Z] Finished setup-mitmproxy step (success) 11:26:22 INFO - [mozharness: 2017-09-05 18:26:22.760883Z] Running run-tests step. 11:26:22 INFO - Running pre-action listener: _resource_record_pre_action 11:26:22 INFO - Running pre-action listener: _set_gcov_prefix 11:26:22 INFO - Running main action method: run_tests 11:26:22 WARNING - Try message not found. 11:26:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:26:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:26:22 INFO - Python 2.7.3 11:26:22 INFO - Return code: 0 11:26:22 INFO - grabbing minidump binary from tooltool 11:26: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')]} 11:26:22 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 0x1e00090>, '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 0x1e00d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dfaf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:26:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:26:22 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:26:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:26:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:26:22 INFO - Return code: 0 11:26:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:26:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:26:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:26:22 INFO - ENV: RUST_BACKTRACE is now full 11:26:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:26:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:26:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:26:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:26:22 INFO - Using env: {'DISPLAY': ':0', 11:26:22 INFO - 'HOME': '/home/cltbld', 11:26:22 INFO - 'LANG': 'en_US.UTF-8', 11:26:22 INFO - 'LANGUAGE': 'en_US:en', 11:26:22 INFO - 'LOGNAME': 'cltbld', 11:26:22 INFO - 'MAIL': '/var/mail/cltbld', 11:26:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:26:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:26:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:22 INFO - 'MOZ_NO_REMOTE': '1', 11:26:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:26:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:22 INFO - 'NO_EM_RESTART': '1', 11:26:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:22 INFO - 'PWD': '/builds/slave/test', 11:26:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:26:22 INFO - 'RUST_BACKTRACE': 'full', 11:26:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:26:22 INFO - 'SHELL': '/bin/bash', 11:26:22 INFO - 'SHLVL': '1', 11:26:22 INFO - 'TERM': 'linux', 11:26:22 INFO - 'TMOUT': '86400', 11:26:22 INFO - 'USER': 'cltbld', 11:26:22 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:26:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:22 INFO - '_': '/tools/buildbot/bin/python'} 11:26:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:26:23 INFO - mozversion application_buildid: 20170905173605 11:26:23 INFO - mozversion application_changeset: 973e8b890a62aee4b3170558ac3b608928162ef6 11:26:23 INFO - mozversion application_display_name: Nightly 11:26:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:26:23 INFO - mozversion application_name: Firefox 11:26:23 INFO - mozversion application_remotingname: firefox 11:26:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:26:23 INFO - mozversion application_vendor: Mozilla 11:26:23 INFO - mozversion application_version: 57.0a1 11:26:23 INFO - mozversion platform_buildid: 20170905173605 11:26:23 INFO - mozversion platform_changeset: 973e8b890a62aee4b3170558ac3b608928162ef6 11:26:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:26:23 INFO - mozversion platform_version: 57.0a1 11:26:23 INFO - using testdate: 1504635983 11:26:23 INFO - actual date: 1504635983 11:26:23 INFO - starting webserver on 'localhost:47427' 11:26:23 INFO - SUITE-START | Running 2 tests 11:26:23 INFO - TEST-START | dromaeo_css 11:26:23 INFO - Initialising browser for dromaeo_css test... 11:26:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47427/getInfo.html -profile /tmp/tmpqckKCV/profile 11:26:23 INFO - TEST-INFO | started process 4740 (/builds/slave/test/build/application/firefox/firefox http://localhost:47427/getInfo.html) 11:26:29 INFO - TEST-INFO | 4740: exit 0 11:26:29 INFO - Browser initialized. 11:26:29 INFO - Running cycle 1/1 for dromaeo_css test... 11:26:29 INFO - Using env: {'DISPLAY': ':0', 11:26:29 INFO - 'HOME': '/home/cltbld', 11:26:29 INFO - 'JSGC_DISABLE_POISONING': '1', 11:26:29 INFO - 'LANG': 'en_US.UTF-8', 11:26:29 INFO - 'LANGUAGE': 'en_US:en', 11:26:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:26:29 INFO - 'LOGNAME': 'cltbld', 11:26:29 INFO - 'MAIL': '/var/mail/cltbld', 11:26:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:26:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:26:29 INFO - 'MOZ_CRASHREPORTER': '1', 11:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:26:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:26:29 INFO - 'MOZ_NO_REMOTE': '1', 11:26:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:26:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:26:29 INFO - 'NO_EM_RESTART': '1', 11:26:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:26:29 INFO - 'PWD': '/builds/slave/test', 11:26:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:26:29 INFO - 'RUST_BACKTRACE': 'full', 11:26:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:26:29 INFO - 'SHELL': '/bin/bash', 11:26:29 INFO - 'SHLVL': '1', 11:26:29 INFO - 'TERM': 'linux', 11:26:29 INFO - 'TMOUT': '86400', 11:26:29 INFO - 'USER': 'cltbld', 11:26:29 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:26:29 INFO - '_': '/tools/buildbot/bin/python'} 11:26:29 INFO - TEST-INFO | started process 4929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqckKCV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:26:30 INFO - PID 4929 | 11:26:30 INFO - PID 4929 | (/builds/slave/test/build/application/firefox/firefox:4981): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:26:30 INFO - PID 4929 | 11:26:40 INFO - PID 4929 | RSS: Main: 167550976 11:26:40 INFO - PID 4929 | 11:29:39 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:47427/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47427/tests/dromaeo/cssquery-ext.html) 11:29:41 INFO - PID 4929 | RSS: Main: 172392448 11:29:41 INFO - PID 4929 | 11:32:42 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:47427/tests/dromaeo/cssquery-ext.html (next: http://localhost:47427/tests/dromaeo/cssquery-jquery.html) 11:32:43 INFO - PID 4929 | RSS: Main: 169152512 11:32:43 INFO - PID 4929 | 11:35:45 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:47427/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47427/tests/dromaeo/cssquery-mootools.html) 11:35:45 INFO - PID 4929 | RSS: Main: 171245568 11:35:45 INFO - PID 4929 | 11:38:47 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:47427/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47427/tests/dromaeo/cssquery-prototype.html) 11:38:48 INFO - PID 4929 | RSS: Main: 170434560 11:38:48 INFO - PID 4929 | 11:41:51 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:47427/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47427/tests/dromaeo/cssquery-yui.html) 11:41:52 INFO - PID 4929 | RSS: Main: 172949504 11:41:52 INFO - PID 4929 | 11:44:53 INFO - PID 4929 | Cycle 1(1): loaded http://localhost:47427/tests/dromaeo/cssquery-yui.html (next: http://localhost:47427/tests/dromaeo/cssquery-dojo.html) 11:44:54 INFO - PID 4929 | RSS: Main: 171642880 11:44:54 INFO - PID 4929 | 11:44:54 INFO - PID 4929 | __start_tp_report 11:44:54 INFO - PID 4929 | _x_x_mozilla_page_load 11:44:54 INFO - PID 4929 | _x_x_mozilla_page_load_details 11:44:54 INFO - PID 4929 | |i|pagename|runs| 11:44:54 INFO - PID 4929 | |0;dojo.html;3353.161709572607;3374.780639258449;3376.290978894432;3404.0979140527766;3421.982890085549;6241.812745617627;6243.56295059346;6254.43710066094;6314.621122732639;6346.682665866705;6305.653189074599;6452.58058226215;6466.224053113621;6472.158619379486;6509.251436084852;5895.9705201473935;5895.970520147404;5913.201717768089;5949.107633854935;6050.6369617823075;5960.761569537235;5981.521478281748;6044;6117.724702388381;6121.357257487966;1056.1274999878076;5846.853828654275;5986.910196347058;5991.221141251653;6085.756569737206;5846.532277417797;5865.002949498596;5926.081457374114;6019.668918209497;6038.728257228391;5845.5908086433965;5907.202527658797;5931.406859314077;5934.436228558323;5994;6412.80189657728;6482.189726284215;6482.416582507597;6517.837054073639;6569.0474881142445;6928.896066559006;6951.096357473496;6953.826154346182;7014.473914456436;7047.823804404879;6793.286704895964;6898.827529311757;6991.8951215731295;6993.510454268203;7086.9645651771925;6432.774852880127;6507.707153178071;6512.055751915993;6513.446357059703;6514.478841692653;7143.071400717978;7168.354848063699;7211.963940180265;7217.603031833194;7239.89140162898;6825.658717064127;6842.863142737117;6919.861602767916;6936.410405115521;6949.061876646689;6760.763373281911;6892.724291028403;6912.343327383891;6969.54697944632;7039;6968.059311992816;6974.476914231453;6986.510944233854;6988.860222795617;6995.965020174866;6907.965460172667;6983.8603227935155;6988.475864310197;6988.895166572505;6991.056207412036;5458.918116228259;5494.395616482184;5507.614466987353;5535;5539.861503462445;4705.035467729107;4756.7145971241835;4758.714477131383;4763.54746299101;4769.523047695203;5960;5976;5978.192943952511;6006.579539432285;6078.787242446581;7016.649167541603;7032.472564557678;7034.964825175944;7035.6482175891;7040.471964602675;5964.552658550626;5979.043353063489;6060.33336333003;6060.696965151725;6067.332593414809;5846.941530584725;5942.435468630473;5984;6007.128966299862;6061.515078793686;6698.062271281927;6777.288384719632;6819;6821.897671534931;6823.522353435212;6916.481263905328;6989.231184569795;7020.333068358498;7078.327558882104;7081.221065682874;6726.932730672631;6735.225449073419;6817.352351526597;6820.863582728417;6836.794896153124;6644.435223005905;6769.695363708744;6778.152730908637;6850.143732033496;6960.860782784417;6898.758543450955;6911.861762764817;6921.965390173218;6923.82690432723;6954.582725036514;6676.632785196861;6734.764283250062;6754.155730533684;6764.526483146133;6775.457963363017;6743.797686069426;6786.3892249697765;6838.042674025541;6853.040574319699;6855.040294358893;6692.531522793358;6706.966465167643;6768.86462270742;6792;6802;6578.80263592093;6732.259451460433;6739.427148692416;6811.250762416229;6832.385085342343;6432.453241474527;6443.903341449788;6453.09656648079;6458.063580780667;6459.644719540471 11:44:54 INFO - PID 4929 | |1;ext.html;774.9302562769353;841.2260720137475;870.2689740617882;878.9648414063438;1133;29960.10119696407;30729.07812765618;40412.393814092815;41178;41228.79385603075;42389.36415953763;42465.78767106168;42671.78664106683;42686.7865660672;42721.14557708844;7632.694692212305;7635.121960974476;7806.921930780704;7814.413918956073;7825.413094017943;7565.962170189141;7657;7697.846043079135;7700.075990881102;7700.614969251543;7591.278828511283;7619.085709714842;7667.923320766777;7672.501287416328;7675.808104797369;7593.126790419117;7646.808829779245;7674;7680.807979800494;7682.385409167253;7594.050743657043;7643.579603121826;7649.617519124022;7649.847003059907;7656.119546252196;36331.4550281746;36535.63464365376;36688.44967325492;36699.633003669886;36731.81634091812;3536.6109727929907;3574.6067932527403;3575.982120089409;3578.159132603836;3581.4090675038588;30072.69927300721;30272.697273027206;30333.090007299816;30376;30391.24021899448;4978.303037574742;5203.063448579254;5234.659747116425;5248.632595718302;5275.103232450492;181182;181269;181604;181876;182181.99999999866;47038.5296147041;47089;47150.5284947153;47207.527924721;47300.76349618299;48964.7551762246;49002;49059.75470122698;49130.75434622876;49185.754071229414;14345.784813227601;14928.626784330261;15068.472603458826;15123;15124.773128403083;11852.288862668267;13343.332833358294;13534.796978045337;13556.322183890963;13596.66008349799;13913;13954.441822327197;13976.161430314214;14046.85953140476;14111.50609728675;5597.384287728347;5775.884482310415;5797.971010144923;5815.476607105296;5976.103584462366;1009.7273736091214;1010.2069875147973;1018.3584341864726;1026.2867307221409;1026.3328836256494;22746.09015639389;23412.64881026786;28333.57499637507;28768.415849986868;29294.853525732236;1093.0654290581438;1103.972400689973;1105.1932089574655;1108.2238991326835;1112.5327362507767;12424.503019879103;12601.747965040831;12617.684557885943;12658.43037063316;12683.302418366893;233055;234000;234701;234940;235546;51316.486835131174;51379;51430.74284628628;51560;51710.74144629253;1441.5315022617692;1455.716135353596;1457.242234038294;1466.5013895275658;1469.162577330911;22832.65751013703;22881;23011.079556817538;23054.42363940915;23297.301080967598;36261.274774503836;36673.44989825208;36715.63284367122;36731;36754.81622591977;200948;202184;202509;202918;203210;32977.34045319128;35637.46543801897;36407.63592364149;36425.08937276589;36480;42847;43876;44054.11891762083;44092.33861492144;44561.77719111383;32461.675383245863;32507.53716082829;32667;32858.17854553655;33578.3284334307;32153;32271;32319.353612927138;32872.395067145786;33102.834485827414;37283.88148355554;37553;37557.62442375541;37562.24875502529;37572.62427375691;31617.20956976002;31837.20406989844;31921.521177181898;31967.200819979687;32060.358792823547;23783.643245350988;24114.156004539756;24200;24398.512029759662;25350.1127460538 11:44:54 INFO - PID 4929 | |2;jquery.html;559.3595333343325;561.2787567975153;569.7379205565439;576.472527637212;580.4920694392407;22089.337319880407;22808.315750527512;22839.42901427465;22851.771482285192;22910.312690619307;26060.34849128773;26676;26736.46527069462;26746.063887763932;26763.464730705375;6583.242927063401;6735.225449073346;6748.763793267237;6750.696218670147;6754.358335958076;6576.375244351779;6624.205095388536;6872.347127022925;7182.569045857239;7248.746293879715;7223.89164162538;7230.276972302754;7237.782866513987;7241.203467618558;7245.4928155029165;7224.891626625578;7232.457565682595;7238.059052323164;7239.963800180992;7240.493165478419;7065.43476521877;7069.8939515907305;7073.080499535079;7073.964630176816;7103.609301488416;31008.224794380094;31036.37927241465;32445.513317300258;32594.185145371317;32745.83627081873;2110.525131845338;2142.517933464958;2143.689165071072;2149.0544160569298;2149.5915776002666;20381.490462738402;20931.790682093135;20934.47663808402;20961.790382096136;20976.580468390548;2526.5199612073648;2544.75824796644;2614.7254538273587;2614.8692565371657;2616.7514086161973;140526;145596;147542;147777;148873.3007014687;19807.306744263882;20847.37457876266;21814.345569632937;21899.452513686967;22254.443638909157;22327.553448931256;23696.170634027727;23701.644475332538;23761.049558017832;44263.336049959275;10215.795684086277;10405.323653962469;10452.982568367075;10500.159987201005;10551.045707201996;9565.139137477661;9569.473678947725;9649.758756031155;9658.951705241569;9679.70960871175;9207.953960230156;9213.539323033821;9216.308776841764;9433;9475.620975160917;2632.842029478199;2696.8381897086238;2697.379602691392;2717.1576811188593;2748.7663548598193;944.5749412764284;975.9167324270054;977.8337682593834;993.4635296939716;1008.3143462445607;19327.63464522921;19947.401577952394;20697.586048279252;24227.273181804576;25416.618750718753;981.4700061966458;1031.6904928521415;1043.3791893823163;1050.7740835720203;1073.7530368015243;10593.523291451904;10792.136629069655;10904.073153782017;11140.554377824958;11347;475809;484415;487933;488595;490280;124433;125267;126686;128173.35913320561;129239.35380323227;1210.3464129370218;1232.5316379775627;1253.1854294708512;1263.298869127643;1266.9809952850715;17694.203760830536;17988.55028624295;18093.36673216431;18216.36242731498;18294.176762046005;55137;55451.72274138604;55531;56599.71700141638;57635.71182144062;75321.24678753142;75674;76508.23491765234;76714.6164269175;77677;14136;14585.854141458449;14593.197374144524;14621.853781462049;14622.926885365505;19477.220911163397;19753.80246197577;22984;23078.653820193045;23331.88334058319;18926.526836828638;19145.904270478557;19153.52116197106;19175.945323007367;19199.136038878005;16038.358465661242;16165.4342098026;16202.918985404998;16222.756658649894;16257.51227463178;19121.71317430212;20108.095135718635;20139;20222.79777202209;20320.79679203189;14384.712305754036;14925.477608283658;15157.393704251706;15161.09033457996;15165.469208578088;19101.522461938006;19449.027548622795;19627.70558441653;19628.705569416757;19635.11641976086 11:44:54 INFO - PID 4929 | |3;mootools.html;1356.945722171113;1405.1358414575038;1406.0860440713534;1422.4310275889643;1423.8718515333621;14782.26088695565;15388.615284617876;15406.537803865902;15410.537683869474;15428.228588570582;14886.851131488682;15881.205939702997;15882.205889705527;15922.363105475768;15926.362945482197;2759.8758055887433;2811.0442449567145;2833.957490637642;2834.886604535816;2845.3313471334322;2654.4558365535117;2703.1620197738666;2715.1990162901993;2746.6978632350424;2751.7385848344375;2737.8083534152615;2779.2634951737805;2793.064323451646;2796;2796.2310364649784;2675.919722408321;2688.314479807656;2693.892244310225;2694.5419278722666;2700.9459810803774;2627.973720262792;2642.3922497825415;2650.483155784623;2653.509100816336;2655.8406495610325;14648.853511464855;14981.475648352256;15004.624884377869;15006.324715387835;15013.324400402009;7959.880601790977;8223;8236.341092712568;8237.83524329516;8241.95879020601;5255.632105752599;5326.786928522854;5384.380796208447;5418.347404041766;5601.915971260434;1930.8938008409532;1941.2041063164036;1948.2271133268657;1965.9606807863906;1987.2349145578912;25674.358141046436;25906.96372145112;25924.61113083305;25936.481270374486;25939.74060259411;6726.596404215763;6898.103246577913;6901.482388820758;7068.5758854468895;7097.609631470215;16876.493705188863;16890.239939202766;16920.069396183328;16930.322787088626;17328.48014559565;7545.849083018418;7841.882371764428;7852.960735196288;7857.1749966253865;7867.567283799332;7198.100237470317;7225.060742103598;7257.092863392076;7344.596047217455;7372.373348265351;5218.139007063792;5383.973080134652;5387.3804512481565;5396.271503347077;5414.8917021659345;1527.5417374787519;1558.3299181352127;1560.3992462901638;1560.66445714172;1566.5535322433052;853.3216093205792;873.9956300218458;881.0396667632311;882.2280504558511;887.3123329419751;7124.109486314211;7234.927650723531;7237.203907570163;7263.164736055422;7297.416206703451;702.6346299924011;720.315700084911;720.3516834848679;723.3959643697466;732.0812380462486;4264.786760661955;4287.892802679958;4334.41485399467;4346;4392;20869;21511.13955441796;21523.67714484284;21680.674789878165;21743.782562174492;20947;21373.89313053425;21826.89086554557;21983.450413739785;22057.889710551666;11226.943865280622;11530.942345288388;11595.768084638428;11607.883921160848;11618.76762464746;7858.371330293677;8163.183681631789;8328.83342333162;8404;8445;17619.207135679182;17638.735418968983;17852.55368615795;18876.52808679739;18973.43079707611;17882.28470861151;18863.528411789815;18899.716504252174;19039.904800476463;19265.13306901221;12727.618171454871;13261.535846245335;13308.401121949342;13333.466661333438;13376.063675542619;13263.535776247785;13270.46918123303;13441.798373024218;13442.529511467052;13446.058775885595;10394.376337419779;10512.211584131373;10532.473376331;10613.363198208132;10654.520546575268;10615.097716694167;10648.84026739584;10686.519106640375;10874.293170943864;11048.889511104786;9294.860577091484;9451.338406311492;9458.668946586837;9460.526973651153;9471.289653275893;6822.215445223863;6862.279460656559;6885.931140688529;6902.758403456056;6908.48186386013;7067.469939754642;7068.6112263823925;7095.858082838417;7182.820429489305;7206.639668016683 11:44:54 INFO - PID 4929 | |4;prototype.html;493.0262731106065;496.3175633503932;524.8556646922099;529.5102030621674;535.2105644174843;6388.616682999023;6391.968040159799;6417.422431981121;6462.224533056028;6486.643234622094;5995.820125396245;6139.3907589274495;6228;6257.342978987198;6271.09069184969;5020.121478741224;5066.569341605968;5079.339685840845;5111.51440613143;5151.974240128794;4887.902241955158;5022.648414610979;5073.162928116856;5110.386753589574;5118.795248190068;5025.84922452327;5037.420696619899;5042.571381432583;5061.266116413136;5084.822031228908;4975.701457912519;5009.098362294803;5025.1708468102715;5052.368453943257;5054.494550544953;4484.394606728082;4567.474740404829;4567.657425693086;4571.542845715435;4593.678442509025;6257.843553911143;6303.8739225215695;6326.240851097852;6338.112664227012;6387.169667943138;5809.82570522885;5811;5813.674530187958;5836.795712150097;5847.836300568774;6565.934340656628;6604.933950660528;6663.433608143314;6674.699638516278;6717.496187785936;4891.608671306322;4916.647921821492;4916.655834091615;4917.188663870457;4936.975315123437;6969.895451568231;6994.755183568551;7017.754378596725;7103.147622285307;7132.857342853114;5002.74986250686;5034.144195486776;5053.1157047516535;5054;5077.898442031139;5109.616778741609;5118.564921981599;5119.564836988823;5128.564072053842;5137.460566640524;5779.971100144472;5804.332501762267;5809.331926828384;5826.388229235954;5857.707114644336;6637.502187335969;6646.8005959821285;6671.566348187353;6693.665316734144;6763.5941843489545;1662.5012496251077;1722.6985277576373;1726.1541844496107;1753.2636292756818;1775.3963652358261;1227.993860030712;1230.932298723561;1233.7285797124619;1240.2124650846777;1240.231056744824;5714.599978001583;5806.65160090396;5879.412058794086;5887.322957859816;5891.145783861316;6605.900911486331;6857.245702972669;6913.827154321081;6929.965350173317;6941.548799328028;5854.502367298827;5906.8818623627285;5908.3205431375945;5943.732532036069;5962.34414214436;3598.2803439312243;3598.676119149289;3638.6712503997464;3668.7248456365346;3682.760620559655;6687.76592819249;6701.363370479797;6702.798916032527;6707.5304728668525;6714.328567143344;6997.580145191203;7008.4769482986085;7089.0784198053925;7103.467239957024;7142.285771422816;6582;6594.406503414716;6621.735130594722;6659.6670166491485;6695.631740254235;6756.729730810713;6832.385085342343;6854.931450685429;6858.41703455192;6859.211190713132;6781.2879647638265;6795.912653975341;6852.211995620568;6857.382835544825;6866.519343646097;6596.90104648421;6615.239247486601;6645.613894829389;6649.800505984828;6651.833704157435;5540.556755459635;5557.833265001895;5603.971980140073;5627.774889004394;5644.3508996465935;6613.503987200883;6813.693383797642;6923.169219693669;6997.755078572225;7000.684969176297;6267.373262673696;6541.836454088686;6575.96712016456;6616.41959635752;6644.435223005905;6551.246606640107;6584.407403333531;6605.174353205849;6626.138601981712;6655.833604159741;6497.642629655415;6506.219253689587;6508.446782023581;6519.152510173647;6538.444232240313;6449.96775016122;6585.80242592723;6614.570052946543;6663.900041499478;6714.328567143442;6450.709718062604;6458.741650334122;6470.22357317125;6473.255575608865;6515.576487528485 11:44:54 INFO - PID 4929 | |5;yui.html;891.5230351761804;908.5094049213425;913.5569248914273;919.8068405634816;920.5489310237986;19048.523786905334;20083.698744518824;20160.39518814436;20169.596608067866;21044;20763.480912977182;21341.146354145814;21378.57242855142;21436.89281553594;21483.46291342717;975.1711045611227;992.1120597065621;995.1590905684685;995.9592226123718;996.0637001218868;935.0929598289682;979.148141117229;1007.7682133109389;1011.1859952738043;1012.3419777144841;1064.5209655654949;1064.930779499334;1066.2323127348297;1067.0876400677425;1067.1782727299974;1054.3726482742757;1054.947252637365;1055.398422898948;1056.1023130339227;1056.9207309451783;770.0874463760462;770.3452065744128;770.5415277909635;770.9344705700022;772.2972095393221;19454.416367509;19811.60376792456;19855.305064322823;20083.297084601967;20144.194232230693;21057;21095.894520527298;21466.24868129608;21482.677759833616;21590.676139857915;17325.7401138983;17334.39329623457;17500.737488937677;17515.299388024592;17731.202095905715;3708.796016219107;3730.8321125549414;3738.6635202831876;3739.1586892948867;3739.7569158004644;128669.35665321707;129136.35431822875;129371;131058;131299.34350328188;16151.676966460605;16241.675166496603;16386.26261818221;16410.83589164117;16732.24704888283;29429.11712648624;30186;30214.54678179829;30224.94212702545;30343.241418964702;7850.332721718718;8140.959295203486;8593.441426307396;8668.956655216683;8958.328125390623;4314.546972567898;4404.647628189737;4404.8458303959205;4417.977910110429;4429.557044295545;5274.7626356814035;5331.733413329318;5338.492843179892;5374.247605335257;5386.13821788512;1021.3667526133696;1043.1446214104362;1050.1965996013057;1060.596973150198;1064.5848119233506;665.55075324156;679.1815861886415;686.6120641837346;688.174190970837;696.6621188723465;11174.050205732441;11387.373694446886;11787;12938.482460701467;13251.867481325256;698.6192525073847;703.6059806508372;704.9365557099885;722.1226210154688;722.9060222171084;5301.098813201688;5980.611260268069;6067.480861100551;6129.846753831101;6136.386361363828;101950;104315.4784226074;104808.47595761972;108422.45788771006;109731;89684.5515772417;96090;101121.49439252757;103240;103408.31901546312;82408.58795706103;85414.5729271362;86621;88782;89710.55144724235;12342.197757145757;12433.626991190278;12437.564685235973;12437.813432798333;12445.813312800134;40660.39009414802;41079;41085.38371924364;41204.79397602993;42120.157596848505;52513.7374313126;52554.21168682396;53017;53155;53947.73026134844;17274.13629318554;17744.911275444058;17787.73318400251;17987;17998.460046198634;18930.148143333307;19402.61194776125;19471.318503852297;19618.50953726168;19709.70435443441;15674.294656740247;15710.764338534702;15726.842731572538;15759.921200393925;15768.76346854821;17900.55248618795;18023.2790688371;18031;18124.365647202285;18167.909160454114;17530;17573.560660983065;17639.735403968694;17687;17777.377791777224;11696.649100526998;11963.342016189194;12046.156769026084;12207.938960305497;12256.938715306367;12699.873001270238;12838.614841554769;12870.742585148431;12917.483300667867;13019.414126364525 11:44:54 INFO - PID 4929 | __end_tp_report 11:44:54 INFO - PID 4929 | __start_cc_report 11:44:54 INFO - PID 4929 | _x_x_mozilla_cycle_collect,353 11:44:54 INFO - PID 4929 | __end_cc_report 11:44:54 INFO - PID 4929 | __startTimestamp1504637094139__endTimestamp 11:44:54 INFO - PID 4929 | 11:44:54 INFO - PID 4929 | ------- Summary: start ------- 11:44:54 INFO - PID 4929 | Number of tests: 6 11:44:54 INFO - PID 4929 | 11:44:54 INFO - PID 4929 | [#0] dojo.html Cycles:165 Average:6361.44 Median:6676.63 stddev:860.74 (12.9%) stddev-sans-first:830.40 11:44:54 INFO - PID 4929 | Values: 3353.2 3374.8 3376.3 3404.1 3422.0 6241.8 6243.6 6254.4 6314.6 6346.7 6305.7 6452.6 6466.2 6472.2 6509.3 5896.0 5896.0 5913.2 5949.1 6050.6 5960.8 5981.5 6044.0 6117.7 6121.4 1056.1 5846.9 5986.9 5991.2 6085.8 5846.5 5865.0 5926.1 6019.7 6038.7 5845.6 5907.2 5931.4 5934.4 5994.0 6412.8 6482.2 6482.4 6517.8 6569.0 6928.9 6951.1 6953.8 7014.5 7047.8 6793.3 6898.8 6991.9 6993.5 7087.0 6432.8 6507.7 6512.1 6513.4 6514.5 7143.1 7168.4 7212.0 7217.6 7239.9 6825.7 6842.9 6919.9 6936.4 6949.1 6760.8 6892.7 6912.3 6969.5 7039.0 6968.1 6974.5 6986.5 6988.9 6996.0 6908.0 6983.9 6988.5 6988.9 6991.1 5458.9 5494.4 5507.6 5535.0 5539.9 4705.0 4756.7 4758.7 4763.5 4769.5 5960.0 5976.0 5978.2 6006.6 6078.8 7016.6 7032.5 7035.0 7035.6 7040.5 5964.6 5979.0 6060.3 6060.7 6067.3 5846.9 5942.4 5984.0 6007.1 6061.5 6698.1 6777.3 6819.0 6821.9 6823.5 6916.5 6989.2 7020.3 7078.3 7081.2 6726.9 6735.2 6817.4 6820.9 6836.8 6644.4 6769.7 6778.2 6850.1 6960.9 6898.8 6911.9 6922.0 6923.8 6954.6 6676.6 6734.8 6754.2 6764.5 6775.5 6743.8 6786.4 6838.0 6853.0 6855.0 6692.5 6707.0 6768.9 6792.0 6802.0 6578.8 6732.3 6739.4 6811.3 6832.4 6432.5 6443.9 6453.1 6458.1 6459.6 11:44:54 INFO - PID 4929 | 11:44:54 INFO - PID 4929 | [#1] ext.html Cycles:180 Average:37475.39 Median:19606.04 stddev:53670.60 (273.7%) stddev-sans-first:53750.42 11:44:54 INFO - PID 4929 | Values: 774.9 841.2 870.3 879.0 1133.0 29960.1 30729.1 40412.4 41178.0 41228.8 42389.4 42465.8 42671.8 42686.8 42721.1 7632.7 7635.1 7806.9 7814.4 7825.4 7566.0 7657.0 7697.8 7700.1 7700.6 7591.3 7619.1 7667.9 7672.5 7675.8 7593.1 7646.8 7674.0 7680.8 7682.4 7594.1 7643.6 7649.6 7649.8 7656.1 36331.5 36535.6 36688.4 36699.6 36731.8 3536.6 3574.6 3576.0 3578.2 3581.4 30072.7 30272.7 30333.1 30376.0 30391.2 4978.3 5203.1 5234.7 5248.6 5275.1 181182.0 181269.0 181604.0 181876.0 182182.0 47038.5 47089.0 47150.5 47207.5 47300.8 48964.8 49002.0 49059.8 49130.8 49185.8 14345.8 14928.6 15068.5 15123.0 15124.8 11852.3 13343.3 13534.8 13556.3 13596.7 13913.0 13954.4 13976.2 14046.9 14111.5 5597.4 5775.9 5798.0 5815.5 5976.1 1009.7 1010.2 1018.4 1026.3 1026.3 22746.1 23412.6 28333.6 28768.4 29294.9 1093.1 1104.0 1105.2 1108.2 1112.5 12424.5 12601.7 12617.7 12658.4 12683.3 233055.0 234000.0 234701.0 234940.0 235546.0 51316.5 51379.0 51430.7 51560.0 51710.7 1441.5 1455.7 1457.2 1466.5 1469.2 22832.7 22881.0 23011.1 23054.4 23297.3 36261.3 36673.4 36715.6 36731.0 36754.8 200948.0 202184.0 202509.0 202918.0 203210.0 32977.3 35637.5 36407.6 36425.1 36480.0 42847.0 43876.0 44054.1 44092.3 44561.8 32461.7 32507.5 32667.0 32858.2 33578.3 32153.0 32271.0 32319.4 32872.4 33102.8 37283.9 37553.0 37557.6 37562.2 37572.6 31617.2 31837.2 31921.5 31967.2 32060.4 23783.6 24114.2 24200.0 24398.5 25350.1 11:44:54 INFO - PID 4929 | 11:44:54 INFO - PID 4929 | [#2] jquery.html Cycles:180 Average:35953.56 Median:22947.16 stddev:82434.16 (359.2%) stddev-sans-first:82622.57 11:44:54 INFO - PID 4929 | Values: 559.4 561.3 569.7 576.5 580.5 22089.3 22808.3 22839.4 22851.8 22910.3 26060.3 26676.0 26736.5 26746.1 26763.5 6583.2 6735.2 6748.8 6750.7 6754.4 6576.4 6624.2 6872.3 7182.6 7248.7 7223.9 7230.3 7237.8 7241.2 7245.5 7224.9 7232.5 7238.1 7240.0 7240.5 7065.4 7069.9 7073.1 7074.0 7103.6 31008.2 31036.4 32445.5 32594.2 32745.8 2110.5 2142.5 2143.7 2149.1 2149.6 20381.5 20931.8 20934.5 20961.8 20976.6 2526.5 2544.8 2614.7 2614.9 2616.8 140526.0 145596.0 147542.0 147777.0 148873.3 19807.3 20847.4 21814.3 21899.5 22254.4 22327.6 23696.2 23701.6 23761.0 44263.3 10215.8 10405.3 10453.0 10500.2 10551.0 9565.1 9569.5 9649.8 9659.0 9679.7 9208.0 9213.5 9216.3 9433.0 9475.6 2632.8 2696.8 2697.4 2717.2 2748.8 944.6 975.9 977.8 993.5 1008.3 19327.6 19947.4 20697.6 24227.3 25416.6 981.5 1031.7 1043.4 1050.8 1073.8 10593.5 10792.1 10904.1 11140.6 11347.0 475809.0 484415.0 487933.0 488595.0 490280.0 124433.0 125267.0 126686.0 128173.4 129239.4 1210.3 1232.5 1253.2 1263.3 1267.0 17694.2 17988.6 18093.4 18216.4 18294.2 55137.0 55451.7 55531.0 56599.7 57635.7 75321.2 75674.0 76508.2 76714.6 77677.0 14136.0 14585.9 14593.2 14621.9 14622.9 19477.2 19753.8 22984.0 23078.7 23331.9 18926.5 19145.9 19153.5 19175.9 19199.1 16038.4 16165.4 16202.9 16222.8 16257.5 19121.7 20108.1 20139.0 20222.8 20320.8 14384.7 14925.5 15157.4 15161.1 15165.5 19101.5 19449.0 19627.7 19628.7 19635.1 11:44:54 INFO - PID 4929 | 11:44:54 INFO - PID 4929 | [#3] mootools.html Cycles:180 Average:9238.90 Median:2665.88 stddev:6632.63 (248.8%) stddev-sans-first:6624.80 11:44:54 INFO - PID 4929 | Values: 1356.9 1405.1 1406.1 1422.4 1423.9 14782.3 15388.6 15406.5 15410.5 15428.2 14886.9 15881.2 15882.2 15922.4 15926.4 2759.9 2811.0 2834.0 2834.9 2845.3 2654.5 2703.2 2715.2 2746.7 2751.7 2737.8 2779.3 2793.1 2796.0 2796.2 2675.9 2688.3 2693.9 2694.5 2700.9 2628.0 2642.4 2650.5 2653.5 2655.8 14648.9 14981.5 15004.6 15006.3 15013.3 7959.9 8223.0 8236.3 8237.8 8242.0 5255.6 5326.8 5384.4 5418.3 5601.9 1930.9 1941.2 1948.2 1966.0 1987.2 25674.4 25907.0 25924.6 25936.5 25939.7 6726.6 6898.1 6901.5 7068.6 7097.6 16876.5 16890.2 16920.1 16930.3 17328.5 7545.8 7841.9 7853.0 7857.2 7867.6 7198.1 7225.1 7257.1 7344.6 7372.4 5218.1 5384.0 5387.4 5396.3 5414.9 1527.5 1558.3 1560.4 1560.7 1566.6 853.3 874.0 881.0 882.2 887.3 7124.1 7234.9 7237.2 7263.2 7297.4 702.6 720.3 720.4 723.4 732.1 4264.8 4287.9 4334.4 4346.0 4392.0 20869.0 21511.1 21523.7 21680.7 21743.8 20947.0 21373.9 21826.9 21983.5 22057.9 11226.9 11530.9 11595.8 11607.9 11618.8 7858.4 8163.2 8328.8 8404.0 8445.0 17619.2 17638.7 17852.6 18876.5 18973.4 17882.3 18863.5 18899.7 19039.9 19265.1 12727.6 13261.5 13308.4 13333.5 13376.1 13263.5 13270.5 13441.8 13442.5 13446.1 10394.4 10512.2 10532.5 10613.4 10654.5 10615.1 10648.8 10686.5 10874.3 11048.9 9294.9 9451.3 9458.7 9460.5 9471.3 6822.2 6862.3 6885.9 6902.8 6908.5 7067.5 7068.6 7095.9 7182.8 7206.6 11:44:54 INFO - PID 4929 | 11:44:54 INFO - PID 4929 | [#4] prototype.html Cycles:180 Average:5621.90 Median:6269.23 stddev:1581.04 (25.2%) stddev-sans-first:1537.89 11:44:54 INFO - PID 4929 | Values: 493.0 496.3 524.9 529.5 535.2 6388.6 6392.0 6417.4 6462.2 6486.6 5995.8 6139.4 6228.0 6257.3 6271.1 5020.1 5066.6 5079.3 5111.5 5152.0 4887.9 5022.6 5073.2 5110.4 5118.8 5025.8 5037.4 5042.6 5061.3 5084.8 4975.7 5009.1 5025.2 5052.4 5054.5 4484.4 4567.5 4567.7 4571.5 4593.7 6257.8 6303.9 6326.2 6338.1 6387.2 5809.8 5811.0 5813.7 5836.8 5847.8 6565.9 6604.9 6663.4 6674.7 6717.5 4891.6 4916.6 4916.7 4917.2 4937.0 6969.9 6994.8 7017.8 7103.1 7132.9 5002.7 5034.1 5053.1 5054.0 5077.9 5109.6 5118.6 5119.6 5128.6 5137.5 5780.0 5804.3 5809.3 5826.4 5857.7 6637.5 6646.8 6671.6 6693.7 6763.6 1662.5 1722.7 1726.2 1753.3 1775.4 1228.0 1230.9 1233.7 1240.2 1240.2 5714.6 5806.7 5879.4 5887.3 5891.1 6605.9 6857.2 6913.8 6930.0 6941.5 5854.5 5906.9 5908.3 5943.7 5962.3 3598.3 3598.7 3638.7 3668.7 3682.8 6687.8 6701.4 6702.8 6707.5 6714.3 6997.6 7008.5 7089.1 7103.5 7142.3 6582.0 6594.4 6621.7 6659.7 6695.6 6756.7 6832.4 6854.9 6858.4 6859.2 6781.3 6795.9 6852.2 6857.4 6866.5 6596.9 6615.2 6645.6 6649.8 6651.8 5540.6 5557.8 5604.0 5627.8 5644.4 6613.5 6813.7 6923.2 6997.8 7000.7 6267.4 6541.8 6576.0 6616.4 6644.4 6551.2 6584.4 6605.2 6626.1 6655.8 6497.6 6506.2 6508.4 6519.2 6538.4 6450.0 6585.8 6614.6 6663.9 6714.3 6450.7 6458.7 6470.2 6473.3 6515.6 11:44:54 INFO - PID 4929 | 11:44:54 INFO - PID 4929 | [#5] yui.html Cycles:180 Average:23220.65 Median:20083.50 stddev:31718.64 (157.9%) stddev-sans-first:31763.30 11:44:54 INFO - PID 4929 | Values: 891.5 908.5 913.6 919.8 920.5 19048.5 20083.7 20160.4 20169.6 21044.0 20763.5 21341.1 21378.6 21436.9 21483.5 975.2 992.1 995.2 996.0 996.1 935.1 979.1 1007.8 1011.2 1012.3 1064.5 1064.9 1066.2 1067.1 1067.2 1054.4 1054.9 1055.4 1056.1 1056.9 770.1 770.3 770.5 770.9 772.3 19454.4 19811.6 19855.3 20083.3 20144.2 21057.0 21095.9 21466.2 21482.7 21590.7 17325.7 17334.4 17500.7 17515.3 17731.2 3708.8 3730.8 3738.7 3739.2 3739.8 128669.4 129136.4 129371.0 131058.0 131299.3 16151.7 16241.7 16386.3 16410.8 16732.2 29429.1 30186.0 30214.5 30224.9 30343.2 7850.3 8141.0 8593.4 8669.0 8958.3 4314.5 4404.6 4404.8 4418.0 4429.6 5274.8 5331.7 5338.5 5374.2 5386.1 1021.4 1043.1 1050.2 1060.6 1064.6 665.6 679.2 686.6 688.2 696.7 11174.1 11387.4 11787.0 12938.5 13251.9 698.6 703.6 704.9 722.1 722.9 5301.1 5980.6 6067.5 6129.8 6136.4 101950.0 104315.5 104808.5 108422.5 109731.0 89684.6 96090.0 101121.5 103240.0 103408.3 82408.6 85414.6 86621.0 88782.0 89710.6 12342.2 12433.6 12437.6 12437.8 12445.8 40660.4 41079.0 41085.4 41204.8 42120.2 52513.7 52554.2 53017.0 53155.0 53947.7 17274.1 17744.9 17787.7 17987.0 17998.5 18930.1 19402.6 19471.3 19618.5 19709.7 15674.3 15710.8 15726.8 15759.9 15768.8 17900.6 18023.3 18031.0 18124.4 18167.9 17530.0 17573.6 17639.7 17687.0 17777.4 11696.6 11963.3 12046.2 12207.9 12256.9 12699.9 12838.6 12870.7 12917.5 13019.4 11:44:54 INFO - PID 4929 | -------- Summary: end -------- 11:44:54 INFO - PID 4929 | 11:44:54 INFO - TEST-INFO | 4929: exit 0 11:44:54 INFO - TEST-OK | dromaeo_css | took 1111719ms 11:44:54 INFO - TEST-START | kraken 11:44:54 INFO - Initialising browser for kraken test... 11:44:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47427/getInfo.html -profile /tmp/tmp9YS4OT/profile 11:44:54 INFO - TEST-INFO | started process 5308 (/builds/slave/test/build/application/firefox/firefox http://localhost:47427/getInfo.html) 11:45:00 INFO - TEST-INFO | 5308: exit 0 11:45:00 INFO - Browser initialized. 11:45:00 INFO - Running cycle 1/1 for kraken test... 11:45:00 INFO - Using env: {'DISPLAY': ':0', 11:45:00 INFO - 'HOME': '/home/cltbld', 11:45:00 INFO - 'JSGC_DISABLE_POISONING': '1', 11:45:00 INFO - 'LANG': 'en_US.UTF-8', 11:45:00 INFO - 'LANGUAGE': 'en_US:en', 11:45:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 11:45:00 INFO - 'LOGNAME': 'cltbld', 11:45:00 INFO - 'MAIL': '/var/mail/cltbld', 11:45:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:45:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:45:00 INFO - 'MOZ_CRASHREPORTER': '1', 11:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 11:45:00 INFO - 'MOZ_NO_REMOTE': '1', 11:45:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:45:00 INFO - 'NO_EM_RESTART': '1', 11:45:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:00 INFO - 'PWD': '/builds/slave/test', 11:45:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:45:00 INFO - 'RUST_BACKTRACE': 'full', 11:45:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:45:00 INFO - 'SHELL': '/bin/bash', 11:45:00 INFO - 'SHLVL': '1', 11:45:00 INFO - 'TERM': 'linux', 11:45:00 INFO - 'TMOUT': '86400', 11:45:00 INFO - 'USER': 'cltbld', 11:45:00 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323', 11:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:00 INFO - '_': '/tools/buildbot/bin/python'} 11:45:00 INFO - TEST-INFO | started process 5492 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9YS4OT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:01 INFO - PID 5492 | 11:45:01 INFO - PID 5492 | (/builds/slave/test/build/application/firefox/firefox:5545): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:45:01 INFO - PID 5492 | 11:45:11 INFO - PID 5492 | RSS: Main: 169938944 11:45:11 INFO - PID 5492 | 11:45:14 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47427/tests/kraken/driver.html?testName=audio-beat-detection) 11:45:15 INFO - PID 5492 | RSS: Main: 176103424 11:45:15 INFO - PID 5492 | 11:45:17 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47427/tests/kraken/driver.html?testName=audio-dft) 11:45:18 INFO - PID 5492 | RSS: Main: 180195328 11:45:18 INFO - PID 5492 | 11:45:21 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47427/tests/kraken/driver.html?testName=audio-fft) 11:45:22 INFO - PID 5492 | RSS: Main: 173715456 11:45:22 INFO - PID 5492 | 11:45:24 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47427/tests/kraken/driver.html?testName=audio-oscillator) 11:45:24 INFO - PID 5492 | RSS: Main: 173899776 11:45:24 INFO - PID 5492 | 11:45:27 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47427/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:45:27 INFO - PID 5492 | RSS: Main: 174624768 11:45:27 INFO - PID 5492 | 11:45:30 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47427/tests/kraken/driver.html?testName=imaging-darkroom) 11:45:31 INFO - PID 5492 | RSS: Main: 175243264 11:45:31 INFO - PID 5492 | 11:45:34 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47427/tests/kraken/driver.html?testName=imaging-desaturate) 11:45:35 INFO - PID 5492 | RSS: Main: 175411200 11:45:35 INFO - PID 5492 | 11:45:38 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47427/tests/kraken/driver.html?testName=json-parse-financial) 11:45:38 INFO - PID 5492 | RSS: Main: 175927296 11:45:38 INFO - PID 5492 | 11:45:40 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47427/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:45:41 INFO - PID 5492 | RSS: Main: 176271360 11:45:41 INFO - PID 5492 | 11:45:42 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47427/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:45:43 INFO - PID 5492 | RSS: Main: 176132096 11:45:43 INFO - PID 5492 | 11:45:45 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47427/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:45:46 INFO - PID 5492 | RSS: Main: 173281280 11:45:46 INFO - PID 5492 | 11:45:48 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47427/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:45:49 INFO - PID 5492 | RSS: Main: 172888064 11:45:49 INFO - PID 5492 | 11:45:52 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47427/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:45:53 INFO - PID 5492 | RSS: Main: 173297664 11:45:53 INFO - PID 5492 | 11:45:54 INFO - PID 5492 | Cycle 1(1): loaded http://localhost:47427/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47427/tests/kraken/driver.html?testName=ai-astar) 11:45:55 INFO - PID 5492 | RSS: Main: 174993408 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | __start_tp_report 11:45:55 INFO - PID 5492 | _x_x_mozilla_page_load 11:45:55 INFO - PID 5492 | _x_x_mozilla_page_load_details 11:45:55 INFO - PID 5492 | |i|pagename|runs| 11:45:55 INFO - PID 5492 | |0;ai-astar;99;109;102;108;106;113;109;98;107;110 11:45:55 INFO - PID 5492 | |1;audio-beat-detection;154;121;154;177;118;149;150;121;166;115 11:45:55 INFO - PID 5492 | |2;audio-dft;163;161;162;163;162;163;162;168;165;163 11:45:55 INFO - PID 5492 | |3;audio-fft;74;74;74;172;76;91;76;73;148;75 11:45:55 INFO - PID 5492 | |4;audio-oscillator;79;79;131;80;80;79;141;80;80;130 11:45:55 INFO - PID 5492 | |5;imaging-gaussian-blur;115;115;114;114;114;114;113;114;113;114 11:45:55 INFO - PID 5492 | |6;imaging-darkroom;147;147;148;148;148;148;156;148;148;153 11:45:55 INFO - PID 5492 | |7;imaging-desaturate;94;95;94;94;93;94;94;94;93;93 11:45:55 INFO - PID 5492 | |8;json-parse-financial;53;55;55;53;52;52;52;52;52;54 11:45:55 INFO - PID 5492 | |9;json-stringify-tinderbox;38;38;39;39;38;38;39;38;39;38 11:45:55 INFO - PID 5492 | |10;stanford-crypto-aes;77;74;75;76;74;74;76;84;75;74 11:45:55 INFO - PID 5492 | |11;stanford-crypto-ccm;119;127;118;127;122;131;118;120;115;159 11:45:55 INFO - PID 5492 | |12;stanford-crypto-pbkdf2;171;168;170;170;172;171;167;171;168;169 11:45:55 INFO - PID 5492 | |13;stanford-crypto-sha256-iterative;75;63;65;64;65;64;65;64;64;64 11:45:55 INFO - PID 5492 | __end_tp_report 11:45:55 INFO - PID 5492 | __start_cc_report 11:45:55 INFO - PID 5492 | _x_x_mozilla_cycle_collect,917 11:45:55 INFO - PID 5492 | __end_cc_report 11:45:55 INFO - PID 5492 | __startTimestamp1504637155103__endTimestamp 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | ------- Summary: start ------- 11:45:55 INFO - PID 5492 | Number of tests: 14 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#0] ai-astar Cycles:10 Average:106.10 Median:109.50 stddev:4.91 (4.5%) stddev-sans-first:4.48 11:45:55 INFO - PID 5492 | Values: 99.0 109.0 102.0 108.0 106.0 113.0 109.0 98.0 107.0 110.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#1] audio-beat-detection Cycles:10 Average:142.50 Median:152.00 stddev:22.08 (14.5%) stddev-sans-first:23.02 11:45:55 INFO - PID 5492 | Values: 154.0 121.0 154.0 177.0 118.0 149.0 150.0 121.0 166.0 115.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:1.99 (1.2%) stddev-sans-first:2.11 11:45:55 INFO - PID 5492 | Values: 163.0 161.0 162.0 163.0 162.0 163.0 162.0 168.0 165.0 163.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#3] audio-fft Cycles:10 Average:93.30 Median:74.50 stddev:35.98 (48.3%) stddev-sans-first:37.48 11:45:55 INFO - PID 5492 | Values: 74.0 74.0 74.0 172.0 76.0 91.0 76.0 73.0 148.0 75.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:79.50 stddev:26.45 (33.3%) stddev-sans-first:27.34 11:45:55 INFO - PID 5492 | Values: 79.0 79.0 131.0 80.0 80.0 79.0 141.0 80.0 80.0 130.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#5] imaging-gaussian-blur Cycles:10 Average:114.00 Median:114.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 11:45:55 INFO - PID 5492 | Values: 115.0 115.0 114.0 114.0 114.0 114.0 113.0 114.0 113.0 114.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#6] imaging-darkroom Cycles:10 Average:149.10 Median:148.00 stddev:2.96 (2.0%) stddev-sans-first:3.04 11:45:55 INFO - PID 5492 | Values: 147.0 147.0 148.0 148.0 148.0 148.0 156.0 148.0 148.0 153.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 11:45:55 INFO - PID 5492 | Values: 94.0 95.0 94.0 94.0 93.0 94.0 94.0 94.0 93.0 93.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:1.25 (2.4%) stddev-sans-first:1.32 11:45:55 INFO - PID 5492 | Values: 53.0 55.0 55.0 53.0 52.0 52.0 52.0 52.0 52.0 54.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.53 11:45:55 INFO - PID 5492 | Values: 38.0 38.0 39.0 39.0 38.0 38.0 39.0 38.0 39.0 38.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:75.50 stddev:3.03 (4.0%) stddev-sans-first:3.19 11:45:55 INFO - PID 5492 | Values: 77.0 74.0 75.0 76.0 74.0 74.0 76.0 84.0 75.0 74.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#11] stanford-crypto-ccm Cycles:10 Average:125.60 Median:124.50 stddev:12.76 (10.2%) stddev-sans-first:13.30 11:45:55 INFO - PID 5492 | Values: 119.0 127.0 118.0 127.0 122.0 131.0 118.0 120.0 115.0 159.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.50 stddev:1.64 (1.0%) stddev-sans-first:1.67 11:45:55 INFO - PID 5492 | Values: 171.0 168.0 170.0 170.0 172.0 171.0 167.0 171.0 168.0 169.0 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:64.50 stddev:3.47 (5.4%) stddev-sans-first:0.67 11:45:55 INFO - PID 5492 | Values: 75.0 63.0 65.0 64.0 65.0 64.0 65.0 64.0 64.0 64.0 11:45:55 INFO - PID 5492 | -------- Summary: end -------- 11:45:55 INFO - PID 5492 | 11:45:55 INFO - PID 5492 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 11:45:55 INFO - TEST-INFO | 5492: exit 0 11:45:55 INFO - TEST-OK | kraken | took 60943ms 11:45:55 INFO - SUITE-END | took 1172s 11:45:56 INFO - Completed test suite (00:19:33) 11:45:56 INFO - javascript benchmark 11:45:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8642.611116248092, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3353.161709572607, 3374.780639258449, 3376.290978894432, 3404.0979140527766, 3421.982890085549, 6241.812745617627, 6243.56295059346, 6254.43710066094, 6314.621122732639, 6346.682665866705, 6305.653189074599, 6452.58058226215, 6466.224053113621, 6472.158619379486, 6509.251436084852, 5895.9705201473935, 5895.970520147404, 5913.201717768089, 5949.107633854935, 6050.6369617823075, 5960.761569537235, 5981.521478281748, 6044.0, 6117.724702388381, 6121.357257487966, 1056.1274999878076, 5846.853828654275, 5986.910196347058, 5991.221141251653, 6085.756569737206, 5846.532277417797, 5865.002949498596, 5926.081457374114, 6019.668918209497, 6038.728257228391, 5845.5908086433965, 5907.202527658797, 5931.406859314077, 5934.436228558323, 5994.0, 6412.80189657728, 6482.189726284215, 6482.416582507597, 6517.837054073639, 6569.0474881142445, 6928.896066559006, 6951.096357473496, 6953.826154346182, 7014.473914456436, 7047.823804404879, 6793.286704895964, 6898.827529311757, 6991.8951215731295, 6993.510454268203, 7086.9645651771925, 6432.774852880127, 6507.707153178071, 6512.055751915993, 6513.446357059703, 6514.478841692653, 7143.071400717978, 7168.354848063699, 7211.963940180265, 7217.603031833194, 7239.89140162898, 6825.658717064127, 6842.863142737117, 6919.861602767916, 6936.410405115521, 6949.061876646689, 6760.763373281911, 6892.724291028403, 6912.343327383891, 6969.54697944632, 7039.0, 6968.059311992816, 6974.476914231453, 6986.510944233854, 6988.860222795617, 6995.965020174866, 6907.965460172667, 6983.8603227935155, 6988.475864310197, 6988.895166572505, 6991.056207412036, 5458.918116228259, 5494.395616482184, 5507.614466987353, 5535.0, 5539.861503462445, 4705.035467729107, 4756.7145971241835, 4758.714477131383, 4763.54746299101, 4769.523047695203, 5960.0, 5976.0, 5978.192943952511, 6006.579539432285, 6078.787242446581, 7016.649167541603, 7032.472564557678, 7034.964825175944, 7035.6482175891, 7040.471964602675, 5964.552658550626, 5979.043353063489, 6060.33336333003, 6060.696965151725, 6067.332593414809, 5846.941530584725, 5942.435468630473, 5984.0, 6007.128966299862, 6061.515078793686, 6698.062271281927, 6777.288384719632, 6819.0, 6821.897671534931, 6823.522353435212, 6916.481263905328, 6989.231184569795, 7020.333068358498, 7078.327558882104, 7081.221065682874, 6726.932730672631, 6735.225449073419, 6817.352351526597, 6820.863582728417, 6836.794896153124, 6644.435223005905, 6769.695363708744, 6778.152730908637, 6850.143732033496, 6960.860782784417, 6898.758543450955, 6911.861762764817, 6921.965390173218, 6923.82690432723, 6954.582725036514, 6676.632785196861, 6734.764283250062, 6754.155730533684, 6764.526483146133, 6775.457963363017, 6743.797686069426, 6786.3892249697765, 6838.042674025541, 6853.040574319699, 6855.040294358893, 6692.531522793358, 6706.966465167643, 6768.86462270742, 6792.0, 6802.0, 6578.80263592093, 6732.259451460433, 6739.427148692416, 6811.250762416229, 6832.385085342343, 6432.453241474527, 6443.903341449788, 6453.09656648079, 6458.063580780667, 6459.644719540471], "value": 6301.39693628989, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.9302562769353, 841.2260720137475, 870.2689740617882, 878.9648414063438, 1133.0, 29960.10119696407, 30729.07812765618, 40412.393814092815, 41178.0, 41228.79385603075, 42389.36415953763, 42465.78767106168, 42671.78664106683, 42686.7865660672, 42721.14557708844, 7632.694692212305, 7635.121960974476, 7806.921930780704, 7814.413918956073, 7825.413094017943, 7565.962170189141, 7657.0, 7697.846043079135, 7700.075990881102, 7700.614969251543, 7591.278828511283, 7619.085709714842, 7667.923320766777, 7672.501287416328, 7675.808104797369, 7593.126790419117, 7646.808829779245, 7674.0, 7680.807979800494, 7682.385409167253, 7594.050743657043, 7643.579603121826, 7649.617519124022, 7649.847003059907, 7656.119546252196, 36331.4550281746, 36535.63464365376, 36688.44967325492, 36699.633003669886, 36731.81634091812, 3536.6109727929907, 3574.6067932527403, 3575.982120089409, 3578.159132603836, 3581.4090675038588, 30072.69927300721, 30272.697273027206, 30333.090007299816, 30376.0, 30391.24021899448, 4978.303037574742, 5203.063448579254, 5234.659747116425, 5248.632595718302, 5275.103232450492, 181182.0, 181269.0, 181604.0, 181876.0, 182181.99999999866, 47038.5296147041, 47089.0, 47150.5284947153, 47207.527924721, 47300.76349618299, 48964.7551762246, 49002.0, 49059.75470122698, 49130.75434622876, 49185.754071229414, 14345.784813227601, 14928.626784330261, 15068.472603458826, 15123.0, 15124.773128403083, 11852.288862668267, 13343.332833358294, 13534.796978045337, 13556.322183890963, 13596.66008349799, 13913.0, 13954.441822327197, 13976.161430314214, 14046.85953140476, 14111.50609728675, 5597.384287728347, 5775.884482310415, 5797.971010144923, 5815.476607105296, 5976.103584462366, 1009.7273736091214, 1010.2069875147973, 1018.3584341864726, 1026.2867307221409, 1026.3328836256494, 22746.09015639389, 23412.64881026786, 28333.57499637507, 28768.415849986868, 29294.853525732236, 1093.0654290581438, 1103.972400689973, 1105.1932089574655, 1108.2238991326835, 1112.5327362507767, 12424.503019879103, 12601.747965040831, 12617.684557885943, 12658.43037063316, 12683.302418366893, 233055.0, 234000.0, 234701.0, 234940.0, 235546.0, 51316.486835131174, 51379.0, 51430.74284628628, 51560.0, 51710.74144629253, 1441.5315022617692, 1455.716135353596, 1457.242234038294, 1466.5013895275658, 1469.162577330911, 22832.65751013703, 22881.0, 23011.079556817538, 23054.42363940915, 23297.301080967598, 36261.274774503836, 36673.44989825208, 36715.63284367122, 36731.0, 36754.81622591977, 200948.0, 202184.0, 202509.0, 202918.0, 203210.0, 32977.34045319128, 35637.46543801897, 36407.63592364149, 36425.08937276589, 36480.0, 42847.0, 43876.0, 44054.11891762083, 44092.33861492144, 44561.77719111383, 32461.675383245863, 32507.53716082829, 32667.0, 32858.17854553655, 33578.3284334307, 32153.0, 32271.0, 32319.353612927138, 32872.395067145786, 33102.834485827414, 37283.88148355554, 37553.0, 37557.62442375541, 37562.24875502529, 37572.62427375691, 31617.20956976002, 31837.20406989844, 31921.521177181898, 31967.200819979687, 32060.358792823547, 23783.643245350988, 24114.156004539756, 24200.0, 24398.512029759662, 25350.1127460538], "value": 17168.64650942507, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.3595333343325, 561.2787567975153, 569.7379205565439, 576.472527637212, 580.4920694392407, 22089.337319880407, 22808.315750527512, 22839.42901427465, 22851.771482285192, 22910.312690619307, 26060.34849128773, 26676.0, 26736.46527069462, 26746.063887763932, 26763.464730705375, 6583.242927063401, 6735.225449073346, 6748.763793267237, 6750.696218670147, 6754.358335958076, 6576.375244351779, 6624.205095388536, 6872.347127022925, 7182.569045857239, 7248.746293879715, 7223.89164162538, 7230.276972302754, 7237.782866513987, 7241.203467618558, 7245.4928155029165, 7224.891626625578, 7232.457565682595, 7238.059052323164, 7239.963800180992, 7240.493165478419, 7065.43476521877, 7069.8939515907305, 7073.080499535079, 7073.964630176816, 7103.609301488416, 31008.224794380094, 31036.37927241465, 32445.513317300258, 32594.185145371317, 32745.83627081873, 2110.525131845338, 2142.517933464958, 2143.689165071072, 2149.0544160569298, 2149.5915776002666, 20381.490462738402, 20931.790682093135, 20934.47663808402, 20961.790382096136, 20976.580468390548, 2526.5199612073648, 2544.75824796644, 2614.7254538273587, 2614.8692565371657, 2616.7514086161973, 140526.0, 145596.0, 147542.0, 147777.0, 148873.3007014687, 19807.306744263882, 20847.37457876266, 21814.345569632937, 21899.452513686967, 22254.443638909157, 22327.553448931256, 23696.170634027727, 23701.644475332538, 23761.049558017832, 44263.336049959275, 10215.795684086277, 10405.323653962469, 10452.982568367075, 10500.159987201005, 10551.045707201996, 9565.139137477661, 9569.473678947725, 9649.758756031155, 9658.951705241569, 9679.70960871175, 9207.953960230156, 9213.539323033821, 9216.308776841764, 9433.0, 9475.620975160917, 2632.842029478199, 2696.8381897086238, 2697.379602691392, 2717.1576811188593, 2748.7663548598193, 944.5749412764284, 975.9167324270054, 977.8337682593834, 993.4635296939716, 1008.3143462445607, 19327.63464522921, 19947.401577952394, 20697.586048279252, 24227.273181804576, 25416.618750718753, 981.4700061966458, 1031.6904928521415, 1043.3791893823163, 1050.7740835720203, 1073.7530368015243, 10593.523291451904, 10792.136629069655, 10904.073153782017, 11140.554377824958, 11347.0, 475809.0, 484415.0, 487933.0, 488595.0, 490280.0, 124433.0, 125267.0, 126686.0, 128173.35913320561, 129239.35380323227, 1210.3464129370218, 1232.5316379775627, 1253.1854294708512, 1263.298869127643, 1266.9809952850715, 17694.203760830536, 17988.55028624295, 18093.36673216431, 18216.36242731498, 18294.176762046005, 55137.0, 55451.72274138604, 55531.0, 56599.71700141638, 57635.71182144062, 75321.24678753142, 75674.0, 76508.23491765234, 76714.6164269175, 77677.0, 14136.0, 14585.854141458449, 14593.197374144524, 14621.853781462049, 14622.926885365505, 19477.220911163397, 19753.80246197577, 22984.0, 23078.653820193045, 23331.88334058319, 18926.526836828638, 19145.904270478557, 19153.52116197106, 19175.945323007367, 19199.136038878005, 16038.358465661242, 16165.4342098026, 16202.918985404998, 16222.756658649894, 16257.51227463178, 19121.71317430212, 20108.095135718635, 20139.0, 20222.79777202209, 20320.79679203189, 14384.712305754036, 14925.477608283658, 15157.393704251706, 15161.09033457996, 15165.469208578088, 19101.522461938006, 19449.027548622795, 19627.70558441653, 19628.705569416757, 19635.11641976086], "value": 12579.984116000403, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.945722171113, 1405.1358414575038, 1406.0860440713534, 1422.4310275889643, 1423.8718515333621, 14782.26088695565, 15388.615284617876, 15406.537803865902, 15410.537683869474, 15428.228588570582, 14886.851131488682, 15881.205939702997, 15882.205889705527, 15922.363105475768, 15926.362945482197, 2759.8758055887433, 2811.0442449567145, 2833.957490637642, 2834.886604535816, 2845.3313471334322, 2654.4558365535117, 2703.1620197738666, 2715.1990162901993, 2746.6978632350424, 2751.7385848344375, 2737.8083534152615, 2779.2634951737805, 2793.064323451646, 2796.0, 2796.2310364649784, 2675.919722408321, 2688.314479807656, 2693.892244310225, 2694.5419278722666, 2700.9459810803774, 2627.973720262792, 2642.3922497825415, 2650.483155784623, 2653.509100816336, 2655.8406495610325, 14648.853511464855, 14981.475648352256, 15004.624884377869, 15006.324715387835, 15013.324400402009, 7959.880601790977, 8223.0, 8236.341092712568, 8237.83524329516, 8241.95879020601, 5255.632105752599, 5326.786928522854, 5384.380796208447, 5418.347404041766, 5601.915971260434, 1930.8938008409532, 1941.2041063164036, 1948.2271133268657, 1965.9606807863906, 1987.2349145578912, 25674.358141046436, 25906.96372145112, 25924.61113083305, 25936.481270374486, 25939.74060259411, 6726.596404215763, 6898.103246577913, 6901.482388820758, 7068.5758854468895, 7097.609631470215, 16876.493705188863, 16890.239939202766, 16920.069396183328, 16930.322787088626, 17328.48014559565, 7545.849083018418, 7841.882371764428, 7852.960735196288, 7857.1749966253865, 7867.567283799332, 7198.100237470317, 7225.060742103598, 7257.092863392076, 7344.596047217455, 7372.373348265351, 5218.139007063792, 5383.973080134652, 5387.3804512481565, 5396.271503347077, 5414.8917021659345, 1527.5417374787519, 1558.3299181352127, 1560.3992462901638, 1560.66445714172, 1566.5535322433052, 853.3216093205792, 873.9956300218458, 881.0396667632311, 882.2280504558511, 887.3123329419751, 7124.109486314211, 7234.927650723531, 7237.203907570163, 7263.164736055422, 7297.416206703451, 702.6346299924011, 720.315700084911, 720.3516834848679, 723.3959643697466, 732.0812380462486, 4264.786760661955, 4287.892802679958, 4334.41485399467, 4346.0, 4392.0, 20869.0, 21511.13955441796, 21523.67714484284, 21680.674789878165, 21743.782562174492, 20947.0, 21373.89313053425, 21826.89086554557, 21983.450413739785, 22057.889710551666, 11226.943865280622, 11530.942345288388, 11595.768084638428, 11607.883921160848, 11618.76762464746, 7858.371330293677, 8163.183681631789, 8328.83342333162, 8404.0, 8445.0, 17619.207135679182, 17638.735418968983, 17852.55368615795, 18876.52808679739, 18973.43079707611, 17882.28470861151, 18863.528411789815, 18899.716504252174, 19039.904800476463, 19265.13306901221, 12727.618171454871, 13261.535846245335, 13308.401121949342, 13333.466661333438, 13376.063675542619, 13263.535776247785, 13270.46918123303, 13441.798373024218, 13442.529511467052, 13446.058775885595, 10394.376337419779, 10512.211584131373, 10532.473376331, 10613.363198208132, 10654.520546575268, 10615.097716694167, 10648.84026739584, 10686.519106640375, 10874.293170943864, 11048.889511104786, 9294.860577091484, 9451.338406311492, 9458.668946586837, 9460.526973651153, 9471.289653275893, 6822.215445223863, 6862.279460656559, 6885.931140688529, 6902.758403456056, 6908.48186386013, 7067.469939754642, 7068.6112263823925, 7095.858082838417, 7182.820429489305, 7206.639668016683], "value": 6563.568708148073, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [493.0262731106065, 496.3175633503932, 524.8556646922099, 529.5102030621674, 535.2105644174843, 6388.616682999023, 6391.968040159799, 6417.422431981121, 6462.224533056028, 6486.643234622094, 5995.820125396245, 6139.3907589274495, 6228.0, 6257.342978987198, 6271.09069184969, 5020.121478741224, 5066.569341605968, 5079.339685840845, 5111.51440613143, 5151.974240128794, 4887.902241955158, 5022.648414610979, 5073.162928116856, 5110.386753589574, 5118.795248190068, 5025.84922452327, 5037.420696619899, 5042.571381432583, 5061.266116413136, 5084.822031228908, 4975.701457912519, 5009.098362294803, 5025.1708468102715, 5052.368453943257, 5054.494550544953, 4484.394606728082, 4567.474740404829, 4567.657425693086, 4571.542845715435, 4593.678442509025, 6257.843553911143, 6303.8739225215695, 6326.240851097852, 6338.112664227012, 6387.169667943138, 5809.82570522885, 5811.0, 5813.674530187958, 5836.795712150097, 5847.836300568774, 6565.934340656628, 6604.933950660528, 6663.433608143314, 6674.699638516278, 6717.496187785936, 4891.608671306322, 4916.647921821492, 4916.655834091615, 4917.188663870457, 4936.975315123437, 6969.895451568231, 6994.755183568551, 7017.754378596725, 7103.147622285307, 7132.857342853114, 5002.74986250686, 5034.144195486776, 5053.1157047516535, 5054.0, 5077.898442031139, 5109.616778741609, 5118.564921981599, 5119.564836988823, 5128.564072053842, 5137.460566640524, 5779.971100144472, 5804.332501762267, 5809.331926828384, 5826.388229235954, 5857.707114644336, 6637.502187335969, 6646.8005959821285, 6671.566348187353, 6693.665316734144, 6763.5941843489545, 1662.5012496251077, 1722.6985277576373, 1726.1541844496107, 1753.2636292756818, 1775.3963652358261, 1227.993860030712, 1230.932298723561, 1233.7285797124619, 1240.2124650846777, 1240.231056744824, 5714.599978001583, 5806.65160090396, 5879.412058794086, 5887.322957859816, 5891.145783861316, 6605.900911486331, 6857.245702972669, 6913.827154321081, 6929.965350173317, 6941.548799328028, 5854.502367298827, 5906.8818623627285, 5908.3205431375945, 5943.732532036069, 5962.34414214436, 3598.2803439312243, 3598.676119149289, 3638.6712503997464, 3668.7248456365346, 3682.760620559655, 6687.76592819249, 6701.363370479797, 6702.798916032527, 6707.5304728668525, 6714.328567143344, 6997.580145191203, 7008.4769482986085, 7089.0784198053925, 7103.467239957024, 7142.285771422816, 6582.0, 6594.406503414716, 6621.735130594722, 6659.6670166491485, 6695.631740254235, 6756.729730810713, 6832.385085342343, 6854.931450685429, 6858.41703455192, 6859.211190713132, 6781.2879647638265, 6795.912653975341, 6852.211995620568, 6857.382835544825, 6866.519343646097, 6596.90104648421, 6615.239247486601, 6645.613894829389, 6649.800505984828, 6651.833704157435, 5540.556755459635, 5557.833265001895, 5603.971980140073, 5627.774889004394, 5644.3508996465935, 6613.503987200883, 6813.693383797642, 6923.169219693669, 6997.755078572225, 7000.684969176297, 6267.373262673696, 6541.836454088686, 6575.96712016456, 6616.41959635752, 6644.435223005905, 6551.246606640107, 6584.407403333531, 6605.174353205849, 6626.138601981712, 6655.833604159741, 6497.642629655415, 6506.219253689587, 6508.446782023581, 6519.152510173647, 6538.444232240313, 6449.96775016122, 6585.80242592723, 6614.570052946543, 6663.900041499478, 6714.328567143442, 6450.709718062604, 6458.741650334122, 6470.22357317125, 6473.255575608865, 6515.576487528485], "value": 5151.6235195316385, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [891.5230351761804, 908.5094049213425, 913.5569248914273, 919.8068405634816, 920.5489310237986, 19048.523786905334, 20083.698744518824, 20160.39518814436, 20169.596608067866, 21044.0, 20763.480912977182, 21341.146354145814, 21378.57242855142, 21436.89281553594, 21483.46291342717, 975.1711045611227, 992.1120597065621, 995.1590905684685, 995.9592226123718, 996.0637001218868, 935.0929598289682, 979.148141117229, 1007.7682133109389, 1011.1859952738043, 1012.3419777144841, 1064.5209655654949, 1064.930779499334, 1066.2323127348297, 1067.0876400677425, 1067.1782727299974, 1054.3726482742757, 1054.947252637365, 1055.398422898948, 1056.1023130339227, 1056.9207309451783, 770.0874463760462, 770.3452065744128, 770.5415277909635, 770.9344705700022, 772.2972095393221, 19454.416367509, 19811.60376792456, 19855.305064322823, 20083.297084601967, 20144.194232230693, 21057.0, 21095.894520527298, 21466.24868129608, 21482.677759833616, 21590.676139857915, 17325.7401138983, 17334.39329623457, 17500.737488937677, 17515.299388024592, 17731.202095905715, 3708.796016219107, 3730.8321125549414, 3738.6635202831876, 3739.1586892948867, 3739.7569158004644, 128669.35665321707, 129136.35431822875, 129371.0, 131058.0, 131299.34350328188, 16151.676966460605, 16241.675166496603, 16386.26261818221, 16410.83589164117, 16732.24704888283, 29429.11712648624, 30186.0, 30214.54678179829, 30224.94212702545, 30343.241418964702, 7850.332721718718, 8140.959295203486, 8593.441426307396, 8668.956655216683, 8958.328125390623, 4314.546972567898, 4404.647628189737, 4404.8458303959205, 4417.977910110429, 4429.557044295545, 5274.7626356814035, 5331.733413329318, 5338.492843179892, 5374.247605335257, 5386.13821788512, 1021.3667526133696, 1043.1446214104362, 1050.1965996013057, 1060.596973150198, 1064.5848119233506, 665.55075324156, 679.1815861886415, 686.6120641837346, 688.174190970837, 696.6621188723465, 11174.050205732441, 11387.373694446886, 11787.0, 12938.482460701467, 13251.867481325256, 698.6192525073847, 703.6059806508372, 704.9365557099885, 722.1226210154688, 722.9060222171084, 5301.098813201688, 5980.611260268069, 6067.480861100551, 6129.846753831101, 6136.386361363828, 101950.0, 104315.4784226074, 104808.47595761972, 108422.45788771006, 109731.0, 89684.5515772417, 96090.0, 101121.49439252757, 103240.0, 103408.31901546312, 82408.58795706103, 85414.5729271362, 86621.0, 88782.0, 89710.55144724235, 12342.197757145757, 12433.626991190278, 12437.564685235973, 12437.813432798333, 12445.813312800134, 40660.39009414802, 41079.0, 41085.38371924364, 41204.79397602993, 42120.157596848505, 52513.7374313126, 52554.21168682396, 53017.0, 53155.0, 53947.73026134844, 17274.13629318554, 17744.911275444058, 17787.73318400251, 17987.0, 17998.460046198634, 18930.148143333307, 19402.61194776125, 19471.318503852297, 19618.50953726168, 19709.70435443441, 15674.294656740247, 15710.764338534702, 15726.842731572538, 15759.921200393925, 15768.76346854821, 17900.55248618795, 18023.2790688371, 18031.0, 18124.365647202285, 18167.909160454114, 17530.0, 17573.560660983065, 17639.735403968694, 17687.0, 17777.377791777224, 11696.649100526998, 11963.342016189194, 12046.156769026084, 12207.938960305497, 12256.938715306367, 12699.873001270238, 12838.614841554769, 12870.742585148431, 12917.483300667867, 13019.414126364525], "value": 9055.387278996695, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1485.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 109.0, 102.0, 108.0, 106.0, 113.0, 109.0, 98.0, 107.0, 110.0], "value": 106.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 121.0, 154.0, 177.0, 118.0, 149.0, 150.0, 121.0, 166.0, 115.0], "value": 142.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 161.0, 162.0, 163.0, 162.0, 163.0, 162.0, 168.0, 165.0, 163.0], "value": 163.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 172.0, 76.0, 91.0, 76.0, 73.0, 148.0, 75.0], "value": 93.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 131.0, 80.0, 80.0, 79.0, 141.0, 80.0, 80.0, 130.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 114.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 148.0, 148.0, 148.0, 156.0, 148.0, 148.0, 153.0], "value": 149.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 55.0, 53.0, 52.0, 52.0, 52.0, 52.0, 52.0, 54.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 39.0, 38.0, 38.0, 39.0, 38.0, 39.0, 38.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 75.0, 76.0, 74.0, 74.0, 76.0, 84.0, 75.0, 74.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 127.0, 118.0, 127.0, 122.0, 131.0, 118.0, 120.0, 115.0, 159.0], "value": 125.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 170.0, 170.0, 172.0, 171.0, 167.0, 171.0, 168.0, 169.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 63.0, 65.0, 64.0, 65.0, 64.0, 65.0, 64.0, 64.0, 64.0], "value": 65.3, "unit": "score"}]}]} 11:45:56 INFO - Return code: 0 11:45:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:45:56 INFO - # TBPL SUCCESS # 11:45:56 INFO - Running post-action listener: _package_coverage_data 11:45:56 INFO - Running post-action listener: _resource_record_post_action 11:45:56 INFO - [mozharness: 2017-09-05 18:45:56.400675Z] Finished run-tests step (success) 11:45:56 INFO - Running post-run listener: _resource_record_post_run 11:45:56 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 16384; Write bytes: 392810496; Read time: 148; Write time: 217276 11:45:56 INFO - TinderboxPrint: CPU usage
13.1% 11:45:56 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 148 11:45:56 INFO - TinderboxPrint: I/O write bytes / time
392,810,496 / 217,276 11:45:56 INFO - TinderboxPrint: CPU idle
8,243.5 (86.9%) 11:45:56 INFO - TinderboxPrint: CPU user
1,193.4 (12.6%) 11:45:56 INFO - TinderboxPrint: Swap in / out
0 / 0 11:45:56 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 106659840; Read time: 48; Write time: 59672 11:45:56 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:45:56 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 12288; Write bytes: 286150656; Read time: 100; Write time: 157604 11:45:57 INFO - Running post-run listener: _upload_blobber_files 11:45:57 INFO - Blob upload gear active. 11:45:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:45:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:45:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:45:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:45:57 INFO - (blobuploader) - INFO - Open directory for files ... 11:45:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:45:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:45:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:45:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:45:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:45:58 INFO - (blobuploader) - INFO - Done attempting. 11:45:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:45:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:45:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:45:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:45:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:45:59 INFO - (blobuploader) - INFO - Done attempting. 11:45:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:45:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:45:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:46:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:05 INFO - (blobuploader) - INFO - Done attempting. 11:46:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:46:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:46:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:07 INFO - (blobuploader) - INFO - Done attempting. 11:46:07 INFO - (blobuploader) - INFO - Iteration through files over. 11:46:07 INFO - Return code: 0 11:46:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:46:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:46:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4326df3cc2c340048d5af585641e327087c543324e290ec4cf6dd35ca6b6e6d8c781c171448418967fa975677d498368f5492a5832ffd512399e4dcf8d339fd", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0dd2e12ae962f6963f8f3a46a582f69aefd3406a479a5bd63f43c893c926d54ea96c1688ab87c82b433a5bbb8ae72f8d342e3a332ca3e5f4c818f101ab25fea", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7785e806c72a3bafd589646737bc99f385a74dea44c40473b01cab68da480da712cba0b4d8310830dae6cb4488e8c0db3f0638a0941dcd040331580df69c429a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c926b78689d0ada1a5a9fbfb013bd92cc278db4e1135e561bf90375609374879eecd11acbcc423dc3e68a72092e106041a089f47626ab9dfba416be45ee9aa2"} 11:46:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:46:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:46:07 INFO - Contents: 11:46:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4326df3cc2c340048d5af585641e327087c543324e290ec4cf6dd35ca6b6e6d8c781c171448418967fa975677d498368f5492a5832ffd512399e4dcf8d339fd", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0dd2e12ae962f6963f8f3a46a582f69aefd3406a479a5bd63f43c893c926d54ea96c1688ab87c82b433a5bbb8ae72f8d342e3a332ca3e5f4c818f101ab25fea", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7785e806c72a3bafd589646737bc99f385a74dea44c40473b01cab68da480da712cba0b4d8310830dae6cb4488e8c0db3f0638a0941dcd040331580df69c429a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c926b78689d0ada1a5a9fbfb013bd92cc278db4e1135e561bf90375609374879eecd11acbcc423dc3e68a72092e106041a089f47626ab9dfba416be45ee9aa2"} 11:46:07 INFO - Running post-run listener: copy_logs_to_upload_dir 11:46:07 INFO - Copying logs to upload dir... 11:46:07 INFO - mkdir: /builds/slave/test/build/upload/logs 11:46:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.439479 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-09-05 11:46:07.322655) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 11:46:07.329890) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323 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-Non-PGO/sha512/a4326df3cc2c340048d5af585641e327087c543324e290ec4cf6dd35ca6b6e6d8c781c171448418967fa975677d498368f5492a5832ffd512399e4dcf8d339fd", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0dd2e12ae962f6963f8f3a46a582f69aefd3406a479a5bd63f43c893c926d54ea96c1688ab87c82b433a5bbb8ae72f8d342e3a332ca3e5f4c818f101ab25fea", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7785e806c72a3bafd589646737bc99f385a74dea44c40473b01cab68da480da712cba0b4d8310830dae6cb4488e8c0db3f0638a0941dcd040331580df69c429a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c926b78689d0ada1a5a9fbfb013bd92cc278db4e1135e561bf90375609374879eecd11acbcc423dc3e68a72092e106041a089f47626ab9dfba416be45ee9aa2"} build_url:https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011600 build_url: 'https://queue.taskcluster.net/v1/task/TJbnw1eAT3m2u_5HiPuYfA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a4326df3cc2c340048d5af585641e327087c543324e290ec4cf6dd35ca6b6e6d8c781c171448418967fa975677d498368f5492a5832ffd512399e4dcf8d339fd", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e0dd2e12ae962f6963f8f3a46a582f69aefd3406a479a5bd63f43c893c926d54ea96c1688ab87c82b433a5bbb8ae72f8d342e3a332ca3e5f4c818f101ab25fea", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7785e806c72a3bafd589646737bc99f385a74dea44c40473b01cab68da480da712cba0b4d8310830dae6cb4488e8c0db3f0638a0941dcd040331580df69c429a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c926b78689d0ada1a5a9fbfb013bd92cc278db4e1135e561bf90375609374879eecd11acbcc423dc3e68a72092e106041a089f47626ab9dfba416be45ee9aa2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-05 11:46:07.370374) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:46:07.370713) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1504631582.920737-710115323 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004649 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-05 11:46:07.420287) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 11:46:07.420603) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-05 11:46:07.420891) ========= ========= Total master_lag: 0.20 =========