builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-030 starttime: 1506338550.44 results: success (0) revision: 13ce77b78e364cc952d51b959f22202502be2941 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.441157) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.441609) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.441896) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.566092) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.566345) ========= 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=3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-25 04:22:30-- 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% 249K=0.05s 2017-09-25 04:22:30 (249 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.217650 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.842709) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.843019) ========= 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=3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.057476 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.913544) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:30.913892) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 13ce77b78e364cc952d51b959f22202502be2941 --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 13ce77b78e364cc952d51b959f22202502be2941 --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=3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-25 04:22:30,966 truncating revision to first 12 chars 2017-09-25 04:22:30,966 Setting DEBUG logging. 2017-09-25 04:22:30,967 attempt 1/10 2017-09-25 04:22:30,967 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/13ce77b78e36?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-25 04:22:31,208 unpacking tar archive at: mozilla-central-13ce77b78e36/testing/mozharness/ program finished with exit code 0 elapsedTime=0.525965 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.464740) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.465029) ========= script_repo_revision: 13ce77b78e364cc952d51b959f22202502be2941 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.465408) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.465671) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-25 04:22:31.481598) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-25 04:22:31.481874) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:22:31 INFO - MultiFileLogger online at 20170925 04:22:31 in /builds/slave/test 04:22:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:22:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:22:31 INFO - {'append_to_log': False, 04:22:31 INFO - 'base_work_dir': '/builds/slave/test', 04:22:31 INFO - 'blob_upload_branch': 'Firefox', 04:22:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:22:31 INFO - 'branch': 'Firefox', 04:22:31 INFO - 'buildbot_json_path': 'buildprops.json', 04:22:31 INFO - 'code_coverage': False, 04:22:31 INFO - 'config_files': ('talos/linux_config.py',), 04:22:31 INFO - 'default_actions': ('clobber', 04:22:31 INFO - 'read-buildbot-config', 04:22:31 INFO - 'download-and-extract', 04:22:31 INFO - 'populate-webroot', 04:22:31 INFO - 'create-virtualenv', 04:22:31 INFO - 'install', 04:22:31 INFO - 'setup-mitmproxy', 04:22:31 INFO - 'run-tests'), 04:22:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:22:31 INFO - 'disable_ccov_upload': False, 04:22:31 INFO - 'disable_stylo': False, 04:22:31 INFO - 'download_minidump_stackwalk': True, 04:22:31 INFO - 'download_symbols': 'ondemand', 04:22:31 INFO - 'enable_stylo': False, 04:22:31 INFO - 'enable_webrender': False, 04:22:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:22:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:22:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:22:31 INFO - 'gecko_profile': False, 04:22:31 INFO - 'gecko_profile_interval': 0, 04:22:31 INFO - 'installer_path': 'installer.exe', 04:22:31 INFO - 'log_level': 'info', 04:22:31 INFO - 'log_name': 'talos', 04:22:31 INFO - 'log_to_console': True, 04:22:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:22:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:22:31 INFO - 'opt_config_files': (), 04:22:31 INFO - 'pip_index': False, 04:22:31 INFO - 'suite': 'dromaeojs-e10s', 04:22:31 INFO - 'system_bits': '32', 04:22:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:22:31 INFO - 'title': 'talos-linux64-ix-030', 04:22:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:22:31 INFO - 'use_talos_json': True, 04:22:31 INFO - 'verify': 'False', 04:22:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:22:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:22:31 INFO - 'work_dir': 'build'} 04:22:31 INFO - [mozharness: 2017-09-25 11:22:31.740336Z] Running clobber step. 04:22:31 INFO - Running pre-action listener: _resource_record_pre_action 04:22:31 INFO - Running main action method: clobber 04:22:31 INFO - rmtree: /builds/slave/test/build 04:22:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:22:33 INFO - Running post-action listener: _resource_record_post_action 04:22:33 INFO - [mozharness: 2017-09-25 11:22:33.427500Z] Finished clobber step (success) 04:22:33 INFO - [mozharness: 2017-09-25 11:22:33.427597Z] Running read-buildbot-config step. 04:22:33 INFO - Running pre-action listener: _resource_record_pre_action 04:22:33 INFO - Running main action method: read_buildbot_config 04:22:33 INFO - Using buildbot properties: 04:22:33 INFO - { 04:22:33 INFO - "project": "", 04:22:33 INFO - "product": "firefox", 04:22:33 INFO - "who": "archaeopteryx@coole-files.de", 04:22:33 INFO - "installer_path": "public/build/target.tar.bz2", 04:22:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:22:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:22:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:22:33 INFO - "stage_platform": "linux64-pgo", 04:22:33 INFO - "basedir": "/builds/slave/test", 04:22:33 INFO - "buildnumber": 202, 04:22:33 INFO - "platform": "ubuntu64_hw", 04:22:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:22:33 INFO - "slavebuilddir": "test", 04:22:33 INFO - "taskId": "X9RG7MigRZylRh-BohtRXQ", 04:22:33 INFO - "branch": "mozilla-central", 04:22:33 INFO - "script_repo_revision": "production", 04:22:33 INFO - "revision": "13ce77b78e364cc952d51b959f22202502be2941", 04:22:33 INFO - "slavename": "talos-linux64-ix-030", 04:22:33 INFO - "repo_path": "mozilla-central" 04:22:33 INFO - } 04:22:33 INFO - Finding installer, test and symbols from parent task. 04:22:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X9RG7MigRZylRh-BohtRXQ'}, attempt #1 04:22:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X9RG7MigRZylRh-BohtRXQ'}, attempt #1 04:22:34 INFO - Task dependencies: c6Yk_WdkTh-UotDFJcbP0A 04:22:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A'}, attempt #1 04:22:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.tar.bz2 04:22:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.test_packages.json 04:22:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.crashreporter-symbols.zip 04:22:34 INFO - Running post-action listener: _resource_record_post_action 04:22:34 INFO - [mozharness: 2017-09-25 11:22:34.761588Z] Finished read-buildbot-config step (success) 04:22:34 INFO - [mozharness: 2017-09-25 11:22:34.761805Z] Running download-and-extract step. 04:22:34 INFO - Running pre-action listener: _resource_record_pre_action 04:22:34 INFO - Running main action method: download_and_extract 04:22:34 INFO - mkdir: /builds/slave/test/build/tests 04:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:22:34 INFO - trying https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.test_packages.json 04:22:34 INFO - Downloading https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:22:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:22:35 INFO - Downloaded 989 bytes. 04:22:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:22:35 INFO - Using the following test package requirements: 04:22:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:22:35 INFO - u'common': [u'target.common.tests.zip'], 04:22:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:22:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:22:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:22:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:22:35 INFO - u'mozbase': [u'target.common.tests.zip'], 04:22:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:22:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:22:35 INFO - u'web-platform': [u'target.common.tests.zip', 04:22:35 INFO - u'target.web-platform.tests.tar.gz'], 04:22:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:22:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:22:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.common.tests.zip 04:22:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:22:35 INFO - Fetch https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.common.tests.zip into memory 04:22:36 INFO - Content-Length response header: 55344822 04:22:36 INFO - Bytes received: 55344822 04:22:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.talos.tests.zip 04:22:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:22:47 INFO - Fetch https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.talos.tests.zip into memory 04:22:47 INFO - Content-Length response header: 14513823 04:22:47 INFO - Bytes received: 14513823 04:22: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')]} 04:22:48 INFO - trying https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.tar.bz2 04:22:48 INFO - Downloading https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:22:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:22:49 INFO - Downloaded 62299341 bytes. 04:22:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.tar.bz2 04:22:49 INFO - mkdir: /builds/slave/test/properties 04:22:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:22:49 INFO - Writing to file /builds/slave/test/properties/build_url 04:22:49 INFO - Contents: 04:22:49 INFO - build_url:https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.tar.bz2 04:22:49 INFO - Running post-action listener: _resource_record_post_action 04:22:49 INFO - Running post-action listener: find_tests_for_verification 04:22:49 INFO - Running post-action listener: set_extra_try_arguments 04:22:49 INFO - [mozharness: 2017-09-25 11:22:49.329629Z] Finished download-and-extract step (success) 04:22:49 INFO - [mozharness: 2017-09-25 11:22:49.329728Z] Running populate-webroot step. 04:22:49 INFO - Running pre-action listener: _resource_record_pre_action 04:22:49 INFO - Running main action method: populate_webroot 04:22:49 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:22:49 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['tresize', 04:22:49 INFO - 'tcanvasmark']}, 04:22:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:22:49 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['dromaeo_css', 04:22:49 INFO - 'kraken']}, 04:22:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:22:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:22:49 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:49 INFO - 'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:22:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:22:49 INFO - 'tests': ['damp', 'tps']}, 04:22:49 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:49 INFO - 'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['damp', 'tps']}, 04:22:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:22:49 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['dromaeo_dom']}, 04:22:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:22:49 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['basic_compositor_video', 04:22:49 INFO - 'glvideo']}, 04:22:49 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:22:49 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:22:49 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:49 INFO - 'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['ts_paint_webext', 04:22:49 INFO - 'tp5o_webext']}, 04:22:49 INFO - 'other-e10s': {'tests': ['a11yr', 04:22:49 INFO - 'ts_paint', 04:22:49 INFO - 'tpaint', 04:22:49 INFO - 'sessionrestore', 04:22:49 INFO - 'sessionrestore_many_windows', 04:22:49 INFO - 'sessionrestore_no_auto_restore', 04:22:49 INFO - 'tabpaint']}, 04:22:49 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['a11yr', 04:22:49 INFO - 'ts_paint', 04:22:49 INFO - 'tpaint', 04:22:49 INFO - 'sessionrestore', 04:22:49 INFO - 'sessionrestore_many_windows', 04:22:49 INFO - 'sessionrestore_no_auto_restore', 04:22:49 INFO - 'tabpaint']}, 04:22:49 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:22:49 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:22:49 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['perf_reftest_singletons']}, 04:22:49 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['perf_reftest']}, 04:22:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:22:49 INFO - 'tsvgr_opacity', 04:22:49 INFO - 'tart', 04:22:49 INFO - 'tscrollx', 04:22:49 INFO - 'tsvg_static']}, 04:22:49 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['tsvgx', 04:22:49 INFO - 'tsvgr_opacity', 04:22:49 INFO - 'tart', 04:22:49 INFO - 'tscrollx', 04:22:49 INFO - 'tsvg_static']}, 04:22:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:22:49 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:49 INFO - 'talos_options': ['--disable-stylo'], 04:22:49 INFO - 'tests': ['tp5o']}, 04:22:49 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:22:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:22:49 INFO - 'talos_options': ['--mitmproxy', 04:22:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:22:49 INFO - '--firstNonBlankPaint'], 04:22:49 INFO - 'tests': ['tp6_google', 04:22:49 INFO - 'tp6_youtube', 04:22:49 INFO - 'tp6_amazon', 04:22:49 INFO - 'tp6_facebook']}, 04:22:49 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:22:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:22:49 INFO - 'talos_options': ['--disable-stylo', 04:22:49 INFO - '--mitmproxy', 04:22:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:22:49 INFO - '--firstNonBlankPaint'], 04:22:49 INFO - 'tests': ['tp6_google', 04:22:49 INFO - 'tp6_youtube', 04:22:49 INFO - 'tp6_amazon', 04:22:49 INFO - 'tp6_facebook']}, 04:22:49 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:22:49 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:22:49 INFO - 'talos_options': ['--stylo-threads=1', 04:22:49 INFO - '--mitmproxy', 04:22:49 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:22:49 INFO - '--firstNonBlankPaint'], 04:22:49 INFO - 'tests': ['tp6_google', 04:22:49 INFO - 'tp6_youtube', 04:22:49 INFO - 'tp6_amazon', 04:22:49 INFO - 'tp6_facebook']}, 04:22:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:22:49 INFO - 'talos_options': ['--xperf_path', 04:22:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:22:49 INFO - 'tests': ['tp5n']}, 04:22:49 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:22:49 INFO - 'talos_options': ['--disable-stylo', 04:22:49 INFO - '--xperf_path', 04:22:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:22:49 INFO - 'tests': ['tp5n']}}} 04:22:49 INFO - Running post-action listener: _resource_record_post_action 04:22:49 INFO - [mozharness: 2017-09-25 11:22:49.345284Z] Finished populate-webroot step (success) 04:22:49 INFO - [mozharness: 2017-09-25 11:22:49.345374Z] Running create-virtualenv step. 04:22:49 INFO - Running pre-action listener: _resource_record_pre_action 04:22:49 INFO - Running main action method: create_virtualenv 04:22:49 INFO - Creating virtualenv /builds/slave/test/build/venv 04:22:49 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:22:49 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:22:49 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:22:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:22:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:22:50 INFO - Installing distribute.............................................................................................................................................................................................done. 04:22:50 INFO - Installing pip...............done. 04:22:50 INFO - Return code: 0 04:22:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:22:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:22:50 INFO - Reading from file tmpfile_stdout 04:22:50 INFO - Output received: 04:22:50 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:22:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:22: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')]} 04:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22: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')]} 04:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:22: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', 'pip>=1.5'] in /builds/slave/test/build 04:22: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 pip>=1.5 04:22:50 INFO - Using env: {'DISPLAY': ':0', 04:22:50 INFO - 'HOME': '/home/cltbld', 04:22:50 INFO - 'LANG': 'en_US.UTF-8', 04:22:50 INFO - 'LANGUAGE': 'en_US:en', 04:22:50 INFO - 'LOGNAME': 'cltbld', 04:22:50 INFO - 'MAIL': '/var/mail/cltbld', 04:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:50 INFO - 'MOZ_NO_REMOTE': '1', 04:22:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:50 INFO - 'NO_EM_RESTART': '1', 04:22:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:50 INFO - 'PWD': '/builds/slave/test', 04:22:50 INFO - 'SHELL': '/bin/bash', 04:22:50 INFO - 'SHLVL': '1', 04:22:50 INFO - 'TERM': 'linux', 04:22:50 INFO - 'TMOUT': '86400', 04:22:50 INFO - 'USER': 'cltbld', 04:22:50 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:50 INFO - '_': '/tools/buildbot/bin/python'} 04:22:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:22:53 INFO - Downloading/unpacking pip>=1.5 04:22:53 INFO - Running setup.py egg_info for package pip 04:22:53 INFO - warning: no files found matching 'pip/cacert.pem' 04:22:53 INFO - warning: no files found matching '*.html' under directory 'docs' 04:22:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:22:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:22:53 INFO - Installing collected packages: pip 04:22:53 INFO - Found existing installation: pip 1.1 04:22:53 INFO - Uninstalling pip: 04:22:53 INFO - Successfully uninstalled pip 04:22:53 INFO - Running setup.py install for pip 04:22:53 INFO - warning: no files found matching 'pip/cacert.pem' 04:22:53 INFO - warning: no files found matching '*.html' under directory 'docs' 04:22:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:22:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:22:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:22:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:22:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:22:53 INFO - Successfully installed pip 04:22:53 INFO - Cleaning up... 04:22:53 INFO - Return code: 0 04:22:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:22: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')]} 04:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22: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')]} 04:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22: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', '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 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:22: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:22: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 psutil>=3.1.1 04:22:53 INFO - Using env: {'DISPLAY': ':0', 04:22:53 INFO - 'HOME': '/home/cltbld', 04:22:53 INFO - 'LANG': 'en_US.UTF-8', 04:22:53 INFO - 'LANGUAGE': 'en_US:en', 04:22:53 INFO - 'LOGNAME': 'cltbld', 04:22:53 INFO - 'MAIL': '/var/mail/cltbld', 04:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:53 INFO - 'MOZ_NO_REMOTE': '1', 04:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:53 INFO - 'NO_EM_RESTART': '1', 04:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:53 INFO - 'PWD': '/builds/slave/test', 04:22:53 INFO - 'SHELL': '/bin/bash', 04:22:53 INFO - 'SHLVL': '1', 04:22:53 INFO - 'TERM': 'linux', 04:22:53 INFO - 'TMOUT': '86400', 04:22:53 INFO - 'USER': 'cltbld', 04:22:53 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:53 INFO - '_': '/tools/buildbot/bin/python'} 04:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:53 INFO - Downloading/unpacking psutil>=3.1.1 04:22: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 04:22: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 04:22: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 04:22: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 04:22:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:22:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:22:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:22:54 INFO - Installing collected packages: psutil 04:22:54 INFO - Running setup.py install for psutil 04:22:54 INFO - building 'psutil._psutil_linux' extension 04:22:54 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 04:22:55 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 04:22:55 INFO - building 'psutil._psutil_posix' extension 04:22:55 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 04:22:56 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 04:22:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:22:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:22:56 INFO - Successfully installed psutil 04:22:56 INFO - Cleaning up... 04:22:56 INFO - Return code: 0 04:22:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:22: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')]} 04:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22: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')]} 04:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:22: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:22: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 mozsystemmonitor==0.3 04:22:56 INFO - Using env: {'DISPLAY': ':0', 04:22:56 INFO - 'HOME': '/home/cltbld', 04:22:56 INFO - 'LANG': 'en_US.UTF-8', 04:22:56 INFO - 'LANGUAGE': 'en_US:en', 04:22:56 INFO - 'LOGNAME': 'cltbld', 04:22:56 INFO - 'MAIL': '/var/mail/cltbld', 04:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:56 INFO - 'MOZ_NO_REMOTE': '1', 04:22:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:56 INFO - 'NO_EM_RESTART': '1', 04:22:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:56 INFO - 'PWD': '/builds/slave/test', 04:22:56 INFO - 'SHELL': '/bin/bash', 04:22:56 INFO - 'SHLVL': '1', 04:22:56 INFO - 'TERM': 'linux', 04:22:56 INFO - 'TMOUT': '86400', 04:22:56 INFO - 'USER': 'cltbld', 04:22:56 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:56 INFO - '_': '/tools/buildbot/bin/python'} 04:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:22: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 04:22: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 04:22: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 04:22: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 04:22:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:22:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:22:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:22:57 INFO - Installing collected packages: mozsystemmonitor 04:22:57 INFO - Running setup.py install for mozsystemmonitor 04:22:57 INFO - Successfully installed mozsystemmonitor 04:22:57 INFO - Cleaning up... 04:22:57 INFO - Return code: 0 04:22:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:22: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')]} 04:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:22: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')]} 04:22:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:22: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', '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 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:22: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:22: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 jsonschema==2.5.1 04:22:57 INFO - Using env: {'DISPLAY': ':0', 04:22:57 INFO - 'HOME': '/home/cltbld', 04:22:57 INFO - 'LANG': 'en_US.UTF-8', 04:22:57 INFO - 'LANGUAGE': 'en_US:en', 04:22:57 INFO - 'LOGNAME': 'cltbld', 04:22:57 INFO - 'MAIL': '/var/mail/cltbld', 04:22:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:22:57 INFO - 'MOZ_NO_REMOTE': '1', 04:22:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:22:57 INFO - 'NO_EM_RESTART': '1', 04:22:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:22:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:22:57 INFO - 'PWD': '/builds/slave/test', 04:22:57 INFO - 'SHELL': '/bin/bash', 04:22:57 INFO - 'SHLVL': '1', 04:22:57 INFO - 'TERM': 'linux', 04:22:57 INFO - 'TMOUT': '86400', 04:22:57 INFO - 'USER': 'cltbld', 04:22:57 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:22:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:22:57 INFO - '_': '/tools/buildbot/bin/python'} 04:22:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:22:57 INFO - Downloading/unpacking jsonschema==2.5.1 04:22: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 04:22: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 04:22: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 04:22: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 04:23:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:23:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:00 INFO - Downloading functools32-3.2.3-2.tar.gz 04:23:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:23:00 INFO - warning: no files found matching '*.txt' 04:23:00 INFO - no previously-included directories found matching 'build' 04:23:00 INFO - no previously-included directories found matching 'dist' 04:23:00 INFO - no previously-included directories found matching '.git*' 04:23:00 INFO - Installing collected packages: jsonschema, functools32 04:23:00 INFO - Running setup.py install for functools32 04:23:00 INFO - warning: no files found matching '*.txt' 04:23:00 INFO - no previously-included directories found matching 'build' 04:23:00 INFO - no previously-included directories found matching 'dist' 04:23:00 INFO - no previously-included directories found matching '.git*' 04:23:00 INFO - Successfully installed jsonschema functools32 04:23:00 INFO - Cleaning up... 04:23:00 INFO - Return code: 0 04:23:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:23:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:23:00 INFO - Using env: {'DISPLAY': ':0', 04:23:00 INFO - 'HOME': '/home/cltbld', 04:23:00 INFO - 'LANG': 'en_US.UTF-8', 04:23:00 INFO - 'LANGUAGE': 'en_US:en', 04:23:00 INFO - 'LOGNAME': 'cltbld', 04:23:00 INFO - 'MAIL': '/var/mail/cltbld', 04:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:00 INFO - 'MOZ_NO_REMOTE': '1', 04:23:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:00 INFO - 'NO_EM_RESTART': '1', 04:23:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:00 INFO - 'PWD': '/builds/slave/test', 04:23:00 INFO - 'SHELL': '/bin/bash', 04:23:00 INFO - 'SHLVL': '1', 04:23:00 INFO - 'TERM': 'linux', 04:23:00 INFO - 'TMOUT': '86400', 04:23:00 INFO - 'USER': 'cltbld', 04:23:00 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:00 INFO - '_': '/tools/buildbot/bin/python'} 04:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:23:00 INFO - Cleaning up... 04:23:00 INFO - Return code: 0 04:23:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:23:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:23:00 INFO - Using env: {'DISPLAY': ':0', 04:23:00 INFO - 'HOME': '/home/cltbld', 04:23:00 INFO - 'LANG': 'en_US.UTF-8', 04:23:00 INFO - 'LANGUAGE': 'en_US:en', 04:23:00 INFO - 'LOGNAME': 'cltbld', 04:23:00 INFO - 'MAIL': '/var/mail/cltbld', 04:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:00 INFO - 'MOZ_NO_REMOTE': '1', 04:23:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:00 INFO - 'NO_EM_RESTART': '1', 04:23:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:00 INFO - 'PWD': '/builds/slave/test', 04:23:00 INFO - 'SHELL': '/bin/bash', 04:23:00 INFO - 'SHLVL': '1', 04:23:00 INFO - 'TERM': 'linux', 04:23:00 INFO - 'TMOUT': '86400', 04:23:00 INFO - 'USER': 'cltbld', 04:23:00 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:00 INFO - '_': '/tools/buildbot/bin/python'} 04:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:00 INFO - Downloading/unpacking blobuploader==1.2.4 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:01 INFO - Downloading blobuploader-1.2.4.tar.gz 04:23:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:23:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:23:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:02 INFO - Downloading docopt-0.6.1.tar.gz 04:23:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:23:02 INFO - Installing collected packages: blobuploader, requests, docopt 04:23:02 INFO - Running setup.py install for blobuploader 04:23:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:23:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:23:02 INFO - Running setup.py install for requests 04:23:03 INFO - Running setup.py install for docopt 04:23:03 INFO - Successfully installed blobuploader requests docopt 04:23:03 INFO - Cleaning up... 04:23:03 INFO - Return code: 0 04:23:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:03 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')]} 04:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:03 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')]} 04:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:03 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 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:23:03 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 04:23:03 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 04:23:03 INFO - Using env: {'DISPLAY': ':0', 04:23:03 INFO - 'HOME': '/home/cltbld', 04:23:03 INFO - 'LANG': 'en_US.UTF-8', 04:23:03 INFO - 'LANGUAGE': 'en_US:en', 04:23:03 INFO - 'LOGNAME': 'cltbld', 04:23:03 INFO - 'MAIL': '/var/mail/cltbld', 04:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:03 INFO - 'MOZ_NO_REMOTE': '1', 04:23:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:03 INFO - 'NO_EM_RESTART': '1', 04:23:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:03 INFO - 'PWD': '/builds/slave/test', 04:23:03 INFO - 'SHELL': '/bin/bash', 04:23:03 INFO - 'SHLVL': '1', 04:23:03 INFO - 'TERM': 'linux', 04:23:03 INFO - 'TMOUT': '86400', 04:23:03 INFO - 'USER': 'cltbld', 04:23:03 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:03 INFO - '_': '/tools/buildbot/bin/python'} 04:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:03 INFO - Running setup.py (path:/tmp/pip-f9gMhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:03 INFO - Running setup.py (path:/tmp/pip-1vt8He-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:03 INFO - Running setup.py (path:/tmp/pip-TpIGnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:03 INFO - Running setup.py (path:/tmp/pip-fGbDkg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:03 INFO - Running setup.py (path:/tmp/pip-KUvpQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:03 INFO - Running setup.py (path:/tmp/pip-6m2dw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:03 INFO - Running setup.py (path:/tmp/pip-Gs7ydq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:03 INFO - Running setup.py (path:/tmp/pip-aZsLuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:03 INFO - Running setup.py (path:/tmp/pip-WV26xl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:04 INFO - Running setup.py (path:/tmp/pip-EMITfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:04 INFO - Running setup.py (path:/tmp/pip-qGuxoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:04 INFO - Running setup.py (path:/tmp/pip-08i5hZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:04 INFO - Running setup.py (path:/tmp/pip-5CoLjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:04 INFO - Running setup.py (path:/tmp/pip-twadyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:04 INFO - Running setup.py (path:/tmp/pip-cmr5G4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:04 INFO - Running setup.py (path:/tmp/pip-k0YDCg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:04 INFO - Running setup.py (path:/tmp/pip-EDyq1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:23:04 INFO - Running setup.py install for manifestparser 04:23:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:23:04 INFO - Running setup.py install for mozcrash 04:23:04 INFO - Running setup.py install for mozdebug 04:23:04 INFO - Running setup.py install for mozdevice 04:23:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Running setup.py install for mozfile 04:23:05 INFO - Running setup.py install for mozhttpd 04:23:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Running setup.py install for mozinfo 04:23:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Running setup.py install for mozInstall 04:23:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Running setup.py install for mozleak 04:23:05 INFO - Running setup.py install for mozlog 04:23:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Running setup.py install for moznetwork 04:23:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Running setup.py install for mozprocess 04:23:05 INFO - Running setup.py install for mozprofile 04:23:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:23:05 INFO - Running setup.py install for mozrunner 04:23:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:23:06 INFO - Running setup.py install for mozscreenshot 04:23:06 INFO - Running setup.py install for moztest 04:23:06 INFO - Running setup.py install for mozversion 04:23:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:23:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:23:06 INFO - Cleaning up... 04:23:06 INFO - Return code: 0 04:23:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:06 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 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:23:06 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 04:23:06 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 04:23:06 INFO - Using env: {'DISPLAY': ':0', 04:23:06 INFO - 'HOME': '/home/cltbld', 04:23:06 INFO - 'LANG': 'en_US.UTF-8', 04:23:06 INFO - 'LANGUAGE': 'en_US:en', 04:23:06 INFO - 'LOGNAME': 'cltbld', 04:23:06 INFO - 'MAIL': '/var/mail/cltbld', 04:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:06 INFO - 'MOZ_NO_REMOTE': '1', 04:23:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:06 INFO - 'NO_EM_RESTART': '1', 04:23:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:06 INFO - 'PWD': '/builds/slave/test', 04:23:06 INFO - 'SHELL': '/bin/bash', 04:23:06 INFO - 'SHLVL': '1', 04:23:06 INFO - 'TERM': 'linux', 04:23:06 INFO - 'TMOUT': '86400', 04:23:06 INFO - 'USER': 'cltbld', 04:23:06 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:06 INFO - '_': '/tools/buildbot/bin/python'} 04:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:06 INFO - Running setup.py (path:/tmp/pip-gTpISp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:06 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)) 04:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:06 INFO - Running setup.py (path:/tmp/pip-Q2apps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:06 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)) 04:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:06 INFO - Running setup.py (path:/tmp/pip-xbCSqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:06 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)) 04:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:06 INFO - Running setup.py (path:/tmp/pip-J4XDJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:06 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)) 04:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:06 INFO - Running setup.py (path:/tmp/pip-uVd_qP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:06 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)) 04:23:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:06 INFO - Running setup.py (path:/tmp/pip-40h6Sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:07 INFO - Running setup.py (path:/tmp/pip-u0MNWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:07 INFO - Running setup.py (path:/tmp/pip-3Qe0ZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:07 INFO - Running setup.py (path:/tmp/pip-VDl2hJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:07 INFO - Running setup.py (path:/tmp/pip-2_QN4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:07 INFO - Running setup.py (path:/tmp/pip-Jd3_Nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:07 INFO - Running setup.py (path:/tmp/pip-BJrHsP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:07 INFO - Running setup.py (path:/tmp/pip-EaP6Qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:07 INFO - Running setup.py (path:/tmp/pip-56rUfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:07 INFO - Running setup.py (path:/tmp/pip-1KetEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:07 INFO - Running setup.py (path:/tmp/pip-fvx120-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:07 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)) 04:23:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:07 INFO - Running setup.py (path:/tmp/pip-vr4rnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:07 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)) 04:23:07 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)) 04:23:07 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)) 04:23:07 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)) 04:23:07 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)) 04:23:07 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)) 04:23:07 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)) 04:23:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:08 INFO - Downloading blessings-1.6.tar.gz 04:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:23:09 INFO - Installing collected packages: blessings 04:23:09 INFO - Running setup.py install for blessings 04:23:09 INFO - Successfully installed blessings 04:23:09 INFO - Cleaning up... 04:23:09 INFO - Return code: 0 04:23:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:23:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:09 INFO - Reading from file tmpfile_stdout 04:23:09 INFO - Current package versions: 04:23:09 INFO - argparse == 1.2.1 04:23:09 INFO - blessings == 1.6 04:23:09 INFO - blobuploader == 1.2.4 04:23:09 INFO - docopt == 0.6.1 04:23:09 INFO - functools32 == 3.2.3-2 04:23:09 INFO - jsonschema == 2.5.1 04:23:09 INFO - manifestparser == 1.1 04:23:09 INFO - mozInstall == 1.13 04:23:09 INFO - mozcrash == 1.0 04:23:09 INFO - mozdebug == 0.1 04:23:09 INFO - mozdevice == 0.51 04:23:09 INFO - mozfile == 1.2 04:23:09 INFO - mozhttpd == 0.7 04:23:09 INFO - mozinfo == 0.10 04:23:09 INFO - mozleak == 0.1 04:23:09 INFO - mozlog == 3.5 04:23:09 INFO - moznetwork == 0.27 04:23:09 INFO - mozprocess == 0.25 04:23:09 INFO - mozprofile == 0.28 04:23:09 INFO - mozrunner == 6.13 04:23:09 INFO - mozscreenshot == 0.1 04:23:09 INFO - mozsystemmonitor == 0.3 04:23:09 INFO - moztest == 0.8 04:23:09 INFO - mozversion == 1.4 04:23:09 INFO - psutil == 3.1.1 04:23:09 INFO - requests == 1.2.3 04:23:09 INFO - wsgiref == 0.1.2 04:23:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:09 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 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:23:09 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 04:23:09 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 04:23:09 INFO - Using env: {'DISPLAY': ':0', 04:23:09 INFO - 'HOME': '/home/cltbld', 04:23:09 INFO - 'LANG': 'en_US.UTF-8', 04:23:09 INFO - 'LANGUAGE': 'en_US:en', 04:23:09 INFO - 'LOGNAME': 'cltbld', 04:23:09 INFO - 'MAIL': '/var/mail/cltbld', 04:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:09 INFO - 'MOZ_NO_REMOTE': '1', 04:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:09 INFO - 'NO_EM_RESTART': '1', 04:23:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:09 INFO - 'PWD': '/builds/slave/test', 04:23:09 INFO - 'SHELL': '/bin/bash', 04:23:09 INFO - 'SHLVL': '1', 04:23:09 INFO - 'TERM': 'linux', 04:23:09 INFO - 'TMOUT': '86400', 04:23:09 INFO - 'USER': 'cltbld', 04:23:09 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:09 INFO - '_': '/tools/buildbot/bin/python'} 04:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:09 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)) 04:23:09 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)) 04:23:09 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)) 04:23:09 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)) 04:23:09 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)) 04:23:09 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)) 04:23:09 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)) 04:23:09 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)) 04:23:09 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)) 04:23:09 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:23:09 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 04:23:09 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 04:23:09 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 04:23:09 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 04:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:23:10 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)) 04:23:10 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)) 04:23:10 INFO - Installing collected packages: simplejson 04:23:10 INFO - Running setup.py install for simplejson 04:23:10 INFO - building 'simplejson._speedups' extension 04:23:10 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 04:23:11 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 04:23:11 INFO - Successfully installed simplejson 04:23:11 INFO - Cleaning up... 04:23:11 INFO - Return code: 0 04:23:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:11 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 0xaa21f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc6ab70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe0f9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, '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': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', '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 04:23:11 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 04:23:11 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 04:23:11 INFO - Using env: {'DISPLAY': ':0', 04:23:11 INFO - 'HOME': '/home/cltbld', 04:23:11 INFO - 'LANG': 'en_US.UTF-8', 04:23:11 INFO - 'LANGUAGE': 'en_US:en', 04:23:11 INFO - 'LOGNAME': 'cltbld', 04:23:11 INFO - 'MAIL': '/var/mail/cltbld', 04:23:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:11 INFO - 'MOZ_NO_REMOTE': '1', 04:23:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:11 INFO - 'NO_EM_RESTART': '1', 04:23:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:11 INFO - 'PWD': '/builds/slave/test', 04:23:11 INFO - 'SHELL': '/bin/bash', 04:23:11 INFO - 'SHLVL': '1', 04:23:11 INFO - 'TERM': 'linux', 04:23:11 INFO - 'TMOUT': '86400', 04:23:11 INFO - 'USER': 'cltbld', 04:23:11 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:23:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:11 INFO - '_': '/tools/buildbot/bin/python'} 04:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:23:12 INFO - Cleaning up... 04:23:12 INFO - Return code: 0 04:23:12 INFO - Running post-action listener: _resource_record_post_action 04:23:12 INFO - Running post-action listener: _start_resource_monitoring 04:23:12 INFO - Starting resource monitoring. 04:23:12 INFO - [mozharness: 2017-09-25 11:23:12.153726Z] Finished create-virtualenv step (success) 04:23:12 INFO - [mozharness: 2017-09-25 11:23:12.154806Z] Running install step. 04:23:12 INFO - Running pre-action listener: _resource_record_pre_action 04:23:12 INFO - Running main action method: install 04:23:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:12 INFO - Reading from file tmpfile_stdout 04:23:12 INFO - Detecting whether we're running mozinstall >=1.0... 04:23:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:23:12 INFO - Reading from file tmpfile_stdout 04:23:12 INFO - Output received: 04:23:12 INFO - Usage: mozinstall [options] installer 04:23:12 INFO - Options: 04:23:12 INFO - -h, --help show this help message and exit 04:23:12 INFO - -d DEST, --destination=DEST 04:23:12 INFO - Directory to install application into. [default: 04:23:12 INFO - "/builds/slave/test"] 04:23:12 INFO - --app=APP Application being installed. [default: firefox] 04:23:12 INFO - mkdir: /builds/slave/test/build/application 04:23:12 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'] 04:23:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:23:30 INFO - Reading from file tmpfile_stdout 04:23:30 INFO - Output received: 04:23:30 INFO - /builds/slave/test/build/application/firefox/firefox 04:23:30 INFO - Running post-action listener: _resource_record_post_action 04:23:30 INFO - [mozharness: 2017-09-25 11:23:30.287560Z] Finished install step (success) 04:23:30 INFO - [mozharness: 2017-09-25 11:23:30.287810Z] Running setup-mitmproxy step. 04:23:30 INFO - Running pre-action listener: _resource_record_pre_action 04:23:30 INFO - Running main action method: setup_mitmproxy 04:23:30 INFO - Skipping: mitmproxy is not required 04:23:30 INFO - Running post-action listener: _resource_record_post_action 04:23:30 INFO - [mozharness: 2017-09-25 11:23:30.288936Z] Finished setup-mitmproxy step (success) 04:23:30 INFO - [mozharness: 2017-09-25 11:23:30.289156Z] Running run-tests step. 04:23:30 INFO - Running pre-action listener: _resource_record_pre_action 04:23:30 INFO - Running pre-action listener: _set_gcov_prefix 04:23:30 INFO - Running main action method: run_tests 04:23:30 WARNING - Try message not found. 04:23:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:23:30 INFO - Python 2.7.3 04:23:30 INFO - Return code: 0 04:23:30 INFO - grabbing minidump binary from tooltool 04:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:23:30 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 0xe0cd60>, '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 0xe0da60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe0def0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:23:30 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 04:23:30 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 04:23:30 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 04:23:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:23:30 INFO - Return code: 0 04:23:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:23:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:23:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:23:30 INFO - ENV: RUST_BACKTRACE is now full 04:23:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:23:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:23:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 04:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 04:23:30 INFO - Using env: {'DISPLAY': ':0', 04:23:30 INFO - 'HOME': '/home/cltbld', 04:23:30 INFO - 'LANG': 'en_US.UTF-8', 04:23:30 INFO - 'LANGUAGE': 'en_US:en', 04:23:30 INFO - 'LOGNAME': 'cltbld', 04:23:30 INFO - 'MAIL': '/var/mail/cltbld', 04:23:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:23:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:30 INFO - 'MOZ_NO_REMOTE': '1', 04:23:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:23:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:30 INFO - 'NO_EM_RESTART': '1', 04:23:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:30 INFO - 'PWD': '/builds/slave/test', 04:23:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:23:30 INFO - 'RUST_BACKTRACE': 'full', 04:23:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:23:30 INFO - 'SHELL': '/bin/bash', 04:23:30 INFO - 'SHLVL': '1', 04:23:30 INFO - 'TERM': 'linux', 04:23:30 INFO - 'TMOUT': '86400', 04:23:30 INFO - 'USER': 'cltbld', 04:23:30 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:30 INFO - '_': '/tools/buildbot/bin/python'} 04:23:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 04:23:30 INFO - mozversion application_buildid: 20170925095628 04:23:30 INFO - mozversion application_changeset: 13ce77b78e364cc952d51b959f22202502be2941 04:23:30 INFO - mozversion application_display_name: Nightly 04:23:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:23:30 INFO - mozversion application_name: Firefox 04:23:30 INFO - mozversion application_remotingname: firefox 04:23:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:23:30 INFO - mozversion application_vendor: Mozilla 04:23:30 INFO - mozversion application_version: 58.0a1 04:23:30 INFO - mozversion platform_buildid: 20170925095628 04:23:30 INFO - mozversion platform_changeset: 13ce77b78e364cc952d51b959f22202502be2941 04:23:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:23:30 INFO - mozversion platform_version: 58.0a1 04:23:30 INFO - using testdate: 1506338610 04:23:30 INFO - actual date: 1506338610 04:23:30 INFO - starting webserver on 'localhost:56253' 04:23:30 INFO - SUITE-START | Running 2 tests 04:23:30 INFO - TEST-START | dromaeo_css 04:23:30 INFO - Initialising browser for dromaeo_css test... 04:23:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56253/getInfo.html -profile /tmp/tmp9Xsr2Q/profile 04:23:30 INFO - TEST-INFO | started process 3605 (/builds/slave/test/build/application/firefox/firefox http://localhost:56253/getInfo.html) 04:23:38 INFO - TEST-INFO | 3605: exit 0 04:23:38 INFO - Browser initialized. 04:23:38 INFO - Running cycle 1/1 for dromaeo_css test... 04:23:38 INFO - Using env: {'DISPLAY': ':0', 04:23:38 INFO - 'HOME': '/home/cltbld', 04:23:38 INFO - 'JSGC_DISABLE_POISONING': '1', 04:23:38 INFO - 'LANG': 'en_US.UTF-8', 04:23:38 INFO - 'LANGUAGE': 'en_US:en', 04:23:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:23:38 INFO - 'LOGNAME': 'cltbld', 04:23:38 INFO - 'MAIL': '/var/mail/cltbld', 04:23:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:23:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:23:38 INFO - 'MOZ_CRASHREPORTER': '1', 04:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:23:38 INFO - 'MOZ_NO_REMOTE': '1', 04:23:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:23:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:23:38 INFO - 'NO_EM_RESTART': '1', 04:23:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:38 INFO - 'PWD': '/builds/slave/test', 04:23:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:23:38 INFO - 'RUST_BACKTRACE': 'full', 04:23:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:23:38 INFO - 'SHELL': '/bin/bash', 04:23:38 INFO - 'SHLVL': '1', 04:23:38 INFO - 'TERM': 'linux', 04:23:38 INFO - 'TMOUT': '86400', 04:23:38 INFO - 'USER': 'cltbld', 04:23:38 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:38 INFO - '_': '/tools/buildbot/bin/python'} 04:23:38 INFO - TEST-INFO | started process 3837 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9Xsr2Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:23:38 INFO - PID 3837 | 04:23:38 INFO - PID 3837 | (/builds/slave/test/build/application/firefox/firefox:3886): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:23:38 INFO - PID 3837 | 04:23:49 INFO - PID 3837 | RSS: Main: 141328384 04:23:49 INFO - PID 3837 | 04:26:46 INFO - PID 3837 | Cycle 1(1): loaded http://localhost:56253/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56253/tests/dromaeo/cssquery-ext.html) 04:26:47 INFO - PID 3837 | RSS: Main: 146165760 04:26:47 INFO - PID 3837 | 04:29:49 INFO - PID 3837 | Cycle 1(1): loaded http://localhost:56253/tests/dromaeo/cssquery-ext.html (next: http://localhost:56253/tests/dromaeo/cssquery-jquery.html) 04:29:50 INFO - PID 3837 | RSS: Main: 146124800 04:29:50 INFO - PID 3837 | 04:32:51 INFO - PID 3837 | Cycle 1(1): loaded http://localhost:56253/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56253/tests/dromaeo/cssquery-mootools.html) 04:32:52 INFO - PID 3837 | RSS: Main: 147828736 04:32:52 INFO - PID 3837 | 04:35:53 INFO - PID 3837 | Cycle 1(1): loaded http://localhost:56253/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56253/tests/dromaeo/cssquery-prototype.html) 04:35:54 INFO - PID 3837 | RSS: Main: 146153472 04:35:54 INFO - PID 3837 | 04:38:57 INFO - PID 3837 | Cycle 1(1): loaded http://localhost:56253/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56253/tests/dromaeo/cssquery-yui.html) 04:38:58 INFO - PID 3837 | RSS: Main: 145735680 04:38:58 INFO - PID 3837 | 04:41:59 INFO - PID 3837 | Cycle 1(1): loaded http://localhost:56253/tests/dromaeo/cssquery-yui.html (next: http://localhost:56253/tests/dromaeo/cssquery-dojo.html) 04:41:59 INFO - PID 3837 | RSS: Main: 150433792 04:41:59 INFO - PID 3837 | 04:41:59 INFO - PID 3837 | __start_tp_report 04:41:59 INFO - PID 3837 | _x_x_mozilla_page_load 04:41:59 INFO - PID 3837 | _x_x_mozilla_page_load_details 04:41:59 INFO - PID 3837 | |i|pagename|runs| 04:41:59 INFO - PID 3837 | |0;dojo.html;3879.670228030617;3882.55350634677;3884.0678237223074;3899.7465164764308;3922.176342967977;6263.467605253548;6275.466585340251;6294;6310.148130002448;6383.80848574543;6278.686065696708;6354.078658594501;6370.3311152328915;6389.488840892729;6520.543561950665;5641.576881733866;5783.016887129188;5788.82633520995;5798.391168927265;5807.303123625171;817.8703361956616;5664.065429204176;5766.365699773021;5801.245838041049;5844.26946631671;5668.291463567054;5677.602567820254;5700.37295897453;5714.399988001283;5739.684317362543;5671.319441667027;5675.659460432346;5762.279715035621;5768.105943578752;5769.509591684712;5668.319801623831;5674.347450043257;5738.995675756768;5761.971190144022;5764.7117644117625;6326.68366581669;6345.365463453664;6362.5546211765195;6419.518536109764;6423.132877061584;6734.562253453559;6815.352541508549;6819.420349270318;6926.757563485254;7023.92976070243;6948.131483564554;6966.164060312744;6967.442604591621;6970.024196612428;6976.441884649216;6282.371762823681;6432.646204458753;6508.088867558546;6533.052707357454;6556.573822701557;7107.466939979418;7109.92890071103;7185.568865868065;7196.532225405332;7208;6754.155730533684;6820.113385259983;6870.244273129952;6928;6937.9653101735175;6816.182058152955;6939.826504337432;6947.89578156328;6988.301169883072;7024.1571011478945;6917.342852429011;6963.547369420971;6965.756198533027;6974.197967233732;6979.232284448706;6875.518713690094;6885.586864788129;6974.511784175161;6977.581345119309;6982.581045137308;4961.355023846877;5026.84919452417;5056.140456122468;5090.872728181825;5093.363329583802;4504.436945381827;4572.154151481986;4609.3086037094035;4621.2837010263465;4635.814567417266;6337.556371054075;6339.953907605193;6358.968205158944;6412.551121421549;6418.133551970424;6843;6846.4522838172825;6930.029795828588;6934.791956241321;6968.059311992917;6203.31763506014;6267.655278959701;6287.434130928238;6356.173697419306;6382.138411314413;5574.247476590689;5634.464725851038;5656.604037717402;5661.179129026268;5661.207430959669;6987.615681137586;6999.12510936133;7085.82285442868;7204.56772593646;7248.746293879689;6784.185897692309;6861.279565645636;6980;6987.02181694553;6991.370776630128;7083.043789088407;7133.500654954103;7148.749793757193;7194.604296763729;7272.345488906024;6856.280090590616;6876.17485901675;6879.036934829028;6882.793516194522;6882.8623427532175;6878.518503704692;6918.930810691829;6934.063901373452;6936.7572134975035;6980.057692211486;6562.376574225441;6649.900251496329;6727.831804204934;6738.831529211809;6739.66301684904;6623.569467984566;6627.105340778933;6703.162104736908;6718.832029199114;6723.125993620798;6502.4472919800455;6548.934510654833;6622.238442579165;6628.602283862983;6691.5315927884585;6685.732570697118;6734.124563806674;6743.865122697421;6780.084688566981;6794.082798822095;6480.157579514633;6486;6491.772787952588;6570.310117437601;6570.5729127606555 04:41:59 INFO - PID 3837 | |1;ext.html;942.3121121581247;963.7397902566307;966.2704657983221;967.5452537307463;969.4667932637047;44055.559444405546;44729.552704472946;44941.32588011182;44990;45021.774891125584;42001.789991050005;42170.57829421705;42244.155116897644;42306.365404518954;42520.57479425205;7683.8079048023965;7847.725329613465;8239.134890836465;8391.874121888177;8407.831843363128;8533.615987280587;8640.481571105722;8658.220760131619;8668.826623467527;8674.653013879437;8660.220580147785;8675.739727808144;8728.738137855842;8736.170063843925;8746.825063498727;8577.185167409087;8629.180227878403;8651.95674021629;8658.307335413183;8692.956535217316;8291.792705182359;8327.83344333116;8343.791405214857;8398.202170793766;8404.579771011486;41933.790331048454;42041.369379459335;42073.368899466535;42082;42150.78924605388;3843.692504599653;3850.9229815403814;3873.767573945544;3875.069983204039;3887.300285948528;34587.82706086453;34613;34648.826755866314;34671.653283467094;34772.82613586916;5498;5507.697076660822;5523.613347065707;5541;5635.380108188096;214052;216339;217918;218575;218686;47584.5241547587;48545.75727121342;54319.72840135774;54349.45650543523;54583;58059;58125.41874581284;58680;58715;58744.7062764692;17794.644107117787;17843;17915.283388664568;17919.552011199823;17920.372786952656;14351.78472322916;14456.060356076821;14474.276286185648;14492.202928838804;14511.419543218364;15791.763123553155;15815.36738530469;15896.841031589767;15919.363225471083;15957.840421595636;6207.441330280206;6329.556931014785;6343.572696143331;6401.29585745577;6412.070249813751;1109.3953795181646;1116.9162312826409;1118.8992990630882;1121.517747368623;1122.3939072900541;26140.738592614212;26272.73727262741;26445.206643800717;26478.33804154873;26646;1212.53923509066;1218.3908045977012;1219.5975328141692;1236.042067397756;1239.8388209532884;13895.166290022631;13969.860301397059;14047.08693934891;14091.577252682437;14155.858441415454;285860;286904;288241;288984;289210;60272.69863650654;60535.39464605298;60989;61237.38762612406;61721.382786172464;1536.216529569905;1538.8845836562302;1551.3794482207206;1562.3360071969369;1576.5428025872598;25314.367140821625;25522.983278527714;25642.871785640953;25667.3583160415;25691.871540642176;42384;42425.15149696927;42469.787651061546;42504.57495425006;42575.574244257165;226586;226651;230103;230492;230603;38055.429168563045;40635.390469142396;41203.7939810311;41551.79224103981;41604.37593436161;51599;51621.7418912903;51976.440706778856;52263.21605175849;52487.47512524826;37269.4409583851;37299.81350093232;37355.626443735215;37638.2472350546;37816.62183378131;36489.27021459503;36692.44961325529;36798.63201367952;36882;37196.62803371932;42218.57781422146;42931;43511.347329789445;43606.12787744164;43873.34189987217;35232;35723.28553428969;35944.46083308805;35977.460338094425;36383.45424818683;26508.03235900303;26568.468630627667;26811;27351.316217094733;27491.450170996068 04:41:59 INFO - PID 3837 | |2;jquery.html;598.5271635408027;599.8500374906273;601.912722655215;609.9329073801883;616.6546733829056;23319.650205246933;24363.26910192697;24365.14721984731;24378;24413.413128146658;24739.505209895793;26259.3435164121;26309;26392.944282228735;26415.867920660417;6560.803175904719;6713.563618364815;6719.832004199885;6726.764563240287;6743.8651226975435;6519.185101862267;6573.112629794964;6693.029510720942;6788.287229840869;6811.046453496514;6760.39156475917;6765.932340676604;6766.7969960901255;6767.830804229909;6769.255381907986;6450;6660.533762636617;6676.899846502306;6763.628000459973;6766.492513061516;6220.533459990519;6222.439980401786;6235;6244.344343843876;6255.06174073888;29898.40203195924;30448.54327185094;30731.846340768378;30775.846120769478;30803.229919252197;2218.234709025384;2227.3318004598555;2231.4086767006756;2234.0728597631964;2244.8989795459242;20480.897595511928;21359;21521.67717484239;21526.031328590252;21533.13867445316;2535.492901419705;2540.174443305933;2608.6217498462806;2615.9215223543324;2623.7638612524966;136009;143796;154682;162575.1871240648;162650;15801;16039.839601604068;16362.427315043917;16719.83280167207;16790.83209167917;26043.479130417287;26109.477810443685;26134.51722086399;26266.60600091;26340.341491462867;11019.393933333744;11045.226834121695;11157.832632510519;11167.05080068187;11244.26912250701;9976.05227503386;10102.343347682377;10154.441505717108;10263.435511046815;10319.329243599143;9873.654422095193;9894.427448863347;9937.254705896941;10054;10308.690739277834;3067.708567686066;3098.5662007318992;3104.463290671132;3140.089374081491;3181.109289398972;1035.425338936882;1052.85786418834;1054.2936232724094;1054.6519648516125;1060.6446840308429;20777.58444831095;21521.354359369245;21558;21745.23891663784;21795.782042179377;1096.4791723931069;1115.1914861725184;1116.3078891087578;1122.960696375623;1138.066785236115;11504.76990460186;11634.418279086012;11672.241304315192;11755.353455560027;11800.17398782077;575700;584278;586840;600649;611606;123476.38261808634;125133;127337.36331318284;128257.35871320583;128609;1123.1015187849687;1173.724174818917;1234.216272666863;1247.9188852724726;1312.9474821007243;22248.55502889901;22619.547609048055;22876;23297.76702232956;23306;64345;65083.34916650773;65413.01880471892;66077;67246.66376668085;80573;81842;82131;82419.5879020601;82595.5870220645;20511.077001535265;20888.268910588056;21045.684314735598;21257.255996040065;21361.572768544855;23581.174658886855;23587;24243.151489697775;24482.877585611957;24535;19634.50913727168;19714.60570788605;19895.801041989394;19937.401877943685;20081.899590501955;16901.154942252593;16924.74612880783;17320.91339543294;17719.20263588116;17834.28662853471;19865.00674966217;19881.801181988572;19937.501562461053;20426.38720838377;20429.79570204279;20580.176792928116;20600.793992060488;20726.378208653765;20751.8962405187;20792.688109678063;19300.613987719888;19383.612327753082;19488.415347539598;19508.414747557596;19584.216631335155 04:41:59 INFO - PID 3837 | |3;mootools.html;1377.3251106957591;1416.9291535423226;1427.5503216486802;1444.1984698492333;1450.514077783943;16710.832891671078;17326.913365433185;17337.566560835985;17348;17349.739753903687;16875.324987000506;16972.15139243036;17015.82984170158;17044.914775426107;17047.403340883073;2714.8642567871625;2851.5009873272197;2851.8146320489204;2852.1728698677357;2852.9286767830763;2603.1539749581366;2712.688040875295;2716.2258756254473;2716.3209197700576;2717.184844546638;2778.9305267368277;2839.801213915027;2866.6846646868826;2866.9426611467757;2878.8704508297074;2596.961045584299;2651.3636727185335;2690.0315886280923;2723.237493501823;2735.8221715588525;2551.5152121097117;2875.971240287612;2881.0924558763923;2881.985590072057;2883.1062370665163;16725.330986760517;16741.162941852857;16755;16793.83206167935;16926.83073169277;8440.831183376298;8768.386212965095;8772.736817895473;8775.385722999392;8779.517126558037;5206.504688706289;5273.2617433559335;5378.838634840961;5394.460553944614;5447.373552041527;2014.5020174983063;2053.9281125160696;2106.5997460482613;2122.843050537461;2127.744670639533;30353.848230758926;30462.39075218483;30521.38957220843;30658.233544161576;30672.69327306743;6914.965425172842;7253.310935461122;7257;7263.455240856957;7410.370118539877;19588.21647134127;19614.803851961584;19623.705644415346;19723.211071557263;19744.90127549382;7854.764357069297;7919;7944.324732397811;7944.761657150179;7950.443468957117;7247.383972362303;7255.637218139072;7478.9252107479315;7617.847643047108;7801.141874393812;5451.236826844245;5683.431656834284;5707.429257074343;5712.314522257356;5763.6541807491685;1530.9157996310087;1531.525227179578;1548.3651702801797;1548.7289724298203;1571.1829848478726;914.6158613382395;947.9905200947902;950.0024973777644;952.33812500313;955.7945041815904;8230.21812927771;8414;8416.831663366698;8460.238578527838;8483.915160848435;791.291793844506;809.6801763303462;814.0150417994175;814.1126172472034;828.4615000249886;5797.623154494944;5805.651660900359;5809.564282678816;5844.766209351578;6003.519718422515;24495.142670006466;24929.750702492744;25135.120270790434;25627.10305139311;25673.871630641726;23539.29382118539;24994.125205617715;25287.620685689362;25512.362190945005;25630.359241019123;12748.49006039748;12860.421281042376;12921.612351629467;13031.478740850449;13042.608721738363;8325.54209518458;8493.405461617875;8522.829543408972;8571.100034496289;8577.571121444027;20750.481237969172;21080.156793728693;21119;21146.577068458853;21688.891555542123;21685.132594696035;21979.780202198413;22309.330720078422;22337.32988010362;22493.887530562242;12233.877661223274;12465.252084874937;12483.063770217088;12483.31341776211;12486.250824950532;14935.701285974003;15579.922100389878;15664.686706265584;15696.21518924072;15703.21483925822;12172.57395991136;12184.451699673713;12208.32854193028;12270.81593776112;12619.81070283928;11598.420078996185;11747.17769756143;11748.236364636272;11800.409979500819;12057.216280941711;11136.33182009082;11261.7747645045;11296.943515282372;11374.772504550028;11379.08967282632;7784.688612455665;7861.567613781297;7953.32396746283;7981.281684648177;7986.161453047579;7866.13472518034;7983.441159118806;8000.799980000546;8075.757727268191;8104.878426823484 04:41:59 INFO - PID 3837 | |4;prototype.html;541.5667466027177;553.1453903718755;565.3442007271565;574.2764117212312;574.9564540359248;6304.401081897224;6386.776462823802;6410.916452153051;6432.839179020527;6455.806325810233;6287.59130656507;6289.937100628992;6309.810705678826;6319.6524191169465;6321.367863213676;4940.752962351886;5067.366579177603;5092.236164575306;5097.260897169908;5114.567920982113;5083.822066227683;5095.261187127864;5106.387233531981;5110.872228194306;5116.641835071547;5026.170681837492;5041.773120209581;5067.467915868836;5080.618953578478;5083.796648134089;5042.924356134661;5056.747162641872;5059.342285502861;5068.163752980754;5116.846494605168;4616.699914505546;4619.86140415788;4662.743549104798;4688.859334219979;4694.788734506956;6203.100550420164;6251.12484252205;6340.556161068727;6340.619562826245;6405.583637063576;5920.5559583031445;5930.525557955396;5949.167116603679;5951.940480595182;6023.427774361428;6621.86756264872;6669.23303820062;6681.164854393201;6763.425108865703;6771.153605799275;5095.923561146586;5127.641065125443;5145.125328694131;5150.227465880111;5163.8192663256605;6955.652217389161;6972.51192416531;6974.930250697479;7007.789766307019;7079.504434689626;5280.419153893069;5331.360236771612;5335.06636338647;5335.973320133452;5353.250544923714;5402.568319395364;5406.864828379322;5425.294711687534;5429.294191755125;5458;5891.734871930774;5901.911471327933;5935.73289201987;5954.612950158225;5963.522918166536;6572.047053177263;6604.174478190226;6678.565893216925;6756.662166891636;6764.289749576224;1811.8731688781922;1863.4782260966952;1864.7109697996832;1867.2251015828278;1872.101391332168;1158.29922896648;1321.2336844630092;1336.62574479146;1337.8394592648945;1340.470514146926;6138.5396095292135;6167.568270221132;6228.750849966042;6277.905831412532;6327.462165715966;6816.829579260559;6844.21291551468;6864.279250678607;6881.587104773729;6895.310468953165;6275.278342990615;6308.52686048539;6317.820351978652;6321.336259692666;6330.335314791972;3582.480540321691;3587.3542762303036;3592.6586974237407;3594.2452085062428;3628.564572251347;7030.929690703129;7097.290270972861;7128.322809333209;7166.390856777128;7167.319104685047;6930.44556435484;7003.809352410102;7022.5435346703325;7117;7141.571505709674;7064.964675176591;7081.150261968597;7119.2168861426235;7126.536775109601;7186.778247697904;6661.134052573135;6675.532712710064;6677.432418244503;6748.763793267212;6822.249552549315;6772.71318449493;6830.31696830313;6867.931320686929;6917.896231556631;6924.4114250287275;7026.543274687129;7072.823179420556;7134.1795693495915;7134.250903655042;7147.499675022698;5231.607629427732;5234.162533994542;5259.4477579853565;5277.393099793573;5280.577553795764;6817.250102488623;6825.668994546016;6838.247792742894;6919.481038922202;6927.757528486479;6570.901436478361;6618.8345291368105;6623.801285961429;6633.270340262664;6701.865962680621;6588.242352129567;6599.119251013438;6643.57855704303;6701.698423571049;6707.5975441473665;6438.806835794933;6616.900746488903;6639.240601240772;6654.866902661823;6686.765963191265;6088.360722124113;6495.415412612888;6514.837129071811;6636.402723754885;6689.832754181184;6317.810465686037;6383.457406120346;6475.676216189077;6494.610323380423;6561.212654481493 04:41:59 INFO - PID 3837 | |5;yui.html;948.506776476232;977.9608815647372;981.1022914033659;984.2224642532395;998.8152191844507;20514.692279615818;21055.578888422224;21058.368248952538;21067.367978960618;21080.156793728253;18929.33747318844;18981.33565325214;19035.524111897208;19101.61796764064;19595.706064409045;1007.2043085962099;1030.1346868630349;1033.4729288063068;1034.131329683066;1036.808190484759;1039.50103950104;1061.4745700878077;1064.4092528646597;1067.3862529045791;1069;1117.854678891747;1137.5677242647782;1137.744007598292;1137.8634563852308;1138.7267055906605;1060.6128763001525;1110.6001839337912;1116.542217690743;1123.376526028054;1127.7518945831903;805.3516918880299;808.7129069180415;809.2918696140877;809.8137428391503;810.4448452809844;21213.257535986322;21314.893425532773;21411.14355425781;21511.247106351202;21540.676889846665;21779.891100544555;22805.543889122124;22813.42966425836;22816.771832281796;22884.77115228843;18919.86028043437;18955.147018384207;19084.522886927938;19222.03889805504;19241.61516769671;3976.5029371328583;4017.2969730297373;4032.334664780308;4033.7983100845136;4035.293823580891;157362;159121;160607.1969640156;163566.1821690896;163880;17652.911735441496;17668.469945901645;17982.190801413966;18005.819941800677;18073.18670659797;34161.658383415845;34167.658323416945;34453.138671533416;34460.31079378398;35023.4746478803;9641.469719165514;9676.951615241878;9680.370775899544;9693.273004524688;9704.9029509704;4945.9258111128365;5035.974820125949;5047;5052.646314758005;5067.290579318898;6107.603005804608;6243.125962365272;6248.31268560458;6272.18461599989;6276.309605943343;1073.5652060915343;1090.3621381491917;1123.9044681202026;1124.2860783402598;1126.1328776841785;741.8590467811099;752.3792870881545;771.6411868481183;772.4284029818007;778.2567647896245;12678.175918565265;13185.406656700472;13533.39099740514;14253.572392828231;14328.641783955485;715.6707914359453;724.2359310927022;740.825905912121;748.472327009457;748.9812754681177;5975.043992961106;6023.578349515492;6139.263288405331;6170.907436388457;6179.907301390483;143713;144111;145144;149210.25394873173;150648;124734.37632811778;126023.36988315;126980;127148.36425817996;133779.33110334387;106364.46817765862;107778.46110769553;109662.45168774265;113307;113945;14406.135631862122;14520.128792272497;14671.266436678337;14779.92610036943;14842.554723358317;51926.74036629793;52130.218046730086;52580.21129682981;52938.2059269119;54361.72819135879;65287;65714;66068.33931660622;66703.33296667166;67143.32856671566;21103.8944805275;21378.89310553437;21492.24777132793;21521.6771748427;21599.892000540527;21722.34832955014;21827.890860545594;21998.670019949393;22059.007344669208;22215.555688886456;22112.88943555272;22490.662640060084;22500.43748906225;22692.319230423112;22752.544949101255;19788.90105549463;19949.501262468555;19995.700064498753;20008.199672012957;20064.69902951486;20481.89759051195;20599.897000515502;20701.792982069986;20779.272725455143;20816.89591552093;14171.433142674177;14440.277986100864;14637.194954277616;14798.704025919205;14878.628034299229;14822.40710371573;15155.46955856584;15245.16151611672;15257.771133432785;15345.386184552493 04:41:59 INFO - PID 3837 | __end_tp_report 04:41:59 INFO - PID 3837 | __start_cc_report 04:41:59 INFO - PID 3837 | _x_x_mozilla_cycle_collect,281 04:41:59 INFO - PID 3837 | __end_cc_report 04:41:59 INFO - PID 3837 | __startTimestamp1506339719784__endTimestamp 04:41:59 INFO - PID 3837 | 04:41:59 INFO - PID 3837 | ------- Summary: start ------- 04:41:59 INFO - PID 3837 | Number of tests: 6 04:41:59 INFO - PID 3837 | 04:41:59 INFO - PID 3837 | [#0] dojo.html Cycles:165 Average:6329.84 Median:6622.24 stddev:872.71 (13.2%) stddev-sans-first:853.96 04:41:59 INFO - PID 3837 | Values: 3879.7 3882.6 3884.1 3899.7 3922.2 6263.5 6275.5 6294.0 6310.1 6383.8 6278.7 6354.1 6370.3 6389.5 6520.5 5641.6 5783.0 5788.8 5798.4 5807.3 817.9 5664.1 5766.4 5801.2 5844.3 5668.3 5677.6 5700.4 5714.4 5739.7 5671.3 5675.7 5762.3 5768.1 5769.5 5668.3 5674.3 5739.0 5762.0 5764.7 6326.7 6345.4 6362.6 6419.5 6423.1 6734.6 6815.4 6819.4 6926.8 7023.9 6948.1 6966.2 6967.4 6970.0 6976.4 6282.4 6432.6 6508.1 6533.1 6556.6 7107.5 7109.9 7185.6 7196.5 7208.0 6754.2 6820.1 6870.2 6928.0 6938.0 6816.2 6939.8 6947.9 6988.3 7024.2 6917.3 6963.5 6965.8 6974.2 6979.2 6875.5 6885.6 6974.5 6977.6 6982.6 4961.4 5026.8 5056.1 5090.9 5093.4 4504.4 4572.2 4609.3 4621.3 4635.8 6337.6 6340.0 6359.0 6412.6 6418.1 6843.0 6846.5 6930.0 6934.8 6968.1 6203.3 6267.7 6287.4 6356.2 6382.1 5574.2 5634.5 5656.6 5661.2 5661.2 6987.6 6999.1 7085.8 7204.6 7248.7 6784.2 6861.3 6980.0 6987.0 6991.4 7083.0 7133.5 7148.7 7194.6 7272.3 6856.3 6876.2 6879.0 6882.8 6882.9 6878.5 6918.9 6934.1 6936.8 6980.1 6562.4 6649.9 6727.8 6738.8 6739.7 6623.6 6627.1 6703.2 6718.8 6723.1 6502.4 6548.9 6622.2 6628.6 6691.5 6685.7 6734.1 6743.9 6780.1 6794.1 6480.2 6486.0 6491.8 6570.3 6570.6 04:41:59 INFO - PID 3837 | 04:41:59 INFO - PID 3837 | [#1] ext.html Cycles:180 Average:43374.91 Median:22261.35 stddev:64041.92 (287.7%) stddev-sans-first:64142.32 04:41:59 INFO - PID 3837 | Values: 942.3 963.7 966.3 967.5 969.5 44055.6 44729.6 44941.3 44990.0 45021.8 42001.8 42170.6 42244.2 42306.4 42520.6 7683.8 7847.7 8239.1 8391.9 8407.8 8533.6 8640.5 8658.2 8668.8 8674.7 8660.2 8675.7 8728.7 8736.2 8746.8 8577.2 8629.2 8652.0 8658.3 8693.0 8291.8 8327.8 8343.8 8398.2 8404.6 41933.8 42041.4 42073.4 42082.0 42150.8 3843.7 3850.9 3873.8 3875.1 3887.3 34587.8 34613.0 34648.8 34671.7 34772.8 5498.0 5507.7 5523.6 5541.0 5635.4 214052.0 216339.0 217918.0 218575.0 218686.0 47584.5 48545.8 54319.7 54349.5 54583.0 58059.0 58125.4 58680.0 58715.0 58744.7 17794.6 17843.0 17915.3 17919.6 17920.4 14351.8 14456.1 14474.3 14492.2 14511.4 15791.8 15815.4 15896.8 15919.4 15957.8 6207.4 6329.6 6343.6 6401.3 6412.1 1109.4 1116.9 1118.9 1121.5 1122.4 26140.7 26272.7 26445.2 26478.3 26646.0 1212.5 1218.4 1219.6 1236.0 1239.8 13895.2 13969.9 14047.1 14091.6 14155.9 285860.0 286904.0 288241.0 288984.0 289210.0 60272.7 60535.4 60989.0 61237.4 61721.4 1536.2 1538.9 1551.4 1562.3 1576.5 25314.4 25523.0 25642.9 25667.4 25691.9 42384.0 42425.2 42469.8 42504.6 42575.6 226586.0 226651.0 230103.0 230492.0 230603.0 38055.4 40635.4 41203.8 41551.8 41604.4 51599.0 51621.7 51976.4 52263.2 52487.5 37269.4 37299.8 37355.6 37638.2 37816.6 36489.3 36692.4 36798.6 36882.0 37196.6 42218.6 42931.0 43511.3 43606.1 43873.3 35232.0 35723.3 35944.5 35977.5 36383.5 26508.0 26568.5 26811.0 27351.3 27491.5 04:41:59 INFO - PID 3837 | 04:41:59 INFO - PID 3837 | [#2] jquery.html Cycles:180 Average:39922.92 Median:2239.49 stddev:99086.20 (4424.5%) stddev-sans-first:99320.17 04:41:59 INFO - PID 3837 | Values: 598.5 599.9 601.9 609.9 616.7 23319.7 24363.3 24365.1 24378.0 24413.4 24739.5 26259.3 26309.0 26392.9 26415.9 6560.8 6713.6 6719.8 6726.8 6743.9 6519.2 6573.1 6693.0 6788.3 6811.0 6760.4 6765.9 6766.8 6767.8 6769.3 6450.0 6660.5 6676.9 6763.6 6766.5 6220.5 6222.4 6235.0 6244.3 6255.1 29898.4 30448.5 30731.8 30775.8 30803.2 2218.2 2227.3 2231.4 2234.1 2244.9 20480.9 21359.0 21521.7 21526.0 21533.1 2535.5 2540.2 2608.6 2615.9 2623.8 136009.0 143796.0 154682.0 162575.2 162650.0 15801.0 16039.8 16362.4 16719.8 16790.8 26043.5 26109.5 26134.5 26266.6 26340.3 11019.4 11045.2 11157.8 11167.1 11244.3 9976.1 10102.3 10154.4 10263.4 10319.3 9873.7 9894.4 9937.3 10054.0 10308.7 3067.7 3098.6 3104.5 3140.1 3181.1 1035.4 1052.9 1054.3 1054.7 1060.6 20777.6 21521.4 21558.0 21745.2 21795.8 1096.5 1115.2 1116.3 1123.0 1138.1 11504.8 11634.4 11672.2 11755.4 11800.2 575700.0 584278.0 586840.0 600649.0 611606.0 123476.4 125133.0 127337.4 128257.4 128609.0 1123.1 1173.7 1234.2 1247.9 1312.9 22248.6 22619.5 22876.0 23297.8 23306.0 64345.0 65083.3 65413.0 66077.0 67246.7 80573.0 81842.0 82131.0 82419.6 82595.6 20511.1 20888.3 21045.7 21257.3 21361.6 23581.2 23587.0 24243.2 24482.9 24535.0 19634.5 19714.6 19895.8 19937.4 20081.9 16901.2 16924.7 17320.9 17719.2 17834.3 19865.0 19881.8 19937.5 20426.4 20429.8 20580.2 20600.8 20726.4 20751.9 20792.7 19300.6 19383.6 19488.4 19508.4 19584.2 04:41:59 INFO - PID 3837 | 04:41:59 INFO - PID 3837 | [#3] mootools.html Cycles:180 Average:10349.49 Median:2713.78 stddev:7781.16 (286.7%) stddev-sans-first:7773.79 04:41:59 INFO - PID 3837 | Values: 1377.3 1416.9 1427.6 1444.2 1450.5 16710.8 17326.9 17337.6 17348.0 17349.7 16875.3 16972.2 17015.8 17044.9 17047.4 2714.9 2851.5 2851.8 2852.2 2852.9 2603.2 2712.7 2716.2 2716.3 2717.2 2778.9 2839.8 2866.7 2866.9 2878.9 2597.0 2651.4 2690.0 2723.2 2735.8 2551.5 2876.0 2881.1 2882.0 2883.1 16725.3 16741.2 16755.0 16793.8 16926.8 8440.8 8768.4 8772.7 8775.4 8779.5 5206.5 5273.3 5378.8 5394.5 5447.4 2014.5 2053.9 2106.6 2122.8 2127.7 30353.8 30462.4 30521.4 30658.2 30672.7 6915.0 7253.3 7257.0 7263.5 7410.4 19588.2 19614.8 19623.7 19723.2 19744.9 7854.8 7919.0 7944.3 7944.8 7950.4 7247.4 7255.6 7478.9 7617.8 7801.1 5451.2 5683.4 5707.4 5712.3 5763.7 1530.9 1531.5 1548.4 1548.7 1571.2 914.6 948.0 950.0 952.3 955.8 8230.2 8414.0 8416.8 8460.2 8483.9 791.3 809.7 814.0 814.1 828.5 5797.6 5805.7 5809.6 5844.8 6003.5 24495.1 24929.8 25135.1 25627.1 25673.9 23539.3 24994.1 25287.6 25512.4 25630.4 12748.5 12860.4 12921.6 13031.5 13042.6 8325.5 8493.4 8522.8 8571.1 8577.6 20750.5 21080.2 21119.0 21146.6 21688.9 21685.1 21979.8 22309.3 22337.3 22493.9 12233.9 12465.3 12483.1 12483.3 12486.3 14935.7 15579.9 15664.7 15696.2 15703.2 12172.6 12184.5 12208.3 12270.8 12619.8 11598.4 11747.2 11748.2 11800.4 12057.2 11136.3 11261.8 11296.9 11374.8 11379.1 7784.7 7861.6 7953.3 7981.3 7986.2 7866.1 7983.4 8000.8 8075.8 8104.9 04:41:59 INFO - PID 3837 | 04:41:59 INFO - PID 3837 | [#4] prototype.html Cycles:180 Average:5714.48 Median:6320.49 stddev:1592.14 (25.2%) stddev-sans-first:1548.54 04:41:59 INFO - PID 3837 | Values: 541.6 553.1 565.3 574.3 575.0 6304.4 6386.8 6410.9 6432.8 6455.8 6287.6 6289.9 6309.8 6319.7 6321.4 4940.8 5067.4 5092.2 5097.3 5114.6 5083.8 5095.3 5106.4 5110.9 5116.6 5026.2 5041.8 5067.5 5080.6 5083.8 5042.9 5056.7 5059.3 5068.2 5116.8 4616.7 4619.9 4662.7 4688.9 4694.8 6203.1 6251.1 6340.6 6340.6 6405.6 5920.6 5930.5 5949.2 5951.9 6023.4 6621.9 6669.2 6681.2 6763.4 6771.2 5095.9 5127.6 5145.1 5150.2 5163.8 6955.7 6972.5 6974.9 7007.8 7079.5 5280.4 5331.4 5335.1 5336.0 5353.3 5402.6 5406.9 5425.3 5429.3 5458.0 5891.7 5901.9 5935.7 5954.6 5963.5 6572.0 6604.2 6678.6 6756.7 6764.3 1811.9 1863.5 1864.7 1867.2 1872.1 1158.3 1321.2 1336.6 1337.8 1340.5 6138.5 6167.6 6228.8 6277.9 6327.5 6816.8 6844.2 6864.3 6881.6 6895.3 6275.3 6308.5 6317.8 6321.3 6330.3 3582.5 3587.4 3592.7 3594.2 3628.6 7030.9 7097.3 7128.3 7166.4 7167.3 6930.4 7003.8 7022.5 7117.0 7141.6 7065.0 7081.2 7119.2 7126.5 7186.8 6661.1 6675.5 6677.4 6748.8 6822.2 6772.7 6830.3 6867.9 6917.9 6924.4 7026.5 7072.8 7134.2 7134.3 7147.5 5231.6 5234.2 5259.4 5277.4 5280.6 6817.3 6825.7 6838.2 6919.5 6927.8 6570.9 6618.8 6623.8 6633.3 6701.9 6588.2 6599.1 6643.6 6701.7 6707.6 6438.8 6616.9 6639.2 6654.9 6686.8 6088.4 6495.4 6514.8 6636.4 6689.8 6317.8 6383.5 6475.7 6494.6 6561.2 04:41:59 INFO - PID 3837 | 04:41:59 INFO - PID 3837 | [#5] yui.html Cycles:180 Average:28450.14 Median:20936.24 stddev:41237.58 (197.0%) stddev-sans-first:41301.56 04:41:59 INFO - PID 3837 | Values: 948.5 978.0 981.1 984.2 998.8 20514.7 21055.6 21058.4 21067.4 21080.2 18929.3 18981.3 19035.5 19101.6 19595.7 1007.2 1030.1 1033.5 1034.1 1036.8 1039.5 1061.5 1064.4 1067.4 1069.0 1117.9 1137.6 1137.7 1137.9 1138.7 1060.6 1110.6 1116.5 1123.4 1127.8 805.4 808.7 809.3 809.8 810.4 21213.3 21314.9 21411.1 21511.2 21540.7 21779.9 22805.5 22813.4 22816.8 22884.8 18919.9 18955.1 19084.5 19222.0 19241.6 3976.5 4017.3 4032.3 4033.8 4035.3 157362.0 159121.0 160607.2 163566.2 163880.0 17652.9 17668.5 17982.2 18005.8 18073.2 34161.7 34167.7 34453.1 34460.3 35023.5 9641.5 9677.0 9680.4 9693.3 9704.9 4945.9 5036.0 5047.0 5052.6 5067.3 6107.6 6243.1 6248.3 6272.2 6276.3 1073.6 1090.4 1123.9 1124.3 1126.1 741.9 752.4 771.6 772.4 778.3 12678.2 13185.4 13533.4 14253.6 14328.6 715.7 724.2 740.8 748.5 749.0 5975.0 6023.6 6139.3 6170.9 6179.9 143713.0 144111.0 145144.0 149210.3 150648.0 124734.4 126023.4 126980.0 127148.4 133779.3 106364.5 107778.5 109662.5 113307.0 113945.0 14406.1 14520.1 14671.3 14779.9 14842.6 51926.7 52130.2 52580.2 52938.2 54361.7 65287.0 65714.0 66068.3 66703.3 67143.3 21103.9 21378.9 21492.2 21521.7 21599.9 21722.3 21827.9 21998.7 22059.0 22215.6 22112.9 22490.7 22500.4 22692.3 22752.5 19788.9 19949.5 19995.7 20008.2 20064.7 20481.9 20599.9 20701.8 20779.3 20816.9 14171.4 14440.3 14637.2 14798.7 14878.6 14822.4 15155.5 15245.2 15257.8 15345.4 04:41:59 INFO - PID 3837 | -------- Summary: end -------- 04:41:59 INFO - PID 3837 | 04:42:00 INFO - TEST-INFO | 3837: exit 0 04:42:00 INFO - TEST-OK | dromaeo_css | took 1109729ms 04:42:00 INFO - TEST-START | kraken 04:42:00 INFO - Initialising browser for kraken test... 04:42:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56253/getInfo.html -profile /tmp/tmpFVeyE3/profile 04:42:00 INFO - TEST-INFO | started process 4216 (/builds/slave/test/build/application/firefox/firefox http://localhost:56253/getInfo.html) 04:42:05 INFO - TEST-INFO | 4216: exit 0 04:42:06 INFO - Browser initialized. 04:42:06 INFO - Running cycle 1/1 for kraken test... 04:42:06 INFO - Using env: {'DISPLAY': ':0', 04:42:06 INFO - 'HOME': '/home/cltbld', 04:42:06 INFO - 'JSGC_DISABLE_POISONING': '1', 04:42:06 INFO - 'LANG': 'en_US.UTF-8', 04:42:06 INFO - 'LANGUAGE': 'en_US:en', 04:42:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:42:06 INFO - 'LOGNAME': 'cltbld', 04:42:06 INFO - 'MAIL': '/var/mail/cltbld', 04:42:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:42:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:42:06 INFO - 'MOZ_CRASHREPORTER': '1', 04:42:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:42:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:42:06 INFO - 'MOZ_NO_REMOTE': '1', 04:42:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:42:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:42:06 INFO - 'NO_EM_RESTART': '1', 04:42:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:42:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:42:06 INFO - 'PWD': '/builds/slave/test', 04:42:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:42:06 INFO - 'RUST_BACKTRACE': 'full', 04:42:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:42:06 INFO - 'SHELL': '/bin/bash', 04:42:06 INFO - 'SHLVL': '1', 04:42:06 INFO - 'TERM': 'linux', 04:42:06 INFO - 'TMOUT': '86400', 04:42:06 INFO - 'USER': 'cltbld', 04:42:06 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138', 04:42:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:42:06 INFO - '_': '/tools/buildbot/bin/python'} 04:42:06 INFO - TEST-INFO | started process 4455 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFVeyE3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:42:06 INFO - PID 4455 | 04:42:06 INFO - PID 4455 | (/builds/slave/test/build/application/firefox/firefox:4506): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:42:06 INFO - PID 4455 | 04:42:17 INFO - PID 4455 | RSS: Main: 144474112 04:42:17 INFO - PID 4455 | 04:42:19 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56253/tests/kraken/driver.html?testName=audio-beat-detection) 04:42:20 INFO - PID 4455 | RSS: Main: 150786048 04:42:20 INFO - PID 4455 | 04:42:23 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56253/tests/kraken/driver.html?testName=audio-dft) 04:42:23 INFO - PID 4455 | RSS: Main: 151248896 04:42:23 INFO - PID 4455 | 04:42:26 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56253/tests/kraken/driver.html?testName=audio-fft) 04:42:27 INFO - PID 4455 | RSS: Main: 147742720 04:42:27 INFO - PID 4455 | 04:42:29 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56253/tests/kraken/driver.html?testName=audio-oscillator) 04:42:29 INFO - PID 4455 | RSS: Main: 148525056 04:42:29 INFO - PID 4455 | 04:42:31 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56253/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:42:32 INFO - PID 4455 | RSS: Main: 148455424 04:42:32 INFO - PID 4455 | 04:42:35 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56253/tests/kraken/driver.html?testName=imaging-darkroom) 04:42:36 INFO - PID 4455 | RSS: Main: 148348928 04:42:36 INFO - PID 4455 | 04:42:39 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56253/tests/kraken/driver.html?testName=imaging-desaturate) 04:42:40 INFO - PID 4455 | RSS: Main: 149676032 04:42:40 INFO - PID 4455 | 04:42:43 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56253/tests/kraken/driver.html?testName=json-parse-financial) 04:42:44 INFO - PID 4455 | RSS: Main: 151642112 04:42:44 INFO - PID 4455 | 04:42:45 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56253/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:42:46 INFO - PID 4455 | RSS: Main: 151638016 04:42:46 INFO - PID 4455 | 04:42:47 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56253/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:42:48 INFO - PID 4455 | RSS: Main: 151826432 04:42:48 INFO - PID 4455 | 04:42:50 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56253/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:42:51 INFO - PID 4455 | RSS: Main: 148656128 04:42:51 INFO - PID 4455 | 04:42:53 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:42:54 INFO - PID 4455 | RSS: Main: 148713472 04:42:54 INFO - PID 4455 | 04:42:56 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:42:57 INFO - PID 4455 | RSS: Main: 149323776 04:42:57 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | Cycle 1(1): loaded http://localhost:56253/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56253/tests/kraken/driver.html?testName=ai-astar) 04:42:59 INFO - PID 4455 | RSS: Main: 150581248 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | __start_tp_report 04:42:59 INFO - PID 4455 | _x_x_mozilla_page_load 04:42:59 INFO - PID 4455 | _x_x_mozilla_page_load_details 04:42:59 INFO - PID 4455 | |i|pagename|runs| 04:42:59 INFO - PID 4455 | |0;ai-astar;105;105;105;107;108;115;104;105;106;106 04:42:59 INFO - PID 4455 | |1;audio-beat-detection;116;125;113;113;115;115;134;116;111;111 04:42:59 INFO - PID 4455 | |2;audio-dft;164;163;162;161;160;160;163;161;164;167 04:42:59 INFO - PID 4455 | |3;audio-fft;74;75;73;74;74;73;73;73;74;76 04:42:59 INFO - PID 4455 | |4;audio-oscillator;79;79;79;77;77;77;79;77;77;78 04:42:59 INFO - PID 4455 | |5;imaging-gaussian-blur;113;116;114;113;110;115;114;113;114;114 04:42:59 INFO - PID 4455 | |6;imaging-darkroom;149;149;149;151;149;152;150;149;149;151 04:42:59 INFO - PID 4455 | |7;imaging-desaturate;95;96;94;94;100;94;95;96;97;97 04:42:59 INFO - PID 4455 | |8;json-parse-financial;48;48;45;45;44;50;47;46;49;46 04:42:59 INFO - PID 4455 | |9;json-stringify-tinderbox;39;39;39;39;39;39;39;39;38;38 04:42:59 INFO - PID 4455 | |10;stanford-crypto-aes;71;72;70;71;71;80;75;74;73;69 04:42:59 INFO - PID 4455 | |11;stanford-crypto-ccm;108;106;102;118;112;105;104;112;106;113 04:42:59 INFO - PID 4455 | |12;stanford-crypto-pbkdf2;155;150;156;155;150;150;151;152;152;154 04:42:59 INFO - PID 4455 | |13;stanford-crypto-sha256-iterative;63;68;64;64;62;58;58;60;58;58 04:42:59 INFO - PID 4455 | __end_tp_report 04:42:59 INFO - PID 4455 | __start_cc_report 04:42:59 INFO - PID 4455 | _x_x_mozilla_cycle_collect,732 04:42:59 INFO - PID 4455 | __end_cc_report 04:42:59 INFO - PID 4455 | __startTimestamp1506339779348__endTimestamp 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | ------- Summary: start ------- 04:42:59 INFO - PID 4455 | Number of tests: 14 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#0] ai-astar Cycles:10 Average:106.60 Median:106.00 stddev:3.17 (3.0%) stddev-sans-first:3.31 04:42:59 INFO - PID 4455 | Values: 105.0 105.0 105.0 107.0 108.0 115.0 104.0 105.0 106.0 106.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#1] audio-beat-detection Cycles:10 Average:116.90 Median:115.50 stddev:7.20 (6.2%) stddev-sans-first:7.63 04:42:59 INFO - PID 4455 | Values: 116.0 125.0 113.0 113.0 115.0 115.0 134.0 116.0 111.0 111.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#2] audio-dft Cycles:10 Average:162.50 Median:163.00 stddev:2.17 (1.3%) stddev-sans-first:2.24 04:42:59 INFO - PID 4455 | Values: 164.0 163.0 162.0 161.0 160.0 160.0 163.0 161.0 164.0 167.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#3] audio-fft Cycles:10 Average:73.90 Median:74.00 stddev:0.99 (1.3%) stddev-sans-first:1.05 04:42:59 INFO - PID 4455 | Values: 74.0 75.0 73.0 74.0 74.0 73.0 73.0 73.0 74.0 76.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:78.50 stddev:0.99 (1.3%) stddev-sans-first:0.97 04:42:59 INFO - PID 4455 | Values: 79.0 79.0 79.0 77.0 77.0 77.0 79.0 77.0 77.0 78.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#5] imaging-gaussian-blur Cycles:10 Average:113.60 Median:114.00 stddev:1.58 (1.4%) stddev-sans-first:1.66 04:42:59 INFO - PID 4455 | Values: 113.0 116.0 114.0 113.0 110.0 115.0 114.0 113.0 114.0 114.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#6] imaging-darkroom Cycles:10 Average:149.80 Median:149.50 stddev:1.14 (0.8%) stddev-sans-first:1.17 04:42:59 INFO - PID 4455 | Values: 149.0 149.0 149.0 151.0 149.0 152.0 150.0 149.0 149.0 151.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:95.50 stddev:1.87 (2.0%) stddev-sans-first:1.96 04:42:59 INFO - PID 4455 | Values: 95.0 96.0 94.0 94.0 100.0 94.0 95.0 96.0 97.0 97.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#8] json-parse-financial Cycles:10 Average:46.80 Median:47.50 stddev:1.93 (4.1%) stddev-sans-first:2.00 04:42:59 INFO - PID 4455 | Values: 48.0 48.0 45.0 45.0 44.0 50.0 47.0 46.0 49.0 46.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#9] json-stringify-tinderbox Cycles:10 Average:38.80 Median:39.00 stddev:0.42 (1.1%) stddev-sans-first:0.44 04:42:59 INFO - PID 4455 | Values: 39.0 39.0 39.0 39.0 39.0 39.0 39.0 39.0 38.0 38.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:72.50 stddev:3.17 (4.4%) stddev-sans-first:3.31 04:42:59 INFO - PID 4455 | Values: 71.0 72.0 70.0 71.0 71.0 80.0 75.0 74.0 73.0 69.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#11] stanford-crypto-ccm Cycles:10 Average:108.60 Median:110.00 stddev:4.97 (4.5%) stddev-sans-first:5.27 04:42:59 INFO - PID 4455 | Values: 108.0 106.0 102.0 118.0 112.0 105.0 104.0 112.0 106.0 113.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:153.00 stddev:2.32 (1.5%) stddev-sans-first:2.28 04:42:59 INFO - PID 4455 | Values: 155.0 150.0 156.0 155.0 150.0 150.0 151.0 152.0 152.0 154.0 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:62.50 stddev:3.47 (5.5%) stddev-sans-first:3.62 04:42:59 INFO - PID 4455 | Values: 63.0 68.0 64.0 64.0 62.0 58.0 58.0 60.0 58.0 58.0 04:42:59 INFO - PID 4455 | -------- Summary: end -------- 04:42:59 INFO - PID 4455 | 04:42:59 INFO - PID 4455 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:42:59 INFO - TEST-INFO | 4455: exit 0 04:43:00 INFO - TEST-OK | kraken | took 59559ms 04:43:00 INFO - SUITE-END | took 1169s 04:43:00 INFO - Completed test suite (00:19:29) 04:43:00 INFO - javascript benchmark 04:43:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9219.917534311073, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3879.670228030617, 3882.55350634677, 3884.0678237223074, 3899.7465164764308, 3922.176342967977, 6263.467605253548, 6275.466585340251, 6294.0, 6310.148130002448, 6383.80848574543, 6278.686065696708, 6354.078658594501, 6370.3311152328915, 6389.488840892729, 6520.543561950665, 5641.576881733866, 5783.016887129188, 5788.82633520995, 5798.391168927265, 5807.303123625171, 817.8703361956616, 5664.065429204176, 5766.365699773021, 5801.245838041049, 5844.26946631671, 5668.291463567054, 5677.602567820254, 5700.37295897453, 5714.399988001283, 5739.684317362543, 5671.319441667027, 5675.659460432346, 5762.279715035621, 5768.105943578752, 5769.509591684712, 5668.319801623831, 5674.347450043257, 5738.995675756768, 5761.971190144022, 5764.7117644117625, 6326.68366581669, 6345.365463453664, 6362.5546211765195, 6419.518536109764, 6423.132877061584, 6734.562253453559, 6815.352541508549, 6819.420349270318, 6926.757563485254, 7023.92976070243, 6948.131483564554, 6966.164060312744, 6967.442604591621, 6970.024196612428, 6976.441884649216, 6282.371762823681, 6432.646204458753, 6508.088867558546, 6533.052707357454, 6556.573822701557, 7107.466939979418, 7109.92890071103, 7185.568865868065, 7196.532225405332, 7208.0, 6754.155730533684, 6820.113385259983, 6870.244273129952, 6928.0, 6937.9653101735175, 6816.182058152955, 6939.826504337432, 6947.89578156328, 6988.301169883072, 7024.1571011478945, 6917.342852429011, 6963.547369420971, 6965.756198533027, 6974.197967233732, 6979.232284448706, 6875.518713690094, 6885.586864788129, 6974.511784175161, 6977.581345119309, 6982.581045137308, 4961.355023846877, 5026.84919452417, 5056.140456122468, 5090.872728181825, 5093.363329583802, 4504.436945381827, 4572.154151481986, 4609.3086037094035, 4621.2837010263465, 4635.814567417266, 6337.556371054075, 6339.953907605193, 6358.968205158944, 6412.551121421549, 6418.133551970424, 6843.0, 6846.4522838172825, 6930.029795828588, 6934.791956241321, 6968.059311992917, 6203.31763506014, 6267.655278959701, 6287.434130928238, 6356.173697419306, 6382.138411314413, 5574.247476590689, 5634.464725851038, 5656.604037717402, 5661.179129026268, 5661.207430959669, 6987.615681137586, 6999.12510936133, 7085.82285442868, 7204.56772593646, 7248.746293879689, 6784.185897692309, 6861.279565645636, 6980.0, 6987.02181694553, 6991.370776630128, 7083.043789088407, 7133.500654954103, 7148.749793757193, 7194.604296763729, 7272.345488906024, 6856.280090590616, 6876.17485901675, 6879.036934829028, 6882.793516194522, 6882.8623427532175, 6878.518503704692, 6918.930810691829, 6934.063901373452, 6936.7572134975035, 6980.057692211486, 6562.376574225441, 6649.900251496329, 6727.831804204934, 6738.831529211809, 6739.66301684904, 6623.569467984566, 6627.105340778933, 6703.162104736908, 6718.832029199114, 6723.125993620798, 6502.4472919800455, 6548.934510654833, 6622.238442579165, 6628.602283862983, 6691.5315927884585, 6685.732570697118, 6734.124563806674, 6743.865122697421, 6780.084688566981, 6794.082798822095, 6480.157579514633, 6486.0, 6491.772787952588, 6570.310117437601, 6570.5729127606555], "value": 6271.886902221468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [942.3121121581247, 963.7397902566307, 966.2704657983221, 967.5452537307463, 969.4667932637047, 44055.559444405546, 44729.552704472946, 44941.32588011182, 44990.0, 45021.774891125584, 42001.789991050005, 42170.57829421705, 42244.155116897644, 42306.365404518954, 42520.57479425205, 7683.8079048023965, 7847.725329613465, 8239.134890836465, 8391.874121888177, 8407.831843363128, 8533.615987280587, 8640.481571105722, 8658.220760131619, 8668.826623467527, 8674.653013879437, 8660.220580147785, 8675.739727808144, 8728.738137855842, 8736.170063843925, 8746.825063498727, 8577.185167409087, 8629.180227878403, 8651.95674021629, 8658.307335413183, 8692.956535217316, 8291.792705182359, 8327.83344333116, 8343.791405214857, 8398.202170793766, 8404.579771011486, 41933.790331048454, 42041.369379459335, 42073.368899466535, 42082.0, 42150.78924605388, 3843.692504599653, 3850.9229815403814, 3873.767573945544, 3875.069983204039, 3887.300285948528, 34587.82706086453, 34613.0, 34648.826755866314, 34671.653283467094, 34772.82613586916, 5498.0, 5507.697076660822, 5523.613347065707, 5541.0, 5635.380108188096, 214052.0, 216339.0, 217918.0, 218575.0, 218686.0, 47584.5241547587, 48545.75727121342, 54319.72840135774, 54349.45650543523, 54583.0, 58059.0, 58125.41874581284, 58680.0, 58715.0, 58744.7062764692, 17794.644107117787, 17843.0, 17915.283388664568, 17919.552011199823, 17920.372786952656, 14351.78472322916, 14456.060356076821, 14474.276286185648, 14492.202928838804, 14511.419543218364, 15791.763123553155, 15815.36738530469, 15896.841031589767, 15919.363225471083, 15957.840421595636, 6207.441330280206, 6329.556931014785, 6343.572696143331, 6401.29585745577, 6412.070249813751, 1109.3953795181646, 1116.9162312826409, 1118.8992990630882, 1121.517747368623, 1122.3939072900541, 26140.738592614212, 26272.73727262741, 26445.206643800717, 26478.33804154873, 26646.0, 1212.53923509066, 1218.3908045977012, 1219.5975328141692, 1236.042067397756, 1239.8388209532884, 13895.166290022631, 13969.860301397059, 14047.08693934891, 14091.577252682437, 14155.858441415454, 285860.0, 286904.0, 288241.0, 288984.0, 289210.0, 60272.69863650654, 60535.39464605298, 60989.0, 61237.38762612406, 61721.382786172464, 1536.216529569905, 1538.8845836562302, 1551.3794482207206, 1562.3360071969369, 1576.5428025872598, 25314.367140821625, 25522.983278527714, 25642.871785640953, 25667.3583160415, 25691.871540642176, 42384.0, 42425.15149696927, 42469.787651061546, 42504.57495425006, 42575.574244257165, 226586.0, 226651.0, 230103.0, 230492.0, 230603.0, 38055.429168563045, 40635.390469142396, 41203.7939810311, 41551.79224103981, 41604.37593436161, 51599.0, 51621.7418912903, 51976.440706778856, 52263.21605175849, 52487.47512524826, 37269.4409583851, 37299.81350093232, 37355.626443735215, 37638.2472350546, 37816.62183378131, 36489.27021459503, 36692.44961325529, 36798.63201367952, 36882.0, 37196.62803371932, 42218.57781422146, 42931.0, 43511.347329789445, 43606.12787744164, 43873.34189987217, 35232.0, 35723.28553428969, 35944.46083308805, 35977.460338094425, 36383.45424818683, 26508.03235900303, 26568.468630627667, 26811.0, 27351.316217094733, 27491.450170996068], "value": 19268.445352790168, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.5271635408027, 599.8500374906273, 601.912722655215, 609.9329073801883, 616.6546733829056, 23319.650205246933, 24363.26910192697, 24365.14721984731, 24378.0, 24413.413128146658, 24739.505209895793, 26259.3435164121, 26309.0, 26392.944282228735, 26415.867920660417, 6560.803175904719, 6713.563618364815, 6719.832004199885, 6726.764563240287, 6743.8651226975435, 6519.185101862267, 6573.112629794964, 6693.029510720942, 6788.287229840869, 6811.046453496514, 6760.39156475917, 6765.932340676604, 6766.7969960901255, 6767.830804229909, 6769.255381907986, 6450.0, 6660.533762636617, 6676.899846502306, 6763.628000459973, 6766.492513061516, 6220.533459990519, 6222.439980401786, 6235.0, 6244.344343843876, 6255.06174073888, 29898.40203195924, 30448.54327185094, 30731.846340768378, 30775.846120769478, 30803.229919252197, 2218.234709025384, 2227.3318004598555, 2231.4086767006756, 2234.0728597631964, 2244.8989795459242, 20480.897595511928, 21359.0, 21521.67717484239, 21526.031328590252, 21533.13867445316, 2535.492901419705, 2540.174443305933, 2608.6217498462806, 2615.9215223543324, 2623.7638612524966, 136009.0, 143796.0, 154682.0, 162575.1871240648, 162650.0, 15801.0, 16039.839601604068, 16362.427315043917, 16719.83280167207, 16790.83209167917, 26043.479130417287, 26109.477810443685, 26134.51722086399, 26266.60600091, 26340.341491462867, 11019.393933333744, 11045.226834121695, 11157.832632510519, 11167.05080068187, 11244.26912250701, 9976.05227503386, 10102.343347682377, 10154.441505717108, 10263.435511046815, 10319.329243599143, 9873.654422095193, 9894.427448863347, 9937.254705896941, 10054.0, 10308.690739277834, 3067.708567686066, 3098.5662007318992, 3104.463290671132, 3140.089374081491, 3181.109289398972, 1035.425338936882, 1052.85786418834, 1054.2936232724094, 1054.6519648516125, 1060.6446840308429, 20777.58444831095, 21521.354359369245, 21558.0, 21745.23891663784, 21795.782042179377, 1096.4791723931069, 1115.1914861725184, 1116.3078891087578, 1122.960696375623, 1138.066785236115, 11504.76990460186, 11634.418279086012, 11672.241304315192, 11755.353455560027, 11800.17398782077, 575700.0, 584278.0, 586840.0, 600649.0, 611606.0, 123476.38261808634, 125133.0, 127337.36331318284, 128257.35871320583, 128609.0, 1123.1015187849687, 1173.724174818917, 1234.216272666863, 1247.9188852724726, 1312.9474821007243, 22248.55502889901, 22619.547609048055, 22876.0, 23297.76702232956, 23306.0, 64345.0, 65083.34916650773, 65413.01880471892, 66077.0, 67246.66376668085, 80573.0, 81842.0, 82131.0, 82419.5879020601, 82595.5870220645, 20511.077001535265, 20888.268910588056, 21045.684314735598, 21257.255996040065, 21361.572768544855, 23581.174658886855, 23587.0, 24243.151489697775, 24482.877585611957, 24535.0, 19634.50913727168, 19714.60570788605, 19895.801041989394, 19937.401877943685, 20081.899590501955, 16901.154942252593, 16924.74612880783, 17320.91339543294, 17719.20263588116, 17834.28662853471, 19865.00674966217, 19881.801181988572, 19937.501562461053, 20426.38720838377, 20429.79570204279, 20580.176792928116, 20600.793992060488, 20726.378208653765, 20751.8962405187, 20792.688109678063, 19300.613987719888, 19383.612327753082, 19488.415347539598, 19508.414747557596, 19584.216631335155], "value": 13153.129236151035, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.3251106957591, 1416.9291535423226, 1427.5503216486802, 1444.1984698492333, 1450.514077783943, 16710.832891671078, 17326.913365433185, 17337.566560835985, 17348.0, 17349.739753903687, 16875.324987000506, 16972.15139243036, 17015.82984170158, 17044.914775426107, 17047.403340883073, 2714.8642567871625, 2851.5009873272197, 2851.8146320489204, 2852.1728698677357, 2852.9286767830763, 2603.1539749581366, 2712.688040875295, 2716.2258756254473, 2716.3209197700576, 2717.184844546638, 2778.9305267368277, 2839.801213915027, 2866.6846646868826, 2866.9426611467757, 2878.8704508297074, 2596.961045584299, 2651.3636727185335, 2690.0315886280923, 2723.237493501823, 2735.8221715588525, 2551.5152121097117, 2875.971240287612, 2881.0924558763923, 2881.985590072057, 2883.1062370665163, 16725.330986760517, 16741.162941852857, 16755.0, 16793.83206167935, 16926.83073169277, 8440.831183376298, 8768.386212965095, 8772.736817895473, 8775.385722999392, 8779.517126558037, 5206.504688706289, 5273.2617433559335, 5378.838634840961, 5394.460553944614, 5447.373552041527, 2014.5020174983063, 2053.9281125160696, 2106.5997460482613, 2122.843050537461, 2127.744670639533, 30353.848230758926, 30462.39075218483, 30521.38957220843, 30658.233544161576, 30672.69327306743, 6914.965425172842, 7253.310935461122, 7257.0, 7263.455240856957, 7410.370118539877, 19588.21647134127, 19614.803851961584, 19623.705644415346, 19723.211071557263, 19744.90127549382, 7854.764357069297, 7919.0, 7944.324732397811, 7944.761657150179, 7950.443468957117, 7247.383972362303, 7255.637218139072, 7478.9252107479315, 7617.847643047108, 7801.141874393812, 5451.236826844245, 5683.431656834284, 5707.429257074343, 5712.314522257356, 5763.6541807491685, 1530.9157996310087, 1531.525227179578, 1548.3651702801797, 1548.7289724298203, 1571.1829848478726, 914.6158613382395, 947.9905200947902, 950.0024973777644, 952.33812500313, 955.7945041815904, 8230.21812927771, 8414.0, 8416.831663366698, 8460.238578527838, 8483.915160848435, 791.291793844506, 809.6801763303462, 814.0150417994175, 814.1126172472034, 828.4615000249886, 5797.623154494944, 5805.651660900359, 5809.564282678816, 5844.766209351578, 6003.519718422515, 24495.142670006466, 24929.750702492744, 25135.120270790434, 25627.10305139311, 25673.871630641726, 23539.29382118539, 24994.125205617715, 25287.620685689362, 25512.362190945005, 25630.359241019123, 12748.49006039748, 12860.421281042376, 12921.612351629467, 13031.478740850449, 13042.608721738363, 8325.54209518458, 8493.405461617875, 8522.829543408972, 8571.100034496289, 8577.571121444027, 20750.481237969172, 21080.156793728693, 21119.0, 21146.577068458853, 21688.891555542123, 21685.132594696035, 21979.780202198413, 22309.330720078422, 22337.32988010362, 22493.887530562242, 12233.877661223274, 12465.252084874937, 12483.063770217088, 12483.31341776211, 12486.250824950532, 14935.701285974003, 15579.922100389878, 15664.686706265584, 15696.21518924072, 15703.21483925822, 12172.57395991136, 12184.451699673713, 12208.32854193028, 12270.81593776112, 12619.81070283928, 11598.420078996185, 11747.17769756143, 11748.236364636272, 11800.409979500819, 12057.216280941711, 11136.33182009082, 11261.7747645045, 11296.943515282372, 11374.772504550028, 11379.08967282632, 7784.688612455665, 7861.567613781297, 7953.32396746283, 7981.281684648177, 7986.161453047579, 7866.13472518034, 7983.441159118806, 8000.799980000546, 8075.757727268191, 8104.878426823484], "value": 7163.569899019209, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.5667466027177, 553.1453903718755, 565.3442007271565, 574.2764117212312, 574.9564540359248, 6304.401081897224, 6386.776462823802, 6410.916452153051, 6432.839179020527, 6455.806325810233, 6287.59130656507, 6289.937100628992, 6309.810705678826, 6319.6524191169465, 6321.367863213676, 4940.752962351886, 5067.366579177603, 5092.236164575306, 5097.260897169908, 5114.567920982113, 5083.822066227683, 5095.261187127864, 5106.387233531981, 5110.872228194306, 5116.641835071547, 5026.170681837492, 5041.773120209581, 5067.467915868836, 5080.618953578478, 5083.796648134089, 5042.924356134661, 5056.747162641872, 5059.342285502861, 5068.163752980754, 5116.846494605168, 4616.699914505546, 4619.86140415788, 4662.743549104798, 4688.859334219979, 4694.788734506956, 6203.100550420164, 6251.12484252205, 6340.556161068727, 6340.619562826245, 6405.583637063576, 5920.5559583031445, 5930.525557955396, 5949.167116603679, 5951.940480595182, 6023.427774361428, 6621.86756264872, 6669.23303820062, 6681.164854393201, 6763.425108865703, 6771.153605799275, 5095.923561146586, 5127.641065125443, 5145.125328694131, 5150.227465880111, 5163.8192663256605, 6955.652217389161, 6972.51192416531, 6974.930250697479, 7007.789766307019, 7079.504434689626, 5280.419153893069, 5331.360236771612, 5335.06636338647, 5335.973320133452, 5353.250544923714, 5402.568319395364, 5406.864828379322, 5425.294711687534, 5429.294191755125, 5458.0, 5891.734871930774, 5901.911471327933, 5935.73289201987, 5954.612950158225, 5963.522918166536, 6572.047053177263, 6604.174478190226, 6678.565893216925, 6756.662166891636, 6764.289749576224, 1811.8731688781922, 1863.4782260966952, 1864.7109697996832, 1867.2251015828278, 1872.101391332168, 1158.29922896648, 1321.2336844630092, 1336.62574479146, 1337.8394592648945, 1340.470514146926, 6138.5396095292135, 6167.568270221132, 6228.750849966042, 6277.905831412532, 6327.462165715966, 6816.829579260559, 6844.21291551468, 6864.279250678607, 6881.587104773729, 6895.310468953165, 6275.278342990615, 6308.52686048539, 6317.820351978652, 6321.336259692666, 6330.335314791972, 3582.480540321691, 3587.3542762303036, 3592.6586974237407, 3594.2452085062428, 3628.564572251347, 7030.929690703129, 7097.290270972861, 7128.322809333209, 7166.390856777128, 7167.319104685047, 6930.44556435484, 7003.809352410102, 7022.5435346703325, 7117.0, 7141.571505709674, 7064.964675176591, 7081.150261968597, 7119.2168861426235, 7126.536775109601, 7186.778247697904, 6661.134052573135, 6675.532712710064, 6677.432418244503, 6748.763793267212, 6822.249552549315, 6772.71318449493, 6830.31696830313, 6867.931320686929, 6917.896231556631, 6924.4114250287275, 7026.543274687129, 7072.823179420556, 7134.1795693495915, 7134.250903655042, 7147.499675022698, 5231.607629427732, 5234.162533994542, 5259.4477579853565, 5277.393099793573, 5280.577553795764, 6817.250102488623, 6825.668994546016, 6838.247792742894, 6919.481038922202, 6927.757528486479, 6570.901436478361, 6618.8345291368105, 6623.801285961429, 6633.270340262664, 6701.865962680621, 6588.242352129567, 6599.119251013438, 6643.57855704303, 6701.698423571049, 6707.5975441473665, 6438.806835794933, 6616.900746488903, 6639.240601240772, 6654.866902661823, 6686.765963191265, 6088.360722124113, 6495.415412612888, 6514.837129071811, 6636.402723754885, 6689.832754181184, 6317.810465686037, 6383.457406120346, 6475.676216189077, 6494.610323380423, 6561.212654481493], "value": 5254.636703563303, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [948.506776476232, 977.9608815647372, 981.1022914033659, 984.2224642532395, 998.8152191844507, 20514.692279615818, 21055.578888422224, 21058.368248952538, 21067.367978960618, 21080.156793728253, 18929.33747318844, 18981.33565325214, 19035.524111897208, 19101.61796764064, 19595.706064409045, 1007.2043085962099, 1030.1346868630349, 1033.4729288063068, 1034.131329683066, 1036.808190484759, 1039.50103950104, 1061.4745700878077, 1064.4092528646597, 1067.3862529045791, 1069.0, 1117.854678891747, 1137.5677242647782, 1137.744007598292, 1137.8634563852308, 1138.7267055906605, 1060.6128763001525, 1110.6001839337912, 1116.542217690743, 1123.376526028054, 1127.7518945831903, 805.3516918880299, 808.7129069180415, 809.2918696140877, 809.8137428391503, 810.4448452809844, 21213.257535986322, 21314.893425532773, 21411.14355425781, 21511.247106351202, 21540.676889846665, 21779.891100544555, 22805.543889122124, 22813.42966425836, 22816.771832281796, 22884.77115228843, 18919.86028043437, 18955.147018384207, 19084.522886927938, 19222.03889805504, 19241.61516769671, 3976.5029371328583, 4017.2969730297373, 4032.334664780308, 4033.7983100845136, 4035.293823580891, 157362.0, 159121.0, 160607.1969640156, 163566.1821690896, 163880.0, 17652.911735441496, 17668.469945901645, 17982.190801413966, 18005.819941800677, 18073.18670659797, 34161.658383415845, 34167.658323416945, 34453.138671533416, 34460.31079378398, 35023.4746478803, 9641.469719165514, 9676.951615241878, 9680.370775899544, 9693.273004524688, 9704.9029509704, 4945.9258111128365, 5035.974820125949, 5047.0, 5052.646314758005, 5067.290579318898, 6107.603005804608, 6243.125962365272, 6248.31268560458, 6272.18461599989, 6276.309605943343, 1073.5652060915343, 1090.3621381491917, 1123.9044681202026, 1124.2860783402598, 1126.1328776841785, 741.8590467811099, 752.3792870881545, 771.6411868481183, 772.4284029818007, 778.2567647896245, 12678.175918565265, 13185.406656700472, 13533.39099740514, 14253.572392828231, 14328.641783955485, 715.6707914359453, 724.2359310927022, 740.825905912121, 748.472327009457, 748.9812754681177, 5975.043992961106, 6023.578349515492, 6139.263288405331, 6170.907436388457, 6179.907301390483, 143713.0, 144111.0, 145144.0, 149210.25394873173, 150648.0, 124734.37632811778, 126023.36988315, 126980.0, 127148.36425817996, 133779.33110334387, 106364.46817765862, 107778.46110769553, 109662.45168774265, 113307.0, 113945.0, 14406.135631862122, 14520.128792272497, 14671.266436678337, 14779.92610036943, 14842.554723358317, 51926.74036629793, 52130.218046730086, 52580.21129682981, 52938.2059269119, 54361.72819135879, 65287.0, 65714.0, 66068.33931660622, 66703.33296667166, 67143.32856671566, 21103.8944805275, 21378.89310553437, 21492.24777132793, 21521.6771748427, 21599.892000540527, 21722.34832955014, 21827.890860545594, 21998.670019949393, 22059.007344669208, 22215.555688886456, 22112.88943555272, 22490.662640060084, 22500.43748906225, 22692.319230423112, 22752.544949101255, 19788.90105549463, 19949.501262468555, 19995.700064498753, 20008.199672012957, 20064.69902951486, 20481.89759051195, 20599.897000515502, 20701.792982069986, 20779.272725455143, 20816.89591552093, 14171.433142674177, 14440.277986100864, 14637.194954277616, 14798.704025919205, 14878.628034299229, 14822.40710371573, 15155.46955856584, 15245.16151611672, 15257.771133432785, 15345.386184552493], "value": 10265.693359955221, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1377.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 105.0, 107.0, 108.0, 115.0, 104.0, 105.0, 106.0, 106.0], "value": 106.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 125.0, 113.0, 113.0, 115.0, 115.0, 134.0, 116.0, 111.0, 111.0], "value": 116.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 162.0, 161.0, 160.0, 160.0, 163.0, 161.0, 164.0, 167.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 74.0, 74.0, 73.0, 73.0, 73.0, 74.0, 76.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 77.0, 77.0, 77.0, 79.0, 77.0, 77.0, 78.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 116.0, 114.0, 113.0, 110.0, 115.0, 114.0, 113.0, 114.0, 114.0], "value": 113.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 149.0, 149.0, 151.0, 149.0, 152.0, 150.0, 149.0, 149.0, 151.0], "value": 149.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 94.0, 94.0, 100.0, 94.0, 95.0, 96.0, 97.0, 97.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 45.0, 45.0, 44.0, 50.0, 47.0, 46.0, 49.0, 46.0], "value": 46.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 39.0, 39.0, 39.0, 39.0, 39.0, 39.0, 39.0, 38.0, 38.0], "value": 38.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 70.0, 71.0, 71.0, 80.0, 75.0, 74.0, 73.0, 69.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 106.0, 102.0, 118.0, 112.0, 105.0, 104.0, 112.0, 106.0, 113.0], "value": 108.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 150.0, 156.0, 155.0, 150.0, 150.0, 151.0, 152.0, 152.0, 154.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 64.0, 64.0, 62.0, 58.0, 58.0, 60.0, 58.0, 58.0], "value": 61.3, "unit": "score"}]}]} 04:43:00 INFO - Return code: 0 04:43:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:43:00 INFO - # TBPL SUCCESS # 04:43:00 INFO - Running post-action listener: _package_coverage_data 04:43:00 INFO - Running post-action listener: _resource_record_post_action 04:43:00 INFO - [mozharness: 2017-09-25 11:43:00.644835Z] Finished run-tests step (success) 04:43:00 INFO - Running post-run listener: _resource_record_post_run 04:43:00 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 12066816; Write bytes: 454930432; Read time: 4360; Write time: 347376 04:43:00 INFO - TinderboxPrint: CPU usage
13.1% 04:43:00 INFO - TinderboxPrint: I/O read bytes / time
12,066,816 / 4,360 04:43:00 INFO - TinderboxPrint: I/O write bytes / time
454,930,432 / 347,376 04:43:00 INFO - TinderboxPrint: CPU idle
8,231.7 (86.9%) 04:43:00 INFO - TinderboxPrint: CPU user
1,187.6 (12.5%) 04:43:00 INFO - TinderboxPrint: Swap in / out
0 / 0 04:43:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 146227200; Read time: 168; Write time: 115668 04:43:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:43:00 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 9691136; Write bytes: 308297728; Read time: 3844; Write time: 231680 04:43:01 INFO - Running post-run listener: _upload_blobber_files 04:43:01 INFO - Blob upload gear active. 04:43:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:43: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 04:43: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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:43: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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:43:01 INFO - (blobuploader) - INFO - Open directory for files ... 04:43:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:43:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:43:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:07 INFO - (blobuploader) - INFO - Done attempting. 04:43:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:43:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:43:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:08 INFO - (blobuploader) - INFO - Done attempting. 04:43:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:43:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:43:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:10 INFO - (blobuploader) - INFO - Done attempting. 04:43:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:43:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:43:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:11 INFO - (blobuploader) - INFO - Done attempting. 04:43:11 INFO - (blobuploader) - INFO - Iteration through files over. 04:43:11 INFO - Return code: 0 04:43:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:43:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:43:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c806302828ff55d7540a81fb225fe49fae1ee1b20ad19ad867b87ac8a2b98669e33f7ccc893e0bf67d9f405b197ece517742850ec87befcb1586b62b1669b6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c77c4d39d4cdfc4ae3f1a30f883774ddf0aaa05c8c3ae03b69d259b3e3707a44d9b004086f0839a899e963b26df05186e9f7380f51ecf79f8ab06ea45ec98980", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/846a7ad9182050e4649b57207a6ee2657b9c0792e7d26e8218076f715a5ec084037625ca03f0f3e04a2299ad4dd68c51c323d7c3a906935db6e10d42f5056f4d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6bf5d2ca38f4e61994b31daee3ab5f84e1cb7b2df2427515505b685543f0fa35f9f8e3304b7049177a3c106db6066475109b527c070d258dd309047400025d9"} 04:43:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:43:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:43:11 INFO - Contents: 04:43:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c806302828ff55d7540a81fb225fe49fae1ee1b20ad19ad867b87ac8a2b98669e33f7ccc893e0bf67d9f405b197ece517742850ec87befcb1586b62b1669b6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c77c4d39d4cdfc4ae3f1a30f883774ddf0aaa05c8c3ae03b69d259b3e3707a44d9b004086f0839a899e963b26df05186e9f7380f51ecf79f8ab06ea45ec98980", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/846a7ad9182050e4649b57207a6ee2657b9c0792e7d26e8218076f715a5ec084037625ca03f0f3e04a2299ad4dd68c51c323d7c3a906935db6e10d42f5056f4d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6bf5d2ca38f4e61994b31daee3ab5f84e1cb7b2df2427515505b685543f0fa35f9f8e3304b7049177a3c106db6066475109b527c070d258dd309047400025d9"} 04:43:11 INFO - Running post-run listener: copy_logs_to_upload_dir 04:43:11 INFO - Copying logs to upload dir... 04:43:11 INFO - mkdir: /builds/slave/test/build/upload/logs 04:43:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.994496 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2017-09-25 04:43:11.543662) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 04:43:11.550324) ========= 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=3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138 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/sha512/2c806302828ff55d7540a81fb225fe49fae1ee1b20ad19ad867b87ac8a2b98669e33f7ccc893e0bf67d9f405b197ece517742850ec87befcb1586b62b1669b6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c77c4d39d4cdfc4ae3f1a30f883774ddf0aaa05c8c3ae03b69d259b3e3707a44d9b004086f0839a899e963b26df05186e9f7380f51ecf79f8ab06ea45ec98980", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/846a7ad9182050e4649b57207a6ee2657b9c0792e7d26e8218076f715a5ec084037625ca03f0f3e04a2299ad4dd68c51c323d7c3a906935db6e10d42f5056f4d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6bf5d2ca38f4e61994b31daee3ab5f84e1cb7b2df2427515505b685543f0fa35f9f8e3304b7049177a3c106db6066475109b527c070d258dd309047400025d9"} build_url:https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011281 build_url: 'https://queue.taskcluster.net/v1/task/c6Yk_WdkTh-UotDFJcbP0A/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2c806302828ff55d7540a81fb225fe49fae1ee1b20ad19ad867b87ac8a2b98669e33f7ccc893e0bf67d9f405b197ece517742850ec87befcb1586b62b1669b6b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c77c4d39d4cdfc4ae3f1a30f883774ddf0aaa05c8c3ae03b69d259b3e3707a44d9b004086f0839a899e963b26df05186e9f7380f51ecf79f8ab06ea45ec98980", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/846a7ad9182050e4649b57207a6ee2657b9c0792e7d26e8218076f715a5ec084037625ca03f0f3e04a2299ad4dd68c51c323d7c3a906935db6e10d42f5056f4d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6bf5d2ca38f4e61994b31daee3ab5f84e1cb7b2df2427515505b685543f0fa35f9f8e3304b7049177a3c106db6066475109b527c070d258dd309047400025d9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-25 04:43:11.578300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:43:11.578622) ========= 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=3836458cccd887f2d2de3cf1000001de-1506337221.98421-1524718138 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004484 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-25 04:43:11.664779) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 04:43:11.665100) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-25 04:43:11.665419) ========= ========= Total master_lag: 0.26 =========