builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-028 starttime: 1504519344.64 results: success (0) revision: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:24.637479) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:24.637973) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:24.638249) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:24.656180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:24.656535) ========= 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=836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-04 03:02:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 26.8M=0s 2017-09-04 03:02:24 (26.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.161507 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:24.839024) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:24.839487) ========= 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=836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017298 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:24.892512) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:24.895865) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 --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 cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 --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=836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-04 03:02:24,895 truncating revision to first 12 chars 2017-09-04 03:02:24,895 Setting DEBUG logging. 2017-09-04 03:02:24,895 attempt 1/10 2017-09-04 03:02:24,895 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cef1935ebd9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-04 03:02:25,318 unpacking tar archive at: mozilla-central-cef1935ebd9b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.737408 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:25.649424) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:25.652753) ========= script_repo_revision: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:25.653162) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:25.653429) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-04 03:02:25.666728) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-04 03:02:25.667019) ========= /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=836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:02:25 INFO - MultiFileLogger online at 20170904 03:02:25 in /builds/slave/test 03:02:25 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 03:02:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:02:25 INFO - {'append_to_log': False, 03:02:25 INFO - 'base_work_dir': '/builds/slave/test', 03:02:25 INFO - 'blob_upload_branch': 'Firefox', 03:02:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:02:25 INFO - 'branch': 'Firefox', 03:02:25 INFO - 'buildbot_json_path': 'buildprops.json', 03:02:25 INFO - 'code_coverage': False, 03:02:25 INFO - 'config_files': ('talos/linux_config.py',), 03:02:25 INFO - 'default_actions': ('clobber', 03:02:25 INFO - 'read-buildbot-config', 03:02:25 INFO - 'download-and-extract', 03:02:25 INFO - 'populate-webroot', 03:02:25 INFO - 'create-virtualenv', 03:02:25 INFO - 'install', 03:02:25 INFO - 'setup-mitmproxy', 03:02:25 INFO - 'run-tests'), 03:02:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:02:25 INFO - 'disable_ccov_upload': False, 03:02:25 INFO - 'disable_stylo': False, 03:02:25 INFO - 'download_minidump_stackwalk': True, 03:02:25 INFO - 'download_symbols': 'ondemand', 03:02:25 INFO - 'enable_stylo': False, 03:02:25 INFO - 'enable_webrender': False, 03:02:25 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 03:02:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:02:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:02:25 INFO - 'gecko_profile': False, 03:02:25 INFO - 'gecko_profile_interval': 0, 03:02:25 INFO - 'installer_path': 'installer.exe', 03:02:25 INFO - 'log_level': 'info', 03:02:25 INFO - 'log_name': 'talos', 03:02:25 INFO - 'log_to_console': True, 03:02:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:02:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:02:25 INFO - 'opt_config_files': (), 03:02:25 INFO - 'pip_index': False, 03:02:25 INFO - 'suite': 'dromaeojs-e10s', 03:02:25 INFO - 'system_bits': '32', 03:02:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:02:25 INFO - 'title': 'talos-linux64-ix-028', 03:02:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:02:25 INFO - 'use_talos_json': True, 03:02:25 INFO - 'verify': 'False', 03:02:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:02:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:02:25 INFO - 'work_dir': 'build'} 03:02:25 INFO - [mozharness: 2017-09-04 10:02:25.802134Z] Running clobber step. 03:02:25 INFO - Running pre-action listener: _resource_record_pre_action 03:02:25 INFO - Running main action method: clobber 03:02:25 INFO - rmtree: /builds/slave/test/build 03:02:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:02:26 INFO - Running post-action listener: _resource_record_post_action 03:02:26 INFO - [mozharness: 2017-09-04 10:02:26.805322Z] Finished clobber step (success) 03:02:26 INFO - [mozharness: 2017-09-04 10:02:26.805685Z] Running read-buildbot-config step. 03:02:26 INFO - Running pre-action listener: _resource_record_pre_action 03:02:26 INFO - Running main action method: read_buildbot_config 03:02:26 INFO - Using buildbot properties: 03:02:26 INFO - { 03:02:26 INFO - "project": "", 03:02:26 INFO - "product": "firefox", 03:02:26 INFO - "who": "archaeopteryx@coole-files.de", 03:02:26 INFO - "installer_path": "public/build/target.tar.bz2", 03:02:26 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 03:02:26 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 03:02:26 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 03:02:26 INFO - "stage_platform": "linux64-stylo", 03:02:26 INFO - "basedir": "/builds/slave/test", 03:02:26 INFO - "buildnumber": 120, 03:02:26 INFO - "platform": "ubuntu64_hw_stylo", 03:02:26 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:02:26 INFO - "slavebuilddir": "test", 03:02:26 INFO - "taskId": "IhMF14fpRnSsdtD7hxvi3g", 03:02:26 INFO - "branch": "mozilla-central", 03:02:26 INFO - "script_repo_revision": "production", 03:02:26 INFO - "revision": "cef1935ebd9b328fc1ab5f5171cc7cdb6844a855", 03:02:26 INFO - "slavename": "talos-linux64-ix-028", 03:02:26 INFO - "repo_path": "mozilla-central" 03:02:26 INFO - } 03:02:26 INFO - Finding installer, test and symbols from parent task. 03:02:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IhMF14fpRnSsdtD7hxvi3g'}, attempt #1 03:02:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IhMF14fpRnSsdtD7hxvi3g'}, attempt #1 03:02:27 INFO - Task dependencies: e7aTuZz-Q-mFnC0rW_6dHw 03:02:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw'}, attempt #1 03:02:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 03:02:28 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.test_packages.json 03:02:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.crashreporter-symbols.zip 03:02:28 INFO - Running post-action listener: _resource_record_post_action 03:02:28 INFO - [mozharness: 2017-09-04 10:02:28.145015Z] Finished read-buildbot-config step (success) 03:02:28 INFO - [mozharness: 2017-09-04 10:02:28.145230Z] Running download-and-extract step. 03:02:28 INFO - Running pre-action listener: _resource_record_pre_action 03:02:28 INFO - Running main action method: download_and_extract 03:02:28 INFO - mkdir: /builds/slave/test/build/tests 03:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:28 INFO - trying https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.test_packages.json 03:02:28 INFO - Downloading https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 03:02:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 03:02:28 INFO - Downloaded 989 bytes. 03:02:28 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 03:02:28 INFO - Using the following test package requirements: 03:02:28 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 03:02:28 INFO - u'common': [u'target.common.tests.zip'], 03:02:28 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 03:02:28 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 03:02:28 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 03:02:28 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 03:02:28 INFO - u'mozbase': [u'target.common.tests.zip'], 03:02:28 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 03:02:28 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 03:02:28 INFO - u'web-platform': [u'target.common.tests.zip', 03:02:28 INFO - u'target.web-platform.tests.tar.gz'], 03:02:28 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 03:02:28 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 03:02:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip 03:02:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:02:28 INFO - Fetch https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip into memory 03:02:29 INFO - Content-Length response header: 55022364 03:02:29 INFO - Bytes received: 55022364 03:02:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 03:02:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip 03:02:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip'}, attempt #1 03:02:41 INFO - Fetch https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.common.tests.zip into memory 03:02:42 INFO - Content-Length response header: 55022364 03:02:42 INFO - Bytes received: 55022364 03:02:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.talos.tests.zip 03:02:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 03:02:55 INFO - Fetch https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.talos.tests.zip into memory 03:02:56 INFO - Content-Length response header: 14496650 03:02:56 INFO - Bytes received: 14496650 03:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:56 INFO - trying https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 03:02:56 INFO - Downloading https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 03:02:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 03:02:58 INFO - Downloaded 62705404 bytes. 03:02:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 03:02:58 INFO - mkdir: /builds/slave/test/properties 03:02:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:02:58 INFO - Writing to file /builds/slave/test/properties/build_url 03:02:58 INFO - Contents: 03:02:58 INFO - build_url:https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 03:02:58 INFO - Running post-action listener: _resource_record_post_action 03:02:58 INFO - Running post-action listener: find_tests_for_verification 03:02:58 INFO - Running post-action listener: set_extra_try_arguments 03:02:58 INFO - [mozharness: 2017-09-04 10:02:58.578789Z] Finished download-and-extract step (success) 03:02:58 INFO - [mozharness: 2017-09-04 10:02:58.578885Z] Running populate-webroot step. 03:02:58 INFO - Running pre-action listener: _resource_record_pre_action 03:02:58 INFO - Running main action method: populate_webroot 03:02:58 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:02:58 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:02:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:02:58 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:02:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:02:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:02:58 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:02:58 INFO - 'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:02:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:02:58 INFO - 'tests': ['damp', 'tps']}, 03:02:58 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:02:58 INFO - 'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['damp', 'tps']}, 03:02:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:02:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 03:02:58 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 03:02:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 03:02:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:02:58 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:02:58 INFO - 'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 03:02:58 INFO - 'other-e10s': {'tests': ['a11yr', 03:02:58 INFO - 'ts_paint', 03:02:58 INFO - 'tpaint', 03:02:58 INFO - 'sessionrestore', 03:02:58 INFO - 'sessionrestore_many_windows', 03:02:58 INFO - 'sessionrestore_no_auto_restore', 03:02:58 INFO - 'tabpaint']}, 03:02:58 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['a11yr', 03:02:58 INFO - 'ts_paint', 03:02:58 INFO - 'tpaint', 03:02:58 INFO - 'sessionrestore', 03:02:58 INFO - 'sessionrestore_many_windows', 03:02:58 INFO - 'sessionrestore_no_auto_restore', 03:02:58 INFO - 'tabpaint']}, 03:02:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 03:02:58 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 03:02:58 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['bloom_basic_singleton']}, 03:02:58 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['bloom_basic']}, 03:02:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:02:58 INFO - 'tsvgr_opacity', 03:02:58 INFO - 'tart', 03:02:58 INFO - 'tscrollx', 03:02:58 INFO - 'tsvg_static']}, 03:02:58 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['tsvgx', 03:02:58 INFO - 'tsvgr_opacity', 03:02:58 INFO - 'tart', 03:02:58 INFO - 'tscrollx', 03:02:58 INFO - 'tsvg_static']}, 03:02:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:02:58 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:02:58 INFO - 'talos_options': ['--enable-stylo'], 03:02:58 INFO - 'tests': ['tp5o']}, 03:02:58 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:02:58 INFO - 'talos_options': ['--mitmproxy', 03:02:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:02:58 INFO - '--firstNonBlankPaint'], 03:02:58 INFO - 'tests': ['tp6_google', 03:02:58 INFO - 'tp6_youtube', 03:02:58 INFO - 'tp6_amazon', 03:02:58 INFO - 'tp6_facebook']}, 03:02:58 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:02:58 INFO - 'talos_options': ['--enable-stylo', 03:02:58 INFO - '--mitmproxy', 03:02:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:02:58 INFO - '--firstNonBlankPaint'], 03:02:58 INFO - 'tests': ['tp6_google', 03:02:58 INFO - 'tp6_youtube', 03:02:58 INFO - 'tp6_amazon', 03:02:58 INFO - 'tp6_facebook']}, 03:02:58 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 03:02:58 INFO - 'talos_options': ['--enable-stylo', 03:02:58 INFO - '--stylo-threads=1', 03:02:58 INFO - '--mitmproxy', 03:02:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 03:02:58 INFO - '--firstNonBlankPaint'], 03:02:58 INFO - 'tests': ['tp6_google', 03:02:58 INFO - 'tp6_youtube', 03:02:58 INFO - 'tp6_amazon', 03:02:58 INFO - 'tp6_facebook']}, 03:02:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:02:58 INFO - 'talos_options': ['--xperf_path', 03:02:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:02:58 INFO - 'tests': ['tp5n']}, 03:02:58 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 03:02:58 INFO - 'talos_options': ['--enable-stylo', 03:02:58 INFO - '--xperf_path', 03:02:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:02:58 INFO - 'tests': ['tp5n']}}} 03:02:58 INFO - Running post-action listener: _resource_record_post_action 03:02:58 INFO - [mozharness: 2017-09-04 10:02:58.596002Z] Finished populate-webroot step (success) 03:02:58 INFO - [mozharness: 2017-09-04 10:02:58.596090Z] Running create-virtualenv step. 03:02:58 INFO - Running pre-action listener: _resource_record_pre_action 03:02:58 INFO - Running main action method: create_virtualenv 03:02:58 INFO - Creating virtualenv /builds/slave/test/build/venv 03:02:58 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:02:58 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 03:02:58 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 03:02:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:02:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:02:59 INFO - Installing distribute.............................................................................................................................................................................................done. 03:02:59 INFO - Installing pip...............done. 03:02:59 INFO - Return code: 0 03:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:02:59 INFO - Reading from file tmpfile_stdout 03:02:59 INFO - Output received: 03:02:59 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 03:02:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:02:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:02:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:02:59 INFO - Using env: {'DISPLAY': ':0', 03:02:59 INFO - 'HOME': '/home/cltbld', 03:02:59 INFO - 'LANG': 'en_US.UTF-8', 03:02:59 INFO - 'LANGUAGE': 'en_US:en', 03:02:59 INFO - 'LOGNAME': 'cltbld', 03:02:59 INFO - 'MAIL': '/var/mail/cltbld', 03:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:02:59 INFO - 'MOZ_NO_REMOTE': '1', 03:02:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:02:59 INFO - 'NO_EM_RESTART': '1', 03:02:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:02:59 INFO - 'PWD': '/builds/slave/test', 03:02:59 INFO - 'SHELL': '/bin/bash', 03:02:59 INFO - 'SHLVL': '1', 03:02:59 INFO - 'TERM': 'linux', 03:02:59 INFO - 'TMOUT': '86400', 03:02:59 INFO - 'USER': 'cltbld', 03:02:59 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:02:59 INFO - '_': '/tools/buildbot/bin/python'} 03:03:00 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:03:00 INFO - Downloading/unpacking pip>=1.5 03:03:00 INFO - Running setup.py egg_info for package pip 03:03:00 INFO - warning: no files found matching 'pip/cacert.pem' 03:03:00 INFO - warning: no files found matching '*.html' under directory 'docs' 03:03:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:03:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:03:00 INFO - Installing collected packages: pip 03:03:00 INFO - Found existing installation: pip 1.1 03:03:00 INFO - Uninstalling pip: 03:03:00 INFO - Successfully uninstalled pip 03:03:00 INFO - Running setup.py install for pip 03:03:00 INFO - warning: no files found matching 'pip/cacert.pem' 03:03:00 INFO - warning: no files found matching '*.html' under directory 'docs' 03:03:00 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 03:03:00 INFO - no previously-included directories found matching 'docs/_build/_sources' 03:03:00 INFO - Installing pip script to /builds/slave/test/build/venv/bin 03:03:00 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 03:03:00 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 03:03:00 INFO - Successfully installed pip 03:03:00 INFO - Cleaning up... 03:03:00 INFO - Return code: 0 03:03:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:03:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:03:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:03:00 INFO - Using env: {'DISPLAY': ':0', 03:03:00 INFO - 'HOME': '/home/cltbld', 03:03:00 INFO - 'LANG': 'en_US.UTF-8', 03:03:00 INFO - 'LANGUAGE': 'en_US:en', 03:03:00 INFO - 'LOGNAME': 'cltbld', 03:03:00 INFO - 'MAIL': '/var/mail/cltbld', 03:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:00 INFO - 'MOZ_NO_REMOTE': '1', 03:03:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:00 INFO - 'NO_EM_RESTART': '1', 03:03:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:00 INFO - 'PWD': '/builds/slave/test', 03:03:00 INFO - 'SHELL': '/bin/bash', 03:03:00 INFO - 'SHLVL': '1', 03:03:00 INFO - 'TERM': 'linux', 03:03:00 INFO - 'TMOUT': '86400', 03:03:00 INFO - 'USER': 'cltbld', 03:03:00 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:00 INFO - '_': '/tools/buildbot/bin/python'} 03:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:00 INFO - Downloading/unpacking psutil>=3.1.1 03:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:03:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:02 INFO - Installing collected packages: psutil 03:03:02 INFO - Running setup.py install for psutil 03:03:02 INFO - building 'psutil._psutil_linux' extension 03:03:02 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 03:03:02 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 03:03:02 INFO - building 'psutil._psutil_posix' extension 03:03:02 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 03:03:02 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 03:03:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:02 INFO - Successfully installed psutil 03:03:02 INFO - Cleaning up... 03:03:02 INFO - Return code: 0 03:03:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:03:02 INFO - Using env: {'DISPLAY': ':0', 03:03:02 INFO - 'HOME': '/home/cltbld', 03:03:02 INFO - 'LANG': 'en_US.UTF-8', 03:03:02 INFO - 'LANGUAGE': 'en_US:en', 03:03:02 INFO - 'LOGNAME': 'cltbld', 03:03:02 INFO - 'MAIL': '/var/mail/cltbld', 03:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:02 INFO - 'MOZ_NO_REMOTE': '1', 03:03:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:02 INFO - 'NO_EM_RESTART': '1', 03:03:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:02 INFO - 'PWD': '/builds/slave/test', 03:03:02 INFO - 'SHELL': '/bin/bash', 03:03:02 INFO - 'SHLVL': '1', 03:03:02 INFO - 'TERM': 'linux', 03:03:02 INFO - 'TMOUT': '86400', 03:03:02 INFO - 'USER': 'cltbld', 03:03:02 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:02 INFO - '_': '/tools/buildbot/bin/python'} 03:03:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:03:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:03:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:03:04 INFO - Installing collected packages: mozsystemmonitor 03:03:04 INFO - Running setup.py install for mozsystemmonitor 03:03:04 INFO - Successfully installed mozsystemmonitor 03:03:04 INFO - Cleaning up... 03:03:04 INFO - Return code: 0 03:03:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:03: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')]} 03:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03: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')]} 03:03:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:03:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:03:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:03:04 INFO - Using env: {'DISPLAY': ':0', 03:03:04 INFO - 'HOME': '/home/cltbld', 03:03:04 INFO - 'LANG': 'en_US.UTF-8', 03:03:04 INFO - 'LANGUAGE': 'en_US:en', 03:03:04 INFO - 'LOGNAME': 'cltbld', 03:03:04 INFO - 'MAIL': '/var/mail/cltbld', 03:03:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:04 INFO - 'MOZ_NO_REMOTE': '1', 03:03:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:04 INFO - 'NO_EM_RESTART': '1', 03:03:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:04 INFO - 'PWD': '/builds/slave/test', 03:03:04 INFO - 'SHELL': '/bin/bash', 03:03:04 INFO - 'SHLVL': '1', 03:03:04 INFO - 'TERM': 'linux', 03:03:04 INFO - 'TMOUT': '86400', 03:03:04 INFO - 'USER': 'cltbld', 03:03:04 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:04 INFO - '_': '/tools/buildbot/bin/python'} 03:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:04 INFO - Downloading/unpacking jsonschema==2.5.1 03:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:03:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:05 INFO - Downloading functools32-3.2.3-2.tar.gz 03:03:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:03:05 INFO - warning: no files found matching '*.txt' 03:03:05 INFO - no previously-included directories found matching 'build' 03:03:05 INFO - no previously-included directories found matching 'dist' 03:03:05 INFO - no previously-included directories found matching '.git*' 03:03:05 INFO - Installing collected packages: jsonschema, functools32 03:03:05 INFO - Running setup.py install for functools32 03:03:05 INFO - warning: no files found matching '*.txt' 03:03:05 INFO - no previously-included directories found matching 'build' 03:03:05 INFO - no previously-included directories found matching 'dist' 03:03:05 INFO - no previously-included directories found matching '.git*' 03:03:05 INFO - Successfully installed jsonschema functools32 03:03:05 INFO - Cleaning up... 03:03:05 INFO - Return code: 0 03:03:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:03:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:03:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:03:05 INFO - Using env: {'DISPLAY': ':0', 03:03:05 INFO - 'HOME': '/home/cltbld', 03:03:05 INFO - 'LANG': 'en_US.UTF-8', 03:03:05 INFO - 'LANGUAGE': 'en_US:en', 03:03:05 INFO - 'LOGNAME': 'cltbld', 03:03:05 INFO - 'MAIL': '/var/mail/cltbld', 03:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:05 INFO - 'MOZ_NO_REMOTE': '1', 03:03:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:05 INFO - 'NO_EM_RESTART': '1', 03:03:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:05 INFO - 'PWD': '/builds/slave/test', 03:03:05 INFO - 'SHELL': '/bin/bash', 03:03:05 INFO - 'SHLVL': '1', 03:03:05 INFO - 'TERM': 'linux', 03:03:05 INFO - 'TMOUT': '86400', 03:03:05 INFO - 'USER': 'cltbld', 03:03:05 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:05 INFO - '_': '/tools/buildbot/bin/python'} 03:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:03:06 INFO - Cleaning up... 03:03:06 INFO - Return code: 0 03:03:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:06 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 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:03:06 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 03:03:06 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 03:03:06 INFO - Using env: {'DISPLAY': ':0', 03:03:06 INFO - 'HOME': '/home/cltbld', 03:03:06 INFO - 'LANG': 'en_US.UTF-8', 03:03:06 INFO - 'LANGUAGE': 'en_US:en', 03:03:06 INFO - 'LOGNAME': 'cltbld', 03:03:06 INFO - 'MAIL': '/var/mail/cltbld', 03:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:06 INFO - 'MOZ_NO_REMOTE': '1', 03:03:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:06 INFO - 'NO_EM_RESTART': '1', 03:03:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:06 INFO - 'PWD': '/builds/slave/test', 03:03:06 INFO - 'SHELL': '/bin/bash', 03:03:06 INFO - 'SHLVL': '1', 03:03:06 INFO - 'TERM': 'linux', 03:03:06 INFO - 'TMOUT': '86400', 03:03:06 INFO - 'USER': 'cltbld', 03:03:06 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:06 INFO - '_': '/tools/buildbot/bin/python'} 03:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:06 INFO - Downloading/unpacking blobuploader==1.2.4 03:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:07 INFO - Downloading blobuploader-1.2.4.tar.gz 03:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:03:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:03:07 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 03:03:07 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 03:03:07 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 03:03:07 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 03:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:03:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:03:07 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 03:03:07 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 03:03:07 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 03:03:07 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 03:03:07 INFO - Downloading docopt-0.6.1.tar.gz 03:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:03:08 INFO - Installing collected packages: blobuploader, requests, docopt 03:03:08 INFO - Running setup.py install for blobuploader 03:03:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:03:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:03:08 INFO - Running setup.py install for requests 03:03:08 INFO - Running setup.py install for docopt 03:03:08 INFO - Successfully installed blobuploader requests docopt 03:03:08 INFO - Cleaning up... 03:03:08 INFO - Return code: 0 03:03:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:08 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')]} 03:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:08 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')]} 03:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:08 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 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:03:08 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 03:03:08 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 03:03:08 INFO - Using env: {'DISPLAY': ':0', 03:03:08 INFO - 'HOME': '/home/cltbld', 03:03:08 INFO - 'LANG': 'en_US.UTF-8', 03:03:08 INFO - 'LANGUAGE': 'en_US:en', 03:03:08 INFO - 'LOGNAME': 'cltbld', 03:03:08 INFO - 'MAIL': '/var/mail/cltbld', 03:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:08 INFO - 'MOZ_NO_REMOTE': '1', 03:03:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:08 INFO - 'NO_EM_RESTART': '1', 03:03:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:08 INFO - 'PWD': '/builds/slave/test', 03:03:08 INFO - 'SHELL': '/bin/bash', 03:03:08 INFO - 'SHLVL': '1', 03:03:08 INFO - 'TERM': 'linux', 03:03:08 INFO - 'TMOUT': '86400', 03:03:08 INFO - 'USER': 'cltbld', 03:03:08 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:08 INFO - '_': '/tools/buildbot/bin/python'} 03:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:08 INFO - Running setup.py (path:/tmp/pip-P5QySo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:08 INFO - Running setup.py (path:/tmp/pip-BK6YPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:08 INFO - Running setup.py (path:/tmp/pip-v7CxB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:09 INFO - Running setup.py (path:/tmp/pip-fFvPji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:03:09 INFO - Running setup.py (path:/tmp/pip-c6DHDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:03:09 INFO - Running setup.py (path:/tmp/pip-D6jjZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:03:09 INFO - Running setup.py (path:/tmp/pip-YKt_av-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:03:09 INFO - Running setup.py (path:/tmp/pip-ElyeOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:03:09 INFO - Running setup.py (path:/tmp/pip-oUh6tR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:03:09 INFO - Running setup.py (path:/tmp/pip-WziQnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:03:09 INFO - Running setup.py (path:/tmp/pip-wrdAH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:03:09 INFO - Running setup.py (path:/tmp/pip-kPRJEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:03:09 INFO - Running setup.py (path:/tmp/pip-4rJjJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:03:09 INFO - Running setup.py (path:/tmp/pip-YdXbdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:09 INFO - Running setup.py (path:/tmp/pip-37NN4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:03:09 INFO - Running setup.py (path:/tmp/pip-9u__35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:03:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:03:09 INFO - Running setup.py (path:/tmp/pip-u1BPv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:03:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:03:10 INFO - Running setup.py install for manifestparser 03:03:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:03:10 INFO - Running setup.py install for mozcrash 03:03:10 INFO - Running setup.py install for mozdebug 03:03:10 INFO - Running setup.py install for mozdevice 03:03:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:03:10 INFO - Running setup.py install for mozfile 03:03:10 INFO - Running setup.py install for mozhttpd 03:03:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:03:10 INFO - Running setup.py install for mozinfo 03:03:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:03:10 INFO - Running setup.py install for mozInstall 03:03:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:03:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:03:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:03:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:03:10 INFO - Running setup.py install for mozleak 03:03:10 INFO - Running setup.py install for mozlog 03:03:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:03:11 INFO - Running setup.py install for moznetwork 03:03:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:03:11 INFO - Running setup.py install for mozprocess 03:03:11 INFO - Running setup.py install for mozprofile 03:03:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:03:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:03:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:03:11 INFO - Running setup.py install for mozrunner 03:03:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:03:11 INFO - Running setup.py install for mozscreenshot 03:03:11 INFO - Running setup.py install for moztest 03:03:11 INFO - Running setup.py install for mozversion 03:03:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:03:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:03:11 INFO - Cleaning up... 03:03:11 INFO - Return code: 0 03:03:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:11 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')]} 03:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:11 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')]} 03:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:11 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 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:03:11 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 03:03:11 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 03:03:11 INFO - Using env: {'DISPLAY': ':0', 03:03:11 INFO - 'HOME': '/home/cltbld', 03:03:11 INFO - 'LANG': 'en_US.UTF-8', 03:03:11 INFO - 'LANGUAGE': 'en_US:en', 03:03:11 INFO - 'LOGNAME': 'cltbld', 03:03:11 INFO - 'MAIL': '/var/mail/cltbld', 03:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:11 INFO - 'MOZ_NO_REMOTE': '1', 03:03:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:11 INFO - 'NO_EM_RESTART': '1', 03:03:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:11 INFO - 'PWD': '/builds/slave/test', 03:03:11 INFO - 'SHELL': '/bin/bash', 03:03:11 INFO - 'SHLVL': '1', 03:03:11 INFO - 'TERM': 'linux', 03:03:11 INFO - 'TMOUT': '86400', 03:03:11 INFO - 'USER': 'cltbld', 03:03:11 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:11 INFO - '_': '/tools/buildbot/bin/python'} 03:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:11 INFO - Running setup.py (path:/tmp/pip-wpwO72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:12 INFO - Running setup.py (path:/tmp/pip-1tq5OT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:12 INFO - Running setup.py (path:/tmp/pip-67vTNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:12 INFO - Running setup.py (path:/tmp/pip-ZsFlFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:03:12 INFO - Running setup.py (path:/tmp/pip-0SB8pf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:03:12 INFO - Running setup.py (path:/tmp/pip-1nc9dS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:03:12 INFO - Running setup.py (path:/tmp/pip-eQC4qK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:03:12 INFO - Running setup.py (path:/tmp/pip-q2op9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:03:12 INFO - Running setup.py (path:/tmp/pip-E6m8Gy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:03:12 INFO - Running setup.py (path:/tmp/pip-0nfv6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:03:12 INFO - Running setup.py (path:/tmp/pip-ApuI_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:03:12 INFO - Running setup.py (path:/tmp/pip-TleIHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:03:12 INFO - Running setup.py (path:/tmp/pip-9euAiN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:03:12 INFO - Running setup.py (path:/tmp/pip-SKTD53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:03:12 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)) 03:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:12 INFO - Running setup.py (path:/tmp/pip-5kSujf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:13 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)) 03:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:03:13 INFO - Running setup.py (path:/tmp/pip-Q3XWQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:03:13 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)) 03:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:03:13 INFO - Running setup.py (path:/tmp/pip-HE7Wav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:03:13 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)) 03:03:13 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)) 03:03:13 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)) 03:03:13 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)) 03:03:13 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)) 03:03:13 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)) 03:03:13 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)) 03:03:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:14 INFO - Downloading blessings-1.6.tar.gz 03:03:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:03:14 INFO - Installing collected packages: blessings 03:03:14 INFO - Running setup.py install for blessings 03:03:14 INFO - Successfully installed blessings 03:03:14 INFO - Cleaning up... 03:03:14 INFO - Return code: 0 03:03:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:03:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:03:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:03:14 INFO - Reading from file tmpfile_stdout 03:03:14 INFO - Current package versions: 03:03:14 INFO - argparse == 1.2.1 03:03:14 INFO - blessings == 1.6 03:03:14 INFO - blobuploader == 1.2.4 03:03:14 INFO - docopt == 0.6.1 03:03:14 INFO - functools32 == 3.2.3-2 03:03:14 INFO - jsonschema == 2.5.1 03:03:14 INFO - manifestparser == 1.1 03:03:14 INFO - mozInstall == 1.13 03:03:14 INFO - mozcrash == 1.0 03:03:14 INFO - mozdebug == 0.1 03:03:14 INFO - mozdevice == 0.51 03:03:14 INFO - mozfile == 1.2 03:03:14 INFO - mozhttpd == 0.7 03:03:14 INFO - mozinfo == 0.10 03:03:14 INFO - mozleak == 0.1 03:03:14 INFO - mozlog == 3.5 03:03:14 INFO - moznetwork == 0.27 03:03:14 INFO - mozprocess == 0.25 03:03:14 INFO - mozprofile == 0.28 03:03:14 INFO - mozrunner == 6.13 03:03:14 INFO - mozscreenshot == 0.1 03:03:14 INFO - mozsystemmonitor == 0.3 03:03:14 INFO - moztest == 0.8 03:03:14 INFO - mozversion == 1.4 03:03:14 INFO - psutil == 3.1.1 03:03:14 INFO - requests == 1.2.3 03:03:14 INFO - wsgiref == 0.1.2 03:03:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:14 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 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:03:14 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 03:03:14 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 03:03:14 INFO - Using env: {'DISPLAY': ':0', 03:03:14 INFO - 'HOME': '/home/cltbld', 03:03:14 INFO - 'LANG': 'en_US.UTF-8', 03:03:14 INFO - 'LANGUAGE': 'en_US:en', 03:03:14 INFO - 'LOGNAME': 'cltbld', 03:03:14 INFO - 'MAIL': '/var/mail/cltbld', 03:03:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:14 INFO - 'MOZ_NO_REMOTE': '1', 03:03:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:14 INFO - 'NO_EM_RESTART': '1', 03:03:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:14 INFO - 'PWD': '/builds/slave/test', 03:03:14 INFO - 'SHELL': '/bin/bash', 03:03:14 INFO - 'SHLVL': '1', 03:03:14 INFO - 'TERM': 'linux', 03:03:14 INFO - 'TMOUT': '86400', 03:03:14 INFO - 'USER': 'cltbld', 03:03:14 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:14 INFO - '_': '/tools/buildbot/bin/python'} 03:03:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:14 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)) 03:03:14 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)) 03:03:14 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)) 03:03:14 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)) 03:03:14 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)) 03:03:14 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)) 03:03:14 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)) 03:03:14 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)) 03:03:14 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)) 03:03:14 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 03:03:14 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 03:03:14 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 03:03:14 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 03:03:14 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 03:03:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 03:03:16 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)) 03:03:16 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)) 03:03:16 INFO - Installing collected packages: simplejson 03:03:16 INFO - Running setup.py install for simplejson 03:03:16 INFO - building 'simplejson._speedups' extension 03:03:16 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 03:03:17 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 03:03:17 INFO - Successfully installed simplejson 03:03:17 INFO - Cleaning up... 03:03:17 INFO - Return code: 0 03:03:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:17 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 0x29961f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b5fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d02a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, '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': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', '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 03:03:17 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 03:03:17 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 03:03:17 INFO - Using env: {'DISPLAY': ':0', 03:03:17 INFO - 'HOME': '/home/cltbld', 03:03:17 INFO - 'LANG': 'en_US.UTF-8', 03:03:17 INFO - 'LANGUAGE': 'en_US:en', 03:03:17 INFO - 'LOGNAME': 'cltbld', 03:03:17 INFO - 'MAIL': '/var/mail/cltbld', 03:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:17 INFO - 'MOZ_NO_REMOTE': '1', 03:03:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:17 INFO - 'NO_EM_RESTART': '1', 03:03:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:17 INFO - 'PWD': '/builds/slave/test', 03:03:17 INFO - 'SHELL': '/bin/bash', 03:03:17 INFO - 'SHLVL': '1', 03:03:17 INFO - 'TERM': 'linux', 03:03:17 INFO - 'TMOUT': '86400', 03:03:17 INFO - 'USER': 'cltbld', 03:03:17 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:17 INFO - '_': '/tools/buildbot/bin/python'} 03:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:03:17 INFO - Cleaning up... 03:03:17 INFO - Return code: 0 03:03:17 INFO - Running post-action listener: _resource_record_post_action 03:03:17 INFO - Running post-action listener: _start_resource_monitoring 03:03:17 INFO - Starting resource monitoring. 03:03:17 INFO - [mozharness: 2017-09-04 10:03:17.412729Z] Finished create-virtualenv step (success) 03:03:17 INFO - [mozharness: 2017-09-04 10:03:17.413249Z] Running install step. 03:03:17 INFO - Running pre-action listener: _resource_record_pre_action 03:03:17 INFO - Running main action method: install 03:03:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:03:17 INFO - Reading from file tmpfile_stdout 03:03:17 INFO - Detecting whether we're running mozinstall >=1.0... 03:03:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:03:17 INFO - Reading from file tmpfile_stdout 03:03:17 INFO - Output received: 03:03:17 INFO - Usage: mozinstall [options] installer 03:03:17 INFO - Options: 03:03:17 INFO - -h, --help show this help message and exit 03:03:17 INFO - -d DEST, --destination=DEST 03:03:17 INFO - Directory to install application into. [default: 03:03:17 INFO - "/builds/slave/test"] 03:03:17 INFO - --app=APP Application being installed. [default: firefox] 03:03:17 INFO - mkdir: /builds/slave/test/build/application 03:03:17 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'] 03:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 03:03:34 INFO - Reading from file tmpfile_stdout 03:03:34 INFO - Output received: 03:03:34 INFO - /builds/slave/test/build/application/firefox/firefox 03:03:34 INFO - Running post-action listener: _resource_record_post_action 03:03:34 INFO - [mozharness: 2017-09-04 10:03:34.269137Z] Finished install step (success) 03:03:34 INFO - [mozharness: 2017-09-04 10:03:34.269387Z] Running setup-mitmproxy step. 03:03:34 INFO - Running pre-action listener: _resource_record_pre_action 03:03:34 INFO - Running main action method: setup_mitmproxy 03:03:34 INFO - Skipping: mitmproxy is not required 03:03:34 INFO - Running post-action listener: _resource_record_post_action 03:03:34 INFO - [mozharness: 2017-09-04 10:03:34.270549Z] Finished setup-mitmproxy step (success) 03:03:34 INFO - [mozharness: 2017-09-04 10:03:34.270766Z] Running run-tests step. 03:03:34 INFO - Running pre-action listener: _resource_record_pre_action 03:03:34 INFO - Running pre-action listener: _set_gcov_prefix 03:03:34 INFO - Running main action method: run_tests 03:03:34 WARNING - Try message not found. 03:03:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:03:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:03:34 INFO - Python 2.7.3 03:03:34 INFO - Return code: 0 03:03:34 INFO - grabbing minidump binary from tooltool 03:03: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')]} 03:03:34 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 0x2d06090>, '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 0x2d06d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d00f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:03:34 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 03:03:34 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 03:03:34 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 03:03:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:03:34 INFO - Return code: 0 03:03:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:03:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:03:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:03:34 INFO - ENV: RUST_BACKTRACE is now full 03:03:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:03:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:03:34 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/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 03:03:34 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/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 03:03:34 INFO - Using env: {'DISPLAY': ':0', 03:03:34 INFO - 'HOME': '/home/cltbld', 03:03:34 INFO - 'LANG': 'en_US.UTF-8', 03:03:34 INFO - 'LANGUAGE': 'en_US:en', 03:03:34 INFO - 'LOGNAME': 'cltbld', 03:03:34 INFO - 'MAIL': '/var/mail/cltbld', 03:03:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:03:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:03:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:34 INFO - 'MOZ_NO_REMOTE': '1', 03:03:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:03:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:34 INFO - 'NO_EM_RESTART': '1', 03:03:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:34 INFO - 'PWD': '/builds/slave/test', 03:03:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:03:34 INFO - 'RUST_BACKTRACE': 'full', 03:03:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:03:34 INFO - 'SHELL': '/bin/bash', 03:03:34 INFO - 'SHLVL': '1', 03:03:34 INFO - 'STYLO_FORCE_ENABLED': '1', 03:03:34 INFO - 'TERM': 'linux', 03:03:34 INFO - 'TMOUT': '86400', 03:03:34 INFO - 'USER': 'cltbld', 03:03:34 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:34 INFO - '_': '/tools/buildbot/bin/python'} 03:03:34 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/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 03:03:34 INFO - mozversion application_buildid: 20170904091152 03:03:34 INFO - mozversion application_changeset: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 03:03:34 INFO - mozversion application_display_name: Nightly 03:03:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:03:34 INFO - mozversion application_name: Firefox 03:03:34 INFO - mozversion application_remotingname: firefox 03:03:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 03:03:34 INFO - mozversion application_vendor: Mozilla 03:03:34 INFO - mozversion application_version: 57.0a1 03:03:34 INFO - mozversion platform_buildid: 20170904091152 03:03:34 INFO - mozversion platform_changeset: cef1935ebd9b328fc1ab5f5171cc7cdb6844a855 03:03:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 03:03:34 INFO - mozversion platform_version: 57.0a1 03:03:34 INFO - using testdate: 1504519414 03:03:34 INFO - actual date: 1504519414 03:03:34 INFO - starting webserver on 'localhost:52458' 03:03:34 INFO - SUITE-START | Running 2 tests 03:03:34 INFO - TEST-START | dromaeo_css 03:03:34 INFO - Initialising browser for dromaeo_css test... 03:03:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52458/getInfo.html -profile /tmp/tmpoRnwmx/profile 03:03:34 INFO - TEST-INFO | started process 4438 (/builds/slave/test/build/application/firefox/firefox http://localhost:52458/getInfo.html) 03:03:40 INFO - TEST-INFO | 4438: exit 0 03:03:40 INFO - Browser initialized. 03:03:40 INFO - Running cycle 1/1 for dromaeo_css test... 03:03:40 INFO - Using env: {'DISPLAY': ':0', 03:03:40 INFO - 'HOME': '/home/cltbld', 03:03:40 INFO - 'JSGC_DISABLE_POISONING': '1', 03:03:40 INFO - 'LANG': 'en_US.UTF-8', 03:03:40 INFO - 'LANGUAGE': 'en_US:en', 03:03:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:03:40 INFO - 'LOGNAME': 'cltbld', 03:03:40 INFO - 'MAIL': '/var/mail/cltbld', 03:03:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:03:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:03:40 INFO - 'MOZ_CRASHREPORTER': '1', 03:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:03:40 INFO - 'MOZ_NO_REMOTE': '1', 03:03:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:03:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:03:40 INFO - 'NO_EM_RESTART': '1', 03:03:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:40 INFO - 'PWD': '/builds/slave/test', 03:03:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:03:40 INFO - 'RUST_BACKTRACE': 'full', 03:03:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:03:40 INFO - 'SHELL': '/bin/bash', 03:03:40 INFO - 'SHLVL': '1', 03:03:40 INFO - 'STYLO_FORCE_ENABLED': '1', 03:03:40 INFO - 'TERM': 'linux', 03:03:40 INFO - 'TMOUT': '86400', 03:03:40 INFO - 'USER': 'cltbld', 03:03:40 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:40 INFO - '_': '/tools/buildbot/bin/python'} 03:03:40 INFO - TEST-INFO | started process 4636 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoRnwmx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:03:41 INFO - PID 4636 | 03:03:41 INFO - PID 4636 | (/builds/slave/test/build/application/firefox/firefox:4689): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:03:41 INFO - PID 4636 | 03:03:51 INFO - PID 4636 | RSS: Main: 166436864 03:03:51 INFO - PID 4636 | 03:06:50 INFO - PID 4636 | Cycle 1(1): loaded http://localhost:52458/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52458/tests/dromaeo/cssquery-ext.html) 03:06:52 INFO - PID 4636 | RSS: Main: 173371392 03:06:52 INFO - PID 4636 | 03:09:53 INFO - PID 4636 | Cycle 1(1): loaded http://localhost:52458/tests/dromaeo/cssquery-ext.html (next: http://localhost:52458/tests/dromaeo/cssquery-jquery.html) 03:09:54 INFO - PID 4636 | RSS: Main: 170233856 03:09:54 INFO - PID 4636 | 03:12:56 INFO - PID 4636 | Cycle 1(1): loaded http://localhost:52458/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52458/tests/dromaeo/cssquery-mootools.html) 03:12:56 INFO - PID 4636 | RSS: Main: 172523520 03:12:56 INFO - PID 4636 | 03:15:58 INFO - PID 4636 | Cycle 1(1): loaded http://localhost:52458/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52458/tests/dromaeo/cssquery-prototype.html) 03:15:59 INFO - PID 4636 | RSS: Main: 170901504 03:15:59 INFO - PID 4636 | 03:19:02 INFO - PID 4636 | Cycle 1(1): loaded http://localhost:52458/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52458/tests/dromaeo/cssquery-yui.html) 03:19:03 INFO - PID 4636 | RSS: Main: 172138496 03:19:03 INFO - PID 4636 | 03:22:05 INFO - PID 4636 | Cycle 1(1): loaded http://localhost:52458/tests/dromaeo/cssquery-yui.html (next: http://localhost:52458/tests/dromaeo/cssquery-dojo.html) 03:22:05 INFO - PID 4636 | RSS: Main: 174931968 03:22:05 INFO - PID 4636 | 03:22:05 INFO - PID 4636 | __start_tp_report 03:22:05 INFO - PID 4636 | _x_x_mozilla_page_load 03:22:05 INFO - PID 4636 | _x_x_mozilla_page_load_details 03:22:05 INFO - PID 4636 | |i|pagename|runs| 03:22:05 INFO - PID 4636 | |0;dojo.html;3323.9501407478892;3367.0908854609256;3371.5954085509734;3379.2565635560168;3402.098443912362;6455.419012288893;6504.609723416598;6538.215414150302;6549.115869357635;6552;6304.243490781101;6401.711922963466;6444.194475690539;6451.677416129198;6456.903146452795;5933.258342707161;5935.732892019848;5958.702064896738;5967.224260846106;5973.701314934257;5926.940730592682;5974.970125149368;6098.542609304298;6116.44951954324;6154.3537928517535;5971.910421343683;6043.939560604404;6048.969755151218;6059.484943779762;6127.969360153215;5759.5968282220265;5987.940120598803;6002.219711437485;6014.30835453924;6070.5447091468095;5843.298804143487;5880.088586269135;5890.676012819294;5954.9578823706115;6001.219841420631;6422.4862011039;6467.902981455282;6507.837304067389;6520.380563846427;6537.084808126866;6806.863862722767;6847.657617119125;6885.552439091443;6888.965555172243;6965.65171741416;6923.584584924921;6926.480513961473;6927.030215769796;6958.617276049815;7012.684429200698;6369.8407539811415;6522.77170299042;6559.508036897157;6563.146790917151;6563.212414510242;7167.964160179165;7183.245759195313;7193.460490463236;7193.856122877618;7284.380827629605;6764.864702705919;6790.93209067903;6865.41643960259;6876.965615171892;6963.825904352431;6943.618101004392;6960.547564408297;6974.093367862247;6975.128108986377;6975.197852246955;6872.69072891721;6898.068760717239;6955.269696681877;6973.79078627642;6973.895391569131;6811.182658081062;6886.931130688729;6891.173059232924;6982.092327997328;6989.7553585624255;5437.429069947678;5449.918251226235;5452.563794896399;5469.726513674301;5471.50756431923;4775.140474714584;4787.736674482867;4791.712497250176;4893.486183950705;4901.975490122598;6049.909251361233;6056.606320589236;6102.176206212193;6136;6142.140100385949;6932.584044957319;6936.4797640177185;7042.225355210922;7043.92956070443;7055.89416158758;5927.8221653350465;5987.640741555433;6029.336772954972;6045.667488288186;6124.6325220486915;5907.379725128885;5916.437938395846;5918.792842250587;5936.22829032223;5990.400959903975;6532.477401807844;6545.378189072126;6546.37809408115;6617.933820661828;6636.668166591651;6799.796006119825;6851.760188393382;6854.246032936273;6952.304769523007;6961.512694111566;6567.31043240472;6587.868242635216;6593.406593406617;6603.108580341593;6608.8347791305605;6759.459243260429;6773.458123350021;6786.253512113565;6857.27998560144;6890.896636550356;6966.12923384577;7026.578405295699;7047.894281585883;7049.894251586333;7062.576245425291;6688.464922806261;6708.530402871752;6775.186977562526;6775.390214880685;6790;6517.055027020961;6708.463322934056;6715.26132125456;6805.557638753465;6809.8638027238185;6588.769393071219;6608.041833934149;6625;6635.933640663725;6646.9335306648245;6759.493038022267;6763.7632682855865;6824.658767061528;6836.418904393182;6846.5892046477375;6513.609183449008;6529.379708927644;6610.4711623071;6617.8014659560295;6618.569792963442 03:22:05 INFO - PID 4636 | |1;ext.html;770.537677393564;808.4704518540358;836.7406104107727;856.8971723393194;857.6655104509239;39719.80140099299;41082.7945860271;41169.17661646765;41297.7935110324;41306;36719.26561468769;37084.629153708454;37197.62802371976;37211.255774884485;37232.44151337732;6655.833604159899;6792.456603471735;6795.864082718367;6846.041554182393;6867.450603951672;6783.287754785751;6873.793786186422;6875.1749790025015;6877.140357455318;6877.931220687804;7419.591922444263;7635.580043097655;7639.885401718979;7649.158592554814;7651.120121186079;7529.171791102975;7595.126560445537;7601.163871974079;7602.733904313378;7604.0875094988405;7344.853102937965;7524.247575242432;7527.134379546313;7527.247275272483;7527.774166775005;37543;37792.62207377919;37864;37865.43201851974;37920;3447.2588393495266;3481.408160612702;3486.267883744417;3492.5634295713035;3495.790252584853;30408.391832163456;30436.391272174653;30491.542626860613;30494.54258186129;30533.541996870066;5169.81905633301;5175.0943584872875;5195.428502864682;5201.29782479368;5224.634275600672;187579;190434;194079;202336;205859.97070014704;44624.776876115415;44813.551864480934;45165.54834451679;45225.547744522795;45281.320780188325;46856;47310.29034564484;47356;47400.28899566509;47489.5251047492;15475.380984760484;15540.067595944054;15647.530574082795;15740.842591573937;15842.366305347658;13132.080754347295;13162.736745265232;13213.075084743976;13213.603591892259;13215.339233038501;13266.204027758173;13286.933565332112;13287.402066907012;13399.598012059654;13439.46242150303;5390.595705322038;5393.379761327497;5418.4039755626845;5505.752241149158;5560.638558493685;972.4894430424084;1024.0757219485242;1024.144839059379;1025.236199031726;1025.4821315235906;22530.774692253195;22587.88706056492;22804.087836486688;22815.657765133536;23072;1081.6052140968648;1106.3251416635846;1118.6979515530925;1119.871214810291;1120.2891839813565;12210.389480525939;12236.510539578318;12363.876361236273;12417;12431.129820912449;239324;241601;242050;242300;243749;52721.7363913178;53013.204801928005;53014.46985530095;53076.46923530715;53175;1425.501074623894;1461.9122852628664;1463.0636392708675;1466.0690461556987;1466.288849907794;21820.01809918589;22068.88965555162;22254.332370028926;22492.775072249067;22635.547289054455;33226.667733322356;37650;37731.05672358213;37733.81133094317;37782.43326350162;209716;210213;210421;210584;210585;29197;30113.247168820955;30143.246418838826;33081.83459082689;33244.335113297115;41927;42349.78825105855;42650.360244596974;42738.358924616776;42971;31533.684663153075;31549;31666.841665791522;31851.044468665976;32055.358892821547;30716.078517644506;30873.073807785804;30881.69118308788;31524.36951260916;32029.67970320267;35516;35963.10092247715;36068.27863442769;36082.4587631191;36125.458118127724;29331.560026599192;29462.263443414085;29849;30077.54883676703;30227;22866.428339290986;23182.65226021577;23266;23280.6507902385;23426.414339641644 03:22:05 INFO - PID 4636 | |2;jquery.html;538.9164679474682;555.6305057137004;556.3740791609439;563.7942151114844;567.8239745678837;21054.789452105495;22054.779452205494;22084.11663533457;22101.226457074004;22121.77878221217;24106.51786964264;25205.873970630168;26418.339541511472;26507.73492265077;26652.866735666295;6477.352264773532;6606.735730570771;6635.270120286765;6638.5684930479365;6666.0334251533495;6211.6273023618505;6482.319356467574;6515.967420162892;6539.901901471481;6577.177852768404;6503.739850406002;6519.152510173671;6521.315261897504;6521.576097553668;6522.1521202243875;6501.707423165969;6513.869722605568;6517.3482651734685;6517.478601711851;6517.771877984248;6051.697415129226;6051.818445446644;6053.545984051169;6070.2412198475195;6071.483923866476;28259.434811303865;28903.56644650332;29451.116466505824;29819.254518637208;29930.40139197204;2004.91980320787;2024.089159878046;2042.5710600773855;2044.131244221215;2044.4173410578062;19218.4234472966;20839.479013024644;21632.78367216339;21671.67492487614;21781.128754849786;2426.9029238830603;2453.202709119543;2537.9111731089324;2580.1743442098536;2595.7014943281574;141444;144131.27934360367;144239.27880360637;144739;145075;19869.503262418555;21657.350279491642;21829.345119646438;22019.66970495444;22150.77849221487;43992.56007439949;44042.779786101506;44044.55955440405;44054.11891762211;44073;11491.253068550517;11643.650690479299;11697.707557311136;11843.46704398304;11845.5854045108;8342.37432192588;9642.180414664692;9696.660616878375;9849.45827979468;9864.901350986398;9547.379420337656;10866.565337386573;11381.317120972768;11411.315321080596;11846.763064738658;3750.7374483785875;3942.6845852331744;3977.5028121484816;4029.838806447768;4368.475782906072;964.2671569607149;972.0959507657946;975.8438649815995;986.7977064701719;995.183949161695;19930;20071;20254;20628.58742825135;20839;1028.3315844701003;1029.7683021320142;1033.188946676855;1038.133158812385;1053.5733028123623;11187.4965626547;11217.775644487065;11274.943625281821;11320.377379244055;11393.544258229744;466535;466867;467562;480233;489329;111854;111957;113221.43389283001;114627;115381;1157.288267715349;1166.585862018988;1186.7329850783506;1188.1623455463705;1203.3501908969058;17860.91069544644;17906.28374864991;17969.64060718752;18059.277628895223;18189.818101819343;59097.70451147717;60667;60692.39307607044;61571.6921415408;63244;70786.29213708003;74859.62570187115;77960;79084.60457697905;80326.19673803421;12212.145149839425;12585.874141258837;12983.091183617056;14258.786118208027;15005.849941500444;22811.88594057019;23658.763412365657;23744.88127559351;23748.643770343806;24026.87986560056;17682.292708291523;17811;17856.196471159088;17901.10494475245;17997.550061248574;16959.23683434273;17290.740638890176;17363.048281792377;17411.651766964842;17854.375096871547;19685.901570492057;19732.112054957273;20291.8985405072;20440.79559204389;20465.79534204639;14695.265236738334;14847.40610375573;14878.330475128862;14898.478553250585;14903.552893413214;17863.642727145645;18123.45629631113;18519.53701157428;18585.2565897368;18851.15169817334 03:22:05 INFO - PID 4636 | |3;mootools.html;1272.3638180909545;1319.6173109798153;1338.3040818774234;1349.9460021599136;1353.8646135386468;14974;15397.461088861892;15527.534173974786;15571.377144914204;15587.298571564279;15781.526554203363;16113.194340282998;16115.435959741382;16118.83881161188;16119.194040297996;2632.8157029007975;2692.1250593557074;2767.4188420431733;2774.1261502626608;2776.8195067320557;2795.049683107743;2830.1792480180725;2835.234486688593;2844.8719807608604;2846.388026574286;2769.6814866290333;2792.8463934483593;2825.3784167483122;2832.943341133176;2844.8862045518154;2661.3878807874203;2663.5738281874906;2664.40050988528;2680.839149651027;2682.932926676829;2628.3560527670843;2644.259607309956;2651.5227259093353;2654.9070782522517;2656.5085459190113;14956.850431495655;15311.846881531153;15404.152771597557;15412.614684632861;15458.61353466161;8007.399445041587;8299.419040667155;8305.875411868827;8342.16578342167;8347.332213422973;5312.256284120226;5361.51746342831;5420.620556561043;5502.339719233678;5508.779648814043;1900.4203717866046;1918.7601549806275;1925.2202857842456;1950.9122089506006;1968.2914150905663;26176.214713558624;27077.45845083107;27392;27465.862670686718;27854.5821812675;6783.694733736895;7032.226455089936;7166.8208294792;7199.568025918357;7289.307515785992;17457.388991385495;17464.82535174632;17494.212760425813;17501.212445439985;17513;7986;8123.390745694097;8128;8130.512169269745;8131.18688131194;7473.327400533978;7476;7477.214892436325;7562.432817538706;7622.618869056525;5159.17453207485;5389.973050134724;5397.298351214317;5403.405625381205;5457.590680698963;1563.5778339848177;1574.6220906982242;1582.8021497312836;1593.8326475720114;1602.206907580755;868.748063061705;891.2513488669549;905.6060613633091;909.6270529083176;909.7589138878203;7654.808629784188;7871.881921771178;7897.24976127267;7938.444308898322;8024.679012839421;686.0738003694972;687.3538873458932;692.2869444472202;709.2942522190453;719.7408932784193;4759.690620109682;5052.8484145475695;5403.918941215885;5428.430014848463;5443.673379597237;21756.56486870254;22934.541309174056;23041.884790576274;23060;23062.65406018911;21345.466363341042;21715.891420542797;21917;22403.551928961657;22413;11215;11365.715857103638;11611.303321800719;11730.472128754227;11870.169088163919;8384.329253659815;8749.562521873753;8809.911900881165;8861.778455538406;9033.096690330914;18855.057247137313;19086.522836929187;19131.71302430437;19321.710174347678;19516.707249391555;19071.90464047671;19168.137433815235;19366.806331937063;19390.2243910242;19848.50378740543;11932.105092117952;12145.817812732992;12220.572279970158;12231.082668799698;12249.326287054297;14156.221407822668;14295.285235737963;14371.78442323345;14387.208703521406;14389.280535973368;10811.513481893177;10829.458527073772;10886.67339979802;10952.671419857417;11060.336379817236;10523.631672891412;10526.473676316307;10701.411422371844;10911.508982095665;11008.559657613605;9083.818323633623;9104.317176211678;9130.223930965942;9185.357025008185;9211.263098952202;6898.896516552155;6917.619530925646;7022.54353467023;7082.575045497287;7092.9645351772915;6796.864062718619;6949.235584085847;7053.964730176316;7150.785476435715;7154.964225179049 03:22:05 INFO - PID 4636 | |4;prototype.html;502.71604938271605;520.3391692550463;530.8434011966469;535.5153586004666;541.1990254423454;6447.838804029896;6498.512611554141;6499.870002599945;6508.4142427181605;6544.541882068257;6341.95357765968;6392.105105285264;6422.775202867901;6430.292667806538;6438.356164383571;5270.1040823060175;5277;5292.894142117156;5293.126634105368;5308.681479111266;4902.421232500277;5013.473585273548;5187.547486703729;5265.842024739264;5288.576913846883;5012.899742005157;5043.36957880265;5051.3938327400765;5059.392872855244;5082.466341034194;4986.276989836471;5004.624653150991;5069.467705890884;5101.7194054327;5107.565856902168;4372.797480692803;4473.619742321906;4487.326900964848;4522.751248681321;4528.230200865861;6170.907436388413;6237.750489980441;6241.65670888101;6251.9687401562705;6291.6854157291955;5685.914711279334;5771.711414429262;5784.132380143013;5800.390958949334;5812.418758124196;6662.80011599648;6750.527463077587;6756.797296081126;6768.966155169242;6773.8645227095185;4979.7261150636705;4981.227909674007;4994.250862370637;4999.100161970844;5005.147066172283;6769.661516924134;6832.99999999995;6842.384185423336;6892.483063770137;7056.682449289794;4997.600191984632;5009.323741294951;5043.571296439771;5049.8737531562;5053.0399224147295;5067.25179812964;5070.315507406527;5131.2559678846355;5139.100657384943;5148.330717006766;5627.212190293362;5693.914591281051;5739.512141468022;5742.109972954198;5760.971195143998;6621.238557565845;6669.933300666931;6698.196216454056;6701.798946031627;6728.562643428161;1780.448061101037;1788.6780379531808;1792.4891405949243;1800.5588630785542;1803.6212395396851;1181.9172657913864;1210.116614871149;1224.9142560020714;1244.6826059354808;1262.8042653388557;5776.595638305278;5784.942150578525;5822.650640961471;5893.3222679391565;5928.525717942554;6741.460683145337;6748.628825414551;6838.230516640769;6881.552699074544;6939.167299924042;5865.912011319833;5886.352501224862;5891.23413956183;5938.465538101592;5956.434138756811;3575.4458059000895;3650.3976843820474;3652.1052342175803;3673.1184515716036;3687.575928768226;6507.7071531781185;6579.440747536513;6604.570702904296;6623.801285961333;6633.568818026813;6613.239477460057;6978.685959131852;7019.262977387403;7035.1909530404655;7107.502474826712;6378.170837791243;6443.903341449882;6489;6524.7602955438315;6608.306127856602;6791.694373753292;6796.728130874907;6809.148856392951;6842.178938527409;6859.211190713132;6732.926579419576;6777.152855893013;6777.864442711217;6831.692573834289;6832.214295355899;6482.902756458562;6483.708233129426;6530.510211734044;6538.574992625464;6586.80239592813;5028.924566131438;5054.140796064751;5055;5062.31658726082;5068.062408454448;6655.700493477902;6726.226483954408;6749.392554670103;6768.763093291711;6841;6483.189601299838;6601.860269948802;6618.63597502142;6677.966610166918;6689.397954184147;6624.8343791405605;6627.834304142435;6681.46548276127;6716.966415168088;6752.358525940028;6452.838679033061;6486.4486518646445;6578.684263147294;6635.767748128986;6670.9332906670315;6738.528303018742;6773.458123350219;6789.796306110825;6804.353586409283;6835.965820170867;6515.641639709672;6528.412442880163;6545.574537655038;6553.868922621617;6557.573757705733 03:22:05 INFO - PID 4636 | |5;yui.html;900.7342833864013;912.6121398405677;934.654177954157;936.2089034765622;939.9671011514598;19167.137478813453;20466.488337791543;20516.076776545073;20606.690899636516;20684.896575517138;20682.27612033579;22011.33965981023;22086.337409877728;22089.226877059307;22144.446388840246;915.2906497464452;985.1872205430512;994.1102713071797;994.602159136344;995.5022488755623;965.4979410706428;1002.3284399452384;1002.6390499420203;1006.3760468509498;1006.6325791086274;1041.8489319048733;1046.2676126711294;1047.680457460478;1048.9423081730502;1050.9647996723222;1031.072035168347;1044.5821671331453;1044.5926088825365;1044.8746150461955;1046.4349251404271;753.1188509443909;753.773867839646;759.4342215049824;760.3612965109335;770.4029377232633;16659.500214993568;16898.915505422516;17106;17243.82756172435;19974.201031958703;20354.79645203544;20506.282280120275;21164;21167.364979050653;21285.36143915685;17228.56928576783;17436;17545.473635790946;17637.382691605733;17673.646527069388;3669.504616876714;3712.7401081924277;3738.12154143776;3739.5886452490204;3741.588425273245;129000.35499822441;130245;130709;134925;135115;16203.351865925231;16373.91813040927;16505.504834854975;16693.165341732863;16826.411075612294;27401.86299068492;27864.86067569649;28157.57763633547;28732.712672873422;30026.84986575097;8958.86561701575;9223.769405764775;9231.307651925998;9266.768330791649;9267.21228695555;4229.217594744982;4276.978615106905;4396.560343965578;4406.823727050882;4421.5578442156175;5068.163752980717;5208.140656791587;5211.270422140903;5216.843494695166;5230.895382092337;1049.9790004199874;1065.9520321585546;1085.934843909368;1092.5029111754134;1095.3373209208473;636.5925807483214;672.7174586673609;694.2190036209265;703.391566295151;710.8969199466152;10670.626528071636;10855.294405863593;10952.4523773811;11042.116630669689;11271;675.1627981303246;716.5485743981259;719.1549928833599;722.3278111172618;726.9963650181821;4818.180909245437;5205.427402985744;5210.609204309692;5242.672965847833;5433.755481003364;109408;110807;114548.42725786484;115498;116727;98164;99284.50357748165;100534.49732751436;100713.48216294652;101721.49139254405;88383;89937;89965.55017224872;91943;92196.53901730583;11111.722206944567;11434.485448154686;11448.255863368855;11501.654950351503;11603.07175425947;40559.3916091253;40790.18419631531;42118;42517.787411062745;42744;73023.63488182524;74130.62934685292;74790;75871.62064189644;78656.60671696605;18282.451526454228;18335.54161145982;18887.622247555246;19001.23995040183;19253.518662033563;19384.8061519383;20012.299569515;20369.79630203679;20498;20646.07092680863;16016.599585010468;16093.436729714404;16177.757333639769;16182.838171618605;16379.09914954689;15752.369905203663;16237.43168989127;16334;16546.255418505956;16654.583635409213;14662.78005829892;15790.60523486876;16402.83597164061;16522.42171523991;16522.58693532623;12105.939470302592;12132.393380330772;12800.487980480677;12804.039697022574;13730.176189428666;12451.25292482454;12663.936680316909;12760.872391275969;12802.031370275245;12966.35168241603 03:22:05 INFO - PID 4636 | __end_tp_report 03:22:05 INFO - PID 4636 | __start_cc_report 03:22:05 INFO - PID 4636 | _x_x_mozilla_cycle_collect,353 03:22:05 INFO - PID 4636 | __end_cc_report 03:22:05 INFO - PID 4636 | __startTimestamp1504520525338__endTimestamp 03:22:05 INFO - PID 4636 | 03:22:05 INFO - PID 4636 | ------- Summary: start ------- 03:22:05 INFO - PID 4636 | Number of tests: 6 03:22:05 INFO - PID 4636 | 03:22:05 INFO - PID 4636 | [#0] dojo.html Cycles:165 Average:6383.25 Median:6587.87 stddev:739.33 (11.2%) stddev-sans-first:701.56 03:22:05 INFO - PID 4636 | Values: 3324.0 3367.1 3371.6 3379.3 3402.1 6455.4 6504.6 6538.2 6549.1 6552.0 6304.2 6401.7 6444.2 6451.7 6456.9 5933.3 5935.7 5958.7 5967.2 5973.7 5926.9 5975.0 6098.5 6116.4 6154.4 5971.9 6043.9 6049.0 6059.5 6128.0 5759.6 5987.9 6002.2 6014.3 6070.5 5843.3 5880.1 5890.7 5955.0 6001.2 6422.5 6467.9 6507.8 6520.4 6537.1 6806.9 6847.7 6885.6 6889.0 6965.7 6923.6 6926.5 6927.0 6958.6 7012.7 6369.8 6522.8 6559.5 6563.1 6563.2 7168.0 7183.2 7193.5 7193.9 7284.4 6764.9 6790.9 6865.4 6877.0 6963.8 6943.6 6960.5 6974.1 6975.1 6975.2 6872.7 6898.1 6955.3 6973.8 6973.9 6811.2 6886.9 6891.2 6982.1 6989.8 5437.4 5449.9 5452.6 5469.7 5471.5 4775.1 4787.7 4791.7 4893.5 4902.0 6049.9 6056.6 6102.2 6136.0 6142.1 6932.6 6936.5 7042.2 7043.9 7055.9 5927.8 5987.6 6029.3 6045.7 6124.6 5907.4 5916.4 5918.8 5936.2 5990.4 6532.5 6545.4 6546.4 6617.9 6636.7 6799.8 6851.8 6854.2 6952.3 6961.5 6567.3 6587.9 6593.4 6603.1 6608.8 6759.5 6773.5 6786.3 6857.3 6890.9 6966.1 7026.6 7047.9 7049.9 7062.6 6688.5 6708.5 6775.2 6775.4 6790.0 6517.1 6708.5 6715.3 6805.6 6809.9 6588.8 6608.0 6625.0 6635.9 6646.9 6759.5 6763.8 6824.7 6836.4 6846.6 6513.6 6529.4 6610.5 6617.8 6618.6 03:22:05 INFO - PID 4636 | 03:22:05 INFO - PID 4636 | [#1] ext.html Cycles:180 Average:37680.93 Median:3464.33 stddev:56304.39 (1625.3%) stddev-sans-first:56394.13 03:22:05 INFO - PID 4636 | Values: 770.5 808.5 836.7 856.9 857.7 39719.8 41082.8 41169.2 41297.8 41306.0 36719.3 37084.6 37197.6 37211.3 37232.4 6655.8 6792.5 6795.9 6846.0 6867.5 6783.3 6873.8 6875.2 6877.1 6877.9 7419.6 7635.6 7639.9 7649.2 7651.1 7529.2 7595.1 7601.2 7602.7 7604.1 7344.9 7524.2 7527.1 7527.2 7527.8 37543.0 37792.6 37864.0 37865.4 37920.0 3447.3 3481.4 3486.3 3492.6 3495.8 30408.4 30436.4 30491.5 30494.5 30533.5 5169.8 5175.1 5195.4 5201.3 5224.6 187579.0 190434.0 194079.0 202336.0 205860.0 44624.8 44813.6 45165.5 45225.5 45281.3 46856.0 47310.3 47356.0 47400.3 47489.5 15475.4 15540.1 15647.5 15740.8 15842.4 13132.1 13162.7 13213.1 13213.6 13215.3 13266.2 13286.9 13287.4 13399.6 13439.5 5390.6 5393.4 5418.4 5505.8 5560.6 972.5 1024.1 1024.1 1025.2 1025.5 22530.8 22587.9 22804.1 22815.7 23072.0 1081.6 1106.3 1118.7 1119.9 1120.3 12210.4 12236.5 12363.9 12417.0 12431.1 239324.0 241601.0 242050.0 242300.0 243749.0 52721.7 53013.2 53014.5 53076.5 53175.0 1425.5 1461.9 1463.1 1466.1 1466.3 21820.0 22068.9 22254.3 22492.8 22635.5 33226.7 37650.0 37731.1 37733.8 37782.4 209716.0 210213.0 210421.0 210584.0 210585.0 29197.0 30113.2 30143.2 33081.8 33244.3 41927.0 42349.8 42650.4 42738.4 42971.0 31533.7 31549.0 31666.8 31851.0 32055.4 30716.1 30873.1 30881.7 31524.4 32029.7 35516.0 35963.1 36068.3 36082.5 36125.5 29331.6 29462.3 29849.0 30077.5 30227.0 22866.4 23182.7 23266.0 23280.7 23426.4 03:22:05 INFO - PID 4636 | 03:22:05 INFO - PID 4636 | [#2] jquery.html Cycles:180 Average:35680.25 Median:22092.67 stddev:80299.70 (363.5%) stddev-sans-first:80481.61 03:22:05 INFO - PID 4636 | Values: 538.9 555.6 556.4 563.8 567.8 21054.8 22054.8 22084.1 22101.2 22121.8 24106.5 25205.9 26418.3 26507.7 26652.9 6477.4 6606.7 6635.3 6638.6 6666.0 6211.6 6482.3 6516.0 6539.9 6577.2 6503.7 6519.2 6521.3 6521.6 6522.2 6501.7 6513.9 6517.3 6517.5 6517.8 6051.7 6051.8 6053.5 6070.2 6071.5 28259.4 28903.6 29451.1 29819.3 29930.4 2004.9 2024.1 2042.6 2044.1 2044.4 19218.4 20839.5 21632.8 21671.7 21781.1 2426.9 2453.2 2537.9 2580.2 2595.7 141444.0 144131.3 144239.3 144739.0 145075.0 19869.5 21657.4 21829.3 22019.7 22150.8 43992.6 44042.8 44044.6 44054.1 44073.0 11491.3 11643.7 11697.7 11843.5 11845.6 8342.4 9642.2 9696.7 9849.5 9864.9 9547.4 10866.6 11381.3 11411.3 11846.8 3750.7 3942.7 3977.5 4029.8 4368.5 964.3 972.1 975.8 986.8 995.2 19930.0 20071.0 20254.0 20628.6 20839.0 1028.3 1029.8 1033.2 1038.1 1053.6 11187.5 11217.8 11274.9 11320.4 11393.5 466535.0 466867.0 467562.0 480233.0 489329.0 111854.0 111957.0 113221.4 114627.0 115381.0 1157.3 1166.6 1186.7 1188.2 1203.4 17860.9 17906.3 17969.6 18059.3 18189.8 59097.7 60667.0 60692.4 61571.7 63244.0 70786.3 74859.6 77960.0 79084.6 80326.2 12212.1 12585.9 12983.1 14258.8 15005.8 22811.9 23658.8 23744.9 23748.6 24026.9 17682.3 17811.0 17856.2 17901.1 17997.6 16959.2 17290.7 17363.0 17411.7 17854.4 19685.9 19732.1 20291.9 20440.8 20465.8 14695.3 14847.4 14878.3 14898.5 14903.6 17863.6 18123.5 18519.5 18585.3 18851.2 03:22:05 INFO - PID 4636 | 03:22:05 INFO - PID 4636 | [#3] mootools.html Cycles:180 Average:9481.27 Median:2681.89 stddev:6887.16 (256.8%) stddev-sans-first:6878.86 03:22:05 INFO - PID 4636 | Values: 1272.4 1319.6 1338.3 1349.9 1353.9 14974.0 15397.5 15527.5 15571.4 15587.3 15781.5 16113.2 16115.4 16118.8 16119.2 2632.8 2692.1 2767.4 2774.1 2776.8 2795.0 2830.2 2835.2 2844.9 2846.4 2769.7 2792.8 2825.4 2832.9 2844.9 2661.4 2663.6 2664.4 2680.8 2682.9 2628.4 2644.3 2651.5 2654.9 2656.5 14956.9 15311.8 15404.2 15412.6 15458.6 8007.4 8299.4 8305.9 8342.2 8347.3 5312.3 5361.5 5420.6 5502.3 5508.8 1900.4 1918.8 1925.2 1950.9 1968.3 26176.2 27077.5 27392.0 27465.9 27854.6 6783.7 7032.2 7166.8 7199.6 7289.3 17457.4 17464.8 17494.2 17501.2 17513.0 7986.0 8123.4 8128.0 8130.5 8131.2 7473.3 7476.0 7477.2 7562.4 7622.6 5159.2 5390.0 5397.3 5403.4 5457.6 1563.6 1574.6 1582.8 1593.8 1602.2 868.7 891.3 905.6 909.6 909.8 7654.8 7871.9 7897.2 7938.4 8024.7 686.1 687.4 692.3 709.3 719.7 4759.7 5052.8 5403.9 5428.4 5443.7 21756.6 22934.5 23041.9 23060.0 23062.7 21345.5 21715.9 21917.0 22403.6 22413.0 11215.0 11365.7 11611.3 11730.5 11870.2 8384.3 8749.6 8809.9 8861.8 9033.1 18855.1 19086.5 19131.7 19321.7 19516.7 19071.9 19168.1 19366.8 19390.2 19848.5 11932.1 12145.8 12220.6 12231.1 12249.3 14156.2 14295.3 14371.8 14387.2 14389.3 10811.5 10829.5 10886.7 10952.7 11060.3 10523.6 10526.5 10701.4 10911.5 11008.6 9083.8 9104.3 9130.2 9185.4 9211.3 6898.9 6917.6 7022.5 7082.6 7093.0 6796.9 6949.2 7054.0 7150.8 7155.0 03:22:05 INFO - PID 4636 | 03:22:05 INFO - PID 4636 | [#4] prototype.html Cycles:180 Average:5610.90 Median:6360.06 stddev:1572.34 (24.7%) stddev-sans-first:1529.29 03:22:05 INFO - PID 4636 | Values: 502.7 520.3 530.8 535.5 541.2 6447.8 6498.5 6499.9 6508.4 6544.5 6342.0 6392.1 6422.8 6430.3 6438.4 5270.1 5277.0 5292.9 5293.1 5308.7 4902.4 5013.5 5187.5 5265.8 5288.6 5012.9 5043.4 5051.4 5059.4 5082.5 4986.3 5004.6 5069.5 5101.7 5107.6 4372.8 4473.6 4487.3 4522.8 4528.2 6170.9 6237.8 6241.7 6252.0 6291.7 5685.9 5771.7 5784.1 5800.4 5812.4 6662.8 6750.5 6756.8 6769.0 6773.9 4979.7 4981.2 4994.3 4999.1 5005.1 6769.7 6833.0 6842.4 6892.5 7056.7 4997.6 5009.3 5043.6 5049.9 5053.0 5067.3 5070.3 5131.3 5139.1 5148.3 5627.2 5693.9 5739.5 5742.1 5761.0 6621.2 6669.9 6698.2 6701.8 6728.6 1780.4 1788.7 1792.5 1800.6 1803.6 1181.9 1210.1 1224.9 1244.7 1262.8 5776.6 5784.9 5822.7 5893.3 5928.5 6741.5 6748.6 6838.2 6881.6 6939.2 5865.9 5886.4 5891.2 5938.5 5956.4 3575.4 3650.4 3652.1 3673.1 3687.6 6507.7 6579.4 6604.6 6623.8 6633.6 6613.2 6978.7 7019.3 7035.2 7107.5 6378.2 6443.9 6489.0 6524.8 6608.3 6791.7 6796.7 6809.1 6842.2 6859.2 6732.9 6777.2 6777.9 6831.7 6832.2 6482.9 6483.7 6530.5 6538.6 6586.8 5028.9 5054.1 5055.0 5062.3 5068.1 6655.7 6726.2 6749.4 6768.8 6841.0 6483.2 6601.9 6618.6 6678.0 6689.4 6624.8 6627.8 6681.5 6717.0 6752.4 6452.8 6486.4 6578.7 6635.8 6670.9 6738.5 6773.5 6789.8 6804.4 6836.0 6515.6 6528.4 6545.6 6553.9 6557.6 03:22:05 INFO - PID 4636 | 03:22:05 INFO - PID 4636 | [#5] yui.html Cycles:180 Average:24085.76 Median:20362.30 stddev:33574.92 (164.9%) stddev-sans-first:33623.97 03:22:05 INFO - PID 4636 | Values: 900.7 912.6 934.7 936.2 940.0 19167.1 20466.5 20516.1 20606.7 20684.9 20682.3 22011.3 22086.3 22089.2 22144.4 915.3 985.2 994.1 994.6 995.5 965.5 1002.3 1002.6 1006.4 1006.6 1041.8 1046.3 1047.7 1048.9 1051.0 1031.1 1044.6 1044.6 1044.9 1046.4 753.1 753.8 759.4 760.4 770.4 16659.5 16898.9 17106.0 17243.8 19974.2 20354.8 20506.3 21164.0 21167.4 21285.4 17228.6 17436.0 17545.5 17637.4 17673.6 3669.5 3712.7 3738.1 3739.6 3741.6 129000.4 130245.0 130709.0 134925.0 135115.0 16203.4 16373.9 16505.5 16693.2 16826.4 27401.9 27864.9 28157.6 28732.7 30026.8 8958.9 9223.8 9231.3 9266.8 9267.2 4229.2 4277.0 4396.6 4406.8 4421.6 5068.2 5208.1 5211.3 5216.8 5230.9 1050.0 1066.0 1085.9 1092.5 1095.3 636.6 672.7 694.2 703.4 710.9 10670.6 10855.3 10952.5 11042.1 11271.0 675.2 716.5 719.2 722.3 727.0 4818.2 5205.4 5210.6 5242.7 5433.8 109408.0 110807.0 114548.4 115498.0 116727.0 98164.0 99284.5 100534.5 100713.5 101721.5 88383.0 89937.0 89965.6 91943.0 92196.5 11111.7 11434.5 11448.3 11501.7 11603.1 40559.4 40790.2 42118.0 42517.8 42744.0 73023.6 74130.6 74790.0 75871.6 78656.6 18282.5 18335.5 18887.6 19001.2 19253.5 19384.8 20012.3 20369.8 20498.0 20646.1 16016.6 16093.4 16177.8 16182.8 16379.1 15752.4 16237.4 16334.0 16546.3 16654.6 14662.8 15790.6 16402.8 16522.4 16522.6 12105.9 12132.4 12800.5 12804.0 13730.2 12451.3 12663.9 12760.9 12802.0 12966.4 03:22:05 INFO - PID 4636 | -------- Summary: end -------- 03:22:05 INFO - PID 4636 | 03:22:06 INFO - TEST-INFO | 4636: exit 0 03:22:06 INFO - TEST-OK | dromaeo_css | took 1111426ms 03:22:06 INFO - TEST-START | kraken 03:22:06 INFO - Initialising browser for kraken test... 03:22:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52458/getInfo.html -profile /tmp/tmpURs8_j/profile 03:22:06 INFO - TEST-INFO | started process 5023 (/builds/slave/test/build/application/firefox/firefox http://localhost:52458/getInfo.html) 03:22:11 INFO - TEST-INFO | 5023: exit 0 03:22:11 INFO - Browser initialized. 03:22:11 INFO - Running cycle 1/1 for kraken test... 03:22:11 INFO - Using env: {'DISPLAY': ':0', 03:22:11 INFO - 'HOME': '/home/cltbld', 03:22:11 INFO - 'JSGC_DISABLE_POISONING': '1', 03:22:11 INFO - 'LANG': 'en_US.UTF-8', 03:22:11 INFO - 'LANGUAGE': 'en_US:en', 03:22:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 03:22:11 INFO - 'LOGNAME': 'cltbld', 03:22:11 INFO - 'MAIL': '/var/mail/cltbld', 03:22:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:22:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:22:11 INFO - 'MOZ_CRASHREPORTER': '1', 03:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 03:22:11 INFO - 'MOZ_NO_REMOTE': '1', 03:22:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:22:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:22:11 INFO - 'NO_EM_RESTART': '1', 03:22:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:11 INFO - 'PWD': '/builds/slave/test', 03:22:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:22:11 INFO - 'RUST_BACKTRACE': 'full', 03:22:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 03:22:11 INFO - 'SHELL': '/bin/bash', 03:22:11 INFO - 'SHLVL': '1', 03:22:11 INFO - 'STYLO_FORCE_ENABLED': '1', 03:22:11 INFO - 'TERM': 'linux', 03:22:11 INFO - 'TMOUT': '86400', 03:22:11 INFO - 'USER': 'cltbld', 03:22:11 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468', 03:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:11 INFO - '_': '/tools/buildbot/bin/python'} 03:22:11 INFO - TEST-INFO | started process 5217 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpURs8_j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 03:22:12 INFO - PID 5217 | 03:22:12 INFO - PID 5217 | (/builds/slave/test/build/application/firefox/firefox:5269): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 03:22:12 INFO - PID 5217 | 03:22:23 INFO - PID 5217 | RSS: Main: 170725376 03:22:23 INFO - PID 5217 | 03:22:25 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52458/tests/kraken/driver.html?testName=audio-beat-detection) 03:22:26 INFO - PID 5217 | RSS: Main: 176660480 03:22:26 INFO - PID 5217 | 03:22:29 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52458/tests/kraken/driver.html?testName=audio-dft) 03:22:30 INFO - PID 5217 | RSS: Main: 180793344 03:22:30 INFO - PID 5217 | 03:22:33 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52458/tests/kraken/driver.html?testName=audio-fft) 03:22:33 INFO - PID 5217 | RSS: Main: 173154304 03:22:33 INFO - PID 5217 | 03:22:35 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52458/tests/kraken/driver.html?testName=audio-oscillator) 03:22:36 INFO - PID 5217 | RSS: Main: 173776896 03:22:36 INFO - PID 5217 | 03:22:38 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52458/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:22:39 INFO - PID 5217 | RSS: Main: 174481408 03:22:39 INFO - PID 5217 | 03:22:42 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52458/tests/kraken/driver.html?testName=imaging-darkroom) 03:22:42 INFO - PID 5217 | RSS: Main: 174997504 03:22:42 INFO - PID 5217 | 03:22:46 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52458/tests/kraken/driver.html?testName=imaging-desaturate) 03:22:47 INFO - PID 5217 | RSS: Main: 174923776 03:22:47 INFO - PID 5217 | 03:22:50 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52458/tests/kraken/driver.html?testName=json-parse-financial) 03:22:50 INFO - PID 5217 | RSS: Main: 175161344 03:22:50 INFO - PID 5217 | 03:22:52 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52458/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:22:52 INFO - PID 5217 | RSS: Main: 175386624 03:22:52 INFO - PID 5217 | 03:22:54 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52458/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:22:55 INFO - PID 5217 | RSS: Main: 172797952 03:22:55 INFO - PID 5217 | 03:22:57 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52458/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:22:57 INFO - PID 5217 | RSS: Main: 172199936 03:22:57 INFO - PID 5217 | 03:23:00 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52458/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:23:01 INFO - PID 5217 | RSS: Main: 172490752 03:23:01 INFO - PID 5217 | 03:23:04 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52458/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:23:04 INFO - PID 5217 | RSS: Main: 172957696 03:23:04 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | Cycle 1(1): loaded http://localhost:52458/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52458/tests/kraken/driver.html?testName=ai-astar) 03:23:06 INFO - PID 5217 | RSS: Main: 174809088 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | __start_tp_report 03:23:06 INFO - PID 5217 | _x_x_mozilla_page_load 03:23:06 INFO - PID 5217 | _x_x_mozilla_page_load_details 03:23:06 INFO - PID 5217 | |i|pagename|runs| 03:23:06 INFO - PID 5217 | |0;ai-astar;98;109;99;106;109;108;110;98;107;108 03:23:06 INFO - PID 5217 | |1;audio-beat-detection;143;126;139;179;116;148;154;118;172;116 03:23:06 INFO - PID 5217 | |2;audio-dft;163;163;162;163;163;164;165;166;168;161 03:23:06 INFO - PID 5217 | |3;audio-fft;75;82;73;157;77;78;73;88;98;75 03:23:06 INFO - PID 5217 | |4;audio-oscillator;79;80;127;80;80;80;124;79;79;136 03:23:06 INFO - PID 5217 | |5;imaging-gaussian-blur;114;115;115;114;113;114;114;115;115;115 03:23:06 INFO - PID 5217 | |6;imaging-darkroom;148;149;148;147;148;149;148;148;149;148 03:23:06 INFO - PID 5217 | |7;imaging-desaturate;93;94;94;94;94;93;94;95;94;93 03:23:06 INFO - PID 5217 | |8;json-parse-financial;52;51;52;52;54;52;51;54;51;52 03:23:06 INFO - PID 5217 | |9;json-stringify-tinderbox;38;38;38;38;38;38;38;38;38;38 03:23:06 INFO - PID 5217 | |10;stanford-crypto-aes;74;76;74;74;77;74;74;75;75;75 03:23:06 INFO - PID 5217 | |11;stanford-crypto-ccm;125;125;119;119;127;121;122;125;135;160 03:23:06 INFO - PID 5217 | |12;stanford-crypto-pbkdf2;171;171;172;175;170;170;178;173;171;168 03:23:06 INFO - PID 5217 | |13;stanford-crypto-sha256-iterative;64;65;63;63;64;63;66;62;63;64 03:23:06 INFO - PID 5217 | __end_tp_report 03:23:06 INFO - PID 5217 | __start_cc_report 03:23:06 INFO - PID 5217 | _x_x_mozilla_cycle_collect,953 03:23:06 INFO - PID 5217 | __end_cc_report 03:23:06 INFO - PID 5217 | __startTimestamp1504520586898__endTimestamp 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | ------- Summary: start ------- 03:23:06 INFO - PID 5217 | Number of tests: 14 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#0] ai-astar Cycles:10 Average:105.20 Median:109.50 stddev:4.87 (4.4%) stddev-sans-first:4.42 03:23:06 INFO - PID 5217 | Values: 98.0 109.0 99.0 106.0 109.0 108.0 110.0 98.0 107.0 108.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:145.50 stddev:22.69 (15.6%) stddev-sans-first:24.06 03:23:06 INFO - PID 5217 | Values: 143.0 126.0 139.0 179.0 116.0 148.0 154.0 118.0 172.0 116.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#2] audio-dft Cycles:10 Average:163.80 Median:163.50 stddev:2.04 (1.3%) stddev-sans-first:2.15 03:23:06 INFO - PID 5217 | Values: 163.0 163.0 162.0 163.0 163.0 164.0 165.0 166.0 168.0 161.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#3] audio-fft Cycles:10 Average:87.60 Median:77.50 stddev:25.61 (33.0%) stddev-sans-first:26.76 03:23:06 INFO - PID 5217 | Values: 75.0 82.0 73.0 157.0 77.0 78.0 73.0 88.0 98.0 75.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#4] audio-oscillator Cycles:10 Average:94.40 Median:79.50 stddev:24.06 (30.3%) stddev-sans-first:24.87 03:23:06 INFO - PID 5217 | Values: 79.0 80.0 127.0 80.0 80.0 80.0 124.0 79.0 79.0 136.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:115.00 stddev:0.70 (0.6%) stddev-sans-first:0.73 03:23:06 INFO - PID 5217 | Values: 114.0 115.0 115.0 114.0 113.0 114.0 114.0 115.0 115.0 115.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#6] imaging-darkroom Cycles:10 Average:148.20 Median:148.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 03:23:06 INFO - PID 5217 | Values: 148.0 149.0 148.0 147.0 148.0 149.0 148.0 148.0 149.0 148.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 03:23:06 INFO - PID 5217 | Values: 93.0 94.0 94.0 94.0 94.0 93.0 94.0 95.0 94.0 93.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:1.10 (2.1%) stddev-sans-first:1.17 03:23:06 INFO - PID 5217 | Values: 52.0 51.0 52.0 52.0 54.0 52.0 51.0 54.0 51.0 52.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#9] json-stringify-tinderbox Cycles:10 Average:38.00 Median:38.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 03:23:06 INFO - PID 5217 | Values: 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 38.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:75.00 stddev:1.03 (1.4%) stddev-sans-first:1.05 03:23:06 INFO - PID 5217 | Values: 74.0 76.0 74.0 74.0 77.0 74.0 74.0 75.0 75.0 75.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#11] stanford-crypto-ccm Cycles:10 Average:127.80 Median:125.00 stddev:12.24 (9.8%) stddev-sans-first:12.94 03:23:06 INFO - PID 5217 | Values: 125.0 125.0 119.0 119.0 127.0 121.0 122.0 125.0 135.0 160.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:171.50 stddev:2.85 (1.7%) stddev-sans-first:3.00 03:23:06 INFO - PID 5217 | Values: 171.0 171.0 172.0 175.0 170.0 170.0 178.0 173.0 171.0 168.0 03:23:06 INFO - PID 5217 | 03:23:06 INFO - PID 5217 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:1.16 (1.8%) stddev-sans-first:1.22 03:23:06 INFO - PID 5217 | Values: 64.0 65.0 63.0 63.0 64.0 63.0 66.0 62.0 63.0 64.0 03:23:06 INFO - PID 5217 | -------- Summary: end -------- 03:23:06 INFO - PID 5217 | 03:23:07 INFO - PID 5217 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 03:23:07 INFO - TEST-INFO | 5217: exit 0 03:23:07 INFO - TEST-OK | kraken | took 61584ms 03:23:07 INFO - SUITE-END | took 1173s 03:23:08 INFO - Completed test suite (00:19:33) 03:23:08 INFO - javascript benchmark 03:23:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8648.0759996344, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.9501407478892, 3367.0908854609256, 3371.5954085509734, 3379.2565635560168, 3402.098443912362, 6455.419012288893, 6504.609723416598, 6538.215414150302, 6549.115869357635, 6552.0, 6304.243490781101, 6401.711922963466, 6444.194475690539, 6451.677416129198, 6456.903146452795, 5933.258342707161, 5935.732892019848, 5958.702064896738, 5967.224260846106, 5973.701314934257, 5926.940730592682, 5974.970125149368, 6098.542609304298, 6116.44951954324, 6154.3537928517535, 5971.910421343683, 6043.939560604404, 6048.969755151218, 6059.484943779762, 6127.969360153215, 5759.5968282220265, 5987.940120598803, 6002.219711437485, 6014.30835453924, 6070.5447091468095, 5843.298804143487, 5880.088586269135, 5890.676012819294, 5954.9578823706115, 6001.219841420631, 6422.4862011039, 6467.902981455282, 6507.837304067389, 6520.380563846427, 6537.084808126866, 6806.863862722767, 6847.657617119125, 6885.552439091443, 6888.965555172243, 6965.65171741416, 6923.584584924921, 6926.480513961473, 6927.030215769796, 6958.617276049815, 7012.684429200698, 6369.8407539811415, 6522.77170299042, 6559.508036897157, 6563.146790917151, 6563.212414510242, 7167.964160179165, 7183.245759195313, 7193.460490463236, 7193.856122877618, 7284.380827629605, 6764.864702705919, 6790.93209067903, 6865.41643960259, 6876.965615171892, 6963.825904352431, 6943.618101004392, 6960.547564408297, 6974.093367862247, 6975.128108986377, 6975.197852246955, 6872.69072891721, 6898.068760717239, 6955.269696681877, 6973.79078627642, 6973.895391569131, 6811.182658081062, 6886.931130688729, 6891.173059232924, 6982.092327997328, 6989.7553585624255, 5437.429069947678, 5449.918251226235, 5452.563794896399, 5469.726513674301, 5471.50756431923, 4775.140474714584, 4787.736674482867, 4791.712497250176, 4893.486183950705, 4901.975490122598, 6049.909251361233, 6056.606320589236, 6102.176206212193, 6136.0, 6142.140100385949, 6932.584044957319, 6936.4797640177185, 7042.225355210922, 7043.92956070443, 7055.89416158758, 5927.8221653350465, 5987.640741555433, 6029.336772954972, 6045.667488288186, 6124.6325220486915, 5907.379725128885, 5916.437938395846, 5918.792842250587, 5936.22829032223, 5990.400959903975, 6532.477401807844, 6545.378189072126, 6546.37809408115, 6617.933820661828, 6636.668166591651, 6799.796006119825, 6851.760188393382, 6854.246032936273, 6952.304769523007, 6961.512694111566, 6567.31043240472, 6587.868242635216, 6593.406593406617, 6603.108580341593, 6608.8347791305605, 6759.459243260429, 6773.458123350021, 6786.253512113565, 6857.27998560144, 6890.896636550356, 6966.12923384577, 7026.578405295699, 7047.894281585883, 7049.894251586333, 7062.576245425291, 6688.464922806261, 6708.530402871752, 6775.186977562526, 6775.390214880685, 6790.0, 6517.055027020961, 6708.463322934056, 6715.26132125456, 6805.557638753465, 6809.8638027238185, 6588.769393071219, 6608.041833934149, 6625.0, 6635.933640663725, 6646.9335306648245, 6759.493038022267, 6763.7632682855865, 6824.658767061528, 6836.418904393182, 6846.5892046477375, 6513.609183449008, 6529.379708927644, 6610.4711623071, 6617.8014659560295, 6618.569792963442], "value": 6329.513490503471, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.537677393564, 808.4704518540358, 836.7406104107727, 856.8971723393194, 857.6655104509239, 39719.80140099299, 41082.7945860271, 41169.17661646765, 41297.7935110324, 41306.0, 36719.26561468769, 37084.629153708454, 37197.62802371976, 37211.255774884485, 37232.44151337732, 6655.833604159899, 6792.456603471735, 6795.864082718367, 6846.041554182393, 6867.450603951672, 6783.287754785751, 6873.793786186422, 6875.1749790025015, 6877.140357455318, 6877.931220687804, 7419.591922444263, 7635.580043097655, 7639.885401718979, 7649.158592554814, 7651.120121186079, 7529.171791102975, 7595.126560445537, 7601.163871974079, 7602.733904313378, 7604.0875094988405, 7344.853102937965, 7524.247575242432, 7527.134379546313, 7527.247275272483, 7527.774166775005, 37543.0, 37792.62207377919, 37864.0, 37865.43201851974, 37920.0, 3447.2588393495266, 3481.408160612702, 3486.267883744417, 3492.5634295713035, 3495.790252584853, 30408.391832163456, 30436.391272174653, 30491.542626860613, 30494.54258186129, 30533.541996870066, 5169.81905633301, 5175.0943584872875, 5195.428502864682, 5201.29782479368, 5224.634275600672, 187579.0, 190434.0, 194079.0, 202336.0, 205859.97070014704, 44624.776876115415, 44813.551864480934, 45165.54834451679, 45225.547744522795, 45281.320780188325, 46856.0, 47310.29034564484, 47356.0, 47400.28899566509, 47489.5251047492, 15475.380984760484, 15540.067595944054, 15647.530574082795, 15740.842591573937, 15842.366305347658, 13132.080754347295, 13162.736745265232, 13213.075084743976, 13213.603591892259, 13215.339233038501, 13266.204027758173, 13286.933565332112, 13287.402066907012, 13399.598012059654, 13439.46242150303, 5390.595705322038, 5393.379761327497, 5418.4039755626845, 5505.752241149158, 5560.638558493685, 972.4894430424084, 1024.0757219485242, 1024.144839059379, 1025.236199031726, 1025.4821315235906, 22530.774692253195, 22587.88706056492, 22804.087836486688, 22815.657765133536, 23072.0, 1081.6052140968648, 1106.3251416635846, 1118.6979515530925, 1119.871214810291, 1120.2891839813565, 12210.389480525939, 12236.510539578318, 12363.876361236273, 12417.0, 12431.129820912449, 239324.0, 241601.0, 242050.0, 242300.0, 243749.0, 52721.7363913178, 53013.204801928005, 53014.46985530095, 53076.46923530715, 53175.0, 1425.501074623894, 1461.9122852628664, 1463.0636392708675, 1466.0690461556987, 1466.288849907794, 21820.01809918589, 22068.88965555162, 22254.332370028926, 22492.775072249067, 22635.547289054455, 33226.667733322356, 37650.0, 37731.05672358213, 37733.81133094317, 37782.43326350162, 209716.0, 210213.0, 210421.0, 210584.0, 210585.0, 29197.0, 30113.247168820955, 30143.246418838826, 33081.83459082689, 33244.335113297115, 41927.0, 42349.78825105855, 42650.360244596974, 42738.358924616776, 42971.0, 31533.684663153075, 31549.0, 31666.841665791522, 31851.044468665976, 32055.358892821547, 30716.078517644506, 30873.073807785804, 30881.69118308788, 31524.36951260916, 32029.67970320267, 35516.0, 35963.10092247715, 36068.27863442769, 36082.4587631191, 36125.458118127724, 29331.560026599192, 29462.263443414085, 29849.0, 30077.54883676703, 30227.0, 22866.428339290986, 23182.65226021577, 23266.0, 23280.6507902385, 23426.414339641644], "value": 16722.433540419723, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [538.9164679474682, 555.6305057137004, 556.3740791609439, 563.7942151114844, 567.8239745678837, 21054.789452105495, 22054.779452205494, 22084.11663533457, 22101.226457074004, 22121.77878221217, 24106.51786964264, 25205.873970630168, 26418.339541511472, 26507.73492265077, 26652.866735666295, 6477.352264773532, 6606.735730570771, 6635.270120286765, 6638.5684930479365, 6666.0334251533495, 6211.6273023618505, 6482.319356467574, 6515.967420162892, 6539.901901471481, 6577.177852768404, 6503.739850406002, 6519.152510173671, 6521.315261897504, 6521.576097553668, 6522.1521202243875, 6501.707423165969, 6513.869722605568, 6517.3482651734685, 6517.478601711851, 6517.771877984248, 6051.697415129226, 6051.818445446644, 6053.545984051169, 6070.2412198475195, 6071.483923866476, 28259.434811303865, 28903.56644650332, 29451.116466505824, 29819.254518637208, 29930.40139197204, 2004.91980320787, 2024.089159878046, 2042.5710600773855, 2044.131244221215, 2044.4173410578062, 19218.4234472966, 20839.479013024644, 21632.78367216339, 21671.67492487614, 21781.128754849786, 2426.9029238830603, 2453.202709119543, 2537.9111731089324, 2580.1743442098536, 2595.7014943281574, 141444.0, 144131.27934360367, 144239.27880360637, 144739.0, 145075.0, 19869.503262418555, 21657.350279491642, 21829.345119646438, 22019.66970495444, 22150.77849221487, 43992.56007439949, 44042.779786101506, 44044.55955440405, 44054.11891762211, 44073.0, 11491.253068550517, 11643.650690479299, 11697.707557311136, 11843.46704398304, 11845.5854045108, 8342.37432192588, 9642.180414664692, 9696.660616878375, 9849.45827979468, 9864.901350986398, 9547.379420337656, 10866.565337386573, 11381.317120972768, 11411.315321080596, 11846.763064738658, 3750.7374483785875, 3942.6845852331744, 3977.5028121484816, 4029.838806447768, 4368.475782906072, 964.2671569607149, 972.0959507657946, 975.8438649815995, 986.7977064701719, 995.183949161695, 19930.0, 20071.0, 20254.0, 20628.58742825135, 20839.0, 1028.3315844701003, 1029.7683021320142, 1033.188946676855, 1038.133158812385, 1053.5733028123623, 11187.4965626547, 11217.775644487065, 11274.943625281821, 11320.377379244055, 11393.544258229744, 466535.0, 466867.0, 467562.0, 480233.0, 489329.0, 111854.0, 111957.0, 113221.43389283001, 114627.0, 115381.0, 1157.288267715349, 1166.585862018988, 1186.7329850783506, 1188.1623455463705, 1203.3501908969058, 17860.91069544644, 17906.28374864991, 17969.64060718752, 18059.277628895223, 18189.818101819343, 59097.70451147717, 60667.0, 60692.39307607044, 61571.6921415408, 63244.0, 70786.29213708003, 74859.62570187115, 77960.0, 79084.60457697905, 80326.19673803421, 12212.145149839425, 12585.874141258837, 12983.091183617056, 14258.786118208027, 15005.849941500444, 22811.88594057019, 23658.763412365657, 23744.88127559351, 23748.643770343806, 24026.87986560056, 17682.292708291523, 17811.0, 17856.196471159088, 17901.10494475245, 17997.550061248574, 16959.23683434273, 17290.740638890176, 17363.048281792377, 17411.651766964842, 17854.375096871547, 19685.901570492057, 19732.112054957273, 20291.8985405072, 20440.79559204389, 20465.79534204639, 14695.265236738334, 14847.40610375573, 14878.330475128862, 14898.478553250585, 14903.552893413214, 17863.642727145645, 18123.45629631113, 18519.53701157428, 18585.2565897368, 18851.15169817334], "value": 12661.633426459328, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1272.3638180909545, 1319.6173109798153, 1338.3040818774234, 1349.9460021599136, 1353.8646135386468, 14974.0, 15397.461088861892, 15527.534173974786, 15571.377144914204, 15587.298571564279, 15781.526554203363, 16113.194340282998, 16115.435959741382, 16118.83881161188, 16119.194040297996, 2632.8157029007975, 2692.1250593557074, 2767.4188420431733, 2774.1261502626608, 2776.8195067320557, 2795.049683107743, 2830.1792480180725, 2835.234486688593, 2844.8719807608604, 2846.388026574286, 2769.6814866290333, 2792.8463934483593, 2825.3784167483122, 2832.943341133176, 2844.8862045518154, 2661.3878807874203, 2663.5738281874906, 2664.40050988528, 2680.839149651027, 2682.932926676829, 2628.3560527670843, 2644.259607309956, 2651.5227259093353, 2654.9070782522517, 2656.5085459190113, 14956.850431495655, 15311.846881531153, 15404.152771597557, 15412.614684632861, 15458.61353466161, 8007.399445041587, 8299.419040667155, 8305.875411868827, 8342.16578342167, 8347.332213422973, 5312.256284120226, 5361.51746342831, 5420.620556561043, 5502.339719233678, 5508.779648814043, 1900.4203717866046, 1918.7601549806275, 1925.2202857842456, 1950.9122089506006, 1968.2914150905663, 26176.214713558624, 27077.45845083107, 27392.0, 27465.862670686718, 27854.5821812675, 6783.694733736895, 7032.226455089936, 7166.8208294792, 7199.568025918357, 7289.307515785992, 17457.388991385495, 17464.82535174632, 17494.212760425813, 17501.212445439985, 17513.0, 7986.0, 8123.390745694097, 8128.0, 8130.512169269745, 8131.18688131194, 7473.327400533978, 7476.0, 7477.214892436325, 7562.432817538706, 7622.618869056525, 5159.17453207485, 5389.973050134724, 5397.298351214317, 5403.405625381205, 5457.590680698963, 1563.5778339848177, 1574.6220906982242, 1582.8021497312836, 1593.8326475720114, 1602.206907580755, 868.748063061705, 891.2513488669549, 905.6060613633091, 909.6270529083176, 909.7589138878203, 7654.808629784188, 7871.881921771178, 7897.24976127267, 7938.444308898322, 8024.679012839421, 686.0738003694972, 687.3538873458932, 692.2869444472202, 709.2942522190453, 719.7408932784193, 4759.690620109682, 5052.8484145475695, 5403.918941215885, 5428.430014848463, 5443.673379597237, 21756.56486870254, 22934.541309174056, 23041.884790576274, 23060.0, 23062.65406018911, 21345.466363341042, 21715.891420542797, 21917.0, 22403.551928961657, 22413.0, 11215.0, 11365.715857103638, 11611.303321800719, 11730.472128754227, 11870.169088163919, 8384.329253659815, 8749.562521873753, 8809.911900881165, 8861.778455538406, 9033.096690330914, 18855.057247137313, 19086.522836929187, 19131.71302430437, 19321.710174347678, 19516.707249391555, 19071.90464047671, 19168.137433815235, 19366.806331937063, 19390.2243910242, 19848.50378740543, 11932.105092117952, 12145.817812732992, 12220.572279970158, 12231.082668799698, 12249.326287054297, 14156.221407822668, 14295.285235737963, 14371.78442323345, 14387.208703521406, 14389.280535973368, 10811.513481893177, 10829.458527073772, 10886.67339979802, 10952.671419857417, 11060.336379817236, 10523.631672891412, 10526.473676316307, 10701.411422371844, 10911.508982095665, 11008.559657613605, 9083.818323633623, 9104.317176211678, 9130.223930965942, 9185.357025008185, 9211.263098952202, 6898.896516552155, 6917.619530925646, 7022.54353467023, 7082.575045497287, 7092.9645351772915, 6796.864062718619, 6949.235584085847, 7053.964730176316, 7150.785476435715, 7154.964225179049], "value": 6690.244962614274, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.71604938271605, 520.3391692550463, 530.8434011966469, 535.5153586004666, 541.1990254423454, 6447.838804029896, 6498.512611554141, 6499.870002599945, 6508.4142427181605, 6544.541882068257, 6341.95357765968, 6392.105105285264, 6422.775202867901, 6430.292667806538, 6438.356164383571, 5270.1040823060175, 5277.0, 5292.894142117156, 5293.126634105368, 5308.681479111266, 4902.421232500277, 5013.473585273548, 5187.547486703729, 5265.842024739264, 5288.576913846883, 5012.899742005157, 5043.36957880265, 5051.3938327400765, 5059.392872855244, 5082.466341034194, 4986.276989836471, 5004.624653150991, 5069.467705890884, 5101.7194054327, 5107.565856902168, 4372.797480692803, 4473.619742321906, 4487.326900964848, 4522.751248681321, 4528.230200865861, 6170.907436388413, 6237.750489980441, 6241.65670888101, 6251.9687401562705, 6291.6854157291955, 5685.914711279334, 5771.711414429262, 5784.132380143013, 5800.390958949334, 5812.418758124196, 6662.80011599648, 6750.527463077587, 6756.797296081126, 6768.966155169242, 6773.8645227095185, 4979.7261150636705, 4981.227909674007, 4994.250862370637, 4999.100161970844, 5005.147066172283, 6769.661516924134, 6832.99999999995, 6842.384185423336, 6892.483063770137, 7056.682449289794, 4997.600191984632, 5009.323741294951, 5043.571296439771, 5049.8737531562, 5053.0399224147295, 5067.25179812964, 5070.315507406527, 5131.2559678846355, 5139.100657384943, 5148.330717006766, 5627.212190293362, 5693.914591281051, 5739.512141468022, 5742.109972954198, 5760.971195143998, 6621.238557565845, 6669.933300666931, 6698.196216454056, 6701.798946031627, 6728.562643428161, 1780.448061101037, 1788.6780379531808, 1792.4891405949243, 1800.5588630785542, 1803.6212395396851, 1181.9172657913864, 1210.116614871149, 1224.9142560020714, 1244.6826059354808, 1262.8042653388557, 5776.595638305278, 5784.942150578525, 5822.650640961471, 5893.3222679391565, 5928.525717942554, 6741.460683145337, 6748.628825414551, 6838.230516640769, 6881.552699074544, 6939.167299924042, 5865.912011319833, 5886.352501224862, 5891.23413956183, 5938.465538101592, 5956.434138756811, 3575.4458059000895, 3650.3976843820474, 3652.1052342175803, 3673.1184515716036, 3687.575928768226, 6507.7071531781185, 6579.440747536513, 6604.570702904296, 6623.801285961333, 6633.568818026813, 6613.239477460057, 6978.685959131852, 7019.262977387403, 7035.1909530404655, 7107.502474826712, 6378.170837791243, 6443.903341449882, 6489.0, 6524.7602955438315, 6608.306127856602, 6791.694373753292, 6796.728130874907, 6809.148856392951, 6842.178938527409, 6859.211190713132, 6732.926579419576, 6777.152855893013, 6777.864442711217, 6831.692573834289, 6832.214295355899, 6482.902756458562, 6483.708233129426, 6530.510211734044, 6538.574992625464, 6586.80239592813, 5028.924566131438, 5054.140796064751, 5055.0, 5062.31658726082, 5068.062408454448, 6655.700493477902, 6726.226483954408, 6749.392554670103, 6768.763093291711, 6841.0, 6483.189601299838, 6601.860269948802, 6618.63597502142, 6677.966610166918, 6689.397954184147, 6624.8343791405605, 6627.834304142435, 6681.46548276127, 6716.966415168088, 6752.358525940028, 6452.838679033061, 6486.4486518646445, 6578.684263147294, 6635.767748128986, 6670.9332906670315, 6738.528303018742, 6773.458123350219, 6789.796306110825, 6804.353586409283, 6835.965820170867, 6515.641639709672, 6528.412442880163, 6545.574537655038, 6553.868922621617, 6557.573757705733], "value": 5147.617879192357, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [900.7342833864013, 912.6121398405677, 934.654177954157, 936.2089034765622, 939.9671011514598, 19167.137478813453, 20466.488337791543, 20516.076776545073, 20606.690899636516, 20684.896575517138, 20682.27612033579, 22011.33965981023, 22086.337409877728, 22089.226877059307, 22144.446388840246, 915.2906497464452, 985.1872205430512, 994.1102713071797, 994.602159136344, 995.5022488755623, 965.4979410706428, 1002.3284399452384, 1002.6390499420203, 1006.3760468509498, 1006.6325791086274, 1041.8489319048733, 1046.2676126711294, 1047.680457460478, 1048.9423081730502, 1050.9647996723222, 1031.072035168347, 1044.5821671331453, 1044.5926088825365, 1044.8746150461955, 1046.4349251404271, 753.1188509443909, 753.773867839646, 759.4342215049824, 760.3612965109335, 770.4029377232633, 16659.500214993568, 16898.915505422516, 17106.0, 17243.82756172435, 19974.201031958703, 20354.79645203544, 20506.282280120275, 21164.0, 21167.364979050653, 21285.36143915685, 17228.56928576783, 17436.0, 17545.473635790946, 17637.382691605733, 17673.646527069388, 3669.504616876714, 3712.7401081924277, 3738.12154143776, 3739.5886452490204, 3741.588425273245, 129000.35499822441, 130245.0, 130709.0, 134925.0, 135115.0, 16203.351865925231, 16373.91813040927, 16505.504834854975, 16693.165341732863, 16826.411075612294, 27401.86299068492, 27864.86067569649, 28157.57763633547, 28732.712672873422, 30026.84986575097, 8958.86561701575, 9223.769405764775, 9231.307651925998, 9266.768330791649, 9267.21228695555, 4229.217594744982, 4276.978615106905, 4396.560343965578, 4406.823727050882, 4421.5578442156175, 5068.163752980717, 5208.140656791587, 5211.270422140903, 5216.843494695166, 5230.895382092337, 1049.9790004199874, 1065.9520321585546, 1085.934843909368, 1092.5029111754134, 1095.3373209208473, 636.5925807483214, 672.7174586673609, 694.2190036209265, 703.391566295151, 710.8969199466152, 10670.626528071636, 10855.294405863593, 10952.4523773811, 11042.116630669689, 11271.0, 675.1627981303246, 716.5485743981259, 719.1549928833599, 722.3278111172618, 726.9963650181821, 4818.180909245437, 5205.427402985744, 5210.609204309692, 5242.672965847833, 5433.755481003364, 109408.0, 110807.0, 114548.42725786484, 115498.0, 116727.0, 98164.0, 99284.50357748165, 100534.49732751436, 100713.48216294652, 101721.49139254405, 88383.0, 89937.0, 89965.55017224872, 91943.0, 92196.53901730583, 11111.722206944567, 11434.485448154686, 11448.255863368855, 11501.654950351503, 11603.07175425947, 40559.3916091253, 40790.18419631531, 42118.0, 42517.787411062745, 42744.0, 73023.63488182524, 74130.62934685292, 74790.0, 75871.62064189644, 78656.60671696605, 18282.451526454228, 18335.54161145982, 18887.622247555246, 19001.23995040183, 19253.518662033563, 19384.8061519383, 20012.299569515, 20369.79630203679, 20498.0, 20646.07092680863, 16016.599585010468, 16093.436729714404, 16177.757333639769, 16182.838171618605, 16379.09914954689, 15752.369905203663, 16237.43168989127, 16334.0, 16546.255418505956, 16654.583635409213, 14662.78005829892, 15790.60523486876, 16402.83597164061, 16522.42171523991, 16522.58693532623, 12105.939470302592, 12132.393380330772, 12800.487980480677, 12804.039697022574, 13730.176189428666, 12451.25292482454, 12663.936680316909, 12760.872391275969, 12802.031370275245, 12966.35168241603], "value": 9063.258196166664, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1476.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 109.0, 99.0, 106.0, 109.0, 108.0, 110.0, 98.0, 107.0, 108.0], "value": 105.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 126.0, 139.0, 179.0, 116.0, 148.0, 154.0, 118.0, 172.0, 116.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 163.0, 163.0, 164.0, 165.0, 166.0, 168.0, 161.0], "value": 163.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 82.0, 73.0, 157.0, 77.0, 78.0, 73.0, 88.0, 98.0, 75.0], "value": 87.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 127.0, 80.0, 80.0, 80.0, 124.0, 79.0, 79.0, 136.0], "value": 94.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 115.0, 114.0, 113.0, 114.0, 114.0, 115.0, 115.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 148.0, 147.0, 148.0, 149.0, 148.0, 148.0, 149.0, 148.0], "value": 148.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 95.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 52.0, 54.0, 52.0, 51.0, 54.0, 51.0, 52.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0, 38.0], "value": 38.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 74.0, 77.0, 74.0, 74.0, 75.0, 75.0, 75.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 125.0, 119.0, 119.0, 127.0, 121.0, 122.0, 125.0, 135.0, 160.0], "value": 127.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 172.0, 175.0, 170.0, 170.0, 178.0, 173.0, 171.0, 168.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 63.0, 63.0, 64.0, 63.0, 66.0, 62.0, 63.0, 64.0], "value": 63.7, "unit": "score"}]}]} 03:23:08 INFO - Return code: 0 03:23:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:23:08 INFO - # TBPL SUCCESS # 03:23:08 INFO - Running post-action listener: _package_coverage_data 03:23:08 INFO - Running post-action listener: _resource_record_post_action 03:23:08 INFO - [mozharness: 2017-09-04 10:23:08.208814Z] Finished run-tests step (success) 03:23:08 INFO - Running post-run listener: _resource_record_post_run 03:23:08 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 446464; Write bytes: 404189184; Read time: 544; Write time: 396904 03:23:08 INFO - TinderboxPrint: CPU usage
13.1% 03:23:08 INFO - TinderboxPrint: I/O read bytes / time
446,464 / 544 03:23:08 INFO - TinderboxPrint: I/O write bytes / time
404,189,184 / 396,904 03:23:08 INFO - TinderboxPrint: CPU idle
8,241.1 (86.9%) 03:23:08 INFO - TinderboxPrint: CPU user
1,193.6 (12.6%) 03:23:08 INFO - TinderboxPrint: Swap in / out
0 / 0 03:23:08 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 12288; Write bytes: 117690368; Read time: 68; Write time: 107716 03:23:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 03:23:08 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 434176; Write bytes: 286498816; Read time: 476; Write time: 289188 03:23:08 INFO - Running post-run listener: _upload_blobber_files 03:23:08 INFO - Blob upload gear active. 03:23:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:23:08 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 03:23:08 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'] 03:23:08 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 03:23:09 INFO - (blobuploader) - INFO - Open directory for files ... 03:23:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:23:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:10 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:23:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:10 INFO - (blobuploader) - INFO - Done attempting. 03:23:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:23:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:23:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:11 INFO - (blobuploader) - INFO - Done attempting. 03:23:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:23:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:23:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:14 INFO - (blobuploader) - INFO - Done attempting. 03:23:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:23:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:23:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:23:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:23:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:23:15 INFO - (blobuploader) - INFO - Done attempting. 03:23:15 INFO - (blobuploader) - INFO - Iteration through files over. 03:23:15 INFO - Return code: 0 03:23:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:23:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:23:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b84c1641b0a49803194c9391b2ad92b938edced3261a9622611ea96c062ba94ea2fd9cc02855f65cbc3bd1db075447ec91f72f49ef2c212161ff788e2937bb1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b1e18fc56f96625a4eac593b08d4a1e4a189897ac544fe33e1cdea0f130816a215bbb64a891bcab8290c0b005a2685054739d681986a9fa00840dc3ac9b0fbe", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59bd088c96988ab289d537073fa2f34c8f96b5f87624217bbd1ce3c92a290bce6a59dbd7a226f362bd137facc3a950b70765fc67879a8520049d6df3a89c73aa", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f15ef31d77b355262c72be62242c6ad4efbed5f1563c80e94baf9dba5f4cb9cf80a91308e3dacb81fa63348498fc5a7f23b7a9196ac932eca7527ac3b6a9bf32"} 03:23:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:23:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:23:15 INFO - Contents: 03:23:15 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b84c1641b0a49803194c9391b2ad92b938edced3261a9622611ea96c062ba94ea2fd9cc02855f65cbc3bd1db075447ec91f72f49ef2c212161ff788e2937bb1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b1e18fc56f96625a4eac593b08d4a1e4a189897ac544fe33e1cdea0f130816a215bbb64a891bcab8290c0b005a2685054739d681986a9fa00840dc3ac9b0fbe", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59bd088c96988ab289d537073fa2f34c8f96b5f87624217bbd1ce3c92a290bce6a59dbd7a226f362bd137facc3a950b70765fc67879a8520049d6df3a89c73aa", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f15ef31d77b355262c72be62242c6ad4efbed5f1563c80e94baf9dba5f4cb9cf80a91308e3dacb81fa63348498fc5a7f23b7a9196ac932eca7527ac3b6a9bf32"} 03:23:15 INFO - Running post-run listener: copy_logs_to_upload_dir 03:23:15 INFO - Copying logs to upload dir... 03:23:15 INFO - mkdir: /builds/slave/test/build/upload/logs 03:23:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.788043 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2017-09-04 03:23:15.497341) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:23:15.505164) ========= 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=836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468 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/3b84c1641b0a49803194c9391b2ad92b938edced3261a9622611ea96c062ba94ea2fd9cc02855f65cbc3bd1db075447ec91f72f49ef2c212161ff788e2937bb1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b1e18fc56f96625a4eac593b08d4a1e4a189897ac544fe33e1cdea0f130816a215bbb64a891bcab8290c0b005a2685054739d681986a9fa00840dc3ac9b0fbe", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59bd088c96988ab289d537073fa2f34c8f96b5f87624217bbd1ce3c92a290bce6a59dbd7a226f362bd137facc3a950b70765fc67879a8520049d6df3a89c73aa", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f15ef31d77b355262c72be62242c6ad4efbed5f1563c80e94baf9dba5f4cb9cf80a91308e3dacb81fa63348498fc5a7f23b7a9196ac932eca7527ac3b6a9bf32"} build_url:https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/e7aTuZz-Q-mFnC0rW_6dHw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b84c1641b0a49803194c9391b2ad92b938edced3261a9622611ea96c062ba94ea2fd9cc02855f65cbc3bd1db075447ec91f72f49ef2c212161ff788e2937bb1", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b1e18fc56f96625a4eac593b08d4a1e4a189897ac544fe33e1cdea0f130816a215bbb64a891bcab8290c0b005a2685054739d681986a9fa00840dc3ac9b0fbe", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/59bd088c96988ab289d537073fa2f34c8f96b5f87624217bbd1ce3c92a290bce6a59dbd7a226f362bd137facc3a950b70765fc67879a8520049d6df3a89c73aa", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f15ef31d77b355262c72be62242c6ad4efbed5f1563c80e94baf9dba5f4cb9cf80a91308e3dacb81fa63348498fc5a7f23b7a9196ac932eca7527ac3b6a9bf32"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-04 03:23:15.535936) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:23:15.536259) ========= 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=836cfdc75598d54cb747368e000001a9-1504518874.619469-1449247468 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004574 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-04 03:23:15.615560) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:23:15.616970) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-04 03:23:15.617272) ========= ========= Total master_lag: 0.21 =========