builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-082 starttime: 1504151390.99 results: success (0) revision: 04b6be50a2526c7a26a63715f441c47e1aa1f9be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:50.993418) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:50.993837) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:50.996162) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:51.012780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:51.013141) ========= 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=51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-30 20:49:51-- 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% 86.6M=0s 2017-08-30 20:49:51 (86.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124977 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:51.153845) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:51.154181) ========= 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=51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018775 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:51.209002) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:51.209368) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 04b6be50a2526c7a26a63715f441c47e1aa1f9be --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 04b6be50a2526c7a26a63715f441c47e1aa1f9be --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-30 20:49:51,346 truncating revision to first 12 chars 2017-08-30 20:49:51,346 Setting DEBUG logging. 2017-08-30 20:49:51,346 attempt 1/10 2017-08-30 20:49:51,346 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/04b6be50a252?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-30 20:49:51,784 unpacking tar archive at: mozilla-central-04b6be50a252/testing/mozharness/ program finished with exit code 0 elapsedTime=0.811013 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:52.035244) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:52.035570) ========= script_repo_revision: 04b6be50a2526c7a26a63715f441c47e1aa1f9be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:52.044009) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:52.044366) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-30 20:49:52.063459) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-08-30 20:49:52.063846) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:49:52 INFO - MultiFileLogger online at 20170830 20:49:52 in /builds/slave/test 20:49:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 20:49:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:49:52 INFO - {'append_to_log': False, 20:49:52 INFO - 'base_work_dir': '/builds/slave/test', 20:49:52 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 20:49:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:49:52 INFO - 'branch': 'Firefox-Non-PGO', 20:49:52 INFO - 'buildbot_json_path': 'buildprops.json', 20:49:52 INFO - 'code_coverage': False, 20:49:52 INFO - 'config_files': ('talos/linux_config.py',), 20:49:52 INFO - 'default_actions': ('clobber', 20:49:52 INFO - 'read-buildbot-config', 20:49:52 INFO - 'download-and-extract', 20:49:52 INFO - 'populate-webroot', 20:49:52 INFO - 'create-virtualenv', 20:49:52 INFO - 'install', 20:49:52 INFO - 'setup-mitmproxy', 20:49:52 INFO - 'run-tests'), 20:49:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:49:52 INFO - 'disable_ccov_upload': False, 20:49:52 INFO - 'disable_stylo': False, 20:49:52 INFO - 'download_minidump_stackwalk': True, 20:49:52 INFO - 'download_symbols': 'ondemand', 20:49:52 INFO - 'enable_stylo': False, 20:49:52 INFO - 'enable_webrender': False, 20:49:52 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 20:49:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:49:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:49:52 INFO - 'gecko_profile': False, 20:49:52 INFO - 'gecko_profile_interval': 0, 20:49:52 INFO - 'installer_path': 'installer.exe', 20:49:52 INFO - 'log_level': 'info', 20:49:52 INFO - 'log_name': 'talos', 20:49:52 INFO - 'log_to_console': True, 20:49:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:49:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:49:52 INFO - 'opt_config_files': (), 20:49:52 INFO - 'pip_index': False, 20:49:52 INFO - 'suite': 'dromaeojs-e10s', 20:49:52 INFO - 'system_bits': '32', 20:49:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:49:52 INFO - 'title': 'talos-linux64-ix-082', 20:49:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:49:52 INFO - 'use_talos_json': True, 20:49:52 INFO - 'verify': 'False', 20:49:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:49:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:49:52 INFO - 'work_dir': 'build'} 20:49:52 INFO - [mozharness: 2017-08-31 03:49:52.342669Z] Running clobber step. 20:49:52 INFO - Running pre-action listener: _resource_record_pre_action 20:49:52 INFO - Running main action method: clobber 20:49:52 INFO - rmtree: /builds/slave/test/build 20:49:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:49:53 INFO - Running post-action listener: _resource_record_post_action 20:49:53 INFO - [mozharness: 2017-08-31 03:49:53.898523Z] Finished clobber step (success) 20:49:53 INFO - [mozharness: 2017-08-31 03:49:53.898621Z] Running read-buildbot-config step. 20:49:53 INFO - Running pre-action listener: _resource_record_pre_action 20:49:53 INFO - Running main action method: read_buildbot_config 20:49:53 INFO - Using buildbot properties: 20:49:53 INFO - { 20:49:53 INFO - "project": "", 20:49:53 INFO - "product": "firefox", 20:49:53 INFO - "who": "kwierso@gmail.com", 20:49:53 INFO - "installer_path": "public/build/target.tar.bz2", 20:49:53 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 20:49:53 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 20:49:53 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 20:49:53 INFO - "stage_platform": "linux64", 20:49:53 INFO - "basedir": "/builds/slave/test", 20:49:53 INFO - "buildnumber": 801, 20:49:53 INFO - "platform": "ubuntu64_hw", 20:49:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:49:53 INFO - "slavebuilddir": "test", 20:49:53 INFO - "taskId": "ff1iBg-7QiKb2m9M0fXPRw", 20:49:53 INFO - "branch": "mozilla-central", 20:49:53 INFO - "script_repo_revision": "production", 20:49:53 INFO - "revision": "04b6be50a2526c7a26a63715f441c47e1aa1f9be", 20:49:53 INFO - "slavename": "talos-linux64-ix-082", 20:49:53 INFO - "repo_path": "mozilla-central" 20:49:53 INFO - } 20:49:53 INFO - Finding installer, test and symbols from parent task. 20:49:53 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ff1iBg-7QiKb2m9M0fXPRw'}, attempt #1 20:49:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ff1iBg-7QiKb2m9M0fXPRw'}, attempt #1 20:49:54 INFO - Task dependencies: Cu5bJiXdSKGYWl3gR-gpGg 20:49:54 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg'}, attempt #1 20:49:55 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:49:55 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json 20:49:55 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip 20:49:55 INFO - Running post-action listener: _resource_record_post_action 20:49:55 INFO - [mozharness: 2017-08-31 03:49:55.298722Z] Finished read-buildbot-config step (success) 20:49:55 INFO - [mozharness: 2017-08-31 03:49:55.298938Z] Running download-and-extract step. 20:49:55 INFO - Running pre-action listener: _resource_record_pre_action 20:49:55 INFO - Running main action method: download_and_extract 20:49:55 INFO - mkdir: /builds/slave/test/build/tests 20:49:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:49:55 INFO - trying https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json 20:49:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 20:49:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 20:49:55 INFO - Downloaded 989 bytes. 20:49:55 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 20:49:55 INFO - Using the following test package requirements: 20:49:55 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 20:49:55 INFO - u'common': [u'target.common.tests.zip'], 20:49:55 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 20:49:55 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 20:49:55 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 20:49:55 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 20:49:55 INFO - u'mozbase': [u'target.common.tests.zip'], 20:49:55 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 20:49:55 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 20:49:55 INFO - u'web-platform': [u'target.common.tests.zip', 20:49:55 INFO - u'target.web-platform.tests.tar.gz'], 20:49:55 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 20:49:55 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 20:49:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip 20:49:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:49:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip into memory 20:49:56 INFO - Content-Length response header: 54950260 20:49:56 INFO - Bytes received: 54950260 20:50:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 20:50:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip 20:50:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip'}, attempt #1 20:50:08 INFO - Fetch https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.common.tests.zip into memory 20:50:09 INFO - Content-Length response header: 54950260 20:50:09 INFO - Bytes received: 54950260 20:50:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.talos.tests.zip 20:50:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 20:50:21 INFO - Fetch https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.talos.tests.zip into memory 20:50:21 INFO - Content-Length response header: 14496650 20:50:21 INFO - Bytes received: 14496650 20:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:22 INFO - trying https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:50:22 INFO - Downloading https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 20:50:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 20:50:23 INFO - Downloaded 62651819 bytes. 20:50:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:50:23 INFO - mkdir: /builds/slave/test/properties 20:50:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:50:23 INFO - Writing to file /builds/slave/test/properties/build_url 20:50:23 INFO - Contents: 20:50:23 INFO - build_url:https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 20:50:23 INFO - Running post-action listener: _resource_record_post_action 20:50:23 INFO - Running post-action listener: find_tests_for_verification 20:50:23 INFO - Running post-action listener: set_extra_try_arguments 20:50:23 INFO - [mozharness: 2017-08-31 03:50:23.388041Z] Finished download-and-extract step (success) 20:50:23 INFO - [mozharness: 2017-08-31 03:50:23.388137Z] Running populate-webroot step. 20:50:23 INFO - Running pre-action listener: _resource_record_pre_action 20:50:23 INFO - Running main action method: populate_webroot 20:50:23 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:50:23 INFO - 'chromez-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:50:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:50:23 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:50:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:50:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:50:23 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:50:23 INFO - 'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:50:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:50:23 INFO - 'tests': ['damp', 'tps']}, 20:50:23 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:50:23 INFO - 'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['damp', 'tps']}, 20:50:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:50:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:50:23 INFO - 'g4-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 20:50:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 20:50:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:50:23 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:50:23 INFO - 'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 20:50:23 INFO - 'other-e10s': {'tests': ['a11yr', 20:50:23 INFO - 'ts_paint', 20:50:23 INFO - 'tpaint', 20:50:23 INFO - 'sessionrestore', 20:50:23 INFO - 'sessionrestore_many_windows', 20:50:23 INFO - 'sessionrestore_no_auto_restore', 20:50:23 INFO - 'tabpaint']}, 20:50:23 INFO - 'other-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['a11yr', 20:50:23 INFO - 'ts_paint', 20:50:23 INFO - 'tpaint', 20:50:23 INFO - 'sessionrestore', 20:50:23 INFO - 'sessionrestore_many_windows', 20:50:23 INFO - 'sessionrestore_no_auto_restore', 20:50:23 INFO - 'tabpaint']}, 20:50:23 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 20:50:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 20:50:23 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['bloom_basic_singleton']}, 20:50:23 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['bloom_basic']}, 20:50:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:50:23 INFO - 'tsvgr_opacity', 20:50:23 INFO - 'tart', 20:50:23 INFO - 'tscrollx', 20:50:23 INFO - 'tsvg_static']}, 20:50:23 INFO - 'svgr-stylo-e10s': {'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['tsvgx', 20:50:23 INFO - 'tsvgr_opacity', 20:50:23 INFO - 'tart', 20:50:23 INFO - 'tscrollx', 20:50:23 INFO - 'tsvg_static']}, 20:50:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:50:23 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:50:23 INFO - 'talos_options': ['--enable-stylo'], 20:50:23 INFO - 'tests': ['tp5o']}, 20:50:23 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:50:23 INFO - 'talos_options': ['--mitmproxy', 20:50:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:50:23 INFO - '--firstNonBlankPaint'], 20:50:23 INFO - 'tests': ['tp6_google', 20:50:23 INFO - 'tp6_youtube', 20:50:23 INFO - 'tp6_amazon', 20:50:23 INFO - 'tp6_facebook']}, 20:50:23 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:50:23 INFO - 'talos_options': ['--enable-stylo', 20:50:23 INFO - '--mitmproxy', 20:50:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:50:23 INFO - '--firstNonBlankPaint'], 20:50:23 INFO - 'tests': ['tp6_google', 20:50:23 INFO - 'tp6_youtube', 20:50:23 INFO - 'tp6_amazon', 20:50:23 INFO - 'tp6_facebook']}, 20:50:23 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 20:50:23 INFO - 'talos_options': ['--enable-stylo', 20:50:23 INFO - '--stylo-threads=1', 20:50:23 INFO - '--mitmproxy', 20:50:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 20:50:23 INFO - '--firstNonBlankPaint'], 20:50:23 INFO - 'tests': ['tp6_google', 20:50:23 INFO - 'tp6_youtube', 20:50:23 INFO - 'tp6_amazon', 20:50:23 INFO - 'tp6_facebook']}, 20:50:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:50:23 INFO - 'talos_options': ['--xperf_path', 20:50:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:50:23 INFO - 'tests': ['tp5n']}, 20:50:23 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 20:50:23 INFO - 'talos_options': ['--enable-stylo', 20:50:23 INFO - '--xperf_path', 20:50:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:50:23 INFO - 'tests': ['tp5n']}}} 20:50:23 INFO - Running post-action listener: _resource_record_post_action 20:50:23 INFO - [mozharness: 2017-08-31 03:50:23.402493Z] Finished populate-webroot step (success) 20:50:23 INFO - [mozharness: 2017-08-31 03:50:23.402582Z] Running create-virtualenv step. 20:50:23 INFO - Running pre-action listener: _resource_record_pre_action 20:50:23 INFO - Running main action method: create_virtualenv 20:50:23 INFO - Creating virtualenv /builds/slave/test/build/venv 20:50:23 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:50:23 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 20:50:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 20:50:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:50:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:50:24 INFO - Installing distribute.............................................................................................................................................................................................done. 20:50:24 INFO - Installing pip...............done. 20:50:24 INFO - Return code: 0 20:50:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:50:24 INFO - Reading from file tmpfile_stdout 20:50:24 INFO - Output received: 20:50:24 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 20:50:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:50:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:50:24 INFO - Using env: {'DISPLAY': ':0', 20:50:24 INFO - 'HOME': '/home/cltbld', 20:50:24 INFO - 'LANG': 'en_US.UTF-8', 20:50:24 INFO - 'LANGUAGE': 'en_US:en', 20:50:24 INFO - 'LOGNAME': 'cltbld', 20:50:24 INFO - 'MAIL': '/var/mail/cltbld', 20:50:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:24 INFO - 'MOZ_NO_REMOTE': '1', 20:50:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:24 INFO - 'NO_EM_RESTART': '1', 20:50:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:24 INFO - 'PWD': '/builds/slave/test', 20:50:24 INFO - 'SHELL': '/bin/bash', 20:50:24 INFO - 'SHLVL': '1', 20:50:24 INFO - 'TERM': 'linux', 20:50:24 INFO - 'TMOUT': '86400', 20:50:24 INFO - 'USER': 'cltbld', 20:50:24 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:24 INFO - '_': '/tools/buildbot/bin/python'} 20:50:25 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:50:25 INFO - Downloading/unpacking pip>=1.5 20:50:25 INFO - Running setup.py egg_info for package pip 20:50:25 INFO - warning: no files found matching 'pip/cacert.pem' 20:50:25 INFO - warning: no files found matching '*.html' under directory 'docs' 20:50:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:50:25 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:50:25 INFO - Installing collected packages: pip 20:50:25 INFO - Found existing installation: pip 1.1 20:50:25 INFO - Uninstalling pip: 20:50:25 INFO - Successfully uninstalled pip 20:50:25 INFO - Running setup.py install for pip 20:50:25 INFO - warning: no files found matching 'pip/cacert.pem' 20:50:25 INFO - warning: no files found matching '*.html' under directory 'docs' 20:50:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 20:50:25 INFO - no previously-included directories found matching 'docs/_build/_sources' 20:50:25 INFO - Installing pip script to /builds/slave/test/build/venv/bin 20:50:25 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 20:50:25 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 20:50:25 INFO - Successfully installed pip 20:50:25 INFO - Cleaning up... 20:50:25 INFO - Return code: 0 20:50:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:50:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:25 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 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:50:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:50:25 INFO - Using env: {'DISPLAY': ':0', 20:50:25 INFO - 'HOME': '/home/cltbld', 20:50:25 INFO - 'LANG': 'en_US.UTF-8', 20:50:25 INFO - 'LANGUAGE': 'en_US:en', 20:50:25 INFO - 'LOGNAME': 'cltbld', 20:50:25 INFO - 'MAIL': '/var/mail/cltbld', 20:50:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:25 INFO - 'MOZ_NO_REMOTE': '1', 20:50:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:25 INFO - 'NO_EM_RESTART': '1', 20:50:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:25 INFO - 'PWD': '/builds/slave/test', 20:50:25 INFO - 'SHELL': '/bin/bash', 20:50:25 INFO - 'SHLVL': '1', 20:50:25 INFO - 'TERM': 'linux', 20:50:25 INFO - 'TMOUT': '86400', 20:50:25 INFO - 'USER': 'cltbld', 20:50:25 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:25 INFO - '_': '/tools/buildbot/bin/python'} 20:50:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:25 INFO - Downloading/unpacking psutil>=3.1.1 20:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:50:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:50:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:50:27 INFO - Installing collected packages: psutil 20:50:27 INFO - Running setup.py install for psutil 20:50:27 INFO - building 'psutil._psutil_linux' extension 20:50:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 20:50:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 20:50:27 INFO - building 'psutil._psutil_posix' extension 20:50:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 20:50:27 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 20:50:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:50:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:50:27 INFO - Successfully installed psutil 20:50:27 INFO - Cleaning up... 20:50:27 INFO - Return code: 0 20:50:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:27 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 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:50:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:50:27 INFO - Using env: {'DISPLAY': ':0', 20:50:27 INFO - 'HOME': '/home/cltbld', 20:50:27 INFO - 'LANG': 'en_US.UTF-8', 20:50:27 INFO - 'LANGUAGE': 'en_US:en', 20:50:27 INFO - 'LOGNAME': 'cltbld', 20:50:27 INFO - 'MAIL': '/var/mail/cltbld', 20:50:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:27 INFO - 'MOZ_NO_REMOTE': '1', 20:50:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:27 INFO - 'NO_EM_RESTART': '1', 20:50:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:27 INFO - 'PWD': '/builds/slave/test', 20:50:27 INFO - 'SHELL': '/bin/bash', 20:50:27 INFO - 'SHLVL': '1', 20:50:27 INFO - 'TERM': 'linux', 20:50:27 INFO - 'TMOUT': '86400', 20:50:27 INFO - 'USER': 'cltbld', 20:50:27 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:27 INFO - '_': '/tools/buildbot/bin/python'} 20:50:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:50:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:50:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:50:29 INFO - Installing collected packages: mozsystemmonitor 20:50:29 INFO - Running setup.py install for mozsystemmonitor 20:50:30 INFO - Successfully installed mozsystemmonitor 20:50:30 INFO - Cleaning up... 20:50:30 INFO - Return code: 0 20:50:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:50:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:50:30 INFO - Using env: {'DISPLAY': ':0', 20:50:30 INFO - 'HOME': '/home/cltbld', 20:50:30 INFO - 'LANG': 'en_US.UTF-8', 20:50:30 INFO - 'LANGUAGE': 'en_US:en', 20:50:30 INFO - 'LOGNAME': 'cltbld', 20:50:30 INFO - 'MAIL': '/var/mail/cltbld', 20:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:30 INFO - 'MOZ_NO_REMOTE': '1', 20:50:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:30 INFO - 'NO_EM_RESTART': '1', 20:50:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:30 INFO - 'PWD': '/builds/slave/test', 20:50:30 INFO - 'SHELL': '/bin/bash', 20:50:30 INFO - 'SHLVL': '1', 20:50:30 INFO - 'TERM': 'linux', 20:50:30 INFO - 'TMOUT': '86400', 20:50:30 INFO - 'USER': 'cltbld', 20:50:30 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:30 INFO - '_': '/tools/buildbot/bin/python'} 20:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:30 INFO - Downloading/unpacking jsonschema==2.5.1 20:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:50:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:31 INFO - Downloading functools32-3.2.3-2.tar.gz 20:50:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:50:31 INFO - warning: no files found matching '*.txt' 20:50:31 INFO - no previously-included directories found matching 'build' 20:50:31 INFO - no previously-included directories found matching 'dist' 20:50:31 INFO - no previously-included directories found matching '.git*' 20:50:31 INFO - Installing collected packages: jsonschema, functools32 20:50:31 INFO - Running setup.py install for functools32 20:50:31 INFO - warning: no files found matching '*.txt' 20:50:31 INFO - no previously-included directories found matching 'build' 20:50:31 INFO - no previously-included directories found matching 'dist' 20:50:31 INFO - no previously-included directories found matching '.git*' 20:50:31 INFO - Successfully installed jsonschema functools32 20:50:31 INFO - Cleaning up... 20:50:31 INFO - Return code: 0 20:50:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:31 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 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:50:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:50:31 INFO - Using env: {'DISPLAY': ':0', 20:50:31 INFO - 'HOME': '/home/cltbld', 20:50:31 INFO - 'LANG': 'en_US.UTF-8', 20:50:31 INFO - 'LANGUAGE': 'en_US:en', 20:50:31 INFO - 'LOGNAME': 'cltbld', 20:50:31 INFO - 'MAIL': '/var/mail/cltbld', 20:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:31 INFO - 'MOZ_NO_REMOTE': '1', 20:50:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:31 INFO - 'NO_EM_RESTART': '1', 20:50:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:31 INFO - 'PWD': '/builds/slave/test', 20:50:31 INFO - 'SHELL': '/bin/bash', 20:50:31 INFO - 'SHLVL': '1', 20:50:31 INFO - 'TERM': 'linux', 20:50:31 INFO - 'TMOUT': '86400', 20:50:31 INFO - 'USER': 'cltbld', 20:50:31 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:31 INFO - '_': '/tools/buildbot/bin/python'} 20:50:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:50:31 INFO - Cleaning up... 20:50:31 INFO - Return code: 0 20:50:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:31 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 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:50:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:50:31 INFO - Using env: {'DISPLAY': ':0', 20:50:31 INFO - 'HOME': '/home/cltbld', 20:50:31 INFO - 'LANG': 'en_US.UTF-8', 20:50:31 INFO - 'LANGUAGE': 'en_US:en', 20:50:31 INFO - 'LOGNAME': 'cltbld', 20:50:31 INFO - 'MAIL': '/var/mail/cltbld', 20:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:31 INFO - 'MOZ_NO_REMOTE': '1', 20:50:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:31 INFO - 'NO_EM_RESTART': '1', 20:50:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:31 INFO - 'PWD': '/builds/slave/test', 20:50:31 INFO - 'SHELL': '/bin/bash', 20:50:31 INFO - 'SHLVL': '1', 20:50:31 INFO - 'TERM': 'linux', 20:50:31 INFO - 'TMOUT': '86400', 20:50:31 INFO - 'USER': 'cltbld', 20:50:31 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:31 INFO - '_': '/tools/buildbot/bin/python'} 20:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:32 INFO - Downloading/unpacking blobuploader==1.2.4 20:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:33 INFO - Downloading blobuploader-1.2.4.tar.gz 20:50:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:50:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:50:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:33 INFO - Downloading docopt-0.6.1.tar.gz 20:50:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:50:33 INFO - Installing collected packages: blobuploader, requests, docopt 20:50:33 INFO - Running setup.py install for blobuploader 20:50:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:50:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:50:33 INFO - Running setup.py install for requests 20:50:34 INFO - Running setup.py install for docopt 20:50:34 INFO - Successfully installed blobuploader requests docopt 20:50:34 INFO - Cleaning up... 20:50:34 INFO - Return code: 0 20:50:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:34 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 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:50:34 INFO - Using env: {'DISPLAY': ':0', 20:50:34 INFO - 'HOME': '/home/cltbld', 20:50:34 INFO - 'LANG': 'en_US.UTF-8', 20:50:34 INFO - 'LANGUAGE': 'en_US:en', 20:50:34 INFO - 'LOGNAME': 'cltbld', 20:50:34 INFO - 'MAIL': '/var/mail/cltbld', 20:50:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:34 INFO - 'MOZ_NO_REMOTE': '1', 20:50:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:34 INFO - 'NO_EM_RESTART': '1', 20:50:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:34 INFO - 'PWD': '/builds/slave/test', 20:50:34 INFO - 'SHELL': '/bin/bash', 20:50:34 INFO - 'SHLVL': '1', 20:50:34 INFO - 'TERM': 'linux', 20:50:34 INFO - 'TMOUT': '86400', 20:50:34 INFO - 'USER': 'cltbld', 20:50:34 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:34 INFO - '_': '/tools/buildbot/bin/python'} 20:50:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:50:34 INFO - Running setup.py (path:/tmp/pip-3bmQQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:50:34 INFO - Running setup.py (path:/tmp/pip-Q7roem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:50:34 INFO - Running setup.py (path:/tmp/pip-jwXByY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:50:34 INFO - Running setup.py (path:/tmp/pip-4AacA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:50:34 INFO - Running setup.py (path:/tmp/pip-MvU5c2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:50:35 INFO - Running setup.py (path:/tmp/pip-kOKUmO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:50:35 INFO - Running setup.py (path:/tmp/pip-7C9xzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:50:35 INFO - Running setup.py (path:/tmp/pip-spPvAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:50:35 INFO - Running setup.py (path:/tmp/pip-V3tAfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:50:35 INFO - Running setup.py (path:/tmp/pip-FAOsdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:50:35 INFO - Running setup.py (path:/tmp/pip-CUNy5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:50:35 INFO - Running setup.py (path:/tmp/pip-sSIPwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:50:35 INFO - Running setup.py (path:/tmp/pip-FYpxss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:50:35 INFO - Running setup.py (path:/tmp/pip-Wzmb7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:50:35 INFO - Running setup.py (path:/tmp/pip-AP5vfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:50:35 INFO - Running setup.py (path:/tmp/pip-MY_Xjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:50:35 INFO - Running setup.py (path:/tmp/pip-_akqbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:50:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:50:35 INFO - Running setup.py install for manifestparser 20:50:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:50:35 INFO - Running setup.py install for mozcrash 20:50:36 INFO - Running setup.py install for mozdebug 20:50:36 INFO - Running setup.py install for mozdevice 20:50:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:50:36 INFO - Running setup.py install for mozfile 20:50:36 INFO - Running setup.py install for mozhttpd 20:50:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:50:36 INFO - Running setup.py install for mozinfo 20:50:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:50:36 INFO - Running setup.py install for mozInstall 20:50:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:50:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:50:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:50:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:50:36 INFO - Running setup.py install for mozleak 20:50:36 INFO - Running setup.py install for mozlog 20:50:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:50:36 INFO - Running setup.py install for moznetwork 20:50:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:50:36 INFO - Running setup.py install for mozprocess 20:50:37 INFO - Running setup.py install for mozprofile 20:50:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:50:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:50:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:50:37 INFO - Running setup.py install for mozrunner 20:50:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:50:37 INFO - Running setup.py install for mozscreenshot 20:50:37 INFO - Running setup.py install for moztest 20:50:37 INFO - Running setup.py install for mozversion 20:50:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:50:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:50:37 INFO - Cleaning up... 20:50:37 INFO - Return code: 0 20:50:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:37 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 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:50:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:50:37 INFO - Using env: {'DISPLAY': ':0', 20:50:37 INFO - 'HOME': '/home/cltbld', 20:50:37 INFO - 'LANG': 'en_US.UTF-8', 20:50:37 INFO - 'LANGUAGE': 'en_US:en', 20:50:37 INFO - 'LOGNAME': 'cltbld', 20:50:37 INFO - 'MAIL': '/var/mail/cltbld', 20:50:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:37 INFO - 'MOZ_NO_REMOTE': '1', 20:50:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:37 INFO - 'NO_EM_RESTART': '1', 20:50:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:37 INFO - 'PWD': '/builds/slave/test', 20:50:37 INFO - 'SHELL': '/bin/bash', 20:50:37 INFO - 'SHLVL': '1', 20:50:37 INFO - 'TERM': 'linux', 20:50:37 INFO - 'TMOUT': '86400', 20:50:37 INFO - 'USER': 'cltbld', 20:50:37 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:37 INFO - '_': '/tools/buildbot/bin/python'} 20:50:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:50:37 INFO - Running setup.py (path:/tmp/pip-Hr1HUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:50:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:50:37 INFO - Running setup.py (path:/tmp/pip-vrNIxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:50:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:50:38 INFO - Running setup.py (path:/tmp/pip-_BpcHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:50:38 INFO - Running setup.py (path:/tmp/pip-rbibSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:50:38 INFO - Running setup.py (path:/tmp/pip-8KAY_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:50:38 INFO - Running setup.py (path:/tmp/pip-BJ3Yo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:50:38 INFO - Running setup.py (path:/tmp/pip-JmlIx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:50:38 INFO - Running setup.py (path:/tmp/pip-CTsFj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:50:38 INFO - Running setup.py (path:/tmp/pip-_qVAXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:50:38 INFO - Running setup.py (path:/tmp/pip-l0I8Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:50:38 INFO - Running setup.py (path:/tmp/pip-u1D0uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:50:38 INFO - Running setup.py (path:/tmp/pip-8yTYcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:50:38 INFO - Running setup.py (path:/tmp/pip-RILPt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:50:38 INFO - Running setup.py (path:/tmp/pip-KP2ku2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:50:38 INFO - Running setup.py (path:/tmp/pip-HOLzL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:50:38 INFO - Running setup.py (path:/tmp/pip-xjacLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:50:38 INFO - Running setup.py (path:/tmp/pip-gNGovL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:50:39 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:50:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:40 INFO - Downloading blessings-1.6.tar.gz 20:50:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:50:40 INFO - Installing collected packages: blessings 20:50:40 INFO - Running setup.py install for blessings 20:50:40 INFO - Successfully installed blessings 20:50:40 INFO - Cleaning up... 20:50:40 INFO - Return code: 0 20:50:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:50:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:50:40 INFO - Reading from file tmpfile_stdout 20:50:40 INFO - Current package versions: 20:50:40 INFO - argparse == 1.2.1 20:50:40 INFO - blessings == 1.6 20:50:40 INFO - blobuploader == 1.2.4 20:50:40 INFO - docopt == 0.6.1 20:50:40 INFO - functools32 == 3.2.3-2 20:50:40 INFO - jsonschema == 2.5.1 20:50:40 INFO - manifestparser == 1.1 20:50:40 INFO - mozInstall == 1.13 20:50:40 INFO - mozcrash == 1.0 20:50:40 INFO - mozdebug == 0.1 20:50:40 INFO - mozdevice == 0.51 20:50:40 INFO - mozfile == 1.2 20:50:40 INFO - mozhttpd == 0.7 20:50:40 INFO - mozinfo == 0.10 20:50:40 INFO - mozleak == 0.1 20:50:40 INFO - mozlog == 3.5 20:50:40 INFO - moznetwork == 0.27 20:50:40 INFO - mozprocess == 0.25 20:50:40 INFO - mozprofile == 0.28 20:50:40 INFO - mozrunner == 6.13 20:50:40 INFO - mozscreenshot == 0.1 20:50:40 INFO - mozsystemmonitor == 0.3 20:50:40 INFO - moztest == 0.8 20:50:40 INFO - mozversion == 1.4 20:50:40 INFO - psutil == 3.1.1 20:50:40 INFO - requests == 1.2.3 20:50:40 INFO - wsgiref == 0.1.2 20:50:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:40 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 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:50:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:50:40 INFO - Using env: {'DISPLAY': ':0', 20:50:40 INFO - 'HOME': '/home/cltbld', 20:50:40 INFO - 'LANG': 'en_US.UTF-8', 20:50:40 INFO - 'LANGUAGE': 'en_US:en', 20:50:40 INFO - 'LOGNAME': 'cltbld', 20:50:40 INFO - 'MAIL': '/var/mail/cltbld', 20:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:40 INFO - 'MOZ_NO_REMOTE': '1', 20:50:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:40 INFO - 'NO_EM_RESTART': '1', 20:50:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:40 INFO - 'PWD': '/builds/slave/test', 20:50:40 INFO - 'SHELL': '/bin/bash', 20:50:40 INFO - 'SHLVL': '1', 20:50:40 INFO - 'TERM': 'linux', 20:50:40 INFO - 'TMOUT': '86400', 20:50:40 INFO - 'USER': 'cltbld', 20:50:40 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:40 INFO - '_': '/tools/buildbot/bin/python'} 20:50:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:50:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:50:40 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 20:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:50:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 20:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:50:42 INFO - Installing collected packages: simplejson 20:50:42 INFO - Running setup.py install for simplejson 20:50:42 INFO - building 'simplejson._speedups' extension 20:50:42 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 20:50:42 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 20:50:43 INFO - Successfully installed simplejson 20:50:43 INFO - Cleaning up... 20:50:43 INFO - Return code: 0 20:50:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:50:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:50:43 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 0x17c01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1988a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b2fb10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, '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': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 20:50:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:50:43 INFO - Using env: {'DISPLAY': ':0', 20:50:43 INFO - 'HOME': '/home/cltbld', 20:50:43 INFO - 'LANG': 'en_US.UTF-8', 20:50:43 INFO - 'LANGUAGE': 'en_US:en', 20:50:43 INFO - 'LOGNAME': 'cltbld', 20:50:43 INFO - 'MAIL': '/var/mail/cltbld', 20:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:50:43 INFO - 'MOZ_NO_REMOTE': '1', 20:50:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:50:43 INFO - 'NO_EM_RESTART': '1', 20:50:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:50:43 INFO - 'PWD': '/builds/slave/test', 20:50:43 INFO - 'SHELL': '/bin/bash', 20:50:43 INFO - 'SHLVL': '1', 20:50:43 INFO - 'TERM': 'linux', 20:50:43 INFO - 'TMOUT': '86400', 20:50:43 INFO - 'USER': 'cltbld', 20:50:43 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:50:43 INFO - '_': '/tools/buildbot/bin/python'} 20:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:50:43 INFO - Cleaning up... 20:50:43 INFO - Return code: 0 20:50:43 INFO - Running post-action listener: _resource_record_post_action 20:50:43 INFO - Running post-action listener: _start_resource_monitoring 20:50:43 INFO - Starting resource monitoring. 20:50:43 INFO - [mozharness: 2017-08-31 03:50:43.328467Z] Finished create-virtualenv step (success) 20:50:43 INFO - [mozharness: 2017-08-31 03:50:43.328936Z] Running install step. 20:50:43 INFO - Running pre-action listener: _resource_record_pre_action 20:50:43 INFO - Running main action method: install 20:50:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:50:43 INFO - Reading from file tmpfile_stdout 20:50:43 INFO - Detecting whether we're running mozinstall >=1.0... 20:50:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:50:43 INFO - Reading from file tmpfile_stdout 20:50:43 INFO - Output received: 20:50:43 INFO - Usage: mozinstall [options] installer 20:50:43 INFO - Options: 20:50:43 INFO - -h, --help show this help message and exit 20:50:43 INFO - -d DEST, --destination=DEST 20:50:43 INFO - Directory to install application into. [default: 20:50:43 INFO - "/builds/slave/test"] 20:50:43 INFO - --app=APP Application being installed. [default: firefox] 20:50:43 INFO - mkdir: /builds/slave/test/build/application 20:50:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 20:51:00 INFO - Reading from file tmpfile_stdout 20:51:00 INFO - Output received: 20:51:00 INFO - /builds/slave/test/build/application/firefox/firefox 20:51:00 INFO - Running post-action listener: _resource_record_post_action 20:51:00 INFO - [mozharness: 2017-08-31 03:51:00.144977Z] Finished install step (success) 20:51:00 INFO - [mozharness: 2017-08-31 03:51:00.145255Z] Running setup-mitmproxy step. 20:51:00 INFO - Running pre-action listener: _resource_record_pre_action 20:51:00 INFO - Running main action method: setup_mitmproxy 20:51:00 INFO - Skipping: mitmproxy is not required 20:51:00 INFO - Running post-action listener: _resource_record_post_action 20:51:00 INFO - [mozharness: 2017-08-31 03:51:00.146454Z] Finished setup-mitmproxy step (success) 20:51:00 INFO - [mozharness: 2017-08-31 03:51:00.146675Z] Running run-tests step. 20:51:00 INFO - Running pre-action listener: _resource_record_pre_action 20:51:00 INFO - Running pre-action listener: _set_gcov_prefix 20:51:00 INFO - Running main action method: run_tests 20:51:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:51:00 INFO - Python 2.7.3 20:51:00 INFO - Return code: 0 20:51:00 INFO - grabbing minidump binary from tooltool 20:51: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')]} 20:51:00 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 0x1b2c580>, '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 0x1b2d480>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b2d910>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:51:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:51:00 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 20:51:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:51:00 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:51:00 INFO - Return code: 0 20:51:00 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:51:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:51:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:51:00 INFO - ENV: RUST_BACKTRACE is now full 20:51:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:51:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:51:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 20:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-082 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 20:51:00 INFO - Using env: {'DISPLAY': ':0', 20:51:00 INFO - 'HOME': '/home/cltbld', 20:51:00 INFO - 'LANG': 'en_US.UTF-8', 20:51:00 INFO - 'LANGUAGE': 'en_US:en', 20:51:00 INFO - 'LOGNAME': 'cltbld', 20:51:00 INFO - 'MAIL': '/var/mail/cltbld', 20:51:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:51:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:00 INFO - 'MOZ_NO_REMOTE': '1', 20:51:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:51:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:51:00 INFO - 'NO_EM_RESTART': '1', 20:51:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:00 INFO - 'PWD': '/builds/slave/test', 20:51:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:51:00 INFO - 'RUST_BACKTRACE': 'full', 20:51:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:51:00 INFO - 'SHELL': '/bin/bash', 20:51:00 INFO - 'SHLVL': '1', 20:51:00 INFO - 'TERM': 'linux', 20:51:00 INFO - 'TMOUT': '86400', 20:51:00 INFO - 'USER': 'cltbld', 20:51:00 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:00 INFO - '_': '/tools/buildbot/bin/python'} 20:51:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-082', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 20:51:00 INFO - mozversion application_buildid: 20170831025255 20:51:00 INFO - mozversion application_changeset: 04b6be50a2526c7a26a63715f441c47e1aa1f9be 20:51:00 INFO - mozversion application_display_name: Nightly 20:51:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:51:00 INFO - mozversion application_name: Firefox 20:51:00 INFO - mozversion application_remotingname: firefox 20:51:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:51:00 INFO - mozversion application_vendor: Mozilla 20:51:00 INFO - mozversion application_version: 57.0a1 20:51:00 INFO - mozversion platform_buildid: 20170831025255 20:51:00 INFO - mozversion platform_changeset: 04b6be50a2526c7a26a63715f441c47e1aa1f9be 20:51:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:51:00 INFO - mozversion platform_version: 57.0a1 20:51:00 INFO - using testdate: 1504151460 20:51:00 INFO - actual date: 1504151460 20:51:00 INFO - starting webserver on 'localhost:54756' 20:51:00 INFO - SUITE-START | Running 2 tests 20:51:00 INFO - TEST-START | dromaeo_css 20:51:00 INFO - Initialising browser for dromaeo_css test... 20:51:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54756/getInfo.html -profile /tmp/tmp5NfiA1/profile 20:51:00 INFO - TEST-INFO | started process 29874 (/builds/slave/test/build/application/firefox/firefox http://localhost:54756/getInfo.html) 20:51:05 INFO - TEST-INFO | 29874: exit 0 20:51:05 INFO - Browser initialized. 20:51:05 INFO - Running cycle 1/1 for dromaeo_css test... 20:51:05 INFO - Using env: {'DISPLAY': ':0', 20:51:05 INFO - 'HOME': '/home/cltbld', 20:51:05 INFO - 'JSGC_DISABLE_POISONING': '1', 20:51:05 INFO - 'LANG': 'en_US.UTF-8', 20:51:05 INFO - 'LANGUAGE': 'en_US:en', 20:51:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 20:51:05 INFO - 'LOGNAME': 'cltbld', 20:51:05 INFO - 'MAIL': '/var/mail/cltbld', 20:51:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:51:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:51:05 INFO - 'MOZ_CRASHREPORTER': '1', 20:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:51:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 20:51:05 INFO - 'MOZ_NO_REMOTE': '1', 20:51:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:51:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:51:05 INFO - 'NO_EM_RESTART': '1', 20:51:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:51:05 INFO - 'PWD': '/builds/slave/test', 20:51:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:51:05 INFO - 'RUST_BACKTRACE': 'full', 20:51:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 20:51:05 INFO - 'SHELL': '/bin/bash', 20:51:05 INFO - 'SHLVL': '1', 20:51:05 INFO - 'TERM': 'linux', 20:51:05 INFO - 'TMOUT': '86400', 20:51:05 INFO - 'USER': 'cltbld', 20:51:05 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 20:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:51:05 INFO - '_': '/tools/buildbot/bin/python'} 20:51:05 INFO - TEST-INFO | started process 30055 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5NfiA1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 20:51:06 INFO - PID 30055 | 20:51:06 INFO - PID 30055 | (/builds/slave/test/build/application/firefox/firefox:30106): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 20:51:06 INFO - PID 30055 | 20:51:17 INFO - PID 30055 | RSS: Main: 169615360 20:51:17 INFO - PID 30055 | 20:54:16 INFO - PID 30055 | Cycle 1(1): loaded http://localhost:54756/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54756/tests/dromaeo/cssquery-ext.html) 20:54:17 INFO - PID 30055 | RSS: Main: 171388928 20:54:17 INFO - PID 30055 | 20:57:19 INFO - PID 30055 | Cycle 1(1): loaded http://localhost:54756/tests/dromaeo/cssquery-ext.html (next: http://localhost:54756/tests/dromaeo/cssquery-jquery.html) 20:57:19 INFO - PID 30055 | RSS: Main: 166096896 20:57:19 INFO - PID 30055 | 21:00:21 INFO - PID 30055 | Cycle 1(1): loaded http://localhost:54756/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54756/tests/dromaeo/cssquery-mootools.html) 21:00:21 INFO - PID 30055 | RSS: Main: 168456192 21:00:21 INFO - PID 30055 | 21:03:23 INFO - PID 30055 | Cycle 1(1): loaded http://localhost:54756/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54756/tests/dromaeo/cssquery-prototype.html) 21:03:24 INFO - PID 30055 | RSS: Main: 168579072 21:03:24 INFO - PID 30055 | 21:06:27 INFO - PID 30055 | Cycle 1(1): loaded http://localhost:54756/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54756/tests/dromaeo/cssquery-yui.html) 21:06:28 INFO - PID 30055 | RSS: Main: 170217472 21:06:28 INFO - PID 30055 | 21:09:30 INFO - PID 30055 | Cycle 1(1): loaded http://localhost:54756/tests/dromaeo/cssquery-yui.html (next: http://localhost:54756/tests/dromaeo/cssquery-dojo.html) 21:09:30 INFO - PID 30055 | RSS: Main: 172646400 21:09:30 INFO - PID 30055 | 21:09:30 INFO - PID 30055 | __start_tp_report 21:09:30 INFO - PID 30055 | _x_x_mozilla_page_load 21:09:30 INFO - PID 30055 | _x_x_mozilla_page_load_details 21:09:30 INFO - PID 30055 | |i|pagename|runs| 21:09:30 INFO - PID 30055 | |0;dojo.html;3333.30000699853;3341.9999999999986;3371.1909141805972;3384.5600071990643;3386.9661303386974;6442.903356449657;6508.6094834309915;6549.345065493449;6555.147830781998;6597.3402659734;6371.267304260012;6386.361363863612;6407.711652975616;6429.871402571957;6500.967495162529;5933.4956528695;5952.553558483131;5974.6713930733795;6004.5796794224425;6028.27660680719;5865.76536938522;5913.852153696149;5932.762689492415;5955.285365756094;5977.073553599177;5755.618651523625;5763.596548241583;5795.072788353864;5861.941380586182;5911.9113213301835;5849.970750146264;5851.268591426072;5889.8822023559715;5927.110933359987;5936.376680448534;5770.451807078362;5857.941420585782;5882.176495290662;5904.439078287599;5915.142304365886;6447.903281450782;6485.286618471964;6517.93482065178;6522.804315870531;6564.80305590833;6928.930710692879;6929.44564434844;6936.237013928464;6980.825479363057;7016.7193312267445;6781.627010514419;6903.378695917391;7003.159620845531;7083.397911177556;7087.468439867031;6403.583767055126;6465.256495502983;6471.255805582324;6480.416762491398;6531.575447595891;7079.610621415769;7089.255628159072;7171.247019062923;7185.712571497186;7191.17301510333;6746.055552222692;6766;6827.146606674166;6859.691313890787;6875.3812156906115;6647.966760166265;6901.585904845625;6903.654817259217;6987.301269872972;6991.23096459389;6809.31906809325;6848.212455567674;6851.2121106072445;6858.862822743517;6864.4165245954655;6806;6838.863222735617;6878.793636190822;6882.277360877036;6889;5370.248165256867;5393.2179833923865;5406.405295417501;5408.323959505062;5414.864628384243;4731.1720448921305;4733.266343716729;4778.928316075262;4787.473377928425;4796.544328288807;5721.542276617861;5960.7019649017375;6024.849378765478;6107.480864126599;6119.112728654321;6935.653217339112;6962.322421247683;7061.470389720792;7062.1878483975;7082.787516374517;5899.174115623816;6000.789972350947;6000.819975400745;6007.189029481052;6084.513238940963;5813.244278243801;5822.737976791054;5848.181254624356;5849.5027922626205;5857.092150716646;5541.861453463615;6567.704453299613;6725.8654826903185;6808.8978665320055;6831;6824.727010919706;6911.7926462206215;6913.827154321182;6918.584884906922;6918.792436226921;6536.575122617205;6560;6580.539362244597;6581.243157037002;6581.308962558863;6514.804555863332;6675.732970681119;6682.031105489774;6690.397864192246;6694.698738556874;6757.222919364336;6785.18577770651;6806.18325800907;6991.44068474531;7010.088688470466;6576.013597960345;6641;6678.365555272241;6680.098186744727;6728.899066513908;6634.469242460688;6637.668116594247;6659.966700166468;6661.034150048313;6669.733210671713;6672.132622759011;6678.766243181465;6692.130023097161;6735.797926062226;6737.124173857564;6700.597964122168;6775.966120169368;6836.863262734618;6847.623380714109;6854.451643868579;6394.424501794861;6517.315681853337;6533.477321814338;6545.410913017851;6625 21:09:30 INFO - PID 30055 | |1;ext.html;825.3850881093582;846.9872951905722;869.200335691164;877.5261358866212;1119.283658458587;30534.23664408391;31036.22409439765;31062.0681379559;42265.57734422655;43207;42813.571864281344;43117.35323970143;43343.566564334345;43350.349744753854;43415.565844341545;7863.960680196591;7895.763127106196;8024.43828931975;8117.512949223036;8120.878186827173;7933.127355990837;8042;8048.879266811003;8050.919490805076;8063;8048.396370272224;8092.190780921919;8104.270615644591;8106.310963568074;8113.350931925462;8043.517388956652;8055.959720201361;8056.7180148694515;8059.798505037392;8063.516189028648;7909.683612655487;7970.760877173694;7971.641276142587;7972.242636949481;7978.521288722695;39271.41092883609;39421.60578394208;39502;39530.60469395327;39548;3582.5163602913535;3614.620464851179;3620.529331186955;3623.8912832615065;3627.093226693327;31653;31697.207569810707;31727.84136079305;31763.52354714681;31811.363772724417;5232.424433312332;5238.449962753894;5240.580753539708;5252.527272545489;5305.867353316159;193555;194635;194824;195374.02312988485;195593;48924;48999.51000490021;49278.014439711005;49392.25911611329;49435.258471122965;50506.74746626243;50997.23504147441;51074.23388649173;51084;51133.23300150501;13594.864051359558;15515.767263491056;15782.605434864221;15867.920660396854;15878.682426351408;11152.330860148417;13140.27728474943;13255.204687718768;13267.336633168303;13276.402561884739;14360;14494.420223191166;14635.414583416545;14652.853471465362;14673.192974386511;5720.542356611544;5875.735591898375;5885.352611212763;5896.380880007538;5974.820755377345;1008.5864795433984;1009.9091081802526;1010.7422607235108;1015.1117771949545;1029.7219750667277;23060.76939230586;23070.538589228123;23075.884620577126;23110.42223944415;23214.883925580263;1124.454639499842;1125.4597793059377;1128.802459569572;1137.5620386151227;1142.4173671427632;12213.816792748115;12302.200356976768;12314.076444266715;12348.506059757688;12379.75240495203;231397;232411;233659;234043;234449;51713.48286517087;52020;52053.479465204866;52226.73886630542;52302;1467.3983666696818;1473.8894582906325;1500.1149321900132;1502.7144842479893;1507.8190617125795;22544.210952617235;22576;22675.88662056679;22733;22801.429964251027;37558;37764.81117594394;37775.81112094422;37921.2415751689;38001.80999094987;196197;197248;198207;198266;198990;34032.65967340295;35078.12304692403;35749;35957;35973;44412.333814992155;45246.3213051811;45590;45683.77158114188;45699.31451028171;34162.65837341595;34179.14552136217;34387.484187737704;34407.483887741204;34423;33481.33037339191;33645.66354336425;33810.49284260787;33964.660353396146;34505.309893802485;38021.23957520889;38109.4283585752;38383.80808095942;38416.23167536689;39046.804765977125;33423.49864752079;33547.83226083854;33779;33779.3244135111;34562;24764.752352476964;24899.64256255112;24932.252032439057;25402.745972540037;25568.872155639103 21:09:30 INFO - PID 30055 | |2;jquery.html;566.4731799426535;571.5570432914488;573.3406582430205;585.4233579923774;591.3229352391512;21855.562888742217;22644.66033009504;22665.433364165885;22702.77297227027;23756.28731138064;24482.265532034024;25305.746942530568;25327.087384950737;25337.74662253377;25343.74656253437;6454.774082907099;6725.899111513343;6844.452443804508;6846.144231971004;6847.93152068478;6666.466682665399;6728.730850765988;6732.158480189976;6736.191657001166;6737.865242695143;6611.173603299588;6664.700088496005;6665.966670166642;6673.866522669544;6677.66611669417;6620.04009418634;6649.767258145966;6656.567323123982;6665.500087493458;6669.833254168636;6170.7531698732;6190.226221722285;6213.96893015532;6221.782237621662;6226.003839385677;28096.859515702494;28795;28796.280092997633;29249.853750731323;29254.70745292541;2006.0772044859236;2029.0666293505005;2029.7564292284874;2030.451778019941;2035.6641154209537;19284.80715192858;20041;20068.39794806158;20109.8994505028;20115.195392184294;2442.511497700449;2472.814538909571;2534.8605826679527;2536.07433286849;2539;141204;143948;143952;144135.27932360375;144436;14522.927385363006;14775.778363324558;14796.63008424798;14924.850751492346;15138.394464221528;22247.888760556416;23068.307950761504;23078;23081.192158274378;23086.65370019451;9248.843894513186;9350.392224505522;9512.714618561455;9579.425234485814;9660.806783864424;8716.259117974916;9414.811703766023;9563.61745530171;9804.950975245078;9836.754081147885;9184.540772961325;9214.01898591806;9372.297077719199;9432.717018489457;9639.518024098767;2629.3689514516377;2678.2634775436522;2761.130243973142;2789.9581506277423;2853.6599988009634;960.6877764726491;967.6274634265718;990.1089019882164;995.3977843404781;1016.1870503597092;19215.423537293904;19702.31041913526;19975.500612484804;20238.291659791837;20283.79716202819;1037.8183817832;1055.883852776194;1072.2065671403107;1074.5486895503907;1076.520948178069;10949.124070074375;10983.670489885317;11022.869767719674;11079;11130.443477826077;447411;453150;454480;456349;459932;112267;114012.42993784978;114434.42782786033;116589.4170529142;117953;1185.2829038431796;1207.3057991654834;1215.635309407192;1219.9268043917393;1304.9086563940623;21339.25312614051;21521;21587.784122159206;21662.241821536172;21719.457013574785;59907.700461497414;60719;61267;62779.6861015692;63347.366526334146;73265.63367183344;75986.24013759791;77183.2281677176;77478;78007.21992780226;14290.356933938207;14473.552644735442;14528.491502797775;14611.707765844836;14684.265786710408;22525.324240272817;22680.206192783175;22882.542349153257;22919.197828075936;23042.42393940098;17311.653766924843;17445.738313925554;18488.260469581066;18573.164207610418;18676.719849202545;14982.400703972155;15709.921450392674;16137;16261.186940653157;16363.836361636231;18056.45830625083;18712.906435467736;18731;18961.620767584296;19023.143958522178;14457.710845782814;14534.491292804702;14629.561113166623;14632.634184145481;14636.975411721078;18394.98827564497;18398.1720822566;18477.35329263523;18521.907390462962;18625 21:09:30 INFO - PID 30055 | |3;mootools.html;1327.5287273018077;1388.9583312500629;1400.4818217259613;1403.0389183409359;1407.8873690104786;14957.177355245458;15206.391744330242;15580.143092129943;15595.922020389897;15630.29663665135;16063.196840158003;16351.509454716348;16373.099479528652;16382.590435239124;16385.508434746946;2812.4375124975036;2879.971200288002;2881.625388699466;2882.423515296949;2883.2647674842888;2681;2865.0688526228955;2865.555838844982;2867.913962581126;2868.3689588290545;2824.957625635617;2828.179827849921;2857.742803147716;2858.0996985949464;2858.128270877392;2810.592464092695;2821.80073468776;2829.8585070746485;2831.3487897783625;2839.9432011359863;2688.610151528037;2689.7579217870484;2701.203145072189;2701.3786829029236;2711.1188863619404;15130.54608361751;15470.071795692182;15482.922585387114;15494.612634684223;15502.767458488132;8085.393595480304;8328.250457458858;8344.08215096339;8387.958060209721;8392.958035209786;5399.37907140676;5520.171974203861;5605.103183490663;5702.54379649627;5732.742026608813;1803.7925638551471;1846.815318468156;1851.1392202625843;1857.7027675571978;1873.6252749450164;26882.32794180161;27208.319792005357;27221.591676124874;27344.316392090157;27386.863065684545;6722.722682690273;6738.730450782012;6835.316468353224;6922.411595014481;7121.252268511836;17439.04085275322;17439.389621363443;17447.825521744875;17510.036947967983;17511.211995460235;8001.799955001172;8024.438289319692;8026.077001144827;8031.477953932976;8038.919610803817;6250.96859018266;7334.3032411920785;7339.412846972229;7449.776506704808;7462.589557574385;5016.899662006739;5158.432572417031;5365.570754339644;5376.91934620981;5380.757865896044;1496.8503149685162;1536.9077855328717;1547.5821528187325;1558.3299181352127;1587.380921440639;926.1757036237753;938.8216238914584;945.8817647794026;949.349695458613;951.2152474208806;8829.16122968317;9063.456192628464;9070.773230669154;9134.360594758304;9136.771580710403;1000.0799264676515;1011.4588695048261;1026.9178465722725;1029.8867124616286;1043.4417586591137;5350.438203988525;5369.4362091980565;5417.377001644862;5436.157395603609;5475.123980163235;22037.11851525921;22697.773022269565;22755.7724422757;22914.198003069814;22927;20986.160553577993;22236.777632223795;22274.999999999676;22286.55426891453;22296.77703222947;11436.370999595103;11788.408564843809;11841.88158118425;11863.703407414883;11867.940660296643;9048.321375896703;9168.129027742354;9212.677556285498;9279.953600231956;9309.953450232706;18436.446906592824;18585.16366763472;18840.623187535897;18936.52658683544;19074.618507630046;18902.621947560696;19082.236710531422;19082.522936926132;19120.27917487433;19166.61666766629;11584.189106762446;11991.700207494883;12043.518259269882;12045;12063.034957203578;12631;13037.348132593523;14050.297485125498;14188.219647919463;14445.78331325052;10759.085477734166;10824.783504330027;10862.837057443987;11004.559817607205;11180.440977951233;11199.328040317607;11236;11257.437128143723;11366.545338186379;11428.085753139896;9767.560459779184;9875.259355548218;9914.702558923243;9968.750781230527;9980.401175929468;7566.810829729301;7616.466847320635;7639.274268944664;7639.770806875803;7645.732399365996;7379.926200737924;7511.173770885089;7515.473916825769;7535.284148005931;7554.584497852671 21:09:30 INFO - PID 30055 | |4;prototype.html;507.4925074925075;521.3535216331748;525.5900397689802;534.262717449919;538.2545174932719;6338.873222535558;6349.238091429029;6352.333005034474;6379.712912918918;6444.548881578291;6198.566100372976;6215.192025036739;6292.874142517158;6336.873262534758;6373.13962615048;5210.426853046162;5212.983468223698;5251.737413129338;5269.525742683158;5288.788448462057;4928.285398617199;5227.575937760773;5227.973860130713;5254.605904557155;5290.206469029637;5034.773435195407;5052.14113600687;5056.949430505685;5064.620153488503;5106.8764871728345;5023.949760502384;5029.974850125763;5037.168867136937;5044.369453818273;5074.441811400743;4375.540568240321;4512.435945506812;4533.52397998212;4544.454665440168;4545.477270113946;6096.817095487142;6111.87776244477;6131.816045518642;6189.38106189382;6192.814215573495;5699.344575373802;5700.828975130753;5710.914336284959;5768.192453056576;5818.185454036438;6457.967710161466;6530.6408147551865;6581.80254592363;6625.933740662628;6645;4921.138800709861;4950.4059512858685;4976.2038073908;4985.775640096168;4986.202207646759;6736.46108311334;6768.357006084414;6856.794296171172;7058.517711280637;7140.714371425137;4858.319835223072;4971.875703107451;4995.975020124876;5045.84862454127;5089;5042.873928151826;5066.986602679479;5078.796848126033;5126.641135120506;5146.768395422215;5675.1770993205755;5705.657660540381;5766.625169363978;5777.826665200051;5781.335146458127;6697.827880120959;6699.598024118569;6722.722682690273;6734.595924244561;6734.932650673529;1748.4929370482419;1780.9198586063658;1794.605186858889;1803.5130514761204;1805.8284462975996;1150.9079273658087;1199.5501686867424;1214.8299238106672;1215.7234903351446;1242.8757124287679;5892.94107058924;5893.44012318829;5911;5929.911051334234;5952.613080149777;6715.664216789141;6804.795856124324;6881.243063263037;6912.274211207851;6921.861562768817;5818.79634212809;5852.15143804146;5867.442592953748;5895.587308888423;5922.496587790086;3599.6400359963795;3622.5109610202285;3628.401313783249;3637.5998640149564;3643.180284435981;6631.469482441393;6652.401283884376;6742.157230346207;6742.831429214309;6748.865022699519;7034.577925324497;7109.182444019004;7140.714371425189;7145.606991615407;7151.89272160918;6185.505159587222;6626.337366263335;6648.135742353463;6684.766033188815;6694.196696396366;6692.364225398579;6816.216135144522;6837.760678376233;6839.965800170968;6854.897176542256;6815.386615204656;6817.625030623363;6821.861943506043;6833.3166683331265;6916.1700595926795;6622.403983641495;6663.800085997428;6735.764248251287;6745.797626071226;6751.93248067513;5489.835304940858;5535.750891209825;5617.073182924935;5619.735559499243;5631.887362252814;6533.889238829315;6575.736970521126;6612;6866.519343646097;6884.173899132136;6575.704093315717;6599.53803233769;6658.500612453989;6680.465562754871;6695.631740254333;6651.900221496779;6666.8666326723605;6676.83307917306;6688.8963321053425;6707.832304192434;6585.506087043395;6588.538802283986;6591.209054913441;6607.603543787388;6646.335366463315;6664.5334826561675;6669.499787515859;6686.498512611476;6696.966515167393;6733.158355205599;6477.894684227739;6486.254080780771;6504.30887969122;6504.902426463702;6505.251896032017 21:09:30 INFO - PID 30055 | |5;yui.html;902.7066203483868;907.691384929124;921.9078092190782;926.8887733471985;932.4871320773577;16891.155442227886;18075.457736267916;18195.727064094048;21224.363269101952;22115.889420552914;18489.167987440564;19670.70493942592;19835.900820495877;19842.70235946462;20110.396688099343;964.0841200859177;987.4272921705393;991.1921783746224;991.5736233419626;992.2310209587555;948.1892981500821;973.4110862927932;1003.3578509753763;1005.607812952949;1005.9094681478665;1049.8530205771199;1051.8685164354456;1052.0689190066807;1052.2003277509073;1052.7210289273348;1048.1457612046158;1050.0497050169608;1050.112654806687;1050.1913526584526;1050.6637875879683;773.3465221887496;773.6595897804948;775.9728409505697;776.829097598533;777.0558771093149;20143.294984675613;20466.692999604868;20584.27955021582;20782.5843483131;20794.896025519927;20950;21737.891310543346;21802.563948721094;22325.330240092822;22362.66456003161;17279.04965226912;17345.39291124817;17358.479245622653;17443.5639109022;17569.648607027917;3826.7321287509885;3873.9418908716393;3874.9031274218087;3875.5736868944678;3876.2247550489733;127125.36437317754;127598.72401276055;129664;131004.34497827545;132017;16125.113118778345;16471.25879335433;16588.170591470378;16735.414260501067;16789.24448399825;30408.391832163234;31001.224969375948;31081.533776993365;31192.06423807244;31359.686403135678;8593.269572086443;8745.650173992968;8750.562471876381;8769.956150219208;8825.867611985825;4329.783510824509;4539.977300113478;4571.314302854532;4571.771411429415;4578.496365399803;5155.896882062413;5251.684898906002;5268.552173065333;5280.683159010472;5285.577153827761;978.9902100978899;1031.4120951057976;1031.4636389077787;1031.7059638003134;1039.589362201926;644.4940721533574;650.1418128070995;658.4173006888842;672.3814091036259;690.3096903096903;11241.55033798639;11549.826752598718;11745.29693194482;12959.805602915963;12969.805452918214;680.3706571421416;698.3575110897985;708.2421808664782;722.9783106506814;738.9704411823467;5321.920171197435;5631.352394474606;5699;5930.703464826742;5941.138534912414;109816;110995;113107;113948.7251218922;117077;95998.52000739952;96356.51821740987;98339.50830245802;99519.50240248753;99864;85325.57337213274;85551;85641;88829.55585222162;90223;12569.349340743618;13151.717229108463;13205.009624278026;13215.273159976294;13298.202107873558;40959.79520102381;40974.38538421986;41341;41616.583834162484;42085;52915.735421324185;53078.469215307356;53148;53758;56164.43835561592;17340.47978560645;17346.5663358412;17540.649187016443;17687.292508299524;17781.73327400116;19050.7142392867;19340.806591934463;19457.902710486356;19721.211151553776;19838;17565.473035808947;17687.734683979492;17793.288268469118;17884.55288617795;17906.373276935246;17798.91100544489;18027.2789088435;18068.36760713369;18106.909465452587;18278.543036424195;17249.827501724823;17389.217485212943;17450.91274543619;17574.20916058755;17585.20866560982;12733.490660373482;13037.956963443092;13618.114822536503;13946.930265348608;14322.928385358007;13673.863261367656;13705.246211458465;13711.657208569866;13810.171389716648;14034.929825350808 21:09:30 INFO - PID 30055 | __end_tp_report 21:09:30 INFO - PID 30055 | __start_cc_report 21:09:30 INFO - PID 30055 | _x_x_mozilla_cycle_collect,359 21:09:30 INFO - PID 30055 | __end_cc_report 21:09:30 INFO - PID 30055 | __startTimestamp1504152570396__endTimestamp 21:09:30 INFO - PID 30055 | 21:09:30 INFO - PID 30055 | ------- Summary: start ------- 21:09:30 INFO - PID 30055 | Number of tests: 6 21:09:30 INFO - PID 30055 | 21:09:30 INFO - PID 30055 | [#0] dojo.html Cycles:165 Average:6335.98 Median:6567.70 stddev:749.80 (11.4%) stddev-sans-first:714.15 21:09:30 INFO - PID 30055 | Values: 3333.3 3342.0 3371.2 3384.6 3387.0 6442.9 6508.6 6549.3 6555.1 6597.3 6371.3 6386.4 6407.7 6429.9 6501.0 5933.5 5952.6 5974.7 6004.6 6028.3 5865.8 5913.9 5932.8 5955.3 5977.1 5755.6 5763.6 5795.1 5861.9 5911.9 5850.0 5851.3 5889.9 5927.1 5936.4 5770.5 5857.9 5882.2 5904.4 5915.1 6447.9 6485.3 6517.9 6522.8 6564.8 6928.9 6929.4 6936.2 6980.8 7016.7 6781.6 6903.4 7003.2 7083.4 7087.5 6403.6 6465.3 6471.3 6480.4 6531.6 7079.6 7089.3 7171.2 7185.7 7191.2 6746.1 6766.0 6827.1 6859.7 6875.4 6648.0 6901.6 6903.7 6987.3 6991.2 6809.3 6848.2 6851.2 6858.9 6864.4 6806.0 6838.9 6878.8 6882.3 6889.0 5370.2 5393.2 5406.4 5408.3 5414.9 4731.2 4733.3 4778.9 4787.5 4796.5 5721.5 5960.7 6024.8 6107.5 6119.1 6935.7 6962.3 7061.5 7062.2 7082.8 5899.2 6000.8 6000.8 6007.2 6084.5 5813.2 5822.7 5848.2 5849.5 5857.1 5541.9 6567.7 6725.9 6808.9 6831.0 6824.7 6911.8 6913.8 6918.6 6918.8 6536.6 6560.0 6580.5 6581.2 6581.3 6514.8 6675.7 6682.0 6690.4 6694.7 6757.2 6785.2 6806.2 6991.4 7010.1 6576.0 6641.0 6678.4 6680.1 6728.9 6634.5 6637.7 6660.0 6661.0 6669.7 6672.1 6678.8 6692.1 6735.8 6737.1 6700.6 6776.0 6836.9 6847.6 6854.5 6394.4 6517.3 6533.5 6545.4 6625.0 21:09:30 INFO - PID 30055 | 21:09:30 INFO - PID 30055 | [#1] ext.html Cycles:180 Average:38117.77 Median:35413.56 stddev:54188.63 (153.0%) stddev-sans-first:54268.30 21:09:30 INFO - PID 30055 | Values: 825.4 847.0 869.2 877.5 1119.3 30534.2 31036.2 31062.1 42265.6 43207.0 42813.6 43117.4 43343.6 43350.3 43415.6 7864.0 7895.8 8024.4 8117.5 8120.9 7933.1 8042.0 8048.9 8050.9 8063.0 8048.4 8092.2 8104.3 8106.3 8113.4 8043.5 8056.0 8056.7 8059.8 8063.5 7909.7 7970.8 7971.6 7972.2 7978.5 39271.4 39421.6 39502.0 39530.6 39548.0 3582.5 3614.6 3620.5 3623.9 3627.1 31653.0 31697.2 31727.8 31763.5 31811.4 5232.4 5238.4 5240.6 5252.5 5305.9 193555.0 194635.0 194824.0 195374.0 195593.0 48924.0 48999.5 49278.0 49392.3 49435.3 50506.7 50997.2 51074.2 51084.0 51133.2 13594.9 15515.8 15782.6 15867.9 15878.7 11152.3 13140.3 13255.2 13267.3 13276.4 14360.0 14494.4 14635.4 14652.9 14673.2 5720.5 5875.7 5885.4 5896.4 5974.8 1008.6 1009.9 1010.7 1015.1 1029.7 23060.8 23070.5 23075.9 23110.4 23214.9 1124.5 1125.5 1128.8 1137.6 1142.4 12213.8 12302.2 12314.1 12348.5 12379.8 231397.0 232411.0 233659.0 234043.0 234449.0 51713.5 52020.0 52053.5 52226.7 52302.0 1467.4 1473.9 1500.1 1502.7 1507.8 22544.2 22576.0 22675.9 22733.0 22801.4 37558.0 37764.8 37775.8 37921.2 38001.8 196197.0 197248.0 198207.0 198266.0 198990.0 34032.7 35078.1 35749.0 35957.0 35973.0 44412.3 45246.3 45590.0 45683.8 45699.3 34162.7 34179.1 34387.5 34407.5 34423.0 33481.3 33645.7 33810.5 33964.7 34505.3 38021.2 38109.4 38383.8 38416.2 39046.8 33423.5 33547.8 33779.0 33779.3 34562.0 24764.8 24899.6 24932.3 25402.7 25568.9 21:09:30 INFO - PID 30055 | 21:09:30 INFO - PID 30055 | [#2] jquery.html Cycles:180 Average:34255.60 Median:23055.37 stddev:77394.09 (335.7%) stddev-sans-first:77569.87 21:09:30 INFO - PID 30055 | Values: 566.5 571.6 573.3 585.4 591.3 21855.6 22644.7 22665.4 22702.8 23756.3 24482.3 25305.7 25327.1 25337.7 25343.7 6454.8 6725.9 6844.5 6846.1 6847.9 6666.5 6728.7 6732.2 6736.2 6737.9 6611.2 6664.7 6666.0 6673.9 6677.7 6620.0 6649.8 6656.6 6665.5 6669.8 6170.8 6190.2 6214.0 6221.8 6226.0 28096.9 28795.0 28796.3 29249.9 29254.7 2006.1 2029.1 2029.8 2030.5 2035.7 19284.8 20041.0 20068.4 20109.9 20115.2 2442.5 2472.8 2534.9 2536.1 2539.0 141204.0 143948.0 143952.0 144135.3 144436.0 14522.9 14775.8 14796.6 14924.9 15138.4 22247.9 23068.3 23078.0 23081.2 23086.7 9248.8 9350.4 9512.7 9579.4 9660.8 8716.3 9414.8 9563.6 9805.0 9836.8 9184.5 9214.0 9372.3 9432.7 9639.5 2629.4 2678.3 2761.1 2790.0 2853.7 960.7 967.6 990.1 995.4 1016.2 19215.4 19702.3 19975.5 20238.3 20283.8 1037.8 1055.9 1072.2 1074.5 1076.5 10949.1 10983.7 11022.9 11079.0 11130.4 447411.0 453150.0 454480.0 456349.0 459932.0 112267.0 114012.4 114434.4 116589.4 117953.0 1185.3 1207.3 1215.6 1219.9 1304.9 21339.3 21521.0 21587.8 21662.2 21719.5 59907.7 60719.0 61267.0 62779.7 63347.4 73265.6 75986.2 77183.2 77478.0 78007.2 14290.4 14473.6 14528.5 14611.7 14684.3 22525.3 22680.2 22882.5 22919.2 23042.4 17311.7 17445.7 18488.3 18573.2 18676.7 14982.4 15709.9 16137.0 16261.2 16363.8 18056.5 18712.9 18731.0 18961.6 19023.1 14457.7 14534.5 14629.6 14632.6 14637.0 18395.0 18398.2 18477.4 18521.9 18625.0 21:09:30 INFO - PID 30055 | 21:09:30 INFO - PID 30055 | [#3] mootools.html Cycles:180 Average:9573.32 Median:2706.25 stddev:6822.17 (252.1%) stddev-sans-first:6813.18 21:09:30 INFO - PID 30055 | Values: 1327.5 1389.0 1400.5 1403.0 1407.9 14957.2 15206.4 15580.1 15595.9 15630.3 16063.2 16351.5 16373.1 16382.6 16385.5 2812.4 2880.0 2881.6 2882.4 2883.3 2681.0 2865.1 2865.6 2867.9 2868.4 2825.0 2828.2 2857.7 2858.1 2858.1 2810.6 2821.8 2829.9 2831.3 2839.9 2688.6 2689.8 2701.2 2701.4 2711.1 15130.5 15470.1 15482.9 15494.6 15502.8 8085.4 8328.3 8344.1 8388.0 8393.0 5399.4 5520.2 5605.1 5702.5 5732.7 1803.8 1846.8 1851.1 1857.7 1873.6 26882.3 27208.3 27221.6 27344.3 27386.9 6722.7 6738.7 6835.3 6922.4 7121.3 17439.0 17439.4 17447.8 17510.0 17511.2 8001.8 8024.4 8026.1 8031.5 8038.9 6251.0 7334.3 7339.4 7449.8 7462.6 5016.9 5158.4 5365.6 5376.9 5380.8 1496.9 1536.9 1547.6 1558.3 1587.4 926.2 938.8 945.9 949.3 951.2 8829.2 9063.5 9070.8 9134.4 9136.8 1000.1 1011.5 1026.9 1029.9 1043.4 5350.4 5369.4 5417.4 5436.2 5475.1 22037.1 22697.8 22755.8 22914.2 22927.0 20986.2 22236.8 22275.0 22286.6 22296.8 11436.4 11788.4 11841.9 11863.7 11867.9 9048.3 9168.1 9212.7 9280.0 9310.0 18436.4 18585.2 18840.6 18936.5 19074.6 18902.6 19082.2 19082.5 19120.3 19166.6 11584.2 11991.7 12043.5 12045.0 12063.0 12631.0 13037.3 14050.3 14188.2 14445.8 10759.1 10824.8 10862.8 11004.6 11180.4 11199.3 11236.0 11257.4 11366.5 11428.1 9767.6 9875.3 9914.7 9968.8 9980.4 7566.8 7616.5 7639.3 7639.8 7645.7 7379.9 7511.2 7515.5 7535.3 7554.6 21:09:30 INFO - PID 30055 | 21:09:30 INFO - PID 30055 | [#4] prototype.html Cycles:180 Average:5624.17 Median:6195.69 stddev:1576.15 (25.4%) stddev-sans-first:1533.07 21:09:30 INFO - PID 30055 | Values: 507.5 521.4 525.6 534.3 538.3 6338.9 6349.2 6352.3 6379.7 6444.5 6198.6 6215.2 6292.9 6336.9 6373.1 5210.4 5213.0 5251.7 5269.5 5288.8 4928.3 5227.6 5228.0 5254.6 5290.2 5034.8 5052.1 5056.9 5064.6 5106.9 5023.9 5030.0 5037.2 5044.4 5074.4 4375.5 4512.4 4533.5 4544.5 4545.5 6096.8 6111.9 6131.8 6189.4 6192.8 5699.3 5700.8 5710.9 5768.2 5818.2 6458.0 6530.6 6581.8 6625.9 6645.0 4921.1 4950.4 4976.2 4985.8 4986.2 6736.5 6768.4 6856.8 7058.5 7140.7 4858.3 4971.9 4996.0 5045.8 5089.0 5042.9 5067.0 5078.8 5126.6 5146.8 5675.2 5705.7 5766.6 5777.8 5781.3 6697.8 6699.6 6722.7 6734.6 6734.9 1748.5 1780.9 1794.6 1803.5 1805.8 1150.9 1199.6 1214.8 1215.7 1242.9 5892.9 5893.4 5911.0 5929.9 5952.6 6715.7 6804.8 6881.2 6912.3 6921.9 5818.8 5852.2 5867.4 5895.6 5922.5 3599.6 3622.5 3628.4 3637.6 3643.2 6631.5 6652.4 6742.2 6742.8 6748.9 7034.6 7109.2 7140.7 7145.6 7151.9 6185.5 6626.3 6648.1 6684.8 6694.2 6692.4 6816.2 6837.8 6840.0 6854.9 6815.4 6817.6 6821.9 6833.3 6916.2 6622.4 6663.8 6735.8 6745.8 6751.9 5489.8 5535.8 5617.1 5619.7 5631.9 6533.9 6575.7 6612.0 6866.5 6884.2 6575.7 6599.5 6658.5 6680.5 6695.6 6651.9 6666.9 6676.8 6688.9 6707.8 6585.5 6588.5 6591.2 6607.6 6646.3 6664.5 6669.5 6686.5 6697.0 6733.2 6477.9 6486.3 6504.3 6504.9 6505.3 21:09:30 INFO - PID 30055 | 21:09:30 INFO - PID 30055 | [#5] yui.html Cycles:180 Average:23557.52 Median:20304.99 stddev:32183.14 (158.5%) stddev-sans-first:32228.46 21:09:30 INFO - PID 30055 | Values: 902.7 907.7 921.9 926.9 932.5 16891.2 18075.5 18195.7 21224.4 22115.9 18489.2 19670.7 19835.9 19842.7 20110.4 964.1 987.4 991.2 991.6 992.2 948.2 973.4 1003.4 1005.6 1005.9 1049.9 1051.9 1052.1 1052.2 1052.7 1048.1 1050.0 1050.1 1050.2 1050.7 773.3 773.7 776.0 776.8 777.1 20143.3 20466.7 20584.3 20782.6 20794.9 20950.0 21737.9 21802.6 22325.3 22362.7 17279.0 17345.4 17358.5 17443.6 17569.6 3826.7 3873.9 3874.9 3875.6 3876.2 127125.4 127598.7 129664.0 131004.3 132017.0 16125.1 16471.3 16588.2 16735.4 16789.2 30408.4 31001.2 31081.5 31192.1 31359.7 8593.3 8745.7 8750.6 8770.0 8825.9 4329.8 4540.0 4571.3 4571.8 4578.5 5155.9 5251.7 5268.6 5280.7 5285.6 979.0 1031.4 1031.5 1031.7 1039.6 644.5 650.1 658.4 672.4 690.3 11241.6 11549.8 11745.3 12959.8 12969.8 680.4 698.4 708.2 723.0 739.0 5321.9 5631.4 5699.0 5930.7 5941.1 109816.0 110995.0 113107.0 113948.7 117077.0 95998.5 96356.5 98339.5 99519.5 99864.0 85325.6 85551.0 85641.0 88829.6 90223.0 12569.3 13151.7 13205.0 13215.3 13298.2 40959.8 40974.4 41341.0 41616.6 42085.0 52915.7 53078.5 53148.0 53758.0 56164.4 17340.5 17346.6 17540.6 17687.3 17781.7 19050.7 19340.8 19457.9 19721.2 19838.0 17565.5 17687.7 17793.3 17884.6 17906.4 17798.9 18027.3 18068.4 18106.9 18278.5 17249.8 17389.2 17450.9 17574.2 17585.2 12733.5 13038.0 13618.1 13946.9 14322.9 13673.9 13705.2 13711.7 13810.2 14034.9 21:09:30 INFO - PID 30055 | -------- Summary: end -------- 21:09:30 INFO - PID 30055 | 21:09:31 INFO - TEST-INFO | 30055: exit 0 21:09:31 INFO - TEST-OK | dromaeo_css | took 1110660ms 21:09:31 INFO - TEST-START | kraken 21:09:31 INFO - Initialising browser for kraken test... 21:09:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54756/getInfo.html -profile /tmp/tmpy7VlGu/profile 21:09:31 INFO - TEST-INFO | started process 30426 (/builds/slave/test/build/application/firefox/firefox http://localhost:54756/getInfo.html) 21:09:36 INFO - TEST-INFO | 30426: exit 0 21:09:36 INFO - Browser initialized. 21:09:36 INFO - Running cycle 1/1 for kraken test... 21:09:36 INFO - Using env: {'DISPLAY': ':0', 21:09:36 INFO - 'HOME': '/home/cltbld', 21:09:36 INFO - 'JSGC_DISABLE_POISONING': '1', 21:09:36 INFO - 'LANG': 'en_US.UTF-8', 21:09:36 INFO - 'LANGUAGE': 'en_US:en', 21:09:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:09:36 INFO - 'LOGNAME': 'cltbld', 21:09:36 INFO - 'MAIL': '/var/mail/cltbld', 21:09:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:09:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:09:36 INFO - 'MOZ_CRASHREPORTER': '1', 21:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:09:36 INFO - 'MOZ_NO_REMOTE': '1', 21:09:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:09:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:09:36 INFO - 'NO_EM_RESTART': '1', 21:09:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:36 INFO - 'PWD': '/builds/slave/test', 21:09:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:09:36 INFO - 'RUST_BACKTRACE': 'full', 21:09:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:09:36 INFO - 'SHELL': '/bin/bash', 21:09:36 INFO - 'SHLVL': '1', 21:09:36 INFO - 'TERM': 'linux', 21:09:36 INFO - 'TMOUT': '86400', 21:09:36 INFO - 'USER': 'cltbld', 21:09:36 INFO - 'XDG_SESSION_COOKIE': '51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215', 21:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:36 INFO - '_': '/tools/buildbot/bin/python'} 21:09:36 INFO - TEST-INFO | started process 30611 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy7VlGu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:09:37 INFO - PID 30611 | 21:09:37 INFO - PID 30611 | (/builds/slave/test/build/application/firefox/firefox:30663): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:09:37 INFO - PID 30611 | 21:09:47 INFO - PID 30611 | RSS: Main: 170856448 21:09:47 INFO - PID 30611 | 21:09:50 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54756/tests/kraken/driver.html?testName=audio-beat-detection) 21:09:51 INFO - PID 30611 | RSS: Main: 175509504 21:09:51 INFO - PID 30611 | 21:09:53 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54756/tests/kraken/driver.html?testName=audio-dft) 21:09:54 INFO - PID 30611 | RSS: Main: 176160768 21:09:54 INFO - PID 30611 | 21:09:57 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54756/tests/kraken/driver.html?testName=audio-fft) 21:09:58 INFO - PID 30611 | RSS: Main: 171036672 21:09:58 INFO - PID 30611 | 21:10:00 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54756/tests/kraken/driver.html?testName=audio-oscillator) 21:10:00 INFO - PID 30611 | RSS: Main: 171352064 21:10:00 INFO - PID 30611 | 21:10:02 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54756/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:10:03 INFO - PID 30611 | RSS: Main: 171315200 21:10:03 INFO - PID 30611 | 21:10:06 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54756/tests/kraken/driver.html?testName=imaging-darkroom) 21:10:07 INFO - PID 30611 | RSS: Main: 173916160 21:10:07 INFO - PID 30611 | 21:10:10 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54756/tests/kraken/driver.html?testName=imaging-desaturate) 21:10:11 INFO - PID 30611 | RSS: Main: 173883392 21:10:11 INFO - PID 30611 | 21:10:14 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54756/tests/kraken/driver.html?testName=json-parse-financial) 21:10:14 INFO - PID 30611 | RSS: Main: 174096384 21:10:14 INFO - PID 30611 | 21:10:16 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54756/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:10:17 INFO - PID 30611 | RSS: Main: 173801472 21:10:17 INFO - PID 30611 | 21:10:18 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54756/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:10:19 INFO - PID 30611 | RSS: Main: 173916160 21:10:19 INFO - PID 30611 | 21:10:21 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54756/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:10:22 INFO - PID 30611 | RSS: Main: 170856448 21:10:22 INFO - PID 30611 | 21:10:24 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54756/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:10:25 INFO - PID 30611 | RSS: Main: 170590208 21:10:25 INFO - PID 30611 | 21:10:28 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54756/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:10:28 INFO - PID 30611 | RSS: Main: 171352064 21:10:28 INFO - PID 30611 | 21:10:30 INFO - PID 30611 | Cycle 1(1): loaded http://localhost:54756/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54756/tests/kraken/driver.html?testName=ai-astar) 21:10:30 INFO - PID 30611 | RSS: Main: 172470272 21:10:30 INFO - PID 30611 | 21:10:30 INFO - PID 30611 | __start_tp_report 21:10:30 INFO - PID 30611 | _x_x_mozilla_page_load 21:10:30 INFO - PID 30611 | _x_x_mozilla_page_load_details 21:10:30 INFO - PID 30611 | |i|pagename|runs| 21:10:30 INFO - PID 30611 | |0;ai-astar;100;112;100;109;108;110;109;99;111;111 21:10:30 INFO - PID 30611 | |1;audio-beat-detection;141;115;143;183;115;147;147;123;175;117 21:10:30 INFO - PID 30611 | |2;audio-dft;164;164;162;163;162;162;163;167;164;169 21:10:30 INFO - PID 30611 | |3;audio-fft;77;73;74;168;75;73;79;74;160;74 21:10:30 INFO - PID 30611 | |4;audio-oscillator;79;79;135;80;79;80;135;79;80;123 21:10:30 INFO - PID 30611 | |5;imaging-gaussian-blur;114;113;114;114;114;114;115;120;114;114 21:10:30 INFO - PID 30611 | |6;imaging-darkroom;147;150;148;148;147;148;149;147;148;148 21:10:30 INFO - PID 30611 | |7;imaging-desaturate;94;95;100;94;93;95;94;95;94;93 21:10:30 INFO - PID 30611 | |8;json-parse-financial;52;51;51;52;54;54;54;52;51;57 21:10:30 INFO - PID 30611 | |9;json-stringify-tinderbox;38;39;38;38;38;38;38;39;38;38 21:10:30 INFO - PID 30611 | |10;stanford-crypto-aes;77;77;75;77;75;75;75;75;76;75 21:10:30 INFO - PID 30611 | |11;stanford-crypto-ccm;121;123;119;125;121;127;123;133;122;155 21:10:30 INFO - PID 30611 | |12;stanford-crypto-pbkdf2;170;171;167;171;169;174;170;168;172;173 21:10:30 INFO - PID 30611 | |13;stanford-crypto-sha256-iterative;65;66;67;65;66;68;64;64;68;64 21:10:30 INFO - PID 30611 | __end_tp_report 21:10:30 INFO - PID 30611 | __start_cc_report 21:10:30 INFO - PID 30611 | _x_x_mozilla_cycle_collect,930 21:10:30 INFO - PID 30611 | __end_cc_report 21:10:30 INFO - PID 30611 | __startTimestamp1504152630987__endTimestamp 21:10:30 INFO - PID 30611 | 21:10:30 INFO - PID 30611 | ------- Summary: start ------- 21:10:30 INFO - PID 30611 | Number of tests: 14 21:10:30 INFO - PID 30611 | 21:10:30 INFO - PID 30611 | [#0] ai-astar Cycles:10 Average:106.90 Median:110.50 stddev:5.13 (4.6%) stddev-sans-first:4.80 21:10:30 INFO - PID 30611 | Values: 100.0 112.0 100.0 109.0 108.0 110.0 109.0 99.0 111.0 111.0 21:10:30 INFO - PID 30611 | 21:10:30 INFO - PID 30611 | [#1] audio-beat-detection Cycles:10 Average:140.60 Median:145.00 stddev:24.14 (16.7%) stddev-sans-first:25.61 21:10:30 INFO - PID 30611 | Values: 141.0 115.0 143.0 183.0 115.0 147.0 147.0 123.0 175.0 117.0 21:10:30 INFO - PID 30611 | 21:10:30 INFO - PID 30611 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.00 stddev:2.31 (1.4%) stddev-sans-first:2.45 21:10:31 INFO - PID 30611 | Values: 164.0 164.0 162.0 163.0 162.0 162.0 163.0 167.0 164.0 169.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#3] audio-fft Cycles:10 Average:92.70 Median:74.00 stddev:37.67 (50.9%) stddev-sans-first:39.53 21:10:31 INFO - PID 30611 | Values: 77.0 73.0 74.0 168.0 75.0 73.0 79.0 74.0 160.0 74.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:79.00 stddev:25.13 (31.8%) stddev-sans-first:25.99 21:10:31 INFO - PID 30611 | Values: 79.0 79.0 135.0 80.0 79.0 80.0 135.0 79.0 80.0 123.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#5] imaging-gaussian-blur Cycles:10 Average:114.60 Median:114.00 stddev:1.96 (1.7%) stddev-sans-first:2.06 21:10:31 INFO - PID 30611 | Values: 114.0 113.0 114.0 114.0 114.0 114.0 115.0 120.0 114.0 114.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.94 (0.6%) stddev-sans-first:0.93 21:10:31 INFO - PID 30611 | Values: 147.0 150.0 148.0 148.0 147.0 148.0 149.0 147.0 148.0 148.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:2.00 (2.1%) stddev-sans-first:2.11 21:10:31 INFO - PID 30611 | Values: 94.0 95.0 100.0 94.0 93.0 95.0 94.0 95.0 94.0 93.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.93 (3.6%) stddev-sans-first:2.03 21:10:31 INFO - PID 30611 | Values: 52.0 51.0 51.0 52.0 54.0 54.0 54.0 52.0 51.0 57.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#9] json-stringify-tinderbox Cycles:10 Average:38.20 Median:38.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 21:10:31 INFO - PID 30611 | Values: 38.0 39.0 38.0 38.0 38.0 38.0 38.0 39.0 38.0 38.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:75.50 stddev:0.95 (1.3%) stddev-sans-first:0.88 21:10:31 INFO - PID 30611 | Values: 77.0 77.0 75.0 77.0 75.0 75.0 75.0 75.0 76.0 75.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:124.00 stddev:10.63 (8.6%) stddev-sans-first:11.06 21:10:31 INFO - PID 30611 | Values: 121.0 123.0 119.0 125.0 121.0 127.0 123.0 133.0 122.0 155.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.50 Median:171.00 stddev:2.17 (1.3%) stddev-sans-first:2.30 21:10:31 INFO - PID 30611 | Values: 170.0 171.0 167.0 171.0 169.0 174.0 170.0 168.0 172.0 173.0 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.57 (2.4%) stddev-sans-first:1.64 21:10:31 INFO - PID 30611 | Values: 65.0 66.0 67.0 65.0 66.0 68.0 64.0 64.0 68.0 64.0 21:10:31 INFO - PID 30611 | -------- Summary: end -------- 21:10:31 INFO - PID 30611 | 21:10:31 INFO - PID 30611 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:10:31 INFO - TEST-INFO | 30611: exit 0 21:10:31 INFO - TEST-OK | kraken | took 60569ms 21:10:31 INFO - SUITE-END | took 1171s 21:10:32 INFO - Completed test suite (00:19:31) 21:10:32 INFO - javascript benchmark 21:10:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8689.91721691502, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.30000699853, 3341.9999999999986, 3371.1909141805972, 3384.5600071990643, 3386.9661303386974, 6442.903356449657, 6508.6094834309915, 6549.345065493449, 6555.147830781998, 6597.3402659734, 6371.267304260012, 6386.361363863612, 6407.711652975616, 6429.871402571957, 6500.967495162529, 5933.4956528695, 5952.553558483131, 5974.6713930733795, 6004.5796794224425, 6028.27660680719, 5865.76536938522, 5913.852153696149, 5932.762689492415, 5955.285365756094, 5977.073553599177, 5755.618651523625, 5763.596548241583, 5795.072788353864, 5861.941380586182, 5911.9113213301835, 5849.970750146264, 5851.268591426072, 5889.8822023559715, 5927.110933359987, 5936.376680448534, 5770.451807078362, 5857.941420585782, 5882.176495290662, 5904.439078287599, 5915.142304365886, 6447.903281450782, 6485.286618471964, 6517.93482065178, 6522.804315870531, 6564.80305590833, 6928.930710692879, 6929.44564434844, 6936.237013928464, 6980.825479363057, 7016.7193312267445, 6781.627010514419, 6903.378695917391, 7003.159620845531, 7083.397911177556, 7087.468439867031, 6403.583767055126, 6465.256495502983, 6471.255805582324, 6480.416762491398, 6531.575447595891, 7079.610621415769, 7089.255628159072, 7171.247019062923, 7185.712571497186, 7191.17301510333, 6746.055552222692, 6766.0, 6827.146606674166, 6859.691313890787, 6875.3812156906115, 6647.966760166265, 6901.585904845625, 6903.654817259217, 6987.301269872972, 6991.23096459389, 6809.31906809325, 6848.212455567674, 6851.2121106072445, 6858.862822743517, 6864.4165245954655, 6806.0, 6838.863222735617, 6878.793636190822, 6882.277360877036, 6889.0, 5370.248165256867, 5393.2179833923865, 5406.405295417501, 5408.323959505062, 5414.864628384243, 4731.1720448921305, 4733.266343716729, 4778.928316075262, 4787.473377928425, 4796.544328288807, 5721.542276617861, 5960.7019649017375, 6024.849378765478, 6107.480864126599, 6119.112728654321, 6935.653217339112, 6962.322421247683, 7061.470389720792, 7062.1878483975, 7082.787516374517, 5899.174115623816, 6000.789972350947, 6000.819975400745, 6007.189029481052, 6084.513238940963, 5813.244278243801, 5822.737976791054, 5848.181254624356, 5849.5027922626205, 5857.092150716646, 5541.861453463615, 6567.704453299613, 6725.8654826903185, 6808.8978665320055, 6831.0, 6824.727010919706, 6911.7926462206215, 6913.827154321182, 6918.584884906922, 6918.792436226921, 6536.575122617205, 6560.0, 6580.539362244597, 6581.243157037002, 6581.308962558863, 6514.804555863332, 6675.732970681119, 6682.031105489774, 6690.397864192246, 6694.698738556874, 6757.222919364336, 6785.18577770651, 6806.18325800907, 6991.44068474531, 7010.088688470466, 6576.013597960345, 6641.0, 6678.365555272241, 6680.098186744727, 6728.899066513908, 6634.469242460688, 6637.668116594247, 6659.966700166468, 6661.034150048313, 6669.733210671713, 6672.132622759011, 6678.766243181465, 6692.130023097161, 6735.797926062226, 6737.124173857564, 6700.597964122168, 6775.966120169368, 6836.863262734618, 6847.623380714109, 6854.451643868579, 6394.424501794861, 6517.315681853337, 6533.477321814338, 6545.410913017851, 6625.0], "value": 6281.5757065775515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.3850881093582, 846.9872951905722, 869.200335691164, 877.5261358866212, 1119.283658458587, 30534.23664408391, 31036.22409439765, 31062.0681379559, 42265.57734422655, 43207.0, 42813.571864281344, 43117.35323970143, 43343.566564334345, 43350.349744753854, 43415.565844341545, 7863.960680196591, 7895.763127106196, 8024.43828931975, 8117.512949223036, 8120.878186827173, 7933.127355990837, 8042.0, 8048.879266811003, 8050.919490805076, 8063.0, 8048.396370272224, 8092.190780921919, 8104.270615644591, 8106.310963568074, 8113.350931925462, 8043.517388956652, 8055.959720201361, 8056.7180148694515, 8059.798505037392, 8063.516189028648, 7909.683612655487, 7970.760877173694, 7971.641276142587, 7972.242636949481, 7978.521288722695, 39271.41092883609, 39421.60578394208, 39502.0, 39530.60469395327, 39548.0, 3582.5163602913535, 3614.620464851179, 3620.529331186955, 3623.8912832615065, 3627.093226693327, 31653.0, 31697.207569810707, 31727.84136079305, 31763.52354714681, 31811.363772724417, 5232.424433312332, 5238.449962753894, 5240.580753539708, 5252.527272545489, 5305.867353316159, 193555.0, 194635.0, 194824.0, 195374.02312988485, 195593.0, 48924.0, 48999.51000490021, 49278.014439711005, 49392.25911611329, 49435.258471122965, 50506.74746626243, 50997.23504147441, 51074.23388649173, 51084.0, 51133.23300150501, 13594.864051359558, 15515.767263491056, 15782.605434864221, 15867.920660396854, 15878.682426351408, 11152.330860148417, 13140.27728474943, 13255.204687718768, 13267.336633168303, 13276.402561884739, 14360.0, 14494.420223191166, 14635.414583416545, 14652.853471465362, 14673.192974386511, 5720.542356611544, 5875.735591898375, 5885.352611212763, 5896.380880007538, 5974.820755377345, 1008.5864795433984, 1009.9091081802526, 1010.7422607235108, 1015.1117771949545, 1029.7219750667277, 23060.76939230586, 23070.538589228123, 23075.884620577126, 23110.42223944415, 23214.883925580263, 1124.454639499842, 1125.4597793059377, 1128.802459569572, 1137.5620386151227, 1142.4173671427632, 12213.816792748115, 12302.200356976768, 12314.076444266715, 12348.506059757688, 12379.75240495203, 231397.0, 232411.0, 233659.0, 234043.0, 234449.0, 51713.48286517087, 52020.0, 52053.479465204866, 52226.73886630542, 52302.0, 1467.3983666696818, 1473.8894582906325, 1500.1149321900132, 1502.7144842479893, 1507.8190617125795, 22544.210952617235, 22576.0, 22675.88662056679, 22733.0, 22801.429964251027, 37558.0, 37764.81117594394, 37775.81112094422, 37921.2415751689, 38001.80999094987, 196197.0, 197248.0, 198207.0, 198266.0, 198990.0, 34032.65967340295, 35078.12304692403, 35749.0, 35957.0, 35973.0, 44412.333814992155, 45246.3213051811, 45590.0, 45683.77158114188, 45699.31451028171, 34162.65837341595, 34179.14552136217, 34387.484187737704, 34407.483887741204, 34423.0, 33481.33037339191, 33645.66354336425, 33810.49284260787, 33964.660353396146, 34505.309893802485, 38021.23957520889, 38109.4283585752, 38383.80808095942, 38416.23167536689, 39046.804765977125, 33423.49864752079, 33547.83226083854, 33779.0, 33779.3244135111, 34562.0, 24764.752352476964, 24899.64256255112, 24932.252032439057, 25402.745972540037, 25568.872155639103], "value": 17494.408760720115, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.4731799426535, 571.5570432914488, 573.3406582430205, 585.4233579923774, 591.3229352391512, 21855.562888742217, 22644.66033009504, 22665.433364165885, 22702.77297227027, 23756.28731138064, 24482.265532034024, 25305.746942530568, 25327.087384950737, 25337.74662253377, 25343.74656253437, 6454.774082907099, 6725.899111513343, 6844.452443804508, 6846.144231971004, 6847.93152068478, 6666.466682665399, 6728.730850765988, 6732.158480189976, 6736.191657001166, 6737.865242695143, 6611.173603299588, 6664.700088496005, 6665.966670166642, 6673.866522669544, 6677.66611669417, 6620.04009418634, 6649.767258145966, 6656.567323123982, 6665.500087493458, 6669.833254168636, 6170.7531698732, 6190.226221722285, 6213.96893015532, 6221.782237621662, 6226.003839385677, 28096.859515702494, 28795.0, 28796.280092997633, 29249.853750731323, 29254.70745292541, 2006.0772044859236, 2029.0666293505005, 2029.7564292284874, 2030.451778019941, 2035.6641154209537, 19284.80715192858, 20041.0, 20068.39794806158, 20109.8994505028, 20115.195392184294, 2442.511497700449, 2472.814538909571, 2534.8605826679527, 2536.07433286849, 2539.0, 141204.0, 143948.0, 143952.0, 144135.27932360375, 144436.0, 14522.927385363006, 14775.778363324558, 14796.63008424798, 14924.850751492346, 15138.394464221528, 22247.888760556416, 23068.307950761504, 23078.0, 23081.192158274378, 23086.65370019451, 9248.843894513186, 9350.392224505522, 9512.714618561455, 9579.425234485814, 9660.806783864424, 8716.259117974916, 9414.811703766023, 9563.61745530171, 9804.950975245078, 9836.754081147885, 9184.540772961325, 9214.01898591806, 9372.297077719199, 9432.717018489457, 9639.518024098767, 2629.3689514516377, 2678.2634775436522, 2761.130243973142, 2789.9581506277423, 2853.6599988009634, 960.6877764726491, 967.6274634265718, 990.1089019882164, 995.3977843404781, 1016.1870503597092, 19215.423537293904, 19702.31041913526, 19975.500612484804, 20238.291659791837, 20283.79716202819, 1037.8183817832, 1055.883852776194, 1072.2065671403107, 1074.5486895503907, 1076.520948178069, 10949.124070074375, 10983.670489885317, 11022.869767719674, 11079.0, 11130.443477826077, 447411.0, 453150.0, 454480.0, 456349.0, 459932.0, 112267.0, 114012.42993784978, 114434.42782786033, 116589.4170529142, 117953.0, 1185.2829038431796, 1207.3057991654834, 1215.635309407192, 1219.9268043917393, 1304.9086563940623, 21339.25312614051, 21521.0, 21587.784122159206, 21662.241821536172, 21719.457013574785, 59907.700461497414, 60719.0, 61267.0, 62779.6861015692, 63347.366526334146, 73265.63367183344, 75986.24013759791, 77183.2281677176, 77478.0, 78007.21992780226, 14290.356933938207, 14473.552644735442, 14528.491502797775, 14611.707765844836, 14684.265786710408, 22525.324240272817, 22680.206192783175, 22882.542349153257, 22919.197828075936, 23042.42393940098, 17311.653766924843, 17445.738313925554, 18488.260469581066, 18573.164207610418, 18676.719849202545, 14982.400703972155, 15709.921450392674, 16137.0, 16261.186940653157, 16363.836361636231, 18056.45830625083, 18712.906435467736, 18731.0, 18961.620767584296, 19023.143958522178, 14457.710845782814, 14534.491292804702, 14629.561113166623, 14632.634184145481, 14636.975411721078, 18394.98827564497, 18398.1720822566, 18477.35329263523, 18521.907390462962, 18625.0], "value": 12105.901658982259, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1327.5287273018077, 1388.9583312500629, 1400.4818217259613, 1403.0389183409359, 1407.8873690104786, 14957.177355245458, 15206.391744330242, 15580.143092129943, 15595.922020389897, 15630.29663665135, 16063.196840158003, 16351.509454716348, 16373.099479528652, 16382.590435239124, 16385.508434746946, 2812.4375124975036, 2879.971200288002, 2881.625388699466, 2882.423515296949, 2883.2647674842888, 2681.0, 2865.0688526228955, 2865.555838844982, 2867.913962581126, 2868.3689588290545, 2824.957625635617, 2828.179827849921, 2857.742803147716, 2858.0996985949464, 2858.128270877392, 2810.592464092695, 2821.80073468776, 2829.8585070746485, 2831.3487897783625, 2839.9432011359863, 2688.610151528037, 2689.7579217870484, 2701.203145072189, 2701.3786829029236, 2711.1188863619404, 15130.54608361751, 15470.071795692182, 15482.922585387114, 15494.612634684223, 15502.767458488132, 8085.393595480304, 8328.250457458858, 8344.08215096339, 8387.958060209721, 8392.958035209786, 5399.37907140676, 5520.171974203861, 5605.103183490663, 5702.54379649627, 5732.742026608813, 1803.7925638551471, 1846.815318468156, 1851.1392202625843, 1857.7027675571978, 1873.6252749450164, 26882.32794180161, 27208.319792005357, 27221.591676124874, 27344.316392090157, 27386.863065684545, 6722.722682690273, 6738.730450782012, 6835.316468353224, 6922.411595014481, 7121.252268511836, 17439.04085275322, 17439.389621363443, 17447.825521744875, 17510.036947967983, 17511.211995460235, 8001.799955001172, 8024.438289319692, 8026.077001144827, 8031.477953932976, 8038.919610803817, 6250.96859018266, 7334.3032411920785, 7339.412846972229, 7449.776506704808, 7462.589557574385, 5016.899662006739, 5158.432572417031, 5365.570754339644, 5376.91934620981, 5380.757865896044, 1496.8503149685162, 1536.9077855328717, 1547.5821528187325, 1558.3299181352127, 1587.380921440639, 926.1757036237753, 938.8216238914584, 945.8817647794026, 949.349695458613, 951.2152474208806, 8829.16122968317, 9063.456192628464, 9070.773230669154, 9134.360594758304, 9136.771580710403, 1000.0799264676515, 1011.4588695048261, 1026.9178465722725, 1029.8867124616286, 1043.4417586591137, 5350.438203988525, 5369.4362091980565, 5417.377001644862, 5436.157395603609, 5475.123980163235, 22037.11851525921, 22697.773022269565, 22755.7724422757, 22914.198003069814, 22927.0, 20986.160553577993, 22236.777632223795, 22274.999999999676, 22286.55426891453, 22296.77703222947, 11436.370999595103, 11788.408564843809, 11841.88158118425, 11863.703407414883, 11867.940660296643, 9048.321375896703, 9168.129027742354, 9212.677556285498, 9279.953600231956, 9309.953450232706, 18436.446906592824, 18585.16366763472, 18840.623187535897, 18936.52658683544, 19074.618507630046, 18902.621947560696, 19082.236710531422, 19082.522936926132, 19120.27917487433, 19166.61666766629, 11584.189106762446, 11991.700207494883, 12043.518259269882, 12045.0, 12063.034957203578, 12631.0, 13037.348132593523, 14050.297485125498, 14188.219647919463, 14445.78331325052, 10759.085477734166, 10824.783504330027, 10862.837057443987, 11004.559817607205, 11180.440977951233, 11199.328040317607, 11236.0, 11257.437128143723, 11366.545338186379, 11428.085753139896, 9767.560459779184, 9875.259355548218, 9914.702558923243, 9968.750781230527, 9980.401175929468, 7566.810829729301, 7616.466847320635, 7639.274268944664, 7639.770806875803, 7645.732399365996, 7379.926200737924, 7511.173770885089, 7515.473916825769, 7535.284148005931, 7554.584497852671], "value": 6861.344743862686, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.4925074925075, 521.3535216331748, 525.5900397689802, 534.262717449919, 538.2545174932719, 6338.873222535558, 6349.238091429029, 6352.333005034474, 6379.712912918918, 6444.548881578291, 6198.566100372976, 6215.192025036739, 6292.874142517158, 6336.873262534758, 6373.13962615048, 5210.426853046162, 5212.983468223698, 5251.737413129338, 5269.525742683158, 5288.788448462057, 4928.285398617199, 5227.575937760773, 5227.973860130713, 5254.605904557155, 5290.206469029637, 5034.773435195407, 5052.14113600687, 5056.949430505685, 5064.620153488503, 5106.8764871728345, 5023.949760502384, 5029.974850125763, 5037.168867136937, 5044.369453818273, 5074.441811400743, 4375.540568240321, 4512.435945506812, 4533.52397998212, 4544.454665440168, 4545.477270113946, 6096.817095487142, 6111.87776244477, 6131.816045518642, 6189.38106189382, 6192.814215573495, 5699.344575373802, 5700.828975130753, 5710.914336284959, 5768.192453056576, 5818.185454036438, 6457.967710161466, 6530.6408147551865, 6581.80254592363, 6625.933740662628, 6645.0, 4921.138800709861, 4950.4059512858685, 4976.2038073908, 4985.775640096168, 4986.202207646759, 6736.46108311334, 6768.357006084414, 6856.794296171172, 7058.517711280637, 7140.714371425137, 4858.319835223072, 4971.875703107451, 4995.975020124876, 5045.84862454127, 5089.0, 5042.873928151826, 5066.986602679479, 5078.796848126033, 5126.641135120506, 5146.768395422215, 5675.1770993205755, 5705.657660540381, 5766.625169363978, 5777.826665200051, 5781.335146458127, 6697.827880120959, 6699.598024118569, 6722.722682690273, 6734.595924244561, 6734.932650673529, 1748.4929370482419, 1780.9198586063658, 1794.605186858889, 1803.5130514761204, 1805.8284462975996, 1150.9079273658087, 1199.5501686867424, 1214.8299238106672, 1215.7234903351446, 1242.8757124287679, 5892.94107058924, 5893.44012318829, 5911.0, 5929.911051334234, 5952.613080149777, 6715.664216789141, 6804.795856124324, 6881.243063263037, 6912.274211207851, 6921.861562768817, 5818.79634212809, 5852.15143804146, 5867.442592953748, 5895.587308888423, 5922.496587790086, 3599.6400359963795, 3622.5109610202285, 3628.401313783249, 3637.5998640149564, 3643.180284435981, 6631.469482441393, 6652.401283884376, 6742.157230346207, 6742.831429214309, 6748.865022699519, 7034.577925324497, 7109.182444019004, 7140.714371425189, 7145.606991615407, 7151.89272160918, 6185.505159587222, 6626.337366263335, 6648.135742353463, 6684.766033188815, 6694.196696396366, 6692.364225398579, 6816.216135144522, 6837.760678376233, 6839.965800170968, 6854.897176542256, 6815.386615204656, 6817.625030623363, 6821.861943506043, 6833.3166683331265, 6916.1700595926795, 6622.403983641495, 6663.800085997428, 6735.764248251287, 6745.797626071226, 6751.93248067513, 5489.835304940858, 5535.750891209825, 5617.073182924935, 5619.735559499243, 5631.887362252814, 6533.889238829315, 6575.736970521126, 6612.0, 6866.519343646097, 6884.173899132136, 6575.704093315717, 6599.53803233769, 6658.500612453989, 6680.465562754871, 6695.631740254333, 6651.900221496779, 6666.8666326723605, 6676.83307917306, 6688.8963321053425, 6707.832304192434, 6585.506087043395, 6588.538802283986, 6591.209054913441, 6607.603543787388, 6646.335366463315, 6664.5334826561675, 6669.499787515859, 6686.498512611476, 6696.966515167393, 6733.158355205599, 6477.894684227739, 6486.254080780771, 6504.30887969122, 6504.902426463702, 6505.251896032017], "value": 5157.212736921282, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [902.7066203483868, 907.691384929124, 921.9078092190782, 926.8887733471985, 932.4871320773577, 16891.155442227886, 18075.457736267916, 18195.727064094048, 21224.363269101952, 22115.889420552914, 18489.167987440564, 19670.70493942592, 19835.900820495877, 19842.70235946462, 20110.396688099343, 964.0841200859177, 987.4272921705393, 991.1921783746224, 991.5736233419626, 992.2310209587555, 948.1892981500821, 973.4110862927932, 1003.3578509753763, 1005.607812952949, 1005.9094681478665, 1049.8530205771199, 1051.8685164354456, 1052.0689190066807, 1052.2003277509073, 1052.7210289273348, 1048.1457612046158, 1050.0497050169608, 1050.112654806687, 1050.1913526584526, 1050.6637875879683, 773.3465221887496, 773.6595897804948, 775.9728409505697, 776.829097598533, 777.0558771093149, 20143.294984675613, 20466.692999604868, 20584.27955021582, 20782.5843483131, 20794.896025519927, 20950.0, 21737.891310543346, 21802.563948721094, 22325.330240092822, 22362.66456003161, 17279.04965226912, 17345.39291124817, 17358.479245622653, 17443.5639109022, 17569.648607027917, 3826.7321287509885, 3873.9418908716393, 3874.9031274218087, 3875.5736868944678, 3876.2247550489733, 127125.36437317754, 127598.72401276055, 129664.0, 131004.34497827545, 132017.0, 16125.113118778345, 16471.25879335433, 16588.170591470378, 16735.414260501067, 16789.24448399825, 30408.391832163234, 31001.224969375948, 31081.533776993365, 31192.06423807244, 31359.686403135678, 8593.269572086443, 8745.650173992968, 8750.562471876381, 8769.956150219208, 8825.867611985825, 4329.783510824509, 4539.977300113478, 4571.314302854532, 4571.771411429415, 4578.496365399803, 5155.896882062413, 5251.684898906002, 5268.552173065333, 5280.683159010472, 5285.577153827761, 978.9902100978899, 1031.4120951057976, 1031.4636389077787, 1031.7059638003134, 1039.589362201926, 644.4940721533574, 650.1418128070995, 658.4173006888842, 672.3814091036259, 690.3096903096903, 11241.55033798639, 11549.826752598718, 11745.29693194482, 12959.805602915963, 12969.805452918214, 680.3706571421416, 698.3575110897985, 708.2421808664782, 722.9783106506814, 738.9704411823467, 5321.920171197435, 5631.352394474606, 5699.0, 5930.703464826742, 5941.138534912414, 109816.0, 110995.0, 113107.0, 113948.7251218922, 117077.0, 95998.52000739952, 96356.51821740987, 98339.50830245802, 99519.50240248753, 99864.0, 85325.57337213274, 85551.0, 85641.0, 88829.55585222162, 90223.0, 12569.349340743618, 13151.717229108463, 13205.009624278026, 13215.273159976294, 13298.202107873558, 40959.79520102381, 40974.38538421986, 41341.0, 41616.583834162484, 42085.0, 52915.735421324185, 53078.469215307356, 53148.0, 53758.0, 56164.43835561592, 17340.47978560645, 17346.5663358412, 17540.649187016443, 17687.292508299524, 17781.73327400116, 19050.7142392867, 19340.806591934463, 19457.902710486356, 19721.211151553776, 19838.0, 17565.473035808947, 17687.734683979492, 17793.288268469118, 17884.55288617795, 17906.373276935246, 17798.91100544489, 18027.2789088435, 18068.36760713369, 18106.909465452587, 18278.543036424195, 17249.827501724823, 17389.217485212943, 17450.91274543619, 17574.20916058755, 17585.20866560982, 12733.490660373482, 13037.956963443092, 13618.114822536503, 13946.930265348608, 14322.928385358007, 13673.863261367656, 13705.246211458465, 13711.657208569866, 13810.171389716648, 14034.929825350808], "value": 9147.019011522587, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1486.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 112.0, 100.0, 109.0, 108.0, 110.0, 109.0, 99.0, 111.0, 111.0], "value": 106.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 115.0, 143.0, 183.0, 115.0, 147.0, 147.0, 123.0, 175.0, 117.0], "value": 140.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 162.0, 163.0, 162.0, 162.0, 163.0, 167.0, 164.0, 169.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 74.0, 168.0, 75.0, 73.0, 79.0, 74.0, 160.0, 74.0], "value": 92.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 135.0, 80.0, 79.0, 80.0, 135.0, 79.0, 80.0, 123.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 115.0, 120.0, 114.0, 114.0], "value": 114.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 150.0, 148.0, 148.0, 147.0, 148.0, 149.0, 147.0, 148.0, 148.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 100.0, 94.0, 93.0, 95.0, 94.0, 95.0, 94.0, 93.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 52.0, 54.0, 54.0, 54.0, 52.0, 51.0, 57.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 39.0, 38.0, 38.0, 38.0, 38.0, 38.0, 39.0, 38.0, 38.0], "value": 38.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 75.0, 77.0, 75.0, 75.0, 75.0, 75.0, 76.0, 75.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 123.0, 119.0, 125.0, 121.0, 127.0, 123.0, 133.0, 122.0, 155.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 167.0, 171.0, 169.0, 174.0, 170.0, 168.0, 172.0, 173.0], "value": 170.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 65.0, 66.0, 68.0, 64.0, 64.0, 68.0, 64.0], "value": 65.7, "unit": "score"}]}]} 21:10:32 INFO - Return code: 0 21:10:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:10:32 INFO - # TBPL SUCCESS # 21:10:32 INFO - Running post-action listener: _package_coverage_data 21:10:32 INFO - Running post-action listener: _resource_record_post_action 21:10:32 INFO - [mozharness: 2017-08-31 04:10:32.284803Z] Finished run-tests step (success) 21:10:32 INFO - Running post-run listener: _resource_record_post_run 21:10:32 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 392761344; Read time: 20; Write time: 210324 21:10:32 INFO - TinderboxPrint: CPU usage
13.1% 21:10:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 21:10:32 INFO - TinderboxPrint: I/O write bytes / time
392,761,344 / 210,324 21:10:32 INFO - TinderboxPrint: CPU idle
8,231.6 (86.9%) 21:10:32 INFO - TinderboxPrint: CPU user
1,191.1 (12.6%) 21:10:32 INFO - TinderboxPrint: Swap in / out
0 / 0 21:10:32 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 106971136; Read time: 0; Write time: 64336 21:10:32 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:10:32 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285351936; Read time: 20; Write time: 145960 21:10:32 INFO - Running post-run listener: _upload_blobber_files 21:10:32 INFO - Blob upload gear active. 21:10:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:10:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:10:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:10:33 INFO - (blobuploader) - INFO - Open directory for files ... 21:10:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:10:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:10:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:10:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:10:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:10:36 INFO - (blobuploader) - INFO - Done attempting. 21:10:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:10:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:10:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:10:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:10:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:10:37 INFO - (blobuploader) - INFO - Done attempting. 21:10:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:10:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:10:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:10:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:10:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:10:39 INFO - (blobuploader) - INFO - Done attempting. 21:10:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:10:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:10:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:10:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:10:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:10:40 INFO - (blobuploader) - INFO - Done attempting. 21:10:40 INFO - (blobuploader) - INFO - Iteration through files over. 21:10:40 INFO - Return code: 0 21:10:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:10:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:10:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f743526c75100681a153c1afe62aa5342ae5ab74802db7e356f88b1f578cc4d25c7192e8ab994f9bf9a1e68fd80c6e225c2f9dfedef803561241dbb13e35b9b1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/89cdfbdcca52132b8e83dd611b6ec64ab2d2600e2198ea6f55f4e86216d047b91560f36c52d198ab87d0597fc72f9e39888dba6cb9f727405594db906b22e382", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c8bc80c06920767cd81a69ce072c2dc699b60b439b47959f709c5e19b0db64e95a0440a7a526c0d59e2ee3f6f4beb659efccd39f96728b58ed651bce32104baa", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8991ef5471fb500d7672a7b6dd7e9e628579481a5e798eeb54d9409299e27a243f4ba9bd7a3287ffeb720e389c1ed4516a65562faa317c569fba4b0b8b735a0d"} 21:10:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:10:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:10:40 INFO - Contents: 21:10:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f743526c75100681a153c1afe62aa5342ae5ab74802db7e356f88b1f578cc4d25c7192e8ab994f9bf9a1e68fd80c6e225c2f9dfedef803561241dbb13e35b9b1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/89cdfbdcca52132b8e83dd611b6ec64ab2d2600e2198ea6f55f4e86216d047b91560f36c52d198ab87d0597fc72f9e39888dba6cb9f727405594db906b22e382", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c8bc80c06920767cd81a69ce072c2dc699b60b439b47959f709c5e19b0db64e95a0440a7a526c0d59e2ee3f6f4beb659efccd39f96728b58ed651bce32104baa", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8991ef5471fb500d7672a7b6dd7e9e628579481a5e798eeb54d9409299e27a243f4ba9bd7a3287ffeb720e389c1ed4516a65562faa317c569fba4b0b8b735a0d"} 21:10:40 INFO - Running post-run listener: copy_logs_to_upload_dir 21:10:40 INFO - Copying logs to upload dir... 21:10:40 INFO - mkdir: /builds/slave/test/build/upload/logs 21:10:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.112298 ========= master_lag: -0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2017-08-30 21:10:40.093890) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 21:10:40.101430) ========= 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=51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f743526c75100681a153c1afe62aa5342ae5ab74802db7e356f88b1f578cc4d25c7192e8ab994f9bf9a1e68fd80c6e225c2f9dfedef803561241dbb13e35b9b1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/89cdfbdcca52132b8e83dd611b6ec64ab2d2600e2198ea6f55f4e86216d047b91560f36c52d198ab87d0597fc72f9e39888dba6cb9f727405594db906b22e382", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c8bc80c06920767cd81a69ce072c2dc699b60b439b47959f709c5e19b0db64e95a0440a7a526c0d59e2ee3f6f4beb659efccd39f96728b58ed651bce32104baa", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8991ef5471fb500d7672a7b6dd7e9e628579481a5e798eeb54d9409299e27a243f4ba9bd7a3287ffeb720e389c1ed4516a65562faa317c569fba4b0b8b735a0d"} build_url:https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011374 build_url: 'https://queue.taskcluster.net/v1/task/Cu5bJiXdSKGYWl3gR-gpGg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f743526c75100681a153c1afe62aa5342ae5ab74802db7e356f88b1f578cc4d25c7192e8ab994f9bf9a1e68fd80c6e225c2f9dfedef803561241dbb13e35b9b1", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/89cdfbdcca52132b8e83dd611b6ec64ab2d2600e2198ea6f55f4e86216d047b91560f36c52d198ab87d0597fc72f9e39888dba6cb9f727405594db906b22e382", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c8bc80c06920767cd81a69ce072c2dc699b60b439b47959f709c5e19b0db64e95a0440a7a526c0d59e2ee3f6f4beb659efccd39f96728b58ed651bce32104baa", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8991ef5471fb500d7672a7b6dd7e9e628579481a5e798eeb54d9409299e27a243f4ba9bd7a3287ffeb720e389c1ed4516a65562faa317c569fba4b0b8b735a0d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-30 21:10:40.131478) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 21:10:40.131862) ========= 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=51fc86400edbde9650d2781a000001d0-1504151389.717709-1935193215 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004563 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-30 21:10:40.213940) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 21:10:40.214304) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-30 21:10:40.214670) ========= ========= Total master_lag: 0.08 =========