builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-083 starttime: 1506815725.06 results: success (0) revision: 179dccc17c7f261347436b4b1c33029c8272960b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:25.061187) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:25.061587) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:25.061869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:25.181169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:25.181448) ========= 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=3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-30 16:55:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 19.9M=0.001s 2017-09-30 16:55:25 (19.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.181831 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:25.424561) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:25.424846) ========= 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=3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017153 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:25.490782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-30 16:55:25.494124) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 179dccc17c7f261347436b4b1c33029c8272960b --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 179dccc17c7f261347436b4b1c33029c8272960b --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=3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-30 16:55:25,558 truncating revision to first 12 chars 2017-09-30 16:55:25,559 Setting DEBUG logging. 2017-09-30 16:55:25,559 attempt 1/10 2017-09-30 16:55:25,559 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/179dccc17c7f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-30 16:55:26,831 unpacking tar archive at: mozilla-central-179dccc17c7f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.606316 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-30 16:55:27.123735) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:27.127067) ========= script_repo_revision: 179dccc17c7f261347436b4b1c33029c8272960b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:27.127482) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:27.127739) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-30 16:55:27.148739) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-30 16:55:27.149035) ========= /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=3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:55:27 INFO - MultiFileLogger online at 20170930 16:55:27 in /builds/slave/test 16:55:27 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 16:55:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:55:27 INFO - {'append_to_log': False, 16:55:27 INFO - 'base_work_dir': '/builds/slave/test', 16:55:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:55:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:55:27 INFO - 'branch': 'Firefox-Non-PGO', 16:55:27 INFO - 'buildbot_json_path': 'buildprops.json', 16:55:27 INFO - 'code_coverage': False, 16:55:27 INFO - 'config_files': ('talos/linux_config.py',), 16:55:27 INFO - 'default_actions': ('clobber', 16:55:27 INFO - 'read-buildbot-config', 16:55:27 INFO - 'download-and-extract', 16:55:27 INFO - 'populate-webroot', 16:55:27 INFO - 'create-virtualenv', 16:55:27 INFO - 'install', 16:55:27 INFO - 'setup-mitmproxy', 16:55:27 INFO - 'run-tests'), 16:55:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:55:27 INFO - 'disable_ccov_upload': False, 16:55:27 INFO - 'disable_stylo': False, 16:55:27 INFO - 'download_minidump_stackwalk': True, 16:55:27 INFO - 'download_symbols': 'ondemand', 16:55:27 INFO - 'enable_stylo': False, 16:55:27 INFO - 'enable_webrender': False, 16:55:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 16:55:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:55:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:55:27 INFO - 'gecko_profile': False, 16:55:27 INFO - 'gecko_profile_interval': 0, 16:55:27 INFO - 'installer_path': 'installer.exe', 16:55:27 INFO - 'log_level': 'info', 16:55:27 INFO - 'log_name': 'talos', 16:55:27 INFO - 'log_to_console': True, 16:55:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:55:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:55:27 INFO - 'opt_config_files': (), 16:55:27 INFO - 'pip_index': False, 16:55:27 INFO - 'suite': 'dromaeojs-e10s', 16:55:27 INFO - 'system_bits': '32', 16:55:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:55:27 INFO - 'title': 'talos-linux64-ix-083', 16:55:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:55:27 INFO - 'use_talos_json': True, 16:55:27 INFO - 'verify': 'False', 16:55:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:55:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:55:27 INFO - 'work_dir': 'build'} 16:55:27 INFO - [mozharness: 2017-09-30 23:55:27.430608Z] Running clobber step. 16:55:27 INFO - Running pre-action listener: _resource_record_pre_action 16:55:27 INFO - Running main action method: clobber 16:55:27 INFO - rmtree: /builds/slave/test/build 16:55:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:55:28 INFO - Running post-action listener: _resource_record_post_action 16:55:28 INFO - [mozharness: 2017-09-30 23:55:28.391917Z] Finished clobber step (success) 16:55:28 INFO - [mozharness: 2017-09-30 23:55:28.392014Z] Running read-buildbot-config step. 16:55:28 INFO - Running pre-action listener: _resource_record_pre_action 16:55:28 INFO - Running main action method: read_buildbot_config 16:55:28 INFO - Using buildbot properties: 16:55:28 INFO - { 16:55:28 INFO - "project": "", 16:55:28 INFO - "product": "firefox", 16:55:28 INFO - "who": "archaeopteryx@coole-files.de", 16:55:28 INFO - "installer_path": "public/build/target.tar.bz2", 16:55:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:55:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 16:55:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:55:28 INFO - "stage_platform": "linux64", 16:55:28 INFO - "basedir": "/builds/slave/test", 16:55:28 INFO - "buildnumber": 887, 16:55:28 INFO - "platform": "ubuntu64_hw", 16:55:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:55:28 INFO - "slavebuilddir": "test", 16:55:28 INFO - "taskId": "BcCOYs-ISdaBg2bGf5TbXA", 16:55:28 INFO - "branch": "mozilla-central", 16:55:28 INFO - "script_repo_revision": "production", 16:55:28 INFO - "revision": "179dccc17c7f261347436b4b1c33029c8272960b", 16:55:28 INFO - "slavename": "talos-linux64-ix-083", 16:55:28 INFO - "repo_path": "mozilla-central" 16:55:28 INFO - } 16:55:28 INFO - Finding installer, test and symbols from parent task. 16:55:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BcCOYs-ISdaBg2bGf5TbXA'}, attempt #1 16:55:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BcCOYs-ISdaBg2bGf5TbXA'}, attempt #1 16:55:29 INFO - Task dependencies: Oy_3fd7dS46Shpt74MAsbg 16:55:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg'}, attempt #1 16:55:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.tar.bz2 16:55:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.test_packages.json 16:55:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.crashreporter-symbols.zip 16:55:29 INFO - Running post-action listener: _resource_record_post_action 16:55:29 INFO - [mozharness: 2017-09-30 23:55:29.822336Z] Finished read-buildbot-config step (success) 16:55:29 INFO - [mozharness: 2017-09-30 23:55:29.822556Z] Running download-and-extract step. 16:55:29 INFO - Running pre-action listener: _resource_record_pre_action 16:55:29 INFO - Running main action method: download_and_extract 16:55:29 INFO - mkdir: /builds/slave/test/build/tests 16:55:29 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')]} 16:55:29 INFO - trying https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.test_packages.json 16:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 16:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 16:55:30 INFO - Downloaded 989 bytes. 16:55:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 16:55:30 INFO - Using the following test package requirements: 16:55:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 16:55:30 INFO - u'common': [u'target.common.tests.zip'], 16:55:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 16:55:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 16:55:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 16:55:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 16:55:30 INFO - u'mozbase': [u'target.common.tests.zip'], 16:55:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 16:55:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 16:55:30 INFO - u'web-platform': [u'target.common.tests.zip', 16:55:30 INFO - u'target.web-platform.tests.tar.gz'], 16:55:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 16:55:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 16:55:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.common.tests.zip 16:55:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.common.tests.zip'}, attempt #1 16:55:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.common.tests.zip into memory 16:55:32 INFO - Content-Length response header: 55366791 16:55:32 INFO - Bytes received: 55366791 16:55:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.talos.tests.zip 16:55:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 16:55:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.talos.tests.zip into memory 16:55:44 INFO - Content-Length response header: 14522385 16:55:44 INFO - Bytes received: 14522385 16:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:44 INFO - trying https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.tar.bz2 16:55:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 16:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 16:55:47 INFO - Downloaded 61553897 bytes. 16:55:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.tar.bz2 16:55:47 INFO - mkdir: /builds/slave/test/properties 16:55:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:55:47 INFO - Writing to file /builds/slave/test/properties/build_url 16:55:47 INFO - Contents: 16:55:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.tar.bz2 16:55:47 INFO - Running post-action listener: _resource_record_post_action 16:55:47 INFO - Running post-action listener: find_tests_for_verification 16:55:47 INFO - Running post-action listener: set_extra_try_arguments 16:55:47 INFO - [mozharness: 2017-09-30 23:55:47.604141Z] Finished download-and-extract step (success) 16:55:47 INFO - [mozharness: 2017-09-30 23:55:47.604375Z] Running populate-webroot step. 16:55:47 INFO - Running pre-action listener: _resource_record_pre_action 16:55:47 INFO - Running main action method: populate_webroot 16:55:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:55:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['tresize', 16:55:47 INFO - 'tcanvasmark']}, 16:55:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:55:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['dromaeo_css', 16:55:47 INFO - 'kraken']}, 16:55:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:55:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:55:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:55:47 INFO - 'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:55:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:55:47 INFO - 'tests': ['damp', 'tps']}, 16:55:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:55:47 INFO - 'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['damp', 'tps']}, 16:55:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:55:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['dromaeo_dom']}, 16:55:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:55:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['basic_compositor_video', 16:55:47 INFO - 'glvideo']}, 16:55:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:55:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:55:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:55:47 INFO - 'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['ts_paint_webext', 16:55:47 INFO - 'tp5o_webext']}, 16:55:47 INFO - 'other-e10s': {'tests': ['a11yr', 16:55:47 INFO - 'ts_paint', 16:55:47 INFO - 'tpaint', 16:55:47 INFO - 'sessionrestore', 16:55:47 INFO - 'sessionrestore_many_windows', 16:55:47 INFO - 'sessionrestore_no_auto_restore', 16:55:47 INFO - 'tabpaint', 16:55:47 INFO - 'cpstartup']}, 16:55:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['a11yr', 16:55:47 INFO - 'ts_paint', 16:55:47 INFO - 'tpaint', 16:55:47 INFO - 'sessionrestore', 16:55:47 INFO - 'sessionrestore_many_windows', 16:55:47 INFO - 'sessionrestore_no_auto_restore', 16:55:47 INFO - 'tabpaint', 16:55:47 INFO - 'cpstartup']}, 16:55:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 16:55:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 16:55:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['perf_reftest_singletons']}, 16:55:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['perf_reftest']}, 16:55:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:55:47 INFO - 'tsvgr_opacity', 16:55:47 INFO - 'tart', 16:55:47 INFO - 'tscrollx', 16:55:47 INFO - 'tsvg_static']}, 16:55:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['tsvgx', 16:55:47 INFO - 'tsvgr_opacity', 16:55:47 INFO - 'tart', 16:55:47 INFO - 'tscrollx', 16:55:47 INFO - 'tsvg_static']}, 16:55:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:55:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:55:47 INFO - 'talos_options': ['--disable-stylo'], 16:55:47 INFO - 'tests': ['tp5o']}, 16:55:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:55:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:55:47 INFO - 'talos_options': ['--mitmproxy', 16:55:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:55:47 INFO - '--firstNonBlankPaint'], 16:55:47 INFO - 'tests': ['tp6_google', 16:55:47 INFO - 'tp6_youtube', 16:55:47 INFO - 'tp6_amazon', 16:55:47 INFO - 'tp6_facebook']}, 16:55:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:55:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:55:47 INFO - 'talos_options': ['--disable-stylo', 16:55:47 INFO - '--mitmproxy', 16:55:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:55:47 INFO - '--firstNonBlankPaint'], 16:55:47 INFO - 'tests': ['tp6_google', 16:55:47 INFO - 'tp6_youtube', 16:55:47 INFO - 'tp6_amazon', 16:55:47 INFO - 'tp6_facebook']}, 16:55:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 16:55:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 16:55:47 INFO - 'talos_options': ['--stylo-threads=1', 16:55:47 INFO - '--mitmproxy', 16:55:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 16:55:47 INFO - '--firstNonBlankPaint'], 16:55:47 INFO - 'tests': ['tp6_google', 16:55:47 INFO - 'tp6_youtube', 16:55:47 INFO - 'tp6_amazon', 16:55:47 INFO - 'tp6_facebook']}, 16:55:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:55:47 INFO - 'talos_options': ['--xperf_path', 16:55:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:55:47 INFO - 'tests': ['tp5n']}, 16:55:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 16:55:47 INFO - 'talos_options': ['--disable-stylo', 16:55:47 INFO - '--xperf_path', 16:55:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:55:47 INFO - 'tests': ['tp5n']}}} 16:55:47 INFO - Running post-action listener: _resource_record_post_action 16:55:47 INFO - [mozharness: 2017-09-30 23:55:47.623279Z] Finished populate-webroot step (success) 16:55:47 INFO - [mozharness: 2017-09-30 23:55:47.623370Z] Running create-virtualenv step. 16:55:47 INFO - Running pre-action listener: _resource_record_pre_action 16:55:47 INFO - Running main action method: create_virtualenv 16:55:47 INFO - Creating virtualenv /builds/slave/test/build/venv 16:55:47 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:55:47 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 16:55:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:55:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:55:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:55:48 INFO - Installing distribute.............................................................................................................................................................................................done. 16:55:48 INFO - Installing pip...............done. 16:55:48 INFO - Return code: 0 16:55:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:55:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:55:48 INFO - Reading from file tmpfile_stdout 16:55:48 INFO - Output received: 16:55:48 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 16:55:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:55:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:55:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:55:48 INFO - Using env: {'DISPLAY': ':0', 16:55:48 INFO - 'HOME': '/home/cltbld', 16:55:48 INFO - 'LANG': 'en_US.UTF-8', 16:55:48 INFO - 'LANGUAGE': 'en_US:en', 16:55:48 INFO - 'LOGNAME': 'cltbld', 16:55:48 INFO - 'MAIL': '/var/mail/cltbld', 16:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:48 INFO - 'MOZ_NO_REMOTE': '1', 16:55:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:48 INFO - 'NO_EM_RESTART': '1', 16:55:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:49 INFO - 'PWD': '/builds/slave/test', 16:55:49 INFO - 'SHELL': '/bin/bash', 16:55:49 INFO - 'SHLVL': '1', 16:55:49 INFO - 'TERM': 'linux', 16:55:49 INFO - 'TMOUT': '86400', 16:55:49 INFO - 'USER': 'cltbld', 16:55:49 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:49 INFO - '_': '/tools/buildbot/bin/python'} 16:55:50 INFO - Ignoring indexes: http://pypi.python.org/simple/ 16:55:50 INFO - Downloading/unpacking pip>=1.5 16:55:50 INFO - Running setup.py egg_info for package pip 16:55:50 INFO - warning: no files found matching 'pip/cacert.pem' 16:55:50 INFO - warning: no files found matching '*.html' under directory 'docs' 16:55:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:55:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:55:50 INFO - Installing collected packages: pip 16:55:50 INFO - Found existing installation: pip 1.1 16:55:50 INFO - Uninstalling pip: 16:55:50 INFO - Successfully uninstalled pip 16:55:50 INFO - Running setup.py install for pip 16:55:50 INFO - warning: no files found matching 'pip/cacert.pem' 16:55:50 INFO - warning: no files found matching '*.html' under directory 'docs' 16:55:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 16:55:50 INFO - no previously-included directories found matching 'docs/_build/_sources' 16:55:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin 16:55:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 16:55:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 16:55:50 INFO - Successfully installed pip 16:55:50 INFO - Cleaning up... 16:55:50 INFO - Return code: 0 16:55:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:55:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:55:50 INFO - Using env: {'DISPLAY': ':0', 16:55:50 INFO - 'HOME': '/home/cltbld', 16:55:50 INFO - 'LANG': 'en_US.UTF-8', 16:55:50 INFO - 'LANGUAGE': 'en_US:en', 16:55:50 INFO - 'LOGNAME': 'cltbld', 16:55:50 INFO - 'MAIL': '/var/mail/cltbld', 16:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:50 INFO - 'MOZ_NO_REMOTE': '1', 16:55:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:50 INFO - 'NO_EM_RESTART': '1', 16:55:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:50 INFO - 'PWD': '/builds/slave/test', 16:55:50 INFO - 'SHELL': '/bin/bash', 16:55:50 INFO - 'SHLVL': '1', 16:55:50 INFO - 'TERM': 'linux', 16:55:50 INFO - 'TMOUT': '86400', 16:55:50 INFO - 'USER': 'cltbld', 16:55:50 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:50 INFO - '_': '/tools/buildbot/bin/python'} 16:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:50 INFO - Downloading/unpacking psutil>=3.1.1 16:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:55:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:55:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:55:51 INFO - Installing collected packages: psutil 16:55:51 INFO - Running setup.py install for psutil 16:55:51 INFO - building 'psutil._psutil_linux' extension 16:55:51 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 16:55:53 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 16:55:53 INFO - building 'psutil._psutil_posix' extension 16:55:53 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 16:55:53 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 16:55:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:55:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:55:53 INFO - Successfully installed psutil 16:55:53 INFO - Cleaning up... 16:55:53 INFO - Return code: 0 16:55:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:55:53 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')]} 16:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:53 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')]} 16:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:53 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 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:55:53 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 16:55:53 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 16:55:53 INFO - Using env: {'DISPLAY': ':0', 16:55:53 INFO - 'HOME': '/home/cltbld', 16:55:53 INFO - 'LANG': 'en_US.UTF-8', 16:55:53 INFO - 'LANGUAGE': 'en_US:en', 16:55:53 INFO - 'LOGNAME': 'cltbld', 16:55:53 INFO - 'MAIL': '/var/mail/cltbld', 16:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:53 INFO - 'MOZ_NO_REMOTE': '1', 16:55:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:53 INFO - 'NO_EM_RESTART': '1', 16:55:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:53 INFO - 'PWD': '/builds/slave/test', 16:55:53 INFO - 'SHELL': '/bin/bash', 16:55:53 INFO - 'SHLVL': '1', 16:55:53 INFO - 'TERM': 'linux', 16:55:53 INFO - 'TMOUT': '86400', 16:55:53 INFO - 'USER': 'cltbld', 16:55:53 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:53 INFO - '_': '/tools/buildbot/bin/python'} 16:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:55:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:55:54 INFO - Installing collected packages: mozsystemmonitor 16:55:54 INFO - Running setup.py install for mozsystemmonitor 16:55:55 INFO - Successfully installed mozsystemmonitor 16:55:55 INFO - Cleaning up... 16:55:55 INFO - Return code: 0 16:55:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:55: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')]} 16:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55: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')]} 16:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:55 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 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:55:55 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 16:55:55 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 16:55:55 INFO - Using env: {'DISPLAY': ':0', 16:55:55 INFO - 'HOME': '/home/cltbld', 16:55:55 INFO - 'LANG': 'en_US.UTF-8', 16:55:55 INFO - 'LANGUAGE': 'en_US:en', 16:55:55 INFO - 'LOGNAME': 'cltbld', 16:55:55 INFO - 'MAIL': '/var/mail/cltbld', 16:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:55 INFO - 'MOZ_NO_REMOTE': '1', 16:55:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:55 INFO - 'NO_EM_RESTART': '1', 16:55:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:55 INFO - 'PWD': '/builds/slave/test', 16:55:55 INFO - 'SHELL': '/bin/bash', 16:55:55 INFO - 'SHLVL': '1', 16:55:55 INFO - 'TERM': 'linux', 16:55:55 INFO - 'TMOUT': '86400', 16:55:55 INFO - 'USER': 'cltbld', 16:55:55 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:55 INFO - '_': '/tools/buildbot/bin/python'} 16:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:55 INFO - Downloading/unpacking jsonschema==2.5.1 16:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:55:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:56 INFO - Downloading functools32-3.2.3-2.tar.gz 16:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:55:56 INFO - warning: no files found matching '*.txt' 16:55:56 INFO - no previously-included directories found matching 'build' 16:55:56 INFO - no previously-included directories found matching 'dist' 16:55:56 INFO - no previously-included directories found matching '.git*' 16:55:56 INFO - Installing collected packages: jsonschema, functools32 16:55:56 INFO - Running setup.py install for functools32 16:55:56 INFO - warning: no files found matching '*.txt' 16:55:56 INFO - no previously-included directories found matching 'build' 16:55:56 INFO - no previously-included directories found matching 'dist' 16:55:56 INFO - no previously-included directories found matching '.git*' 16:55:56 INFO - Successfully installed jsonschema functools32 16:55:56 INFO - Cleaning up... 16:55:56 INFO - Return code: 0 16:55:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:56 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 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:55:56 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 16:55:56 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 16:55:56 INFO - Using env: {'DISPLAY': ':0', 16:55:56 INFO - 'HOME': '/home/cltbld', 16:55:56 INFO - 'LANG': 'en_US.UTF-8', 16:55:56 INFO - 'LANGUAGE': 'en_US:en', 16:55:56 INFO - 'LOGNAME': 'cltbld', 16:55:56 INFO - 'MAIL': '/var/mail/cltbld', 16:55:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:56 INFO - 'MOZ_NO_REMOTE': '1', 16:55:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:56 INFO - 'NO_EM_RESTART': '1', 16:55:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:56 INFO - 'PWD': '/builds/slave/test', 16:55:56 INFO - 'SHELL': '/bin/bash', 16:55:56 INFO - 'SHLVL': '1', 16:55:56 INFO - 'TERM': 'linux', 16:55:56 INFO - 'TMOUT': '86400', 16:55:56 INFO - 'USER': 'cltbld', 16:55:56 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:55:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:56 INFO - '_': '/tools/buildbot/bin/python'} 16:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:55:56 INFO - Cleaning up... 16:55:57 INFO - Return code: 0 16:55:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:55:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:55:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:55:57 INFO - Using env: {'DISPLAY': ':0', 16:55:57 INFO - 'HOME': '/home/cltbld', 16:55:57 INFO - 'LANG': 'en_US.UTF-8', 16:55:57 INFO - 'LANGUAGE': 'en_US:en', 16:55:57 INFO - 'LOGNAME': 'cltbld', 16:55:57 INFO - 'MAIL': '/var/mail/cltbld', 16:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:57 INFO - 'MOZ_NO_REMOTE': '1', 16:55:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:57 INFO - 'NO_EM_RESTART': '1', 16:55:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:57 INFO - 'PWD': '/builds/slave/test', 16:55:57 INFO - 'SHELL': '/bin/bash', 16:55:57 INFO - 'SHLVL': '1', 16:55:57 INFO - 'TERM': 'linux', 16:55:57 INFO - 'TMOUT': '86400', 16:55:57 INFO - 'USER': 'cltbld', 16:55:57 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:57 INFO - '_': '/tools/buildbot/bin/python'} 16:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:57 INFO - Downloading/unpacking blobuploader==1.2.4 16:55:57 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 16:55:57 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 16:55:57 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 16:55:57 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 16:55:58 INFO - Downloading blobuploader-1.2.4.tar.gz 16:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:55:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:55:58 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 16:55:58 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 16:55:58 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 16:55:58 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 16:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:55:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:55:58 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 16:55:58 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 16:55:58 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 16:55:58 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 16:55:58 INFO - Downloading docopt-0.6.1.tar.gz 16:55:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:55:58 INFO - Installing collected packages: blobuploader, requests, docopt 16:55:58 INFO - Running setup.py install for blobuploader 16:55:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:55:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:55:59 INFO - Running setup.py install for requests 16:55:59 INFO - Running setup.py install for docopt 16:55:59 INFO - Successfully installed blobuploader requests docopt 16:55:59 INFO - Cleaning up... 16:55:59 INFO - Return code: 0 16:55:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:55:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:59 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 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:55:59 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 16:55:59 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 16:55:59 INFO - Using env: {'DISPLAY': ':0', 16:55:59 INFO - 'HOME': '/home/cltbld', 16:55:59 INFO - 'LANG': 'en_US.UTF-8', 16:55:59 INFO - 'LANGUAGE': 'en_US:en', 16:55:59 INFO - 'LOGNAME': 'cltbld', 16:55:59 INFO - 'MAIL': '/var/mail/cltbld', 16:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:59 INFO - 'MOZ_NO_REMOTE': '1', 16:55:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:55:59 INFO - 'NO_EM_RESTART': '1', 16:55:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:59 INFO - 'PWD': '/builds/slave/test', 16:55:59 INFO - 'SHELL': '/bin/bash', 16:55:59 INFO - 'SHLVL': '1', 16:55:59 INFO - 'TERM': 'linux', 16:55:59 INFO - 'TMOUT': '86400', 16:55:59 INFO - 'USER': 'cltbld', 16:55:59 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:59 INFO - '_': '/tools/buildbot/bin/python'} 16:55:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:55:59 INFO - Running setup.py (path:/tmp/pip-DAlcMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:55:59 INFO - Running setup.py (path:/tmp/pip-LeO_zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:55:59 INFO - Running setup.py (path:/tmp/pip-xPZFEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:55:59 INFO - Running setup.py (path:/tmp/pip-rNeQsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:56:00 INFO - Running setup.py (path:/tmp/pip-WvABYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:56:00 INFO - Running setup.py (path:/tmp/pip-YvVE0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:56:00 INFO - Running setup.py (path:/tmp/pip-NEHx3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:56:00 INFO - Running setup.py (path:/tmp/pip-nm3NS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:56:00 INFO - Running setup.py (path:/tmp/pip-r5jwoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:56:00 INFO - Running setup.py (path:/tmp/pip-ECAUoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:56:00 INFO - Running setup.py (path:/tmp/pip-J8NncN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:56:00 INFO - Running setup.py (path:/tmp/pip-zgVOvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:56:00 INFO - Running setup.py (path:/tmp/pip-Y6FErt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:56:00 INFO - Running setup.py (path:/tmp/pip-NEEndv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:00 INFO - Running setup.py (path:/tmp/pip-_uXuez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:56:00 INFO - Running setup.py (path:/tmp/pip-LiVW_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:56:00 INFO - Running setup.py (path:/tmp/pip-KpbpQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:56:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:56:00 INFO - Running setup.py install for manifestparser 16:56:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Running setup.py install for mozcrash 16:56:01 INFO - Running setup.py install for mozdebug 16:56:01 INFO - Running setup.py install for mozdevice 16:56:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Running setup.py install for mozfile 16:56:01 INFO - Running setup.py install for mozhttpd 16:56:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Running setup.py install for mozinfo 16:56:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Running setup.py install for mozInstall 16:56:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Running setup.py install for mozleak 16:56:01 INFO - Running setup.py install for mozlog 16:56:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Running setup.py install for moznetwork 16:56:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:56:02 INFO - Running setup.py install for mozprocess 16:56:02 INFO - Running setup.py install for mozprofile 16:56:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:56:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:56:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:56:02 INFO - Running setup.py install for mozrunner 16:56:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:56:02 INFO - Running setup.py install for mozscreenshot 16:56:02 INFO - Running setup.py install for moztest 16:56:02 INFO - Running setup.py install for mozversion 16:56:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:56:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:56:02 INFO - Cleaning up... 16:56:02 INFO - Return code: 0 16:56:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:02 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 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:56:02 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 16:56:02 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 16:56:02 INFO - Using env: {'DISPLAY': ':0', 16:56:02 INFO - 'HOME': '/home/cltbld', 16:56:02 INFO - 'LANG': 'en_US.UTF-8', 16:56:02 INFO - 'LANGUAGE': 'en_US:en', 16:56:02 INFO - 'LOGNAME': 'cltbld', 16:56:02 INFO - 'MAIL': '/var/mail/cltbld', 16:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:02 INFO - 'MOZ_NO_REMOTE': '1', 16:56:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:02 INFO - 'NO_EM_RESTART': '1', 16:56:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:02 INFO - 'PWD': '/builds/slave/test', 16:56:02 INFO - 'SHELL': '/bin/bash', 16:56:02 INFO - 'SHLVL': '1', 16:56:02 INFO - 'TERM': 'linux', 16:56:02 INFO - 'TMOUT': '86400', 16:56:02 INFO - 'USER': 'cltbld', 16:56:02 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:02 INFO - '_': '/tools/buildbot/bin/python'} 16:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:56:02 INFO - Running setup.py (path:/tmp/pip-BSH0Iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:56:02 INFO - Running setup.py (path:/tmp/pip-F9ifAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:56:03 INFO - Running setup.py (path:/tmp/pip-unnJzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:56:03 INFO - Running setup.py (path:/tmp/pip-tnPvuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:56:03 INFO - Running setup.py (path:/tmp/pip-YhJU7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:56:03 INFO - Running setup.py (path:/tmp/pip-Qg_TFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:56:03 INFO - Running setup.py (path:/tmp/pip-ptAhH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:56:03 INFO - Running setup.py (path:/tmp/pip-lntWtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:56:03 INFO - Running setup.py (path:/tmp/pip-oTJ2rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:56:03 INFO - Running setup.py (path:/tmp/pip-QMTbYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:56:03 INFO - Running setup.py (path:/tmp/pip-5kOUio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:56:03 INFO - Running setup.py (path:/tmp/pip-P79l0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:56:03 INFO - Running setup.py (path:/tmp/pip-m_6T3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:56:03 INFO - Running setup.py (path:/tmp/pip-dCPx6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:03 INFO - Running setup.py (path:/tmp/pip-t1_AkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:56:03 INFO - Running setup.py (path:/tmp/pip-1JKKD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:56:04 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)) 16:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:56:04 INFO - Running setup.py (path:/tmp/pip-YoIyTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:56:04 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)) 16:56:04 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)) 16:56:04 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)) 16:56:04 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)) 16:56:04 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)) 16:56:04 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)) 16:56:04 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)) 16:56:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:05 INFO - Downloading blessings-1.6.tar.gz 16:56:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:56:05 INFO - Installing collected packages: blessings 16:56:05 INFO - Running setup.py install for blessings 16:56:05 INFO - Successfully installed blessings 16:56:05 INFO - Cleaning up... 16:56:05 INFO - Return code: 0 16:56:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:56:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:05 INFO - Reading from file tmpfile_stdout 16:56:05 INFO - Current package versions: 16:56:05 INFO - argparse == 1.2.1 16:56:05 INFO - blessings == 1.6 16:56:05 INFO - blobuploader == 1.2.4 16:56:05 INFO - docopt == 0.6.1 16:56:05 INFO - functools32 == 3.2.3-2 16:56:05 INFO - jsonschema == 2.5.1 16:56:05 INFO - manifestparser == 1.1 16:56:05 INFO - mozInstall == 1.13 16:56:05 INFO - mozcrash == 1.0 16:56:05 INFO - mozdebug == 0.1 16:56:05 INFO - mozdevice == 0.51 16:56:05 INFO - mozfile == 1.2 16:56:05 INFO - mozhttpd == 0.7 16:56:05 INFO - mozinfo == 0.10 16:56:05 INFO - mozleak == 0.1 16:56:05 INFO - mozlog == 3.5 16:56:05 INFO - moznetwork == 0.27 16:56:05 INFO - mozprocess == 0.25 16:56:05 INFO - mozprofile == 0.28 16:56:05 INFO - mozrunner == 6.13 16:56:05 INFO - mozscreenshot == 0.1 16:56:05 INFO - mozsystemmonitor == 0.3 16:56:05 INFO - moztest == 0.8 16:56:05 INFO - mozversion == 1.4 16:56:05 INFO - psutil == 3.1.1 16:56:05 INFO - requests == 1.2.3 16:56:05 INFO - wsgiref == 0.1.2 16:56:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:05 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 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:56:05 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 16:56:05 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 16:56:05 INFO - Using env: {'DISPLAY': ':0', 16:56:05 INFO - 'HOME': '/home/cltbld', 16:56:05 INFO - 'LANG': 'en_US.UTF-8', 16:56:05 INFO - 'LANGUAGE': 'en_US:en', 16:56:05 INFO - 'LOGNAME': 'cltbld', 16:56:05 INFO - 'MAIL': '/var/mail/cltbld', 16:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:05 INFO - 'MOZ_NO_REMOTE': '1', 16:56:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:05 INFO - 'NO_EM_RESTART': '1', 16:56:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:05 INFO - 'PWD': '/builds/slave/test', 16:56:05 INFO - 'SHELL': '/bin/bash', 16:56:05 INFO - 'SHLVL': '1', 16:56:05 INFO - 'TERM': 'linux', 16:56:05 INFO - 'TMOUT': '86400', 16:56:05 INFO - 'USER': 'cltbld', 16:56:05 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:05 INFO - '_': '/tools/buildbot/bin/python'} 16:56:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:05 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)) 16:56:05 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)) 16:56:05 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)) 16:56:05 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)) 16:56:05 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)) 16:56:05 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)) 16:56:05 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)) 16:56:05 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)) 16:56:05 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)) 16:56:05 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 16:56:07 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)) 16:56:07 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)) 16:56:07 INFO - Installing collected packages: simplejson 16:56:07 INFO - Running setup.py install for simplejson 16:56:07 INFO - building 'simplejson._speedups' extension 16:56:07 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 16:56:08 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 16:56:08 INFO - Successfully installed simplejson 16:56:08 INFO - Cleaning up... 16:56:08 INFO - Return code: 0 16:56:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:08 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 0x25261f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26eeb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28939c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, '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': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', '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 16:56:08 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 16:56:08 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 16:56:08 INFO - Using env: {'DISPLAY': ':0', 16:56:08 INFO - 'HOME': '/home/cltbld', 16:56:08 INFO - 'LANG': 'en_US.UTF-8', 16:56:08 INFO - 'LANGUAGE': 'en_US:en', 16:56:08 INFO - 'LOGNAME': 'cltbld', 16:56:08 INFO - 'MAIL': '/var/mail/cltbld', 16:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:08 INFO - 'MOZ_NO_REMOTE': '1', 16:56:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:08 INFO - 'NO_EM_RESTART': '1', 16:56:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:08 INFO - 'PWD': '/builds/slave/test', 16:56:08 INFO - 'SHELL': '/bin/bash', 16:56:08 INFO - 'SHLVL': '1', 16:56:08 INFO - 'TERM': 'linux', 16:56:08 INFO - 'TMOUT': '86400', 16:56:08 INFO - 'USER': 'cltbld', 16:56:08 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:08 INFO - '_': '/tools/buildbot/bin/python'} 16:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:56:08 INFO - Cleaning up... 16:56:08 INFO - Return code: 0 16:56:08 INFO - Running post-action listener: _resource_record_post_action 16:56:08 INFO - Running post-action listener: _start_resource_monitoring 16:56:08 INFO - Starting resource monitoring. 16:56:08 INFO - [mozharness: 2017-09-30 23:56:08.456521Z] Finished create-virtualenv step (success) 16:56:08 INFO - [mozharness: 2017-09-30 23:56:08.457648Z] Running install step. 16:56:08 INFO - Running pre-action listener: _resource_record_pre_action 16:56:08 INFO - Running main action method: install 16:56:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:08 INFO - Reading from file tmpfile_stdout 16:56:08 INFO - Detecting whether we're running mozinstall >=1.0... 16:56:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:56:08 INFO - Reading from file tmpfile_stdout 16:56:08 INFO - Output received: 16:56:08 INFO - Usage: mozinstall [options] installer 16:56:08 INFO - Options: 16:56:08 INFO - -h, --help show this help message and exit 16:56:08 INFO - -d DEST, --destination=DEST 16:56:08 INFO - Directory to install application into. [default: 16:56:08 INFO - "/builds/slave/test"] 16:56:08 INFO - --app=APP Application being installed. [default: firefox] 16:56:08 INFO - mkdir: /builds/slave/test/build/application 16:56:08 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'] 16:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 16:56:26 INFO - Reading from file tmpfile_stdout 16:56:26 INFO - Output received: 16:56:26 INFO - /builds/slave/test/build/application/firefox/firefox 16:56:26 INFO - Running post-action listener: _resource_record_post_action 16:56:26 INFO - [mozharness: 2017-09-30 23:56:26.611940Z] Finished install step (success) 16:56:26 INFO - [mozharness: 2017-09-30 23:56:26.612281Z] Running setup-mitmproxy step. 16:56:26 INFO - Running pre-action listener: _resource_record_pre_action 16:56:26 INFO - Running main action method: setup_mitmproxy 16:56:26 INFO - Skipping: mitmproxy is not required 16:56:26 INFO - Running post-action listener: _resource_record_post_action 16:56:26 INFO - [mozharness: 2017-09-30 23:56:26.613942Z] Finished setup-mitmproxy step (success) 16:56:26 INFO - [mozharness: 2017-09-30 23:56:26.614267Z] Running run-tests step. 16:56:26 INFO - Running pre-action listener: _resource_record_pre_action 16:56:26 INFO - Running pre-action listener: _set_gcov_prefix 16:56:26 INFO - Running main action method: run_tests 16:56:26 WARNING - Try message not found. 16:56:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:56:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:56:26 INFO - Python 2.7.3 16:56:26 INFO - Return code: 0 16:56:26 INFO - grabbing minidump binary from tooltool 16:56:26 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')]} 16:56:26 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 0x2890d60>, '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 0x2891a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2891ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:56:26 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 16:56:26 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 16:56:26 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 16:56:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:56:26 INFO - Return code: 0 16:56:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:56:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:56:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:56:26 INFO - ENV: RUST_BACKTRACE is now full 16:56:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:56:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:56:26 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/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--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 16:56:26 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/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-083 --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 16:56:26 INFO - Using env: {'DISPLAY': ':0', 16:56:26 INFO - 'HOME': '/home/cltbld', 16:56:26 INFO - 'LANG': 'en_US.UTF-8', 16:56:26 INFO - 'LANGUAGE': 'en_US:en', 16:56:26 INFO - 'LOGNAME': 'cltbld', 16:56:26 INFO - 'MAIL': '/var/mail/cltbld', 16:56:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:56:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:26 INFO - 'MOZ_NO_REMOTE': '1', 16:56:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:26 INFO - 'NO_EM_RESTART': '1', 16:56:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:26 INFO - 'PWD': '/builds/slave/test', 16:56:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:56:26 INFO - 'RUST_BACKTRACE': 'full', 16:56:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:56:26 INFO - 'SHELL': '/bin/bash', 16:56:26 INFO - 'SHLVL': '1', 16:56:26 INFO - 'TERM': 'linux', 16:56:26 INFO - 'TMOUT': '86400', 16:56:26 INFO - 'USER': 'cltbld', 16:56:26 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:26 INFO - '_': '/tools/buildbot/bin/python'} 16:56:26 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/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--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 16:56:27 INFO - mozversion application_buildid: 20170930230214 16:56:27 INFO - mozversion application_changeset: 179dccc17c7f261347436b4b1c33029c8272960b 16:56:27 INFO - mozversion application_display_name: FirefoxNightly 16:56:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:56:27 INFO - mozversion application_name: Firefox 16:56:27 INFO - mozversion application_remotingname: firefox 16:56:27 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:56:27 INFO - mozversion application_vendor: Mozilla 16:56:27 INFO - mozversion application_version: 58.0a1 16:56:27 INFO - mozversion platform_buildid: 20170930230214 16:56:27 INFO - mozversion platform_changeset: 179dccc17c7f261347436b4b1c33029c8272960b 16:56:27 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:56:27 INFO - mozversion platform_version: 58.0a1 16:56:27 INFO - using testdate: 1506815787 16:56:27 INFO - actual date: 1506815787 16:56:27 INFO - starting webserver on 'localhost:52152' 16:56:27 INFO - SUITE-START | Running 2 tests 16:56:27 INFO - TEST-START | dromaeo_css 16:56:27 INFO - Initialising browser for dromaeo_css test... 16:56:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52152/getInfo.html -profile /tmp/tmpJ_ibGO/profile 16:56:27 INFO - TEST-INFO | started process 3622 (/builds/slave/test/build/application/firefox/firefox http://localhost:52152/getInfo.html) 16:56:35 INFO - TEST-INFO | 3622: exit 0 16:56:35 INFO - Browser initialized. 16:56:35 INFO - Running cycle 1/1 for dromaeo_css test... 16:56:35 INFO - Using env: {'DISPLAY': ':0', 16:56:35 INFO - 'HOME': '/home/cltbld', 16:56:35 INFO - 'JSGC_DISABLE_POISONING': '1', 16:56:35 INFO - 'LANG': 'en_US.UTF-8', 16:56:35 INFO - 'LANGUAGE': 'en_US:en', 16:56:35 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:56:35 INFO - 'LOGNAME': 'cltbld', 16:56:35 INFO - 'MAIL': '/var/mail/cltbld', 16:56:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:56:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:56:35 INFO - 'MOZ_CRASHREPORTER': '1', 16:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:35 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:56:35 INFO - 'MOZ_NO_REMOTE': '1', 16:56:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:56:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:56:35 INFO - 'NO_EM_RESTART': '1', 16:56:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:35 INFO - 'PWD': '/builds/slave/test', 16:56:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:56:35 INFO - 'RUST_BACKTRACE': 'full', 16:56:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:56:35 INFO - 'SHELL': '/bin/bash', 16:56:35 INFO - 'SHLVL': '1', 16:56:35 INFO - 'TERM': 'linux', 16:56:35 INFO - 'TMOUT': '86400', 16:56:35 INFO - 'USER': 'cltbld', 16:56:35 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 16:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:35 INFO - '_': '/tools/buildbot/bin/python'} 16:56:36 INFO - TEST-INFO | started process 3860 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJ_ibGO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:56:36 INFO - PID 3860 | 16:56:36 INFO - PID 3860 | (/builds/slave/test/build/application/firefox/firefox:3909): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:56:36 INFO - PID 3860 | 16:56:47 INFO - PID 3860 | RSS: Main: 147734528 16:56:47 INFO - PID 3860 | 16:59:44 INFO - PID 3860 | Cycle 1(1): loaded http://localhost:52152/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52152/tests/dromaeo/cssquery-ext.html) 16:59:45 INFO - PID 3860 | RSS: Main: 149581824 16:59:45 INFO - PID 3860 | 17:02:47 INFO - PID 3860 | Cycle 1(1): loaded http://localhost:52152/tests/dromaeo/cssquery-ext.html (next: http://localhost:52152/tests/dromaeo/cssquery-jquery.html) 17:02:48 INFO - PID 3860 | RSS: Main: 150159360 17:02:48 INFO - PID 3860 | 17:05:50 INFO - PID 3860 | Cycle 1(1): loaded http://localhost:52152/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52152/tests/dromaeo/cssquery-mootools.html) 17:05:50 INFO - PID 3860 | RSS: Main: 150843392 17:05:50 INFO - PID 3860 | 17:08:52 INFO - PID 3860 | Cycle 1(1): loaded http://localhost:52152/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52152/tests/dromaeo/cssquery-prototype.html) 17:08:52 INFO - PID 3860 | RSS: Main: 150970368 17:08:52 INFO - PID 3860 | 17:11:56 INFO - PID 3860 | Cycle 1(1): loaded http://localhost:52152/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52152/tests/dromaeo/cssquery-yui.html) 17:11:56 INFO - PID 3860 | RSS: Main: 153776128 17:11:56 INFO - PID 3860 | 17:14:58 INFO - PID 3860 | Cycle 1(1): loaded http://localhost:52152/tests/dromaeo/cssquery-yui.html (next: http://localhost:52152/tests/dromaeo/cssquery-dojo.html) 17:14:58 INFO - PID 3860 | RSS: Main: 154497024 17:14:58 INFO - PID 3860 | 17:14:58 INFO - PID 3860 | __start_tp_report 17:14:58 INFO - PID 3860 | _x_x_mozilla_page_load 17:14:58 INFO - PID 3860 | _x_x_mozilla_page_load_details 17:14:58 INFO - PID 3860 | |i|pagename|runs| 17:14:58 INFO - PID 3860 | |0;dojo.html;3172.4131035758387;3173.968260317397;3175.317306779042;3188.4420226460365;3200.7759456838016;6088.330283668793;6136.202293701818;6139.447449729523;6263.655498947562;6275.623462592242;6180.567360284782;6210.596311239767;6213.0991006304175;6246;6256.998880179172;5713.71431428429;5730.79942202023;5732.140178973145;5742.368339482654;5748.798792042279;5786.132080187964;5806.65160090396;5826.6212696174825;5828.941710582903;5830.5044071254;5762.337331206902;5781.583512039556;5838.795642152526;5844.678542680151;5868.119782032687;5306.112714350457;5556.638818476786;5669.914951275734;5816.912746308809;5852.063669812831;5692.743826527775;5722.685252311121;5759.424057594207;5782.277215348082;5787.13193021046;5980.222571065734;6161.661108639023;6209.410106039919;6257.217847769029;6263.217097862767;6572.277049524549;6575.638339891352;6604.471642268607;6609.93390066098;6672.866542669168;6799.218089919624;6803.591784492946;6811.795646130624;6816.045753594501;6818.045473633695;6204.100405441186;6222.253329600477;6281.937180628181;6289.119523266746;6294.464970477561;6686.1642294713165;6804.795856124324;6839.931600684029;6952.339527744856;6957.1651401832105;6432.742690292336;6506.121673574007;6515.120458738104;6517.869642607121;6519.152510173647;6662.866742665216;6669.199696036507;6683.933160668331;6688.531802773759;6691.1636045494315;6545.9018114728315;6552.984287435455;6652.268250492442;6660.666966651648;6671.733130674816;6597.109390232353;6608.768693095719;6637.900431493532;6687.6321802301345;6722.932770672329;5153.123968925292;5217.087009773274;5220.295260139908;5223.373195216598;5255.6321057526375;4538.659600529907;4561.29299958507;4622;4640.582347588733;4640.79116439761;5717.599768016282;5779.3642699303045;5794.652320860761;5803.419658034247;5826.2134611827705;6643.2360278568585;6647.634380109043;6705;6709.731610735614;6748.156480439945;5558.722063896789;5662.745176467069;5725.198472213893;5809.738561764731;5826.533877289891;5760.971195143998;5778.27771528559;5781.884362312814;5802.33273173582;5803.100519419497;6321.494280457552;6437.2918978912285;6515;6515.2181738190775;6524.347565243438;6576.671166441754;6684.933150668431;6702.430293425114;6767.560108592926;6811.761588344384;6416;6424.2933277338525;6425.036244563166;6440.29156792743;6446.419822216023;6518.413342799171;6596.472282217507;6600.207975043026;6608.240052394036;6614.966925165343;6662.800115996528;6664.400203981665;6678.2320033194865;6759.526833121634;6772.288909664414;6354.364563543609;6414.807555773335;6441.677916104082;6494.6103233806125;6515.250746164063;6420.775272865428;6428.132202152649;6446.677666116581;6449.4517965971545;6456.580322279036;6332;6366.045093236052;6376.3623637635865;6409.070684750592;6501.08984742127;6529.575577587441;6587.9670601646685;6593.439557637655;6612.90080648781;6617.139771829631;6308.27454842699;6315.621062736251;6391.265004524539;6395.520335974914;6403.6798160090875 17:14:58 INFO - PID 3860 | |1;ext.html;881.6870011146042;902.6705252582809;903.5211337990867;907.2605826251604;907.3376435202302;38391.61608383915;41795.16409671805;41856.79071604645;42039.36940945888;42198;37941.24117517648;37973.43039854404;38311.23377532448;38546.22907541847;38620.22759544807;7361.079865016886;7519.436042296822;8049.436539442241;8250.298724608416;8261.091279959199;8200.958995205016;8312.625931833052;8312.625931833052;8327;8332.375071869603;8285.337173026173;8287.295579875718;8344.20730030649;8348.457350272243;8351.540665263408;8277.54473503957;8277.751667449986;8304.70933517324;8310.459820111673;8325.583720813966;8185.918140818635;8215.055268644122;8217.178282171735;8247.917520824834;8256;37039.629603703885;37712.622873771186;37852.43221351682;38029.239415211814;38089.42865857014;3612.8193590320643;3652.689521390685;3652.9086772830624;3653.3606618841595;3666.834992425347;30772;30937;31123.221919452193;31299.06102816919;31330.373392532023;5057.443681195103;5253.579713622901;5259.76331065103;5262;5287.391949925769;183403.33193336191;184193;186146;186609;187023;42405.787971059945;43103;48608.270875936185;48835.26747098725;49095;45814.31278530825;50394.49605503898;51289.23066154011;51418.74290628523;51691.7415412928;16128.274227659784;16364.181790910407;16389.754153687703;16416.343346266014;16435.09606971628;13326.933365333305;13444.193348399127;13464.124831885896;13486.325683715972;13501.59495215145;14201.147931124166;14328.85671143296;14353.354099065567;14354;14578;5617.354004289559;5643.943560564342;5644.153376993402;5676.460736230051;5742.885142297131;1012.473513772834;1016.2632091733434;1022.1056575496441;1030.4487099401783;1049.0663309654415;22839.885800571225;23058.423539411648;23240.76759232386;23255.651165232535;23350.949207285714;1095.775366049958;1107.4340623193305;1112.3214838948236;1121.377635412354;1131.0386171753944;12466.75066498665;12475.75048499043;12567.245965242115;12586;12640.747185056247;227077;229118;229511;229582;229921;53724.46275537195;53945;53976;53997.73001134969;54203.72898135484;1391.6729568551382;1473.2191938272733;1477.807884974968;1481.7629179331254;1495.2598463760291;22554.774452255268;22713;23195.88402057979;23300.53398932046;23521.52956940886;37735.62264377431;37990.620093799815;37996.62003379931;38070.809645951595;38105;194203;195023;195561;196723;196937;30243.69756302409;33833;34662.82668586641;35018.474722878665;35081;42918.57081429146;43304.56695433131;43338.916527085814;43452.565474344854;43808.56191438045;31993.840030800628;32146.67853321437;32439.67560324461;32586.34827303488;32870.835645821615;31307.217319567193;31415.21461963469;31544.36911261808;31845.20386990344;32193.35613287768;35249.82375088108;35802.64197357993;36027.09932251715;36087.45868812023;36206.45690314595;31263.218419539695;31367.372652547278;31498.212544686565;31881.202969925936;31892;22922.885385573634;23280.835958201682;23369;23809;23829.52340953206 17:14:58 INFO - PID 3860 | |2;jquery.html;558;574.3108270075912;579.2325169150876;590.6249531547468;609.9512039036877;22575.54848903021;23437.648435273484;23454;23539.646905296435;23543.646845297335;23537.529249415;24164.879175604143;24841;25129;25163.370915727115;6516.739330426777;6713.731450741964;6715.496337774662;6717.1939367276;6718.596884186964;6382.680865956683;6564.277929427759;6665.40011398974;6666.866662666744;6668.899966500507;6639.900401493981;6676.532642715012;6678.332166783332;6679.499037572202;6681.198256209262;6648.135742353487;6652.667366631673;6655.267920528738;6663.500237482208;6668.033135195394;6134.202553667995;6160.630362178284;6164.198654174974;6179.87640247197;6193.04007878775;27221;28170.718292817015;28523.857380713172;28822.711772882212;29080.854595727098;2035.7557093148769;2065.1120018392085;2065.5042789730355;2078.3349328214827;2080.6670932650854;19209.423717288504;19799.703004454946;19875.403737887886;19947.900260498605;20003.1998720051;2451.9754802451926;2453.435709786741;2514.069794176148;2528.7344828792898;2533.961990570143;117523.41238293839;122163.99999999911;133849;140317;148940;14603.089401801099;14932.744167912093;15057;15109.671809172543;15465.845341546665;23292.76707232906;23317.766822331898;23389.76610233876;23420.297391078297;23559;10044.849327260097;10100.646477373404;10247.231457640557;10343.689689309333;10428.582856685647;9366.672166474276;9426.575804088832;9493.382930109521;9517.43029382063;9560.66537671192;8778.385513014027;8910.663400489979;9080.455172689659;9292.16370526656;9322.95338523303;2666.5733482642686;2730.494858451193;2745.2999485131563;2845.516262235425;2876.137158852336;977.1010670183448;986.5313975861409;990.2573070197352;991.1278075293695;1005.6128390569684;18946;19649.84140463037;19821.900890495453;19931;20253.696194557095;1028.3315844701003;1028.93311934724;1036.461040259076;1058.343826827372;1065.9360438373722;10813.459327033617;10823.242373033969;10901.945490272497;10948.507317170746;11123.332600044023;456701;460577;474388;481817;489109;103461;106723.4663826676;107301;108910;109543.90456095336;1057.7144171073767;1097.928634638746;1147.3345459633392;1169.9473523691454;1214.1864950483198;20307.492312692302;20371.79628203699;20411;20493.692594610795;20596.691049634566;57244.71377643085;57883;58060.709696451246;58474.7076264616;59548.80902382015;72514.27485725076;72685;72867.63566182135;73036;74041.62979185286;14357.56927292183;14613.269336533343;14685.33915973805;14763.261836908327;14791.408343666133;22175.223867164572;22378.888105559367;22484.32547023592;22717.659235111816;22863.542729145658;18137.455876323205;18206.180721867284;18257.45227643173;18373.908130459262;18429.170687319373;16485.423010194587;16580.83419165841;16695.833041669426;16853.376524047388;17276.568085797957;18374.173162208;18415.355462558746;18442.446726598224;18467.353642622445;18674.71987920155;14351.210683412513;14494.130352178903;14530.564083077525;14568.68881800643;14646.926765366105;18015.549611259823;18146.18342174632;18229.908850455664;18335.35826246075;18430.447086587425 17:14:58 INFO - PID 3860 | |3;mootools.html;1345.9798103028454;1360.5918224532643;1375.8417781955072;1379.5033787836383;1402.8316602007758;14110.717785644281;14685.55943321702;14704.705905881889;14706;14745.77881331781;14471;14617.488387906425;14623.561293161221;14650.413983440676;14668.339924703387;2715.511207982567;2810.690824009357;2820.591014302925;2823.167165686117;2830.0943698016645;2692.4615076984683;2721.6733991920996;2726.7136950620097;2734.8906043758225;2744.341358074058;2624.0422245880213;2737.288305040684;2860.9713902861017;2864.5416733322672;2866.8996585119526;2826.208661574752;2832.915012549606;2849;2851.8574071296352;2860.242035860488;2686.476137153256;2721.047633328339;2738.6713594368603;2764.9447011059665;2764.944701105987;14448.205348705817;14530.346134423977;14540.927295363455;14545;14569.927150364287;7725.768226953201;7998.840023199504;8057.033156021256;8070.515769053875;8095.838083238361;5234.319538460014;5456.536194423439;5494.80768173112;5582.692951887645;5612.7754889804355;1935.3226370770117;1942.320187934226;1953.8534609904173;1980.6533856575043;1985.463924740312;24442.633360499607;24490.63264051041;24568.631470527955;24599.63100553493;24624.50750985006;6698.229703584053;6896.103506544117;6897;7087.787366379017;7102.538335008208;16705.916470417815;16722.665546688997;16739.832601674072;16743;16802.915985419993;7720.845583088307;7934.960325198337;7946.483478573874;7993.080795708568;7994.120646728855;7193.100862392201;7265.09186351706;7342.15565209997;7459.142198647225;7483.663235154322;5131.076406246837;5306.355029940653;5309.097453432925;5317.202419637086;5326.707031113248;1454.345544504977;1466.5453709350136;1470.272215253435;1553.8213105492787;1588.1265304082708;918.8989211186764;931.7810314576069;940.3793496292551;946.0539460539461;948.0472125513814;8757.4745515268;8764.824703505894;8819.867701984474;8830.514321712368;8867.778305542284;968.5447839515557;988.2341185581232;1000.3047881722279;1002.0680766886866;1016.2378216337746;5145.9485405146215;5201.375834899836;5429.185622156708;5633.2395126657375;5720.11338242573;19866.50333741639;19968.600627987358;20844.270450534157;20863.582728345653;21201.303895688307;20562;20726.170953161705;20942.162313507593;20971;20975.265865694626;10478.842817357745;10714.303570268063;10811.729706757395;10820;10920.890791092146;8390.496570205807;8489.660413583388;8493.490390576586;8496.957515212385;8502.617382217692;17384;17696.734548982033;17889.37387191442;18039.909800450914;18140.90929545344;18270.177841996876;18473;18665.53336166607;18674.159662815477;18698.813011869708;10636.09593184557;10893.727656808644;10999.780004400027;11001.119910407308;11012.449377531251;13405.262710550624;13504.189748615114;13562.321883905568;13564.93217533906;13570.79643805324;11299.943500282447;11308.321500709984;11470.4264786762;11529;11569.479373428048;10982.121430285399;11048.668539943814;11093.722656933318;11375.54497820078;11381.317120972768;10114.645987390406;10168.694939151837;10178.134858537107;10194.133498652698;10194.184465242912;7497.400207983457;7558.127931344353;7581.445803610357;7596.544207347577;7620.085589729268;7241.275872412716;7375.262473752582;7382.446316526175;7394.630268486661;7399.852002960019 17:14:58 INFO - PID 3860 | |4;prototype.html;498.7630082449591;507.94412614612384;523.5052875033091;526.8261473713675;539.7382269599244;6296.748130074797;6334.714937827797;6368.29948705642;6368.586041907272;6377.713002914863;6287.685615719207;6309.873802523958;6312.116303717472;6331.810045698625;6337;5211.218317252405;5212.973935130319;5229.7385130743505;5232.790688372461;5241.344831896013;5087.847364579068;5174.197999310094;5176.378834539842;5206.37523497179;5242.711650859202;4801.7118972861745;4937;4937.259411088329;4945.703257804544;4945.862451636126;4858.562729354339;4951.183054795991;4975.104481193383;4981.078500477405;5019.372578427697;4335.609795118455;4438.690586277056;4456.509783923766;4471.575200355961;4482.708623939434;5999.390265234637;6040.637561746265;6065.727042283063;6089.468108497105;6142.78500252489;5531.529819965308;5549.113301477517;5553.888922221533;5619.719014049281;5626.437356264381;6401.871962560769;6494.642794646293;6535.346465353474;6548.1814773153355;6559.311272316386;4583.2666773316105;4851.902961940741;4860.902781944341;4864.343313652647;4884.291777692215;6679.666016699146;6688.060462465879;6755.695993680296;6855.451563874928;6878.896816547755;4965.386249468865;4974.900501990012;4992.900141997213;4993.975030124826;5004.024215278058;5037.79848806051;5041.344625198701;5062.569681577033;5079.187330027178;5079.568236699848;5622.95975244581;5649.288341596057;5672.262605861211;5684.687342196219;5685.289338832646;6552.115464412243;6589.374009469093;6607.810594093109;6608.306127856602;6634.469242460592;1727.5508367824457;1747.6281119321272;1749.4751574527845;1751.702210624197;1767.4785938148277;1153.210051114989;1198.2930071258;1205.4033253539553;1215.53202017224;1224.9816252756216;5555.166724991283;5584.050711379075;5662.348829884616;5696.658200507983;5701.2018317435595;6720.798376048726;6723;6730.360615741497;6734.632683658171;6746.257911629717;5607.186957891083;5667.206591077253;5782.624129431658;5783.074708046693;5783.4794868461195;3512.2448673535314;3537.557805274341;3557.6620221078956;3576.499290099388;3585.15748799032;6333.493320534346;6346.365363463617;6375.776847810304;6409.615423074631;6483.7082331295205;6763.425108865801;6804.3863812355;6812.795616131524;6872.450203983669;6916.757913473004;6222.253329600387;6329.525285603599;6340.30256671775;6345;6399.424051835357;6583.506237032146;6616.823924516355;6643.482431946323;6655.667216639245;6658.167729033871;6460.160179176677;6559.311272316529;6591.967040164768;6656.866862662623;6674.564968531789;6473.385028422293;6477.4494167996245;6480.157579514633;6531.9020214695865;6593.604383736991;4931.3589233399425;4986.725730084735;5018.774155162953;5030.119729047329;5047.192449208109;6444.581102228341;6646.410938093569;6647.368499992493;6672.6997285121315;6676.399124078856;6331.841703957253;6555.2133743951035;6567;6631.6684165792485;6649.135612370361;6455.383816080041;6488.188976377953;6518.315576864361;6538.5615164663695;6538.934610654022;6274.145263904821;6429.22849258092;6453.1610890583925;6460.963347226796;6496.317886621837;6545.312742162005;6551;6567.307403964352;6599.670016499252;6645.800625981228;6358.936410635834;6387.361263873576;6413.807585772434;6416.679166041773;6430.7749228776765 17:14:58 INFO - PID 3860 | |5;yui.html;895.0646574329826;907.904670009649;921.6405601815289;924.8936372317186;929.5305870535378;19274;20095.497612559677;20147.798522014775;20158.89920550399;20166.294179703713;16144;16280.104594247341;16311.184440777974;16345.101019443926;16352.672946541063;896.9237614802734;958.1041725986225;958.5351104714208;958.851378036402;964.0744884910495;962.3023308101606;974.410481658597;976.5410257179115;976.6435251133321;977.6920270114927;986.2356673577963;993.2997236947934;996.5017491254373;997.4314640654829;997.6957028106425;986.8421052631617;999.990000099997;1005.6681295172611;1006.2151521812998;1009.9747506312401;724.3734169943014;738.5421038955828;740.8073900785835;750.5759246025978;751.4702134994816;20621.793782062137;20636.5872682547;20829.68755468669;20846.06192721331;20864.26975055866;20837.37487875366;20881.26915557948;21283.893580532;21430.785692143036;21433.892830535904;16800.57998550046;16878.915605421895;16974.745378819327;17028.914855425766;17142.05718685472;3755.117547376364;3787.507624008889;3795.1081495848453;3796.3356412627954;3796.9810150949343;94623;106073;140539;142163;147171.26414367967;17214.65570688579;17223.138843057797;17327;17588.38440654571;17670.381536646157;31461.37077258442;31501.58242879076;31558.526622100686;31601.683983160336;31625;9397.201237894855;9464.432134071978;9542.904570954202;9543.952280238555;9575.186109180797;4537.591616754508;4620.145273124483;4632.791524381411;4640.512746161672;4654.464736555339;5391.218273350342;5434.538064264502;5482.150266708666;5494.780208791603;5501.257330260443;1086.9673909782719;1095.9232853700323;1114.2701530497536;1115.827046807746;1116.0569318925495;675.5676367125044;698.3819319901822;707.8796604577132;717.1752484642702;722.7867779005206;12078;12469.937650311691;12494.562690305978;12553.560625378068;12603.117781755373;684.1345697692423;685.0819901332139;688.8036909480774;691.2948792231896;703.9155301363869;5779.306483222041;5781.219535362757;5816.151886642301;5911.674857882857;5915.112733089984;120533;121372;121373.39313303554;124808;127041;103599.4820025895;104790.47604761928;107027;108951;112236;94790.52604736932;97408.51295743475;97771;98626.99999999856;99279.50360248152;12389.75220495567;12587.181833180814;12659.177153484994;12730.981521478445;12737.426815793126;41829.372559412244;42399.788001059795;42664.8963252103;42881;43134;54716.72641636767;55015.44984550103;55084.44915550954;55675;55676.16485752636;16564.420245291356;16908.32366705318;16989.575260618585;17028.659426811642;17272.309107636058;18070;18261;18358.265669373075;18364.816351836846;18622.720659190392;16963.745543816607;17283.7407438886;17332.046737429562;17349.479515614552;17417.912910435873;16584.336626534805;16657.167141642625;16689.248983796002;16906.915465422593;16931.15344232808;18139;18268.725969110208;18480;18567.25730970746;18604.627907441503;14021.439142434188;14248.073875198079;14350.712985740436;14399.352029158923;14527.636809079857;13986.300684965507;14006.78989815174;14031.438742450597;14057.156570605797;14070.437182512995 17:14:58 INFO - PID 3860 | __end_tp_report 17:14:58 INFO - PID 3860 | __start_cc_report 17:14:58 INFO - PID 3860 | _x_x_mozilla_cycle_collect,272 17:14:58 INFO - PID 3860 | __end_cc_report 17:14:58 INFO - PID 3860 | __startTimestamp1506816898640__endTimestamp 17:14:58 INFO - PID 3860 | 17:14:58 INFO - PID 3860 | ------- Summary: start ------- 17:14:58 INFO - PID 3860 | Number of tests: 6 17:14:58 INFO - PID 3860 | 17:14:58 INFO - PID 3860 | [#0] dojo.html Cycles:165 Average:6133.49 Median:6391.27 stddev:724.96 (11.3%) stddev-sans-first:688.96 17:14:58 INFO - PID 3860 | Values: 3172.4 3174.0 3175.3 3188.4 3200.8 6088.3 6136.2 6139.4 6263.7 6275.6 6180.6 6210.6 6213.1 6246.0 6257.0 5713.7 5730.8 5732.1 5742.4 5748.8 5786.1 5806.7 5826.6 5828.9 5830.5 5762.3 5781.6 5838.8 5844.7 5868.1 5306.1 5556.6 5669.9 5816.9 5852.1 5692.7 5722.7 5759.4 5782.3 5787.1 5980.2 6161.7 6209.4 6257.2 6263.2 6572.3 6575.6 6604.5 6609.9 6672.9 6799.2 6803.6 6811.8 6816.0 6818.0 6204.1 6222.3 6281.9 6289.1 6294.5 6686.2 6804.8 6839.9 6952.3 6957.2 6432.7 6506.1 6515.1 6517.9 6519.2 6662.9 6669.2 6683.9 6688.5 6691.2 6545.9 6553.0 6652.3 6660.7 6671.7 6597.1 6608.8 6637.9 6687.6 6722.9 5153.1 5217.1 5220.3 5223.4 5255.6 4538.7 4561.3 4622.0 4640.6 4640.8 5717.6 5779.4 5794.7 5803.4 5826.2 6643.2 6647.6 6705.0 6709.7 6748.2 5558.7 5662.7 5725.2 5809.7 5826.5 5761.0 5778.3 5781.9 5802.3 5803.1 6321.5 6437.3 6515.0 6515.2 6524.3 6576.7 6684.9 6702.4 6767.6 6811.8 6416.0 6424.3 6425.0 6440.3 6446.4 6518.4 6596.5 6600.2 6608.2 6615.0 6662.8 6664.4 6678.2 6759.5 6772.3 6354.4 6414.8 6441.7 6494.6 6515.3 6420.8 6428.1 6446.7 6449.5 6456.6 6332.0 6366.0 6376.4 6409.1 6501.1 6529.6 6588.0 6593.4 6612.9 6617.1 6308.3 6315.6 6391.3 6395.5 6403.7 17:14:58 INFO - PID 3860 | 17:14:58 INFO - PID 3860 | [#1] ext.html Cycles:180 Average:37281.60 Median:35526.23 stddev:52831.58 (148.7%) stddev-sans-first:52909.09 17:14:58 INFO - PID 3860 | Values: 881.7 902.7 903.5 907.3 907.3 38391.6 41795.2 41856.8 42039.4 42198.0 37941.2 37973.4 38311.2 38546.2 38620.2 7361.1 7519.4 8049.4 8250.3 8261.1 8201.0 8312.6 8312.6 8327.0 8332.4 8285.3 8287.3 8344.2 8348.5 8351.5 8277.5 8277.8 8304.7 8310.5 8325.6 8185.9 8215.1 8217.2 8247.9 8256.0 37039.6 37712.6 37852.4 38029.2 38089.4 3612.8 3652.7 3652.9 3653.4 3666.8 30772.0 30937.0 31123.2 31299.1 31330.4 5057.4 5253.6 5259.8 5262.0 5287.4 183403.3 184193.0 186146.0 186609.0 187023.0 42405.8 43103.0 48608.3 48835.3 49095.0 45814.3 50394.5 51289.2 51418.7 51691.7 16128.3 16364.2 16389.8 16416.3 16435.1 13326.9 13444.2 13464.1 13486.3 13501.6 14201.1 14328.9 14353.4 14354.0 14578.0 5617.4 5643.9 5644.2 5676.5 5742.9 1012.5 1016.3 1022.1 1030.4 1049.1 22839.9 23058.4 23240.8 23255.7 23350.9 1095.8 1107.4 1112.3 1121.4 1131.0 12466.8 12475.8 12567.2 12586.0 12640.7 227077.0 229118.0 229511.0 229582.0 229921.0 53724.5 53945.0 53976.0 53997.7 54203.7 1391.7 1473.2 1477.8 1481.8 1495.3 22554.8 22713.0 23195.9 23300.5 23521.5 37735.6 37990.6 37996.6 38070.8 38105.0 194203.0 195023.0 195561.0 196723.0 196937.0 30243.7 33833.0 34662.8 35018.5 35081.0 42918.6 43304.6 43338.9 43452.6 43808.6 31993.8 32146.7 32439.7 32586.3 32870.8 31307.2 31415.2 31544.4 31845.2 32193.4 35249.8 35802.6 36027.1 36087.5 36206.5 31263.2 31367.4 31498.2 31881.2 31892.0 22922.9 23280.8 23369.0 23809.0 23829.5 17:14:58 INFO - PID 3860 | 17:14:58 INFO - PID 3860 | [#2] jquery.html Cycles:180 Average:34031.51 Median:22790.60 stddev:79529.91 (349.0%) stddev-sans-first:79713.30 17:14:58 INFO - PID 3860 | Values: 558.0 574.3 579.2 590.6 610.0 22575.5 23437.6 23454.0 23539.6 23543.6 23537.5 24164.9 24841.0 25129.0 25163.4 6516.7 6713.7 6715.5 6717.2 6718.6 6382.7 6564.3 6665.4 6666.9 6668.9 6639.9 6676.5 6678.3 6679.5 6681.2 6648.1 6652.7 6655.3 6663.5 6668.0 6134.2 6160.6 6164.2 6179.9 6193.0 27221.0 28170.7 28523.9 28822.7 29080.9 2035.8 2065.1 2065.5 2078.3 2080.7 19209.4 19799.7 19875.4 19947.9 20003.2 2452.0 2453.4 2514.1 2528.7 2534.0 117523.4 122164.0 133849.0 140317.0 148940.0 14603.1 14932.7 15057.0 15109.7 15465.8 23292.8 23317.8 23389.8 23420.3 23559.0 10044.8 10100.6 10247.2 10343.7 10428.6 9366.7 9426.6 9493.4 9517.4 9560.7 8778.4 8910.7 9080.5 9292.2 9323.0 2666.6 2730.5 2745.3 2845.5 2876.1 977.1 986.5 990.3 991.1 1005.6 18946.0 19649.8 19821.9 19931.0 20253.7 1028.3 1028.9 1036.5 1058.3 1065.9 10813.5 10823.2 10901.9 10948.5 11123.3 456701.0 460577.0 474388.0 481817.0 489109.0 103461.0 106723.5 107301.0 108910.0 109543.9 1057.7 1097.9 1147.3 1169.9 1214.2 20307.5 20371.8 20411.0 20493.7 20596.7 57244.7 57883.0 58060.7 58474.7 59548.8 72514.3 72685.0 72867.6 73036.0 74041.6 14357.6 14613.3 14685.3 14763.3 14791.4 22175.2 22378.9 22484.3 22717.7 22863.5 18137.5 18206.2 18257.5 18373.9 18429.2 16485.4 16580.8 16695.8 16853.4 17276.6 18374.2 18415.4 18442.4 18467.4 18674.7 14351.2 14494.1 14530.6 14568.7 14646.9 18015.5 18146.2 18229.9 18335.4 18430.4 17:14:58 INFO - PID 3860 | 17:14:58 INFO - PID 3860 | [#3] mootools.html Cycles:180 Average:9166.48 Median:24584.13 stddev:6289.74 (25.6%) stddev-sans-first:6279.93 17:14:58 INFO - PID 3860 | Values: 1346.0 1360.6 1375.8 1379.5 1402.8 14110.7 14685.6 14704.7 14706.0 14745.8 14471.0 14617.5 14623.6 14650.4 14668.3 2715.5 2810.7 2820.6 2823.2 2830.1 2692.5 2721.7 2726.7 2734.9 2744.3 2624.0 2737.3 2861.0 2864.5 2866.9 2826.2 2832.9 2849.0 2851.9 2860.2 2686.5 2721.0 2738.7 2764.9 2764.9 14448.2 14530.3 14540.9 14545.0 14569.9 7725.8 7998.8 8057.0 8070.5 8095.8 5234.3 5456.5 5494.8 5582.7 5612.8 1935.3 1942.3 1953.9 1980.7 1985.5 24442.6 24490.6 24568.6 24599.6 24624.5 6698.2 6896.1 6897.0 7087.8 7102.5 16705.9 16722.7 16739.8 16743.0 16802.9 7720.8 7935.0 7946.5 7993.1 7994.1 7193.1 7265.1 7342.2 7459.1 7483.7 5131.1 5306.4 5309.1 5317.2 5326.7 1454.3 1466.5 1470.3 1553.8 1588.1 918.9 931.8 940.4 946.1 948.0 8757.5 8764.8 8819.9 8830.5 8867.8 968.5 988.2 1000.3 1002.1 1016.2 5145.9 5201.4 5429.2 5633.2 5720.1 19866.5 19968.6 20844.3 20863.6 21201.3 20562.0 20726.2 20942.2 20971.0 20975.3 10478.8 10714.3 10811.7 10820.0 10920.9 8390.5 8489.7 8493.5 8497.0 8502.6 17384.0 17696.7 17889.4 18039.9 18140.9 18270.2 18473.0 18665.5 18674.2 18698.8 10636.1 10893.7 10999.8 11001.1 11012.4 13405.3 13504.2 13562.3 13564.9 13570.8 11299.9 11308.3 11470.4 11529.0 11569.5 10982.1 11048.7 11093.7 11375.5 11381.3 10114.6 10168.7 10178.1 10194.1 10194.2 7497.4 7558.1 7581.4 7596.5 7620.1 7241.3 7375.3 7382.4 7394.6 7399.9 17:14:58 INFO - PID 3860 | 17:14:58 INFO - PID 3860 | [#4] prototype.html Cycles:180 Average:5497.11 Median:6248.20 stddev:1536.31 (24.6%) stddev-sans-first:1494.11 17:14:58 INFO - PID 3860 | Values: 498.8 507.9 523.5 526.8 539.7 6296.7 6334.7 6368.3 6368.6 6377.7 6287.7 6309.9 6312.1 6331.8 6337.0 5211.2 5213.0 5229.7 5232.8 5241.3 5087.8 5174.2 5176.4 5206.4 5242.7 4801.7 4937.0 4937.3 4945.7 4945.9 4858.6 4951.2 4975.1 4981.1 5019.4 4335.6 4438.7 4456.5 4471.6 4482.7 5999.4 6040.6 6065.7 6089.5 6142.8 5531.5 5549.1 5553.9 5619.7 5626.4 6401.9 6494.6 6535.3 6548.2 6559.3 4583.3 4851.9 4860.9 4864.3 4884.3 6679.7 6688.1 6755.7 6855.5 6878.9 4965.4 4974.9 4992.9 4994.0 5004.0 5037.8 5041.3 5062.6 5079.2 5079.6 5623.0 5649.3 5672.3 5684.7 5685.3 6552.1 6589.4 6607.8 6608.3 6634.5 1727.6 1747.6 1749.5 1751.7 1767.5 1153.2 1198.3 1205.4 1215.5 1225.0 5555.2 5584.1 5662.3 5696.7 5701.2 6720.8 6723.0 6730.4 6734.6 6746.3 5607.2 5667.2 5782.6 5783.1 5783.5 3512.2 3537.6 3557.7 3576.5 3585.2 6333.5 6346.4 6375.8 6409.6 6483.7 6763.4 6804.4 6812.8 6872.5 6916.8 6222.3 6329.5 6340.3 6345.0 6399.4 6583.5 6616.8 6643.5 6655.7 6658.2 6460.2 6559.3 6592.0 6656.9 6674.6 6473.4 6477.4 6480.2 6531.9 6593.6 4931.4 4986.7 5018.8 5030.1 5047.2 6444.6 6646.4 6647.4 6672.7 6676.4 6331.8 6555.2 6567.0 6631.7 6649.1 6455.4 6488.2 6518.3 6538.6 6538.9 6274.1 6429.2 6453.2 6461.0 6496.3 6545.3 6551.0 6567.3 6599.7 6645.8 6358.9 6387.4 6413.8 6416.7 6430.8 17:14:58 INFO - PID 3860 | 17:14:58 INFO - PID 3860 | [#5] yui.html Cycles:180 Average:24287.95 Median:20841.72 stddev:34157.57 (163.9%) stddev-sans-first:34208.23 17:14:58 INFO - PID 3860 | Values: 895.1 907.9 921.6 924.9 929.5 19274.0 20095.5 20147.8 20158.9 20166.3 16144.0 16280.1 16311.2 16345.1 16352.7 896.9 958.1 958.5 958.9 964.1 962.3 974.4 976.5 976.6 977.7 986.2 993.3 996.5 997.4 997.7 986.8 1000.0 1005.7 1006.2 1010.0 724.4 738.5 740.8 750.6 751.5 20621.8 20636.6 20829.7 20846.1 20864.3 20837.4 20881.3 21283.9 21430.8 21433.9 16800.6 16878.9 16974.7 17028.9 17142.1 3755.1 3787.5 3795.1 3796.3 3797.0 94623.0 106073.0 140539.0 142163.0 147171.3 17214.7 17223.1 17327.0 17588.4 17670.4 31461.4 31501.6 31558.5 31601.7 31625.0 9397.2 9464.4 9542.9 9544.0 9575.2 4537.6 4620.1 4632.8 4640.5 4654.5 5391.2 5434.5 5482.2 5494.8 5501.3 1087.0 1095.9 1114.3 1115.8 1116.1 675.6 698.4 707.9 717.2 722.8 12078.0 12469.9 12494.6 12553.6 12603.1 684.1 685.1 688.8 691.3 703.9 5779.3 5781.2 5816.2 5911.7 5915.1 120533.0 121372.0 121373.4 124808.0 127041.0 103599.5 104790.5 107027.0 108951.0 112236.0 94790.5 97408.5 97771.0 98627.0 99279.5 12389.8 12587.2 12659.2 12731.0 12737.4 41829.4 42399.8 42664.9 42881.0 43134.0 54716.7 55015.4 55084.4 55675.0 55676.2 16564.4 16908.3 16989.6 17028.7 17272.3 18070.0 18261.0 18358.3 18364.8 18622.7 16963.7 17283.7 17332.0 17349.5 17417.9 16584.3 16657.2 16689.2 16906.9 16931.2 18139.0 18268.7 18480.0 18567.3 18604.6 14021.4 14248.1 14350.7 14399.4 14527.6 13986.3 14006.8 14031.4 14057.2 14070.4 17:14:58 INFO - PID 3860 | -------- Summary: end -------- 17:14:58 INFO - PID 3860 | 17:14:59 INFO - TEST-INFO | 3860: exit 0 17:14:59 INFO - TEST-OK | dromaeo_css | took 1112056ms 17:14:59 INFO - TEST-START | kraken 17:14:59 INFO - Initialising browser for kraken test... 17:14:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52152/getInfo.html -profile /tmp/tmpDKX0yf/profile 17:14:59 INFO - TEST-INFO | started process 4243 (/builds/slave/test/build/application/firefox/firefox http://localhost:52152/getInfo.html) 17:15:04 INFO - TEST-INFO | 4243: exit 0 17:15:04 INFO - Browser initialized. 17:15:04 INFO - Running cycle 1/1 for kraken test... 17:15:04 INFO - Using env: {'DISPLAY': ':0', 17:15:04 INFO - 'HOME': '/home/cltbld', 17:15:04 INFO - 'JSGC_DISABLE_POISONING': '1', 17:15:04 INFO - 'LANG': 'en_US.UTF-8', 17:15:04 INFO - 'LANGUAGE': 'en_US:en', 17:15:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 17:15:04 INFO - 'LOGNAME': 'cltbld', 17:15:04 INFO - 'MAIL': '/var/mail/cltbld', 17:15:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:15:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:15:04 INFO - 'MOZ_CRASHREPORTER': '1', 17:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:15:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 17:15:04 INFO - 'MOZ_NO_REMOTE': '1', 17:15:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:15:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:15:04 INFO - 'NO_EM_RESTART': '1', 17:15:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:15:04 INFO - 'PWD': '/builds/slave/test', 17:15:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:15:04 INFO - 'RUST_BACKTRACE': 'full', 17:15:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:15:04 INFO - 'SHELL': '/bin/bash', 17:15:04 INFO - 'SHLVL': '1', 17:15:04 INFO - 'TERM': 'linux', 17:15:04 INFO - 'TMOUT': '86400', 17:15:04 INFO - 'USER': 'cltbld', 17:15:04 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703', 17:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:15:04 INFO - '_': '/tools/buildbot/bin/python'} 17:15:04 INFO - TEST-INFO | started process 4480 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDKX0yf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:05 INFO - PID 4480 | 17:15:05 INFO - PID 4480 | (/builds/slave/test/build/application/firefox/firefox:4528): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 17:15:05 INFO - PID 4480 | 17:15:16 INFO - PID 4480 | RSS: Main: 151932928 17:15:16 INFO - PID 4480 | 17:15:18 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52152/tests/kraken/driver.html?testName=audio-beat-detection) 17:15:19 INFO - PID 4480 | RSS: Main: 157761536 17:15:19 INFO - PID 4480 | 17:15:22 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52152/tests/kraken/driver.html?testName=audio-dft) 17:15:22 INFO - PID 4480 | RSS: Main: 157851648 17:15:22 INFO - PID 4480 | 17:15:25 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52152/tests/kraken/driver.html?testName=audio-fft) 17:15:26 INFO - PID 4480 | RSS: Main: 154890240 17:15:26 INFO - PID 4480 | 17:15:28 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52152/tests/kraken/driver.html?testName=audio-oscillator) 17:15:29 INFO - PID 4480 | RSS: Main: 154824704 17:15:29 INFO - PID 4480 | 17:15:31 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52152/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:15:31 INFO - PID 4480 | RSS: Main: 154898432 17:15:31 INFO - PID 4480 | 17:15:34 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52152/tests/kraken/driver.html?testName=imaging-darkroom) 17:15:35 INFO - PID 4480 | RSS: Main: 157052928 17:15:35 INFO - PID 4480 | 17:15:39 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52152/tests/kraken/driver.html?testName=imaging-desaturate) 17:15:39 INFO - PID 4480 | RSS: Main: 158093312 17:15:39 INFO - PID 4480 | 17:15:42 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52152/tests/kraken/driver.html?testName=json-parse-financial) 17:15:43 INFO - PID 4480 | RSS: Main: 158588928 17:15:43 INFO - PID 4480 | 17:15:45 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52152/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:15:45 INFO - PID 4480 | RSS: Main: 158191616 17:15:45 INFO - PID 4480 | 17:15:47 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52152/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:15:47 INFO - PID 4480 | RSS: Main: 158072832 17:15:47 INFO - PID 4480 | 17:15:49 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52152/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:15:50 INFO - PID 4480 | RSS: Main: 155074560 17:15:50 INFO - PID 4480 | 17:15:53 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52152/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:15:53 INFO - PID 4480 | RSS: Main: 154898432 17:15:53 INFO - PID 4480 | 17:15:56 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52152/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:15:57 INFO - PID 4480 | RSS: Main: 155447296 17:15:57 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | Cycle 1(1): loaded http://localhost:52152/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52152/tests/kraken/driver.html?testName=ai-astar) 17:15:59 INFO - PID 4480 | RSS: Main: 156864512 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | __start_tp_report 17:15:59 INFO - PID 4480 | _x_x_mozilla_page_load 17:15:59 INFO - PID 4480 | _x_x_mozilla_page_load_details 17:15:59 INFO - PID 4480 | |i|pagename|runs| 17:15:59 INFO - PID 4480 | |0;ai-astar;109;107;106;106;107;111;107;108;108;106 17:15:59 INFO - PID 4480 | |1;audio-beat-detection;118;118;118;116;123;149;115;118;119;119 17:15:59 INFO - PID 4480 | |2;audio-dft;167;164;164;162;162;162;163;163;168;166 17:15:59 INFO - PID 4480 | |3;audio-fft;74;74;75;74;74;74;74;75;74;75 17:15:59 INFO - PID 4480 | |4;audio-oscillator;80;78;78;80;79;80;80;79;77;77 17:15:59 INFO - PID 4480 | |5;imaging-gaussian-blur;111;111;111;112;111;111;111;112;112;112 17:15:59 INFO - PID 4480 | |6;imaging-darkroom;151;147;149;149;148;147;146;150;157;148 17:15:59 INFO - PID 4480 | |7;imaging-desaturate;94;93;94;95;94;94;95;94;95;94 17:15:59 INFO - PID 4480 | |8;json-parse-financial;54;54;53;50;51;52;53;51;52;51 17:15:59 INFO - PID 4480 | |9;json-stringify-tinderbox;37;37;36;37;37;38;37;38;37;37 17:15:59 INFO - PID 4480 | |10;stanford-crypto-aes;77;77;75;79;75;73;78;78;76;74 17:15:59 INFO - PID 4480 | |11;stanford-crypto-ccm;109;106;108;113;120;112;114;110;108;119 17:15:59 INFO - PID 4480 | |12;stanford-crypto-pbkdf2;164;163;162;166;170;168;164;182;164;163 17:15:59 INFO - PID 4480 | |13;stanford-crypto-sha256-iterative;69;63;63;68;66;61;67;68;67;63 17:15:59 INFO - PID 4480 | __end_tp_report 17:15:59 INFO - PID 4480 | __start_cc_report 17:15:59 INFO - PID 4480 | _x_x_mozilla_cycle_collect,721 17:15:59 INFO - PID 4480 | __end_cc_report 17:15:59 INFO - PID 4480 | __startTimestamp1506816959335__endTimestamp 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | ------- Summary: start ------- 17:15:59 INFO - PID 4480 | Number of tests: 14 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#0] ai-astar Cycles:10 Average:107.50 Median:107.50 stddev:1.58 (1.5%) stddev-sans-first:1.58 17:15:59 INFO - PID 4480 | Values: 109.0 107.0 106.0 106.0 107.0 111.0 107.0 108.0 108.0 106.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:118.50 stddev:9.96 (8.4%) stddev-sans-first:10.49 17:15:59 INFO - PID 4480 | Values: 118.0 118.0 118.0 116.0 123.0 149.0 115.0 118.0 119.0 119.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#2] audio-dft Cycles:10 Average:164.10 Median:164.00 stddev:2.18 (1.3%) stddev-sans-first:2.05 17:15:59 INFO - PID 4480 | Values: 167.0 164.0 164.0 162.0 162.0 162.0 163.0 163.0 168.0 166.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#3] audio-fft Cycles:10 Average:74.30 Median:74.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 17:15:59 INFO - PID 4480 | Values: 74.0 74.0 75.0 74.0 74.0 74.0 74.0 75.0 74.0 75.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#4] audio-oscillator Cycles:10 Average:78.80 Median:79.50 stddev:1.23 (1.5%) stddev-sans-first:1.22 17:15:59 INFO - PID 4480 | Values: 80.0 78.0 78.0 80.0 79.0 80.0 80.0 79.0 77.0 77.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#5] imaging-gaussian-blur Cycles:10 Average:111.40 Median:111.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 17:15:59 INFO - PID 4480 | Values: 111.0 111.0 111.0 112.0 111.0 111.0 111.0 112.0 112.0 112.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#6] imaging-darkroom Cycles:10 Average:149.20 Median:149.00 stddev:3.12 (2.1%) stddev-sans-first:3.24 17:15:59 INFO - PID 4480 | Values: 151.0 147.0 149.0 149.0 148.0 147.0 146.0 150.0 157.0 148.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 17:15:59 INFO - PID 4480 | Values: 94.0 93.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.50 stddev:1.37 (2.6%) stddev-sans-first:1.27 17:15:59 INFO - PID 4480 | Values: 54.0 54.0 53.0 50.0 51.0 52.0 53.0 51.0 52.0 51.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#9] json-stringify-tinderbox Cycles:10 Average:37.10 Median:37.00 stddev:0.57 (1.5%) stddev-sans-first:0.60 17:15:59 INFO - PID 4480 | Values: 37.0 37.0 36.0 37.0 37.0 38.0 37.0 38.0 37.0 37.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:77.00 stddev:1.93 (2.5%) stddev-sans-first:2.03 17:15:59 INFO - PID 4480 | Values: 77.0 77.0 75.0 79.0 75.0 73.0 78.0 78.0 76.0 74.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#11] stanford-crypto-ccm Cycles:10 Average:111.90 Median:112.50 stddev:4.70 (4.2%) stddev-sans-first:4.87 17:15:59 INFO - PID 4480 | Values: 109.0 106.0 108.0 113.0 120.0 112.0 114.0 110.0 108.0 119.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.60 Median:165.00 stddev:5.95 (3.6%) stddev-sans-first:6.23 17:15:59 INFO - PID 4480 | Values: 164.0 163.0 162.0 166.0 170.0 168.0 164.0 182.0 164.0 163.0 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:67.00 stddev:2.76 (4.1%) stddev-sans-first:2.62 17:15:59 INFO - PID 4480 | Values: 69.0 63.0 63.0 68.0 66.0 61.0 67.0 68.0 67.0 63.0 17:15:59 INFO - PID 4480 | -------- Summary: end -------- 17:15:59 INFO - PID 4480 | 17:15:59 INFO - PID 4480 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 17:15:59 INFO - TEST-INFO | 4480: exit 0 17:16:00 INFO - TEST-OK | kraken | took 60860ms 17:16:00 INFO - SUITE-END | took 1172s 17:16:00 INFO - Completed test suite (00:19:33) 17:16:00 INFO - javascript benchmark 17:16:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8552.29098044867, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3172.4131035758387, 3173.968260317397, 3175.317306779042, 3188.4420226460365, 3200.7759456838016, 6088.330283668793, 6136.202293701818, 6139.447449729523, 6263.655498947562, 6275.623462592242, 6180.567360284782, 6210.596311239767, 6213.0991006304175, 6246.0, 6256.998880179172, 5713.71431428429, 5730.79942202023, 5732.140178973145, 5742.368339482654, 5748.798792042279, 5786.132080187964, 5806.65160090396, 5826.6212696174825, 5828.941710582903, 5830.5044071254, 5762.337331206902, 5781.583512039556, 5838.795642152526, 5844.678542680151, 5868.119782032687, 5306.112714350457, 5556.638818476786, 5669.914951275734, 5816.912746308809, 5852.063669812831, 5692.743826527775, 5722.685252311121, 5759.424057594207, 5782.277215348082, 5787.13193021046, 5980.222571065734, 6161.661108639023, 6209.410106039919, 6257.217847769029, 6263.217097862767, 6572.277049524549, 6575.638339891352, 6604.471642268607, 6609.93390066098, 6672.866542669168, 6799.218089919624, 6803.591784492946, 6811.795646130624, 6816.045753594501, 6818.045473633695, 6204.100405441186, 6222.253329600477, 6281.937180628181, 6289.119523266746, 6294.464970477561, 6686.1642294713165, 6804.795856124324, 6839.931600684029, 6952.339527744856, 6957.1651401832105, 6432.742690292336, 6506.121673574007, 6515.120458738104, 6517.869642607121, 6519.152510173647, 6662.866742665216, 6669.199696036507, 6683.933160668331, 6688.531802773759, 6691.1636045494315, 6545.9018114728315, 6552.984287435455, 6652.268250492442, 6660.666966651648, 6671.733130674816, 6597.109390232353, 6608.768693095719, 6637.900431493532, 6687.6321802301345, 6722.932770672329, 5153.123968925292, 5217.087009773274, 5220.295260139908, 5223.373195216598, 5255.6321057526375, 4538.659600529907, 4561.29299958507, 4622.0, 4640.582347588733, 4640.79116439761, 5717.599768016282, 5779.3642699303045, 5794.652320860761, 5803.419658034247, 5826.2134611827705, 6643.2360278568585, 6647.634380109043, 6705.0, 6709.731610735614, 6748.156480439945, 5558.722063896789, 5662.745176467069, 5725.198472213893, 5809.738561764731, 5826.533877289891, 5760.971195143998, 5778.27771528559, 5781.884362312814, 5802.33273173582, 5803.100519419497, 6321.494280457552, 6437.2918978912285, 6515.0, 6515.2181738190775, 6524.347565243438, 6576.671166441754, 6684.933150668431, 6702.430293425114, 6767.560108592926, 6811.761588344384, 6416.0, 6424.2933277338525, 6425.036244563166, 6440.29156792743, 6446.419822216023, 6518.413342799171, 6596.472282217507, 6600.207975043026, 6608.240052394036, 6614.966925165343, 6662.800115996528, 6664.400203981665, 6678.2320033194865, 6759.526833121634, 6772.288909664414, 6354.364563543609, 6414.807555773335, 6441.677916104082, 6494.6103233806125, 6515.250746164063, 6420.775272865428, 6428.132202152649, 6446.677666116581, 6449.4517965971545, 6456.580322279036, 6332.0, 6366.045093236052, 6376.3623637635865, 6409.070684750592, 6501.08984742127, 6529.575577587441, 6587.9670601646685, 6593.439557637655, 6612.90080648781, 6617.139771829631, 6308.27454842699, 6315.621062736251, 6391.265004524539, 6395.520335974914, 6403.6798160090875], "value": 6079.421287280146, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [881.6870011146042, 902.6705252582809, 903.5211337990867, 907.2605826251604, 907.3376435202302, 38391.61608383915, 41795.16409671805, 41856.79071604645, 42039.36940945888, 42198.0, 37941.24117517648, 37973.43039854404, 38311.23377532448, 38546.22907541847, 38620.22759544807, 7361.079865016886, 7519.436042296822, 8049.436539442241, 8250.298724608416, 8261.091279959199, 8200.958995205016, 8312.625931833052, 8312.625931833052, 8327.0, 8332.375071869603, 8285.337173026173, 8287.295579875718, 8344.20730030649, 8348.457350272243, 8351.540665263408, 8277.54473503957, 8277.751667449986, 8304.70933517324, 8310.459820111673, 8325.583720813966, 8185.918140818635, 8215.055268644122, 8217.178282171735, 8247.917520824834, 8256.0, 37039.629603703885, 37712.622873771186, 37852.43221351682, 38029.239415211814, 38089.42865857014, 3612.8193590320643, 3652.689521390685, 3652.9086772830624, 3653.3606618841595, 3666.834992425347, 30772.0, 30937.0, 31123.221919452193, 31299.06102816919, 31330.373392532023, 5057.443681195103, 5253.579713622901, 5259.76331065103, 5262.0, 5287.391949925769, 183403.33193336191, 184193.0, 186146.0, 186609.0, 187023.0, 42405.787971059945, 43103.0, 48608.270875936185, 48835.26747098725, 49095.0, 45814.31278530825, 50394.49605503898, 51289.23066154011, 51418.74290628523, 51691.7415412928, 16128.274227659784, 16364.181790910407, 16389.754153687703, 16416.343346266014, 16435.09606971628, 13326.933365333305, 13444.193348399127, 13464.124831885896, 13486.325683715972, 13501.59495215145, 14201.147931124166, 14328.85671143296, 14353.354099065567, 14354.0, 14578.0, 5617.354004289559, 5643.943560564342, 5644.153376993402, 5676.460736230051, 5742.885142297131, 1012.473513772834, 1016.2632091733434, 1022.1056575496441, 1030.4487099401783, 1049.0663309654415, 22839.885800571225, 23058.423539411648, 23240.76759232386, 23255.651165232535, 23350.949207285714, 1095.775366049958, 1107.4340623193305, 1112.3214838948236, 1121.377635412354, 1131.0386171753944, 12466.75066498665, 12475.75048499043, 12567.245965242115, 12586.0, 12640.747185056247, 227077.0, 229118.0, 229511.0, 229582.0, 229921.0, 53724.46275537195, 53945.0, 53976.0, 53997.73001134969, 54203.72898135484, 1391.6729568551382, 1473.2191938272733, 1477.807884974968, 1481.7629179331254, 1495.2598463760291, 22554.774452255268, 22713.0, 23195.88402057979, 23300.53398932046, 23521.52956940886, 37735.62264377431, 37990.620093799815, 37996.62003379931, 38070.809645951595, 38105.0, 194203.0, 195023.0, 195561.0, 196723.0, 196937.0, 30243.69756302409, 33833.0, 34662.82668586641, 35018.474722878665, 35081.0, 42918.57081429146, 43304.56695433131, 43338.916527085814, 43452.565474344854, 43808.56191438045, 31993.840030800628, 32146.67853321437, 32439.67560324461, 32586.34827303488, 32870.835645821615, 31307.217319567193, 31415.21461963469, 31544.36911261808, 31845.20386990344, 32193.35613287768, 35249.82375088108, 35802.64197357993, 36027.09932251715, 36087.45868812023, 36206.45690314595, 31263.218419539695, 31367.372652547278, 31498.212544686565, 31881.202969925936, 31892.0, 22922.885385573634, 23280.835958201682, 23369.0, 23809.0, 23829.52340953206], "value": 17306.18196818932, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.0, 574.3108270075912, 579.2325169150876, 590.6249531547468, 609.9512039036877, 22575.54848903021, 23437.648435273484, 23454.0, 23539.646905296435, 23543.646845297335, 23537.529249415, 24164.879175604143, 24841.0, 25129.0, 25163.370915727115, 6516.739330426777, 6713.731450741964, 6715.496337774662, 6717.1939367276, 6718.596884186964, 6382.680865956683, 6564.277929427759, 6665.40011398974, 6666.866662666744, 6668.899966500507, 6639.900401493981, 6676.532642715012, 6678.332166783332, 6679.499037572202, 6681.198256209262, 6648.135742353487, 6652.667366631673, 6655.267920528738, 6663.500237482208, 6668.033135195394, 6134.202553667995, 6160.630362178284, 6164.198654174974, 6179.87640247197, 6193.04007878775, 27221.0, 28170.718292817015, 28523.857380713172, 28822.711772882212, 29080.854595727098, 2035.7557093148769, 2065.1120018392085, 2065.5042789730355, 2078.3349328214827, 2080.6670932650854, 19209.423717288504, 19799.703004454946, 19875.403737887886, 19947.900260498605, 20003.1998720051, 2451.9754802451926, 2453.435709786741, 2514.069794176148, 2528.7344828792898, 2533.961990570143, 117523.41238293839, 122163.99999999911, 133849.0, 140317.0, 148940.0, 14603.089401801099, 14932.744167912093, 15057.0, 15109.671809172543, 15465.845341546665, 23292.76707232906, 23317.766822331898, 23389.76610233876, 23420.297391078297, 23559.0, 10044.849327260097, 10100.646477373404, 10247.231457640557, 10343.689689309333, 10428.582856685647, 9366.672166474276, 9426.575804088832, 9493.382930109521, 9517.43029382063, 9560.66537671192, 8778.385513014027, 8910.663400489979, 9080.455172689659, 9292.16370526656, 9322.95338523303, 2666.5733482642686, 2730.494858451193, 2745.2999485131563, 2845.516262235425, 2876.137158852336, 977.1010670183448, 986.5313975861409, 990.2573070197352, 991.1278075293695, 1005.6128390569684, 18946.0, 19649.84140463037, 19821.900890495453, 19931.0, 20253.696194557095, 1028.3315844701003, 1028.93311934724, 1036.461040259076, 1058.343826827372, 1065.9360438373722, 10813.459327033617, 10823.242373033969, 10901.945490272497, 10948.507317170746, 11123.332600044023, 456701.0, 460577.0, 474388.0, 481817.0, 489109.0, 103461.0, 106723.4663826676, 107301.0, 108910.0, 109543.90456095336, 1057.7144171073767, 1097.928634638746, 1147.3345459633392, 1169.9473523691454, 1214.1864950483198, 20307.492312692302, 20371.79628203699, 20411.0, 20493.692594610795, 20596.691049634566, 57244.71377643085, 57883.0, 58060.709696451246, 58474.7076264616, 59548.80902382015, 72514.27485725076, 72685.0, 72867.63566182135, 73036.0, 74041.62979185286, 14357.56927292183, 14613.269336533343, 14685.33915973805, 14763.261836908327, 14791.408343666133, 22175.223867164572, 22378.888105559367, 22484.32547023592, 22717.659235111816, 22863.542729145658, 18137.455876323205, 18206.180721867284, 18257.45227643173, 18373.908130459262, 18429.170687319373, 16485.423010194587, 16580.83419165841, 16695.833041669426, 16853.376524047388, 17276.568085797957, 18374.173162208, 18415.355462558746, 18442.446726598224, 18467.353642622445, 18674.71987920155, 14351.210683412513, 14494.130352178903, 14530.564083077525, 14568.68881800643, 14646.926765366105, 18015.549611259823, 18146.18342174632, 18229.908850455664, 18335.35826246075, 18430.447086587425], "value": 12010.71091152541, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1345.9798103028454, 1360.5918224532643, 1375.8417781955072, 1379.5033787836383, 1402.8316602007758, 14110.717785644281, 14685.55943321702, 14704.705905881889, 14706.0, 14745.77881331781, 14471.0, 14617.488387906425, 14623.561293161221, 14650.413983440676, 14668.339924703387, 2715.511207982567, 2810.690824009357, 2820.591014302925, 2823.167165686117, 2830.0943698016645, 2692.4615076984683, 2721.6733991920996, 2726.7136950620097, 2734.8906043758225, 2744.341358074058, 2624.0422245880213, 2737.288305040684, 2860.9713902861017, 2864.5416733322672, 2866.8996585119526, 2826.208661574752, 2832.915012549606, 2849.0, 2851.8574071296352, 2860.242035860488, 2686.476137153256, 2721.047633328339, 2738.6713594368603, 2764.9447011059665, 2764.944701105987, 14448.205348705817, 14530.346134423977, 14540.927295363455, 14545.0, 14569.927150364287, 7725.768226953201, 7998.840023199504, 8057.033156021256, 8070.515769053875, 8095.838083238361, 5234.319538460014, 5456.536194423439, 5494.80768173112, 5582.692951887645, 5612.7754889804355, 1935.3226370770117, 1942.320187934226, 1953.8534609904173, 1980.6533856575043, 1985.463924740312, 24442.633360499607, 24490.63264051041, 24568.631470527955, 24599.63100553493, 24624.50750985006, 6698.229703584053, 6896.103506544117, 6897.0, 7087.787366379017, 7102.538335008208, 16705.916470417815, 16722.665546688997, 16739.832601674072, 16743.0, 16802.915985419993, 7720.845583088307, 7934.960325198337, 7946.483478573874, 7993.080795708568, 7994.120646728855, 7193.100862392201, 7265.09186351706, 7342.15565209997, 7459.142198647225, 7483.663235154322, 5131.076406246837, 5306.355029940653, 5309.097453432925, 5317.202419637086, 5326.707031113248, 1454.345544504977, 1466.5453709350136, 1470.272215253435, 1553.8213105492787, 1588.1265304082708, 918.8989211186764, 931.7810314576069, 940.3793496292551, 946.0539460539461, 948.0472125513814, 8757.4745515268, 8764.824703505894, 8819.867701984474, 8830.514321712368, 8867.778305542284, 968.5447839515557, 988.2341185581232, 1000.3047881722279, 1002.0680766886866, 1016.2378216337746, 5145.9485405146215, 5201.375834899836, 5429.185622156708, 5633.2395126657375, 5720.11338242573, 19866.50333741639, 19968.600627987358, 20844.270450534157, 20863.582728345653, 21201.303895688307, 20562.0, 20726.170953161705, 20942.162313507593, 20971.0, 20975.265865694626, 10478.842817357745, 10714.303570268063, 10811.729706757395, 10820.0, 10920.890791092146, 8390.496570205807, 8489.660413583388, 8493.490390576586, 8496.957515212385, 8502.617382217692, 17384.0, 17696.734548982033, 17889.37387191442, 18039.909800450914, 18140.90929545344, 18270.177841996876, 18473.0, 18665.53336166607, 18674.159662815477, 18698.813011869708, 10636.09593184557, 10893.727656808644, 10999.780004400027, 11001.119910407308, 11012.449377531251, 13405.262710550624, 13504.189748615114, 13562.321883905568, 13564.93217533906, 13570.79643805324, 11299.943500282447, 11308.321500709984, 11470.4264786762, 11529.0, 11569.479373428048, 10982.121430285399, 11048.668539943814, 11093.722656933318, 11375.54497820078, 11381.317120972768, 10114.645987390406, 10168.694939151837, 10178.134858537107, 10194.133498652698, 10194.184465242912, 7497.400207983457, 7558.127931344353, 7581.445803610357, 7596.544207347577, 7620.085589729268, 7241.275872412716, 7375.262473752582, 7382.446316526175, 7394.630268486661, 7399.852002960019], "value": 6679.357406106195, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.7630082449591, 507.94412614612384, 523.5052875033091, 526.8261473713675, 539.7382269599244, 6296.748130074797, 6334.714937827797, 6368.29948705642, 6368.586041907272, 6377.713002914863, 6287.685615719207, 6309.873802523958, 6312.116303717472, 6331.810045698625, 6337.0, 5211.218317252405, 5212.973935130319, 5229.7385130743505, 5232.790688372461, 5241.344831896013, 5087.847364579068, 5174.197999310094, 5176.378834539842, 5206.37523497179, 5242.711650859202, 4801.7118972861745, 4937.0, 4937.259411088329, 4945.703257804544, 4945.862451636126, 4858.562729354339, 4951.183054795991, 4975.104481193383, 4981.078500477405, 5019.372578427697, 4335.609795118455, 4438.690586277056, 4456.509783923766, 4471.575200355961, 4482.708623939434, 5999.390265234637, 6040.637561746265, 6065.727042283063, 6089.468108497105, 6142.78500252489, 5531.529819965308, 5549.113301477517, 5553.888922221533, 5619.719014049281, 5626.437356264381, 6401.871962560769, 6494.642794646293, 6535.346465353474, 6548.1814773153355, 6559.311272316386, 4583.2666773316105, 4851.902961940741, 4860.902781944341, 4864.343313652647, 4884.291777692215, 6679.666016699146, 6688.060462465879, 6755.695993680296, 6855.451563874928, 6878.896816547755, 4965.386249468865, 4974.900501990012, 4992.900141997213, 4993.975030124826, 5004.024215278058, 5037.79848806051, 5041.344625198701, 5062.569681577033, 5079.187330027178, 5079.568236699848, 5622.95975244581, 5649.288341596057, 5672.262605861211, 5684.687342196219, 5685.289338832646, 6552.115464412243, 6589.374009469093, 6607.810594093109, 6608.306127856602, 6634.469242460592, 1727.5508367824457, 1747.6281119321272, 1749.4751574527845, 1751.702210624197, 1767.4785938148277, 1153.210051114989, 1198.2930071258, 1205.4033253539553, 1215.53202017224, 1224.9816252756216, 5555.166724991283, 5584.050711379075, 5662.348829884616, 5696.658200507983, 5701.2018317435595, 6720.798376048726, 6723.0, 6730.360615741497, 6734.632683658171, 6746.257911629717, 5607.186957891083, 5667.206591077253, 5782.624129431658, 5783.074708046693, 5783.4794868461195, 3512.2448673535314, 3537.557805274341, 3557.6620221078956, 3576.499290099388, 3585.15748799032, 6333.493320534346, 6346.365363463617, 6375.776847810304, 6409.615423074631, 6483.7082331295205, 6763.425108865801, 6804.3863812355, 6812.795616131524, 6872.450203983669, 6916.757913473004, 6222.253329600387, 6329.525285603599, 6340.30256671775, 6345.0, 6399.424051835357, 6583.506237032146, 6616.823924516355, 6643.482431946323, 6655.667216639245, 6658.167729033871, 6460.160179176677, 6559.311272316529, 6591.967040164768, 6656.866862662623, 6674.564968531789, 6473.385028422293, 6477.4494167996245, 6480.157579514633, 6531.9020214695865, 6593.604383736991, 4931.3589233399425, 4986.725730084735, 5018.774155162953, 5030.119729047329, 5047.192449208109, 6444.581102228341, 6646.410938093569, 6647.368499992493, 6672.6997285121315, 6676.399124078856, 6331.841703957253, 6555.2133743951035, 6567.0, 6631.6684165792485, 6649.135612370361, 6455.383816080041, 6488.188976377953, 6518.315576864361, 6538.5615164663695, 6538.934610654022, 6274.145263904821, 6429.22849258092, 6453.1610890583925, 6460.963347226796, 6496.317886621837, 6545.312742162005, 6551.0, 6567.307403964352, 6599.670016499252, 6645.800625981228, 6358.936410635834, 6387.361263873576, 6413.807585772434, 6416.679166041773, 6430.7749228776765], "value": 5044.695980193737, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [895.0646574329826, 907.904670009649, 921.6405601815289, 924.8936372317186, 929.5305870535378, 19274.0, 20095.497612559677, 20147.798522014775, 20158.89920550399, 20166.294179703713, 16144.0, 16280.104594247341, 16311.184440777974, 16345.101019443926, 16352.672946541063, 896.9237614802734, 958.1041725986225, 958.5351104714208, 958.851378036402, 964.0744884910495, 962.3023308101606, 974.410481658597, 976.5410257179115, 976.6435251133321, 977.6920270114927, 986.2356673577963, 993.2997236947934, 996.5017491254373, 997.4314640654829, 997.6957028106425, 986.8421052631617, 999.990000099997, 1005.6681295172611, 1006.2151521812998, 1009.9747506312401, 724.3734169943014, 738.5421038955828, 740.8073900785835, 750.5759246025978, 751.4702134994816, 20621.793782062137, 20636.5872682547, 20829.68755468669, 20846.06192721331, 20864.26975055866, 20837.37487875366, 20881.26915557948, 21283.893580532, 21430.785692143036, 21433.892830535904, 16800.57998550046, 16878.915605421895, 16974.745378819327, 17028.914855425766, 17142.05718685472, 3755.117547376364, 3787.507624008889, 3795.1081495848453, 3796.3356412627954, 3796.9810150949343, 94623.0, 106073.0, 140539.0, 142163.0, 147171.26414367967, 17214.65570688579, 17223.138843057797, 17327.0, 17588.38440654571, 17670.381536646157, 31461.37077258442, 31501.58242879076, 31558.526622100686, 31601.683983160336, 31625.0, 9397.201237894855, 9464.432134071978, 9542.904570954202, 9543.952280238555, 9575.186109180797, 4537.591616754508, 4620.145273124483, 4632.791524381411, 4640.512746161672, 4654.464736555339, 5391.218273350342, 5434.538064264502, 5482.150266708666, 5494.780208791603, 5501.257330260443, 1086.9673909782719, 1095.9232853700323, 1114.2701530497536, 1115.827046807746, 1116.0569318925495, 675.5676367125044, 698.3819319901822, 707.8796604577132, 717.1752484642702, 722.7867779005206, 12078.0, 12469.937650311691, 12494.562690305978, 12553.560625378068, 12603.117781755373, 684.1345697692423, 685.0819901332139, 688.8036909480774, 691.2948792231896, 703.9155301363869, 5779.306483222041, 5781.219535362757, 5816.151886642301, 5911.674857882857, 5915.112733089984, 120533.0, 121372.0, 121373.39313303554, 124808.0, 127041.0, 103599.4820025895, 104790.47604761928, 107027.0, 108951.0, 112236.0, 94790.52604736932, 97408.51295743475, 97771.0, 98626.99999999856, 99279.50360248152, 12389.75220495567, 12587.181833180814, 12659.177153484994, 12730.981521478445, 12737.426815793126, 41829.372559412244, 42399.788001059795, 42664.8963252103, 42881.0, 43134.0, 54716.72641636767, 55015.44984550103, 55084.44915550954, 55675.0, 55676.16485752636, 16564.420245291356, 16908.32366705318, 16989.575260618585, 17028.659426811642, 17272.309107636058, 18070.0, 18261.0, 18358.265669373075, 18364.816351836846, 18622.720659190392, 16963.745543816607, 17283.7407438886, 17332.046737429562, 17349.479515614552, 17417.912910435873, 16584.336626534805, 16657.167141642625, 16689.248983796002, 16906.915465422593, 16931.15344232808, 18139.0, 18268.725969110208, 18480.0, 18567.25730970746, 18604.627907441503, 14021.439142434188, 14248.073875198079, 14350.712985740436, 14399.352029158923, 14527.636809079857, 13986.300684965507, 14006.78989815174, 14031.438742450597, 14057.156570605797, 14070.437182512995], "value": 9188.96381632241, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1410.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 107.0, 106.0, 106.0, 107.0, 111.0, 107.0, 108.0, 108.0, 106.0], "value": 107.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 116.0, 123.0, 149.0, 115.0, 118.0, 119.0, 119.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 164.0, 162.0, 162.0, 162.0, 163.0, 163.0, 168.0, 166.0], "value": 164.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 74.0, 74.0, 74.0, 74.0, 75.0, 74.0, 75.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 78.0, 80.0, 79.0, 80.0, 80.0, 79.0, 77.0, 77.0], "value": 78.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 111.0, 111.0, 112.0, 111.0, 111.0, 111.0, 112.0, 112.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 147.0, 149.0, 149.0, 148.0, 147.0, 146.0, 150.0, 157.0, 148.0], "value": 149.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 53.0, 50.0, 51.0, 52.0, 53.0, 51.0, 52.0, 51.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 36.0, 37.0, 37.0, 38.0, 37.0, 38.0, 37.0, 37.0], "value": 37.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 75.0, 79.0, 75.0, 73.0, 78.0, 78.0, 76.0, 74.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 106.0, 108.0, 113.0, 120.0, 112.0, 114.0, 110.0, 108.0, 119.0], "value": 111.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 162.0, 166.0, 170.0, 168.0, 164.0, 182.0, 164.0, 163.0], "value": 166.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 63.0, 63.0, 68.0, 66.0, 61.0, 67.0, 68.0, 67.0, 63.0], "value": 65.5, "unit": "score"}]}]} 17:16:00 INFO - Return code: 0 17:16:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:16:00 INFO - # TBPL SUCCESS # 17:16:00 INFO - Running post-action listener: _package_coverage_data 17:16:00 INFO - Running post-action listener: _resource_record_post_action 17:16:00 INFO - [mozharness: 2017-10-01 00:16:00.658143Z] Finished run-tests step (success) 17:16:00 INFO - Running post-run listener: _resource_record_post_run 17:16:00 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 11014144; Write bytes: 401125376; Read time: 4264; Write time: 267840 17:16:00 INFO - TinderboxPrint: CPU usage
13.1% 17:16:00 INFO - TinderboxPrint: I/O read bytes / time
11,014,144 / 4,264 17:16:00 INFO - TinderboxPrint: I/O write bytes / time
401,125,376 / 267,840 17:16:00 INFO - TinderboxPrint: CPU idle
8,254.4 (86.9%) 17:16:00 INFO - TinderboxPrint: CPU user
1,192.6 (12.6%) 17:16:00 INFO - TinderboxPrint: Swap in / out
0 / 0 17:16:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 73977856; Read time: 164; Write time: 20312 17:16:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:16:00 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 8769536; Write bytes: 299106304; Read time: 3656; Write time: 240216 17:16:01 INFO - Running post-run listener: _upload_blobber_files 17:16:01 INFO - Blob upload gear active. 17:16:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:16:01 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 17:16:01 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'] 17:16:01 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 17:16:01 INFO - (blobuploader) - INFO - Open directory for files ... 17:16:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:16:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:16:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:02 INFO - (blobuploader) - INFO - Done attempting. 17:16:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:16:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:16:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:03 INFO - (blobuploader) - INFO - Done attempting. 17:16:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:16:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:16:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:04 INFO - (blobuploader) - INFO - Done attempting. 17:16:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:16:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:16:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:12 INFO - (blobuploader) - INFO - Done attempting. 17:16:12 INFO - (blobuploader) - INFO - Iteration through files over. 17:16:12 INFO - Return code: 0 17:16:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:16:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:16:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d741ae4a1ee212759362c6c6f4c744c182215148a16bfc400383d639fd4461d882687a10289815bfd8162db3964c8a7552dd8b74dac767274fe25e747b241cc6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e66521acafa225b782c890c701ffedaf106d019a3ed6734e5ca844347c3c28d03ec6ed1177ad08f4c26a9fa8ef0076691b198ed1cb20dba24ccae7c5dd47477", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7fdd8680f1aedfdcf730251856fac1a588b941fd4bae7bcf0dc3a756f630ef0d4a2f5aed348a706c646f9cc052456d5013bb8c8c8f8e3c4b41dfaa44aa482bcf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7000da8f043642c42ff5a5bdadab29d281d4a7ce0016a7c08155045c550d3ddf20ec525f97adf5bcc7819686d492428e7aeda3badd4b9437f1f4dfb40b67662"} 17:16:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:16:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:16:12 INFO - Contents: 17:16:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d741ae4a1ee212759362c6c6f4c744c182215148a16bfc400383d639fd4461d882687a10289815bfd8162db3964c8a7552dd8b74dac767274fe25e747b241cc6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e66521acafa225b782c890c701ffedaf106d019a3ed6734e5ca844347c3c28d03ec6ed1177ad08f4c26a9fa8ef0076691b198ed1cb20dba24ccae7c5dd47477", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7fdd8680f1aedfdcf730251856fac1a588b941fd4bae7bcf0dc3a756f630ef0d4a2f5aed348a706c646f9cc052456d5013bb8c8c8f8e3c4b41dfaa44aa482bcf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7000da8f043642c42ff5a5bdadab29d281d4a7ce0016a7c08155045c550d3ddf20ec525f97adf5bcc7819686d492428e7aeda3badd4b9437f1f4dfb40b67662"} 17:16:12 INFO - Running post-run listener: copy_logs_to_upload_dir 17:16:12 INFO - Copying logs to upload dir... 17:16:12 INFO - mkdir: /builds/slave/test/build/upload/logs 17:16:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.242777 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2017-09-30 17:16:12.443572) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 17:16:12.451299) ========= 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=3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703 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/d741ae4a1ee212759362c6c6f4c744c182215148a16bfc400383d639fd4461d882687a10289815bfd8162db3964c8a7552dd8b74dac767274fe25e747b241cc6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e66521acafa225b782c890c701ffedaf106d019a3ed6734e5ca844347c3c28d03ec6ed1177ad08f4c26a9fa8ef0076691b198ed1cb20dba24ccae7c5dd47477", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7fdd8680f1aedfdcf730251856fac1a588b941fd4bae7bcf0dc3a756f630ef0d4a2f5aed348a706c646f9cc052456d5013bb8c8c8f8e3c4b41dfaa44aa482bcf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7000da8f043642c42ff5a5bdadab29d281d4a7ce0016a7c08155045c550d3ddf20ec525f97adf5bcc7819686d492428e7aeda3badd4b9437f1f4dfb40b67662"} build_url:https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011306 build_url: 'https://queue.taskcluster.net/v1/task/Oy_3fd7dS46Shpt74MAsbg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d741ae4a1ee212759362c6c6f4c744c182215148a16bfc400383d639fd4461d882687a10289815bfd8162db3964c8a7552dd8b74dac767274fe25e747b241cc6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e66521acafa225b782c890c701ffedaf106d019a3ed6734e5ca844347c3c28d03ec6ed1177ad08f4c26a9fa8ef0076691b198ed1cb20dba24ccae7c5dd47477", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7fdd8680f1aedfdcf730251856fac1a588b941fd4bae7bcf0dc3a756f630ef0d4a2f5aed348a706c646f9cc052456d5013bb8c8c8f8e3c4b41dfaa44aa482bcf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c7000da8f043642c42ff5a5bdadab29d281d4a7ce0016a7c08155045c550d3ddf20ec525f97adf5bcc7819686d492428e7aeda3badd4b9437f1f4dfb40b67662"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-30 17:16:12.482802) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:16:12.483142) ========= 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=3297cc2eebb5444a7688c33f000001b5-1506814743.464637-1730287703 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-30 17:16:12.563828) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 17:16:12.564141) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-30 17:16:12.564443) ========= ========= Total master_lag: 0.28 =========