builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-089 starttime: 1504151341.39 results: success (0) revision: 04b6be50a2526c7a26a63715f441c47e1aa1f9be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:01.387358) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:01.387800) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:01.388070) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:01.404686) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:01.405076) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-30 20:49:01-- 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% 42.8M=0s 2017-08-30 20:49:01 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111097 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:01.531436) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:01.531765) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019957 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:01.581177) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:01.581539) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 04b6be50a2526c7a26a63715f441c47e1aa1f9be --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 04b6be50a2526c7a26a63715f441c47e1aa1f9be --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=8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-30 20:49:01,637 truncating revision to first 12 chars 2017-08-30 20:49:01,637 Setting DEBUG logging. 2017-08-30 20:49:01,637 attempt 1/10 2017-08-30 20:49:01,637 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/04b6be50a252?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-30 20:49:02,041 unpacking tar archive at: mozilla-central-04b6be50a252/testing/mozharness/ program finished with exit code 0 elapsedTime=0.697413 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:02.293756) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:02.294072) ========= script_repo_revision: 04b6be50a2526c7a26a63715f441c47e1aa1f9be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:02.294464) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:02.294766) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:02.328920) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-08-30 20:49:02.329442) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:49:02 INFO - MultiFileLogger online at 20170830 20:49:02 in /builds/slave/test 20:49:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:49:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:49:02 INFO - {'append_to_log': False, 20:49:02 INFO - 'base_work_dir': '/builds/slave/test', 20:49:02 INFO - 'blob_upload_branch': 'Firefox', 20:49:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:49:02 INFO - 'branch': 'Firefox', 20:49:02 INFO - 'buildbot_json_path': 'buildprops.json', 20:49:02 INFO - 'code_coverage': False, 20:49:02 INFO - 'config_files': ('talos/linux_config.py',), 20:49:02 INFO - 'default_actions': ('clobber', 20:49:02 INFO - 'read-buildbot-config', 20:49:02 INFO - 'download-and-extract', 20:49:02 INFO - 'populate-webroot', 20:49:02 INFO - 'create-virtualenv', 20:49:02 INFO - 'install', 20:49:02 INFO - 'setup-mitmproxy', 20:49:02 INFO - 'run-tests'), 20:49:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:49:02 INFO - 'disable_ccov_upload': False, 20:49:02 INFO - 'disable_stylo': False, 20:49:02 INFO - 'download_minidump_stackwalk': True, 20:49:02 INFO - 'download_symbols': 'ondemand', 20:49:02 INFO - 'enable_stylo': False, 20:49:02 INFO - 'enable_webrender': False, 20:49:02 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 20:49:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:49:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:49:02 INFO - 'gecko_profile': False, 20:49:02 INFO - 'gecko_profile_interval': 0, 20:49:02 INFO - 'installer_path': 'installer.exe', 20:49:02 INFO - 'log_level': 'info', 20:49:02 INFO - 'log_name': 'talos', 20:49:02 INFO - 'log_to_console': True, 20:49:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:49:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:49:02 INFO - 'opt_config_files': (), 20:49:02 INFO - 'pip_index': False, 20:49:02 INFO - 'suite': 'dromaeojs-e10s', 20:49:02 INFO - 'system_bits': '32', 20:49:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:49:02 INFO - 'title': 'talos-linux64-ix-089', 20:49:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:49:02 INFO - 'use_talos_json': True, 20:49:02 INFO - 'verify': 'False', 20:49:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:49:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:49:02 INFO - 'work_dir': 'build'} 20:49:02 INFO - [mozharness: 2017-08-31 03:49:02.512176Z] Running clobber step. 20:49:02 INFO - Running pre-action listener: _resource_record_pre_action 20:49:02 INFO - Running main action method: clobber 20:49:02 INFO - rmtree: /builds/slave/test/build 20:49:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:49:03 INFO - Running post-action listener: _resource_record_post_action 20:49:03 INFO - [mozharness: 2017-08-31 03:49:03.554043Z] Finished clobber step (success) 20:49:03 INFO - [mozharness: 2017-08-31 03:49:03.554139Z] Running read-buildbot-config step. 20:49:03 INFO - Running pre-action listener: _resource_record_pre_action 20:49:03 INFO - Running main action method: read_buildbot_config 20:49:03 INFO - Using buildbot properties: 20:49:03 INFO - { 20:49:03 INFO - "project": "", 20:49:03 INFO - "product": "firefox", 20:49:03 INFO - "who": "kwierso@gmail.com", 20:49:03 INFO - "installer_path": "public/build/target.tar.bz2", 20:49:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 20:49:03 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 20:49:03 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 20:49:03 INFO - "stage_platform": "linux64-stylo", 20:49:03 INFO - "basedir": "/builds/slave/test", 20:49:03 INFO - "buildnumber": 288, 20:49:03 INFO - "platform": "ubuntu64_hw_stylo", 20:49:03 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 20:49:03 INFO - "slavebuilddir": "test", 20:49:03 INFO - "taskId": "fBBNp_VkRDqejvTdQ04-zg", 20:49:03 INFO - "branch": "mozilla-central", 20:49:03 INFO - "script_repo_revision": "production", 20:49:03 INFO - "revision": "04b6be50a2526c7a26a63715f441c47e1aa1f9be", 20:49:03 INFO - "slavename": "talos-linux64-ix-089", 20:49:03 INFO - "repo_path": "mozilla-central" 20:49:03 INFO - } 20:49:03 INFO - Finding installer, test and symbols from parent task. 20:49:03 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fBBNp_VkRDqejvTdQ04-zg'}, attempt #1 20:49:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fBBNp_VkRDqejvTdQ04-zg'}, attempt #1 20:49:04 INFO - Task dependencies: Cu5bJiXdSKGYWl3gR-gpGg 20:49:04 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg'}, attempt #1 20:49:04 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:49:04 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json 20:49:04 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip 20:49:04 INFO - Running post-action listener: _resource_record_post_action 20:49:04 INFO - [mozharness: 2017-08-31 03:49:04.913546Z] Finished read-buildbot-config step (success) 20:49:04 INFO - [mozharness: 2017-08-31 03:49:04.913764Z] Running download-and-extract step. 20:49:04 INFO - Running pre-action listener: _resource_record_pre_action 20:49:04 INFO - Running main action method: download_and_extract 20:49:04 INFO - mkdir: /builds/slave/test/build/tests 20:49: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')]} 20:49:04 INFO - trying https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json 20:49:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:49:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:49:05 INFO - Downloaded 989 bytes. 20:49:05 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:49:05 INFO - Using the following test package requirements: 20:49:05 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 20:49:05 INFO - u'common': [u'target.common.tests.zip'], 20:49:05 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:49:05 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:49:05 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:49:05 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:49:05 INFO - u'mozbase': [u'target.common.tests.zip'], 20:49:05 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:49:05 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:49:05 INFO - u'web-platform': [u'target.common.tests.zip', 20:49:05 INFO - u'target.web-platform.tests.tar.gz'], 20:49:05 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:49:05 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:49:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip 20:49:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:49:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip into memory 20:49:06 INFO - Content-Length response header: 54950260 20:49:06 INFO - Bytes received: 54950260 20:49:17 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:49:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip 20:49:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:49:17 INFO - Fetch https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip into memory 20:49:18 INFO - Content-Length response header: 54950260 20:49:18 INFO - Bytes received: 54950260 20:49:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.talos.tests.zip 20:49:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:49:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.talos.tests.zip into memory 20:49:31 INFO - Content-Length response header: 14496650 20:49:31 INFO - Bytes received: 14496650 20:49:31 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')]} 20:49:31 INFO - trying https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:49:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:49:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:49:32 INFO - Downloaded 62651819 bytes. 20:49:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:49:32 INFO - mkdir: /builds/slave/test/properties 20:49:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:49:32 INFO - Writing to file /builds/slave/test/properties/build_url 20:49:32 INFO - Contents: 20:49:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:49:32 INFO - Running post-action listener: _resource_record_post_action 20:49:32 INFO - Running post-action listener: find_tests_for_verification 20:49:32 INFO - Running post-action listener: set_extra_try_arguments 20:49:32 INFO - [mozharness: 2017-08-31 03:49:32.553156Z] Finished download-and-extract step (success) 20:49:32 INFO - [mozharness: 2017-08-31 03:49:32.553253Z] Running populate-webroot step. 20:49:32 INFO - Running pre-action listener: _resource_record_pre_action 20:49:32 INFO - Running main action method: populate_webroot 20:49:32 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:49:32 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:49:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:49:32 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:49:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:49:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:49:32 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:49:32 INFO - 'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:49:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:49:32 INFO - 'tests': ['damp', 'tps']}, 20:49:32 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:49:32 INFO - 'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['damp', 'tps']}, 20:49:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:49:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:49:32 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 20:49:32 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:49:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:49:32 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:49:32 INFO - 'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:49:32 INFO - 'other-e10s': {'tests': ['a11yr', 20:49:32 INFO - 'ts_paint', 20:49:32 INFO - 'tpaint', 20:49:32 INFO - 'sessionrestore', 20:49:32 INFO - 'sessionrestore_many_windows', 20:49:32 INFO - 'sessionrestore_no_auto_restore', 20:49:32 INFO - 'tabpaint']}, 20:49:32 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['a11yr', 20:49:32 INFO - 'ts_paint', 20:49:32 INFO - 'tpaint', 20:49:32 INFO - 'sessionrestore', 20:49:32 INFO - 'sessionrestore_many_windows', 20:49:32 INFO - 'sessionrestore_no_auto_restore', 20:49:32 INFO - 'tabpaint']}, 20:49:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 20:49:32 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 20:49:32 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['bloom_basic_singleton']}, 20:49:32 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['bloom_basic']}, 20:49:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:49:32 INFO - 'tsvgr_opacity', 20:49:32 INFO - 'tart', 20:49:32 INFO - 'tscrollx', 20:49:32 INFO - 'tsvg_static']}, 20:49:32 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['tsvgx', 20:49:32 INFO - 'tsvgr_opacity', 20:49:32 INFO - 'tart', 20:49:32 INFO - 'tscrollx', 20:49:32 INFO - 'tsvg_static']}, 20:49:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:49:32 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:49:32 INFO - 'talos_options': ['--enable-stylo'], 20:49:32 INFO - 'tests': ['tp5o']}, 20:49:32 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:49:32 INFO - 'talos_options': ['--mitmproxy', 20:49:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:49:32 INFO - '--firstNonBlankPaint'], 20:49:32 INFO - 'tests': ['tp6_google', 20:49:32 INFO - 'tp6_youtube', 20:49:32 INFO - 'tp6_amazon', 20:49:32 INFO - 'tp6_facebook']}, 20:49:32 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:49:32 INFO - 'talos_options': ['--enable-stylo', 20:49:32 INFO - '--mitmproxy', 20:49:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:49:32 INFO - '--firstNonBlankPaint'], 20:49:32 INFO - 'tests': ['tp6_google', 20:49:32 INFO - 'tp6_youtube', 20:49:32 INFO - 'tp6_amazon', 20:49:32 INFO - 'tp6_facebook']}, 20:49:32 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:49:32 INFO - 'talos_options': ['--enable-stylo', 20:49:32 INFO - '--stylo-threads=1', 20:49:32 INFO - '--mitmproxy', 20:49:32 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:49:32 INFO - '--firstNonBlankPaint'], 20:49:32 INFO - 'tests': ['tp6_google', 20:49:32 INFO - 'tp6_youtube', 20:49:32 INFO - 'tp6_amazon', 20:49:32 INFO - 'tp6_facebook']}, 20:49:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:49:32 INFO - 'talos_options': ['--xperf_path', 20:49:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:49:32 INFO - 'tests': ['tp5n']}, 20:49:32 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:49:32 INFO - 'talos_options': ['--enable-stylo', 20:49:32 INFO - '--xperf_path', 20:49:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:49:32 INFO - 'tests': ['tp5n']}}} 20:49:32 INFO - Running post-action listener: _resource_record_post_action 20:49:32 INFO - [mozharness: 2017-08-31 03:49:32.567439Z] Finished populate-webroot step (success) 20:49:32 INFO - [mozharness: 2017-08-31 03:49:32.567528Z] Running create-virtualenv step. 20:49:32 INFO - Running pre-action listener: _resource_record_pre_action 20:49:32 INFO - Running main action method: create_virtualenv 20:49:32 INFO - Creating virtualenv /builds/slave/test/build/venv 20:49:32 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:49:32 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 20:49:32 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 20:49:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:49:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:49:33 INFO - Installing distribute.............................................................................................................................................................................................done. 20:49:33 INFO - Installing pip...............done. 20:49:33 INFO - Return code: 0 20:49:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:49:33 INFO - Reading from file tmpfile_stdout 20:49:33 INFO - Output received: 20:49:33 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 20:49:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:49:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:49:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:49:33 INFO - Using env: {'DISPLAY': ':0', 20:49:33 INFO - 'HOME': '/home/cltbld', 20:49:33 INFO - 'LANG': 'en_US.UTF-8', 20:49:33 INFO - 'LANGUAGE': 'en_US:en', 20:49:33 INFO - 'LOGNAME': 'cltbld', 20:49:33 INFO - 'MAIL': '/var/mail/cltbld', 20:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:33 INFO - 'MOZ_NO_REMOTE': '1', 20:49:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:33 INFO - 'NO_EM_RESTART': '1', 20:49:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:33 INFO - 'PWD': '/builds/slave/test', 20:49:33 INFO - 'SHELL': '/bin/bash', 20:49:33 INFO - 'SHLVL': '1', 20:49:33 INFO - 'TERM': 'linux', 20:49:33 INFO - 'TMOUT': '86400', 20:49:33 INFO - 'USER': 'cltbld', 20:49:33 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:33 INFO - '_': '/tools/buildbot/bin/python'} 20:49:34 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:49:34 INFO - Downloading/unpacking pip>=1.5 20:49:34 INFO - Running setup.py egg_info for package pip 20:49:34 INFO - warning: no files found matching 'pip/cacert.pem' 20:49:34 INFO - warning: no files found matching '*.html' under directory 'docs' 20:49:34 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:49:34 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:49:34 INFO - Installing collected packages: pip 20:49:34 INFO - Found existing installation: pip 1.1 20:49:34 INFO - Uninstalling pip: 20:49:34 INFO - Successfully uninstalled pip 20:49:34 INFO - Running setup.py install for pip 20:49:34 INFO - warning: no files found matching 'pip/cacert.pem' 20:49:34 INFO - warning: no files found matching '*.html' under directory 'docs' 20:49:34 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:49:34 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:49:34 INFO - Installing pip script to /builds/slave/test/build/venv/bin 20:49:34 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 20:49:34 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 20:49:34 INFO - Successfully installed pip 20:49:34 INFO - Cleaning up... 20:49:34 INFO - Return code: 0 20:49:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:49:34 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')]} 20:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:34 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')]} 20:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:34 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 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:34 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 20:49:34 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 20:49:34 INFO - Using env: {'DISPLAY': ':0', 20:49:34 INFO - 'HOME': '/home/cltbld', 20:49:34 INFO - 'LANG': 'en_US.UTF-8', 20:49:34 INFO - 'LANGUAGE': 'en_US:en', 20:49:34 INFO - 'LOGNAME': 'cltbld', 20:49:34 INFO - 'MAIL': '/var/mail/cltbld', 20:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:34 INFO - 'MOZ_NO_REMOTE': '1', 20:49:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:34 INFO - 'NO_EM_RESTART': '1', 20:49:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:34 INFO - 'PWD': '/builds/slave/test', 20:49:34 INFO - 'SHELL': '/bin/bash', 20:49:34 INFO - 'SHLVL': '1', 20:49:34 INFO - 'TERM': 'linux', 20:49:34 INFO - 'TMOUT': '86400', 20:49:34 INFO - 'USER': 'cltbld', 20:49:34 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:34 INFO - '_': '/tools/buildbot/bin/python'} 20:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:35 INFO - Downloading/unpacking psutil>=3.1.1 20:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:49:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:49:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:49:36 INFO - Installing collected packages: psutil 20:49:36 INFO - Running setup.py install for psutil 20:49:36 INFO - building 'psutil._psutil_linux' extension 20:49:36 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 20:49:36 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 20:49:36 INFO - building 'psutil._psutil_posix' extension 20:49:36 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 20:49:36 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 20:49:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:49:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:49:36 INFO - Successfully installed psutil 20:49:36 INFO - Cleaning up... 20:49:36 INFO - Return code: 0 20:49:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:49:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:36 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 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:36 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 20:49:36 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 20:49:36 INFO - Using env: {'DISPLAY': ':0', 20:49:36 INFO - 'HOME': '/home/cltbld', 20:49:36 INFO - 'LANG': 'en_US.UTF-8', 20:49:36 INFO - 'LANGUAGE': 'en_US:en', 20:49:36 INFO - 'LOGNAME': 'cltbld', 20:49:36 INFO - 'MAIL': '/var/mail/cltbld', 20:49:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:36 INFO - 'MOZ_NO_REMOTE': '1', 20:49:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:36 INFO - 'NO_EM_RESTART': '1', 20:49:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:36 INFO - 'PWD': '/builds/slave/test', 20:49:36 INFO - 'SHELL': '/bin/bash', 20:49:36 INFO - 'SHLVL': '1', 20:49:36 INFO - 'TERM': 'linux', 20:49:36 INFO - 'TMOUT': '86400', 20:49:36 INFO - 'USER': 'cltbld', 20:49:36 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:36 INFO - '_': '/tools/buildbot/bin/python'} 20:49:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:49:36 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 20:49:36 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 20:49:36 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 20:49:36 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 20:49:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:49:38 INFO - Installing collected packages: mozsystemmonitor 20:49:38 INFO - Running setup.py install for mozsystemmonitor 20:49:38 INFO - Successfully installed mozsystemmonitor 20:49:38 INFO - Cleaning up... 20:49:38 INFO - Return code: 0 20:49:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:49:38 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')]} 20:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:38 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')]} 20:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:38 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 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:38 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 20:49:38 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 20:49:38 INFO - Using env: {'DISPLAY': ':0', 20:49:38 INFO - 'HOME': '/home/cltbld', 20:49:38 INFO - 'LANG': 'en_US.UTF-8', 20:49:38 INFO - 'LANGUAGE': 'en_US:en', 20:49:38 INFO - 'LOGNAME': 'cltbld', 20:49:38 INFO - 'MAIL': '/var/mail/cltbld', 20:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:38 INFO - 'MOZ_NO_REMOTE': '1', 20:49:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:38 INFO - 'NO_EM_RESTART': '1', 20:49:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:38 INFO - 'PWD': '/builds/slave/test', 20:49:38 INFO - 'SHELL': '/bin/bash', 20:49:38 INFO - 'SHLVL': '1', 20:49:38 INFO - 'TERM': 'linux', 20:49:38 INFO - 'TMOUT': '86400', 20:49:38 INFO - 'USER': 'cltbld', 20:49:38 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:38 INFO - '_': '/tools/buildbot/bin/python'} 20:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:38 INFO - Downloading/unpacking jsonschema==2.5.1 20:49:38 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 20:49:38 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 20:49:38 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 20:49:38 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 20:49:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:49:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:40 INFO - Downloading functools32-3.2.3-2.tar.gz 20:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:49:40 INFO - warning: no files found matching '*.txt' 20:49:40 INFO - no previously-included directories found matching 'build' 20:49:40 INFO - no previously-included directories found matching 'dist' 20:49:40 INFO - no previously-included directories found matching '.git*' 20:49:40 INFO - Installing collected packages: jsonschema, functools32 20:49:40 INFO - Running setup.py install for functools32 20:49:40 INFO - warning: no files found matching '*.txt' 20:49:40 INFO - no previously-included directories found matching 'build' 20:49:40 INFO - no previously-included directories found matching 'dist' 20:49:40 INFO - no previously-included directories found matching '.git*' 20:49:40 INFO - Successfully installed jsonschema functools32 20:49:40 INFO - Cleaning up... 20:49:40 INFO - Return code: 0 20:49:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:49:40 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')]} 20:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:40 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')]} 20:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:40 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 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:40 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 20:49:40 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 20:49:40 INFO - Using env: {'DISPLAY': ':0', 20:49:40 INFO - 'HOME': '/home/cltbld', 20:49:40 INFO - 'LANG': 'en_US.UTF-8', 20:49:40 INFO - 'LANGUAGE': 'en_US:en', 20:49:40 INFO - 'LOGNAME': 'cltbld', 20:49:40 INFO - 'MAIL': '/var/mail/cltbld', 20:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:40 INFO - 'MOZ_NO_REMOTE': '1', 20:49:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:40 INFO - 'NO_EM_RESTART': '1', 20:49:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:40 INFO - 'PWD': '/builds/slave/test', 20:49:40 INFO - 'SHELL': '/bin/bash', 20:49:40 INFO - 'SHLVL': '1', 20:49:40 INFO - 'TERM': 'linux', 20:49:40 INFO - 'TMOUT': '86400', 20:49:40 INFO - 'USER': 'cltbld', 20:49:40 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:40 INFO - '_': '/tools/buildbot/bin/python'} 20:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:49:41 INFO - Cleaning up... 20:49:41 INFO - Return code: 0 20:49:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:49:41 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')]} 20:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:41 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')]} 20:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:41 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 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:41 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 20:49:41 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 20:49:41 INFO - Using env: {'DISPLAY': ':0', 20:49:41 INFO - 'HOME': '/home/cltbld', 20:49:41 INFO - 'LANG': 'en_US.UTF-8', 20:49:41 INFO - 'LANGUAGE': 'en_US:en', 20:49:41 INFO - 'LOGNAME': 'cltbld', 20:49:41 INFO - 'MAIL': '/var/mail/cltbld', 20:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:41 INFO - 'MOZ_NO_REMOTE': '1', 20:49:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:41 INFO - 'NO_EM_RESTART': '1', 20:49:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:41 INFO - 'PWD': '/builds/slave/test', 20:49:41 INFO - 'SHELL': '/bin/bash', 20:49:41 INFO - 'SHLVL': '1', 20:49:41 INFO - 'TERM': 'linux', 20:49:41 INFO - 'TMOUT': '86400', 20:49:41 INFO - 'USER': 'cltbld', 20:49:41 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:41 INFO - '_': '/tools/buildbot/bin/python'} 20:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:41 INFO - Downloading/unpacking blobuploader==1.2.4 20:49:41 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 20:49:41 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 20:49:41 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 20:49:41 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 20:49:42 INFO - Downloading blobuploader-1.2.4.tar.gz 20:49:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:49:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:49:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:42 INFO - Downloading docopt-0.6.1.tar.gz 20:49:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:49:43 INFO - Installing collected packages: blobuploader, requests, docopt 20:49:43 INFO - Running setup.py install for blobuploader 20:49:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:49:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:49:43 INFO - Running setup.py install for requests 20:49:43 INFO - Running setup.py install for docopt 20:49:43 INFO - Successfully installed blobuploader requests docopt 20:49:43 INFO - Cleaning up... 20:49:43 INFO - Return code: 0 20:49:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:49:43 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')]} 20:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:43 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')]} 20:49:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:43 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 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:43 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 20:49:43 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 20:49:43 INFO - Using env: {'DISPLAY': ':0', 20:49:43 INFO - 'HOME': '/home/cltbld', 20:49:43 INFO - 'LANG': 'en_US.UTF-8', 20:49:43 INFO - 'LANGUAGE': 'en_US:en', 20:49:43 INFO - 'LOGNAME': 'cltbld', 20:49:43 INFO - 'MAIL': '/var/mail/cltbld', 20:49:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:43 INFO - 'MOZ_NO_REMOTE': '1', 20:49:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:43 INFO - 'NO_EM_RESTART': '1', 20:49:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:43 INFO - 'PWD': '/builds/slave/test', 20:49:43 INFO - 'SHELL': '/bin/bash', 20:49:43 INFO - 'SHLVL': '1', 20:49:43 INFO - 'TERM': 'linux', 20:49:43 INFO - 'TMOUT': '86400', 20:49:43 INFO - 'USER': 'cltbld', 20:49:43 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:43 INFO - '_': '/tools/buildbot/bin/python'} 20:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:49:43 INFO - Running setup.py (path:/tmp/pip-bX6EXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:49:43 INFO - Running setup.py (path:/tmp/pip-nnN4Mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:49:43 INFO - Running setup.py (path:/tmp/pip-VrWkn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:49:44 INFO - Running setup.py (path:/tmp/pip-fdBDMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:49:44 INFO - Running setup.py (path:/tmp/pip-QWknb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:49:44 INFO - Running setup.py (path:/tmp/pip-mh5JTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:49:44 INFO - Running setup.py (path:/tmp/pip-4zk283-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:49:44 INFO - Running setup.py (path:/tmp/pip-IRM81y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:49:44 INFO - Running setup.py (path:/tmp/pip-mfiW5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:49:44 INFO - Running setup.py (path:/tmp/pip-bYWV7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:49:44 INFO - Running setup.py (path:/tmp/pip-L4TOkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:49:44 INFO - Running setup.py (path:/tmp/pip-30MJfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:49:44 INFO - Running setup.py (path:/tmp/pip-7MKjYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:49:44 INFO - Running setup.py (path:/tmp/pip-JTZl2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:49:44 INFO - Running setup.py (path:/tmp/pip-EsVckk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:49:44 INFO - Running setup.py (path:/tmp/pip-m48bPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:49:44 INFO - Running setup.py (path:/tmp/pip-v6V6TS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:49:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:49:45 INFO - Running setup.py install for manifestparser 20:49:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:49:45 INFO - Running setup.py install for mozcrash 20:49:45 INFO - Running setup.py install for mozdebug 20:49:45 INFO - Running setup.py install for mozdevice 20:49:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:49:45 INFO - Running setup.py install for mozfile 20:49:45 INFO - Running setup.py install for mozhttpd 20:49:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:49:45 INFO - Running setup.py install for mozinfo 20:49:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:49:45 INFO - Running setup.py install for mozInstall 20:49:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:49:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:49:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:49:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:49:45 INFO - Running setup.py install for mozleak 20:49:45 INFO - Running setup.py install for mozlog 20:49:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:49:46 INFO - Running setup.py install for moznetwork 20:49:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:49:46 INFO - Running setup.py install for mozprocess 20:49:46 INFO - Running setup.py install for mozprofile 20:49:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:49:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:49:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:49:46 INFO - Running setup.py install for mozrunner 20:49:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:49:46 INFO - Running setup.py install for mozscreenshot 20:49:46 INFO - Running setup.py install for moztest 20:49:46 INFO - Running setup.py install for mozversion 20:49:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:49:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:49:46 INFO - Cleaning up... 20:49:46 INFO - Return code: 0 20:49:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:46 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 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:46 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 20:49:46 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 20:49:46 INFO - Using env: {'DISPLAY': ':0', 20:49:46 INFO - 'HOME': '/home/cltbld', 20:49:46 INFO - 'LANG': 'en_US.UTF-8', 20:49:46 INFO - 'LANGUAGE': 'en_US:en', 20:49:46 INFO - 'LOGNAME': 'cltbld', 20:49:46 INFO - 'MAIL': '/var/mail/cltbld', 20:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:46 INFO - 'MOZ_NO_REMOTE': '1', 20:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:46 INFO - 'NO_EM_RESTART': '1', 20:49:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:46 INFO - 'PWD': '/builds/slave/test', 20:49:46 INFO - 'SHELL': '/bin/bash', 20:49:46 INFO - 'SHLVL': '1', 20:49:46 INFO - 'TERM': 'linux', 20:49:46 INFO - 'TMOUT': '86400', 20:49:46 INFO - 'USER': 'cltbld', 20:49:46 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:46 INFO - '_': '/tools/buildbot/bin/python'} 20:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:49:46 INFO - Running setup.py (path:/tmp/pip-elOEwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:49:47 INFO - Running setup.py (path:/tmp/pip-S1lFhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:49:47 INFO - Running setup.py (path:/tmp/pip-W2k0yI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:49:47 INFO - Running setup.py (path:/tmp/pip-HQarVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:49:47 INFO - Running setup.py (path:/tmp/pip-nPBe3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:49:47 INFO - Running setup.py (path:/tmp/pip-afOX0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:49:47 INFO - Running setup.py (path:/tmp/pip-_f1ajR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:49:47 INFO - Running setup.py (path:/tmp/pip-bQsLjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:49:47 INFO - Running setup.py (path:/tmp/pip-lCq8tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:49:47 INFO - Running setup.py (path:/tmp/pip-8KV3Br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:49:47 INFO - Running setup.py (path:/tmp/pip-9tv1Zy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:49:47 INFO - Running setup.py (path:/tmp/pip-o7kJMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:49:47 INFO - Running setup.py (path:/tmp/pip-TpKvn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:49:47 INFO - Running setup.py (path:/tmp/pip-6pgPBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:49:47 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)) 20:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:49:47 INFO - Running setup.py (path:/tmp/pip-Omsh7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:49:48 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)) 20:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:49:48 INFO - Running setup.py (path:/tmp/pip-yRYCo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:49:48 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)) 20:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:49:48 INFO - Running setup.py (path:/tmp/pip-6CBY9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:49:48 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)) 20:49:48 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)) 20:49:48 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)) 20:49:48 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)) 20:49:48 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)) 20:49:48 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)) 20:49:48 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)) 20:49:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:49:49 INFO - Downloading blessings-1.6.tar.gz 20:49:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:49:49 INFO - Installing collected packages: blessings 20:49:49 INFO - Running setup.py install for blessings 20:49:49 INFO - Successfully installed blessings 20:49:49 INFO - Cleaning up... 20:49:49 INFO - Return code: 0 20:49:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:49:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:49:49 INFO - Reading from file tmpfile_stdout 20:49:49 INFO - Current package versions: 20:49:49 INFO - argparse == 1.2.1 20:49:49 INFO - blessings == 1.6 20:49:49 INFO - blobuploader == 1.2.4 20:49:49 INFO - docopt == 0.6.1 20:49:49 INFO - functools32 == 3.2.3-2 20:49:49 INFO - jsonschema == 2.5.1 20:49:49 INFO - manifestparser == 1.1 20:49:49 INFO - mozInstall == 1.13 20:49:49 INFO - mozcrash == 1.0 20:49:49 INFO - mozdebug == 0.1 20:49:49 INFO - mozdevice == 0.51 20:49:49 INFO - mozfile == 1.2 20:49:49 INFO - mozhttpd == 0.7 20:49:49 INFO - mozinfo == 0.10 20:49:49 INFO - mozleak == 0.1 20:49:49 INFO - mozlog == 3.5 20:49:49 INFO - moznetwork == 0.27 20:49:49 INFO - mozprocess == 0.25 20:49:49 INFO - mozprofile == 0.28 20:49:49 INFO - mozrunner == 6.13 20:49:49 INFO - mozscreenshot == 0.1 20:49:49 INFO - mozsystemmonitor == 0.3 20:49:49 INFO - moztest == 0.8 20:49:49 INFO - mozversion == 1.4 20:49:49 INFO - psutil == 3.1.1 20:49:49 INFO - requests == 1.2.3 20:49:49 INFO - wsgiref == 0.1.2 20:49:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:49:49 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')]} 20:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:49 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')]} 20:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:49 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 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:49 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 20:49:49 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 20:49:49 INFO - Using env: {'DISPLAY': ':0', 20:49:49 INFO - 'HOME': '/home/cltbld', 20:49:49 INFO - 'LANG': 'en_US.UTF-8', 20:49:49 INFO - 'LANGUAGE': 'en_US:en', 20:49:49 INFO - 'LOGNAME': 'cltbld', 20:49:49 INFO - 'MAIL': '/var/mail/cltbld', 20:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:49 INFO - 'MOZ_NO_REMOTE': '1', 20:49:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:49 INFO - 'NO_EM_RESTART': '1', 20:49:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:49 INFO - 'PWD': '/builds/slave/test', 20:49:49 INFO - 'SHELL': '/bin/bash', 20:49:49 INFO - 'SHLVL': '1', 20:49:49 INFO - 'TERM': 'linux', 20:49:49 INFO - 'TMOUT': '86400', 20:49:49 INFO - 'USER': 'cltbld', 20:49:49 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:49 INFO - '_': '/tools/buildbot/bin/python'} 20:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:49 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)) 20:49:49 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)) 20:49:49 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)) 20:49:49 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)) 20:49:49 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)) 20:49:49 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)) 20:49:49 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)) 20:49:49 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)) 20:49:49 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)) 20:49:49 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:49:49 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 20:49:49 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 20:49:49 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 20:49:49 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 20:49:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 20:49:51 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)) 20:49:51 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)) 20:49:51 INFO - Installing collected packages: simplejson 20:49:51 INFO - Running setup.py install for simplejson 20:49:51 INFO - building 'simplejson._speedups' extension 20:49:51 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 20:49:52 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 20:49:52 INFO - Successfully installed simplejson 20:49:52 INFO - Cleaning up... 20:49:52 INFO - Return code: 0 20:49:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:49:52 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')]} 20:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:49:52 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')]} 20:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:49:52 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 0x1a8c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c54a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dfbb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, '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': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', '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 20:49:52 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 20:49:52 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 20:49:52 INFO - Using env: {'DISPLAY': ':0', 20:49:52 INFO - 'HOME': '/home/cltbld', 20:49:52 INFO - 'LANG': 'en_US.UTF-8', 20:49:52 INFO - 'LANGUAGE': 'en_US:en', 20:49:52 INFO - 'LOGNAME': 'cltbld', 20:49:52 INFO - 'MAIL': '/var/mail/cltbld', 20:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:49:52 INFO - 'MOZ_NO_REMOTE': '1', 20:49:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:49:52 INFO - 'NO_EM_RESTART': '1', 20:49:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:49:52 INFO - 'PWD': '/builds/slave/test', 20:49:52 INFO - 'SHELL': '/bin/bash', 20:49:52 INFO - 'SHLVL': '1', 20:49:52 INFO - 'TERM': 'linux', 20:49:52 INFO - 'TMOUT': '86400', 20:49:52 INFO - 'USER': 'cltbld', 20:49:52 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:49:52 INFO - '_': '/tools/buildbot/bin/python'} 20:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:49:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:49:52 INFO - Cleaning up... 20:49:52 INFO - Return code: 0 20:49:52 INFO - Running post-action listener: _resource_record_post_action 20:49:52 INFO - Running post-action listener: _start_resource_monitoring 20:49:52 INFO - Starting resource monitoring. 20:49:52 INFO - [mozharness: 2017-08-31 03:49:52.420752Z] Finished create-virtualenv step (success) 20:49:52 INFO - [mozharness: 2017-08-31 03:49:52.421275Z] Running install step. 20:49:52 INFO - Running pre-action listener: _resource_record_pre_action 20:49:52 INFO - Running main action method: install 20:49:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:49:52 INFO - Reading from file tmpfile_stdout 20:49:52 INFO - Detecting whether we're running mozinstall >=1.0... 20:49:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:49:52 INFO - Reading from file tmpfile_stdout 20:49:52 INFO - Output received: 20:49:52 INFO - Usage: mozinstall [options] installer 20:49:52 INFO - Options: 20:49:52 INFO - -h, --help show this help message and exit 20:49:52 INFO - -d DEST, --destination=DEST 20:49:52 INFO - Directory to install application into. [default: 20:49:52 INFO - "/builds/slave/test"] 20:49:52 INFO - --app=APP Application being installed. [default: firefox] 20:49:52 INFO - mkdir: /builds/slave/test/build/application 20:49:52 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'] 20:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:50:09 INFO - Reading from file tmpfile_stdout 20:50:09 INFO - Output received: 20:50:09 INFO - /builds/slave/test/build/application/firefox/firefox 20:50:09 INFO - Running post-action listener: _resource_record_post_action 20:50:09 INFO - [mozharness: 2017-08-31 03:50:09.223465Z] Finished install step (success) 20:50:09 INFO - [mozharness: 2017-08-31 03:50:09.223742Z] Running setup-mitmproxy step. 20:50:09 INFO - Running pre-action listener: _resource_record_pre_action 20:50:09 INFO - Running main action method: setup_mitmproxy 20:50:09 INFO - Skipping: mitmproxy is not required 20:50:09 INFO - Running post-action listener: _resource_record_post_action 20:50:09 INFO - [mozharness: 2017-08-31 03:50:09.224930Z] Finished setup-mitmproxy step (success) 20:50:09 INFO - [mozharness: 2017-08-31 03:50:09.225148Z] Running run-tests step. 20:50:09 INFO - Running pre-action listener: _resource_record_pre_action 20:50:09 INFO - Running pre-action listener: _set_gcov_prefix 20:50:09 INFO - Running main action method: run_tests 20:50:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:50:09 INFO - Python 2.7.3 20:50:09 INFO - Return code: 0 20:50:09 INFO - grabbing minidump binary from tooltool 20:50:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:09 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 0x1df8580>, '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 0x1df9480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df9910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:50:09 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 20:50:09 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 20:50:09 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 20:50:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:50:09 INFO - Return code: 0 20:50:09 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:50:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:50:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:50:09 INFO - ENV: RUST_BACKTRACE is now full 20:50:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:50:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:50:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 20:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --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 20:50:09 INFO - Using env: {'DISPLAY': ':0', 20:50:09 INFO - 'HOME': '/home/cltbld', 20:50:09 INFO - 'LANG': 'en_US.UTF-8', 20:50:09 INFO - 'LANGUAGE': 'en_US:en', 20:50:09 INFO - 'LOGNAME': 'cltbld', 20:50:09 INFO - 'MAIL': '/var/mail/cltbld', 20:50:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:50:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:09 INFO - 'MOZ_NO_REMOTE': '1', 20:50:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:50:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:09 INFO - 'NO_EM_RESTART': '1', 20:50:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:09 INFO - 'PWD': '/builds/slave/test', 20:50:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:50:09 INFO - 'RUST_BACKTRACE': 'full', 20:50:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:50:09 INFO - 'SHELL': '/bin/bash', 20:50:09 INFO - 'SHLVL': '1', 20:50:09 INFO - 'STYLO_FORCE_ENABLED': '1', 20:50:09 INFO - 'TERM': 'linux', 20:50:09 INFO - 'TMOUT': '86400', 20:50:09 INFO - 'USER': 'cltbld', 20:50:09 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:09 INFO - '_': '/tools/buildbot/bin/python'} 20:50:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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 20:50:09 INFO - mozversion application_buildid: 20170831025255 20:50:09 INFO - mozversion application_changeset: 04b6be50a2526c7a26a63715f441c47e1aa1f9be 20:50:09 INFO - mozversion application_display_name: Nightly 20:50:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:50:09 INFO - mozversion application_name: Firefox 20:50:09 INFO - mozversion application_remotingname: firefox 20:50:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:50:09 INFO - mozversion application_vendor: Mozilla 20:50:09 INFO - mozversion application_version: 57.0a1 20:50:09 INFO - mozversion platform_buildid: 20170831025255 20:50:09 INFO - mozversion platform_changeset: 04b6be50a2526c7a26a63715f441c47e1aa1f9be 20:50:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:50:09 INFO - mozversion platform_version: 57.0a1 20:50:09 INFO - using testdate: 1504151409 20:50:09 INFO - actual date: 1504151409 20:50:09 INFO - starting webserver on 'localhost:59244' 20:50:09 INFO - SUITE-START | Running 2 tests 20:50:09 INFO - TEST-START | dromaeo_css 20:50:09 INFO - Initialising browser for dromaeo_css test... 20:50:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59244/getInfo.html -profile /tmp/tmpQ_ohz9/profile 20:50:09 INFO - TEST-INFO | started process 7220 (/builds/slave/test/build/application/firefox/firefox http://localhost:59244/getInfo.html) 20:50:15 INFO - TEST-INFO | 7220: exit 0 20:50:15 INFO - Browser initialized. 20:50:15 INFO - Running cycle 1/1 for dromaeo_css test... 20:50:15 INFO - Using env: {'DISPLAY': ':0', 20:50:15 INFO - 'HOME': '/home/cltbld', 20:50:15 INFO - 'JSGC_DISABLE_POISONING': '1', 20:50:15 INFO - 'LANG': 'en_US.UTF-8', 20:50:15 INFO - 'LANGUAGE': 'en_US:en', 20:50:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:50:15 INFO - 'LOGNAME': 'cltbld', 20:50:15 INFO - 'MAIL': '/var/mail/cltbld', 20:50:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:50:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:50:15 INFO - 'MOZ_CRASHREPORTER': '1', 20:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:50:15 INFO - 'MOZ_NO_REMOTE': '1', 20:50:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:50:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:15 INFO - 'NO_EM_RESTART': '1', 20:50:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:15 INFO - 'PWD': '/builds/slave/test', 20:50:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:50:15 INFO - 'RUST_BACKTRACE': 'full', 20:50:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:50:15 INFO - 'SHELL': '/bin/bash', 20:50:15 INFO - 'SHLVL': '1', 20:50:15 INFO - 'STYLO_FORCE_ENABLED': '1', 20:50:15 INFO - 'TERM': 'linux', 20:50:15 INFO - 'TMOUT': '86400', 20:50:15 INFO - 'USER': 'cltbld', 20:50:15 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 20:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:15 INFO - '_': '/tools/buildbot/bin/python'} 20:50:15 INFO - TEST-INFO | started process 7412 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQ_ohz9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:50:16 INFO - PID 7412 | 20:50:16 INFO - PID 7412 | (/builds/slave/test/build/application/firefox/firefox:7463): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:50:16 INFO - PID 7412 | 20:50:26 INFO - PID 7412 | RSS: Main: 172343296 20:50:26 INFO - PID 7412 | 20:53:25 INFO - PID 7412 | Cycle 1(1): loaded http://localhost:59244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59244/tests/dromaeo/cssquery-ext.html) 20:53:26 INFO - PID 7412 | RSS: Main: 174026752 20:53:26 INFO - PID 7412 | 20:56:28 INFO - PID 7412 | Cycle 1(1): loaded http://localhost:59244/tests/dromaeo/cssquery-ext.html (next: http://localhost:59244/tests/dromaeo/cssquery-jquery.html) 20:56:28 INFO - PID 7412 | RSS: Main: 168857600 20:56:28 INFO - PID 7412 | 20:59:30 INFO - PID 7412 | Cycle 1(1): loaded http://localhost:59244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59244/tests/dromaeo/cssquery-mootools.html) 20:59:30 INFO - PID 7412 | RSS: Main: 172773376 20:59:30 INFO - PID 7412 | 21:02:32 INFO - PID 7412 | Cycle 1(1): loaded http://localhost:59244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59244/tests/dromaeo/cssquery-prototype.html) 21:02:33 INFO - PID 7412 | RSS: Main: 171773952 21:02:33 INFO - PID 7412 | 21:05:36 INFO - PID 7412 | Cycle 1(1): loaded http://localhost:59244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59244/tests/dromaeo/cssquery-yui.html) 21:05:37 INFO - PID 7412 | RSS: Main: 172818432 21:05:37 INFO - PID 7412 | 21:08:39 INFO - PID 7412 | Cycle 1(1): loaded http://localhost:59244/tests/dromaeo/cssquery-yui.html (next: http://localhost:59244/tests/dromaeo/cssquery-dojo.html) 21:08:39 INFO - PID 7412 | RSS: Main: 175394816 21:08:39 INFO - PID 7412 | 21:08:39 INFO - PID 7412 | __start_tp_report 21:08:39 INFO - PID 7412 | _x_x_mozilla_page_load 21:08:39 INFO - PID 7412 | _x_x_mozilla_page_load_details 21:08:39 INFO - PID 7412 | |i|pagename|runs| 21:08:39 INFO - PID 7412 | |0;dojo.html;3299.868005279789;3336.165958510374;3345.799252044879;3350.246194606214;3363.2937083212523;6412.070249813774;6480.675966201695;6487.156669632947;6560.639164845931;6576.112224849644;6283.654399008053;6344.143540622014;6432.807015789522;6484.870302593945;6524.739010439576;5891.440313170242;5927.762889484437;5932.644041357511;5957.434043765836;5979.252593425822;72.86313533930159;5751.511121554652;5856.355800861902;5897.056470964647;5904.7047647617665;5830.300363956331;5840.737166827503;5873.8825223495505;5922.615030023035;5932.940670593303;5832.737526811261;5848.473637372657;5921.91117133239;5932.851678708067;5936.821895343147;5791.449812267828;5793.102069179284;5877.617954832965;5887.941120588825;5904;6366.172397588331;6371.362863713638;6419.903701444482;6441.066045423434;6451.1936007999;6698.497612679069;6717.261101278856;6773;6796.830079248059;6802.387785099315;6839.384455398988;6893.620850853251;7006.229314775371;7014.193367762721;7034.718611255544;6435.259945106326;6495.545311828174;6518.087467754518;6524.9673751630935;6530.93469065308;7007.824804379931;7087.0786797717;7090.929090709027;7118.786436406916;7244;6697;6699.5310328276555;6713.060171575983;6717.664116794141;6720.932790672128;6783.59298442095;6788.796336109925;6795.694193761293;6805.625690587065;6865.347791959756;6920;6951.096357473597;6952.130983627047;6956.930430695729;6967.616781077089;6716.261211266757;6831.385175334244;6846.246912839583;6930.44556435484;7004.579725216503;5426.620136590401;5466.726663666801;5477.178423236546;5490.1764735289225;5497.47773961473;4729.976350118296;4777.8088876445245;4784.832530861403;4813.831515896927;4823.348847905558;5981.820545383645;5997.940020599825;6007.429294217042;6076.878462430726;6090.786822461193;6774.864502709918;6895.965520172467;6920.065791118235;6982.895256571155;7005.194402643761;5991.460768530853;6054.152418661391;6067.908981365283;6069.817905462844;6072.028475443908;5866.911996320058;5996.790112346047;6029.3669164737075;6030.03519436888;6042.1843051187525;6647.833804154935;6656.600603963778;6752.662366881636;6753.29090445506;6768.526203165731;6805.353491418307;6905.1713794345;6906.274841141809;6938.444924406136;6992.755253566101;6658.300878407891;6686.665666716742;6693.464522838259;6698.095757072733;6698.56459330142;6694.230163531256;6701.932980670131;6714.194296684429;6762.357576030269;6792.898106528505;6791.864162716817;6875.278095800069;6883;6955.061066756126;6980.825479363057;6555.2789193189665;6661.833454163685;6728.158980127484;6762.357576030269;6763.2898545652;6523.902141467976;6729;6783.830404239736;6802.319768023356;6817.659117044227;6491.05879647439;6541.411272985454;6613.371729685672;6620.702068406837;6630.834229144117;6839.965800171166;6849;6867.175938887365;6881.89677154833;6888.690008949709;6544.476441884733;6557.967210163919;6559.0817285579105;6664.366885145903;6667.49993750461 21:08:39 INFO - PID 7412 | |1;ext.html;768.1780494870487;822.153182222311;852.6845067325089;1153.2676750263586;1168.6552467022227;30392;40665.3900191497;41288.380674289874;41291.38062929059;41322.96692582687;42061.57938420615;42398;42425.575744242546;42440.36339454911;42445.15109697804;7704.653290601922;7857.371410287192;7860.842783144334;7860.921390786105;7862.646180921844;7684.500507467024;7787.493812902171;7791.142974272854;7791.493552919071;7795.532268063935;7590.127135379419;7770;7774.41691873109;7785;7796.571188584626;7699.538027718327;7764.145943946161;7764.922350776505;7767.067951845815;7775.805604859867;7579.393648508106;7601.049868766404;7684.039495063117;7684.577348245844;7691.461597688164;37270.62729372726;37302;37427.06432339186;37431.625683743085;37435.438468422995;3520.419130843408;3542.220711443478;3551.4140166872435;3554.7867127972404;3555.3244883472057;31969;31986;32000.839995800106;32030;32044.198895027577;5263.789448422059;5269.6311258211945;5286.60350473716;5320.201969704575;5333.306670132897;187594;188455;188483.99999999863;188867;189014;47034.76482617633;47076.76461617739;47262;47452.525474745504;47657;49870.75064624726;50438.74780626073;50451.747741261795;50528.242076368886;50531.49468505341;15735.606609834616;16042;16081.356745730274;16090.195490225677;16090.75863862043;13154.736905262032;13185.736285274432;13196.14225075367;13209.273489958145;13224;12480.625581232578;12725;13647.38586763598;13983.30083495821;14319.498817541544;5516.641418307797;5759.452851979056;5771.68255745938;5791.210821445149;5867.207926929896;1026.312370711625;1026.9127124194379;1030.2479190191202;1031.138998935897;1045.7699306152633;22370.77629223687;22794.544109117724;23007.194748183734;23051.53896922052;23159.7684023161;1131.660501849442;1134.7219931116765;1139.5214010115772;1153.5558809858264;1161.071143085528;12114.515419383126;12133.817992730115;12178.939105304416;12241.755164896831;12267.631971040882;216736;218448;218925;218943;220302;51334.22998655023;51392.229116563285;51485.7425712869;51644.22533661998;51669.48330516722;1460.364741337543;1490.3591455674164;1495.7606782914681;1502.3915314297508;1502.8121484814399;22146.114155433603;22336.441588960406;22516.662250065936;22670.206542770924;22879.88560057189;37427;37476.812615936746;37515.43726844154;37528.81235593805;37563.81218094001;193265;193396;193578;193696;193926;32510.512342315356;32721.018369448004;32859.50710739293;32890.50664240086;32940.17649558684;41691.37462937998;44519.332210016226;44589;44747;44827;32418;33065.50401744024;33128.66871331256;33705.494417583264;33962;32894.835525822215;32940.835295823366;33067.338653227285;33259.50110748389;33825;36753.816230918674;36869.26261474702;36970.630293697795;37257.441138382404;37348.439773402875;32018.35963280768;32693.509597356533;32712.182195445304;32728.50907236441;33142;23982.160624378062;24697.506049878542;24746.50506989886;25043.248702538953;25481.745182547937 21:08:39 INFO - PID 7412 | |2;jquery.html;568.3293713418168;573.9225204597383;574.5489790514447;580.5703779203391;590.9970450147748;22281;22348.553028939412;23055.538889222225;23100;23110.42223944398;25046.123385681505;25611.615825762583;25742.485150296983;25752.742472575315;25804.225873223786;6587.736490540373;6872.347127022925;6873;6879.036934829103;6880.759173428906;6569.343065693441;6835.111435513376;6858.0055891895645;6863.3479819417325;6863.965680171617;6806.7957961261245;6824.317568243186;6824.488163387741;6827.487938404614;6835.28229535899;6780.762673306411;6793.966030169842;6822.0449137120595;6822.351876571718;6824.908014717621;6320.115183874262;6322.74709011639;6323.494120470351;6326.525510586678;6337.651429171394;28583;29103.126906192847;29560.26099347512;29857.701422985927;30053;1999.7000449932482;2046.1201683276186;2046.2019812273224;2047.4164863013968;2048;19498.220071197135;20060.297889573794;20071.698924516142;20083.899580502002;20135.093920773455;2445.2297526279167;2484.7391023942405;2538.6826646669165;2542.872856357175;2544.4147845995526;134040;134219;137228;137295;137390.3130484351;14286.642833929234;14446.205458699662;14605.269736513132;14918.850811491746;15139.848601514064;22829.88585057064;23435.648465273036;23475.826208689497;23483.647745283834;23801.166959156348;10004.649837255662;10094.848577271347;10159.542820573091;10184.643537476153;10335.379877207391;9209.815803683889;9351.251899848132;9372.297077719062;9387.765305867408;9438;9116.908830911738;9338.393004454825;9496.952515237517;9663.226941844636;9670.661526846527;2767.3911739417294;2803.3832556837465;2821.407504424094;2888.62447881774;2957.8373189474787;962.3119469579144;964.609333220033;986.4451071724269;986.9703908882746;991.3556188477547;19545.915082427076;20343.796562034488;20382.184712611328;24296.906639200926;24848.254552363458;1046.7383154211448;1079.5897558927713;1089.6948854320804;1096.1395304685784;1098.5825386353295;10966.616168434066;11085.667429977113;11246.49390777417;11566.30602163856;11590.652280431432;561670;595042;595296;598811;607934;124028;124825.37587312005;126158;126688;127203;1233.0505510756668;1236.0358920042381;1269.2701696524534;1271.9173253738663;1280.225463594512;18272.90863545727;18888.811111889256;18928.43214703561;19061.237550497826;19113.259130052353;60453.69773151106;61414.07878881731;62424;63431.36568634255;64117;78818.21181788109;79298;79486.60256698911;79559;79600.60199698964;20194;20935.371938841858;21060.473488162304;21115.683264751347;21296.893515532323;23047.88476057609;23151;23168.76831231666;23331.533369332177;23492.76507234906;18534.536636584195;18576.81423185751;18682.626347473248;18766.812331877052;18785.718214226523;17110.230039648493;17121.400750973655;17720.291188352323;17961.461156165336;18257.45227643173;20136;20184.49538761543;20449.591008179457;20494.487637809176;20496.38510844677;20207.393778186677;20678.896605516875;20783.79216207879;20882.477938051063;20949.05729242218;18824.717629235845;18874;18880;18897.244110235435;19811 21:08:39 INFO - PID 7412 | |3;mootools.html;1358.436248956683;1393.1641015390767;1406.0438901546956;1421.6659085114995;1426.5007247463388;14647.04794188377;15135.394584216645;15274;15296.464623738171;15317.770233446507;15969.520914372559;16189.91905040476;16218.1080040598;16218.918905405457;16230.918845405757;2698.2579790557606;2731.4673638640475;2743.2044707034934;2755.2147637923194;2762.9171124866184;2398.5083057973075;2487.502499500098;2776.486350025242;2859.8570071496447;2877.899273525427;2765.806393552452;2773.3759904021535;2804.5793131030305;2805.088346287465;2807.8736456859388;2809.1853362524844;2809.7752179825566;2819.703931087237;2834.14975507347;2835.8298502089942;2686.435848471823;2730.781537476997;2743.533599288126;2743.903963361293;2747.2994386431333;14888.553343399715;15100.622484437867;15106.320215590325;15129.848701512954;15143.924280378527;7721.7683469496005;7887.645055972495;7955.920440795634;7986.960065199637;7998.920010799876;5313.149896016657;5443.891122177574;5494;5563.36021357545;5590.608657393984;1855.6195979824238;1889.3859495663964;1895.3650527073446;1928.807119288074;1932.0339830084959;26285.73714262871;26321;26391.604125938127;26407.471850563074;26461.603075953684;6649.667516624149;6721.294264102296;6762.932370676231;6990.755323563651;7022.648867556704;16579.088150151616;16769.32922683079;16779.91610041942;16820.158992050594;16833.579160521083;8050.879236811453;8050.959745201237;8052.355811535063;8062.475939063942;8065.193480652005;6204.7828326008375;7170.784876453715;7448.664810083559;7489.737859174903;7551.358134558514;4926.802927882844;5199.74001299941;5340.626156169031;5400.648957817729;5401.810936617199;1518.4457672949516;1553.502879078684;1568.7803707480962;1578.542222755411;1594.075436246974;927.5084205371164;937.484383589037;950.6150009246128;950.7385468996082;955.4744890310302;8702.825943481095;8826.249768769598;8859.512726800089;8870.955645221733;8930.86603700945;983.306768728045;1024.9795004100026;1025.6974192613197;1030.1810061001522;1030.6186710916859;4595.9080818383445;4776.355192049029;4861.683990540603;5483.287172667528;5535.6955367454375;20819.167233310498;20994.37016889496;21950.341489755334;21971.890140549516;21973.78026219749;21254.681179782314;21428;21472.24847130343;21475;21477.67783483249;11000;11182.384968826793;11308.208425410143;11328.093752499944;11394.430278486043;9220.585073671567;9284.071592840663;9320.860187097063;9339.673111441067;9399.577019034297;18441.26234950587;18985.90507047456;19035.238590456782;19157.521061973006;19512.121954512364;19706.1132249052;19713.605727885075;19872.801271987097;19931;20029.799702002794;11452.541898323974;11670.416479176178;11739.530418783153;11785.823212651645;11811;13828.723425531232;13987.93006035004;14002.57992260234;14017.789733153806;14017.929910350791;10745.892541074802;10843.240973131806;10862.293950893167;10969.50637221343;11010.724731881766;10477.738056548647;10537.315074520131;10549.894501055198;10720.571177152826;10895.836562451412;9279.86080208811;9435;9458.810823783624;9486.430814151174;9489.383190092622;7505.512141710771;7514.699412023458;7594.6202689866395;7595.392368610608;7625.046869141357;7118.323759242864;7212.567245965259;7345.632718363953;7350.448716346188;7378.4466165036765 21:08:39 INFO - PID 7412 | |4;prototype.html;494.36720997123695;500.1048123858294;530.7213712800782;530.9946900530995;537.188844844285;6407.585590176305;6421.967890160543;6446.355364463557;6551.508636852232;6618.56979296346;6214.596051256665;6275.466585340251;6327.7785277515295;6339.556231063827;6380.585261957981;5100.923486147711;5104.744762761866;5112.360954880622;5130.6152038597065;5175.404828444721;4991.425986011601;5067.275552468951;5102.1836506159025;5171.327727395434;5174.094533456649;5047.545720885119;5089.923651145235;5091.872703182432;5099.949000510003;5107.106256405133;5117.901660319251;5127.871803204913;5148.330717006784;5167.199084141964;5208.348956380452;4551.226291530448;4592.563706447882;4609.179374147227;4622.399088118523;4626.791794369262;6364.109024736541;6390.137331460286;6410.692150825699;6415;6436.356364363573;5825.475707186373;5913.2608423947;5923.289205295392;5926.940730592682;5926.970365148147;6787.913933770573;6817.454603631698;6817.965910170417;6826.24911259761;6829.351211634937;5027.924581131286;5043.394792624908;5049.974750126225;5055.165897626887;5059.392872855281;7168.462365322622;7191;7261.273872612749;7263.3462988330775;7303.30618591233;5130.948690513047;5140.820071297487;5152.433232344439;5179.948200518022;5185;5223.955208958224;5230.189320655304;5242.475752424727;5251.291075704808;5261.4738526147075;5880.382559831241;5898.351181370046;5901.29184497863;5941.792037278675;5947.613405128653;6698.4641228701585;6807.745159275453;6825.761098361533;6865.485088618274;6872.347127022925;1686.4181857259225;1729.2737050438845;1746.8689848261176;1803.5941913069717;1820.25369598464;1231.735176936963;1240.875912408752;1254.9058820588493;1260.464302671361;1270.7013851744832;5942.584019118706;5964.433378829004;5968.970155149196;5985.461308482258;5997.220361353084;6892.793216203522;6992.300769923068;7068.858622827514;7076.363127318566;7076.610786406694;6039.909401358983;6049.576529642971;6069.2716873975405;6071.605345652519;6101.511879049665;3741.9064523387133;3749.343864823645;3756.1736417988;3763.6048214937587;3772.1701225730294;6795.1845778506895;6808.285130061272;6818.386345228953;6825.419839313615;6832.487563432763;7151.53515021522;7223;7237.674304656303;7239.963800180965;7266.63666816657;6664.566803157779;6713.1944166700305;6743.932560674429;6761.188657361148;6837.589744615339;6922.965385173042;6984.301569843178;6995.545289556163;7010.422654902606;7059.858802823812;6820.62486563224;6886.48351373659;6891.345322194384;6896.689648965909;6974.58152510851;6814.284500127415;6832.658367081725;6860.627874425235;6863.931360686329;6864.897026544506;5687.20379146928;5698.629589076697;5713.971430142823;5729.885402292014;5743.396943321059;6711.6509221771985;6888.586684798928;6910.654467276717;6998.720051198099;7090.184628767758;6518;6714.563553369016;6782.69477873487;6807.761728339485;6812.761553345609;6751.729930802713;6780.559263647847;6783.389494945479;6811.489138314745;6817.602391509828;6674.0634120986615;6705.530612857053;6708.832279192864;6712;6777.390034896883;6793.320667933167;6832.350926661959;6832.93167068323;6871.690773915086;6901.654917254217;6613.173353330833;6628.403443690091;6636.568623039487;6648.700808463534;6689.498287628545 21:08:39 INFO - PID 7412 | |5;yui.html;892.6295587331258;894.3873446689017;915.8351496730586;920.8940971788246;921.9170274675278;18272.269109235633;18837.81162188378;19182.424527264186;19326.903365483155;19831;19703.704444433344;19971.900140499314;20379.694304585406;20379.898100509512;20552.38342849713;765.3532764813724;773.3117525402396;1005.5374527717258;1007.5012868630001;1008.1833714691087;997.1474389397033;998.0418797953973;999.5252255178805;1000.0449570660038;1001.5242759689162;1068.9198310126733;1069.6684027951321;1070.9892901071007;1071.9249652524327;1072.4316112460408;1062.925595208332;1062.9893701063006;1063.0326402973258;1063.1547919404093;1063.8670166229222;780.7657702689228;782.0459039971227;782.5734974438944;782.6399856066216;782.7103971530513;18408.815911840844;18765.0617469126;19014.52463688405;19499.902500487548;19613.80386196134;21163.941802909947;21176.364709058755;21556.137754489802;21643;21647;16859.409920652717;16863.747043794232;16931.830681693147;17064.914675426542;17304.221310041077;3733.21602463483;3765.3034188675183;3766.453864189677;3766.773993560395;3768.7550309229814;129048;130301.34849325787;130754;133777;133812.33093834567;16575.50273491773;16636.916815415847;16763.49709508717;17007.23467443968;17208.913955430144;30160.698393016228;30909.381812364078;31089.378212436077;31188;31323;7340.082489688789;8310.21052999964;8445.451045682012;8474.364422668325;8483.236508714246;4104.8152833122585;4345.239583073013;4391.868243952623;4412.801423935866;4419.0940857124215;5146.228065790161;5193.662411943212;5271.604629652791;5277.498637629419;5302.231176479466;1039.9012093851072;1062.7343164208949;1066.3921564708041;1066.829307310842;1071.8124328242527;687.8830598798216;701.5545128843221;709.5529816215751;719.04367191635;729.3435907683022;11474.36890970988;11491.885081149248;11516;11664.88335116638;11894.167408281337;692.7436848366136;695.3290075077473;699.6152116335995;711.2212127720138;715.6117132762424;5074.391073071255;5100.642954993189;5151.5878729701535;5159.819406320836;5213.791448342024;111187;112488;114423;116182.41908790571;120243.39878300727;94355.52822235845;94437;97708.51145744226;97777.02222977822;98966;80817;81860.59069704614;81929;82472;83256;9390.67132650354;9535.952320238355;9566.521673916415;9580.904190958001;9627.807443850943;40273;42104.57895421007;42441.575584244994;42937;43271.56728432801;53179.202311964575;72157;72587.63706181436;74847.62576187302;76006.23993759992;16048.679026419173;16662.75005874937;16818.747718784474;16876.24056917466;16904.07027613493;16669.416570419977;17155.970641761534;17349.913250434172;19127.904360478107;19524.21903123916;14562.19907905033;14652.926735366254;15144.78841692684;16149.838501615304;17999.910000449912;15921.283542240395;16007.67984640324;16679.332826686797;17282.481525554253;17449.214785334945;16135.838641613904;16455.25951332169;16849.842387542663;16930.492085237463;16989;11999.64001079969;12030.218035827644;12125.81811272814;12301.876981230072;12863.099583029098;12901.741965160832;13045.282509462071;13088.672783180496;13129.34353282313;13155.736885262433 21:08:39 INFO - PID 7412 | __end_tp_report 21:08:39 INFO - PID 7412 | __start_cc_report 21:08:39 INFO - PID 7412 | _x_x_mozilla_cycle_collect,373 21:08:39 INFO - PID 7412 | __end_cc_report 21:08:39 INFO - PID 7412 | __startTimestamp1504152519515__endTimestamp 21:08:39 INFO - PID 7412 | 21:08:39 INFO - PID 7412 | ------- Summary: start ------- 21:08:39 INFO - PID 7412 | Number of tests: 6 21:08:39 INFO - PID 7412 | 21:08:39 INFO - PID 7412 | [#0] dojo.html Cycles:165 Average:6310.45 Median:6620.70 stddev:884.81 (13.4%) stddev-sans-first:855.42 21:08:39 INFO - PID 7412 | Values: 3299.9 3336.2 3345.8 3350.2 3363.3 6412.1 6480.7 6487.2 6560.6 6576.1 6283.7 6344.1 6432.8 6484.9 6524.7 5891.4 5927.8 5932.6 5957.4 5979.3 72.9 5751.5 5856.4 5897.1 5904.7 5830.3 5840.7 5873.9 5922.6 5932.9 5832.7 5848.5 5921.9 5932.9 5936.8 5791.4 5793.1 5877.6 5887.9 5904.0 6366.2 6371.4 6419.9 6441.1 6451.2 6698.5 6717.3 6773.0 6796.8 6802.4 6839.4 6893.6 7006.2 7014.2 7034.7 6435.3 6495.5 6518.1 6525.0 6530.9 7007.8 7087.1 7090.9 7118.8 7244.0 6697.0 6699.5 6713.1 6717.7 6720.9 6783.6 6788.8 6795.7 6805.6 6865.3 6920.0 6951.1 6952.1 6956.9 6967.6 6716.3 6831.4 6846.2 6930.4 7004.6 5426.6 5466.7 5477.2 5490.2 5497.5 4730.0 4777.8 4784.8 4813.8 4823.3 5981.8 5997.9 6007.4 6076.9 6090.8 6774.9 6896.0 6920.1 6982.9 7005.2 5991.5 6054.2 6067.9 6069.8 6072.0 5866.9 5996.8 6029.4 6030.0 6042.2 6647.8 6656.6 6752.7 6753.3 6768.5 6805.4 6905.2 6906.3 6938.4 6992.8 6658.3 6686.7 6693.5 6698.1 6698.6 6694.2 6701.9 6714.2 6762.4 6792.9 6791.9 6875.3 6883.0 6955.1 6980.8 6555.3 6661.8 6728.2 6762.4 6763.3 6523.9 6729.0 6783.8 6802.3 6817.7 6491.1 6541.4 6613.4 6620.7 6630.8 6840.0 6849.0 6867.2 6881.9 6888.7 6544.5 6558.0 6559.1 6664.4 6667.5 21:08:39 INFO - PID 7412 | 21:08:39 INFO - PID 7412 | [#1] ext.html Cycles:180 Average:37057.60 Median:33765.25 stddev:51895.35 (153.7%) stddev-sans-first:51969.39 21:08:39 INFO - PID 7412 | Values: 768.2 822.2 852.7 1153.3 1168.7 30392.0 40665.4 41288.4 41291.4 41323.0 42061.6 42398.0 42425.6 42440.4 42445.2 7704.7 7857.4 7860.8 7860.9 7862.6 7684.5 7787.5 7791.1 7791.5 7795.5 7590.1 7770.0 7774.4 7785.0 7796.6 7699.5 7764.1 7764.9 7767.1 7775.8 7579.4 7601.0 7684.0 7684.6 7691.5 37270.6 37302.0 37427.1 37431.6 37435.4 3520.4 3542.2 3551.4 3554.8 3555.3 31969.0 31986.0 32000.8 32030.0 32044.2 5263.8 5269.6 5286.6 5320.2 5333.3 187594.0 188455.0 188484.0 188867.0 189014.0 47034.8 47076.8 47262.0 47452.5 47657.0 49870.8 50438.7 50451.7 50528.2 50531.5 15735.6 16042.0 16081.4 16090.2 16090.8 13154.7 13185.7 13196.1 13209.3 13224.0 12480.6 12725.0 13647.4 13983.3 14319.5 5516.6 5759.5 5771.7 5791.2 5867.2 1026.3 1026.9 1030.2 1031.1 1045.8 22370.8 22794.5 23007.2 23051.5 23159.8 1131.7 1134.7 1139.5 1153.6 1161.1 12114.5 12133.8 12178.9 12241.8 12267.6 216736.0 218448.0 218925.0 218943.0 220302.0 51334.2 51392.2 51485.7 51644.2 51669.5 1460.4 1490.4 1495.8 1502.4 1502.8 22146.1 22336.4 22516.7 22670.2 22879.9 37427.0 37476.8 37515.4 37528.8 37563.8 193265.0 193396.0 193578.0 193696.0 193926.0 32510.5 32721.0 32859.5 32890.5 32940.2 41691.4 44519.3 44589.0 44747.0 44827.0 32418.0 33065.5 33128.7 33705.5 33962.0 32894.8 32940.8 33067.3 33259.5 33825.0 36753.8 36869.3 36970.6 37257.4 37348.4 32018.4 32693.5 32712.2 32728.5 33142.0 23982.2 24697.5 24746.5 25043.2 25481.7 21:08:39 INFO - PID 7412 | 21:08:39 INFO - PID 7412 | [#2] jquery.html Cycles:180 Average:38821.52 Median:23159.88 stddev:98624.76 (425.8%) stddev-sans-first:98859.61 21:08:39 INFO - PID 7412 | Values: 568.3 573.9 574.5 580.6 591.0 22281.0 22348.6 23055.5 23100.0 23110.4 25046.1 25611.6 25742.5 25752.7 25804.2 6587.7 6872.3 6873.0 6879.0 6880.8 6569.3 6835.1 6858.0 6863.3 6864.0 6806.8 6824.3 6824.5 6827.5 6835.3 6780.8 6794.0 6822.0 6822.4 6824.9 6320.1 6322.7 6323.5 6326.5 6337.7 28583.0 29103.1 29560.3 29857.7 30053.0 1999.7 2046.1 2046.2 2047.4 2048.0 19498.2 20060.3 20071.7 20083.9 20135.1 2445.2 2484.7 2538.7 2542.9 2544.4 134040.0 134219.0 137228.0 137295.0 137390.3 14286.6 14446.2 14605.3 14918.9 15139.8 22829.9 23435.6 23475.8 23483.6 23801.2 10004.6 10094.8 10159.5 10184.6 10335.4 9209.8 9351.3 9372.3 9387.8 9438.0 9116.9 9338.4 9497.0 9663.2 9670.7 2767.4 2803.4 2821.4 2888.6 2957.8 962.3 964.6 986.4 987.0 991.4 19545.9 20343.8 20382.2 24296.9 24848.3 1046.7 1079.6 1089.7 1096.1 1098.6 10966.6 11085.7 11246.5 11566.3 11590.7 561670.0 595042.0 595296.0 598811.0 607934.0 124028.0 124825.4 126158.0 126688.0 127203.0 1233.1 1236.0 1269.3 1271.9 1280.2 18272.9 18888.8 18928.4 19061.2 19113.3 60453.7 61414.1 62424.0 63431.4 64117.0 78818.2 79298.0 79486.6 79559.0 79600.6 20194.0 20935.4 21060.5 21115.7 21296.9 23047.9 23151.0 23168.8 23331.5 23492.8 18534.5 18576.8 18682.6 18766.8 18785.7 17110.2 17121.4 17720.3 17961.5 18257.5 20136.0 20184.5 20449.6 20494.5 20496.4 20207.4 20678.9 20783.8 20882.5 20949.1 18824.7 18874.0 18880.0 18897.2 19811.0 21:08:39 INFO - PID 7412 | 21:08:39 INFO - PID 7412 | [#3] mootools.html Cycles:180 Average:9404.02 Median:14574.02 stddev:6687.11 (45.9%) stddev-sans-first:6678.55 21:08:39 INFO - PID 7412 | Values: 1358.4 1393.2 1406.0 1421.7 1426.5 14647.0 15135.4 15274.0 15296.5 15317.8 15969.5 16189.9 16218.1 16218.9 16230.9 2698.3 2731.5 2743.2 2755.2 2762.9 2398.5 2487.5 2776.5 2859.9 2877.9 2765.8 2773.4 2804.6 2805.1 2807.9 2809.2 2809.8 2819.7 2834.1 2835.8 2686.4 2730.8 2743.5 2743.9 2747.3 14888.6 15100.6 15106.3 15129.8 15143.9 7721.8 7887.6 7955.9 7987.0 7998.9 5313.1 5443.9 5494.0 5563.4 5590.6 1855.6 1889.4 1895.4 1928.8 1932.0 26285.7 26321.0 26391.6 26407.5 26461.6 6649.7 6721.3 6762.9 6990.8 7022.6 16579.1 16769.3 16779.9 16820.2 16833.6 8050.9 8051.0 8052.4 8062.5 8065.2 6204.8 7170.8 7448.7 7489.7 7551.4 4926.8 5199.7 5340.6 5400.6 5401.8 1518.4 1553.5 1568.8 1578.5 1594.1 927.5 937.5 950.6 950.7 955.5 8702.8 8826.2 8859.5 8871.0 8930.9 983.3 1025.0 1025.7 1030.2 1030.6 4595.9 4776.4 4861.7 5483.3 5535.7 20819.2 20994.4 21950.3 21971.9 21973.8 21254.7 21428.0 21472.2 21475.0 21477.7 11000.0 11182.4 11308.2 11328.1 11394.4 9220.6 9284.1 9320.9 9339.7 9399.6 18441.3 18985.9 19035.2 19157.5 19512.1 19706.1 19713.6 19872.8 19931.0 20029.8 11452.5 11670.4 11739.5 11785.8 11811.0 13828.7 13987.9 14002.6 14017.8 14017.9 10745.9 10843.2 10862.3 10969.5 11010.7 10477.7 10537.3 10549.9 10720.6 10895.8 9279.9 9435.0 9458.8 9486.4 9489.4 7505.5 7514.7 7594.6 7595.4 7625.0 7118.3 7212.6 7345.6 7350.4 7378.4 21:08:39 INFO - PID 7412 | 21:08:39 INFO - PID 7412 | [#4] prototype.html Cycles:180 Average:5739.40 Median:6385.36 stddev:1619.51 (25.4%) stddev-sans-first:1575.48 21:08:39 INFO - PID 7412 | Values: 494.4 500.1 530.7 531.0 537.2 6407.6 6422.0 6446.4 6551.5 6618.6 6214.6 6275.5 6327.8 6339.6 6380.6 5100.9 5104.7 5112.4 5130.6 5175.4 4991.4 5067.3 5102.2 5171.3 5174.1 5047.5 5089.9 5091.9 5099.9 5107.1 5117.9 5127.9 5148.3 5167.2 5208.3 4551.2 4592.6 4609.2 4622.4 4626.8 6364.1 6390.1 6410.7 6415.0 6436.4 5825.5 5913.3 5923.3 5926.9 5927.0 6787.9 6817.5 6818.0 6826.2 6829.4 5027.9 5043.4 5050.0 5055.2 5059.4 7168.5 7191.0 7261.3 7263.3 7303.3 5130.9 5140.8 5152.4 5179.9 5185.0 5224.0 5230.2 5242.5 5251.3 5261.5 5880.4 5898.4 5901.3 5941.8 5947.6 6698.5 6807.7 6825.8 6865.5 6872.3 1686.4 1729.3 1746.9 1803.6 1820.3 1231.7 1240.9 1254.9 1260.5 1270.7 5942.6 5964.4 5969.0 5985.5 5997.2 6892.8 6992.3 7068.9 7076.4 7076.6 6039.9 6049.6 6069.3 6071.6 6101.5 3741.9 3749.3 3756.2 3763.6 3772.2 6795.2 6808.3 6818.4 6825.4 6832.5 7151.5 7223.0 7237.7 7240.0 7266.6 6664.6 6713.2 6743.9 6761.2 6837.6 6923.0 6984.3 6995.5 7010.4 7059.9 6820.6 6886.5 6891.3 6896.7 6974.6 6814.3 6832.7 6860.6 6863.9 6864.9 5687.2 5698.6 5714.0 5729.9 5743.4 6711.7 6888.6 6910.7 6998.7 7090.2 6518.0 6714.6 6782.7 6807.8 6812.8 6751.7 6780.6 6783.4 6811.5 6817.6 6674.1 6705.5 6708.8 6712.0 6777.4 6793.3 6832.4 6832.9 6871.7 6901.7 6613.2 6628.4 6636.6 6648.7 6689.5 21:08:39 INFO - PID 7412 | 21:08:39 INFO - PID 7412 | [#5] yui.html Cycles:180 Average:23520.10 Median:19901.45 stddev:32855.02 (165.1%) stddev-sans-first:32903.25 21:08:39 INFO - PID 7412 | Values: 892.6 894.4 915.8 920.9 921.9 18272.3 18837.8 19182.4 19326.9 19831.0 19703.7 19971.9 20379.7 20379.9 20552.4 765.4 773.3 1005.5 1007.5 1008.2 997.1 998.0 999.5 1000.0 1001.5 1068.9 1069.7 1071.0 1071.9 1072.4 1062.9 1063.0 1063.0 1063.2 1063.9 780.8 782.0 782.6 782.6 782.7 18408.8 18765.1 19014.5 19499.9 19613.8 21163.9 21176.4 21556.1 21643.0 21647.0 16859.4 16863.7 16931.8 17064.9 17304.2 3733.2 3765.3 3766.5 3766.8 3768.8 129048.0 130301.3 130754.0 133777.0 133812.3 16575.5 16636.9 16763.5 17007.2 17208.9 30160.7 30909.4 31089.4 31188.0 31323.0 7340.1 8310.2 8445.5 8474.4 8483.2 4104.8 4345.2 4391.9 4412.8 4419.1 5146.2 5193.7 5271.6 5277.5 5302.2 1039.9 1062.7 1066.4 1066.8 1071.8 687.9 701.6 709.6 719.0 729.3 11474.4 11491.9 11516.0 11664.9 11894.2 692.7 695.3 699.6 711.2 715.6 5074.4 5100.6 5151.6 5159.8 5213.8 111187.0 112488.0 114423.0 116182.4 120243.4 94355.5 94437.0 97708.5 97777.0 98966.0 80817.0 81860.6 81929.0 82472.0 83256.0 9390.7 9536.0 9566.5 9580.9 9627.8 40273.0 42104.6 42441.6 42937.0 43271.6 53179.2 72157.0 72587.6 74847.6 76006.2 16048.7 16662.8 16818.7 16876.2 16904.1 16669.4 17156.0 17349.9 19127.9 19524.2 14562.2 14652.9 15144.8 16149.8 17999.9 15921.3 16007.7 16679.3 17282.5 17449.2 16135.8 16455.3 16849.8 16930.5 16989.0 11999.6 12030.2 12125.8 12301.9 12863.1 12901.7 13045.3 13088.7 13129.3 13155.7 21:08:39 INFO - PID 7412 | -------- Summary: end -------- 21:08:39 INFO - PID 7412 | 21:08:40 INFO - TEST-INFO | 7412: exit 0 21:08:40 INFO - TEST-OK | dromaeo_css | took 1110679ms 21:08:40 INFO - TEST-START | kraken 21:08:40 INFO - Initialising browser for kraken test... 21:08:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59244/getInfo.html -profile /tmp/tmpaUktp0/profile 21:08:40 INFO - TEST-INFO | started process 7789 (/builds/slave/test/build/application/firefox/firefox http://localhost:59244/getInfo.html) 21:08:45 INFO - TEST-INFO | 7789: exit 0 21:08:45 INFO - Browser initialized. 21:08:45 INFO - Running cycle 1/1 for kraken test... 21:08:45 INFO - Using env: {'DISPLAY': ':0', 21:08:45 INFO - 'HOME': '/home/cltbld', 21:08:45 INFO - 'JSGC_DISABLE_POISONING': '1', 21:08:45 INFO - 'LANG': 'en_US.UTF-8', 21:08:45 INFO - 'LANGUAGE': 'en_US:en', 21:08:45 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:08:45 INFO - 'LOGNAME': 'cltbld', 21:08:45 INFO - 'MAIL': '/var/mail/cltbld', 21:08:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:08:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:08:45 INFO - 'MOZ_CRASHREPORTER': '1', 21:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:08:45 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:08:45 INFO - 'MOZ_NO_REMOTE': '1', 21:08:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:08:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:08:45 INFO - 'NO_EM_RESTART': '1', 21:08:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:08:45 INFO - 'PWD': '/builds/slave/test', 21:08:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:08:45 INFO - 'RUST_BACKTRACE': 'full', 21:08:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:08:45 INFO - 'SHELL': '/bin/bash', 21:08:45 INFO - 'SHLVL': '1', 21:08:45 INFO - 'STYLO_FORCE_ENABLED': '1', 21:08:45 INFO - 'TERM': 'linux', 21:08:45 INFO - 'TMOUT': '86400', 21:08:45 INFO - 'USER': 'cltbld', 21:08:45 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444', 21:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:08:45 INFO - '_': '/tools/buildbot/bin/python'} 21:08:45 INFO - TEST-INFO | started process 7985 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaUktp0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:08:46 INFO - PID 7985 | 21:08:46 INFO - PID 7985 | (/builds/slave/test/build/application/firefox/firefox:8036): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:08:46 INFO - PID 7985 | 21:08:56 INFO - PID 7985 | RSS: Main: 172244992 21:08:56 INFO - PID 7985 | 21:08:59 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59244/tests/kraken/driver.html?testName=audio-beat-detection) 21:09:00 INFO - PID 7985 | RSS: Main: 178597888 21:09:00 INFO - PID 7985 | 21:09:03 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59244/tests/kraken/driver.html?testName=audio-dft) 21:09:03 INFO - PID 7985 | RSS: Main: 178782208 21:09:03 INFO - PID 7985 | 21:09:06 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59244/tests/kraken/driver.html?testName=audio-fft) 21:09:07 INFO - PID 7985 | RSS: Main: 172240896 21:09:07 INFO - PID 7985 | 21:09:09 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59244/tests/kraken/driver.html?testName=audio-oscillator) 21:09:10 INFO - PID 7985 | RSS: Main: 173367296 21:09:10 INFO - PID 7985 | 21:09:12 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:09:12 INFO - PID 7985 | RSS: Main: 173432832 21:09:12 INFO - PID 7985 | 21:09:16 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59244/tests/kraken/driver.html?testName=imaging-darkroom) 21:09:16 INFO - PID 7985 | RSS: Main: 176074752 21:09:16 INFO - PID 7985 | 21:09:20 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59244/tests/kraken/driver.html?testName=imaging-desaturate) 21:09:20 INFO - PID 7985 | RSS: Main: 175710208 21:09:20 INFO - PID 7985 | 21:09:23 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59244/tests/kraken/driver.html?testName=json-parse-financial) 21:09:24 INFO - PID 7985 | RSS: Main: 175570944 21:09:24 INFO - PID 7985 | 21:09:25 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:09:26 INFO - PID 7985 | RSS: Main: 175779840 21:09:26 INFO - PID 7985 | 21:09:28 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59244/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:09:28 INFO - PID 7985 | RSS: Main: 175804416 21:09:28 INFO - PID 7985 | 21:09:30 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:09:31 INFO - PID 7985 | RSS: Main: 172863488 21:09:31 INFO - PID 7985 | 21:09:34 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:09:34 INFO - PID 7985 | RSS: Main: 173232128 21:09:34 INFO - PID 7985 | 21:09:37 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:09:38 INFO - PID 7985 | RSS: Main: 172994560 21:09:38 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | Cycle 1(1): loaded http://localhost:59244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59244/tests/kraken/driver.html?testName=ai-astar) 21:09:40 INFO - PID 7985 | RSS: Main: 174485504 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | __start_tp_report 21:09:40 INFO - PID 7985 | _x_x_mozilla_page_load 21:09:40 INFO - PID 7985 | _x_x_mozilla_page_load_details 21:09:40 INFO - PID 7985 | |i|pagename|runs| 21:09:40 INFO - PID 7985 | |0;ai-astar;98;112;100;108;106;119;107;99;108;107 21:09:40 INFO - PID 7985 | |1;audio-beat-detection;140;120;140;184;120;146;143;118;168;116 21:09:40 INFO - PID 7985 | |2;audio-dft;163;163;162;162;163;162;160;165;168;162 21:09:40 INFO - PID 7985 | |3;audio-fft;75;73;74;168;74;74;74;88;99;74 21:09:40 INFO - PID 7985 | |4;audio-oscillator;78;79;134;80;78;79;128;79;79;139 21:09:40 INFO - PID 7985 | |5;imaging-gaussian-blur;117;113;114;113;114;114;115;114;114;114 21:09:40 INFO - PID 7985 | |6;imaging-darkroom;147;148;149;149;148;149;148;148;147;148 21:09:40 INFO - PID 7985 | |7;imaging-desaturate;93;93;93;98;94;94;94;94;94;94 21:09:40 INFO - PID 7985 | |8;json-parse-financial;52;54;52;52;53;53;52;52;55;52 21:09:40 INFO - PID 7985 | |9;json-stringify-tinderbox;39;39;38;38;38;38;38;38;38;38 21:09:40 INFO - PID 7985 | |10;stanford-crypto-aes;74;75;74;75;75;76;74;78;76;74 21:09:40 INFO - PID 7985 | |11;stanford-crypto-ccm;131;131;118;130;122;123;129;129;123;150 21:09:40 INFO - PID 7985 | |12;stanford-crypto-pbkdf2;175;172;185;178;171;175;170;170;173;169 21:09:40 INFO - PID 7985 | |13;stanford-crypto-sha256-iterative;65;63;72;65;66;64;66;69;65;63 21:09:40 INFO - PID 7985 | __end_tp_report 21:09:40 INFO - PID 7985 | __start_cc_report 21:09:40 INFO - PID 7985 | _x_x_mozilla_cycle_collect,965 21:09:40 INFO - PID 7985 | __end_cc_report 21:09:40 INFO - PID 7985 | __startTimestamp1504152580600__endTimestamp 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | ------- Summary: start ------- 21:09:40 INFO - PID 7985 | Number of tests: 14 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#0] ai-astar Cycles:10 Average:106.40 Median:110.00 stddev:6.35 (5.8%) stddev-sans-first:5.96 21:09:40 INFO - PID 7985 | Values: 98.0 112.0 100.0 108.0 106.0 119.0 107.0 99.0 108.0 107.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:141.50 stddev:22.66 (16.0%) stddev-sans-first:24.04 21:09:40 INFO - PID 7985 | Values: 140.0 120.0 140.0 184.0 120.0 146.0 143.0 118.0 168.0 116.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#2] audio-dft Cycles:10 Average:163.00 Median:163.00 stddev:2.16 (1.3%) stddev-sans-first:2.29 21:09:40 INFO - PID 7985 | Values: 163.0 163.0 162.0 162.0 163.0 162.0 160.0 165.0 168.0 162.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#3] audio-fft Cycles:10 Average:87.30 Median:74.00 stddev:29.61 (40.0%) stddev-sans-first:31.07 21:09:40 INFO - PID 7985 | Values: 75.0 73.0 74.0 168.0 74.0 74.0 74.0 88.0 99.0 74.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:79.00 stddev:26.61 (33.7%) stddev-sans-first:27.48 21:09:40 INFO - PID 7985 | Values: 78.0 79.0 134.0 80.0 78.0 79.0 128.0 79.0 79.0 139.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:1.14 (1.0%) stddev-sans-first:0.60 21:09:40 INFO - PID 7985 | Values: 117.0 113.0 114.0 113.0 114.0 114.0 115.0 114.0 114.0 114.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.74 (0.5%) stddev-sans-first:0.67 21:09:40 INFO - PID 7985 | Values: 147.0 148.0 149.0 149.0 148.0 149.0 148.0 148.0 147.0 148.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:1.45 (1.5%) stddev-sans-first:1.48 21:09:40 INFO - PID 7985 | Values: 93.0 93.0 93.0 98.0 94.0 94.0 94.0 94.0 94.0 94.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:52.50 stddev:1.06 (2.0%) stddev-sans-first:1.09 21:09:40 INFO - PID 7985 | Values: 52.0 54.0 52.0 52.0 53.0 53.0 52.0 52.0 55.0 52.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.33 21:09:40 INFO - PID 7985 | Values: 39.0 39.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#10] stanford-crypto-aes Cycles:10 Average:75.10 Median:75.00 stddev:1.29 (1.7%) stddev-sans-first:1.30 21:09:40 INFO - PID 7985 | Values: 74.0 75.0 74.0 75.0 75.0 76.0 74.0 78.0 76.0 74.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#11] stanford-crypto-ccm Cycles:10 Average:128.60 Median:129.50 stddev:8.76 (6.8%) stddev-sans-first:9.25 21:09:40 INFO - PID 7985 | Values: 131.0 131.0 118.0 130.0 122.0 123.0 129.0 129.0 123.0 150.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:174.00 stddev:4.83 (2.8%) stddev-sans-first:5.10 21:09:40 INFO - PID 7985 | Values: 175.0 172.0 185.0 178.0 171.0 175.0 170.0 170.0 173.0 169.0 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.50 stddev:2.78 (4.2%) stddev-sans-first:2.93 21:09:40 INFO - PID 7985 | Values: 65.0 63.0 72.0 65.0 66.0 64.0 66.0 69.0 65.0 63.0 21:09:40 INFO - PID 7985 | -------- Summary: end -------- 21:09:40 INFO - PID 7985 | 21:09:40 INFO - PID 7985 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:09:41 INFO - TEST-INFO | 7985: exit 0 21:09:41 INFO - TEST-OK | kraken | took 61026ms 21:09:41 INFO - SUITE-END | took 1171s 21:09:41 INFO - Completed test suite (00:19:32) 21:09:41 INFO - javascript benchmark 21:09:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8692.625046394198, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.868005279789, 3336.165958510374, 3345.799252044879, 3350.246194606214, 3363.2937083212523, 6412.070249813774, 6480.675966201695, 6487.156669632947, 6560.639164845931, 6576.112224849644, 6283.654399008053, 6344.143540622014, 6432.807015789522, 6484.870302593945, 6524.739010439576, 5891.440313170242, 5927.762889484437, 5932.644041357511, 5957.434043765836, 5979.252593425822, 72.86313533930159, 5751.511121554652, 5856.355800861902, 5897.056470964647, 5904.7047647617665, 5830.300363956331, 5840.737166827503, 5873.8825223495505, 5922.615030023035, 5932.940670593303, 5832.737526811261, 5848.473637372657, 5921.91117133239, 5932.851678708067, 5936.821895343147, 5791.449812267828, 5793.102069179284, 5877.617954832965, 5887.941120588825, 5904.0, 6366.172397588331, 6371.362863713638, 6419.903701444482, 6441.066045423434, 6451.1936007999, 6698.497612679069, 6717.261101278856, 6773.0, 6796.830079248059, 6802.387785099315, 6839.384455398988, 6893.620850853251, 7006.229314775371, 7014.193367762721, 7034.718611255544, 6435.259945106326, 6495.545311828174, 6518.087467754518, 6524.9673751630935, 6530.93469065308, 7007.824804379931, 7087.0786797717, 7090.929090709027, 7118.786436406916, 7244.0, 6697.0, 6699.5310328276555, 6713.060171575983, 6717.664116794141, 6720.932790672128, 6783.59298442095, 6788.796336109925, 6795.694193761293, 6805.625690587065, 6865.347791959756, 6920.0, 6951.096357473597, 6952.130983627047, 6956.930430695729, 6967.616781077089, 6716.261211266757, 6831.385175334244, 6846.246912839583, 6930.44556435484, 7004.579725216503, 5426.620136590401, 5466.726663666801, 5477.178423236546, 5490.1764735289225, 5497.47773961473, 4729.976350118296, 4777.8088876445245, 4784.832530861403, 4813.831515896927, 4823.348847905558, 5981.820545383645, 5997.940020599825, 6007.429294217042, 6076.878462430726, 6090.786822461193, 6774.864502709918, 6895.965520172467, 6920.065791118235, 6982.895256571155, 7005.194402643761, 5991.460768530853, 6054.152418661391, 6067.908981365283, 6069.817905462844, 6072.028475443908, 5866.911996320058, 5996.790112346047, 6029.3669164737075, 6030.03519436888, 6042.1843051187525, 6647.833804154935, 6656.600603963778, 6752.662366881636, 6753.29090445506, 6768.526203165731, 6805.353491418307, 6905.1713794345, 6906.274841141809, 6938.444924406136, 6992.755253566101, 6658.300878407891, 6686.665666716742, 6693.464522838259, 6698.095757072733, 6698.56459330142, 6694.230163531256, 6701.932980670131, 6714.194296684429, 6762.357576030269, 6792.898106528505, 6791.864162716817, 6875.278095800069, 6883.0, 6955.061066756126, 6980.825479363057, 6555.2789193189665, 6661.833454163685, 6728.158980127484, 6762.357576030269, 6763.2898545652, 6523.902141467976, 6729.0, 6783.830404239736, 6802.319768023356, 6817.659117044227, 6491.05879647439, 6541.411272985454, 6613.371729685672, 6620.702068406837, 6630.834229144117, 6839.965800171166, 6849.0, 6867.175938887365, 6881.89677154833, 6888.690008949709, 6544.476441884733, 6557.967210163919, 6559.0817285579105, 6664.366885145903, 6667.49993750461], "value": 6249.649927986015, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.1780494870487, 822.153182222311, 852.6845067325089, 1153.2676750263586, 1168.6552467022227, 30392.0, 40665.3900191497, 41288.380674289874, 41291.38062929059, 41322.96692582687, 42061.57938420615, 42398.0, 42425.575744242546, 42440.36339454911, 42445.15109697804, 7704.653290601922, 7857.371410287192, 7860.842783144334, 7860.921390786105, 7862.646180921844, 7684.500507467024, 7787.493812902171, 7791.142974272854, 7791.493552919071, 7795.532268063935, 7590.127135379419, 7770.0, 7774.41691873109, 7785.0, 7796.571188584626, 7699.538027718327, 7764.145943946161, 7764.922350776505, 7767.067951845815, 7775.805604859867, 7579.393648508106, 7601.049868766404, 7684.039495063117, 7684.577348245844, 7691.461597688164, 37270.62729372726, 37302.0, 37427.06432339186, 37431.625683743085, 37435.438468422995, 3520.419130843408, 3542.220711443478, 3551.4140166872435, 3554.7867127972404, 3555.3244883472057, 31969.0, 31986.0, 32000.839995800106, 32030.0, 32044.198895027577, 5263.789448422059, 5269.6311258211945, 5286.60350473716, 5320.201969704575, 5333.306670132897, 187594.0, 188455.0, 188483.99999999863, 188867.0, 189014.0, 47034.76482617633, 47076.76461617739, 47262.0, 47452.525474745504, 47657.0, 49870.75064624726, 50438.74780626073, 50451.747741261795, 50528.242076368886, 50531.49468505341, 15735.606609834616, 16042.0, 16081.356745730274, 16090.195490225677, 16090.75863862043, 13154.736905262032, 13185.736285274432, 13196.14225075367, 13209.273489958145, 13224.0, 12480.625581232578, 12725.0, 13647.38586763598, 13983.30083495821, 14319.498817541544, 5516.641418307797, 5759.452851979056, 5771.68255745938, 5791.210821445149, 5867.207926929896, 1026.312370711625, 1026.9127124194379, 1030.2479190191202, 1031.138998935897, 1045.7699306152633, 22370.77629223687, 22794.544109117724, 23007.194748183734, 23051.53896922052, 23159.7684023161, 1131.660501849442, 1134.7219931116765, 1139.5214010115772, 1153.5558809858264, 1161.071143085528, 12114.515419383126, 12133.817992730115, 12178.939105304416, 12241.755164896831, 12267.631971040882, 216736.0, 218448.0, 218925.0, 218943.0, 220302.0, 51334.22998655023, 51392.229116563285, 51485.7425712869, 51644.22533661998, 51669.48330516722, 1460.364741337543, 1490.3591455674164, 1495.7606782914681, 1502.3915314297508, 1502.8121484814399, 22146.114155433603, 22336.441588960406, 22516.662250065936, 22670.206542770924, 22879.88560057189, 37427.0, 37476.812615936746, 37515.43726844154, 37528.81235593805, 37563.81218094001, 193265.0, 193396.0, 193578.0, 193696.0, 193926.0, 32510.512342315356, 32721.018369448004, 32859.50710739293, 32890.50664240086, 32940.17649558684, 41691.37462937998, 44519.332210016226, 44589.0, 44747.0, 44827.0, 32418.0, 33065.50401744024, 33128.66871331256, 33705.494417583264, 33962.0, 32894.835525822215, 32940.835295823366, 33067.338653227285, 33259.50110748389, 33825.0, 36753.816230918674, 36869.26261474702, 36970.630293697795, 37257.441138382404, 37348.439773402875, 32018.35963280768, 32693.509597356533, 32712.182195445304, 32728.50907236441, 33142.0, 23982.160624378062, 24697.506049878542, 24746.50506989886, 25043.248702538953, 25481.745182547937], "value": 17218.049338807923, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.3293713418168, 573.9225204597383, 574.5489790514447, 580.5703779203391, 590.9970450147748, 22281.0, 22348.553028939412, 23055.538889222225, 23100.0, 23110.42223944398, 25046.123385681505, 25611.615825762583, 25742.485150296983, 25752.742472575315, 25804.225873223786, 6587.736490540373, 6872.347127022925, 6873.0, 6879.036934829103, 6880.759173428906, 6569.343065693441, 6835.111435513376, 6858.0055891895645, 6863.3479819417325, 6863.965680171617, 6806.7957961261245, 6824.317568243186, 6824.488163387741, 6827.487938404614, 6835.28229535899, 6780.762673306411, 6793.966030169842, 6822.0449137120595, 6822.351876571718, 6824.908014717621, 6320.115183874262, 6322.74709011639, 6323.494120470351, 6326.525510586678, 6337.651429171394, 28583.0, 29103.126906192847, 29560.26099347512, 29857.701422985927, 30053.0, 1999.7000449932482, 2046.1201683276186, 2046.2019812273224, 2047.4164863013968, 2048.0, 19498.220071197135, 20060.297889573794, 20071.698924516142, 20083.899580502002, 20135.093920773455, 2445.2297526279167, 2484.7391023942405, 2538.6826646669165, 2542.872856357175, 2544.4147845995526, 134040.0, 134219.0, 137228.0, 137295.0, 137390.3130484351, 14286.642833929234, 14446.205458699662, 14605.269736513132, 14918.850811491746, 15139.848601514064, 22829.88585057064, 23435.648465273036, 23475.826208689497, 23483.647745283834, 23801.166959156348, 10004.649837255662, 10094.848577271347, 10159.542820573091, 10184.643537476153, 10335.379877207391, 9209.815803683889, 9351.251899848132, 9372.297077719062, 9387.765305867408, 9438.0, 9116.908830911738, 9338.393004454825, 9496.952515237517, 9663.226941844636, 9670.661526846527, 2767.3911739417294, 2803.3832556837465, 2821.407504424094, 2888.62447881774, 2957.8373189474787, 962.3119469579144, 964.609333220033, 986.4451071724269, 986.9703908882746, 991.3556188477547, 19545.915082427076, 20343.796562034488, 20382.184712611328, 24296.906639200926, 24848.254552363458, 1046.7383154211448, 1079.5897558927713, 1089.6948854320804, 1096.1395304685784, 1098.5825386353295, 10966.616168434066, 11085.667429977113, 11246.49390777417, 11566.30602163856, 11590.652280431432, 561670.0, 595042.0, 595296.0, 598811.0, 607934.0, 124028.0, 124825.37587312005, 126158.0, 126688.0, 127203.0, 1233.0505510756668, 1236.0358920042381, 1269.2701696524534, 1271.9173253738663, 1280.225463594512, 18272.90863545727, 18888.811111889256, 18928.43214703561, 19061.237550497826, 19113.259130052353, 60453.69773151106, 61414.07878881731, 62424.0, 63431.36568634255, 64117.0, 78818.21181788109, 79298.0, 79486.60256698911, 79559.0, 79600.60199698964, 20194.0, 20935.371938841858, 21060.473488162304, 21115.683264751347, 21296.893515532323, 23047.88476057609, 23151.0, 23168.76831231666, 23331.533369332177, 23492.76507234906, 18534.536636584195, 18576.81423185751, 18682.626347473248, 18766.812331877052, 18785.718214226523, 17110.230039648493, 17121.400750973655, 17720.291188352323, 17961.461156165336, 18257.45227643173, 20136.0, 20184.49538761543, 20449.591008179457, 20494.487637809176, 20496.38510844677, 20207.393778186677, 20678.896605516875, 20783.79216207879, 20882.477938051063, 20949.05729242218, 18824.717629235845, 18874.0, 18880.0, 18897.244110235435, 19811.0], "value": 12659.748170242432, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1358.436248956683, 1393.1641015390767, 1406.0438901546956, 1421.6659085114995, 1426.5007247463388, 14647.04794188377, 15135.394584216645, 15274.0, 15296.464623738171, 15317.770233446507, 15969.520914372559, 16189.91905040476, 16218.1080040598, 16218.918905405457, 16230.918845405757, 2698.2579790557606, 2731.4673638640475, 2743.2044707034934, 2755.2147637923194, 2762.9171124866184, 2398.5083057973075, 2487.502499500098, 2776.486350025242, 2859.8570071496447, 2877.899273525427, 2765.806393552452, 2773.3759904021535, 2804.5793131030305, 2805.088346287465, 2807.8736456859388, 2809.1853362524844, 2809.7752179825566, 2819.703931087237, 2834.14975507347, 2835.8298502089942, 2686.435848471823, 2730.781537476997, 2743.533599288126, 2743.903963361293, 2747.2994386431333, 14888.553343399715, 15100.622484437867, 15106.320215590325, 15129.848701512954, 15143.924280378527, 7721.7683469496005, 7887.645055972495, 7955.920440795634, 7986.960065199637, 7998.920010799876, 5313.149896016657, 5443.891122177574, 5494.0, 5563.36021357545, 5590.608657393984, 1855.6195979824238, 1889.3859495663964, 1895.3650527073446, 1928.807119288074, 1932.0339830084959, 26285.73714262871, 26321.0, 26391.604125938127, 26407.471850563074, 26461.603075953684, 6649.667516624149, 6721.294264102296, 6762.932370676231, 6990.755323563651, 7022.648867556704, 16579.088150151616, 16769.32922683079, 16779.91610041942, 16820.158992050594, 16833.579160521083, 8050.879236811453, 8050.959745201237, 8052.355811535063, 8062.475939063942, 8065.193480652005, 6204.7828326008375, 7170.784876453715, 7448.664810083559, 7489.737859174903, 7551.358134558514, 4926.802927882844, 5199.74001299941, 5340.626156169031, 5400.648957817729, 5401.810936617199, 1518.4457672949516, 1553.502879078684, 1568.7803707480962, 1578.542222755411, 1594.075436246974, 927.5084205371164, 937.484383589037, 950.6150009246128, 950.7385468996082, 955.4744890310302, 8702.825943481095, 8826.249768769598, 8859.512726800089, 8870.955645221733, 8930.86603700945, 983.306768728045, 1024.9795004100026, 1025.6974192613197, 1030.1810061001522, 1030.6186710916859, 4595.9080818383445, 4776.355192049029, 4861.683990540603, 5483.287172667528, 5535.6955367454375, 20819.167233310498, 20994.37016889496, 21950.341489755334, 21971.890140549516, 21973.78026219749, 21254.681179782314, 21428.0, 21472.24847130343, 21475.0, 21477.67783483249, 11000.0, 11182.384968826793, 11308.208425410143, 11328.093752499944, 11394.430278486043, 9220.585073671567, 9284.071592840663, 9320.860187097063, 9339.673111441067, 9399.577019034297, 18441.26234950587, 18985.90507047456, 19035.238590456782, 19157.521061973006, 19512.121954512364, 19706.1132249052, 19713.605727885075, 19872.801271987097, 19931.0, 20029.799702002794, 11452.541898323974, 11670.416479176178, 11739.530418783153, 11785.823212651645, 11811.0, 13828.723425531232, 13987.93006035004, 14002.57992260234, 14017.789733153806, 14017.929910350791, 10745.892541074802, 10843.240973131806, 10862.293950893167, 10969.50637221343, 11010.724731881766, 10477.738056548647, 10537.315074520131, 10549.894501055198, 10720.571177152826, 10895.836562451412, 9279.86080208811, 9435.0, 9458.810823783624, 9486.430814151174, 9489.383190092622, 7505.512141710771, 7514.699412023458, 7594.6202689866395, 7595.392368610608, 7625.046869141357, 7118.323759242864, 7212.567245965259, 7345.632718363953, 7350.448716346188, 7378.4466165036765], "value": 6757.006512997474, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.36720997123695, 500.1048123858294, 530.7213712800782, 530.9946900530995, 537.188844844285, 6407.585590176305, 6421.967890160543, 6446.355364463557, 6551.508636852232, 6618.56979296346, 6214.596051256665, 6275.466585340251, 6327.7785277515295, 6339.556231063827, 6380.585261957981, 5100.923486147711, 5104.744762761866, 5112.360954880622, 5130.6152038597065, 5175.404828444721, 4991.425986011601, 5067.275552468951, 5102.1836506159025, 5171.327727395434, 5174.094533456649, 5047.545720885119, 5089.923651145235, 5091.872703182432, 5099.949000510003, 5107.106256405133, 5117.901660319251, 5127.871803204913, 5148.330717006784, 5167.199084141964, 5208.348956380452, 4551.226291530448, 4592.563706447882, 4609.179374147227, 4622.399088118523, 4626.791794369262, 6364.109024736541, 6390.137331460286, 6410.692150825699, 6415.0, 6436.356364363573, 5825.475707186373, 5913.2608423947, 5923.289205295392, 5926.940730592682, 5926.970365148147, 6787.913933770573, 6817.454603631698, 6817.965910170417, 6826.24911259761, 6829.351211634937, 5027.924581131286, 5043.394792624908, 5049.974750126225, 5055.165897626887, 5059.392872855281, 7168.462365322622, 7191.0, 7261.273872612749, 7263.3462988330775, 7303.30618591233, 5130.948690513047, 5140.820071297487, 5152.433232344439, 5179.948200518022, 5185.0, 5223.955208958224, 5230.189320655304, 5242.475752424727, 5251.291075704808, 5261.4738526147075, 5880.382559831241, 5898.351181370046, 5901.29184497863, 5941.792037278675, 5947.613405128653, 6698.4641228701585, 6807.745159275453, 6825.761098361533, 6865.485088618274, 6872.347127022925, 1686.4181857259225, 1729.2737050438845, 1746.8689848261176, 1803.5941913069717, 1820.25369598464, 1231.735176936963, 1240.875912408752, 1254.9058820588493, 1260.464302671361, 1270.7013851744832, 5942.584019118706, 5964.433378829004, 5968.970155149196, 5985.461308482258, 5997.220361353084, 6892.793216203522, 6992.300769923068, 7068.858622827514, 7076.363127318566, 7076.610786406694, 6039.909401358983, 6049.576529642971, 6069.2716873975405, 6071.605345652519, 6101.511879049665, 3741.9064523387133, 3749.343864823645, 3756.1736417988, 3763.6048214937587, 3772.1701225730294, 6795.1845778506895, 6808.285130061272, 6818.386345228953, 6825.419839313615, 6832.487563432763, 7151.53515021522, 7223.0, 7237.674304656303, 7239.963800180965, 7266.63666816657, 6664.566803157779, 6713.1944166700305, 6743.932560674429, 6761.188657361148, 6837.589744615339, 6922.965385173042, 6984.301569843178, 6995.545289556163, 7010.422654902606, 7059.858802823812, 6820.62486563224, 6886.48351373659, 6891.345322194384, 6896.689648965909, 6974.58152510851, 6814.284500127415, 6832.658367081725, 6860.627874425235, 6863.931360686329, 6864.897026544506, 5687.20379146928, 5698.629589076697, 5713.971430142823, 5729.885402292014, 5743.396943321059, 6711.6509221771985, 6888.586684798928, 6910.654467276717, 6998.720051198099, 7090.184628767758, 6518.0, 6714.563553369016, 6782.69477873487, 6807.761728339485, 6812.761553345609, 6751.729930802713, 6780.559263647847, 6783.389494945479, 6811.489138314745, 6817.602391509828, 6674.0634120986615, 6705.530612857053, 6708.832279192864, 6712.0, 6777.390034896883, 6793.320667933167, 6832.350926661959, 6832.93167068323, 6871.690773915086, 6901.654917254217, 6613.173353330833, 6628.403443690091, 6636.568623039487, 6648.700808463534, 6689.498287628545], "value": 5255.348497325665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [892.6295587331258, 894.3873446689017, 915.8351496730586, 920.8940971788246, 921.9170274675278, 18272.269109235633, 18837.81162188378, 19182.424527264186, 19326.903365483155, 19831.0, 19703.704444433344, 19971.900140499314, 20379.694304585406, 20379.898100509512, 20552.38342849713, 765.3532764813724, 773.3117525402396, 1005.5374527717258, 1007.5012868630001, 1008.1833714691087, 997.1474389397033, 998.0418797953973, 999.5252255178805, 1000.0449570660038, 1001.5242759689162, 1068.9198310126733, 1069.6684027951321, 1070.9892901071007, 1071.9249652524327, 1072.4316112460408, 1062.925595208332, 1062.9893701063006, 1063.0326402973258, 1063.1547919404093, 1063.8670166229222, 780.7657702689228, 782.0459039971227, 782.5734974438944, 782.6399856066216, 782.7103971530513, 18408.815911840844, 18765.0617469126, 19014.52463688405, 19499.902500487548, 19613.80386196134, 21163.941802909947, 21176.364709058755, 21556.137754489802, 21643.0, 21647.0, 16859.409920652717, 16863.747043794232, 16931.830681693147, 17064.914675426542, 17304.221310041077, 3733.21602463483, 3765.3034188675183, 3766.453864189677, 3766.773993560395, 3768.7550309229814, 129048.0, 130301.34849325787, 130754.0, 133777.0, 133812.33093834567, 16575.50273491773, 16636.916815415847, 16763.49709508717, 17007.23467443968, 17208.913955430144, 30160.698393016228, 30909.381812364078, 31089.378212436077, 31188.0, 31323.0, 7340.082489688789, 8310.21052999964, 8445.451045682012, 8474.364422668325, 8483.236508714246, 4104.8152833122585, 4345.239583073013, 4391.868243952623, 4412.801423935866, 4419.0940857124215, 5146.228065790161, 5193.662411943212, 5271.604629652791, 5277.498637629419, 5302.231176479466, 1039.9012093851072, 1062.7343164208949, 1066.3921564708041, 1066.829307310842, 1071.8124328242527, 687.8830598798216, 701.5545128843221, 709.5529816215751, 719.04367191635, 729.3435907683022, 11474.36890970988, 11491.885081149248, 11516.0, 11664.88335116638, 11894.167408281337, 692.7436848366136, 695.3290075077473, 699.6152116335995, 711.2212127720138, 715.6117132762424, 5074.391073071255, 5100.642954993189, 5151.5878729701535, 5159.819406320836, 5213.791448342024, 111187.0, 112488.0, 114423.0, 116182.41908790571, 120243.39878300727, 94355.52822235845, 94437.0, 97708.51145744226, 97777.02222977822, 98966.0, 80817.0, 81860.59069704614, 81929.0, 82472.0, 83256.0, 9390.67132650354, 9535.952320238355, 9566.521673916415, 9580.904190958001, 9627.807443850943, 40273.0, 42104.57895421007, 42441.575584244994, 42937.0, 43271.56728432801, 53179.202311964575, 72157.0, 72587.63706181436, 74847.62576187302, 76006.23993759992, 16048.679026419173, 16662.75005874937, 16818.747718784474, 16876.24056917466, 16904.07027613493, 16669.416570419977, 17155.970641761534, 17349.913250434172, 19127.904360478107, 19524.21903123916, 14562.19907905033, 14652.926735366254, 15144.78841692684, 16149.838501615304, 17999.910000449912, 15921.283542240395, 16007.67984640324, 16679.332826686797, 17282.481525554253, 17449.214785334945, 16135.838641613904, 16455.25951332169, 16849.842387542663, 16930.492085237463, 16989.0, 11999.64001079969, 12030.218035827644, 12125.81811272814, 12301.876981230072, 12863.099583029098, 12901.741965160832, 13045.282509462071, 13088.672783180496, 13129.34353282313, 13155.736885262433], "value": 8917.851987373426, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 112.0, 100.0, 108.0, 106.0, 119.0, 107.0, 99.0, 108.0, 107.0], "value": 106.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 120.0, 140.0, 184.0, 120.0, 146.0, 143.0, 118.0, 168.0, 116.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 162.0, 163.0, 162.0, 160.0, 165.0, 168.0, 162.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 168.0, 74.0, 74.0, 74.0, 88.0, 99.0, 74.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 134.0, 80.0, 78.0, 79.0, 128.0, 79.0, 79.0, 139.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 114.0, 113.0, 114.0, 114.0, 115.0, 114.0, 114.0, 114.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 149.0, 149.0, 148.0, 149.0, 148.0, 148.0, 147.0, 148.0], "value": 148.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 98.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 54.0, 52.0, 52.0, 53.0, 53.0, 52.0, 52.0, 55.0, 52.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 75.0, 75.0, 76.0, 74.0, 78.0, 76.0, 74.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 131.0, 118.0, 130.0, 122.0, 123.0, 129.0, 129.0, 123.0, 150.0], "value": 128.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 172.0, 185.0, 178.0, 171.0, 175.0, 170.0, 170.0, 173.0, 169.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 72.0, 65.0, 66.0, 64.0, 66.0, 69.0, 65.0, 63.0], "value": 65.8, "unit": "score"}]}]} 21:09:41 INFO - Return code: 0 21:09:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:09:41 INFO - # TBPL SUCCESS # 21:09:41 INFO - Running post-action listener: _package_coverage_data 21:09:41 INFO - Running post-action listener: _resource_record_post_action 21:09:41 INFO - [mozharness: 2017-08-31 04:09:41.896064Z] Finished run-tests step (success) 21:09:41 INFO - Running post-run listener: _resource_record_post_run 21:09:41 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 397930496; Read time: 24; Write time: 207592 21:09:41 INFO - TinderboxPrint: CPU usage
13.1% 21:09:41 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 24 21:09:41 INFO - TinderboxPrint: I/O write bytes / time
397,930,496 / 207,592 21:09:41 INFO - TinderboxPrint: CPU idle
8,238.3 (86.9%) 21:09:41 INFO - TinderboxPrint: CPU user
1,192.8 (12.6%) 21:09:41 INFO - TinderboxPrint: Swap in / out
0 / 0 21:09:41 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 113401856; Read time: 0; Write time: 79072 21:09:41 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:09:41 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284528640; Read time: 24; Write time: 128520 21:09:42 INFO - Running post-run listener: _upload_blobber_files 21:09:42 INFO - Blob upload gear active. 21:09:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:09:42 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 21:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:09:42 INFO - (blobuploader) - INFO - Open directory for files ... 21:09:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:09:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:09:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:09:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:09:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:09:43 INFO - (blobuploader) - INFO - Done attempting. 21:09:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:09:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:09:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:09:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:09:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:09:44 INFO - (blobuploader) - INFO - Done attempting. 21:09:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:09:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:09:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:09:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:09:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:09:45 INFO - (blobuploader) - INFO - Done attempting. 21:09:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:09:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:09:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:09:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:09:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:09:49 INFO - (blobuploader) - INFO - Done attempting. 21:09:49 INFO - (blobuploader) - INFO - Iteration through files over. 21:09:49 INFO - Return code: 0 21:09:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:09:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:09:49 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5190bfa55d9a6ec13de7298c97a0b3b0bffaa9f381e0a541fe31c29d1ceb481a9a1788b8c7644c6ace35622170a1bd187d2e8c6033f38aff27071eab6ba73c7f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5e8a5e291278b4fe8793151cd2381de7ac477316752a78bcee2754cbac94f7177a8fecd6768a85475f3fc37ed72e4c13f82efa6e378a1035d2b40a875d53e8b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c80c30bfe6fcbc49c65700d5f90b4bb0c811b10f37198dd1ed6db01fe896fc41d8cd654347cc4774c8e1ac68d25c8ca050c7610099bbfc91eeff7f196b82b53", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b25b23e867ab399143f322fb810399caa52679bcf5e617a06f80be4ec294da9df891b166098acc2c9be84c9bdea25c8a413ca595911ff2ac336967630887d5e"} 21:09:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:09:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:09:49 INFO - Contents: 21:09:49 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5190bfa55d9a6ec13de7298c97a0b3b0bffaa9f381e0a541fe31c29d1ceb481a9a1788b8c7644c6ace35622170a1bd187d2e8c6033f38aff27071eab6ba73c7f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5e8a5e291278b4fe8793151cd2381de7ac477316752a78bcee2754cbac94f7177a8fecd6768a85475f3fc37ed72e4c13f82efa6e378a1035d2b40a875d53e8b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c80c30bfe6fcbc49c65700d5f90b4bb0c811b10f37198dd1ed6db01fe896fc41d8cd654347cc4774c8e1ac68d25c8ca050c7610099bbfc91eeff7f196b82b53", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b25b23e867ab399143f322fb810399caa52679bcf5e617a06f80be4ec294da9df891b166098acc2c9be84c9bdea25c8a413ca595911ff2ac336967630887d5e"} 21:09:49 INFO - Running post-run listener: copy_logs_to_upload_dir 21:09:49 INFO - Copying logs to upload dir... 21:09:49 INFO - mkdir: /builds/slave/test/build/upload/logs 21:09:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.026005 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-08-30 21:09:49.385743) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 21:09:49.388898) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5190bfa55d9a6ec13de7298c97a0b3b0bffaa9f381e0a541fe31c29d1ceb481a9a1788b8c7644c6ace35622170a1bd187d2e8c6033f38aff27071eab6ba73c7f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5e8a5e291278b4fe8793151cd2381de7ac477316752a78bcee2754cbac94f7177a8fecd6768a85475f3fc37ed72e4c13f82efa6e378a1035d2b40a875d53e8b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c80c30bfe6fcbc49c65700d5f90b4bb0c811b10f37198dd1ed6db01fe896fc41d8cd654347cc4774c8e1ac68d25c8ca050c7610099bbfc91eeff7f196b82b53", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b25b23e867ab399143f322fb810399caa52679bcf5e617a06f80be4ec294da9df891b166098acc2c9be84c9bdea25c8a413ca595911ff2ac336967630887d5e"} build_url:https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011439 build_url: 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5190bfa55d9a6ec13de7298c97a0b3b0bffaa9f381e0a541fe31c29d1ceb481a9a1788b8c7644c6ace35622170a1bd187d2e8c6033f38aff27071eab6ba73c7f", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e5e8a5e291278b4fe8793151cd2381de7ac477316752a78bcee2754cbac94f7177a8fecd6768a85475f3fc37ed72e4c13f82efa6e378a1035d2b40a875d53e8b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c80c30bfe6fcbc49c65700d5f90b4bb0c811b10f37198dd1ed6db01fe896fc41d8cd654347cc4774c8e1ac68d25c8ca050c7610099bbfc91eeff7f196b82b53", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7b25b23e867ab399143f322fb810399caa52679bcf5e617a06f80be4ec294da9df891b166098acc2c9be84c9bdea25c8a413ca595911ff2ac336967630887d5e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 21:09:49.416118) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 21:09:49.416476) ========= 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=8ec0ee4e6a493f968516bea6000001a5-1504151340.719469-558535444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 21:09:49.500566) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 21:09:49.500886) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 21:09:49.501197) ========= ========= Total master_lag: 0.19 =========