builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-043 starttime: 1505034498.96 results: success (0) revision: f8f481901e2d86dd5be8b8a9af1f81f67e1e9158 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:18.964214) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:18.964740) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:18.965028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:18.979411) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:18.979739) ========= 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=d2a8c569965ab8367867b893000001a1-1505034498.167352-663301265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-10 02:08:19-- 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% 46.3M=0s 2017-09-10 02:08:19 (46.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133400 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:19.130169) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:19.130496) ========= 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=d2a8c569965ab8367867b893000001a1-1505034498.167352-663301265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019004 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:19.180566) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-10 02:08:19.180896) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f8f481901e2d86dd5be8b8a9af1f81f67e1e9158 --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 f8f481901e2d86dd5be8b8a9af1f81f67e1e9158 --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=d2a8c569965ab8367867b893000001a1-1505034498.167352-663301265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-10 02:08:19,267 truncating revision to first 12 chars 2017-09-10 02:08:19,267 Setting DEBUG logging. 2017-09-10 02:08:19,267 attempt 1/10 2017-09-10 02:08:19,267 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f8f481901e2d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-10 02:08:20,187 unpacking tar archive at: mozilla-central-f8f481901e2d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.227858 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-10 02:08:20.424928) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:20.425252) ========= script_repo_revision: f8f481901e2d86dd5be8b8a9af1f81f67e1e9158 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:20.425631) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:20.425893) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-10 02:08:20.438666) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-10 02:08:20.439036) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d2a8c569965ab8367867b893000001a1-1505034498.167352-663301265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:08:20 INFO - MultiFileLogger online at 20170910 02:08:20 in /builds/slave/test 02:08:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 02:08:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:08:20 INFO - {'append_to_log': False, 02:08:20 INFO - 'base_work_dir': '/builds/slave/test', 02:08:20 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 02:08:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:08:20 INFO - 'branch': 'Firefox-Non-PGO', 02:08:20 INFO - 'buildbot_json_path': 'buildprops.json', 02:08:20 INFO - 'code_coverage': False, 02:08:20 INFO - 'config_files': ('talos/linux_config.py',), 02:08:20 INFO - 'default_actions': ('clobber', 02:08:20 INFO - 'read-buildbot-config', 02:08:20 INFO - 'download-and-extract', 02:08:20 INFO - 'populate-webroot', 02:08:20 INFO - 'create-virtualenv', 02:08:20 INFO - 'install', 02:08:20 INFO - 'setup-mitmproxy', 02:08:20 INFO - 'run-tests'), 02:08:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:08:20 INFO - 'disable_ccov_upload': False, 02:08:20 INFO - 'disable_stylo': False, 02:08:20 INFO - 'download_minidump_stackwalk': True, 02:08:20 INFO - 'download_symbols': 'ondemand', 02:08:20 INFO - 'enable_stylo': False, 02:08:20 INFO - 'enable_webrender': False, 02:08:20 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 02:08:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:08:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:08:20 INFO - 'gecko_profile': False, 02:08:20 INFO - 'gecko_profile_interval': 0, 02:08:20 INFO - 'installer_path': 'installer.exe', 02:08:20 INFO - 'log_level': 'info', 02:08:20 INFO - 'log_name': 'talos', 02:08:20 INFO - 'log_to_console': True, 02:08:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:08:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:08:20 INFO - 'opt_config_files': (), 02:08:20 INFO - 'pip_index': False, 02:08:20 INFO - 'suite': 'dromaeojs-e10s', 02:08:20 INFO - 'system_bits': '32', 02:08:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:08:20 INFO - 'title': 'talos-linux64-ix-043', 02:08:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:08:20 INFO - 'use_talos_json': True, 02:08:20 INFO - 'verify': 'False', 02:08:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:08:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:08:20 INFO - 'work_dir': 'build'} 02:08:20 INFO - [mozharness: 2017-09-10 09:08:20.656862Z] Running clobber step. 02:08:20 INFO - Running pre-action listener: _resource_record_pre_action 02:08:20 INFO - Running main action method: clobber 02:08:20 INFO - rmtree: /builds/slave/test/build 02:08:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:08:22 INFO - Running post-action listener: _resource_record_post_action 02:08:22 INFO - [mozharness: 2017-09-10 09:08:22.192590Z] Finished clobber step (success) 02:08:22 INFO - [mozharness: 2017-09-10 09:08:22.192688Z] Running read-buildbot-config step. 02:08:22 INFO - Running pre-action listener: _resource_record_pre_action 02:08:22 INFO - Running main action method: read_buildbot_config 02:08:22 INFO - Using buildbot properties: 02:08:22 INFO - { 02:08:22 INFO - "project": "", 02:08:22 INFO - "product": "firefox", 02:08:22 INFO - "who": "archaeopteryx@coole-files.de", 02:08:22 INFO - "installer_path": "public/build/target.tar.bz2", 02:08:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 02:08:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 02:08:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 02:08:22 INFO - "stage_platform": "linux64", 02:08:22 INFO - "basedir": "/builds/slave/test", 02:08:22 INFO - "buildnumber": 1131, 02:08:22 INFO - "platform": "ubuntu64_hw", 02:08:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:08:22 INFO - "slavebuilddir": "test", 02:08:22 INFO - "taskId": "fS_bTGtqSeaXRIqO8F-jvA", 02:08:22 INFO - "branch": "mozilla-central", 02:08:22 INFO - "script_repo_revision": "production", 02:08:22 INFO - "revision": "f8f481901e2d86dd5be8b8a9af1f81f67e1e9158", 02:08:22 INFO - "slavename": "talos-linux64-ix-043", 02:08:22 INFO - "repo_path": "mozilla-central" 02:08:22 INFO - } 02:08:22 INFO - Finding installer, test and symbols from parent task. 02:08:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fS_bTGtqSeaXRIqO8F-jvA'}, attempt #1 02:08:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fS_bTGtqSeaXRIqO8F-jvA'}, attempt #1 02:08:23 INFO - Task dependencies: W1NZUJsyQSiI_HuWC54dMw 02:08:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw'}, attempt #1 02:08:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.tar.bz2 02:08:23 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.test_packages.json 02:08:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.crashreporter-symbols.zip 02:08:23 INFO - Running post-action listener: _resource_record_post_action 02:08:23 INFO - [mozharness: 2017-09-10 09:08:23.606963Z] Finished read-buildbot-config step (success) 02:08:23 INFO - [mozharness: 2017-09-10 09:08:23.607185Z] Running download-and-extract step. 02:08:23 INFO - Running pre-action listener: _resource_record_pre_action 02:08:23 INFO - Running main action method: download_and_extract 02:08:23 INFO - mkdir: /builds/slave/test/build/tests 02:08:23 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')]} 02:08:23 INFO - trying https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.test_packages.json 02:08:23 INFO - Downloading https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 02:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 02:08:24 INFO - Downloaded 989 bytes. 02:08:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 02:08:24 INFO - Using the following test package requirements: 02:08:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 02:08:24 INFO - u'common': [u'target.common.tests.zip'], 02:08:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 02:08:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 02:08:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 02:08:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 02:08:24 INFO - u'mozbase': [u'target.common.tests.zip'], 02:08:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 02:08:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 02:08:24 INFO - u'web-platform': [u'target.common.tests.zip', 02:08:24 INFO - u'target.web-platform.tests.tar.gz'], 02:08:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 02:08:24 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 02:08:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.common.tests.zip 02:08:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.common.tests.zip'}, attempt #1 02:08:24 INFO - Fetch https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.common.tests.zip into memory 02:08:25 INFO - Content-Length response header: 55024694 02:08:25 INFO - Bytes received: 55024694 02:08:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.talos.tests.zip 02:08:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 02:08:36 INFO - Fetch https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.talos.tests.zip into memory 02:08:36 INFO - Content-Length response header: 14497963 02:08:36 INFO - Bytes received: 14497963 02:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:37 INFO - trying https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.tar.bz2 02:08:37 INFO - Downloading https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 02:08:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 02:08:38 INFO - Downloaded 62944091 bytes. 02:08:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.tar.bz2 02:08:38 INFO - mkdir: /builds/slave/test/properties 02:08:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:08:38 INFO - Writing to file /builds/slave/test/properties/build_url 02:08:38 INFO - Contents: 02:08:38 INFO - build_url:https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.tar.bz2 02:08:38 INFO - Running post-action listener: _resource_record_post_action 02:08:38 INFO - Running post-action listener: find_tests_for_verification 02:08:38 INFO - Running post-action listener: set_extra_try_arguments 02:08:38 INFO - [mozharness: 2017-09-10 09:08:38.669595Z] Finished download-and-extract step (success) 02:08:38 INFO - [mozharness: 2017-09-10 09:08:38.669826Z] Running populate-webroot step. 02:08:38 INFO - Running pre-action listener: _resource_record_pre_action 02:08:38 INFO - Running main action method: populate_webroot 02:08:38 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:08:38 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['tresize', 02:08:38 INFO - 'tcanvasmark']}, 02:08:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:08:38 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['dromaeo_css', 02:08:38 INFO - 'kraken']}, 02:08:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:08:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:38 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:08:38 INFO - 'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:08:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:08:38 INFO - 'tests': ['damp', 'tps']}, 02:08:38 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:08:38 INFO - 'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['damp', 'tps']}, 02:08:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:08:38 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['dromaeo_dom']}, 02:08:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:08:38 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['basic_compositor_video', 02:08:38 INFO - 'glvideo']}, 02:08:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 02:08:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 02:08:38 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:08:38 INFO - 'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['ts_paint_webext', 02:08:38 INFO - 'tp5o_webext']}, 02:08:38 INFO - 'other-e10s': {'tests': ['a11yr', 02:08:38 INFO - 'ts_paint', 02:08:38 INFO - 'tpaint', 02:08:38 INFO - 'sessionrestore', 02:08:38 INFO - 'sessionrestore_many_windows', 02:08:38 INFO - 'sessionrestore_no_auto_restore', 02:08:38 INFO - 'tabpaint']}, 02:08:38 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['a11yr', 02:08:38 INFO - 'ts_paint', 02:08:38 INFO - 'tpaint', 02:08:38 INFO - 'sessionrestore', 02:08:38 INFO - 'sessionrestore_many_windows', 02:08:38 INFO - 'sessionrestore_no_auto_restore', 02:08:38 INFO - 'tabpaint']}, 02:08:38 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 02:08:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 02:08:38 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['bloom_basic_singleton']}, 02:08:38 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['bloom_basic']}, 02:08:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:08:38 INFO - 'tsvgr_opacity', 02:08:38 INFO - 'tart', 02:08:38 INFO - 'tscrollx', 02:08:38 INFO - 'tsvg_static']}, 02:08:38 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['tsvgx', 02:08:38 INFO - 'tsvgr_opacity', 02:08:38 INFO - 'tart', 02:08:38 INFO - 'tscrollx', 02:08:38 INFO - 'tsvg_static']}, 02:08:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:08:38 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:08:38 INFO - 'talos_options': ['--disable-stylo'], 02:08:38 INFO - 'tests': ['tp5o']}, 02:08:38 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:08:38 INFO - 'talos_options': ['--mitmproxy', 02:08:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:08:38 INFO - '--firstNonBlankPaint'], 02:08:38 INFO - 'tests': ['tp6_google', 02:08:38 INFO - 'tp6_youtube', 02:08:38 INFO - 'tp6_amazon', 02:08:38 INFO - 'tp6_facebook']}, 02:08:38 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:08:38 INFO - 'talos_options': ['--disable-stylo', 02:08:38 INFO - '--mitmproxy', 02:08:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:08:38 INFO - '--firstNonBlankPaint'], 02:08:38 INFO - 'tests': ['tp6_google', 02:08:38 INFO - 'tp6_youtube', 02:08:38 INFO - 'tp6_amazon', 02:08:38 INFO - 'tp6_facebook']}, 02:08:38 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 02:08:38 INFO - 'talos_options': ['--stylo-threads=1', 02:08:38 INFO - '--mitmproxy', 02:08:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 02:08:38 INFO - '--firstNonBlankPaint'], 02:08:38 INFO - 'tests': ['tp6_google', 02:08:38 INFO - 'tp6_youtube', 02:08:38 INFO - 'tp6_amazon', 02:08:38 INFO - 'tp6_facebook']}, 02:08:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:08:38 INFO - 'talos_options': ['--xperf_path', 02:08:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:38 INFO - 'tests': ['tp5n']}, 02:08:38 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 02:08:38 INFO - 'talos_options': ['--disable-stylo', 02:08:38 INFO - '--xperf_path', 02:08:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:08:38 INFO - 'tests': ['tp5n']}}} 02:08:38 INFO - Running post-action listener: _resource_record_post_action 02:08:38 INFO - [mozharness: 2017-09-10 09:08:38.688579Z] Finished populate-webroot step (success) 02:08:38 INFO - [mozharness: 2017-09-10 09:08:38.688669Z] Running create-virtualenv step. 02:08:38 INFO - Running pre-action listener: _resource_record_pre_action 02:08:38 INFO - Running main action method: create_virtualenv 02:08:38 INFO - Creating virtualenv /builds/slave/test/build/venv 02:08:38 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:08:38 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 02:08:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:08:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:08:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:08:39 INFO - Installing distribute.............................................................................................................................................................................................done. 02:08:39 INFO - Installing pip...............done. 02:08:39 INFO - Return code: 0 02:08:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:08:39 INFO - Reading from file tmpfile_stdout 02:08:39 INFO - Output received: 02:08:39 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 02:08:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:08:39 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')]} 02:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:39 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')]} 02:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:39 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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:39 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 02:08:39 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 02:08:39 INFO - Using env: {'DISPLAY': ':0', 02:08:39 INFO - 'HOME': '/home/cltbld', 02:08:39 INFO - 'LANG': 'en_US.UTF-8', 02:08:39 INFO - 'LANGUAGE': 'en_US:en', 02:08:39 INFO - 'LOGNAME': 'cltbld', 02:08:39 INFO - 'MAIL': '/var/mail/cltbld', 02:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:39 INFO - 'MOZ_NO_REMOTE': '1', 02:08:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:39 INFO - 'NO_EM_RESTART': '1', 02:08:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:39 INFO - 'PWD': '/builds/slave/test', 02:08:39 INFO - 'SHELL': '/bin/bash', 02:08:39 INFO - 'SHLVL': '1', 02:08:39 INFO - 'TERM': 'linux', 02:08:39 INFO - 'TMOUT': '86400', 02:08:39 INFO - 'USER': 'cltbld', 02:08:39 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:39 INFO - '_': '/tools/buildbot/bin/python'} 02:08:40 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:08:40 INFO - Downloading/unpacking pip>=1.5 02:08:40 INFO - Running setup.py egg_info for package pip 02:08:40 INFO - warning: no files found matching 'pip/cacert.pem' 02:08:40 INFO - warning: no files found matching '*.html' under directory 'docs' 02:08:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:08:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:08:40 INFO - Installing collected packages: pip 02:08:40 INFO - Found existing installation: pip 1.1 02:08:40 INFO - Uninstalling pip: 02:08:40 INFO - Successfully uninstalled pip 02:08:40 INFO - Running setup.py install for pip 02:08:40 INFO - warning: no files found matching 'pip/cacert.pem' 02:08:40 INFO - warning: no files found matching '*.html' under directory 'docs' 02:08:40 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 02:08:40 INFO - no previously-included directories found matching 'docs/_build/_sources' 02:08:40 INFO - Installing pip script to /builds/slave/test/build/venv/bin 02:08:40 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 02:08:40 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 02:08:40 INFO - Successfully installed pip 02:08:40 INFO - Cleaning up... 02:08:40 INFO - Return code: 0 02:08:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:08:40 INFO - Using env: {'DISPLAY': ':0', 02:08:40 INFO - 'HOME': '/home/cltbld', 02:08:40 INFO - 'LANG': 'en_US.UTF-8', 02:08:40 INFO - 'LANGUAGE': 'en_US:en', 02:08:40 INFO - 'LOGNAME': 'cltbld', 02:08:40 INFO - 'MAIL': '/var/mail/cltbld', 02:08:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:40 INFO - 'MOZ_NO_REMOTE': '1', 02:08:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:40 INFO - 'NO_EM_RESTART': '1', 02:08:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:40 INFO - 'PWD': '/builds/slave/test', 02:08:40 INFO - 'SHELL': '/bin/bash', 02:08:40 INFO - 'SHLVL': '1', 02:08:40 INFO - 'TERM': 'linux', 02:08:40 INFO - 'TMOUT': '86400', 02:08:40 INFO - 'USER': 'cltbld', 02:08:40 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:40 INFO - '_': '/tools/buildbot/bin/python'} 02:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:41 INFO - Downloading/unpacking psutil>=3.1.1 02:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:08:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:42 INFO - Installing collected packages: psutil 02:08:42 INFO - Running setup.py install for psutil 02:08:42 INFO - building 'psutil._psutil_linux' extension 02:08:42 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 02:08:42 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 02:08:42 INFO - building 'psutil._psutil_posix' extension 02:08:42 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 02:08:42 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 02:08:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:08:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:08:42 INFO - Successfully installed psutil 02:08:42 INFO - Cleaning up... 02:08:42 INFO - Return code: 0 02:08:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:08:42 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')]} 02:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:42 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')]} 02:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:42 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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:42 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 02:08:42 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 02:08:42 INFO - Using env: {'DISPLAY': ':0', 02:08:42 INFO - 'HOME': '/home/cltbld', 02:08:42 INFO - 'LANG': 'en_US.UTF-8', 02:08:42 INFO - 'LANGUAGE': 'en_US:en', 02:08:42 INFO - 'LOGNAME': 'cltbld', 02:08:42 INFO - 'MAIL': '/var/mail/cltbld', 02:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:42 INFO - 'MOZ_NO_REMOTE': '1', 02:08:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:42 INFO - 'NO_EM_RESTART': '1', 02:08:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:42 INFO - 'PWD': '/builds/slave/test', 02:08:42 INFO - 'SHELL': '/bin/bash', 02:08:42 INFO - 'SHLVL': '1', 02:08:42 INFO - 'TERM': 'linux', 02:08:42 INFO - 'TMOUT': '86400', 02:08:42 INFO - 'USER': 'cltbld', 02:08:42 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:42 INFO - '_': '/tools/buildbot/bin/python'} 02:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:08:43 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 02:08:43 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 02:08:43 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 02:08:43 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 02:08:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:08:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:08:44 INFO - Installing collected packages: mozsystemmonitor 02:08:44 INFO - Running setup.py install for mozsystemmonitor 02:08:44 INFO - Successfully installed mozsystemmonitor 02:08:44 INFO - Cleaning up... 02:08:45 INFO - Return code: 0 02:08:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:08:45 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')]} 02:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:45 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')]} 02:08:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:45 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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:45 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 02:08:45 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 02:08:45 INFO - Using env: {'DISPLAY': ':0', 02:08:45 INFO - 'HOME': '/home/cltbld', 02:08:45 INFO - 'LANG': 'en_US.UTF-8', 02:08:45 INFO - 'LANGUAGE': 'en_US:en', 02:08:45 INFO - 'LOGNAME': 'cltbld', 02:08:45 INFO - 'MAIL': '/var/mail/cltbld', 02:08:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:45 INFO - 'MOZ_NO_REMOTE': '1', 02:08:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:45 INFO - 'NO_EM_RESTART': '1', 02:08:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:45 INFO - 'PWD': '/builds/slave/test', 02:08:45 INFO - 'SHELL': '/bin/bash', 02:08:45 INFO - 'SHLVL': '1', 02:08:45 INFO - 'TERM': 'linux', 02:08:45 INFO - 'TMOUT': '86400', 02:08:45 INFO - 'USER': 'cltbld', 02:08:45 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:45 INFO - '_': '/tools/buildbot/bin/python'} 02:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:45 INFO - Downloading/unpacking jsonschema==2.5.1 02:08:45 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 02:08:45 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 02:08:45 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 02:08:45 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 02:08:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:08:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:08:46 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 02:08:46 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 02:08:46 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 02:08:46 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 02:08:46 INFO - Downloading functools32-3.2.3-2.tar.gz 02:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:08:46 INFO - warning: no files found matching '*.txt' 02:08:46 INFO - no previously-included directories found matching 'build' 02:08:46 INFO - no previously-included directories found matching 'dist' 02:08:46 INFO - no previously-included directories found matching '.git*' 02:08:46 INFO - Installing collected packages: jsonschema, functools32 02:08:47 INFO - Running setup.py install for functools32 02:08:47 INFO - warning: no files found matching '*.txt' 02:08:47 INFO - no previously-included directories found matching 'build' 02:08:47 INFO - no previously-included directories found matching 'dist' 02:08:47 INFO - no previously-included directories found matching '.git*' 02:08:47 INFO - Successfully installed jsonschema functools32 02:08:47 INFO - Cleaning up... 02:08:47 INFO - Return code: 0 02:08:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:08:47 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')]} 02:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:47 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')]} 02:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:47 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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:47 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 02:08:47 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 02:08:47 INFO - Using env: {'DISPLAY': ':0', 02:08:47 INFO - 'HOME': '/home/cltbld', 02:08:47 INFO - 'LANG': 'en_US.UTF-8', 02:08:47 INFO - 'LANGUAGE': 'en_US:en', 02:08:47 INFO - 'LOGNAME': 'cltbld', 02:08:47 INFO - 'MAIL': '/var/mail/cltbld', 02:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:47 INFO - 'MOZ_NO_REMOTE': '1', 02:08:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:47 INFO - 'NO_EM_RESTART': '1', 02:08:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:47 INFO - 'PWD': '/builds/slave/test', 02:08:47 INFO - 'SHELL': '/bin/bash', 02:08:47 INFO - 'SHLVL': '1', 02:08:47 INFO - 'TERM': 'linux', 02:08:47 INFO - 'TMOUT': '86400', 02:08:47 INFO - 'USER': 'cltbld', 02:08:47 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:47 INFO - '_': '/tools/buildbot/bin/python'} 02:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:08:47 INFO - Cleaning up... 02:08:47 INFO - Return code: 0 02:08:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:08:47 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')]} 02:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:47 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')]} 02:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:47 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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:47 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 02:08:47 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 02:08:47 INFO - Using env: {'DISPLAY': ':0', 02:08:47 INFO - 'HOME': '/home/cltbld', 02:08:47 INFO - 'LANG': 'en_US.UTF-8', 02:08:47 INFO - 'LANGUAGE': 'en_US:en', 02:08:47 INFO - 'LOGNAME': 'cltbld', 02:08:47 INFO - 'MAIL': '/var/mail/cltbld', 02:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:47 INFO - 'MOZ_NO_REMOTE': '1', 02:08:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:47 INFO - 'NO_EM_RESTART': '1', 02:08:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:47 INFO - 'PWD': '/builds/slave/test', 02:08:47 INFO - 'SHELL': '/bin/bash', 02:08:47 INFO - 'SHLVL': '1', 02:08:47 INFO - 'TERM': 'linux', 02:08:47 INFO - 'TMOUT': '86400', 02:08:47 INFO - 'USER': 'cltbld', 02:08:47 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:47 INFO - '_': '/tools/buildbot/bin/python'} 02:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:47 INFO - Downloading/unpacking blobuploader==1.2.4 02:08:47 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 02:08:47 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 02:08:47 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 02:08:47 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 02:08:48 INFO - Downloading blobuploader-1.2.4.tar.gz 02:08:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:08:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:08:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:08:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:08:49 INFO - Downloading docopt-0.6.1.tar.gz 02:08:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:08:49 INFO - Installing collected packages: blobuploader, requests, docopt 02:08:49 INFO - Running setup.py install for blobuploader 02:08:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:08:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:08:49 INFO - Running setup.py install for requests 02:08:49 INFO - Running setup.py install for docopt 02:08:49 INFO - Successfully installed blobuploader requests docopt 02:08:49 INFO - Cleaning up... 02:08:49 INFO - Return code: 0 02:08:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:08:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:49 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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:49 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 02:08:49 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 02:08:49 INFO - Using env: {'DISPLAY': ':0', 02:08:49 INFO - 'HOME': '/home/cltbld', 02:08:49 INFO - 'LANG': 'en_US.UTF-8', 02:08:49 INFO - 'LANGUAGE': 'en_US:en', 02:08:49 INFO - 'LOGNAME': 'cltbld', 02:08:49 INFO - 'MAIL': '/var/mail/cltbld', 02:08:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:49 INFO - 'MOZ_NO_REMOTE': '1', 02:08:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:49 INFO - 'NO_EM_RESTART': '1', 02:08:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:49 INFO - 'PWD': '/builds/slave/test', 02:08:49 INFO - 'SHELL': '/bin/bash', 02:08:49 INFO - 'SHLVL': '1', 02:08:49 INFO - 'TERM': 'linux', 02:08:49 INFO - 'TMOUT': '86400', 02:08:49 INFO - 'USER': 'cltbld', 02:08:49 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:49 INFO - '_': '/tools/buildbot/bin/python'} 02:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:50 INFO - Running setup.py (path:/tmp/pip-U7B8OD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:50 INFO - Running setup.py (path:/tmp/pip-I8Xw0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:50 INFO - Running setup.py (path:/tmp/pip-hc6aWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:50 INFO - Running setup.py (path:/tmp/pip-7Pod0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:50 INFO - Running setup.py (path:/tmp/pip-io3N_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:50 INFO - Running setup.py (path:/tmp/pip-rsaUhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:50 INFO - Running setup.py (path:/tmp/pip-50meQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:50 INFO - Running setup.py (path:/tmp/pip-EmNtJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:50 INFO - Running setup.py (path:/tmp/pip-nhvP3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:50 INFO - Running setup.py (path:/tmp/pip-Sk9kya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:50 INFO - Running setup.py (path:/tmp/pip-Wku0Ma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:50 INFO - Running setup.py (path:/tmp/pip-qdJQip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:51 INFO - Running setup.py (path:/tmp/pip-myHhQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:51 INFO - Running setup.py (path:/tmp/pip-TkPJfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:51 INFO - Running setup.py (path:/tmp/pip-UaocBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:51 INFO - Running setup.py (path:/tmp/pip-HFiekJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:08:51 INFO - Running setup.py (path:/tmp/pip-miJe1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:08:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:08:51 INFO - Running setup.py install for manifestparser 02:08:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:08:51 INFO - Running setup.py install for mozcrash 02:08:51 INFO - Running setup.py install for mozdebug 02:08:51 INFO - Running setup.py install for mozdevice 02:08:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:08:51 INFO - Running setup.py install for mozfile 02:08:51 INFO - Running setup.py install for mozhttpd 02:08:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:08:51 INFO - Running setup.py install for mozinfo 02:08:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Running setup.py install for mozInstall 02:08:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Running setup.py install for mozleak 02:08:52 INFO - Running setup.py install for mozlog 02:08:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Running setup.py install for moznetwork 02:08:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Running setup.py install for mozprocess 02:08:52 INFO - Running setup.py install for mozprofile 02:08:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Running setup.py install for mozrunner 02:08:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:08:52 INFO - Running setup.py install for mozscreenshot 02:08:52 INFO - Running setup.py install for moztest 02:08:53 INFO - Running setup.py install for mozversion 02:08:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:08:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:08:53 INFO - Cleaning up... 02:08:53 INFO - Return code: 0 02:08:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08: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')]} 02:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08: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')]} 02:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:53 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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:53 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 02:08:53 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 02:08:53 INFO - Using env: {'DISPLAY': ':0', 02:08:53 INFO - 'HOME': '/home/cltbld', 02:08:53 INFO - 'LANG': 'en_US.UTF-8', 02:08:53 INFO - 'LANGUAGE': 'en_US:en', 02:08:53 INFO - 'LOGNAME': 'cltbld', 02:08:53 INFO - 'MAIL': '/var/mail/cltbld', 02:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:53 INFO - 'MOZ_NO_REMOTE': '1', 02:08:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:53 INFO - 'NO_EM_RESTART': '1', 02:08:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:53 INFO - 'PWD': '/builds/slave/test', 02:08:53 INFO - 'SHELL': '/bin/bash', 02:08:53 INFO - 'SHLVL': '1', 02:08:53 INFO - 'TERM': 'linux', 02:08:53 INFO - 'TMOUT': '86400', 02:08:53 INFO - 'USER': 'cltbld', 02:08:53 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:53 INFO - '_': '/tools/buildbot/bin/python'} 02:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:08:53 INFO - Running setup.py (path:/tmp/pip-vcpIVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:08:53 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)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:08:53 INFO - Running setup.py (path:/tmp/pip-lEDCdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:08:53 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)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:08:53 INFO - Running setup.py (path:/tmp/pip-zt0oCK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:08:53 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)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:08:53 INFO - Running setup.py (path:/tmp/pip-5ez7X7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:08:53 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)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:08:53 INFO - Running setup.py (path:/tmp/pip-gEwKVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:08:53 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)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:08:53 INFO - Running setup.py (path:/tmp/pip-8Yt6lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:08:53 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)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:08:53 INFO - Running setup.py (path:/tmp/pip-y9bVN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:08:53 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)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:08:53 INFO - Running setup.py (path:/tmp/pip-i4XfPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:08:53 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)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:08:53 INFO - Running setup.py (path:/tmp/pip-X3R4Rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:08:53 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)) 02:08:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:08:53 INFO - Running setup.py (path:/tmp/pip-ihmdZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:08:54 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)) 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:08:54 INFO - Running setup.py (path:/tmp/pip-yYZKur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:08:54 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)) 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:08:54 INFO - Running setup.py (path:/tmp/pip-q96uqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:08:54 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)) 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:08:54 INFO - Running setup.py (path:/tmp/pip-gjcx9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:08:54 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)) 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:08:54 INFO - Running setup.py (path:/tmp/pip-_LaprQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:08:54 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)) 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:54 INFO - Running setup.py (path:/tmp/pip-PftI8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:08:54 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)) 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:08:54 INFO - Running setup.py (path:/tmp/pip-HXTEG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:08:54 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)) 02:08:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:08:54 INFO - Running setup.py (path:/tmp/pip-tXE6Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:08:54 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)) 02:08:54 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)) 02:08:54 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)) 02:08:54 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)) 02:08:54 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)) 02:08:54 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)) 02:08:54 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)) 02:08:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:08:54 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 02:08:54 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 02:08:54 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 02:08:54 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 02:08:55 INFO - Downloading blessings-1.6.tar.gz 02:08:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:08:55 INFO - Installing collected packages: blessings 02:08:55 INFO - Running setup.py install for blessings 02:08:55 INFO - Successfully installed blessings 02:08:55 INFO - Cleaning up... 02:08:55 INFO - Return code: 0 02:08:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:08:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:08:56 INFO - Reading from file tmpfile_stdout 02:08:56 INFO - Current package versions: 02:08:56 INFO - argparse == 1.2.1 02:08:56 INFO - blessings == 1.6 02:08:56 INFO - blobuploader == 1.2.4 02:08:56 INFO - docopt == 0.6.1 02:08:56 INFO - functools32 == 3.2.3-2 02:08:56 INFO - jsonschema == 2.5.1 02:08:56 INFO - manifestparser == 1.1 02:08:56 INFO - mozInstall == 1.13 02:08:56 INFO - mozcrash == 1.0 02:08:56 INFO - mozdebug == 0.1 02:08:56 INFO - mozdevice == 0.51 02:08:56 INFO - mozfile == 1.2 02:08:56 INFO - mozhttpd == 0.7 02:08:56 INFO - mozinfo == 0.10 02:08:56 INFO - mozleak == 0.1 02:08:56 INFO - mozlog == 3.5 02:08:56 INFO - moznetwork == 0.27 02:08:56 INFO - mozprocess == 0.25 02:08:56 INFO - mozprofile == 0.28 02:08:56 INFO - mozrunner == 6.13 02:08:56 INFO - mozscreenshot == 0.1 02:08:56 INFO - mozsystemmonitor == 0.3 02:08:56 INFO - moztest == 0.8 02:08:56 INFO - mozversion == 1.4 02:08:56 INFO - psutil == 3.1.1 02:08:56 INFO - requests == 1.2.3 02:08:56 INFO - wsgiref == 0.1.2 02:08:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:08: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')]} 02:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08: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')]} 02:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:56 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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:56 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 02:08:56 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 02:08:56 INFO - Using env: {'DISPLAY': ':0', 02:08:56 INFO - 'HOME': '/home/cltbld', 02:08:56 INFO - 'LANG': 'en_US.UTF-8', 02:08:56 INFO - 'LANGUAGE': 'en_US:en', 02:08:56 INFO - 'LOGNAME': 'cltbld', 02:08:56 INFO - 'MAIL': '/var/mail/cltbld', 02:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:56 INFO - 'MOZ_NO_REMOTE': '1', 02:08:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:56 INFO - 'NO_EM_RESTART': '1', 02:08:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:56 INFO - 'PWD': '/builds/slave/test', 02:08:56 INFO - 'SHELL': '/bin/bash', 02:08:56 INFO - 'SHLVL': '1', 02:08:56 INFO - 'TERM': 'linux', 02:08:56 INFO - 'TMOUT': '86400', 02:08:56 INFO - 'USER': 'cltbld', 02:08:56 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:56 INFO - '_': '/tools/buildbot/bin/python'} 02:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:56 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)) 02:08:56 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)) 02:08:56 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)) 02:08:56 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)) 02:08:56 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)) 02:08:56 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)) 02:08:56 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)) 02:08:56 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)) 02:08:56 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)) 02:08:56 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 02:08: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 02:08: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 02:08: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 02:08: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 02:08:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 02:08:57 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)) 02:08:57 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)) 02:08:57 INFO - Installing collected packages: simplejson 02:08:57 INFO - Running setup.py install for simplejson 02:08:57 INFO - building 'simplejson._speedups' extension 02:08:57 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 02:08:58 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 02:08:58 INFO - Successfully installed simplejson 02:08:58 INFO - Cleaning up... 02:08:58 INFO - Return code: 0 02:08:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:08:58 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')]} 02:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:08:58 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')]} 02:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:08:58 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 0x180f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19d8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b7ba60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, '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': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', '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 02:08:58 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 02:08:58 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 02:08:58 INFO - Using env: {'DISPLAY': ':0', 02:08:58 INFO - 'HOME': '/home/cltbld', 02:08:58 INFO - 'LANG': 'en_US.UTF-8', 02:08:58 INFO - 'LANGUAGE': 'en_US:en', 02:08:58 INFO - 'LOGNAME': 'cltbld', 02:08:58 INFO - 'MAIL': '/var/mail/cltbld', 02:08:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:08:58 INFO - 'MOZ_NO_REMOTE': '1', 02:08:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:08:58 INFO - 'NO_EM_RESTART': '1', 02:08:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:08:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:08:58 INFO - 'PWD': '/builds/slave/test', 02:08:58 INFO - 'SHELL': '/bin/bash', 02:08:58 INFO - 'SHLVL': '1', 02:08:58 INFO - 'TERM': 'linux', 02:08:58 INFO - 'TMOUT': '86400', 02:08:58 INFO - 'USER': 'cltbld', 02:08:58 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:08:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:08:58 INFO - '_': '/tools/buildbot/bin/python'} 02:08:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:08:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:08:58 INFO - Cleaning up... 02:08:58 INFO - Return code: 0 02:08:58 INFO - Running post-action listener: _resource_record_post_action 02:08:58 INFO - Running post-action listener: _start_resource_monitoring 02:08:58 INFO - Starting resource monitoring. 02:08:58 INFO - [mozharness: 2017-09-10 09:08:58.807565Z] Finished create-virtualenv step (success) 02:08:58 INFO - [mozharness: 2017-09-10 09:08:58.808035Z] Running install step. 02:08:58 INFO - Running pre-action listener: _resource_record_pre_action 02:08:58 INFO - Running main action method: install 02:08:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:08:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:08:59 INFO - Reading from file tmpfile_stdout 02:08:59 INFO - Detecting whether we're running mozinstall >=1.0... 02:08:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:08:59 INFO - Reading from file tmpfile_stdout 02:08:59 INFO - Output received: 02:08:59 INFO - Usage: mozinstall [options] installer 02:08:59 INFO - Options: 02:08:59 INFO - -h, --help show this help message and exit 02:08:59 INFO - -d DEST, --destination=DEST 02:08:59 INFO - Directory to install application into. [default: 02:08:59 INFO - "/builds/slave/test"] 02:08:59 INFO - --app=APP Application being installed. [default: firefox] 02:08:59 INFO - mkdir: /builds/slave/test/build/application 02:08:59 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'] 02:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 02:09:15 INFO - Reading from file tmpfile_stdout 02:09:15 INFO - Output received: 02:09:15 INFO - /builds/slave/test/build/application/firefox/firefox 02:09:15 INFO - Running post-action listener: _resource_record_post_action 02:09:15 INFO - [mozharness: 2017-09-10 09:09:15.753600Z] Finished install step (success) 02:09:15 INFO - [mozharness: 2017-09-10 09:09:15.753850Z] Running setup-mitmproxy step. 02:09:15 INFO - Running pre-action listener: _resource_record_pre_action 02:09:15 INFO - Running main action method: setup_mitmproxy 02:09:15 INFO - Skipping: mitmproxy is not required 02:09:15 INFO - Running post-action listener: _resource_record_post_action 02:09:15 INFO - [mozharness: 2017-09-10 09:09:15.754982Z] Finished setup-mitmproxy step (success) 02:09:15 INFO - [mozharness: 2017-09-10 09:09:15.755202Z] Running run-tests step. 02:09:15 INFO - Running pre-action listener: _resource_record_pre_action 02:09:15 INFO - Running pre-action listener: _set_gcov_prefix 02:09:15 INFO - Running main action method: run_tests 02:09:15 WARNING - Try message not found. 02:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:09:15 INFO - Python 2.7.3 02:09:15 INFO - Return code: 0 02:09:15 INFO - grabbing minidump binary from tooltool 02:09:15 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')]} 02:09:15 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 0x1b7f090>, '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 0x1b7fd80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b79f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:09:15 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 02:09:15 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 02:09:15 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 02:09:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:09:15 INFO - Return code: 0 02:09:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:09:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:09:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:09:15 INFO - ENV: RUST_BACKTRACE is now full 02:09:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:09:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 02:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 02:09:15 INFO - Using env: {'DISPLAY': ':0', 02:09:15 INFO - 'HOME': '/home/cltbld', 02:09:15 INFO - 'LANG': 'en_US.UTF-8', 02:09:15 INFO - 'LANGUAGE': 'en_US:en', 02:09:15 INFO - 'LOGNAME': 'cltbld', 02:09:15 INFO - 'MAIL': '/var/mail/cltbld', 02:09:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:09:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:15 INFO - 'MOZ_NO_REMOTE': '1', 02:09:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:09:15 INFO - 'NO_EM_RESTART': '1', 02:09:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:15 INFO - 'PWD': '/builds/slave/test', 02:09:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:09:15 INFO - 'RUST_BACKTRACE': 'full', 02:09:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:09:15 INFO - 'SHELL': '/bin/bash', 02:09:15 INFO - 'SHLVL': '1', 02:09:15 INFO - 'TERM': 'linux', 02:09:15 INFO - 'TMOUT': '86400', 02:09:15 INFO - 'USER': 'cltbld', 02:09:15 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:15 INFO - '_': '/tools/buildbot/bin/python'} 02:09:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 02:09:16 INFO - mozversion application_buildid: 20170910080600 02:09:16 INFO - mozversion application_changeset: f8f481901e2d86dd5be8b8a9af1f81f67e1e9158 02:09:16 INFO - mozversion application_display_name: Nightly 02:09:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:09:16 INFO - mozversion application_name: Firefox 02:09:16 INFO - mozversion application_remotingname: firefox 02:09:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:09:16 INFO - mozversion application_vendor: Mozilla 02:09:16 INFO - mozversion application_version: 57.0a1 02:09:16 INFO - mozversion platform_buildid: 20170910080600 02:09:16 INFO - mozversion platform_changeset: f8f481901e2d86dd5be8b8a9af1f81f67e1e9158 02:09:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:09:16 INFO - mozversion platform_version: 57.0a1 02:09:16 INFO - using testdate: 1505034556 02:09:16 INFO - actual date: 1505034556 02:09:16 INFO - starting webserver on 'localhost:56434' 02:09:16 INFO - SUITE-START | Running 2 tests 02:09:16 INFO - TEST-START | dromaeo_css 02:09:16 INFO - Initialising browser for dromaeo_css test... 02:09:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56434/getInfo.html -profile /tmp/tmpJYfb_v/profile 02:09:16 INFO - TEST-INFO | started process 27496 (/builds/slave/test/build/application/firefox/firefox http://localhost:56434/getInfo.html) 02:09:21 INFO - TEST-INFO | 27496: exit 0 02:09:22 INFO - Browser initialized. 02:09:22 INFO - Running cycle 1/1 for dromaeo_css test... 02:09:22 INFO - Using env: {'DISPLAY': ':0', 02:09:22 INFO - 'HOME': '/home/cltbld', 02:09:22 INFO - 'JSGC_DISABLE_POISONING': '1', 02:09:22 INFO - 'LANG': 'en_US.UTF-8', 02:09:22 INFO - 'LANGUAGE': 'en_US:en', 02:09:22 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:09:22 INFO - 'LOGNAME': 'cltbld', 02:09:22 INFO - 'MAIL': '/var/mail/cltbld', 02:09:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:09:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:09:22 INFO - 'MOZ_CRASHREPORTER': '1', 02:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:22 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:09:22 INFO - 'MOZ_NO_REMOTE': '1', 02:09:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:09:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:09:22 INFO - 'NO_EM_RESTART': '1', 02:09:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:22 INFO - 'PWD': '/builds/slave/test', 02:09:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:09:22 INFO - 'RUST_BACKTRACE': 'full', 02:09:22 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:09:22 INFO - 'SHELL': '/bin/bash', 02:09:22 INFO - 'SHLVL': '1', 02:09:22 INFO - 'TERM': 'linux', 02:09:22 INFO - 'TMOUT': '86400', 02:09:22 INFO - 'USER': 'cltbld', 02:09:22 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:22 INFO - '_': '/tools/buildbot/bin/python'} 02:09:22 INFO - TEST-INFO | started process 27692 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJYfb_v/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:09:23 INFO - PID 27692 | 02:09:23 INFO - PID 27692 | (/builds/slave/test/build/application/firefox/firefox:27743): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:09:23 INFO - PID 27692 | 02:09:33 INFO - PID 27692 | RSS: Main: 172679168 02:09:33 INFO - PID 27692 | 02:12:30 INFO - PID 27692 | Cycle 1(1): loaded http://localhost:56434/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56434/tests/dromaeo/cssquery-ext.html) 02:12:31 INFO - PID 27692 | RSS: Main: 174624768 02:12:31 INFO - PID 27692 | 02:15:32 INFO - PID 27692 | Cycle 1(1): loaded http://localhost:56434/tests/dromaeo/cssquery-ext.html (next: http://localhost:56434/tests/dromaeo/cssquery-jquery.html) 02:15:33 INFO - PID 27692 | RSS: Main: 171134976 02:15:33 INFO - PID 27692 | 02:18:35 INFO - PID 27692 | Cycle 1(1): loaded http://localhost:56434/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56434/tests/dromaeo/cssquery-mootools.html) 02:18:35 INFO - PID 27692 | RSS: Main: 173735936 02:18:35 INFO - PID 27692 | 02:21:37 INFO - PID 27692 | Cycle 1(1): loaded http://localhost:56434/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56434/tests/dromaeo/cssquery-prototype.html) 02:21:38 INFO - PID 27692 | RSS: Main: 170991616 02:21:38 INFO - PID 27692 | 02:24:41 INFO - PID 27692 | Cycle 1(1): loaded http://localhost:56434/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56434/tests/dromaeo/cssquery-yui.html) 02:24:42 INFO - PID 27692 | RSS: Main: 172978176 02:24:42 INFO - PID 27692 | 02:27:43 INFO - PID 27692 | Cycle 1(1): loaded http://localhost:56434/tests/dromaeo/cssquery-yui.html (next: http://localhost:56434/tests/dromaeo/cssquery-dojo.html) 02:27:43 INFO - PID 27692 | RSS: Main: 175808512 02:27:43 INFO - PID 27692 | 02:27:43 INFO - PID 27692 | __start_tp_report 02:27:43 INFO - PID 27692 | _x_x_mozilla_page_load 02:27:43 INFO - PID 27692 | _x_x_mozilla_page_load_details 02:27:43 INFO - PID 27692 | |i|pagename|runs| 02:27:43 INFO - PID 27692 | |0;dojo.html;3105.922351941201;3106.9999999999986;3120.7035331643488;3146.77972541922;3159.304952910359;6166.53750968678;6256.374362563742;6303.590266632671;6351.745930162794;6386.616802991818;6188.876222475547;6197.194364732579;6204.379562043793;6212.689365531728;6218.067289906517;5727.284089488824;5771.595988280801;5778.711064446782;5792.82621521355;5887.35239123696;5858.941410585903;5890.263717035371;5901.616394934315;5905.084711869646;5909.763609455638;5821.09772985188;5850.062345688209;5923.437273459014;5934.970325148368;5937.584369094165;813.2404459963772;5234.319538460014;5669.829905102853;5799.21710569072;5808.97095514524;5706.600537962344;5713.342965558931;5778.682172480512;5808.30300363959;5822.854428639275;6257.937420625781;6266.154069200645;6272.717727702265;6330.208723909512;6357.809265722036;6686.5653732507235;6687.565308254996;6694.83262918426;6785.355391237825;6792.558483698544;6644.069830223772;6770.796876093725;6776.186857577122;6858.348456896587;6897.48268879831;6225.190725205694;6226.501879849601;6226.657533835637;6247.094171345129;6294.590851594678;6908.723651053901;6973.686184121625;6991.93008069923;7011.859762804817;7020.403265722369;6672.566283191577;6735.057092007123;6738.528303018742;6739.056532085512;6739.764108256286;6742.393184613408;6751.966240168866;6768.2216545097945;6822.556533825285;6842.6578671066245;6565.343465653492;6668.199816022108;6682;6688.230853451818;6786.59280443175;6606.570572912745;6622.668866556749;6708.127943367331;6721.059051732761;6829.863402732017;5370.489803468664;5386.434424385461;5396.2984811974975;5401.1358182691365;5402.297701298806;4648.279516674921;4668.5798278155125;4682.836100736458;4689.179393606105;4695.906081878412;5650.378458369576;5742.3396309424115;5772.307323121168;5776.075827867521;5861.7655293787775;6794.966025169842;6924.6537673117155;6924.965375173092;6936.791896243019;6938.132733408324;5830.854228644318;5848.064309710511;5853.765849365977;5866.589338746332;5885.676287804212;5786.537077033911;5874.9118763218585;5876.500497457764;5878.2064421302575;5882.441168089024;6382.489400847921;6476.967615161894;6485.351464853571;6564.967175164189;6570.474362051024;6693.832654183684;6729.192496900306;6806.727730890709;6835.58986460814;6836.658167091725;6295.748170073146;6466.805995820133;6470.805875823733;6479.870402592016;6494.870102598016;6482.157319548617;6534.117894084237;6559.01614757771;6569.868602627825;6602.1747281589805;6655.23464801554;6656.567323123982;6721.731130754911;6744.932550674431;6769.458443324621;6564.146660934048;6571.34286571339;6579.80260592183;6588.176477940257;6593.472522198309;6389.68051597409;6479.449246813885;6494.123293355526;6563;6580.177477815273;6341.682915854097;6425.036244563353;6452.12896258999;6505.674716264073;6520.413162815369;6633.170853643294;6684.498662600227;6684.766033188815;6732.596044237362;6735.764248251287;6292.993121100602;6388.840278993062;6436.485081193587;6464.191976003;6464.903026454701 02:27:43 INFO - PID 27692 | |1;ext.html;851.7487341234336;876.163264082801;877.2411863737868;877.7191298784384;880.3573391424262;37977.620223797756;41589.168216635684;41835;41839.79080104603;41922.58077419225;39001.609983900154;39270.21459570807;39311.606883931156;39352.60647393525;39364.21271574567;6913.930860691379;7031.367176954073;7630.465867389257;7795.610219489031;7811.726589569366;7572;7657.808554786119;7660.387169026492;7667.463277570572;7668.846623067535;7606.125295590995;7662.961685191566;7665.846683066336;7670.309672129507;7679.57762323072;7589;7638.121616014146;7651.196624354446;7660.61696915152;7667.271609197117;7628.618569071524;7686.039245094364;7692.884606730903;7704.229577042307;7704.768856934301;37207.813960930296;37383.81308093442;37502.81248593767;37561;37661.81169094164;3551.449525323579;3585.4084076127406;3586.4799604057525;3588.4976103345552;3590.874319398835;30063.849680751457;30371.544426833614;30414.239644008856;30449.695503045128;30474.238144046354;4987.276844857475;5188.948110518884;5195.0908590996805;5218;5221.947780522184;178626;179107;179387;180322;180637;40567.7971610146;41286.58713412887;46945.76527117411;47020.05959880852;47146.5285347149;48651.27023094657;49151.26273105906;49245;49345.753271233414;49379;15075.773863392058;15215.923920380328;15216.543503694907;15220.162891041098;15231.086134831945;11932.105092118127;11958.282503049844;12019.939900300618;12037.87962120385;12041.698957526132;13308.467661293633;13331.933340333237;13383;13426.731465370833;13487.460501580023;5405.189221616711;5455.18172274162;5495.735980724456;5591.384947655754;5650.717464126859;996.1881066930429;1010.3836659637615;1016.2632091733582;1026.8511065895552;1031.3502493429091;22626.208082717025;22660.320190394315;22749.886250568972;22785.88607056954;22819.429514261945;1101.862267216598;1112.527175950218;1112.6161474291519;1113.9888601114046;1122.7361570031037;11898.22661526998;11933.58232461877;12003.97966172885;12004.939975300067;12050.457729402022;222109;223372.88313558654;223517;223764;223959;50015;50160;50266.497335026914;50381.49618503841;50749.23876141861;1430.4349781836117;1448.1890141520782;1452.7239824433325;1463.239115659851;1466.3987765016504;21687;22047.33857984263;22136;22260.109595615995;22525.436864077874;36180.27639447144;36677.81661091677;36716.44925326176;36738.26523469569;36810.447843281836;181210;181285;181372;181686;181884;30971;31084.222894426915;31455;31482.212944676565;31489.425528723932;40331;40459.79770101131;40872.38691419572;40926.59073409228;41002.79498602488;29792;30315.39369212559;30649.233769155948;30716.53925191079;30749.692503074682;30158;30246.243843904078;30410.695893040785;30419.54370684486;30724.846375768873;34603.4809477853;34650.82674586611;34889.8255508731;35056.12359691028;35131.297374051865;30260.5460918082;30269.848650757485;30383.69616303897;30401.543976840807;30489.08532744021;22502.5499490006;22660.320190394315;22981.425464363525;23007;23168.07327706873 02:27:43 INFO - PID 27692 | |2;jquery.html;560.498353973194;570.9143628455734;572.5905977226283;576.2249774053896;595.4462350014485;22046.236301095912;22705.318840434793;22770.88614556927;22793.202237921676;22810.429739256528;20864.269750558735;21661.891690541564;21857.344279671597;24551;24840.254792356212;6248.094026366186;6406.359364063603;6418.42234198922;6418.807435776934;6438.259600145973;6138.907916381258;6331.841703957415;6431.646259455728;6431.742730290783;6431.903521447182;6400.967995160017;6446.323136070715;6468.74125034998;6475;6477.157969463963;6452.290248072755;6458.063580780784;6462.450691691212;6464.547481676284;6485.189351331083;5923.904077745631;5987.072003839368;6144.41628045335;6147.66187859672;6150.323464418911;28438.72025758846;28973;29021.533169680602;29021.56467652987;29040.41919161626;2055.403932859469;2087.5511764970606;2092.0376626751713;2107.34672251601;2108.4728817795553;18741.437756867315;19203.51991200217;19509.414717558495;19527.80472195288;19610.31363902271;2337.3805941425535;2386.129062892049;2395.4370722880108;2455.717592476852;2519.1938579654516;116148.41925790317;120825;136564.31717841447;139587;139939.99999999898;20071.698924516142;21140.894295528426;21243.088122069086;21731.23940662069;21762.455938601663;41324.79337603353;41418.171636567706;41721.79139104285;41770.791146044074;41810.581894181276;11202.719932001764;11343.886561134283;11426.657200284004;11430.885691143149;11445.08439324852;9516.143547080796;9538.14156725898;9550.140487356171;9670.90329096714;9684.46735429558;11115.72210694739;11196.832047519294;11224.214304998572;11261.265747149395;11304.060944154578;4167.6874234432535;4186.372044193335;4259.3185090386005;4263.658907287471;4265.232258193555;954.627695198873;965.2519297544459;966.3766870368573;973.2943615878573;981.504340308154;18632.906835465736;18705.438836834917;19157.233710651417;19297.035148242812;19639.01804909749;1016.4511163971521;1023.9129673977646;1026.0560284538094;1029.8661174047477;1031.128696251666;10466.372017678963;10486.528106235237;10533.789324213627;10598.099161571352;10779.568817247222;403169;414628;420058;425021;426600;109986.45006774915;112176;112224;112620;113303.43348283206;1143.5140065472142;1182.160665927184;1224.4857159993003;1238.6036468330049;1246.4515613130193;19783;20195.596088077862;20266.79733202649;20348.287809927173;20417.79582204159;57435.138472923776;58008.12987805271;58231;59253;59628.40371596228;73142.63428682822;73851.26148738444;73913;74077.62961185159;74387.62806185934;14064;14220.577942205697;14222.146671199762;14222.573322800332;14380.28098595045;21859.234926777488;21947.341579752632;22477.32568022962;22488.887555562116;22586.09655613823;17730;17785.55536111556;17969.281228750704;17970.5507362317;18010;14998.775018374516;15300.46448374346;15360.846391536388;15623.375064997274;15624.765628515808;17838.821611783715;17929.820701792814;17970.281188752302;18037;18133.727994080364;13481.865181348454;14073.577792666236;14205;14215.50245741394;14218.146911185362;17514.299428023245;17529.737053944456;17668.82331176672;17884.55288617743;18036.36872709449 02:27:43 INFO - PID 27692 | |3;mootools.html;1341.2287934437697;1395.3441882315315;1404.080327385563;1410.6755446247362;1412.7245187188496;14904.627384315383;15586.376544938203;15615.843841561567;15617.297221625025;15620.375184992616;15230.847691523082;15284.388624455009;15375.461858834942;15396.230188490586;15397.384104635828;2806.7193280671872;2815.7747380209585;2857.171420288114;2860.713928607133;2864.584635227891;2791.260316016257;2843.033368654657;2849.3161641206066;2854.072426461408;2855.486012517746;2751.284665986848;2832.8441935693527;2838.3046153692376;2849.244950088228;2850.3159241781927;2812.02984970185;2821.2241633550734;2843.8578071096467;2845.900393486218;2861.3991061876927;2540.6824146981626;2547.8598677072755;2558.9104381346565;2564.756348146923;2585.043533892453;14829;15197.848021519865;15216.391344346213;15251.847481525154;15264.923675381551;7919.683212671486;8234.423590348679;8235.176482351777;8241.95879020601;8251.546164960924;5225.0748613224905;5230.790768369261;5313.38896026958;5405.378381486101;5421.593380496439;1932.9323473678353;1953.140618128019;1983.9404817855489;1990.3232900813794;2012.4767560434245;26471.073512426974;26668.73331266682;26701.19896403111;26711.33221669435;26758;6635.867282654416;6825.5222134451105;6905.205901321312;6983.546069505466;7006.01915731798;16763.832361676472;16831.91584042072;16904.154792260582;16912.746308805377;16919.91540042292;7908.209179082046;8080.959595202104;8081.555514446644;8089.676412943534;8089.716859909875;7330.8900366494545;7442.367398771057;7475.6262186890435;7511.887321690179;7516.849663006709;5185.144451165515;5332.706701131475;5344.35867695879;5351;5365.839024829262;1437.180806940055;1482.3699927530756;1595.369828917571;1609.9436519721753;1611;836.6109758962111;855.5422848776;862.9093945135796;869.5304535550858;871.960761765722;7927.048754149539;7963.124056353797;7991;8072.434929554992;8084.231997960184;801.8997625296838;892.4912799704107;1012.4583347908833;1013.6502906497246;1018.6638409324905;4754.43887435337;5022.472640372781;5033.144365457881;5114.846554603368;5119.769610367543;20859.791402085786;21283.574328513652;21763.23828665989;21969.890150549145;22011.669824952638;21764.238251661114;21782.45543861416;21849.235276765554;22034.33896983093;22042.44893877666;10490.31812932157;10894.945525272324;10904;11016.339019658686;11070.833937490945;8158.184181581794;8183.3453323732765;8210.343172546302;8248.46384984974;8294.87557686623;17641.82358176402;18540.721889171404;18731.90634046821;18799.71800423022;18863.622727545648;18505.9074704631;18886.150123244763;19088.713669295248;19096.522586935436;19501;10664.786704265716;10702.143828493858;10729.731756706145;10773.29973551748;10873.78252434931;13553.661158471117;13559.118657287245;13592.252426116658;13684.178949262678;13698.45206191741;10783.352998820095;10854.891451085388;10856.240063195817;10871.456427178451;11094.72410672783;10377.792444151226;10514;10798.784024319313;10817.350958942488;10914.454277286262;9732.902670973483;9918.702438926844;10029.147522460673;10029.799404012025;10030.398176109456;6991.545549539263;7020.859582808417;7022.578645281299;7092.148942126978;7106.786796396116;6809.965950170415;6871.4502839773695;6948.6525673715105;6967.407770339578;6990.475714321345 02:27:43 INFO - PID 27692 | |4;prototype.html;488.7526041057091;517.6195496310208;522.4827420853355;524.0854708533609;527.7071225469863;6220.782272620453;6277.68611569422;6325.873482530353;6358.658726669597;6361.550802940867;6154.446099851012;6174.969125154379;6214.3474935131835;6214.782482613109;6224.19085518883;4999.900001999976;5038.395392552899;5056.08990381732;5056.673929992208;5093.592512599002;4754.714717116966;4953.950460495403;5061.620378471611;5084.822031228908;5133.768980395891;4847.709137451747;4868.543334232699;4874.050379848054;4910.674117988141;4923.70457772533;4844.442889067767;4852.2479015752615;4854.587360074398;4870.780814863339;4893.09477746618;4317.784110794479;4354.259775838115;4361.258586040349;4376.037271800199;4407.2507673695545;5988.281406231236;6056.909146362808;6104.664243466607;6110.419510146529;6127.234095738033;5465.562754979632;5550.611457197998;5574.219609254707;5583.357913839879;5602.971985140089;6473.385028422293;6491.610503369813;6507.02394640803;6542.803715888531;6611.636360000198;4749.097671442415;4750.2874568814605;4771.761411929424;4799.808007679689;4806.519348065201;6541.67291635421;6590.627374145304;6609.5373323867825;6670.099536562599;6717.361850624183;5305.8938821223355;5336.146216605324;5354.330708661418;5372.382176049804;5376;5408.432114627986;5450.945490545044;5457.28095649869;5465.0215632480395;5489.3687225969525;5811.302643682785;5822.475977162076;5840;5852.209951656472;5897.351291357947;6614.140161779034;6635.5023373247195;6661.9000714989315;6664.433523150489;6675.699593518303;1881.5766452548066;1911.5985643014844;1913.4929243750416;1914.0429785107447;1919.654462196818;1365.378752667534;1395.818543589327;1412.569166404236;1419.4180386041674;1423.110555902561;5631.831045068655;5682.687452190169;5689.487946084872;5696.544276457957;5699.230603868425;6659.203134683445;6700.865982680319;6717.56335838169;6726.96636516824;6735.528513004043;5594.489487838342;5677.290338707661;5687.3459552152035;5714.857128571819;5752.971235143881;3496.230829217568;3513.7364697647777;3529;3539.50446937429;3548.183917698944;6482.416582507597;6491.156149700603;6511.446527045254;6521.217453905561;6545.181852268466;6845.691943862438;6868;6874.587524748531;6876.277990810993;6877.89683154753;6356.936430635635;6421.165248517663;6446.935530644633;6478.773242936474;6485.692296928601;6574.671266436755;6601;6628.900566491603;6671.566348187353;6706.597604143767;6641.634710090798;6644.568103073285;6650.600963942179;6686.431653309523;6764.898526522008;6426.196725409324;6434.806955791334;6507.804765857032;6512.92536731434;6525.433895865053;5355.777352398711;5362.7586758596735;5388.973055134699;5411.810586629449;5430.51125398716;6328.968355158195;6453.290138084713;6517.8696426072165;6699.492614161774;6756.560823546453;6342.302346741762;6365.331640177715;6425.614463132227;6458.773942912164;6527.249366322933;6296.968515157578;6307.306196318493;6326.2092238470195;6447.484201263981;6459.773907913201;6106.358832322542;6297.779577714758;6315.5894866833505;6357.332480089709;6445.967770161119;6537.5750576212395;6571.967140164268;6593.472522198117;6596.724130175082;6632.767853125118;6352.06262090067;6387.584806987531;6391.9041214380895;6392.913204755296;6395.360463953567 02:27:43 INFO - PID 27692 | |5;yui.html;806.6208881825542;827.9875801862974;874.4796845876705;919.0074719303145;922.2944447497665;18638.62722745546;19088.713669294953;19136.23455061799;19146.329878454257;19200.231990720353;16386.75419868703;17230.310787568484;17316.913415432937;17322.393716219936;18101;917.4357769971487;967.3663750243602;974.6296407365227;974.7611835100375;976.3946353260989;945.1729736480579;964.8552717092422;978.5254151736402;980.5244456438621;987.7036888933327;1055.3878750324793;1056.5932116135305;1057.386715704893;1057.471264367816;1058.8835228124938;1047.6333283350843;1048.1614708233421;1048.5753269925654;1050.238577031646;1051.5793682526973;728.6502478810148;747.6037700018999;757.4205732614554;757.5341165183428;757.7499425189674;20277.898610507;20302.796972030384;20476.283330083377;20482.795172048387;20513.8974305129;21394.251201207884;21614.35156945294;22115.668264976037;22133.446663833372;22233.888830555905;17058.402955896607;17101.144942752813;17299.913500432544;17429.912850435667;17437;3543.681068703829;3544.556930383702;3555.1467647764607;3556.0931962349696;3559.715222782171;133920.33039834737;134298.3285083578;134642;135642;138647;16338.18309084541;16897.408590699513;16951.152442377832;17034.82965170357;17084.658306833793;30853.074407767803;31331.060068197992;31417.21456963594;31467.370652587277;31618.36763264722;8744.344174186961;8821.514816685016;8851.380403371832;8852.424592401474;8861.911380886237;4210.347396153601;4217.072244106353;4325.3728209409455;4334.913301734011;4341.522432532419;5335.839924802262;5469.343678758495;5472.151816468454;5478.58910581708;5491.972540137273;980.9901900980899;995.6913356859396;1030.3457304611475;1032.9173666106694;1040.4017689828227;645.5513418174418;657.6087228099274;672.1698702102899;679.49038221334;679.8198477403492;10983.066439352584;11155.66533003995;11345.262557933707;11740.119491038206;11884.524619015143;623.803501896901;636.096742625473;641.7368878759686;643.4787821864211;645.1709553224179;5213.2701421800975;5286.97356513215;5294.97352513235;5430.429804870431;5441.483059109458;118858.4057079709;123757;124702;125696;126864;106635;108596.4570177144;110846;114823.42588287005;114885.42557287327;92850.53574732083;94530.52734736145;94848.52575737078;97421.51289243509;97748;9419.764505887408;9647.807043859224;9684.70945871625;9716.174125199437;9913.553890075109;44013.559864400944;44592.55407445884;44634.55365446304;44854.10291794211;46666.766666166455;57739.711301444906;57827.71086144543;58734;60669.69665151823;61503.692481539096;14796.408143674133;15162.31769570394;15164.469243576423;15197.772033419287;15265.160416176772;15997.040177589382;16020.279087441326;16021.359145634511;16072.678546428771;16219.51341459758;15020.17389043613;15110.54668359951;15196.924015380295;15304.234788260765;15309.923450382677;14259.857401425854;14314.642133946734;14555.5633330996;14646.121232726071;14681.412743490568;13469.326533673473;13470.191788492722;13685.11046781956;13706.383212755649;13795.10331828428;9655.855162172713;9765.658201962897;9768.951155244178;9900.752481188028;10061.496925153566;9954.303198776017;9980.650677226262;10107.545160467944;10141.492925353556;10155 02:27:43 INFO - PID 27692 | __end_tp_report 02:27:43 INFO - PID 27692 | __start_cc_report 02:27:43 INFO - PID 27692 | _x_x_mozilla_cycle_collect,354 02:27:43 INFO - PID 27692 | __end_cc_report 02:27:43 INFO - PID 27692 | __startTimestamp1505035663958__endTimestamp 02:27:43 INFO - PID 27692 | 02:27:43 INFO - PID 27692 | ------- Summary: start ------- 02:27:43 INFO - PID 27692 | Number of tests: 6 02:27:43 INFO - PID 27692 | 02:27:43 INFO - PID 27692 | [#0] dojo.html Cycles:165 Average:6170.35 Median:6452.13 stddev:852.57 (13.2%) stddev-sans-first:820.60 02:27:43 INFO - PID 27692 | Values: 3105.9 3107.0 3120.7 3146.8 3159.3 6166.5 6256.4 6303.6 6351.7 6386.6 6188.9 6197.2 6204.4 6212.7 6218.1 5727.3 5771.6 5778.7 5792.8 5887.4 5858.9 5890.3 5901.6 5905.1 5909.8 5821.1 5850.1 5923.4 5935.0 5937.6 813.2 5234.3 5669.8 5799.2 5809.0 5706.6 5713.3 5778.7 5808.3 5822.9 6257.9 6266.2 6272.7 6330.2 6357.8 6686.6 6687.6 6694.8 6785.4 6792.6 6644.1 6770.8 6776.2 6858.3 6897.5 6225.2 6226.5 6226.7 6247.1 6294.6 6908.7 6973.7 6991.9 7011.9 7020.4 6672.6 6735.1 6738.5 6739.1 6739.8 6742.4 6752.0 6768.2 6822.6 6842.7 6565.3 6668.2 6682.0 6688.2 6786.6 6606.6 6622.7 6708.1 6721.1 6829.9 5370.5 5386.4 5396.3 5401.1 5402.3 4648.3 4668.6 4682.8 4689.2 4695.9 5650.4 5742.3 5772.3 5776.1 5861.8 6795.0 6924.7 6925.0 6936.8 6938.1 5830.9 5848.1 5853.8 5866.6 5885.7 5786.5 5874.9 5876.5 5878.2 5882.4 6382.5 6477.0 6485.4 6565.0 6570.5 6693.8 6729.2 6806.7 6835.6 6836.7 6295.7 6466.8 6470.8 6479.9 6494.9 6482.2 6534.1 6559.0 6569.9 6602.2 6655.2 6656.6 6721.7 6744.9 6769.5 6564.1 6571.3 6579.8 6588.2 6593.5 6389.7 6479.4 6494.1 6563.0 6580.2 6341.7 6425.0 6452.1 6505.7 6520.4 6633.2 6684.5 6684.8 6732.6 6735.8 6293.0 6388.8 6436.5 6464.2 6464.9 02:27:43 INFO - PID 27692 | 02:27:43 INFO - PID 27692 | [#1] ext.html Cycles:180 Average:35788.41 Median:34770.33 stddev:50702.02 (145.8%) stddev-sans-first:50776.39 02:27:43 INFO - PID 27692 | Values: 851.7 876.2 877.2 877.7 880.4 37977.6 41589.2 41835.0 41839.8 41922.6 39001.6 39270.2 39311.6 39352.6 39364.2 6913.9 7031.4 7630.5 7795.6 7811.7 7572.0 7657.8 7660.4 7667.5 7668.8 7606.1 7663.0 7665.8 7670.3 7679.6 7589.0 7638.1 7651.2 7660.6 7667.3 7628.6 7686.0 7692.9 7704.2 7704.8 37207.8 37383.8 37502.8 37561.0 37661.8 3551.4 3585.4 3586.5 3588.5 3590.9 30063.8 30371.5 30414.2 30449.7 30474.2 4987.3 5188.9 5195.1 5218.0 5221.9 178626.0 179107.0 179387.0 180322.0 180637.0 40567.8 41286.6 46945.8 47020.1 47146.5 48651.3 49151.3 49245.0 49345.8 49379.0 15075.8 15215.9 15216.5 15220.2 15231.1 11932.1 11958.3 12019.9 12037.9 12041.7 13308.5 13331.9 13383.0 13426.7 13487.5 5405.2 5455.2 5495.7 5591.4 5650.7 996.2 1010.4 1016.3 1026.9 1031.4 22626.2 22660.3 22749.9 22785.9 22819.4 1101.9 1112.5 1112.6 1114.0 1122.7 11898.2 11933.6 12004.0 12004.9 12050.5 222109.0 223372.9 223517.0 223764.0 223959.0 50015.0 50160.0 50266.5 50381.5 50749.2 1430.4 1448.2 1452.7 1463.2 1466.4 21687.0 22047.3 22136.0 22260.1 22525.4 36180.3 36677.8 36716.4 36738.3 36810.4 181210.0 181285.0 181372.0 181686.0 181884.0 30971.0 31084.2 31455.0 31482.2 31489.4 40331.0 40459.8 40872.4 40926.6 41002.8 29792.0 30315.4 30649.2 30716.5 30749.7 30158.0 30246.2 30410.7 30419.5 30724.8 34603.5 34650.8 34889.8 35056.1 35131.3 30260.5 30269.8 30383.7 30401.5 30489.1 22502.5 22660.3 22981.4 23007.0 23168.1 02:27:43 INFO - PID 27692 | 02:27:43 INFO - PID 27692 | [#2] jquery.html Cycles:180 Average:33198.71 Median:21746.85 stddev:71224.24 (327.5%) stddev-sans-first:71381.88 02:27:43 INFO - PID 27692 | Values: 560.5 570.9 572.6 576.2 595.4 22046.2 22705.3 22770.9 22793.2 22810.4 20864.3 21661.9 21857.3 24551.0 24840.3 6248.1 6406.4 6418.4 6418.8 6438.3 6138.9 6331.8 6431.6 6431.7 6431.9 6401.0 6446.3 6468.7 6475.0 6477.2 6452.3 6458.1 6462.5 6464.5 6485.2 5923.9 5987.1 6144.4 6147.7 6150.3 28438.7 28973.0 29021.5 29021.6 29040.4 2055.4 2087.6 2092.0 2107.3 2108.5 18741.4 19203.5 19509.4 19527.8 19610.3 2337.4 2386.1 2395.4 2455.7 2519.2 116148.4 120825.0 136564.3 139587.0 139940.0 20071.7 21140.9 21243.1 21731.2 21762.5 41324.8 41418.2 41721.8 41770.8 41810.6 11202.7 11343.9 11426.7 11430.9 11445.1 9516.1 9538.1 9550.1 9670.9 9684.5 11115.7 11196.8 11224.2 11261.3 11304.1 4167.7 4186.4 4259.3 4263.7 4265.2 954.6 965.3 966.4 973.3 981.5 18632.9 18705.4 19157.2 19297.0 19639.0 1016.5 1023.9 1026.1 1029.9 1031.1 10466.4 10486.5 10533.8 10598.1 10779.6 403169.0 414628.0 420058.0 425021.0 426600.0 109986.5 112176.0 112224.0 112620.0 113303.4 1143.5 1182.2 1224.5 1238.6 1246.5 19783.0 20195.6 20266.8 20348.3 20417.8 57435.1 58008.1 58231.0 59253.0 59628.4 73142.6 73851.3 73913.0 74077.6 74387.6 14064.0 14220.6 14222.1 14222.6 14380.3 21859.2 21947.3 22477.3 22488.9 22586.1 17730.0 17785.6 17969.3 17970.6 18010.0 14998.8 15300.5 15360.8 15623.4 15624.8 17838.8 17929.8 17970.3 18037.0 18133.7 13481.9 14073.6 14205.0 14215.5 14218.1 17514.3 17529.7 17668.8 17884.6 18036.4 02:27:43 INFO - PID 27692 | 02:27:43 INFO - PID 27692 | [#3] mootools.html Cycles:180 Average:9285.18 Median:14528.06 stddev:6666.07 (45.9%) stddev-sans-first:6658.05 02:27:43 INFO - PID 27692 | Values: 1341.2 1395.3 1404.1 1410.7 1412.7 14904.6 15586.4 15615.8 15617.3 15620.4 15230.8 15284.4 15375.5 15396.2 15397.4 2806.7 2815.8 2857.2 2860.7 2864.6 2791.3 2843.0 2849.3 2854.1 2855.5 2751.3 2832.8 2838.3 2849.2 2850.3 2812.0 2821.2 2843.9 2845.9 2861.4 2540.7 2547.9 2558.9 2564.8 2585.0 14829.0 15197.8 15216.4 15251.8 15264.9 7919.7 8234.4 8235.2 8242.0 8251.5 5225.1 5230.8 5313.4 5405.4 5421.6 1932.9 1953.1 1983.9 1990.3 2012.5 26471.1 26668.7 26701.2 26711.3 26758.0 6635.9 6825.5 6905.2 6983.5 7006.0 16763.8 16831.9 16904.2 16912.7 16919.9 7908.2 8081.0 8081.6 8089.7 8089.7 7330.9 7442.4 7475.6 7511.9 7516.8 5185.1 5332.7 5344.4 5351.0 5365.8 1437.2 1482.4 1595.4 1609.9 1611.0 836.6 855.5 862.9 869.5 872.0 7927.0 7963.1 7991.0 8072.4 8084.2 801.9 892.5 1012.5 1013.7 1018.7 4754.4 5022.5 5033.1 5114.8 5119.8 20859.8 21283.6 21763.2 21969.9 22011.7 21764.2 21782.5 21849.2 22034.3 22042.4 10490.3 10894.9 10904.0 11016.3 11070.8 8158.2 8183.3 8210.3 8248.5 8294.9 17641.8 18540.7 18731.9 18799.7 18863.6 18505.9 18886.2 19088.7 19096.5 19501.0 10664.8 10702.1 10729.7 10773.3 10873.8 13553.7 13559.1 13592.3 13684.2 13698.5 10783.4 10854.9 10856.2 10871.5 11094.7 10377.8 10514.0 10798.8 10817.4 10914.5 9732.9 9918.7 10029.1 10029.8 10030.4 6991.5 7020.9 7022.6 7092.1 7106.8 6810.0 6871.5 6948.7 6967.4 6990.5 02:27:43 INFO - PID 27692 | 02:27:43 INFO - PID 27692 | [#4] prototype.html Cycles:180 Average:5515.76 Median:6140.84 stddev:1505.19 (24.5%) stddev-sans-first:1461.36 02:27:43 INFO - PID 27692 | Values: 488.8 517.6 522.5 524.1 527.7 6220.8 6277.7 6325.9 6358.7 6361.6 6154.4 6175.0 6214.3 6214.8 6224.2 4999.9 5038.4 5056.1 5056.7 5093.6 4754.7 4954.0 5061.6 5084.8 5133.8 4847.7 4868.5 4874.1 4910.7 4923.7 4844.4 4852.2 4854.6 4870.8 4893.1 4317.8 4354.3 4361.3 4376.0 4407.3 5988.3 6056.9 6104.7 6110.4 6127.2 5465.6 5550.6 5574.2 5583.4 5603.0 6473.4 6491.6 6507.0 6542.8 6611.6 4749.1 4750.3 4771.8 4799.8 4806.5 6541.7 6590.6 6609.5 6670.1 6717.4 5305.9 5336.1 5354.3 5372.4 5376.0 5408.4 5450.9 5457.3 5465.0 5489.4 5811.3 5822.5 5840.0 5852.2 5897.4 6614.1 6635.5 6661.9 6664.4 6675.7 1881.6 1911.6 1913.5 1914.0 1919.7 1365.4 1395.8 1412.6 1419.4 1423.1 5631.8 5682.7 5689.5 5696.5 5699.2 6659.2 6700.9 6717.6 6727.0 6735.5 5594.5 5677.3 5687.3 5714.9 5753.0 3496.2 3513.7 3529.0 3539.5 3548.2 6482.4 6491.2 6511.4 6521.2 6545.2 6845.7 6868.0 6874.6 6876.3 6877.9 6356.9 6421.2 6446.9 6478.8 6485.7 6574.7 6601.0 6628.9 6671.6 6706.6 6641.6 6644.6 6650.6 6686.4 6764.9 6426.2 6434.8 6507.8 6512.9 6525.4 5355.8 5362.8 5389.0 5411.8 5430.5 6329.0 6453.3 6517.9 6699.5 6756.6 6342.3 6365.3 6425.6 6458.8 6527.2 6297.0 6307.3 6326.2 6447.5 6459.8 6106.4 6297.8 6315.6 6357.3 6446.0 6537.6 6572.0 6593.5 6596.7 6632.8 6352.1 6387.6 6391.9 6392.9 6395.4 02:27:43 INFO - PID 27692 | 02:27:43 INFO - PID 27692 | [#5] yui.html Cycles:180 Average:24035.81 Median:22183.67 stddev:35367.96 (159.4%) stddev-sans-first:35424.17 02:27:43 INFO - PID 27692 | Values: 806.6 828.0 874.5 919.0 922.3 18638.6 19088.7 19136.2 19146.3 19200.2 16386.8 17230.3 17316.9 17322.4 18101.0 917.4 967.4 974.6 974.8 976.4 945.2 964.9 978.5 980.5 987.7 1055.4 1056.6 1057.4 1057.5 1058.9 1047.6 1048.2 1048.6 1050.2 1051.6 728.7 747.6 757.4 757.5 757.7 20277.9 20302.8 20476.3 20482.8 20513.9 21394.3 21614.4 22115.7 22133.4 22233.9 17058.4 17101.1 17299.9 17429.9 17437.0 3543.7 3544.6 3555.1 3556.1 3559.7 133920.3 134298.3 134642.0 135642.0 138647.0 16338.2 16897.4 16951.2 17034.8 17084.7 30853.1 31331.1 31417.2 31467.4 31618.4 8744.3 8821.5 8851.4 8852.4 8861.9 4210.3 4217.1 4325.4 4334.9 4341.5 5335.8 5469.3 5472.2 5478.6 5492.0 981.0 995.7 1030.3 1032.9 1040.4 645.6 657.6 672.2 679.5 679.8 10983.1 11155.7 11345.3 11740.1 11884.5 623.8 636.1 641.7 643.5 645.2 5213.3 5287.0 5295.0 5430.4 5441.5 118858.4 123757.0 124702.0 125696.0 126864.0 106635.0 108596.5 110846.0 114823.4 114885.4 92850.5 94530.5 94848.5 97421.5 97748.0 9419.8 9647.8 9684.7 9716.2 9913.6 44013.6 44592.6 44634.6 44854.1 46666.8 57739.7 57827.7 58734.0 60669.7 61503.7 14796.4 15162.3 15164.5 15197.8 15265.2 15997.0 16020.3 16021.4 16072.7 16219.5 15020.2 15110.5 15196.9 15304.2 15309.9 14259.9 14314.6 14555.6 14646.1 14681.4 13469.3 13470.2 13685.1 13706.4 13795.1 9655.9 9765.7 9769.0 9900.8 10061.5 9954.3 9980.7 10107.5 10141.5 10155.0 02:27:43 INFO - PID 27692 | -------- Summary: end -------- 02:27:43 INFO - PID 27692 | 02:27:44 INFO - TEST-INFO | 27692: exit 0 02:27:44 INFO - TEST-OK | dromaeo_css | took 1108571ms 02:27:44 INFO - TEST-START | kraken 02:27:44 INFO - Initialising browser for kraken test... 02:27:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56434/getInfo.html -profile /tmp/tmpi3Dnv1/profile 02:27:44 INFO - TEST-INFO | started process 28076 (/builds/slave/test/build/application/firefox/firefox http://localhost:56434/getInfo.html) 02:27:50 INFO - TEST-INFO | 28076: exit 0 02:27:50 INFO - Browser initialized. 02:27:50 INFO - Running cycle 1/1 for kraken test... 02:27:50 INFO - Using env: {'DISPLAY': ':0', 02:27:50 INFO - 'HOME': '/home/cltbld', 02:27:50 INFO - 'JSGC_DISABLE_POISONING': '1', 02:27:50 INFO - 'LANG': 'en_US.UTF-8', 02:27:50 INFO - 'LANGUAGE': 'en_US:en', 02:27:50 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 02:27:50 INFO - 'LOGNAME': 'cltbld', 02:27:50 INFO - 'MAIL': '/var/mail/cltbld', 02:27:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:27:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:27:50 INFO - 'MOZ_CRASHREPORTER': '1', 02:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:27:50 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 02:27:50 INFO - 'MOZ_NO_REMOTE': '1', 02:27:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:27:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:27:50 INFO - 'NO_EM_RESTART': '1', 02:27:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:27:50 INFO - 'PWD': '/builds/slave/test', 02:27:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:27:50 INFO - 'RUST_BACKTRACE': 'full', 02:27:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 02:27:50 INFO - 'SHELL': '/bin/bash', 02:27:50 INFO - 'SHLVL': '1', 02:27:50 INFO - 'TERM': 'linux', 02:27:50 INFO - 'TMOUT': '86400', 02:27:50 INFO - 'USER': 'cltbld', 02:27:50 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1505034498.167352-663301265', 02:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:27:50 INFO - '_': '/tools/buildbot/bin/python'} 02:27:50 INFO - TEST-INFO | started process 28271 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpi3Dnv1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 02:27:51 INFO - PID 28271 | 02:27:51 INFO - PID 28271 | (/builds/slave/test/build/application/firefox/firefox:28321): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 02:27:51 INFO - PID 28271 | 02:28:01 INFO - PID 28271 | RSS: Main: 172916736 02:28:01 INFO - PID 28271 | 02:28:04 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56434/tests/kraken/driver.html?testName=audio-beat-detection) 02:28:05 INFO - PID 28271 | RSS: Main: 177934336 02:28:05 INFO - PID 28271 | 02:28:07 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56434/tests/kraken/driver.html?testName=audio-dft) 02:28:08 INFO - PID 28271 | RSS: Main: 179159040 02:28:08 INFO - PID 28271 | 02:28:11 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56434/tests/kraken/driver.html?testName=audio-fft) 02:28:11 INFO - PID 28271 | RSS: Main: 173600768 02:28:11 INFO - PID 28271 | 02:28:14 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56434/tests/kraken/driver.html?testName=audio-oscillator) 02:28:14 INFO - PID 28271 | RSS: Main: 175292416 02:28:14 INFO - PID 28271 | 02:28:16 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56434/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:28:17 INFO - PID 28271 | RSS: Main: 175013888 02:28:17 INFO - PID 28271 | 02:28:20 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56434/tests/kraken/driver.html?testName=imaging-darkroom) 02:28:21 INFO - PID 28271 | RSS: Main: 177754112 02:28:21 INFO - PID 28271 | 02:28:24 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56434/tests/kraken/driver.html?testName=imaging-desaturate) 02:28:25 INFO - PID 28271 | RSS: Main: 177315840 02:28:25 INFO - PID 28271 | 02:28:28 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56434/tests/kraken/driver.html?testName=json-parse-financial) 02:28:29 INFO - PID 28271 | RSS: Main: 177569792 02:28:29 INFO - PID 28271 | 02:28:30 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56434/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:28:31 INFO - PID 28271 | RSS: Main: 177131520 02:28:31 INFO - PID 28271 | 02:28:32 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56434/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:28:33 INFO - PID 28271 | RSS: Main: 177115136 02:28:33 INFO - PID 28271 | 02:28:35 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56434/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:28:36 INFO - PID 28271 | RSS: Main: 174415872 02:28:36 INFO - PID 28271 | 02:28:39 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56434/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:28:39 INFO - PID 28271 | RSS: Main: 174563328 02:28:39 INFO - PID 28271 | 02:28:42 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56434/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:28:43 INFO - PID 28271 | RSS: Main: 175202304 02:28:43 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | Cycle 1(1): loaded http://localhost:56434/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56434/tests/kraken/driver.html?testName=ai-astar) 02:28:45 INFO - PID 28271 | RSS: Main: 176427008 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | __start_tp_report 02:28:45 INFO - PID 28271 | _x_x_mozilla_page_load 02:28:45 INFO - PID 28271 | _x_x_mozilla_page_load_details 02:28:45 INFO - PID 28271 | |i|pagename|runs| 02:28:45 INFO - PID 28271 | |0;ai-astar;111;107;109;108;107;96;108;108;109;109 02:28:45 INFO - PID 28271 | |1;audio-beat-detection;120;117;117;127;126;115;116;117;145;118 02:28:45 INFO - PID 28271 | |2;audio-dft;163;165;164;161;162;162;172;168;163;165 02:28:45 INFO - PID 28271 | |3;audio-fft;74;74;73;75;75;73;72;85;75;73 02:28:45 INFO - PID 28271 | |4;audio-oscillator;80;80;80;79;80;79;79;80;80;79 02:28:45 INFO - PID 28271 | |5;imaging-gaussian-blur;114;114;113;113;114;113;118;117;117;117 02:28:45 INFO - PID 28271 | |6;imaging-darkroom;168;148;148;148;147;149;147;149;148;150 02:28:45 INFO - PID 28271 | |7;imaging-desaturate;93;94;95;95;94;94;99;94;93;95 02:28:45 INFO - PID 28271 | |8;json-parse-financial;62;55;54;53;51;53;52;54;53;52 02:28:45 INFO - PID 28271 | |9;json-stringify-tinderbox;38;37;39;37;37;37;37;37;37;38 02:28:45 INFO - PID 28271 | |10;stanford-crypto-aes;82;78;76;75;74;74;77;82;86;75 02:28:45 INFO - PID 28271 | |11;stanford-crypto-ccm;117;121;124;117;128;119;124;118;124;115 02:28:45 INFO - PID 28271 | |12;stanford-crypto-pbkdf2;174;180;174;170;170;171;182;178;173;174 02:28:45 INFO - PID 28271 | |13;stanford-crypto-sha256-iterative;71;64;65;72;63;66;65;64;63;65 02:28:45 INFO - PID 28271 | __end_tp_report 02:28:45 INFO - PID 28271 | __start_cc_report 02:28:45 INFO - PID 28271 | _x_x_mozilla_cycle_collect,952 02:28:45 INFO - PID 28271 | __end_cc_report 02:28:45 INFO - PID 28271 | __startTimestamp1505035725362__endTimestamp 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | ------- Summary: start ------- 02:28:45 INFO - PID 28271 | Number of tests: 14 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#0] ai-astar Cycles:10 Average:107.20 Median:109.00 stddev:4.10 (3.8%) stddev-sans-first:4.12 02:28:45 INFO - PID 28271 | Values: 111.0 107.0 109.0 108.0 107.0 96.0 108.0 108.0 109.0 109.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:119.00 stddev:9.13 (7.7%) stddev-sans-first:9.66 02:28:45 INFO - PID 28271 | Values: 120.0 117.0 117.0 127.0 126.0 115.0 116.0 117.0 145.0 118.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#2] audio-dft Cycles:10 Average:164.50 Median:164.50 stddev:3.31 (2.0%) stddev-sans-first:3.46 02:28:45 INFO - PID 28271 | Values: 163.0 165.0 164.0 161.0 162.0 162.0 172.0 168.0 163.0 165.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#3] audio-fft Cycles:10 Average:74.90 Median:74.50 stddev:3.70 (5.0%) stddev-sans-first:3.91 02:28:45 INFO - PID 28271 | Values: 74.0 74.0 73.0 75.0 75.0 73.0 72.0 85.0 75.0 73.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:80.00 stddev:0.52 (0.6%) stddev-sans-first:0.53 02:28:45 INFO - PID 28271 | Values: 80.0 80.0 80.0 79.0 80.0 79.0 79.0 80.0 80.0 79.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#5] imaging-gaussian-blur Cycles:10 Average:115.00 Median:115.50 stddev:2.00 (1.7%) stddev-sans-first:2.09 02:28:45 INFO - PID 28271 | Values: 114.0 114.0 113.0 113.0 114.0 113.0 118.0 117.0 117.0 117.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:148.50 stddev:6.32 (4.3%) stddev-sans-first:0.97 02:28:45 INFO - PID 28271 | Values: 168.0 148.0 148.0 148.0 147.0 149.0 147.0 149.0 148.0 150.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:94.50 stddev:1.71 (1.8%) stddev-sans-first:1.72 02:28:45 INFO - PID 28271 | Values: 93.0 94.0 95.0 95.0 94.0 94.0 99.0 94.0 93.0 95.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:53.50 stddev:3.07 (5.7%) stddev-sans-first:1.22 02:28:45 INFO - PID 28271 | Values: 62.0 55.0 54.0 53.0 51.0 53.0 52.0 54.0 53.0 52.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#9] json-stringify-tinderbox Cycles:10 Average:37.40 Median:37.00 stddev:0.70 (1.9%) stddev-sans-first:0.71 02:28:45 INFO - PID 28271 | Values: 38.0 37.0 39.0 37.0 37.0 37.0 37.0 37.0 37.0 38.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#10] stanford-crypto-aes Cycles:10 Average:77.90 Median:77.50 stddev:4.09 (5.3%) stddev-sans-first:4.07 02:28:45 INFO - PID 28271 | Values: 82.0 78.0 76.0 75.0 74.0 74.0 77.0 82.0 86.0 75.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#11] stanford-crypto-ccm Cycles:10 Average:120.70 Median:122.50 stddev:4.16 (3.4%) stddev-sans-first:4.20 02:28:45 INFO - PID 28271 | Values: 117.0 121.0 124.0 117.0 128.0 119.0 124.0 118.0 124.0 115.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:174.00 stddev:4.14 (2.4%) stddev-sans-first:4.39 02:28:45 INFO - PID 28271 | Values: 174.0 180.0 174.0 170.0 170.0 171.0 182.0 178.0 173.0 174.0 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.00 stddev:3.16 (4.9%) stddev-sans-first:2.73 02:28:45 INFO - PID 28271 | Values: 71.0 64.0 65.0 72.0 63.0 66.0 65.0 64.0 63.0 65.0 02:28:45 INFO - PID 28271 | -------- Summary: end -------- 02:28:45 INFO - PID 28271 | 02:28:45 INFO - PID 28271 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 02:28:46 INFO - TEST-INFO | 28271: exit 0 02:28:46 INFO - TEST-OK | kraken | took 61371ms 02:28:46 INFO - SUITE-END | took 1169s 02:28:46 INFO - Completed test suite (00:19:30) 02:28:46 INFO - javascript benchmark 02:28:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8464.093443734413, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3105.922351941201, 3106.9999999999986, 3120.7035331643488, 3146.77972541922, 3159.304952910359, 6166.53750968678, 6256.374362563742, 6303.590266632671, 6351.745930162794, 6386.616802991818, 6188.876222475547, 6197.194364732579, 6204.379562043793, 6212.689365531728, 6218.067289906517, 5727.284089488824, 5771.595988280801, 5778.711064446782, 5792.82621521355, 5887.35239123696, 5858.941410585903, 5890.263717035371, 5901.616394934315, 5905.084711869646, 5909.763609455638, 5821.09772985188, 5850.062345688209, 5923.437273459014, 5934.970325148368, 5937.584369094165, 813.2404459963772, 5234.319538460014, 5669.829905102853, 5799.21710569072, 5808.97095514524, 5706.600537962344, 5713.342965558931, 5778.682172480512, 5808.30300363959, 5822.854428639275, 6257.937420625781, 6266.154069200645, 6272.717727702265, 6330.208723909512, 6357.809265722036, 6686.5653732507235, 6687.565308254996, 6694.83262918426, 6785.355391237825, 6792.558483698544, 6644.069830223772, 6770.796876093725, 6776.186857577122, 6858.348456896587, 6897.48268879831, 6225.190725205694, 6226.501879849601, 6226.657533835637, 6247.094171345129, 6294.590851594678, 6908.723651053901, 6973.686184121625, 6991.93008069923, 7011.859762804817, 7020.403265722369, 6672.566283191577, 6735.057092007123, 6738.528303018742, 6739.056532085512, 6739.764108256286, 6742.393184613408, 6751.966240168866, 6768.2216545097945, 6822.556533825285, 6842.6578671066245, 6565.343465653492, 6668.199816022108, 6682.0, 6688.230853451818, 6786.59280443175, 6606.570572912745, 6622.668866556749, 6708.127943367331, 6721.059051732761, 6829.863402732017, 5370.489803468664, 5386.434424385461, 5396.2984811974975, 5401.1358182691365, 5402.297701298806, 4648.279516674921, 4668.5798278155125, 4682.836100736458, 4689.179393606105, 4695.906081878412, 5650.378458369576, 5742.3396309424115, 5772.307323121168, 5776.075827867521, 5861.7655293787775, 6794.966025169842, 6924.6537673117155, 6924.965375173092, 6936.791896243019, 6938.132733408324, 5830.854228644318, 5848.064309710511, 5853.765849365977, 5866.589338746332, 5885.676287804212, 5786.537077033911, 5874.9118763218585, 5876.500497457764, 5878.2064421302575, 5882.441168089024, 6382.489400847921, 6476.967615161894, 6485.351464853571, 6564.967175164189, 6570.474362051024, 6693.832654183684, 6729.192496900306, 6806.727730890709, 6835.58986460814, 6836.658167091725, 6295.748170073146, 6466.805995820133, 6470.805875823733, 6479.870402592016, 6494.870102598016, 6482.157319548617, 6534.117894084237, 6559.01614757771, 6569.868602627825, 6602.1747281589805, 6655.23464801554, 6656.567323123982, 6721.731130754911, 6744.932550674431, 6769.458443324621, 6564.146660934048, 6571.34286571339, 6579.80260592183, 6588.176477940257, 6593.472522198309, 6389.68051597409, 6479.449246813885, 6494.123293355526, 6563.0, 6580.177477815273, 6341.682915854097, 6425.036244563353, 6452.12896258999, 6505.674716264073, 6520.413162815369, 6633.170853643294, 6684.498662600227, 6684.766033188815, 6732.596044237362, 6735.764248251287, 6292.993121100602, 6388.840278993062, 6436.485081193587, 6464.191976003, 6464.903026454701], "value": 6107.730222038435, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [851.7487341234336, 876.163264082801, 877.2411863737868, 877.7191298784384, 880.3573391424262, 37977.620223797756, 41589.168216635684, 41835.0, 41839.79080104603, 41922.58077419225, 39001.609983900154, 39270.21459570807, 39311.606883931156, 39352.60647393525, 39364.21271574567, 6913.930860691379, 7031.367176954073, 7630.465867389257, 7795.610219489031, 7811.726589569366, 7572.0, 7657.808554786119, 7660.387169026492, 7667.463277570572, 7668.846623067535, 7606.125295590995, 7662.961685191566, 7665.846683066336, 7670.309672129507, 7679.57762323072, 7589.0, 7638.121616014146, 7651.196624354446, 7660.61696915152, 7667.271609197117, 7628.618569071524, 7686.039245094364, 7692.884606730903, 7704.229577042307, 7704.768856934301, 37207.813960930296, 37383.81308093442, 37502.81248593767, 37561.0, 37661.81169094164, 3551.449525323579, 3585.4084076127406, 3586.4799604057525, 3588.4976103345552, 3590.874319398835, 30063.849680751457, 30371.544426833614, 30414.239644008856, 30449.695503045128, 30474.238144046354, 4987.276844857475, 5188.948110518884, 5195.0908590996805, 5218.0, 5221.947780522184, 178626.0, 179107.0, 179387.0, 180322.0, 180637.0, 40567.7971610146, 41286.58713412887, 46945.76527117411, 47020.05959880852, 47146.5285347149, 48651.27023094657, 49151.26273105906, 49245.0, 49345.753271233414, 49379.0, 15075.773863392058, 15215.923920380328, 15216.543503694907, 15220.162891041098, 15231.086134831945, 11932.105092118127, 11958.282503049844, 12019.939900300618, 12037.87962120385, 12041.698957526132, 13308.467661293633, 13331.933340333237, 13383.0, 13426.731465370833, 13487.460501580023, 5405.189221616711, 5455.18172274162, 5495.735980724456, 5591.384947655754, 5650.717464126859, 996.1881066930429, 1010.3836659637615, 1016.2632091733582, 1026.8511065895552, 1031.3502493429091, 22626.208082717025, 22660.320190394315, 22749.886250568972, 22785.88607056954, 22819.429514261945, 1101.862267216598, 1112.527175950218, 1112.6161474291519, 1113.9888601114046, 1122.7361570031037, 11898.22661526998, 11933.58232461877, 12003.97966172885, 12004.939975300067, 12050.457729402022, 222109.0, 223372.88313558654, 223517.0, 223764.0, 223959.0, 50015.0, 50160.0, 50266.497335026914, 50381.49618503841, 50749.23876141861, 1430.4349781836117, 1448.1890141520782, 1452.7239824433325, 1463.239115659851, 1466.3987765016504, 21687.0, 22047.33857984263, 22136.0, 22260.109595615995, 22525.436864077874, 36180.27639447144, 36677.81661091677, 36716.44925326176, 36738.26523469569, 36810.447843281836, 181210.0, 181285.0, 181372.0, 181686.0, 181884.0, 30971.0, 31084.222894426915, 31455.0, 31482.212944676565, 31489.425528723932, 40331.0, 40459.79770101131, 40872.38691419572, 40926.59073409228, 41002.79498602488, 29792.0, 30315.39369212559, 30649.233769155948, 30716.53925191079, 30749.692503074682, 30158.0, 30246.243843904078, 30410.695893040785, 30419.54370684486, 30724.846375768873, 34603.4809477853, 34650.82674586611, 34889.8255508731, 35056.12359691028, 35131.297374051865, 30260.5460918082, 30269.848650757485, 30383.69616303897, 30401.543976840807, 30489.08532744021, 22502.5499490006, 22660.320190394315, 22981.425464363525, 23007.0, 23168.07327706873], "value": 16604.65734313335, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.498353973194, 570.9143628455734, 572.5905977226283, 576.2249774053896, 595.4462350014485, 22046.236301095912, 22705.318840434793, 22770.88614556927, 22793.202237921676, 22810.429739256528, 20864.269750558735, 21661.891690541564, 21857.344279671597, 24551.0, 24840.254792356212, 6248.094026366186, 6406.359364063603, 6418.42234198922, 6418.807435776934, 6438.259600145973, 6138.907916381258, 6331.841703957415, 6431.646259455728, 6431.742730290783, 6431.903521447182, 6400.967995160017, 6446.323136070715, 6468.74125034998, 6475.0, 6477.157969463963, 6452.290248072755, 6458.063580780784, 6462.450691691212, 6464.547481676284, 6485.189351331083, 5923.904077745631, 5987.072003839368, 6144.41628045335, 6147.66187859672, 6150.323464418911, 28438.72025758846, 28973.0, 29021.533169680602, 29021.56467652987, 29040.41919161626, 2055.403932859469, 2087.5511764970606, 2092.0376626751713, 2107.34672251601, 2108.4728817795553, 18741.437756867315, 19203.51991200217, 19509.414717558495, 19527.80472195288, 19610.31363902271, 2337.3805941425535, 2386.129062892049, 2395.4370722880108, 2455.717592476852, 2519.1938579654516, 116148.41925790317, 120825.0, 136564.31717841447, 139587.0, 139939.99999999898, 20071.698924516142, 21140.894295528426, 21243.088122069086, 21731.23940662069, 21762.455938601663, 41324.79337603353, 41418.171636567706, 41721.79139104285, 41770.791146044074, 41810.581894181276, 11202.719932001764, 11343.886561134283, 11426.657200284004, 11430.885691143149, 11445.08439324852, 9516.143547080796, 9538.14156725898, 9550.140487356171, 9670.90329096714, 9684.46735429558, 11115.72210694739, 11196.832047519294, 11224.214304998572, 11261.265747149395, 11304.060944154578, 4167.6874234432535, 4186.372044193335, 4259.3185090386005, 4263.658907287471, 4265.232258193555, 954.627695198873, 965.2519297544459, 966.3766870368573, 973.2943615878573, 981.504340308154, 18632.906835465736, 18705.438836834917, 19157.233710651417, 19297.035148242812, 19639.01804909749, 1016.4511163971521, 1023.9129673977646, 1026.0560284538094, 1029.8661174047477, 1031.128696251666, 10466.372017678963, 10486.528106235237, 10533.789324213627, 10598.099161571352, 10779.568817247222, 403169.0, 414628.0, 420058.0, 425021.0, 426600.0, 109986.45006774915, 112176.0, 112224.0, 112620.0, 113303.43348283206, 1143.5140065472142, 1182.160665927184, 1224.4857159993003, 1238.6036468330049, 1246.4515613130193, 19783.0, 20195.596088077862, 20266.79733202649, 20348.287809927173, 20417.79582204159, 57435.138472923776, 58008.12987805271, 58231.0, 59253.0, 59628.40371596228, 73142.63428682822, 73851.26148738444, 73913.0, 74077.62961185159, 74387.62806185934, 14064.0, 14220.577942205697, 14222.146671199762, 14222.573322800332, 14380.28098595045, 21859.234926777488, 21947.341579752632, 22477.32568022962, 22488.887555562116, 22586.09655613823, 17730.0, 17785.55536111556, 17969.281228750704, 17970.5507362317, 18010.0, 14998.775018374516, 15300.46448374346, 15360.846391536388, 15623.375064997274, 15624.765628515808, 17838.821611783715, 17929.820701792814, 17970.281188752302, 18037.0, 18133.727994080364, 13481.865181348454, 14073.577792666236, 14205.0, 14215.50245741394, 14218.146911185362, 17514.299428023245, 17529.737053944456, 17668.82331176672, 17884.55288617743, 18036.36872709449], "value": 12348.007426683147, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.2287934437697, 1395.3441882315315, 1404.080327385563, 1410.6755446247362, 1412.7245187188496, 14904.627384315383, 15586.376544938203, 15615.843841561567, 15617.297221625025, 15620.375184992616, 15230.847691523082, 15284.388624455009, 15375.461858834942, 15396.230188490586, 15397.384104635828, 2806.7193280671872, 2815.7747380209585, 2857.171420288114, 2860.713928607133, 2864.584635227891, 2791.260316016257, 2843.033368654657, 2849.3161641206066, 2854.072426461408, 2855.486012517746, 2751.284665986848, 2832.8441935693527, 2838.3046153692376, 2849.244950088228, 2850.3159241781927, 2812.02984970185, 2821.2241633550734, 2843.8578071096467, 2845.900393486218, 2861.3991061876927, 2540.6824146981626, 2547.8598677072755, 2558.9104381346565, 2564.756348146923, 2585.043533892453, 14829.0, 15197.848021519865, 15216.391344346213, 15251.847481525154, 15264.923675381551, 7919.683212671486, 8234.423590348679, 8235.176482351777, 8241.95879020601, 8251.546164960924, 5225.0748613224905, 5230.790768369261, 5313.38896026958, 5405.378381486101, 5421.593380496439, 1932.9323473678353, 1953.140618128019, 1983.9404817855489, 1990.3232900813794, 2012.4767560434245, 26471.073512426974, 26668.73331266682, 26701.19896403111, 26711.33221669435, 26758.0, 6635.867282654416, 6825.5222134451105, 6905.205901321312, 6983.546069505466, 7006.01915731798, 16763.832361676472, 16831.91584042072, 16904.154792260582, 16912.746308805377, 16919.91540042292, 7908.209179082046, 8080.959595202104, 8081.555514446644, 8089.676412943534, 8089.716859909875, 7330.8900366494545, 7442.367398771057, 7475.6262186890435, 7511.887321690179, 7516.849663006709, 5185.144451165515, 5332.706701131475, 5344.35867695879, 5351.0, 5365.839024829262, 1437.180806940055, 1482.3699927530756, 1595.369828917571, 1609.9436519721753, 1611.0, 836.6109758962111, 855.5422848776, 862.9093945135796, 869.5304535550858, 871.960761765722, 7927.048754149539, 7963.124056353797, 7991.0, 8072.434929554992, 8084.231997960184, 801.8997625296838, 892.4912799704107, 1012.4583347908833, 1013.6502906497246, 1018.6638409324905, 4754.43887435337, 5022.472640372781, 5033.144365457881, 5114.846554603368, 5119.769610367543, 20859.791402085786, 21283.574328513652, 21763.23828665989, 21969.890150549145, 22011.669824952638, 21764.238251661114, 21782.45543861416, 21849.235276765554, 22034.33896983093, 22042.44893877666, 10490.31812932157, 10894.945525272324, 10904.0, 11016.339019658686, 11070.833937490945, 8158.184181581794, 8183.3453323732765, 8210.343172546302, 8248.46384984974, 8294.87557686623, 17641.82358176402, 18540.721889171404, 18731.90634046821, 18799.71800423022, 18863.622727545648, 18505.9074704631, 18886.150123244763, 19088.713669295248, 19096.522586935436, 19501.0, 10664.786704265716, 10702.143828493858, 10729.731756706145, 10773.29973551748, 10873.78252434931, 13553.661158471117, 13559.118657287245, 13592.252426116658, 13684.178949262678, 13698.45206191741, 10783.352998820095, 10854.891451085388, 10856.240063195817, 10871.456427178451, 11094.72410672783, 10377.792444151226, 10514.0, 10798.784024319313, 10817.350958942488, 10914.454277286262, 9732.902670973483, 9918.702438926844, 10029.147522460673, 10029.799404012025, 10030.398176109456, 6991.545549539263, 7020.859582808417, 7022.578645281299, 7092.148942126978, 7106.786796396116, 6809.965950170415, 6871.4502839773695, 6948.6525673715105, 6967.407770339578, 6990.475714321345], "value": 6657.856645816222, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.7526041057091, 517.6195496310208, 522.4827420853355, 524.0854708533609, 527.7071225469863, 6220.782272620453, 6277.68611569422, 6325.873482530353, 6358.658726669597, 6361.550802940867, 6154.446099851012, 6174.969125154379, 6214.3474935131835, 6214.782482613109, 6224.19085518883, 4999.900001999976, 5038.395392552899, 5056.08990381732, 5056.673929992208, 5093.592512599002, 4754.714717116966, 4953.950460495403, 5061.620378471611, 5084.822031228908, 5133.768980395891, 4847.709137451747, 4868.543334232699, 4874.050379848054, 4910.674117988141, 4923.70457772533, 4844.442889067767, 4852.2479015752615, 4854.587360074398, 4870.780814863339, 4893.09477746618, 4317.784110794479, 4354.259775838115, 4361.258586040349, 4376.037271800199, 4407.2507673695545, 5988.281406231236, 6056.909146362808, 6104.664243466607, 6110.419510146529, 6127.234095738033, 5465.562754979632, 5550.611457197998, 5574.219609254707, 5583.357913839879, 5602.971985140089, 6473.385028422293, 6491.610503369813, 6507.02394640803, 6542.803715888531, 6611.636360000198, 4749.097671442415, 4750.2874568814605, 4771.761411929424, 4799.808007679689, 4806.519348065201, 6541.67291635421, 6590.627374145304, 6609.5373323867825, 6670.099536562599, 6717.361850624183, 5305.8938821223355, 5336.146216605324, 5354.330708661418, 5372.382176049804, 5376.0, 5408.432114627986, 5450.945490545044, 5457.28095649869, 5465.0215632480395, 5489.3687225969525, 5811.302643682785, 5822.475977162076, 5840.0, 5852.209951656472, 5897.351291357947, 6614.140161779034, 6635.5023373247195, 6661.9000714989315, 6664.433523150489, 6675.699593518303, 1881.5766452548066, 1911.5985643014844, 1913.4929243750416, 1914.0429785107447, 1919.654462196818, 1365.378752667534, 1395.818543589327, 1412.569166404236, 1419.4180386041674, 1423.110555902561, 5631.831045068655, 5682.687452190169, 5689.487946084872, 5696.544276457957, 5699.230603868425, 6659.203134683445, 6700.865982680319, 6717.56335838169, 6726.96636516824, 6735.528513004043, 5594.489487838342, 5677.290338707661, 5687.3459552152035, 5714.857128571819, 5752.971235143881, 3496.230829217568, 3513.7364697647777, 3529.0, 3539.50446937429, 3548.183917698944, 6482.416582507597, 6491.156149700603, 6511.446527045254, 6521.217453905561, 6545.181852268466, 6845.691943862438, 6868.0, 6874.587524748531, 6876.277990810993, 6877.89683154753, 6356.936430635635, 6421.165248517663, 6446.935530644633, 6478.773242936474, 6485.692296928601, 6574.671266436755, 6601.0, 6628.900566491603, 6671.566348187353, 6706.597604143767, 6641.634710090798, 6644.568103073285, 6650.600963942179, 6686.431653309523, 6764.898526522008, 6426.196725409324, 6434.806955791334, 6507.804765857032, 6512.92536731434, 6525.433895865053, 5355.777352398711, 5362.7586758596735, 5388.973055134699, 5411.810586629449, 5430.51125398716, 6328.968355158195, 6453.290138084713, 6517.8696426072165, 6699.492614161774, 6756.560823546453, 6342.302346741762, 6365.331640177715, 6425.614463132227, 6458.773942912164, 6527.249366322933, 6296.968515157578, 6307.306196318493, 6326.2092238470195, 6447.484201263981, 6459.773907913201, 6106.358832322542, 6297.779577714758, 6315.5894866833505, 6357.332480089709, 6445.967770161119, 6537.5750576212395, 6571.967140164268, 6593.472522198117, 6596.724130175082, 6632.767853125118, 6352.06262090067, 6387.584806987531, 6391.9041214380895, 6392.913204755296, 6395.360463953567], "value": 5086.8834440441215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.6208881825542, 827.9875801862974, 874.4796845876705, 919.0074719303145, 922.2944447497665, 18638.62722745546, 19088.713669294953, 19136.23455061799, 19146.329878454257, 19200.231990720353, 16386.75419868703, 17230.310787568484, 17316.913415432937, 17322.393716219936, 18101.0, 917.4357769971487, 967.3663750243602, 974.6296407365227, 974.7611835100375, 976.3946353260989, 945.1729736480579, 964.8552717092422, 978.5254151736402, 980.5244456438621, 987.7036888933327, 1055.3878750324793, 1056.5932116135305, 1057.386715704893, 1057.471264367816, 1058.8835228124938, 1047.6333283350843, 1048.1614708233421, 1048.5753269925654, 1050.238577031646, 1051.5793682526973, 728.6502478810148, 747.6037700018999, 757.4205732614554, 757.5341165183428, 757.7499425189674, 20277.898610507, 20302.796972030384, 20476.283330083377, 20482.795172048387, 20513.8974305129, 21394.251201207884, 21614.35156945294, 22115.668264976037, 22133.446663833372, 22233.888830555905, 17058.402955896607, 17101.144942752813, 17299.913500432544, 17429.912850435667, 17437.0, 3543.681068703829, 3544.556930383702, 3555.1467647764607, 3556.0931962349696, 3559.715222782171, 133920.33039834737, 134298.3285083578, 134642.0, 135642.0, 138647.0, 16338.18309084541, 16897.408590699513, 16951.152442377832, 17034.82965170357, 17084.658306833793, 30853.074407767803, 31331.060068197992, 31417.21456963594, 31467.370652587277, 31618.36763264722, 8744.344174186961, 8821.514816685016, 8851.380403371832, 8852.424592401474, 8861.911380886237, 4210.347396153601, 4217.072244106353, 4325.3728209409455, 4334.913301734011, 4341.522432532419, 5335.839924802262, 5469.343678758495, 5472.151816468454, 5478.58910581708, 5491.972540137273, 980.9901900980899, 995.6913356859396, 1030.3457304611475, 1032.9173666106694, 1040.4017689828227, 645.5513418174418, 657.6087228099274, 672.1698702102899, 679.49038221334, 679.8198477403492, 10983.066439352584, 11155.66533003995, 11345.262557933707, 11740.119491038206, 11884.524619015143, 623.803501896901, 636.096742625473, 641.7368878759686, 643.4787821864211, 645.1709553224179, 5213.2701421800975, 5286.97356513215, 5294.97352513235, 5430.429804870431, 5441.483059109458, 118858.4057079709, 123757.0, 124702.0, 125696.0, 126864.0, 106635.0, 108596.4570177144, 110846.0, 114823.42588287005, 114885.42557287327, 92850.53574732083, 94530.52734736145, 94848.52575737078, 97421.51289243509, 97748.0, 9419.764505887408, 9647.807043859224, 9684.70945871625, 9716.174125199437, 9913.553890075109, 44013.559864400944, 44592.55407445884, 44634.55365446304, 44854.10291794211, 46666.766666166455, 57739.711301444906, 57827.71086144543, 58734.0, 60669.69665151823, 61503.692481539096, 14796.408143674133, 15162.31769570394, 15164.469243576423, 15197.772033419287, 15265.160416176772, 15997.040177589382, 16020.279087441326, 16021.359145634511, 16072.678546428771, 16219.51341459758, 15020.17389043613, 15110.54668359951, 15196.924015380295, 15304.234788260765, 15309.923450382677, 14259.857401425854, 14314.642133946734, 14555.5633330996, 14646.121232726071, 14681.412743490568, 13469.326533673473, 13470.191788492722, 13685.11046781956, 13706.383212755649, 13795.10331828428, 9655.855162172713, 9765.658201962897, 9768.951155244178, 9900.752481188028, 10061.496925153566, 9954.303198776017, 9980.650677226262, 10107.545160467944, 10141.492925353556, 10155.0], "value": 8668.911219565854, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1438.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 107.0, 109.0, 108.0, 107.0, 96.0, 108.0, 108.0, 109.0, 109.0], "value": 107.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 117.0, 127.0, 126.0, 115.0, 116.0, 117.0, 145.0, 118.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 164.0, 161.0, 162.0, 162.0, 172.0, 168.0, 163.0, 165.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 75.0, 75.0, 73.0, 72.0, 85.0, 75.0, 73.0], "value": 74.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 80.0, 79.0, 80.0, 79.0, 79.0, 80.0, 80.0, 79.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 113.0, 113.0, 114.0, 113.0, 118.0, 117.0, 117.0, 117.0], "value": 115.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 148.0, 148.0, 148.0, 147.0, 149.0, 147.0, 149.0, 148.0, 150.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 95.0, 94.0, 94.0, 99.0, 94.0, 93.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 55.0, 54.0, 53.0, 51.0, 53.0, 52.0, 54.0, 53.0, 52.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 37.0, 39.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 38.0], "value": 37.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 76.0, 75.0, 74.0, 74.0, 77.0, 82.0, 86.0, 75.0], "value": 77.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 121.0, 124.0, 117.0, 128.0, 119.0, 124.0, 118.0, 124.0, 115.0], "value": 120.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 180.0, 174.0, 170.0, 170.0, 171.0, 182.0, 178.0, 173.0, 174.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 64.0, 65.0, 72.0, 63.0, 66.0, 65.0, 64.0, 63.0, 65.0], "value": 65.8, "unit": "score"}]}]} 02:28:46 INFO - Return code: 0 02:28:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:28:46 INFO - # TBPL SUCCESS # 02:28:46 INFO - Running post-action listener: _package_coverage_data 02:28:46 INFO - Running post-action listener: _resource_record_post_action 02:28:46 INFO - [mozharness: 2017-09-10 09:28:46.636189Z] Finished run-tests step (success) 02:28:46 INFO - Running post-run listener: _resource_record_post_run 02:28:46 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 8192; Write bytes: 306397184; Read time: 188; Write time: 166228 02:28:46 INFO - TinderboxPrint: CPU usage
13.1% 02:28:46 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 188 02:28:46 INFO - TinderboxPrint: I/O write bytes / time
306,397,184 / 166,228 02:28:46 INFO - TinderboxPrint: CPU idle
8,222.3 (86.9%) 02:28:46 INFO - TinderboxPrint: CPU user
1,190.9 (12.6%) 02:28:46 INFO - TinderboxPrint: Swap in / out
0 / 0 02:28:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 12455936; Read time: 0; Write time: 2308 02:28:46 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 02:28:46 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 8192; Write bytes: 293515264; Read time: 188; Write time: 163896 02:28:47 INFO - Running post-run listener: _upload_blobber_files 02:28:47 INFO - Blob upload gear active. 02:28:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:28:47 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 02:28:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:28:47 INFO - (blobuploader) - INFO - Open directory for files ... 02:28:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:28:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:28:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:28:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:28:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:28:48 INFO - (blobuploader) - INFO - Done attempting. 02:28:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:28:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:28:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:28:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:28:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:28:49 INFO - (blobuploader) - INFO - Done attempting. 02:28:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:28:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:28:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:28:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:28:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:28:56 INFO - (blobuploader) - INFO - Done attempting. 02:28:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:28:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:28:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:28:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:28:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:28:57 INFO - (blobuploader) - INFO - Done attempting. 02:28:57 INFO - (blobuploader) - INFO - Iteration through files over. 02:28:57 INFO - Return code: 0 02:28:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:28:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:28:57 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f400ef4f3e08fda755dd106de29f362f87d3970573a2dd9acd07ff6b75f9674c17e18c73fe74352fa87f5b08f2f5efdd5c67330a659361a970804cb9a431f4a0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68230fe7247a8cf3d7faf9394444d0828e828b83a0430a75b0dd6ed85df91fa68256f948d173048037ba7e99b74180f49552fff4800b9e55b13e7f1c69f41136", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2b108d33da97b15ad307e7ed7caa5d897b3161bb0122e1e73d92047029cedc2bbbc0dc81afbfc07e95e2a756aa033897a1e42f36dd24365f4ffc7249a916bcc0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f833ba5da8af7111e0cf0894983cad0be60df634ed7fff5fb35dda63aec999317c7e7d8dc2de710363e1d99ecba3000a3ea0f5a7bdef9af9f45ed0e16bc086f6"} 02:28:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:28:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:28:57 INFO - Contents: 02:28:57 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f400ef4f3e08fda755dd106de29f362f87d3970573a2dd9acd07ff6b75f9674c17e18c73fe74352fa87f5b08f2f5efdd5c67330a659361a970804cb9a431f4a0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68230fe7247a8cf3d7faf9394444d0828e828b83a0430a75b0dd6ed85df91fa68256f948d173048037ba7e99b74180f49552fff4800b9e55b13e7f1c69f41136", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2b108d33da97b15ad307e7ed7caa5d897b3161bb0122e1e73d92047029cedc2bbbc0dc81afbfc07e95e2a756aa033897a1e42f36dd24365f4ffc7249a916bcc0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f833ba5da8af7111e0cf0894983cad0be60df634ed7fff5fb35dda63aec999317c7e7d8dc2de710363e1d99ecba3000a3ea0f5a7bdef9af9f45ed0e16bc086f6"} 02:28:57 INFO - Running post-run listener: copy_logs_to_upload_dir 02:28:57 INFO - Copying logs to upload dir... 02:28:57 INFO - mkdir: /builds/slave/test/build/upload/logs 02:28:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.660355 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2017-09-10 02:28:57.131944) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-10 02:28:57.135891) ========= 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=d2a8c569965ab8367867b893000001a1-1505034498.167352-663301265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f400ef4f3e08fda755dd106de29f362f87d3970573a2dd9acd07ff6b75f9674c17e18c73fe74352fa87f5b08f2f5efdd5c67330a659361a970804cb9a431f4a0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68230fe7247a8cf3d7faf9394444d0828e828b83a0430a75b0dd6ed85df91fa68256f948d173048037ba7e99b74180f49552fff4800b9e55b13e7f1c69f41136", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2b108d33da97b15ad307e7ed7caa5d897b3161bb0122e1e73d92047029cedc2bbbc0dc81afbfc07e95e2a756aa033897a1e42f36dd24365f4ffc7249a916bcc0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f833ba5da8af7111e0cf0894983cad0be60df634ed7fff5fb35dda63aec999317c7e7d8dc2de710363e1d99ecba3000a3ea0f5a7bdef9af9f45ed0e16bc086f6"} build_url:https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011263 build_url: 'https://queue.taskcluster.net/v1/task/W1NZUJsyQSiI_HuWC54dMw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f400ef4f3e08fda755dd106de29f362f87d3970573a2dd9acd07ff6b75f9674c17e18c73fe74352fa87f5b08f2f5efdd5c67330a659361a970804cb9a431f4a0", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/68230fe7247a8cf3d7faf9394444d0828e828b83a0430a75b0dd6ed85df91fa68256f948d173048037ba7e99b74180f49552fff4800b9e55b13e7f1c69f41136", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2b108d33da97b15ad307e7ed7caa5d897b3161bb0122e1e73d92047029cedc2bbbc0dc81afbfc07e95e2a756aa033897a1e42f36dd24365f4ffc7249a916bcc0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f833ba5da8af7111e0cf0894983cad0be60df634ed7fff5fb35dda63aec999317c7e7d8dc2de710363e1d99ecba3000a3ea0f5a7bdef9af9f45ed0e16bc086f6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-10 02:28:57.164329) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:28:57.164682) ========= 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=d2a8c569965ab8367867b893000001a1-1505034498.167352-663301265 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-10 02:28:57.247651) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-10 02:28:57.248056) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-10 02:28:57.248366) ========= ========= Total master_lag: 0.19 =========