builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-055 starttime: 1504824985.16 results: success (0) revision: b4c1ad9565ee9d00d96501c4a83083daf25c1413 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.157716) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.158748) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.159258) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.177358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.177830) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-07 15:56:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 109M=0s 2017-09-07 15:56:25 (109 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165293 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.360641) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.360969) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015716 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.411454) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.411924) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b4c1ad9565ee9d00d96501c4a83083daf25c1413 --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 b4c1ad9565ee9d00d96501c4a83083daf25c1413 --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=bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-07 15:56:25,492 truncating revision to first 12 chars 2017-09-07 15:56:25,492 Setting DEBUG logging. 2017-09-07 15:56:25,492 attempt 1/10 2017-09-07 15:56:25,492 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b4c1ad9565ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-07 15:56:25,763 unpacking tar archive at: mozilla-central-b4c1ad9565ee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.554205 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.982842) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.983235) ========= script_repo_revision: b4c1ad9565ee9d00d96501c4a83083daf25c1413 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.983614) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.983935) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-07 15:56:25.997726) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-07 15:56:25.998164) ========= /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=bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:56:26 INFO - MultiFileLogger online at 20170907 15:56:26 in /builds/slave/test 15:56:26 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 15:56:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:56:26 INFO - {'append_to_log': False, 15:56:26 INFO - 'base_work_dir': '/builds/slave/test', 15:56:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:56:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:56:26 INFO - 'branch': 'Firefox-Non-PGO', 15:56:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:56:26 INFO - 'code_coverage': False, 15:56:26 INFO - 'config_files': ('talos/linux_config.py',), 15:56:26 INFO - 'default_actions': ('clobber', 15:56:26 INFO - 'read-buildbot-config', 15:56:26 INFO - 'download-and-extract', 15:56:26 INFO - 'populate-webroot', 15:56:26 INFO - 'create-virtualenv', 15:56:26 INFO - 'install', 15:56:26 INFO - 'setup-mitmproxy', 15:56:26 INFO - 'run-tests'), 15:56:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:56:26 INFO - 'disable_ccov_upload': False, 15:56:26 INFO - 'disable_stylo': False, 15:56:26 INFO - 'download_minidump_stackwalk': True, 15:56:26 INFO - 'download_symbols': 'ondemand', 15:56:26 INFO - 'enable_stylo': False, 15:56:26 INFO - 'enable_webrender': False, 15:56:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:56:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:56:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:56:26 INFO - 'gecko_profile': False, 15:56:26 INFO - 'gecko_profile_interval': 0, 15:56:26 INFO - 'installer_path': 'installer.exe', 15:56:26 INFO - 'log_level': 'info', 15:56:26 INFO - 'log_name': 'talos', 15:56:26 INFO - 'log_to_console': True, 15:56:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:56:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:56:26 INFO - 'opt_config_files': (), 15:56:26 INFO - 'pip_index': False, 15:56:26 INFO - 'suite': 'dromaeojs-e10s', 15:56:26 INFO - 'system_bits': '32', 15:56:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:56:26 INFO - 'title': 'talos-linux64-ix-055', 15:56:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:56:26 INFO - 'use_talos_json': True, 15:56:26 INFO - 'verify': 'False', 15:56:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:56:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:56:26 INFO - 'work_dir': 'build'} 15:56:26 INFO - [mozharness: 2017-09-07 22:56:26.211101Z] Running clobber step. 15:56:26 INFO - Running pre-action listener: _resource_record_pre_action 15:56:26 INFO - Running main action method: clobber 15:56:26 INFO - rmtree: /builds/slave/test/build 15:56:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:56:27 INFO - Running post-action listener: _resource_record_post_action 15:56:27 INFO - [mozharness: 2017-09-07 22:56:27.224632Z] Finished clobber step (success) 15:56:27 INFO - [mozharness: 2017-09-07 22:56:27.224729Z] Running read-buildbot-config step. 15:56:27 INFO - Running pre-action listener: _resource_record_pre_action 15:56:27 INFO - Running main action method: read_buildbot_config 15:56:27 INFO - Using buildbot properties: 15:56:27 INFO - { 15:56:27 INFO - "project": "", 15:56:27 INFO - "product": "firefox", 15:56:27 INFO - "who": "archaeopteryx@coole-files.de", 15:56:27 INFO - "installer_path": "public/build/target.tar.bz2", 15:56:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:56:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:56:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:56:27 INFO - "stage_platform": "linux64", 15:56:27 INFO - "basedir": "/builds/slave/test", 15:56:27 INFO - "buildnumber": 1124, 15:56:27 INFO - "platform": "ubuntu64_hw", 15:56:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:56:27 INFO - "slavebuilddir": "test", 15:56:27 INFO - "taskId": "QwZeInTnSGGhQqPRufX68w", 15:56:27 INFO - "branch": "mozilla-central", 15:56:27 INFO - "script_repo_revision": "production", 15:56:27 INFO - "revision": "b4c1ad9565ee9d00d96501c4a83083daf25c1413", 15:56:27 INFO - "slavename": "talos-linux64-ix-055", 15:56:27 INFO - "repo_path": "mozilla-central" 15:56:27 INFO - } 15:56:27 INFO - Finding installer, test and symbols from parent task. 15:56:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QwZeInTnSGGhQqPRufX68w'}, attempt #1 15:56:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QwZeInTnSGGhQqPRufX68w'}, attempt #1 15:56:28 INFO - Task dependencies: ZsNuXBl0Q92uv17nTmcSug 15:56:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug'}, attempt #1 15:56:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 15:56:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.test_packages.json 15:56:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.crashreporter-symbols.zip 15:56:28 INFO - Running post-action listener: _resource_record_post_action 15:56:28 INFO - [mozharness: 2017-09-07 22:56:28.565525Z] Finished read-buildbot-config step (success) 15:56:28 INFO - [mozharness: 2017-09-07 22:56:28.565744Z] Running download-and-extract step. 15:56:28 INFO - Running pre-action listener: _resource_record_pre_action 15:56:28 INFO - Running main action method: download_and_extract 15:56:28 INFO - mkdir: /builds/slave/test/build/tests 15:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:56:28 INFO - trying https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.test_packages.json 15:56:28 INFO - Downloading https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:56:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:56:29 INFO - Downloaded 989 bytes. 15:56:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:56:29 INFO - Using the following test package requirements: 15:56:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:56:29 INFO - u'common': [u'target.common.tests.zip'], 15:56:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:56:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:56:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:56:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:56:29 INFO - u'mozbase': [u'target.common.tests.zip'], 15:56:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:56:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:56:29 INFO - u'web-platform': [u'target.common.tests.zip', 15:56:29 INFO - u'target.web-platform.tests.tar.gz'], 15:56:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:56:29 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:56:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip 15:56:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:56:29 INFO - Fetch https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip into memory 15:56:30 INFO - Content-Length response header: 55023786 15:56:30 INFO - Bytes received: 55023786 15:56:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:56:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip 15:56:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:56:41 INFO - Fetch https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.common.tests.zip into memory 15:56:42 INFO - Content-Length response header: 55023786 15:56:42 INFO - Bytes received: 55023786 15:56:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.talos.tests.zip 15:56:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:56:54 INFO - Fetch https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.talos.tests.zip into memory 15:56:55 INFO - Content-Length response header: 14496665 15:56:55 INFO - Bytes received: 14496665 15:56:55 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')]} 15:56:55 INFO - trying https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 15:56:55 INFO - Downloading https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:56:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:56:56 INFO - Downloaded 62837949 bytes. 15:56:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 15:56:56 INFO - mkdir: /builds/slave/test/properties 15:56:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:56:56 INFO - Writing to file /builds/slave/test/properties/build_url 15:56:56 INFO - Contents: 15:56:56 INFO - build_url:https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 15:56:56 INFO - Running post-action listener: _resource_record_post_action 15:56:56 INFO - Running post-action listener: find_tests_for_verification 15:56:56 INFO - Running post-action listener: set_extra_try_arguments 15:56:56 INFO - [mozharness: 2017-09-07 22:56:56.860047Z] Finished download-and-extract step (success) 15:56:56 INFO - [mozharness: 2017-09-07 22:56:56.860200Z] Running populate-webroot step. 15:56:56 INFO - Running pre-action listener: _resource_record_pre_action 15:56:56 INFO - Running main action method: populate_webroot 15:56:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:56:56 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['tresize', 15:56:56 INFO - 'tcanvasmark']}, 15:56:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:56:56 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['dromaeo_css', 15:56:56 INFO - 'kraken']}, 15:56:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:56:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:56:56 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:56 INFO - 'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:56:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:56:56 INFO - 'tests': ['damp', 'tps']}, 15:56:56 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:56 INFO - 'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['damp', 'tps']}, 15:56:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:56:56 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['dromaeo_dom']}, 15:56:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:56:56 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['basic_compositor_video', 15:56:56 INFO - 'glvideo']}, 15:56:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:56:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:56:56 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:56 INFO - 'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['ts_paint_webext', 15:56:56 INFO - 'tp5o_webext']}, 15:56:56 INFO - 'other-e10s': {'tests': ['a11yr', 15:56:56 INFO - 'ts_paint', 15:56:56 INFO - 'tpaint', 15:56:56 INFO - 'sessionrestore', 15:56:56 INFO - 'sessionrestore_many_windows', 15:56:56 INFO - 'sessionrestore_no_auto_restore', 15:56:56 INFO - 'tabpaint']}, 15:56:56 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['a11yr', 15:56:56 INFO - 'ts_paint', 15:56:56 INFO - 'tpaint', 15:56:56 INFO - 'sessionrestore', 15:56:56 INFO - 'sessionrestore_many_windows', 15:56:56 INFO - 'sessionrestore_no_auto_restore', 15:56:56 INFO - 'tabpaint']}, 15:56:56 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:56:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:56:56 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['bloom_basic_singleton']}, 15:56:56 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['bloom_basic']}, 15:56:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:56:56 INFO - 'tsvgr_opacity', 15:56:56 INFO - 'tart', 15:56:56 INFO - 'tscrollx', 15:56:56 INFO - 'tsvg_static']}, 15:56:56 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['tsvgx', 15:56:56 INFO - 'tsvgr_opacity', 15:56:56 INFO - 'tart', 15:56:56 INFO - 'tscrollx', 15:56:56 INFO - 'tsvg_static']}, 15:56:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:56:56 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:56 INFO - 'talos_options': ['--disable-stylo'], 15:56:56 INFO - 'tests': ['tp5o']}, 15:56:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:56:56 INFO - 'talos_options': ['--mitmproxy', 15:56:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:56:56 INFO - '--firstNonBlankPaint'], 15:56:56 INFO - 'tests': ['tp6_google', 15:56:56 INFO - 'tp6_youtube', 15:56:56 INFO - 'tp6_amazon', 15:56:56 INFO - 'tp6_facebook']}, 15:56:56 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:56:56 INFO - 'talos_options': ['--disable-stylo', 15:56:56 INFO - '--mitmproxy', 15:56:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:56:56 INFO - '--firstNonBlankPaint'], 15:56:56 INFO - 'tests': ['tp6_google', 15:56:56 INFO - 'tp6_youtube', 15:56:56 INFO - 'tp6_amazon', 15:56:56 INFO - 'tp6_facebook']}, 15:56:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:56:56 INFO - 'talos_options': ['--stylo-threads=1', 15:56:56 INFO - '--mitmproxy', 15:56:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:56:56 INFO - '--firstNonBlankPaint'], 15:56:56 INFO - 'tests': ['tp6_google', 15:56:56 INFO - 'tp6_youtube', 15:56:56 INFO - 'tp6_amazon', 15:56:56 INFO - 'tp6_facebook']}, 15:56:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:56:56 INFO - 'talos_options': ['--xperf_path', 15:56:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:56:56 INFO - 'tests': ['tp5n']}, 15:56:56 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:56:56 INFO - 'talos_options': ['--disable-stylo', 15:56:56 INFO - '--xperf_path', 15:56:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:56:56 INFO - 'tests': ['tp5n']}}} 15:56:56 INFO - Running post-action listener: _resource_record_post_action 15:56:56 INFO - [mozharness: 2017-09-07 22:56:56.877777Z] Finished populate-webroot step (success) 15:56:56 INFO - [mozharness: 2017-09-07 22:56:56.877867Z] Running create-virtualenv step. 15:56:56 INFO - Running pre-action listener: _resource_record_pre_action 15:56:56 INFO - Running main action method: create_virtualenv 15:56:56 INFO - Creating virtualenv /builds/slave/test/build/venv 15:56:56 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:56:56 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:56:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:56:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:56:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:56:57 INFO - Installing distribute.............................................................................................................................................................................................done. 15:56:57 INFO - Installing pip...............done. 15:56:57 INFO - Return code: 0 15:56:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:56:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:56:57 INFO - Reading from file tmpfile_stdout 15:56:57 INFO - Output received: 15:56:57 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:56:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:56: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')]} 15:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56: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')]} 15:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:57 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 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:56:57 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 15:56:57 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 15:56:57 INFO - Using env: {'DISPLAY': ':0', 15:56:57 INFO - 'HOME': '/home/cltbld', 15:56:57 INFO - 'LANG': 'en_US.UTF-8', 15:56:57 INFO - 'LANGUAGE': 'en_US:en', 15:56:57 INFO - 'LOGNAME': 'cltbld', 15:56:57 INFO - 'MAIL': '/var/mail/cltbld', 15:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:57 INFO - 'MOZ_NO_REMOTE': '1', 15:56:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:57 INFO - 'NO_EM_RESTART': '1', 15:56:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:57 INFO - 'PWD': '/builds/slave/test', 15:56:57 INFO - 'SHELL': '/bin/bash', 15:56:57 INFO - 'SHLVL': '1', 15:56:57 INFO - 'TERM': 'linux', 15:56:57 INFO - 'TMOUT': '86400', 15:56:57 INFO - 'USER': 'cltbld', 15:56:57 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:57 INFO - '_': '/tools/buildbot/bin/python'} 15:56:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:56:59 INFO - Downloading/unpacking pip>=1.5 15:56:59 INFO - Running setup.py egg_info for package pip 15:56:59 INFO - warning: no files found matching 'pip/cacert.pem' 15:56:59 INFO - warning: no files found matching '*.html' under directory 'docs' 15:56:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:56:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:56:59 INFO - Installing collected packages: pip 15:56:59 INFO - Found existing installation: pip 1.1 15:56:59 INFO - Uninstalling pip: 15:56:59 INFO - Successfully uninstalled pip 15:56:59 INFO - Running setup.py install for pip 15:56:59 INFO - warning: no files found matching 'pip/cacert.pem' 15:56:59 INFO - warning: no files found matching '*.html' under directory 'docs' 15:56:59 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:56:59 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:56:59 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:56:59 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:56:59 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:56:59 INFO - Successfully installed pip 15:56:59 INFO - Cleaning up... 15:56:59 INFO - Return code: 0 15:56:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:56:59 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')]} 15:56:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:56:59 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')]} 15:56:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:56:59 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 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:56:59 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 15:56:59 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 15:56:59 INFO - Using env: {'DISPLAY': ':0', 15:56:59 INFO - 'HOME': '/home/cltbld', 15:56:59 INFO - 'LANG': 'en_US.UTF-8', 15:56:59 INFO - 'LANGUAGE': 'en_US:en', 15:56:59 INFO - 'LOGNAME': 'cltbld', 15:56:59 INFO - 'MAIL': '/var/mail/cltbld', 15:56:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:56:59 INFO - 'MOZ_NO_REMOTE': '1', 15:56:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:56:59 INFO - 'NO_EM_RESTART': '1', 15:56:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:56:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:56:59 INFO - 'PWD': '/builds/slave/test', 15:56:59 INFO - 'SHELL': '/bin/bash', 15:56:59 INFO - 'SHLVL': '1', 15:56:59 INFO - 'TERM': 'linux', 15:56:59 INFO - 'TMOUT': '86400', 15:56:59 INFO - 'USER': 'cltbld', 15:56:59 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:56:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:56:59 INFO - '_': '/tools/buildbot/bin/python'} 15:56:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:56:59 INFO - Downloading/unpacking psutil>=3.1.1 15:56:59 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 15:56:59 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 15:56:59 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 15:56:59 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 15:57:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:57:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:00 INFO - Installing collected packages: psutil 15:57:00 INFO - Running setup.py install for psutil 15:57:00 INFO - building 'psutil._psutil_linux' extension 15:57:00 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:57:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:57:00 INFO - building 'psutil._psutil_posix' extension 15:57:00 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 15:57:00 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:57:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:57:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:57:00 INFO - Successfully installed psutil 15:57:00 INFO - Cleaning up... 15:57:01 INFO - Return code: 0 15:57:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:57:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:57:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:57:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:57:01 INFO - Using env: {'DISPLAY': ':0', 15:57:01 INFO - 'HOME': '/home/cltbld', 15:57:01 INFO - 'LANG': 'en_US.UTF-8', 15:57:01 INFO - 'LANGUAGE': 'en_US:en', 15:57:01 INFO - 'LOGNAME': 'cltbld', 15:57:01 INFO - 'MAIL': '/var/mail/cltbld', 15:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:01 INFO - 'MOZ_NO_REMOTE': '1', 15:57:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:01 INFO - 'NO_EM_RESTART': '1', 15:57:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:01 INFO - 'PWD': '/builds/slave/test', 15:57:01 INFO - 'SHELL': '/bin/bash', 15:57:01 INFO - 'SHLVL': '1', 15:57:01 INFO - 'TERM': 'linux', 15:57:01 INFO - 'TMOUT': '86400', 15:57:01 INFO - 'USER': 'cltbld', 15:57:01 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:01 INFO - '_': '/tools/buildbot/bin/python'} 15:57:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:57: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 15:57: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 15:57: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 15:57: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 15:57:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:57:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:57:02 INFO - Installing collected packages: mozsystemmonitor 15:57:02 INFO - Running setup.py install for mozsystemmonitor 15:57:02 INFO - Successfully installed mozsystemmonitor 15:57:02 INFO - Cleaning up... 15:57:02 INFO - Return code: 0 15:57:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:57:02 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')]} 15:57:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:02 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')]} 15:57:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:02 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 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:57:02 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 15:57:02 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 15:57:02 INFO - Using env: {'DISPLAY': ':0', 15:57:02 INFO - 'HOME': '/home/cltbld', 15:57:02 INFO - 'LANG': 'en_US.UTF-8', 15:57:02 INFO - 'LANGUAGE': 'en_US:en', 15:57:02 INFO - 'LOGNAME': 'cltbld', 15:57:02 INFO - 'MAIL': '/var/mail/cltbld', 15:57:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:02 INFO - 'MOZ_NO_REMOTE': '1', 15:57:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:02 INFO - 'NO_EM_RESTART': '1', 15:57:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:02 INFO - 'PWD': '/builds/slave/test', 15:57:02 INFO - 'SHELL': '/bin/bash', 15:57:02 INFO - 'SHLVL': '1', 15:57:02 INFO - 'TERM': 'linux', 15:57:02 INFO - 'TMOUT': '86400', 15:57:02 INFO - 'USER': 'cltbld', 15:57:02 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:02 INFO - '_': '/tools/buildbot/bin/python'} 15:57:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:02 INFO - Downloading/unpacking jsonschema==2.5.1 15:57:02 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 15:57:02 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 15:57:02 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 15:57:02 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 15:57:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:57:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:57: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 15:57: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 15:57: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 15:57: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 15:57:03 INFO - Downloading functools32-3.2.3-2.tar.gz 15:57:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:57:04 INFO - warning: no files found matching '*.txt' 15:57:04 INFO - no previously-included directories found matching 'build' 15:57:04 INFO - no previously-included directories found matching 'dist' 15:57:04 INFO - no previously-included directories found matching '.git*' 15:57:04 INFO - Installing collected packages: jsonschema, functools32 15:57:04 INFO - Running setup.py install for functools32 15:57:04 INFO - warning: no files found matching '*.txt' 15:57:04 INFO - no previously-included directories found matching 'build' 15:57:04 INFO - no previously-included directories found matching 'dist' 15:57:04 INFO - no previously-included directories found matching '.git*' 15:57:04 INFO - Successfully installed jsonschema functools32 15:57:04 INFO - Cleaning up... 15:57:04 INFO - Return code: 0 15:57:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:57:04 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')]} 15:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:04 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')]} 15:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:04 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 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:57:04 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 15:57:04 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 15:57:04 INFO - Using env: {'DISPLAY': ':0', 15:57:04 INFO - 'HOME': '/home/cltbld', 15:57:04 INFO - 'LANG': 'en_US.UTF-8', 15:57:04 INFO - 'LANGUAGE': 'en_US:en', 15:57:04 INFO - 'LOGNAME': 'cltbld', 15:57:04 INFO - 'MAIL': '/var/mail/cltbld', 15:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:04 INFO - 'MOZ_NO_REMOTE': '1', 15:57:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:04 INFO - 'NO_EM_RESTART': '1', 15:57:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:04 INFO - 'PWD': '/builds/slave/test', 15:57:04 INFO - 'SHELL': '/bin/bash', 15:57:04 INFO - 'SHLVL': '1', 15:57:04 INFO - 'TERM': 'linux', 15:57:04 INFO - 'TMOUT': '86400', 15:57:04 INFO - 'USER': 'cltbld', 15:57:04 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:04 INFO - '_': '/tools/buildbot/bin/python'} 15:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:57:04 INFO - Cleaning up... 15:57:04 INFO - Return code: 0 15:57:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:57:04 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')]} 15:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:04 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')]} 15:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:04 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 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:57:04 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 15:57:04 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 15:57:04 INFO - Using env: {'DISPLAY': ':0', 15:57:04 INFO - 'HOME': '/home/cltbld', 15:57:04 INFO - 'LANG': 'en_US.UTF-8', 15:57:04 INFO - 'LANGUAGE': 'en_US:en', 15:57:04 INFO - 'LOGNAME': 'cltbld', 15:57:04 INFO - 'MAIL': '/var/mail/cltbld', 15:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:04 INFO - 'MOZ_NO_REMOTE': '1', 15:57:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:04 INFO - 'NO_EM_RESTART': '1', 15:57:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:04 INFO - 'PWD': '/builds/slave/test', 15:57:04 INFO - 'SHELL': '/bin/bash', 15:57:04 INFO - 'SHLVL': '1', 15:57:04 INFO - 'TERM': 'linux', 15:57:04 INFO - 'TMOUT': '86400', 15:57:04 INFO - 'USER': 'cltbld', 15:57:04 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:04 INFO - '_': '/tools/buildbot/bin/python'} 15:57:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:04 INFO - Downloading/unpacking blobuploader==1.2.4 15:57:04 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 15:57:04 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 15:57:04 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 15:57:04 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 15:57:05 INFO - Downloading blobuploader-1.2.4.tar.gz 15:57:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:57:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:57:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:06 INFO - Downloading docopt-0.6.1.tar.gz 15:57:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:57:06 INFO - Installing collected packages: blobuploader, requests, docopt 15:57:06 INFO - Running setup.py install for blobuploader 15:57:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:57:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:57:06 INFO - Running setup.py install for requests 15:57:06 INFO - Running setup.py install for docopt 15:57:06 INFO - Successfully installed blobuploader requests docopt 15:57:06 INFO - Cleaning up... 15:57:06 INFO - Return code: 0 15:57:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:57:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:57:06 INFO - Using env: {'DISPLAY': ':0', 15:57:06 INFO - 'HOME': '/home/cltbld', 15:57:06 INFO - 'LANG': 'en_US.UTF-8', 15:57:06 INFO - 'LANGUAGE': 'en_US:en', 15:57:06 INFO - 'LOGNAME': 'cltbld', 15:57:06 INFO - 'MAIL': '/var/mail/cltbld', 15:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:06 INFO - 'MOZ_NO_REMOTE': '1', 15:57:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:06 INFO - 'NO_EM_RESTART': '1', 15:57:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:06 INFO - 'PWD': '/builds/slave/test', 15:57:06 INFO - 'SHELL': '/bin/bash', 15:57:06 INFO - 'SHLVL': '1', 15:57:06 INFO - 'TERM': 'linux', 15:57:06 INFO - 'TMOUT': '86400', 15:57:06 INFO - 'USER': 'cltbld', 15:57:06 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:06 INFO - '_': '/tools/buildbot/bin/python'} 15:57:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:57:07 INFO - Running setup.py (path:/tmp/pip-xKjU1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:57:07 INFO - Running setup.py (path:/tmp/pip-7tj8nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:57:07 INFO - Running setup.py (path:/tmp/pip-LFpl6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:57:07 INFO - Running setup.py (path:/tmp/pip-EFfcW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:57:07 INFO - Running setup.py (path:/tmp/pip-mrzQNq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:57:07 INFO - Running setup.py (path:/tmp/pip-e2j_y1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:57:07 INFO - Running setup.py (path:/tmp/pip-RsITSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:57:07 INFO - Running setup.py (path:/tmp/pip-UBTpCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:57:07 INFO - Running setup.py (path:/tmp/pip-uIdX4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:57:07 INFO - Running setup.py (path:/tmp/pip-gRsfTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:57:07 INFO - Running setup.py (path:/tmp/pip-UXMEoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:57:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:57:07 INFO - Running setup.py (path:/tmp/pip-Lvgd7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:57:08 INFO - Running setup.py (path:/tmp/pip-oygD3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:57:08 INFO - Running setup.py (path:/tmp/pip-jxQBfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:57:08 INFO - Running setup.py (path:/tmp/pip-SxGmoK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:57:08 INFO - Running setup.py (path:/tmp/pip-IJ_jpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:57:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:57:08 INFO - Running setup.py (path:/tmp/pip-rizFwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:57:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:57:08 INFO - Running setup.py install for manifestparser 15:57:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:57:08 INFO - Running setup.py install for mozcrash 15:57:08 INFO - Running setup.py install for mozdebug 15:57:08 INFO - Running setup.py install for mozdevice 15:57:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:57:08 INFO - Running setup.py install for mozfile 15:57:08 INFO - Running setup.py install for mozhttpd 15:57:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:57:08 INFO - Running setup.py install for mozinfo 15:57:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Running setup.py install for mozInstall 15:57:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Running setup.py install for mozleak 15:57:09 INFO - Running setup.py install for mozlog 15:57:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Running setup.py install for moznetwork 15:57:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Running setup.py install for mozprocess 15:57:09 INFO - Running setup.py install for mozprofile 15:57:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Running setup.py install for mozrunner 15:57:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:57:09 INFO - Running setup.py install for mozscreenshot 15:57:09 INFO - Running setup.py install for moztest 15:57:10 INFO - Running setup.py install for mozversion 15:57:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:57:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:57:10 INFO - Cleaning up... 15:57:10 INFO - Return code: 0 15:57:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:10 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 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:57:10 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 15:57:10 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 15:57:10 INFO - Using env: {'DISPLAY': ':0', 15:57:10 INFO - 'HOME': '/home/cltbld', 15:57:10 INFO - 'LANG': 'en_US.UTF-8', 15:57:10 INFO - 'LANGUAGE': 'en_US:en', 15:57:10 INFO - 'LOGNAME': 'cltbld', 15:57:10 INFO - 'MAIL': '/var/mail/cltbld', 15:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:10 INFO - 'MOZ_NO_REMOTE': '1', 15:57:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:10 INFO - 'NO_EM_RESTART': '1', 15:57:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:10 INFO - 'PWD': '/builds/slave/test', 15:57:10 INFO - 'SHELL': '/bin/bash', 15:57:10 INFO - 'SHLVL': '1', 15:57:10 INFO - 'TERM': 'linux', 15:57:10 INFO - 'TMOUT': '86400', 15:57:10 INFO - 'USER': 'cltbld', 15:57:10 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:10 INFO - '_': '/tools/buildbot/bin/python'} 15:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:57:10 INFO - Running setup.py (path:/tmp/pip-GDjKgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:57:10 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)) 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:57:10 INFO - Running setup.py (path:/tmp/pip-OcAEiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:57:10 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)) 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:57:10 INFO - Running setup.py (path:/tmp/pip-W0VMDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:57:10 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)) 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:57:10 INFO - Running setup.py (path:/tmp/pip-MzgBW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:57:10 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)) 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:57:10 INFO - Running setup.py (path:/tmp/pip-6iFcDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:57:10 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)) 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:57:10 INFO - Running setup.py (path:/tmp/pip-i1sjqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:57:10 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)) 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:57:10 INFO - Running setup.py (path:/tmp/pip-uVigAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:57:10 INFO - Running setup.py (path:/tmp/pip-OCWi8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:57:10 INFO - Running setup.py (path:/tmp/pip-uVi90o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:57:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:57:10 INFO - Running setup.py (path:/tmp/pip-bBXQsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:57:11 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)) 15:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:57:11 INFO - Running setup.py (path:/tmp/pip-nkI9ym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:57:11 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)) 15:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:57:11 INFO - Running setup.py (path:/tmp/pip-IfTsky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:57:11 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)) 15:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:57:11 INFO - Running setup.py (path:/tmp/pip-Fg9m5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:57:11 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)) 15:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:57:11 INFO - Running setup.py (path:/tmp/pip-ZCHfns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:57:11 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)) 15:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:57:11 INFO - Running setup.py (path:/tmp/pip-B66RJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:57:11 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)) 15:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:57:11 INFO - Running setup.py (path:/tmp/pip-mtIP8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:57:11 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)) 15:57:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:57:11 INFO - Running setup.py (path:/tmp/pip-60s5sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:57:11 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)) 15:57:11 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)) 15:57:11 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)) 15:57:11 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)) 15:57:11 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)) 15:57:11 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)) 15:57:11 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)) 15:57:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:12 INFO - Downloading blessings-1.6.tar.gz 15:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:57:12 INFO - Installing collected packages: blessings 15:57:12 INFO - Running setup.py install for blessings 15:57:12 INFO - Successfully installed blessings 15:57:12 INFO - Cleaning up... 15:57:12 INFO - Return code: 0 15:57:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:57:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:57:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:57:13 INFO - Reading from file tmpfile_stdout 15:57:13 INFO - Current package versions: 15:57:13 INFO - argparse == 1.2.1 15:57:13 INFO - blessings == 1.6 15:57:13 INFO - blobuploader == 1.2.4 15:57:13 INFO - docopt == 0.6.1 15:57:13 INFO - functools32 == 3.2.3-2 15:57:13 INFO - jsonschema == 2.5.1 15:57:13 INFO - manifestparser == 1.1 15:57:13 INFO - mozInstall == 1.13 15:57:13 INFO - mozcrash == 1.0 15:57:13 INFO - mozdebug == 0.1 15:57:13 INFO - mozdevice == 0.51 15:57:13 INFO - mozfile == 1.2 15:57:13 INFO - mozhttpd == 0.7 15:57:13 INFO - mozinfo == 0.10 15:57:13 INFO - mozleak == 0.1 15:57:13 INFO - mozlog == 3.5 15:57:13 INFO - moznetwork == 0.27 15:57:13 INFO - mozprocess == 0.25 15:57:13 INFO - mozprofile == 0.28 15:57:13 INFO - mozrunner == 6.13 15:57:13 INFO - mozscreenshot == 0.1 15:57:13 INFO - mozsystemmonitor == 0.3 15:57:13 INFO - moztest == 0.8 15:57:13 INFO - mozversion == 1.4 15:57:13 INFO - psutil == 3.1.1 15:57:13 INFO - requests == 1.2.3 15:57:13 INFO - wsgiref == 0.1.2 15:57:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:57:13 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')]} 15:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:13 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')]} 15:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:13 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 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:57:13 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 15:57:13 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 15:57:13 INFO - Using env: {'DISPLAY': ':0', 15:57:13 INFO - 'HOME': '/home/cltbld', 15:57:13 INFO - 'LANG': 'en_US.UTF-8', 15:57:13 INFO - 'LANGUAGE': 'en_US:en', 15:57:13 INFO - 'LOGNAME': 'cltbld', 15:57:13 INFO - 'MAIL': '/var/mail/cltbld', 15:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:13 INFO - 'MOZ_NO_REMOTE': '1', 15:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:13 INFO - 'NO_EM_RESTART': '1', 15:57:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:13 INFO - 'PWD': '/builds/slave/test', 15:57:13 INFO - 'SHELL': '/bin/bash', 15:57:13 INFO - 'SHLVL': '1', 15:57:13 INFO - 'TERM': 'linux', 15:57:13 INFO - 'TMOUT': '86400', 15:57:13 INFO - 'USER': 'cltbld', 15:57:13 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:13 INFO - '_': '/tools/buildbot/bin/python'} 15:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:13 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)) 15:57:13 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)) 15:57:13 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)) 15:57:13 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)) 15:57:13 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)) 15:57:13 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)) 15:57:13 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)) 15:57:13 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)) 15:57:13 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)) 15:57:13 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:57:14 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)) 15:57:14 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)) 15:57:14 INFO - Installing collected packages: simplejson 15:57:14 INFO - Running setup.py install for simplejson 15:57:14 INFO - building 'simplejson._speedups' extension 15:57:14 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 15:57:15 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 15:57:15 INFO - Successfully installed simplejson 15:57:15 INFO - Cleaning up... 15:57:15 INFO - Return code: 0 15:57:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:57:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:57:15 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 0x102e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11f7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x139aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, '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': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', '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 15:57:15 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 15:57:15 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 15:57:15 INFO - Using env: {'DISPLAY': ':0', 15:57:15 INFO - 'HOME': '/home/cltbld', 15:57:15 INFO - 'LANG': 'en_US.UTF-8', 15:57:15 INFO - 'LANGUAGE': 'en_US:en', 15:57:15 INFO - 'LOGNAME': 'cltbld', 15:57:15 INFO - 'MAIL': '/var/mail/cltbld', 15:57:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:15 INFO - 'MOZ_NO_REMOTE': '1', 15:57:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:15 INFO - 'NO_EM_RESTART': '1', 15:57:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:15 INFO - 'PWD': '/builds/slave/test', 15:57:15 INFO - 'SHELL': '/bin/bash', 15:57:15 INFO - 'SHLVL': '1', 15:57:15 INFO - 'TERM': 'linux', 15:57:15 INFO - 'TMOUT': '86400', 15:57:15 INFO - 'USER': 'cltbld', 15:57:15 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:15 INFO - '_': '/tools/buildbot/bin/python'} 15:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:57:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:57:15 INFO - Cleaning up... 15:57:15 INFO - Return code: 0 15:57:15 INFO - Running post-action listener: _resource_record_post_action 15:57:15 INFO - Running post-action listener: _start_resource_monitoring 15:57:15 INFO - Starting resource monitoring. 15:57:15 INFO - [mozharness: 2017-09-07 22:57:15.829241Z] Finished create-virtualenv step (success) 15:57:15 INFO - [mozharness: 2017-09-07 22:57:15.829753Z] Running install step. 15:57:15 INFO - Running pre-action listener: _resource_record_pre_action 15:57:15 INFO - Running main action method: install 15:57:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:57:16 INFO - Reading from file tmpfile_stdout 15:57:16 INFO - Detecting whether we're running mozinstall >=1.0... 15:57:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:57:16 INFO - Reading from file tmpfile_stdout 15:57:16 INFO - Output received: 15:57:16 INFO - Usage: mozinstall [options] installer 15:57:16 INFO - Options: 15:57:16 INFO - -h, --help show this help message and exit 15:57:16 INFO - -d DEST, --destination=DEST 15:57:16 INFO - Directory to install application into. [default: 15:57:16 INFO - "/builds/slave/test"] 15:57:16 INFO - --app=APP Application being installed. [default: firefox] 15:57:16 INFO - mkdir: /builds/slave/test/build/application 15:57:16 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'] 15:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:57:32 INFO - Reading from file tmpfile_stdout 15:57:32 INFO - Output received: 15:57:32 INFO - /builds/slave/test/build/application/firefox/firefox 15:57:32 INFO - Running post-action listener: _resource_record_post_action 15:57:32 INFO - [mozharness: 2017-09-07 22:57:32.761858Z] Finished install step (success) 15:57:32 INFO - [mozharness: 2017-09-07 22:57:32.762107Z] Running setup-mitmproxy step. 15:57:32 INFO - Running pre-action listener: _resource_record_pre_action 15:57:32 INFO - Running main action method: setup_mitmproxy 15:57:32 INFO - Skipping: mitmproxy is not required 15:57:32 INFO - Running post-action listener: _resource_record_post_action 15:57:32 INFO - [mozharness: 2017-09-07 22:57:32.763303Z] Finished setup-mitmproxy step (success) 15:57:32 INFO - [mozharness: 2017-09-07 22:57:32.763521Z] Running run-tests step. 15:57:32 INFO - Running pre-action listener: _resource_record_pre_action 15:57:32 INFO - Running pre-action listener: _set_gcov_prefix 15:57:32 INFO - Running main action method: run_tests 15:57:32 WARNING - Try message not found. 15:57:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:57:32 INFO - Python 2.7.3 15:57:32 INFO - Return code: 0 15:57:32 INFO - grabbing minidump binary from tooltool 15:57:32 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')]} 15:57:32 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 0x139e090>, '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 0x139ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1398f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:57:32 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 15:57:32 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 15:57:32 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 15:57:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:57:32 INFO - Return code: 0 15:57:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:57:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:57:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:57:32 INFO - ENV: RUST_BACKTRACE is now full 15:57:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:57:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:57:32 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/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 15:57:32 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/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 15:57:32 INFO - Using env: {'DISPLAY': ':0', 15:57:32 INFO - 'HOME': '/home/cltbld', 15:57:32 INFO - 'LANG': 'en_US.UTF-8', 15:57:32 INFO - 'LANGUAGE': 'en_US:en', 15:57:32 INFO - 'LOGNAME': 'cltbld', 15:57:32 INFO - 'MAIL': '/var/mail/cltbld', 15:57:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:57:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:32 INFO - 'MOZ_NO_REMOTE': '1', 15:57:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:57:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:32 INFO - 'NO_EM_RESTART': '1', 15:57:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:32 INFO - 'PWD': '/builds/slave/test', 15:57:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:57:32 INFO - 'RUST_BACKTRACE': 'full', 15:57:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:57:32 INFO - 'SHELL': '/bin/bash', 15:57:32 INFO - 'SHLVL': '1', 15:57:32 INFO - 'TERM': 'linux', 15:57:32 INFO - 'TMOUT': '86400', 15:57:32 INFO - 'USER': 'cltbld', 15:57:32 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:32 INFO - '_': '/tools/buildbot/bin/python'} 15:57:32 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/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 15:57:33 INFO - mozversion application_buildid: 20170907220040 15:57:33 INFO - mozversion application_changeset: b4c1ad9565ee9d00d96501c4a83083daf25c1413 15:57:33 INFO - mozversion application_display_name: Nightly 15:57:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:57:33 INFO - mozversion application_name: Firefox 15:57:33 INFO - mozversion application_remotingname: firefox 15:57:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:57:33 INFO - mozversion application_vendor: Mozilla 15:57:33 INFO - mozversion application_version: 57.0a1 15:57:33 INFO - mozversion platform_buildid: 20170907220040 15:57:33 INFO - mozversion platform_changeset: b4c1ad9565ee9d00d96501c4a83083daf25c1413 15:57:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:57:33 INFO - mozversion platform_version: 57.0a1 15:57:33 INFO - using testdate: 1504825053 15:57:33 INFO - actual date: 1504825053 15:57:33 INFO - starting webserver on 'localhost:57160' 15:57:33 INFO - SUITE-START | Running 2 tests 15:57:33 INFO - TEST-START | dromaeo_css 15:57:33 INFO - Initialising browser for dromaeo_css test... 15:57:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57160/getInfo.html -profile /tmp/tmpIPLQgu/profile 15:57:33 INFO - TEST-INFO | started process 8411 (/builds/slave/test/build/application/firefox/firefox http://localhost:57160/getInfo.html) 15:57:38 INFO - TEST-INFO | 8411: exit 0 15:57:39 INFO - Browser initialized. 15:57:39 INFO - Running cycle 1/1 for dromaeo_css test... 15:57:39 INFO - Using env: {'DISPLAY': ':0', 15:57:39 INFO - 'HOME': '/home/cltbld', 15:57:39 INFO - 'JSGC_DISABLE_POISONING': '1', 15:57:39 INFO - 'LANG': 'en_US.UTF-8', 15:57:39 INFO - 'LANGUAGE': 'en_US:en', 15:57:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:57:39 INFO - 'LOGNAME': 'cltbld', 15:57:39 INFO - 'MAIL': '/var/mail/cltbld', 15:57:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:57:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:57:39 INFO - 'MOZ_CRASHREPORTER': '1', 15:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:57:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:57:39 INFO - 'MOZ_NO_REMOTE': '1', 15:57:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:57:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:57:39 INFO - 'NO_EM_RESTART': '1', 15:57:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:57:39 INFO - 'PWD': '/builds/slave/test', 15:57:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:57:39 INFO - 'RUST_BACKTRACE': 'full', 15:57:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:57:39 INFO - 'SHELL': '/bin/bash', 15:57:39 INFO - 'SHLVL': '1', 15:57:39 INFO - 'TERM': 'linux', 15:57:39 INFO - 'TMOUT': '86400', 15:57:39 INFO - 'USER': 'cltbld', 15:57:39 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 15:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:57:39 INFO - '_': '/tools/buildbot/bin/python'} 15:57:39 INFO - TEST-INFO | started process 8609 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIPLQgu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:57:40 INFO - PID 8609 | 15:57:40 INFO - PID 8609 | (/builds/slave/test/build/application/firefox/firefox:8660): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:57:40 INFO - PID 8609 | 15:57:50 INFO - PID 8609 | RSS: Main: 166449152 15:57:50 INFO - PID 8609 | 16:00:47 INFO - PID 8609 | Cycle 1(1): loaded http://localhost:57160/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57160/tests/dromaeo/cssquery-ext.html) 16:00:48 INFO - PID 8609 | RSS: Main: 173744128 16:00:48 INFO - PID 8609 | 16:03:49 INFO - PID 8609 | Cycle 1(1): loaded http://localhost:57160/tests/dromaeo/cssquery-ext.html (next: http://localhost:57160/tests/dromaeo/cssquery-jquery.html) 16:03:50 INFO - PID 8609 | RSS: Main: 170119168 16:03:50 INFO - PID 8609 | 16:06:52 INFO - PID 8609 | Cycle 1(1): loaded http://localhost:57160/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57160/tests/dromaeo/cssquery-mootools.html) 16:06:52 INFO - PID 8609 | RSS: Main: 171716608 16:06:52 INFO - PID 8609 | 16:09:54 INFO - PID 8609 | Cycle 1(1): loaded http://localhost:57160/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57160/tests/dromaeo/cssquery-prototype.html) 16:09:55 INFO - PID 8609 | RSS: Main: 171216896 16:09:55 INFO - PID 8609 | 16:12:58 INFO - PID 8609 | Cycle 1(1): loaded http://localhost:57160/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57160/tests/dromaeo/cssquery-yui.html) 16:12:59 INFO - PID 8609 | RSS: Main: 172756992 16:12:59 INFO - PID 8609 | 16:16:00 INFO - PID 8609 | Cycle 1(1): loaded http://localhost:57160/tests/dromaeo/cssquery-yui.html (next: http://localhost:57160/tests/dromaeo/cssquery-dojo.html) 16:16:01 INFO - PID 8609 | RSS: Main: 174788608 16:16:01 INFO - PID 8609 | 16:16:01 INFO - PID 8609 | __start_tp_report 16:16:01 INFO - PID 8609 | _x_x_mozilla_page_load 16:16:01 INFO - PID 8609 | _x_x_mozilla_page_load_details 16:16:01 INFO - PID 8609 | |i|pagename|runs| 16:16:01 INFO - PID 8609 | |0;dojo.html;3110.5178697301917;3139.4349017176905;3142.8114313141214;3144.6855314468544;3166.4458719724057;6100.23747031621;6208.410201030901;6210.378962103788;6214.098955651433;6243;6200.131981522591;6207.658578778165;6254.530910181743;6256.499480041597;6257.124002639634;5732.369439361677;5744.741486633091;5761;5781.71091445426;5852.619579727304;5797.36229014808;5832.1834943108;5854;5867.55993300502;5891.410858914096;95.56838079626284;5656.943430565724;5739.053056245714;5842;5853.034249348852;5664.716764161817;5681.062624666966;5757.510611598019;5818.767249310023;5830.7667693292215;5725.484706376446;5726.541876649858;5803.912941305884;5808.82573522795;5814.244148260741;6187.938120618781;6245.843853903643;6262.405071518198;6338.524610654174;6352.650604216767;6689.5986240825705;6694.43097336727;6770.69531871067;6793.388595026422;6804.523683342168;6498;6591.373819487141;6616.437602803768;6736.225334086594;6831.385175334244;6173.2900716417735;6200.565960382775;6201.720922558496;6204.038374051984;6209.9068513972325;6729.528932974645;6823.317668233137;6845;6847.041414202015;6849.965750171217;6618.933810661832;6644.335566443414;6658.3674550917585;6678.265390807007;6679;6685.0640910272605;6698.5980841149685;6708.228553716288;6779.5932244064525;6789.32106789327;6454.548181627241;6620.205575330991;6621.404073633396;6644.60132392058;6758.020087087345;6592.736290548324;6657.567258128206;6679.398854103154;6682.5322227444585;6684.465242780566;5239.187925871496;5267.920981185285;5280.524752772269;5286.444923283;5287.524122828964;4560.749158796298;4618.976905115453;4648.767561621906;4666.556677115736;4668.323093151474;5735.426457354231;5747.109198074305;5807.709614519257;5811.476967072899;5812.738426770721;6568.80293591193;6651.201855777338;6664.500162487833;6666.400023997767;6689.899651505231;5723.599348045597;5765.135229715576;5821.563382746311;5829.067349224104;5830.737616807254;5725.312962444534;5751.597388182787;5791.623544469596;5818.0981947798155;5821.941780582225;6546.9017964730565;6569.83575410609;6644.966775166094;6653.500987425962;6658.933410665928;6553.442957348679;6644.202695676549;6700.564463309869;6774.932250677529;6802.285759995129;6543.476521878143;6600;6601.636909969998;6601.933980660132;6613.074169616354;6541.96729016371;6671.666416679243;6686.0973768540625;6715.093462382516;6716.899246511404;6701.765438209639;6763.492738044568;6803.25164231944;6828.624425656637;6856.005879147596;6310.2743184534365;6465.579737317059;6466.41802237782;6548.738050477928;6558.8360290993105;6385.776497822554;6430.64631445275;6437.8390540235;6442.452391546771;6451.225852897683;6210.695753891733;6258.249010118815;6283.905741413791;6304.085907543472;6320.747170113144;6436.420722135031;6606.0421238919125;6621;6652.800415987528;6652.800415987528;6182.536309776695;6332.715027823667;6344.492440604833;6430.871382572229;6434 16:16:01 INFO - PID 8609 | |1;ext.html;833.9708110216142;859.7291853066283;863.4171933944588;872.0363997782446;873.1268731268731;42462.57537424625;42995.78502107493;43321.56678433215;43395.915102122424;43421.7828910855;39385;39640.80179599098;39827.40258896119;39854;42251.788741056254;7790.298873101419;7987.760367188994;7987.920120798804;7992.640331185082;7994.840103197933;7826.80432989177;7878.6454609542425;7884.64519096642;7900.091489478697;7935.484193527431;7875.527468351888;7942.841143177133;7943.324817390529;7967.681292748283;7972.641231144612;7861.842763144734;7907.8418431631335;7918.485298455611;7925.048994120685;7927.524348539048;7746.961265193638;7801.648925798353;7805.687772489094;7808;7812.531248125074;38835.805820970716;39273;39322.41016384756;39332.21335573301;39380;3535.522704434894;3574.8748793792092;3575.8390872410805;3576.5886923003927;3578.284343131384;31787.682123178703;31852;31891.6810831891;31980.520292195637;32032.839835800904;4952.207646776498;5238.083335416287;5273.525382715574;5284.947150528484;5288.312519372495;194193;194983;196094;196232.99999999857;198374;43613.78193109077;44206.55793442089;50144.749276254115;51025.489745102816;51052.23421648678;52047.21929171065;52144.47855521472;52195;52236.47763522392;52672.73663631657;15524.301406436738;15748;15750;15771.842281577266;15775.211239438211;12699;12797.488100475877;12803.359832008362;12818.55135070268;12847.421866016051;12968.09223354356;13000.349982500837;13035.283059431633;13039.413226404835;13085;5466.781328746805;5513.401113676989;5520;5522.088855338904;5580.86047848807;1007.66243308491;1025.3437799808128;1036.9637062702768;1044.2585764107416;1046.0219694585587;23113.422164446023;23243.88378058099;23290.184843530395;23418;23668.213085906173;1128.1313388690826;1132.796096702585;1135.2790977729037;1140.5209811878865;1148.1331594646156;12089.455974481169;12249.265044097383;12307.75384492305;12341.69145771345;12435.316057616918;230851;233376;233391;233728;233894;55520;55637;55745.442545574835;55907.72046139825;56018;1461.5250043735616;1473.7641977283583;1494.7608382658723;1508.4192585854526;1524.9847501525064;22754.886225568767;22862;22867.19964801224;22988.080476780742;23094.884525577265;38467.61532384752;38660.80669596634;38706.41940370953;38741.6125838738;38776.806115970365;188679;190558;190608;190626;190725;34763;34938.47592286067;35519.46720799242;37653.62346376611;37825.81087094547;45937.540624594665;47688.761556193385;47790.52209478;47892.76053619849;48063.75968120137;34132.658673412945;34484.137896552784;34486.48270275998;34534.48198277078;34803.47794783131;33831;34140.31719365648;34606.57753957199;34808.47787283243;34950.82524587361;37962.62037379591;38197;38553.421698673985;38734.22531549297;38809.6119038806;33084.33831323312;33127.17182070467;33358;33401.16497087592;33853;24147.275581732578;24236.75763242345;24240;24508;24606.384840379134 16:16:01 INFO - PID 8609 | |2;jquery.html;554.6699713670365;558.2380051230069;566.8412844403568;571.545416913954;590.6633219065131;22164.55670886583;22984.540309193806;23121.421964450896;23202.3039308821;23218.3034508965;21549.353519394444;21718.23986160485;21886;26150.476990460225;26369.86815065922;6577.177852768404;6726.697298621574;6737.326267373248;6743.258241593421;6751.898721519181;6581.21025476941;6679.064930909676;6680.76617318394;6696.933030669704;6981;7244.927550724478;7275.236100209481;7277.636118194096;7280.089988751406;7284.307990740871;7264;7279.854402911938;7288.271172882696;7291.30732580404;7292.453066020017;6870.141232345957;7124.216336203013;7159.319864612853;7193.64031798408;7195.640217989079;31601.52597711013;32443.188920276945;32494.837525812454;32507.83746081278;32518.18704532382;2081.510844951435;2106.7261256036772;2109.504266497387;2113.936581902545;2116.2593092417683;21058.368248952556;21728.456788580253;21797.67303490449;21799.782002179934;22119.77880221193;2604.6483724697114;2609.8173127881055;2663.4007348346468;2667;2667.7999150063824;119187;124261.37869310686;127945.36027319804;133290;139158;21394.35816925495;21415.25046623392;21993;22424;22891.88554057219;43590.78204608957;43747.12505749931;43854.34218486725;43891.78054109709;43974.560254397686;11198.888011119947;11361.318320900607;11443.828342574869;11621.186516943733;11697.824532632018;9537.189338906272;9539.046095390406;9605.751252337152;9675.758106047406;9684.75788105303;11336.206465547333;11358.829617555743;11453.885461145448;11593.6521904343;12198.878011219951;3578.7673801202836;3723.3670276053135;3930.8427662893164;4251.831565901412;4308.892277693083;918.4718786697675;961.8605302231277;964.3731574476506;968.7529679931713;988.367444835306;19475.123619436876;19531.51171220731;19564.510887227647;19856.305029323903;20155.798442015686;1022.2792930983638;1029.9073083422527;1039.5062345386032;1040.443362800913;1041.1670663469345;10852.403117828437;10924.890751092546;10969;10997.780044399067;11172.944135279271;433216;444220;454806;466704;471026;109368;112593;113993.43003285097;115748.42125789318;115775.42112289552;1030.8659874216453;1161.3322339654565;1195.9521619135312;1205.2105870654734;1209.0206932384622;20782.856942868286;20798.688019679415;20977.475563111046;21122.68315975231;21143.8942805285;57425.712871435375;57679.4232057674;57996;58992;59736.402635974824;71514.28485715284;72122.27877721365;72199;72281;72608.27391726016;19844.801551984874;20501.17995280232;20559.280425185047;20666.586668266853;20714.792852071285;22408.551828963657;22837.08651653915;22937.311880643607;22954.885225573766;22957.77042229623;17527.21127549238;17836.3757268495;18261;18594.349197778014;18741.156647950604;16370.263338150051;16852.325906964074;17169.742453862953;17274.740878887078;17368.39210627622;18852.397546208726;18906.054697264808;18976.43070707881;19177;19305;20226.19095236174;20280.695789562873;20307.69538456954;20324;20327.288544900857;17927.64144717072;18361.173747181678;18373.356932507297;18378.81621183771;18673.15970781291 16:16:01 INFO - PID 8609 | |3;mootools.html;1275.2667216350599;1306.203216038216;1330.6939403937088;1342.2684636872903;1350.0617071135564;15013.699726005474;15505.612359691;15570.53288401347;15575.766363504557;15618.141002244873;15156.621084472894;15234;15302;15309.923450382732;15322.770158447633;2611.947761044778;2655.44235710501;2669.2792945904594;2707.431439397729;2707.593860920858;2604.231751633273;2647.3249321423104;2660.8802603882773;2662.2013395981226;2693.8787754550995;2619.9913033482057;2660.627512148301;2695.2857492764433;2705.945881082377;2707.891684332624;2716.375233696251;2742.698303186648;2743;2754.9173524794287;2755.655543057128;2585.31489155374;2592.1057235253998;2594.597837335216;2599.168266154831;2600.661913951174;14903.329350179269;14939.253037348199;15248.31382587775;15278.923605382013;15285.770713439308;7675.577843218621;7958.2439668232;7962.920370796276;7978.441509094366;7983.081945576217;4977.850664480072;5182.119039763212;5253.238280449352;5275.86810329741;5288.762005709714;1868.8691791574595;1888.5656299051295;1897.6204759048246;1931.94204173875;1932.3333449959741;26382;26466.867665661743;26482.735172648412;26490.470190596272;26591.335216619547;6327.746890124436;6344.111824344595;6384.106225128486;6431.871362572722;6487.481001519867;16093.758593621105;16120.838791612168;16276.186190690418;16286.67426651484;16288.592785180228;7622.5045372051745;8035.0759662638375;8272.37957153216;8288.50268983863;8306.460080094896;7330.5968171751065;7362.889556656654;7371.705131794668;7396.889046664304;7397.482176247719;5249.687578105473;5332.7067011313975;5349.277847490617;5371.060064488699;5404.405515393303;1412.2938530734632;1498.8051553298;1504.9398024079132;1520.7262692715417;1539.7305471542436;741.5995362504187;897.1118592593416;903.6249956268207;907.9909200908038;914.3553794574809;8128.552929588811;8221.79445513867;8305.8338833223;8318.708845190511;8379.287760540423;981.8674478945394;1008.6973907827622;1023.6877752285625;1025.5795123999123;1046.3355769086534;5061.240813877874;5133.948660513347;5146.819861304912;5217.0870097733505;5222.791088356423;21623.45941351479;22647.660285095735;22656.207032753773;22766.772332276796;22884.885575572014;21906.23328183506;21927;21930;21995.890020549796;22298.21956231524;11343.20597558163;11495.827562586568;11605.6518304451;11607.303561786151;11608.593699220486;7853.175416581176;7874.881876771968;7993.240642138988;7999.800004999922;8004.879926800986;18442.907785460986;19334;19414.708779368604;19443.8055619442;19550.511237218616;18911.810881891004;19647;19792.802071979673;19852.60294794125;19871.900640496704;10712.19658525598;11053.944730276296;11114.610988615359;11131.666050018512;11133.777324453627;14286.928565357106;14355.066920650124;14361.138331700131;14413.711725765635;14474.85525144735;10700.785984280426;10757;10866.348019118877;10871.728206794893;11131.387773672526;11435.942820286178;11453.770924581628;11491.367974761468;11686.94156529212;11805;9180.265578753817;9276.443413395218;9314.906850931404;9325.160735533564;9326.160645541662;6849;6896.620685862325;6923.5499692519825;6925.68834402443;6926.168859736864;6363.236411630449;6368.840778980562;6374.490040796818;6383.457406120532;6521.152250207443 16:16:01 INFO - PID 8609 | |4;prototype.html;501.99749001254986;517.4515014085072;527.5384038965905;528.2155998342463;540.5837505121057;6150.969245153774;6226.5952713073675;6263.655498947556;6290.685465726719;6381.840453988652;6207.720652570634;6239.313675495703;6268.931252686614;6279.497640188774;6301.77943771967;5154.329937108189;5199.818006369778;5219.765110570033;5233.194547880975;5248.21276808478;4774.259989701602;5033.394444083659;5195.480451954812;5252.395974462947;5255.580993131852;5005;5019.799208031674;5025.974870125644;5036.244563315496;5051.242313652945;4893.4617192108835;4946.72792996385;4954.133026720309;4979.203327467624;4999.9500004999845;4441.622462090726;4450.599446049871;4511.14288285228;4512.368268442421;4515.774211289422;6174.3758902466725;6180.907286390708;6193.380661933816;6239.812805615838;6250.499800079959;5556.583256255797;5692.7153642318135;5699.857503562403;5706.429357064302;5772.653640781525;6475.676216189172;6522.771702990372;6530.334764635035;6581.243157036906;6614.7684831031165;4881.658283920127;4890.290907818347;4896.363472748555;4897.681650692693;4899.608031357483;6874.725010999554;6905.102336696247;6911.3779759821855;6916.723331066802;6984.660138853439;4952.62855285855;4982.302477653131;4992.700437973734;4999;5001.499550134945;5064.873378165576;5066.164082926276;5079.822206222764;5099.719515426613;5109.923351149736;5585.162225666101;5609.354924183689;5655.095184770499;5703.971480142573;5728.140778883201;6538.346165383424;6555.737770489127;6590.868182636416;6627.011896431148;6631.568948018364;1647.780642324695;1664.5422508810175;1696.2875592251285;1705.9658806823793;1748.8251174882664;1193.3794426897964;1209.7338585511172;1219.6889793102703;1228.6314105768413;1243.8383010208615;5644.379118296984;5662.631928924689;5668.206451096849;5795.884082318415;5844.649321040752;6450.6774661266745;6565.835854103686;6622.569532980341;6635.701393437307;6803.897941530881;5728.8828678408145;5741.282339707536;5804.622699524602;5845.09401042839;5850.444207800338;3556.928861422757;3577.5349204603235;3603.4774957631;3624.220792529619;3630.146915474861;6672.699728512229;6694.330566943364;6703.3296670332575;6708.83227919306;6709.329067093252;7025;7027;7039.964800175966;7060.293970603001;7071.540349877241;6593.967030164818;6623.834404139935;6630.569013014138;6653.767118150939;6706.798796036127;6777.728890844311;6791.626460544718;6796.626185559841;6849;6851.588904665736;6722;6732.461403087839;6779.8983015253825;6786.457083433413;6794.966025169842;6544.247411547733;6557;6557.409833115043;6568.9014664781025;6604.5046621502615;4989.126902791924;5006.123928312458;5020.849374518771;5043.2687260347775;5049.444561098349;6746.257911629815;6789.762358317435;6827.283135270924;6848.280930502226;6861.451083913175;6565.704543295658;6632.369924857131;6637.800865974028;6641.701123449359;6641.701123449553;6226.3151053385;6427.742890284523;6434.613923164625;6474.546781725422;6584.703688333941;6186.381361863958;6265;6286.591371560834;6344.54075562614;6346.524010699124;6520.9347906520325;6524.412802847766;6527.3472652734345;6536.346365363615;6536.8365790855605;6283.403076707706;6348.333424990309;6358;6376.608771710608;6424.51816113784 16:16:01 INFO - PID 8609 | |5;yui.html;894.3739382432296;902.4946030223072;916.4913473022474;919.5723988345418;922.3681777982079;19251.32620358286;20013.899930500327;20019.59960800783;20038.59922801543;20082.196712131514;16990.575235619115;17056.914715426406;17127;17169.91415042926;17170.313187472486;987.600021991093;992.057545331934;994.7612572982505;996.9651062212824;997.1374760831865;989.3223142147652;989.7624570103195;990.1929927109383;990.5195979949718;991.4348821171935;1021.2136654775818;1048.1667074675613;1059.2161800267786;1059.8251288537383;1061.4268295120626;1051.8842927277992;1053.4364115198332;1054.4622242656224;1054.7890421915647;1054.8470471781586;726.8546290741873;749.2432643030523;752.0449029732264;757.5644004697266;757.8711619024724;18338.541536461562;18422.81577184238;18446.077696115273;18457;18474.722879156823;21722.782772172235;22087.779122208733;22178.667319990214;22336.329910102722;22426.88786556057;17737.201825917866;17768.644627107515;17976.19107140169;18343.63312733738;18445.815541844542;3625.184333524936;3648.142686468704;3648.6898613617873;3649.4160934250394;3649.6715295623258;102769;140343.29828350793;141224.29387853097;141629.2918535411;142026;16849.157542122844;17463.91268043677;17573.64852702939;17586.12069396525;17587.73618395725;31587.52618710721;31903.840480797448;32220.83889580537;32258.677413226036;32264.83867580647;9475.099865512766;9596.328257022082;9619.759506012266;9700.75748106303;9754.902450975542;4407.867763967086;4492.281235002384;4499.797509112098;4507.301368287921;4517.028838799673;5373.838784836461;5394.91907621386;5460.781568737206;5464.535514481314;5473.67157970523;1114.9609763658395;1123.4102096399456;1134.8865113488584;1139.8632164140356;1144.2962578014628;674.2785219814751;676.2290988273372;683.6923384476905;688.9931100688929;690.892911598693;11010.229283950206;11030.117590592894;11108.111351092055;11325.71685707864;11444.828327575093;627.3350248736263;634.755619086655;635.4503354598239;656.0159760359461;658.1148355461896;5042.369703787027;5076.593872490192;5082.720450375265;5111.670965548984;5186.818461353834;114895;121587;122175.38912305559;123616;125001.37499312627;111112;111273.44363278132;113480.43259783814;114344;115355;88158;89776.5511172453;92395.07604923999;94481;94570.99999999862;9598.088181622734;9611.711648650551;9696.17582505495;9729.805403891742;9858.753531161778;42970;43742.781286094636;44204.11591768082;44307;45541.54458455373;56484.15273770979;56848.43151568597;57496.13755793583;57829.13256301243;59010.40989590221;13402.597922062354;13631.318434078057;13654.453821847015;13836.446542138605;13882.305884705926;15184.316705748048;15312.923435382752;15340.30968606393;15455.304511296794;15474.922625386802;12959.611211663665;12991.545295914597;13018.609441716764;13105.737885242432;13206.075574709677;14685.632859178606;14693.779593306323;14770.778438323649;14771.261436928326;14914.850851491346;13565;13774.586762397144;13807;13849.446022159002;13851.445942162201;9549.427034377959;9612.951935240279;9817.85273220888;9823.410595364301;9917.553710082919;10739.301945373525;10939.835902461311;10964.835527466936;11198.888011119785;11283.492242849255 16:16:01 INFO - PID 8609 | __end_tp_report 16:16:01 INFO - PID 8609 | __start_cc_report 16:16:01 INFO - PID 8609 | _x_x_mozilla_cycle_collect,365 16:16:01 INFO - PID 8609 | __end_cc_report 16:16:01 INFO - PID 8609 | __startTimestamp1504826161057__endTimestamp 16:16:01 INFO - PID 8609 | 16:16:01 INFO - PID 8609 | ------- Summary: start ------- 16:16:01 INFO - PID 8609 | Number of tests: 6 16:16:01 INFO - PID 8609 | 16:16:01 INFO - PID 8609 | [#0] dojo.html Cycles:165 Average:6124.51 Median:6352.65 stddev:869.79 (13.7%) stddev-sans-first:839.71 16:16:01 INFO - PID 8609 | Values: 3110.5 3139.4 3142.8 3144.7 3166.4 6100.2 6208.4 6210.4 6214.1 6243.0 6200.1 6207.7 6254.5 6256.5 6257.1 5732.4 5744.7 5761.0 5781.7 5852.6 5797.4 5832.2 5854.0 5867.6 5891.4 95.6 5656.9 5739.1 5842.0 5853.0 5664.7 5681.1 5757.5 5818.8 5830.8 5725.5 5726.5 5803.9 5808.8 5814.2 6187.9 6245.8 6262.4 6338.5 6352.7 6689.6 6694.4 6770.7 6793.4 6804.5 6498.0 6591.4 6616.4 6736.2 6831.4 6173.3 6200.6 6201.7 6204.0 6209.9 6729.5 6823.3 6845.0 6847.0 6850.0 6618.9 6644.3 6658.4 6678.3 6679.0 6685.1 6698.6 6708.2 6779.6 6789.3 6454.5 6620.2 6621.4 6644.6 6758.0 6592.7 6657.6 6679.4 6682.5 6684.5 5239.2 5267.9 5280.5 5286.4 5287.5 4560.7 4619.0 4648.8 4666.6 4668.3 5735.4 5747.1 5807.7 5811.5 5812.7 6568.8 6651.2 6664.5 6666.4 6689.9 5723.6 5765.1 5821.6 5829.1 5830.7 5725.3 5751.6 5791.6 5818.1 5821.9 6546.9 6569.8 6645.0 6653.5 6658.9 6553.4 6644.2 6700.6 6774.9 6802.3 6543.5 6600.0 6601.6 6601.9 6613.1 6542.0 6671.7 6686.1 6715.1 6716.9 6701.8 6763.5 6803.3 6828.6 6856.0 6310.3 6465.6 6466.4 6548.7 6558.8 6385.8 6430.6 6437.8 6442.5 6451.2 6210.7 6258.2 6283.9 6304.1 6320.7 6436.4 6606.0 6621.0 6652.8 6652.8 6182.5 6332.7 6344.5 6430.9 6434.0 16:16:01 INFO - PID 8609 | 16:16:01 INFO - PID 8609 | [#1] ext.html Cycles:180 Average:38209.03 Median:3576.21 stddev:53730.67 (1502.4%) stddev-sans-first:53808.11 16:16:01 INFO - PID 8609 | Values: 834.0 859.7 863.4 872.0 873.1 42462.6 42995.8 43321.6 43395.9 43421.8 39385.0 39640.8 39827.4 39854.0 42251.8 7790.3 7987.8 7987.9 7992.6 7994.8 7826.8 7878.6 7884.6 7900.1 7935.5 7875.5 7942.8 7943.3 7967.7 7972.6 7861.8 7907.8 7918.5 7925.0 7927.5 7747.0 7801.6 7805.7 7808.0 7812.5 38835.8 39273.0 39322.4 39332.2 39380.0 3535.5 3574.9 3575.8 3576.6 3578.3 31787.7 31852.0 31891.7 31980.5 32032.8 4952.2 5238.1 5273.5 5284.9 5288.3 194193.0 194983.0 196094.0 196233.0 198374.0 43613.8 44206.6 50144.7 51025.5 51052.2 52047.2 52144.5 52195.0 52236.5 52672.7 15524.3 15748.0 15750.0 15771.8 15775.2 12699.0 12797.5 12803.4 12818.6 12847.4 12968.1 13000.3 13035.3 13039.4 13085.0 5466.8 5513.4 5520.0 5522.1 5580.9 1007.7 1025.3 1037.0 1044.3 1046.0 23113.4 23243.9 23290.2 23418.0 23668.2 1128.1 1132.8 1135.3 1140.5 1148.1 12089.5 12249.3 12307.8 12341.7 12435.3 230851.0 233376.0 233391.0 233728.0 233894.0 55520.0 55637.0 55745.4 55907.7 56018.0 1461.5 1473.8 1494.8 1508.4 1525.0 22754.9 22862.0 22867.2 22988.1 23094.9 38467.6 38660.8 38706.4 38741.6 38776.8 188679.0 190558.0 190608.0 190626.0 190725.0 34763.0 34938.5 35519.5 37653.6 37825.8 45937.5 47688.8 47790.5 47892.8 48063.8 34132.7 34484.1 34486.5 34534.5 34803.5 33831.0 34140.3 34606.6 34808.5 34950.8 37962.6 38197.0 38553.4 38734.2 38809.6 33084.3 33127.2 33358.0 33401.2 33853.0 24147.3 24236.8 24240.0 24508.0 24606.4 16:16:01 INFO - PID 8609 | 16:16:01 INFO - PID 8609 | [#2] jquery.html Cycles:180 Average:35030.53 Median:22142.17 stddev:76501.66 (345.5%) stddev-sans-first:76672.48 16:16:01 INFO - PID 8609 | Values: 554.7 558.2 566.8 571.5 590.7 22164.6 22984.5 23121.4 23202.3 23218.3 21549.4 21718.2 21886.0 26150.5 26369.9 6577.2 6726.7 6737.3 6743.3 6751.9 6581.2 6679.1 6680.8 6696.9 6981.0 7244.9 7275.2 7277.6 7280.1 7284.3 7264.0 7279.9 7288.3 7291.3 7292.5 6870.1 7124.2 7159.3 7193.6 7195.6 31601.5 32443.2 32494.8 32507.8 32518.2 2081.5 2106.7 2109.5 2113.9 2116.3 21058.4 21728.5 21797.7 21799.8 22119.8 2604.6 2609.8 2663.4 2667.0 2667.8 119187.0 124261.4 127945.4 133290.0 139158.0 21394.4 21415.3 21993.0 22424.0 22891.9 43590.8 43747.1 43854.3 43891.8 43974.6 11198.9 11361.3 11443.8 11621.2 11697.8 9537.2 9539.0 9605.8 9675.8 9684.8 11336.2 11358.8 11453.9 11593.7 12198.9 3578.8 3723.4 3930.8 4251.8 4308.9 918.5 961.9 964.4 968.8 988.4 19475.1 19531.5 19564.5 19856.3 20155.8 1022.3 1029.9 1039.5 1040.4 1041.2 10852.4 10924.9 10969.0 10997.8 11172.9 433216.0 444220.0 454806.0 466704.0 471026.0 109368.0 112593.0 113993.4 115748.4 115775.4 1030.9 1161.3 1196.0 1205.2 1209.0 20782.9 20798.7 20977.5 21122.7 21143.9 57425.7 57679.4 57996.0 58992.0 59736.4 71514.3 72122.3 72199.0 72281.0 72608.3 19844.8 20501.2 20559.3 20666.6 20714.8 22408.6 22837.1 22937.3 22954.9 22957.8 17527.2 17836.4 18261.0 18594.3 18741.2 16370.3 16852.3 17169.7 17274.7 17368.4 18852.4 18906.1 18976.4 19177.0 19305.0 20226.2 20280.7 20307.7 20324.0 20327.3 17927.6 18361.2 18373.4 18378.8 18673.2 16:16:01 INFO - PID 8609 | 16:16:01 INFO - PID 8609 | [#3] mootools.html Cycles:180 Average:9320.59 Median:2615.97 stddev:6799.61 (259.9%) stddev-sans-first:6791.81 16:16:01 INFO - PID 8609 | Values: 1275.3 1306.2 1330.7 1342.3 1350.1 15013.7 15505.6 15570.5 15575.8 15618.1 15156.6 15234.0 15302.0 15309.9 15322.8 2611.9 2655.4 2669.3 2707.4 2707.6 2604.2 2647.3 2660.9 2662.2 2693.9 2620.0 2660.6 2695.3 2705.9 2707.9 2716.4 2742.7 2743.0 2754.9 2755.7 2585.3 2592.1 2594.6 2599.2 2600.7 14903.3 14939.3 15248.3 15278.9 15285.8 7675.6 7958.2 7962.9 7978.4 7983.1 4977.9 5182.1 5253.2 5275.9 5288.8 1868.9 1888.6 1897.6 1931.9 1932.3 26382.0 26466.9 26482.7 26490.5 26591.3 6327.7 6344.1 6384.1 6431.9 6487.5 16093.8 16120.8 16276.2 16286.7 16288.6 7622.5 8035.1 8272.4 8288.5 8306.5 7330.6 7362.9 7371.7 7396.9 7397.5 5249.7 5332.7 5349.3 5371.1 5404.4 1412.3 1498.8 1504.9 1520.7 1539.7 741.6 897.1 903.6 908.0 914.4 8128.6 8221.8 8305.8 8318.7 8379.3 981.9 1008.7 1023.7 1025.6 1046.3 5061.2 5133.9 5146.8 5217.1 5222.8 21623.5 22647.7 22656.2 22766.8 22884.9 21906.2 21927.0 21930.0 21995.9 22298.2 11343.2 11495.8 11605.7 11607.3 11608.6 7853.2 7874.9 7993.2 7999.8 8004.9 18442.9 19334.0 19414.7 19443.8 19550.5 18911.8 19647.0 19792.8 19852.6 19871.9 10712.2 11053.9 11114.6 11131.7 11133.8 14286.9 14355.1 14361.1 14413.7 14474.9 10700.8 10757.0 10866.3 10871.7 11131.4 11435.9 11453.8 11491.4 11686.9 11805.0 9180.3 9276.4 9314.9 9325.2 9326.2 6849.0 6896.6 6923.5 6925.7 6926.2 6363.2 6368.8 6374.5 6383.5 6521.2 16:16:01 INFO - PID 8609 | 16:16:01 INFO - PID 8609 | [#4] prototype.html Cycles:180 Average:5545.15 Median:6217.02 stddev:1561.65 (25.1%) stddev-sans-first:1519.46 16:16:01 INFO - PID 8609 | Values: 502.0 517.5 527.5 528.2 540.6 6151.0 6226.6 6263.7 6290.7 6381.8 6207.7 6239.3 6268.9 6279.5 6301.8 5154.3 5199.8 5219.8 5233.2 5248.2 4774.3 5033.4 5195.5 5252.4 5255.6 5005.0 5019.8 5026.0 5036.2 5051.2 4893.5 4946.7 4954.1 4979.2 5000.0 4441.6 4450.6 4511.1 4512.4 4515.8 6174.4 6180.9 6193.4 6239.8 6250.5 5556.6 5692.7 5699.9 5706.4 5772.7 6475.7 6522.8 6530.3 6581.2 6614.8 4881.7 4890.3 4896.4 4897.7 4899.6 6874.7 6905.1 6911.4 6916.7 6984.7 4952.6 4982.3 4992.7 4999.0 5001.5 5064.9 5066.2 5079.8 5099.7 5109.9 5585.2 5609.4 5655.1 5704.0 5728.1 6538.3 6555.7 6590.9 6627.0 6631.6 1647.8 1664.5 1696.3 1706.0 1748.8 1193.4 1209.7 1219.7 1228.6 1243.8 5644.4 5662.6 5668.2 5795.9 5844.6 6450.7 6565.8 6622.6 6635.7 6803.9 5728.9 5741.3 5804.6 5845.1 5850.4 3556.9 3577.5 3603.5 3624.2 3630.1 6672.7 6694.3 6703.3 6708.8 6709.3 7025.0 7027.0 7040.0 7060.3 7071.5 6594.0 6623.8 6630.6 6653.8 6706.8 6777.7 6791.6 6796.6 6849.0 6851.6 6722.0 6732.5 6779.9 6786.5 6795.0 6544.2 6557.0 6557.4 6568.9 6604.5 4989.1 5006.1 5020.8 5043.3 5049.4 6746.3 6789.8 6827.3 6848.3 6861.5 6565.7 6632.4 6637.8 6641.7 6641.7 6226.3 6427.7 6434.6 6474.5 6584.7 6186.4 6265.0 6286.6 6344.5 6346.5 6520.9 6524.4 6527.3 6536.3 6536.8 6283.4 6348.3 6358.0 6376.6 6424.5 16:16:01 INFO - PID 8609 | 16:16:01 INFO - PID 8609 | [#5] yui.html Cycles:180 Average:23766.16 Median:21905.28 stddev:34987.25 (159.7%) stddev-sans-first:35043.25 16:16:01 INFO - PID 8609 | Values: 894.4 902.5 916.5 919.6 922.4 19251.3 20013.9 20019.6 20038.6 20082.2 16990.6 17056.9 17127.0 17169.9 17170.3 987.6 992.1 994.8 997.0 997.1 989.3 989.8 990.2 990.5 991.4 1021.2 1048.2 1059.2 1059.8 1061.4 1051.9 1053.4 1054.5 1054.8 1054.8 726.9 749.2 752.0 757.6 757.9 18338.5 18422.8 18446.1 18457.0 18474.7 21722.8 22087.8 22178.7 22336.3 22426.9 17737.2 17768.6 17976.2 18343.6 18445.8 3625.2 3648.1 3648.7 3649.4 3649.7 102769.0 140343.3 141224.3 141629.3 142026.0 16849.2 17463.9 17573.6 17586.1 17587.7 31587.5 31903.8 32220.8 32258.7 32264.8 9475.1 9596.3 9619.8 9700.8 9754.9 4407.9 4492.3 4499.8 4507.3 4517.0 5373.8 5394.9 5460.8 5464.5 5473.7 1115.0 1123.4 1134.9 1139.9 1144.3 674.3 676.2 683.7 689.0 690.9 11010.2 11030.1 11108.1 11325.7 11444.8 627.3 634.8 635.5 656.0 658.1 5042.4 5076.6 5082.7 5111.7 5186.8 114895.0 121587.0 122175.4 123616.0 125001.4 111112.0 111273.4 113480.4 114344.0 115355.0 88158.0 89776.6 92395.1 94481.0 94571.0 9598.1 9611.7 9696.2 9729.8 9858.8 42970.0 43742.8 44204.1 44307.0 45541.5 56484.2 56848.4 57496.1 57829.1 59010.4 13402.6 13631.3 13654.5 13836.4 13882.3 15184.3 15312.9 15340.3 15455.3 15474.9 12959.6 12991.5 13018.6 13105.7 13206.1 14685.6 14693.8 14770.8 14771.3 14914.9 13565.0 13774.6 13807.0 13849.4 13851.4 9549.4 9613.0 9817.9 9823.4 9917.6 10739.3 10939.8 10964.8 11198.9 11283.5 16:16:01 INFO - PID 8609 | -------- Summary: end -------- 16:16:01 INFO - PID 8609 | 16:16:01 INFO - TEST-INFO | 8609: exit 0 16:16:01 INFO - TEST-OK | dromaeo_css | took 1108630ms 16:16:01 INFO - TEST-START | kraken 16:16:01 INFO - Initialising browser for kraken test... 16:16:01 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57160/getInfo.html -profile /tmp/tmpDtyrEG/profile 16:16:01 INFO - TEST-INFO | started process 8991 (/builds/slave/test/build/application/firefox/firefox http://localhost:57160/getInfo.html) 16:16:07 INFO - TEST-INFO | 8991: exit 0 16:16:07 INFO - Browser initialized. 16:16:07 INFO - Running cycle 1/1 for kraken test... 16:16:07 INFO - Using env: {'DISPLAY': ':0', 16:16:07 INFO - 'HOME': '/home/cltbld', 16:16:07 INFO - 'JSGC_DISABLE_POISONING': '1', 16:16:07 INFO - 'LANG': 'en_US.UTF-8', 16:16:07 INFO - 'LANGUAGE': 'en_US:en', 16:16:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:16:07 INFO - 'LOGNAME': 'cltbld', 16:16:07 INFO - 'MAIL': '/var/mail/cltbld', 16:16:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:16:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:16:07 INFO - 'MOZ_CRASHREPORTER': '1', 16:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:16:07 INFO - 'MOZ_NO_REMOTE': '1', 16:16:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:16:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:07 INFO - 'NO_EM_RESTART': '1', 16:16:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:07 INFO - 'PWD': '/builds/slave/test', 16:16:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:16:07 INFO - 'RUST_BACKTRACE': 'full', 16:16:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:16:07 INFO - 'SHELL': '/bin/bash', 16:16:07 INFO - 'SHLVL': '1', 16:16:07 INFO - 'TERM': 'linux', 16:16:07 INFO - 'TMOUT': '86400', 16:16:07 INFO - 'USER': 'cltbld', 16:16:07 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110', 16:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:07 INFO - '_': '/tools/buildbot/bin/python'} 16:16:07 INFO - TEST-INFO | started process 9191 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDtyrEG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:16:08 INFO - PID 9191 | 16:16:08 INFO - PID 9191 | (/builds/slave/test/build/application/firefox/firefox:9242): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:16:08 INFO - PID 9191 | 16:16:18 INFO - PID 9191 | RSS: Main: 166797312 16:16:18 INFO - PID 9191 | 16:16:21 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57160/tests/kraken/driver.html?testName=audio-beat-detection) 16:16:22 INFO - PID 9191 | RSS: Main: 172552192 16:16:22 INFO - PID 9191 | 16:16:25 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57160/tests/kraken/driver.html?testName=audio-dft) 16:16:25 INFO - PID 9191 | RSS: Main: 173432832 16:16:25 INFO - PID 9191 | 16:16:28 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57160/tests/kraken/driver.html?testName=audio-fft) 16:16:29 INFO - PID 9191 | RSS: Main: 168103936 16:16:29 INFO - PID 9191 | 16:16:31 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57160/tests/kraken/driver.html?testName=audio-oscillator) 16:16:32 INFO - PID 9191 | RSS: Main: 169148416 16:16:32 INFO - PID 9191 | 16:16:34 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57160/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:16:34 INFO - PID 9191 | RSS: Main: 169222144 16:16:34 INFO - PID 9191 | 16:16:37 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57160/tests/kraken/driver.html?testName=imaging-darkroom) 16:16:38 INFO - PID 9191 | RSS: Main: 171532288 16:16:38 INFO - PID 9191 | 16:16:42 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57160/tests/kraken/driver.html?testName=imaging-desaturate) 16:16:42 INFO - PID 9191 | RSS: Main: 171388928 16:16:42 INFO - PID 9191 | 16:16:45 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57160/tests/kraken/driver.html?testName=json-parse-financial) 16:16:46 INFO - PID 9191 | RSS: Main: 171962368 16:16:46 INFO - PID 9191 | 16:16:47 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57160/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:16:48 INFO - PID 9191 | RSS: Main: 171970560 16:16:48 INFO - PID 9191 | 16:16:50 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57160/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:16:50 INFO - PID 9191 | RSS: Main: 172318720 16:16:50 INFO - PID 9191 | 16:16:52 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57160/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:16:53 INFO - PID 9191 | RSS: Main: 172396544 16:16:53 INFO - PID 9191 | 16:16:56 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57160/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:16:56 INFO - PID 9191 | RSS: Main: 172793856 16:16:56 INFO - PID 9191 | 16:16:59 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57160/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:17:00 INFO - PID 9191 | RSS: Main: 173178880 16:17:00 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | Cycle 1(1): loaded http://localhost:57160/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57160/tests/kraken/driver.html?testName=ai-astar) 16:17:02 INFO - PID 9191 | RSS: Main: 174841856 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | __start_tp_report 16:17:02 INFO - PID 9191 | _x_x_mozilla_page_load 16:17:02 INFO - PID 9191 | _x_x_mozilla_page_load_details 16:17:02 INFO - PID 9191 | |i|pagename|runs| 16:17:02 INFO - PID 9191 | |0;ai-astar;109;109;109;108;107;110;109;109;112;106 16:17:02 INFO - PID 9191 | |1;audio-beat-detection;118;117;149;115;143;122;118;117;118;141 16:17:02 INFO - PID 9191 | |2;audio-dft;165;161;163;162;161;163;164;165;165;164 16:17:02 INFO - PID 9191 | |3;audio-fft;73;74;73;74;102;75;74;78;75;74 16:17:02 INFO - PID 9191 | |4;audio-oscillator;79;79;79;80;79;78;79;79;79;78 16:17:02 INFO - PID 9191 | |5;imaging-gaussian-blur;114;113;113;113;113;114;114;114;114;114 16:17:02 INFO - PID 9191 | |6;imaging-darkroom;147;150;149;148;148;148;148;149;149;148 16:17:02 INFO - PID 9191 | |7;imaging-desaturate;94;95;93;93;93;95;94;94;94;97 16:17:02 INFO - PID 9191 | |8;json-parse-financial;55;51;52;54;51;52;52;53;52;54 16:17:02 INFO - PID 9191 | |9;json-stringify-tinderbox;39;39;38;38;38;38;39;38;38;39 16:17:02 INFO - PID 9191 | |10;stanford-crypto-aes;75;82;79;78;76;76;77;74;74;86 16:17:02 INFO - PID 9191 | |11;stanford-crypto-ccm;119;116;139;124;123;127;127;129;116;119 16:17:02 INFO - PID 9191 | |12;stanford-crypto-pbkdf2;166;164;166;176;180;167;163;172;168;168 16:17:02 INFO - PID 9191 | |13;stanford-crypto-sha256-iterative;73;67;67;63;66;63;63;64;63;64 16:17:02 INFO - PID 9191 | __end_tp_report 16:17:02 INFO - PID 9191 | __start_cc_report 16:17:02 INFO - PID 9191 | _x_x_mozilla_cycle_collect,937 16:17:02 INFO - PID 9191 | __end_cc_report 16:17:02 INFO - PID 9191 | __startTimestamp1504826222529__endTimestamp 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | ------- Summary: start ------- 16:17:02 INFO - PID 9191 | Number of tests: 14 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#0] ai-astar Cycles:10 Average:108.80 Median:109.00 stddev:1.62 (1.5%) stddev-sans-first:1.72 16:17:02 INFO - PID 9191 | Values: 109.0 109.0 109.0 108.0 107.0 110.0 109.0 109.0 112.0 106.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#1] audio-beat-detection Cycles:10 Average:125.80 Median:120.00 stddev:13.05 (10.9%) stddev-sans-first:13.54 16:17:02 INFO - PID 9191 | Values: 118.0 117.0 149.0 115.0 143.0 122.0 118.0 117.0 118.0 141.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#2] audio-dft Cycles:10 Average:163.30 Median:164.00 stddev:1.57 (1.0%) stddev-sans-first:1.54 16:17:02 INFO - PID 9191 | Values: 165.0 161.0 163.0 162.0 161.0 163.0 164.0 165.0 165.0 164.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#3] audio-fft Cycles:10 Average:77.20 Median:74.00 stddev:8.83 (11.9%) stddev-sans-first:9.23 16:17:02 INFO - PID 9191 | Values: 73.0 74.0 73.0 74.0 102.0 75.0 74.0 78.0 75.0 74.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#4] audio-oscillator Cycles:10 Average:78.90 Median:79.00 stddev:0.57 (0.7%) stddev-sans-first:0.60 16:17:02 INFO - PID 9191 | Values: 79.0 79.0 79.0 80.0 79.0 78.0 79.0 79.0 79.0 78.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:17:02 INFO - PID 9191 | Values: 114.0 113.0 113.0 113.0 113.0 114.0 114.0 114.0 114.0 114.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:0.84 (0.6%) stddev-sans-first:0.73 16:17:02 INFO - PID 9191 | Values: 147.0 150.0 149.0 148.0 148.0 148.0 148.0 149.0 149.0 148.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:1.23 (1.3%) stddev-sans-first:1.30 16:17:02 INFO - PID 9191 | Values: 94.0 95.0 93.0 93.0 93.0 95.0 94.0 94.0 94.0 97.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.50 stddev:1.35 (2.6%) stddev-sans-first:1.12 16:17:02 INFO - PID 9191 | Values: 55.0 51.0 52.0 54.0 51.0 52.0 52.0 53.0 52.0 54.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#9] json-stringify-tinderbox Cycles:10 Average:38.40 Median:38.50 stddev:0.52 (1.3%) stddev-sans-first:0.50 16:17:02 INFO - PID 9191 | Values: 39.0 39.0 38.0 38.0 38.0 38.0 39.0 38.0 38.0 39.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#10] stanford-crypto-aes Cycles:10 Average:77.70 Median:77.50 stddev:3.80 (4.9%) stddev-sans-first:3.91 16:17:02 INFO - PID 9191 | Values: 75.0 82.0 79.0 78.0 76.0 76.0 77.0 74.0 74.0 86.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#11] stanford-crypto-ccm Cycles:10 Average:123.90 Median:125.50 stddev:7.05 (5.6%) stddev-sans-first:7.25 16:17:02 INFO - PID 9191 | Values: 119.0 116.0 139.0 124.0 123.0 127.0 127.0 129.0 116.0 119.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:168.00 stddev:5.42 (3.2%) stddev-sans-first:5.63 16:17:02 INFO - PID 9191 | Values: 166.0 164.0 166.0 176.0 180.0 167.0 163.0 172.0 168.0 168.0 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:3.16 (4.9%) stddev-sans-first:1.74 16:17:02 INFO - PID 9191 | Values: 73.0 67.0 67.0 63.0 66.0 63.0 63.0 64.0 63.0 64.0 16:17:02 INFO - PID 9191 | -------- Summary: end -------- 16:17:02 INFO - PID 9191 | 16:17:02 INFO - PID 9191 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:17:03 INFO - TEST-INFO | 9191: exit 0 16:17:03 INFO - TEST-OK | kraken | took 61447ms 16:17:03 INFO - SUITE-END | took 1170s 16:17:03 INFO - Completed test suite (00:19:30) 16:17:03 INFO - javascript benchmark 16:17:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8599.171845466028, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3110.5178697301917, 3139.4349017176905, 3142.8114313141214, 3144.6855314468544, 3166.4458719724057, 6100.23747031621, 6208.410201030901, 6210.378962103788, 6214.098955651433, 6243.0, 6200.131981522591, 6207.658578778165, 6254.530910181743, 6256.499480041597, 6257.124002639634, 5732.369439361677, 5744.741486633091, 5761.0, 5781.71091445426, 5852.619579727304, 5797.36229014808, 5832.1834943108, 5854.0, 5867.55993300502, 5891.410858914096, 95.56838079626284, 5656.943430565724, 5739.053056245714, 5842.0, 5853.034249348852, 5664.716764161817, 5681.062624666966, 5757.510611598019, 5818.767249310023, 5830.7667693292215, 5725.484706376446, 5726.541876649858, 5803.912941305884, 5808.82573522795, 5814.244148260741, 6187.938120618781, 6245.843853903643, 6262.405071518198, 6338.524610654174, 6352.650604216767, 6689.5986240825705, 6694.43097336727, 6770.69531871067, 6793.388595026422, 6804.523683342168, 6498.0, 6591.373819487141, 6616.437602803768, 6736.225334086594, 6831.385175334244, 6173.2900716417735, 6200.565960382775, 6201.720922558496, 6204.038374051984, 6209.9068513972325, 6729.528932974645, 6823.317668233137, 6845.0, 6847.041414202015, 6849.965750171217, 6618.933810661832, 6644.335566443414, 6658.3674550917585, 6678.265390807007, 6679.0, 6685.0640910272605, 6698.5980841149685, 6708.228553716288, 6779.5932244064525, 6789.32106789327, 6454.548181627241, 6620.205575330991, 6621.404073633396, 6644.60132392058, 6758.020087087345, 6592.736290548324, 6657.567258128206, 6679.398854103154, 6682.5322227444585, 6684.465242780566, 5239.187925871496, 5267.920981185285, 5280.524752772269, 5286.444923283, 5287.524122828964, 4560.749158796298, 4618.976905115453, 4648.767561621906, 4666.556677115736, 4668.323093151474, 5735.426457354231, 5747.109198074305, 5807.709614519257, 5811.476967072899, 5812.738426770721, 6568.80293591193, 6651.201855777338, 6664.500162487833, 6666.400023997767, 6689.899651505231, 5723.599348045597, 5765.135229715576, 5821.563382746311, 5829.067349224104, 5830.737616807254, 5725.312962444534, 5751.597388182787, 5791.623544469596, 5818.0981947798155, 5821.941780582225, 6546.9017964730565, 6569.83575410609, 6644.966775166094, 6653.500987425962, 6658.933410665928, 6553.442957348679, 6644.202695676549, 6700.564463309869, 6774.932250677529, 6802.285759995129, 6543.476521878143, 6600.0, 6601.636909969998, 6601.933980660132, 6613.074169616354, 6541.96729016371, 6671.666416679243, 6686.0973768540625, 6715.093462382516, 6716.899246511404, 6701.765438209639, 6763.492738044568, 6803.25164231944, 6828.624425656637, 6856.005879147596, 6310.2743184534365, 6465.579737317059, 6466.41802237782, 6548.738050477928, 6558.8360290993105, 6385.776497822554, 6430.64631445275, 6437.8390540235, 6442.452391546771, 6451.225852897683, 6210.695753891733, 6258.249010118815, 6283.905741413791, 6304.085907543472, 6320.747170113144, 6436.420722135031, 6606.0421238919125, 6621.0, 6652.800415987528, 6652.800415987528, 6182.536309776695, 6332.715027823667, 6344.492440604833, 6430.871382572229, 6434.0], "value": 6063.161448306359, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.9708110216142, 859.7291853066283, 863.4171933944588, 872.0363997782446, 873.1268731268731, 42462.57537424625, 42995.78502107493, 43321.56678433215, 43395.915102122424, 43421.7828910855, 39385.0, 39640.80179599098, 39827.40258896119, 39854.0, 42251.788741056254, 7790.298873101419, 7987.760367188994, 7987.920120798804, 7992.640331185082, 7994.840103197933, 7826.80432989177, 7878.6454609542425, 7884.64519096642, 7900.091489478697, 7935.484193527431, 7875.527468351888, 7942.841143177133, 7943.324817390529, 7967.681292748283, 7972.641231144612, 7861.842763144734, 7907.8418431631335, 7918.485298455611, 7925.048994120685, 7927.524348539048, 7746.961265193638, 7801.648925798353, 7805.687772489094, 7808.0, 7812.531248125074, 38835.805820970716, 39273.0, 39322.41016384756, 39332.21335573301, 39380.0, 3535.522704434894, 3574.8748793792092, 3575.8390872410805, 3576.5886923003927, 3578.284343131384, 31787.682123178703, 31852.0, 31891.6810831891, 31980.520292195637, 32032.839835800904, 4952.207646776498, 5238.083335416287, 5273.525382715574, 5284.947150528484, 5288.312519372495, 194193.0, 194983.0, 196094.0, 196232.99999999857, 198374.0, 43613.78193109077, 44206.55793442089, 50144.749276254115, 51025.489745102816, 51052.23421648678, 52047.21929171065, 52144.47855521472, 52195.0, 52236.47763522392, 52672.73663631657, 15524.301406436738, 15748.0, 15750.0, 15771.842281577266, 15775.211239438211, 12699.0, 12797.488100475877, 12803.359832008362, 12818.55135070268, 12847.421866016051, 12968.09223354356, 13000.349982500837, 13035.283059431633, 13039.413226404835, 13085.0, 5466.781328746805, 5513.401113676989, 5520.0, 5522.088855338904, 5580.86047848807, 1007.66243308491, 1025.3437799808128, 1036.9637062702768, 1044.2585764107416, 1046.0219694585587, 23113.422164446023, 23243.88378058099, 23290.184843530395, 23418.0, 23668.213085906173, 1128.1313388690826, 1132.796096702585, 1135.2790977729037, 1140.5209811878865, 1148.1331594646156, 12089.455974481169, 12249.265044097383, 12307.75384492305, 12341.69145771345, 12435.316057616918, 230851.0, 233376.0, 233391.0, 233728.0, 233894.0, 55520.0, 55637.0, 55745.442545574835, 55907.72046139825, 56018.0, 1461.5250043735616, 1473.7641977283583, 1494.7608382658723, 1508.4192585854526, 1524.9847501525064, 22754.886225568767, 22862.0, 22867.19964801224, 22988.080476780742, 23094.884525577265, 38467.61532384752, 38660.80669596634, 38706.41940370953, 38741.6125838738, 38776.806115970365, 188679.0, 190558.0, 190608.0, 190626.0, 190725.0, 34763.0, 34938.47592286067, 35519.46720799242, 37653.62346376611, 37825.81087094547, 45937.540624594665, 47688.761556193385, 47790.52209478, 47892.76053619849, 48063.75968120137, 34132.658673412945, 34484.137896552784, 34486.48270275998, 34534.48198277078, 34803.47794783131, 33831.0, 34140.31719365648, 34606.57753957199, 34808.47787283243, 34950.82524587361, 37962.62037379591, 38197.0, 38553.421698673985, 38734.22531549297, 38809.6119038806, 33084.33831323312, 33127.17182070467, 33358.0, 33401.16497087592, 33853.0, 24147.275581732578, 24236.75763242345, 24240.0, 24508.0, 24606.384840379134], "value": 17474.080757865522, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.6699713670365, 558.2380051230069, 566.8412844403568, 571.545416913954, 590.6633219065131, 22164.55670886583, 22984.540309193806, 23121.421964450896, 23202.3039308821, 23218.3034508965, 21549.353519394444, 21718.23986160485, 21886.0, 26150.476990460225, 26369.86815065922, 6577.177852768404, 6726.697298621574, 6737.326267373248, 6743.258241593421, 6751.898721519181, 6581.21025476941, 6679.064930909676, 6680.76617318394, 6696.933030669704, 6981.0, 7244.927550724478, 7275.236100209481, 7277.636118194096, 7280.089988751406, 7284.307990740871, 7264.0, 7279.854402911938, 7288.271172882696, 7291.30732580404, 7292.453066020017, 6870.141232345957, 7124.216336203013, 7159.319864612853, 7193.64031798408, 7195.640217989079, 31601.52597711013, 32443.188920276945, 32494.837525812454, 32507.83746081278, 32518.18704532382, 2081.510844951435, 2106.7261256036772, 2109.504266497387, 2113.936581902545, 2116.2593092417683, 21058.368248952556, 21728.456788580253, 21797.67303490449, 21799.782002179934, 22119.77880221193, 2604.6483724697114, 2609.8173127881055, 2663.4007348346468, 2667.0, 2667.7999150063824, 119187.0, 124261.37869310686, 127945.36027319804, 133290.0, 139158.0, 21394.35816925495, 21415.25046623392, 21993.0, 22424.0, 22891.88554057219, 43590.78204608957, 43747.12505749931, 43854.34218486725, 43891.78054109709, 43974.560254397686, 11198.888011119947, 11361.318320900607, 11443.828342574869, 11621.186516943733, 11697.824532632018, 9537.189338906272, 9539.046095390406, 9605.751252337152, 9675.758106047406, 9684.75788105303, 11336.206465547333, 11358.829617555743, 11453.885461145448, 11593.6521904343, 12198.878011219951, 3578.7673801202836, 3723.3670276053135, 3930.8427662893164, 4251.831565901412, 4308.892277693083, 918.4718786697675, 961.8605302231277, 964.3731574476506, 968.7529679931713, 988.367444835306, 19475.123619436876, 19531.51171220731, 19564.510887227647, 19856.305029323903, 20155.798442015686, 1022.2792930983638, 1029.9073083422527, 1039.5062345386032, 1040.443362800913, 1041.1670663469345, 10852.403117828437, 10924.890751092546, 10969.0, 10997.780044399067, 11172.944135279271, 433216.0, 444220.0, 454806.0, 466704.0, 471026.0, 109368.0, 112593.0, 113993.43003285097, 115748.42125789318, 115775.42112289552, 1030.8659874216453, 1161.3322339654565, 1195.9521619135312, 1205.2105870654734, 1209.0206932384622, 20782.856942868286, 20798.688019679415, 20977.475563111046, 21122.68315975231, 21143.8942805285, 57425.712871435375, 57679.4232057674, 57996.0, 58992.0, 59736.402635974824, 71514.28485715284, 72122.27877721365, 72199.0, 72281.0, 72608.27391726016, 19844.801551984874, 20501.17995280232, 20559.280425185047, 20666.586668266853, 20714.792852071285, 22408.551828963657, 22837.08651653915, 22937.311880643607, 22954.885225573766, 22957.77042229623, 17527.21127549238, 17836.3757268495, 18261.0, 18594.349197778014, 18741.156647950604, 16370.263338150051, 16852.325906964074, 17169.742453862953, 17274.740878887078, 17368.39210627622, 18852.397546208726, 18906.054697264808, 18976.43070707881, 19177.0, 19305.0, 20226.19095236174, 20280.695789562873, 20307.69538456954, 20324.0, 20327.288544900857, 17927.64144717072, 18361.173747181678, 18373.356932507297, 18378.81621183771, 18673.15970781291], "value": 13059.727877441634, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1275.2667216350599, 1306.203216038216, 1330.6939403937088, 1342.2684636872903, 1350.0617071135564, 15013.699726005474, 15505.612359691, 15570.53288401347, 15575.766363504557, 15618.141002244873, 15156.621084472894, 15234.0, 15302.0, 15309.923450382732, 15322.770158447633, 2611.947761044778, 2655.44235710501, 2669.2792945904594, 2707.431439397729, 2707.593860920858, 2604.231751633273, 2647.3249321423104, 2660.8802603882773, 2662.2013395981226, 2693.8787754550995, 2619.9913033482057, 2660.627512148301, 2695.2857492764433, 2705.945881082377, 2707.891684332624, 2716.375233696251, 2742.698303186648, 2743.0, 2754.9173524794287, 2755.655543057128, 2585.31489155374, 2592.1057235253998, 2594.597837335216, 2599.168266154831, 2600.661913951174, 14903.329350179269, 14939.253037348199, 15248.31382587775, 15278.923605382013, 15285.770713439308, 7675.577843218621, 7958.2439668232, 7962.920370796276, 7978.441509094366, 7983.081945576217, 4977.850664480072, 5182.119039763212, 5253.238280449352, 5275.86810329741, 5288.762005709714, 1868.8691791574595, 1888.5656299051295, 1897.6204759048246, 1931.94204173875, 1932.3333449959741, 26382.0, 26466.867665661743, 26482.735172648412, 26490.470190596272, 26591.335216619547, 6327.746890124436, 6344.111824344595, 6384.106225128486, 6431.871362572722, 6487.481001519867, 16093.758593621105, 16120.838791612168, 16276.186190690418, 16286.67426651484, 16288.592785180228, 7622.5045372051745, 8035.0759662638375, 8272.37957153216, 8288.50268983863, 8306.460080094896, 7330.5968171751065, 7362.889556656654, 7371.705131794668, 7396.889046664304, 7397.482176247719, 5249.687578105473, 5332.7067011313975, 5349.277847490617, 5371.060064488699, 5404.405515393303, 1412.2938530734632, 1498.8051553298, 1504.9398024079132, 1520.7262692715417, 1539.7305471542436, 741.5995362504187, 897.1118592593416, 903.6249956268207, 907.9909200908038, 914.3553794574809, 8128.552929588811, 8221.79445513867, 8305.8338833223, 8318.708845190511, 8379.287760540423, 981.8674478945394, 1008.6973907827622, 1023.6877752285625, 1025.5795123999123, 1046.3355769086534, 5061.240813877874, 5133.948660513347, 5146.819861304912, 5217.0870097733505, 5222.791088356423, 21623.45941351479, 22647.660285095735, 22656.207032753773, 22766.772332276796, 22884.885575572014, 21906.23328183506, 21927.0, 21930.0, 21995.890020549796, 22298.21956231524, 11343.20597558163, 11495.827562586568, 11605.6518304451, 11607.303561786151, 11608.593699220486, 7853.175416581176, 7874.881876771968, 7993.240642138988, 7999.800004999922, 8004.879926800986, 18442.907785460986, 19334.0, 19414.708779368604, 19443.8055619442, 19550.511237218616, 18911.810881891004, 19647.0, 19792.802071979673, 19852.60294794125, 19871.900640496704, 10712.19658525598, 11053.944730276296, 11114.610988615359, 11131.666050018512, 11133.777324453627, 14286.928565357106, 14355.066920650124, 14361.138331700131, 14413.711725765635, 14474.85525144735, 10700.785984280426, 10757.0, 10866.348019118877, 10871.728206794893, 11131.387773672526, 11435.942820286178, 11453.770924581628, 11491.367974761468, 11686.94156529212, 11805.0, 9180.265578753817, 9276.443413395218, 9314.906850931404, 9325.160735533564, 9326.160645541662, 6849.0, 6896.620685862325, 6923.5499692519825, 6925.68834402443, 6926.168859736864, 6363.236411630449, 6368.840778980562, 6374.490040796818, 6383.457406120532, 6521.152250207443], "value": 6616.017046645247, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.99749001254986, 517.4515014085072, 527.5384038965905, 528.2155998342463, 540.5837505121057, 6150.969245153774, 6226.5952713073675, 6263.655498947556, 6290.685465726719, 6381.840453988652, 6207.720652570634, 6239.313675495703, 6268.931252686614, 6279.497640188774, 6301.77943771967, 5154.329937108189, 5199.818006369778, 5219.765110570033, 5233.194547880975, 5248.21276808478, 4774.259989701602, 5033.394444083659, 5195.480451954812, 5252.395974462947, 5255.580993131852, 5005.0, 5019.799208031674, 5025.974870125644, 5036.244563315496, 5051.242313652945, 4893.4617192108835, 4946.72792996385, 4954.133026720309, 4979.203327467624, 4999.9500004999845, 4441.622462090726, 4450.599446049871, 4511.14288285228, 4512.368268442421, 4515.774211289422, 6174.3758902466725, 6180.907286390708, 6193.380661933816, 6239.812805615838, 6250.499800079959, 5556.583256255797, 5692.7153642318135, 5699.857503562403, 5706.429357064302, 5772.653640781525, 6475.676216189172, 6522.771702990372, 6530.334764635035, 6581.243157036906, 6614.7684831031165, 4881.658283920127, 4890.290907818347, 4896.363472748555, 4897.681650692693, 4899.608031357483, 6874.725010999554, 6905.102336696247, 6911.3779759821855, 6916.723331066802, 6984.660138853439, 4952.62855285855, 4982.302477653131, 4992.700437973734, 4999.0, 5001.499550134945, 5064.873378165576, 5066.164082926276, 5079.822206222764, 5099.719515426613, 5109.923351149736, 5585.162225666101, 5609.354924183689, 5655.095184770499, 5703.971480142573, 5728.140778883201, 6538.346165383424, 6555.737770489127, 6590.868182636416, 6627.011896431148, 6631.568948018364, 1647.780642324695, 1664.5422508810175, 1696.2875592251285, 1705.9658806823793, 1748.8251174882664, 1193.3794426897964, 1209.7338585511172, 1219.6889793102703, 1228.6314105768413, 1243.8383010208615, 5644.379118296984, 5662.631928924689, 5668.206451096849, 5795.884082318415, 5844.649321040752, 6450.6774661266745, 6565.835854103686, 6622.569532980341, 6635.701393437307, 6803.897941530881, 5728.8828678408145, 5741.282339707536, 5804.622699524602, 5845.09401042839, 5850.444207800338, 3556.928861422757, 3577.5349204603235, 3603.4774957631, 3624.220792529619, 3630.146915474861, 6672.699728512229, 6694.330566943364, 6703.3296670332575, 6708.83227919306, 6709.329067093252, 7025.0, 7027.0, 7039.964800175966, 7060.293970603001, 7071.540349877241, 6593.967030164818, 6623.834404139935, 6630.569013014138, 6653.767118150939, 6706.798796036127, 6777.728890844311, 6791.626460544718, 6796.626185559841, 6849.0, 6851.588904665736, 6722.0, 6732.461403087839, 6779.8983015253825, 6786.457083433413, 6794.966025169842, 6544.247411547733, 6557.0, 6557.409833115043, 6568.9014664781025, 6604.5046621502615, 4989.126902791924, 5006.123928312458, 5020.849374518771, 5043.2687260347775, 5049.444561098349, 6746.257911629815, 6789.762358317435, 6827.283135270924, 6848.280930502226, 6861.451083913175, 6565.704543295658, 6632.369924857131, 6637.800865974028, 6641.701123449359, 6641.701123449553, 6226.3151053385, 6427.742890284523, 6434.613923164625, 6474.546781725422, 6584.703688333941, 6186.381361863958, 6265.0, 6286.591371560834, 6344.54075562614, 6346.524010699124, 6520.9347906520325, 6524.412802847766, 6527.3472652734345, 6536.346365363615, 6536.8365790855605, 6283.403076707706, 6348.333424990309, 6358.0, 6376.608771710608, 6424.51816113784], "value": 5083.930201682621, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [894.3739382432296, 902.4946030223072, 916.4913473022474, 919.5723988345418, 922.3681777982079, 19251.32620358286, 20013.899930500327, 20019.59960800783, 20038.59922801543, 20082.196712131514, 16990.575235619115, 17056.914715426406, 17127.0, 17169.91415042926, 17170.313187472486, 987.600021991093, 992.057545331934, 994.7612572982505, 996.9651062212824, 997.1374760831865, 989.3223142147652, 989.7624570103195, 990.1929927109383, 990.5195979949718, 991.4348821171935, 1021.2136654775818, 1048.1667074675613, 1059.2161800267786, 1059.8251288537383, 1061.4268295120626, 1051.8842927277992, 1053.4364115198332, 1054.4622242656224, 1054.7890421915647, 1054.8470471781586, 726.8546290741873, 749.2432643030523, 752.0449029732264, 757.5644004697266, 757.8711619024724, 18338.541536461562, 18422.81577184238, 18446.077696115273, 18457.0, 18474.722879156823, 21722.782772172235, 22087.779122208733, 22178.667319990214, 22336.329910102722, 22426.88786556057, 17737.201825917866, 17768.644627107515, 17976.19107140169, 18343.63312733738, 18445.815541844542, 3625.184333524936, 3648.142686468704, 3648.6898613617873, 3649.4160934250394, 3649.6715295623258, 102769.0, 140343.29828350793, 141224.29387853097, 141629.2918535411, 142026.0, 16849.157542122844, 17463.91268043677, 17573.64852702939, 17586.12069396525, 17587.73618395725, 31587.52618710721, 31903.840480797448, 32220.83889580537, 32258.677413226036, 32264.83867580647, 9475.099865512766, 9596.328257022082, 9619.759506012266, 9700.75748106303, 9754.902450975542, 4407.867763967086, 4492.281235002384, 4499.797509112098, 4507.301368287921, 4517.028838799673, 5373.838784836461, 5394.91907621386, 5460.781568737206, 5464.535514481314, 5473.67157970523, 1114.9609763658395, 1123.4102096399456, 1134.8865113488584, 1139.8632164140356, 1144.2962578014628, 674.2785219814751, 676.2290988273372, 683.6923384476905, 688.9931100688929, 690.892911598693, 11010.229283950206, 11030.117590592894, 11108.111351092055, 11325.71685707864, 11444.828327575093, 627.3350248736263, 634.755619086655, 635.4503354598239, 656.0159760359461, 658.1148355461896, 5042.369703787027, 5076.593872490192, 5082.720450375265, 5111.670965548984, 5186.818461353834, 114895.0, 121587.0, 122175.38912305559, 123616.0, 125001.37499312627, 111112.0, 111273.44363278132, 113480.43259783814, 114344.0, 115355.0, 88158.0, 89776.5511172453, 92395.07604923999, 94481.0, 94570.99999999862, 9598.088181622734, 9611.711648650551, 9696.17582505495, 9729.805403891742, 9858.753531161778, 42970.0, 43742.781286094636, 44204.11591768082, 44307.0, 45541.54458455373, 56484.15273770979, 56848.43151568597, 57496.13755793583, 57829.13256301243, 59010.40989590221, 13402.597922062354, 13631.318434078057, 13654.453821847015, 13836.446542138605, 13882.305884705926, 15184.316705748048, 15312.923435382752, 15340.30968606393, 15455.304511296794, 15474.922625386802, 12959.611211663665, 12991.545295914597, 13018.609441716764, 13105.737885242432, 13206.075574709677, 14685.632859178606, 14693.779593306323, 14770.778438323649, 14771.261436928326, 14914.850851491346, 13565.0, 13774.586762397144, 13807.0, 13849.446022159002, 13851.445942162201, 9549.427034377959, 9612.951935240279, 9817.85273220888, 9823.410595364301, 9917.553710082919, 10739.301945373525, 10939.835902461311, 10964.835527466936, 11198.888011119785, 11283.492242849255], "value": 8687.36782223175, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1437.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 109.0, 108.0, 107.0, 110.0, 109.0, 109.0, 112.0, 106.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 149.0, 115.0, 143.0, 122.0, 118.0, 117.0, 118.0, 141.0], "value": 125.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 161.0, 163.0, 162.0, 161.0, 163.0, 164.0, 165.0, 165.0, 164.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 73.0, 74.0, 102.0, 75.0, 74.0, 78.0, 75.0, 74.0], "value": 77.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 80.0, 79.0, 78.0, 79.0, 79.0, 79.0, 78.0], "value": 78.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 149.0, 148.0, 148.0, 148.0, 148.0, 149.0, 149.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 93.0, 93.0, 93.0, 95.0, 94.0, 94.0, 94.0, 97.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 52.0, 54.0, 51.0, 52.0, 52.0, 53.0, 52.0, 54.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0, 39.0], "value": 38.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 82.0, 79.0, 78.0, 76.0, 76.0, 77.0, 74.0, 74.0, 86.0], "value": 77.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 116.0, 139.0, 124.0, 123.0, 127.0, 127.0, 129.0, 116.0, 119.0], "value": 123.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 164.0, 166.0, 176.0, 180.0, 167.0, 163.0, 172.0, 168.0, 168.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 67.0, 67.0, 63.0, 66.0, 63.0, 63.0, 64.0, 63.0, 64.0], "value": 65.3, "unit": "score"}]}]} 16:17:03 INFO - Return code: 0 16:17:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:17:03 INFO - # TBPL SUCCESS # 16:17:03 INFO - Running post-action listener: _package_coverage_data 16:17:03 INFO - Running post-action listener: _resource_record_post_action 16:17:03 INFO - [mozharness: 2017-09-07 23:17:03.832439Z] Finished run-tests step (success) 16:17:03 INFO - Running post-run listener: _resource_record_post_run 16:17:03 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 402923520; Read time: 16; Write time: 225892 16:17:03 INFO - TinderboxPrint: CPU usage
13.1% 16:17:03 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:17:03 INFO - TinderboxPrint: I/O write bytes / time
402,923,520 / 225,892 16:17:03 INFO - TinderboxPrint: CPU idle
8,223.7 (86.9%) 16:17:03 INFO - TinderboxPrint: CPU user
1,190.5 (12.6%) 16:17:03 INFO - TinderboxPrint: Swap in / out
0 / 0 16:17:03 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 115216384; Read time: 0; Write time: 79244 16:17:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:17:03 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 4096; Write bytes: 287707136; Read time: 16; Write time: 146648 16:17:04 INFO - Running post-run listener: _upload_blobber_files 16:17:04 INFO - Blob upload gear active. 16:17:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:17:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:17:04 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'] 16:17:04 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 16:17:04 INFO - (blobuploader) - INFO - Open directory for files ... 16:17:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:17:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:17:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:17:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:17:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:17:06 INFO - (blobuploader) - INFO - Done attempting. 16:17:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:17:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:17:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:17:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:17:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:17:12 INFO - (blobuploader) - INFO - Done attempting. 16:17:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:17:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:17:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:17:13 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:17:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:17:13 INFO - (blobuploader) - INFO - Done attempting. 16:17:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:17:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:17:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:17:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:17:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:17:14 INFO - (blobuploader) - INFO - Done attempting. 16:17:14 INFO - (blobuploader) - INFO - Iteration through files over. 16:17:14 INFO - Return code: 0 16:17:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:17:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:17:14 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/45723fefd8797672d9ebd30584eae706398d0e9cb20ec7b2b2da06bd87440ffc7e9d734d46aab663b95e2ff8dd3cf7993d7aa24825c1657cd86b2f81259b0c6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c326ecf763a65d914f7b151311c8a4a944e196cd4cd42eab902491d236ad9461b50b26acffa94a03cff6c09ccf350f7fd660cc1d2b8112f140fd24fa1c6dcf0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b17f8fc20f136068e70ee2016bfea41ae5516b8eeed7ae0c50bb88f0305d43bc2736edaa269634a6e3738c0b3e0bc99fd3266b0b8bb55787c30aa8b321e0acde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/00ed3fba912c44e32f07163a5dff2974a72ad9f4a0a908e01100f1d5e03383859dc214c5a11f56d532029c35ce4d6fbb5d094acdf1a27b8a8d7da95a7d0b8786"} 16:17:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:17:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:17:14 INFO - Contents: 16:17:14 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/45723fefd8797672d9ebd30584eae706398d0e9cb20ec7b2b2da06bd87440ffc7e9d734d46aab663b95e2ff8dd3cf7993d7aa24825c1657cd86b2f81259b0c6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c326ecf763a65d914f7b151311c8a4a944e196cd4cd42eab902491d236ad9461b50b26acffa94a03cff6c09ccf350f7fd660cc1d2b8112f140fd24fa1c6dcf0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b17f8fc20f136068e70ee2016bfea41ae5516b8eeed7ae0c50bb88f0305d43bc2736edaa269634a6e3738c0b3e0bc99fd3266b0b8bb55787c30aa8b321e0acde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/00ed3fba912c44e32f07163a5dff2974a72ad9f4a0a908e01100f1d5e03383859dc214c5a11f56d532029c35ce4d6fbb5d094acdf1a27b8a8d7da95a7d0b8786"} 16:17:14 INFO - Running post-run listener: copy_logs_to_upload_dir 16:17:14 INFO - Copying logs to upload dir... 16:17:14 INFO - mkdir: /builds/slave/test/build/upload/logs 16:17:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.749534 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-09-07 16:17:14.781564) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:17:14.785456) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/45723fefd8797672d9ebd30584eae706398d0e9cb20ec7b2b2da06bd87440ffc7e9d734d46aab663b95e2ff8dd3cf7993d7aa24825c1657cd86b2f81259b0c6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c326ecf763a65d914f7b151311c8a4a944e196cd4cd42eab902491d236ad9461b50b26acffa94a03cff6c09ccf350f7fd660cc1d2b8112f140fd24fa1c6dcf0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b17f8fc20f136068e70ee2016bfea41ae5516b8eeed7ae0c50bb88f0305d43bc2736edaa269634a6e3738c0b3e0bc99fd3266b0b8bb55787c30aa8b321e0acde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/00ed3fba912c44e32f07163a5dff2974a72ad9f4a0a908e01100f1d5e03383859dc214c5a11f56d532029c35ce4d6fbb5d094acdf1a27b8a8d7da95a7d0b8786"} build_url:https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011353 build_url: 'https://queue.taskcluster.net/v1/task/ZsNuXBl0Q92uv17nTmcSug/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/45723fefd8797672d9ebd30584eae706398d0e9cb20ec7b2b2da06bd87440ffc7e9d734d46aab663b95e2ff8dd3cf7993d7aa24825c1657cd86b2f81259b0c6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c326ecf763a65d914f7b151311c8a4a944e196cd4cd42eab902491d236ad9461b50b26acffa94a03cff6c09ccf350f7fd660cc1d2b8112f140fd24fa1c6dcf0a", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b17f8fc20f136068e70ee2016bfea41ae5516b8eeed7ae0c50bb88f0305d43bc2736edaa269634a6e3738c0b3e0bc99fd3266b0b8bb55787c30aa8b321e0acde", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/00ed3fba912c44e32f07163a5dff2974a72ad9f4a0a908e01100f1d5e03383859dc214c5a11f56d532029c35ce4d6fbb5d094acdf1a27b8a8d7da95a7d0b8786"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-07 16:17:14.814535) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:17:14.814852) ========= 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=bb9b54edd4a927dc735d112f000001d9-1504824984.394644-987078110 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004497 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-07 16:17:14.897113) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:17:14.897469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-07 16:17:14.897754) ========= ========= Total master_lag: 0.20 =========