builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-052 starttime: 1505502324.94 results: success (0) revision: 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:24.943028) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:24.943488) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:24.943918) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:25.002087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:25.002342) ========= 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=826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 12:05:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|: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.5M=0s 2017-09-15 12:05:25 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105385 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:25.134174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:25.134438) ========= 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=826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018037 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:25.186283) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-15 12:05:25.186568) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 --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 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 --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=826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 12:05:25,262 truncating revision to first 12 chars 2017-09-15 12:05:25,262 Setting DEBUG logging. 2017-09-15 12:05:25,262 attempt 1/10 2017-09-15 12:05:25,262 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/31992a5c3d64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 12:05:26,175 unpacking tar archive at: mozilla-central-31992a5c3d64/testing/mozharness/ program finished with exit code 0 elapsedTime=1.173016 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-15 12:05:26.389613) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.389896) ========= script_repo_revision: 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.390258) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.390518) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 12:05:26.438628) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-15 12:05:26.438913) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:05:26 INFO - MultiFileLogger online at 20170915 12:05:26 in /builds/slave/test 12:05:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:05:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:05:26 INFO - {'append_to_log': False, 12:05:26 INFO - 'base_work_dir': '/builds/slave/test', 12:05:26 INFO - 'blob_upload_branch': 'Firefox', 12:05:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:05:26 INFO - 'branch': 'Firefox', 12:05:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:05:26 INFO - 'code_coverage': False, 12:05:26 INFO - 'config_files': ('talos/linux_config.py',), 12:05:26 INFO - 'default_actions': ('clobber', 12:05:26 INFO - 'read-buildbot-config', 12:05:26 INFO - 'download-and-extract', 12:05:26 INFO - 'populate-webroot', 12:05:26 INFO - 'create-virtualenv', 12:05:26 INFO - 'install', 12:05:26 INFO - 'setup-mitmproxy', 12:05:26 INFO - 'run-tests'), 12:05:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:05:26 INFO - 'disable_ccov_upload': False, 12:05:26 INFO - 'disable_stylo': False, 12:05:26 INFO - 'download_minidump_stackwalk': True, 12:05:26 INFO - 'download_symbols': 'ondemand', 12:05:26 INFO - 'enable_stylo': False, 12:05:26 INFO - 'enable_webrender': False, 12:05:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 12:05:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:05:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:05:26 INFO - 'gecko_profile': False, 12:05:26 INFO - 'gecko_profile_interval': 0, 12:05:26 INFO - 'installer_path': 'installer.exe', 12:05:26 INFO - 'log_level': 'info', 12:05:26 INFO - 'log_name': 'talos', 12:05:26 INFO - 'log_to_console': True, 12:05:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:05:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:05:26 INFO - 'opt_config_files': (), 12:05:26 INFO - 'pip_index': False, 12:05:26 INFO - 'suite': 'dromaeojs-e10s', 12:05:26 INFO - 'system_bits': '32', 12:05:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:05:26 INFO - 'title': 'talos-linux64-ix-052', 12:05:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:05:26 INFO - 'use_talos_json': True, 12:05:26 INFO - 'verify': 'False', 12:05:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:05:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:05:26 INFO - 'work_dir': 'build'} 12:05:26 INFO - [mozharness: 2017-09-15 19:05:26.658823Z] Running clobber step. 12:05:26 INFO - Running pre-action listener: _resource_record_pre_action 12:05:26 INFO - Running main action method: clobber 12:05:26 INFO - rmtree: /builds/slave/test/build 12:05:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:05:27 INFO - Running post-action listener: _resource_record_post_action 12:05:27 INFO - [mozharness: 2017-09-15 19:05:27.644587Z] Finished clobber step (success) 12:05:27 INFO - [mozharness: 2017-09-15 19:05:27.644683Z] Running read-buildbot-config step. 12:05:27 INFO - Running pre-action listener: _resource_record_pre_action 12:05:27 INFO - Running main action method: read_buildbot_config 12:05:27 INFO - Using buildbot properties: 12:05:27 INFO - { 12:05:27 INFO - "project": "", 12:05:27 INFO - "product": "firefox", 12:05:27 INFO - "who": "ryanvm@gmail.com", 12:05:27 INFO - "installer_path": "public/build/target.tar.bz2", 12:05:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 12:05:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 12:05:27 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 12:05:27 INFO - "stage_platform": "linux64-qr", 12:05:27 INFO - "basedir": "/builds/slave/test", 12:05:27 INFO - "buildnumber": 60, 12:05:27 INFO - "platform": "ubuntu64_hw_qr", 12:05:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 12:05:27 INFO - "slavebuilddir": "test", 12:05:27 INFO - "taskId": "S0gqtcuJSwKBcOB2Wj8rwA", 12:05:27 INFO - "branch": "mozilla-central", 12:05:27 INFO - "script_repo_revision": "production", 12:05:27 INFO - "revision": "31992a5c3d64ade078accdaab8ac4ffb5e43a9d7", 12:05:27 INFO - "slavename": "talos-linux64-ix-052", 12:05:27 INFO - "repo_path": "mozilla-central" 12:05:27 INFO - } 12:05:27 INFO - Finding installer, test and symbols from parent task. 12:05:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S0gqtcuJSwKBcOB2Wj8rwA'}, attempt #1 12:05:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S0gqtcuJSwKBcOB2Wj8rwA'}, attempt #1 12:05:28 INFO - Task dependencies: fA4-U6SkTAuYHvLWxXHwTQ 12:05:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ'}, attempt #1 12:05:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 12:05:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.test_packages.json 12:05:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.crashreporter-symbols.zip 12:05:29 INFO - Running post-action listener: _resource_record_post_action 12:05:29 INFO - [mozharness: 2017-09-15 19:05:29.068110Z] Finished read-buildbot-config step (success) 12:05:29 INFO - [mozharness: 2017-09-15 19:05:29.068324Z] Running download-and-extract step. 12:05:29 INFO - Running pre-action listener: _resource_record_pre_action 12:05:29 INFO - Running main action method: download_and_extract 12:05:29 INFO - mkdir: /builds/slave/test/build/tests 12:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:29 INFO - trying https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.test_packages.json 12:05:29 INFO - Downloading https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 12:05:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 12:05:30 INFO - Downloaded 989 bytes. 12:05:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 12:05:30 INFO - Using the following test package requirements: 12:05:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 12:05:30 INFO - u'common': [u'target.common.tests.zip'], 12:05:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 12:05:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 12:05:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 12:05:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 12:05:30 INFO - u'mozbase': [u'target.common.tests.zip'], 12:05:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 12:05:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 12:05:30 INFO - u'web-platform': [u'target.common.tests.zip', 12:05:30 INFO - u'target.web-platform.tests.tar.gz'], 12:05:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 12:05:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 12:05:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.common.tests.zip 12:05:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 12:05:30 INFO - Fetch https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.common.tests.zip into memory 12:05:32 INFO - Content-Length response header: 54734997 12:05:32 INFO - Bytes received: 54734997 12:05:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.talos.tests.zip 12:05:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 12:05:42 INFO - Fetch https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.talos.tests.zip into memory 12:05:43 INFO - Content-Length response header: 14508749 12:05:43 INFO - Bytes received: 14508749 12:05:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:43 INFO - trying https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 12:05:43 INFO - Downloading https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 12:05:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 12:05:45 INFO - Downloaded 61472732 bytes. 12:05:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 12:05:45 INFO - mkdir: /builds/slave/test/properties 12:05:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:05:45 INFO - Writing to file /builds/slave/test/properties/build_url 12:05:45 INFO - Contents: 12:05:45 INFO - build_url:https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 12:05:45 INFO - Running post-action listener: _resource_record_post_action 12:05:45 INFO - Running post-action listener: find_tests_for_verification 12:05:45 INFO - Running post-action listener: set_extra_try_arguments 12:05:45 INFO - [mozharness: 2017-09-15 19:05:45.085910Z] Finished download-and-extract step (success) 12:05:45 INFO - [mozharness: 2017-09-15 19:05:45.086060Z] Running populate-webroot step. 12:05:45 INFO - Running pre-action listener: _resource_record_pre_action 12:05:45 INFO - Running main action method: populate_webroot 12:05:45 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:05:45 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['tresize', 12:05:45 INFO - 'tcanvasmark']}, 12:05:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:05:45 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['dromaeo_css', 12:05:45 INFO - 'kraken']}, 12:05:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:05:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:45 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:45 INFO - 'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:05:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:05:45 INFO - 'tests': ['damp', 'tps']}, 12:05:45 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:45 INFO - 'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['damp', 'tps']}, 12:05:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:05:45 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['dromaeo_dom']}, 12:05:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:05:45 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['basic_compositor_video', 12:05:45 INFO - 'glvideo']}, 12:05:45 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:05:45 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:05:45 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:45 INFO - 'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['ts_paint_webext', 12:05:45 INFO - 'tp5o_webext']}, 12:05:45 INFO - 'other-e10s': {'tests': ['a11yr', 12:05:45 INFO - 'ts_paint', 12:05:45 INFO - 'tpaint', 12:05:45 INFO - 'sessionrestore', 12:05:45 INFO - 'sessionrestore_many_windows', 12:05:45 INFO - 'sessionrestore_no_auto_restore', 12:05:45 INFO - 'tabpaint']}, 12:05:45 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['a11yr', 12:05:45 INFO - 'ts_paint', 12:05:45 INFO - 'tpaint', 12:05:45 INFO - 'sessionrestore', 12:05:45 INFO - 'sessionrestore_many_windows', 12:05:45 INFO - 'sessionrestore_no_auto_restore', 12:05:45 INFO - 'tabpaint']}, 12:05:45 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 12:05:45 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 12:05:45 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['perf_reftest_singletons']}, 12:05:45 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['bloom_basic']}, 12:05:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:05:45 INFO - 'tsvgr_opacity', 12:05:45 INFO - 'tart', 12:05:45 INFO - 'tscrollx', 12:05:45 INFO - 'tsvg_static']}, 12:05:45 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['tsvgx', 12:05:45 INFO - 'tsvgr_opacity', 12:05:45 INFO - 'tart', 12:05:45 INFO - 'tscrollx', 12:05:45 INFO - 'tsvg_static']}, 12:05:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:05:45 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:45 INFO - 'talos_options': ['--disable-stylo'], 12:05:45 INFO - 'tests': ['tp5o']}, 12:05:45 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:45 INFO - 'talos_options': ['--mitmproxy', 12:05:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:45 INFO - '--firstNonBlankPaint'], 12:05:45 INFO - 'tests': ['tp6_google', 12:05:45 INFO - 'tp6_youtube', 12:05:45 INFO - 'tp6_amazon', 12:05:45 INFO - 'tp6_facebook']}, 12:05:45 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:45 INFO - 'talos_options': ['--disable-stylo', 12:05:45 INFO - '--mitmproxy', 12:05:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:45 INFO - '--firstNonBlankPaint'], 12:05:45 INFO - 'tests': ['tp6_google', 12:05:45 INFO - 'tp6_youtube', 12:05:45 INFO - 'tp6_amazon', 12:05:45 INFO - 'tp6_facebook']}, 12:05:45 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:05:45 INFO - 'talos_options': ['--stylo-threads=1', 12:05:45 INFO - '--mitmproxy', 12:05:45 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:05:45 INFO - '--firstNonBlankPaint'], 12:05:45 INFO - 'tests': ['tp6_google', 12:05:45 INFO - 'tp6_youtube', 12:05:45 INFO - 'tp6_amazon', 12:05:45 INFO - 'tp6_facebook']}, 12:05:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:05:45 INFO - 'talos_options': ['--xperf_path', 12:05:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:45 INFO - 'tests': ['tp5n']}, 12:05:45 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 12:05:45 INFO - 'talos_options': ['--disable-stylo', 12:05:45 INFO - '--xperf_path', 12:05:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:05:45 INFO - 'tests': ['tp5n']}}} 12:05:45 INFO - Running post-action listener: _resource_record_post_action 12:05:45 INFO - [mozharness: 2017-09-15 19:05:45.107144Z] Finished populate-webroot step (success) 12:05:45 INFO - [mozharness: 2017-09-15 19:05:45.107251Z] Running create-virtualenv step. 12:05:45 INFO - Running pre-action listener: _resource_record_pre_action 12:05:45 INFO - Running main action method: create_virtualenv 12:05:45 INFO - Creating virtualenv /builds/slave/test/build/venv 12:05:45 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:05:45 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 12:05:45 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:05:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:05:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:05:46 INFO - Installing distribute.............................................................................................................................................................................................done. 12:05:47 INFO - Installing pip...............done. 12:05:47 INFO - Return code: 0 12:05:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:05:47 INFO - Reading from file tmpfile_stdout 12:05:47 INFO - Output received: 12:05:47 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 12:05:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:05: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')]} 12:05:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05: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')]} 12:05:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:05: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', 'pip>=1.5'] in /builds/slave/test/build 12:05: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 pip>=1.5 12:05:47 INFO - Using env: {'DISPLAY': ':0', 12:05:47 INFO - 'HOME': '/home/cltbld', 12:05:47 INFO - 'LANG': 'en_US.UTF-8', 12:05:47 INFO - 'LANGUAGE': 'en_US:en', 12:05:47 INFO - 'LOGNAME': 'cltbld', 12:05:47 INFO - 'MAIL': '/var/mail/cltbld', 12:05:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:47 INFO - 'MOZ_NO_REMOTE': '1', 12:05:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:47 INFO - 'NO_EM_RESTART': '1', 12:05:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:47 INFO - 'PWD': '/builds/slave/test', 12:05:47 INFO - 'SHELL': '/bin/bash', 12:05:47 INFO - 'SHLVL': '1', 12:05:47 INFO - 'TERM': 'linux', 12:05:47 INFO - 'TMOUT': '86400', 12:05:47 INFO - 'USER': 'cltbld', 12:05:47 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:05:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:47 INFO - '_': '/tools/buildbot/bin/python'} 12:05:48 INFO - Ignoring indexes: http://pypi.python.org/simple/ 12:05:48 INFO - Downloading/unpacking pip>=1.5 12:05:48 INFO - Running setup.py egg_info for package pip 12:05:48 INFO - warning: no files found matching 'pip/cacert.pem' 12:05:48 INFO - warning: no files found matching '*.html' under directory 'docs' 12:05:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:05:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:05:48 INFO - Installing collected packages: pip 12:05:48 INFO - Found existing installation: pip 1.1 12:05:48 INFO - Uninstalling pip: 12:05:48 INFO - Successfully uninstalled pip 12:05:48 INFO - Running setup.py install for pip 12:05:48 INFO - warning: no files found matching 'pip/cacert.pem' 12:05:48 INFO - warning: no files found matching '*.html' under directory 'docs' 12:05:48 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 12:05:48 INFO - no previously-included directories found matching 'docs/_build/_sources' 12:05:48 INFO - Installing pip script to /builds/slave/test/build/venv/bin 12:05:48 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 12:05:48 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 12:05:48 INFO - Successfully installed pip 12:05:48 INFO - Cleaning up... 12:05:48 INFO - Return code: 0 12:05:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:05:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:05:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:05:48 INFO - Using env: {'DISPLAY': ':0', 12:05:48 INFO - 'HOME': '/home/cltbld', 12:05:48 INFO - 'LANG': 'en_US.UTF-8', 12:05:48 INFO - 'LANGUAGE': 'en_US:en', 12:05:48 INFO - 'LOGNAME': 'cltbld', 12:05:48 INFO - 'MAIL': '/var/mail/cltbld', 12:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:48 INFO - 'MOZ_NO_REMOTE': '1', 12:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:48 INFO - 'NO_EM_RESTART': '1', 12:05:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:48 INFO - 'PWD': '/builds/slave/test', 12:05:48 INFO - 'SHELL': '/bin/bash', 12:05:48 INFO - 'SHLVL': '1', 12:05:48 INFO - 'TERM': 'linux', 12:05:48 INFO - 'TMOUT': '86400', 12:05:48 INFO - 'USER': 'cltbld', 12:05:48 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:48 INFO - '_': '/tools/buildbot/bin/python'} 12:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:49 INFO - Downloading/unpacking psutil>=3.1.1 12:05: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 12:05: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 12:05: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 12:05: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 12:05:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:05:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:50 INFO - Installing collected packages: psutil 12:05:50 INFO - Running setup.py install for psutil 12:05:50 INFO - building 'psutil._psutil_linux' extension 12:05:50 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 12:05:50 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 12:05:50 INFO - building 'psutil._psutil_posix' extension 12:05:50 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 12:05:50 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 12:05:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:05:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:05:50 INFO - Successfully installed psutil 12:05:50 INFO - Cleaning up... 12:05:50 INFO - Return code: 0 12:05:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:05:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:05:50 INFO - Using env: {'DISPLAY': ':0', 12:05:50 INFO - 'HOME': '/home/cltbld', 12:05:50 INFO - 'LANG': 'en_US.UTF-8', 12:05:50 INFO - 'LANGUAGE': 'en_US:en', 12:05:50 INFO - 'LOGNAME': 'cltbld', 12:05:50 INFO - 'MAIL': '/var/mail/cltbld', 12:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:50 INFO - 'MOZ_NO_REMOTE': '1', 12:05:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:50 INFO - 'NO_EM_RESTART': '1', 12:05:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:50 INFO - 'PWD': '/builds/slave/test', 12:05:50 INFO - 'SHELL': '/bin/bash', 12:05:50 INFO - 'SHLVL': '1', 12:05:50 INFO - 'TERM': 'linux', 12:05:50 INFO - 'TMOUT': '86400', 12:05:50 INFO - 'USER': 'cltbld', 12:05:50 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:50 INFO - '_': '/tools/buildbot/bin/python'} 12:05:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:05:51 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 12:05:51 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 12:05:51 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 12:05:51 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 12:05:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:05:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:05:52 INFO - Installing collected packages: mozsystemmonitor 12:05:52 INFO - Running setup.py install for mozsystemmonitor 12:05:52 INFO - Successfully installed mozsystemmonitor 12:05:52 INFO - Cleaning up... 12:05:53 INFO - Return code: 0 12:05:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:05: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')]} 12:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05: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')]} 12:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:05:53 INFO - Using env: {'DISPLAY': ':0', 12:05:53 INFO - 'HOME': '/home/cltbld', 12:05:53 INFO - 'LANG': 'en_US.UTF-8', 12:05:53 INFO - 'LANGUAGE': 'en_US:en', 12:05:53 INFO - 'LOGNAME': 'cltbld', 12:05:53 INFO - 'MAIL': '/var/mail/cltbld', 12:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:53 INFO - 'MOZ_NO_REMOTE': '1', 12:05:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:53 INFO - 'NO_EM_RESTART': '1', 12:05:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:53 INFO - 'PWD': '/builds/slave/test', 12:05:53 INFO - 'SHELL': '/bin/bash', 12:05:53 INFO - 'SHLVL': '1', 12:05:53 INFO - 'TERM': 'linux', 12:05:53 INFO - 'TMOUT': '86400', 12:05:53 INFO - 'USER': 'cltbld', 12:05:53 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:53 INFO - '_': '/tools/buildbot/bin/python'} 12:05:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:54 INFO - Downloading/unpacking jsonschema==2.5.1 12:05: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 12:05: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 12:05: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 12:05: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 12:05:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:05:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:55 INFO - Downloading functools32-3.2.3-2.tar.gz 12:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:05:55 INFO - warning: no files found matching '*.txt' 12:05:55 INFO - no previously-included directories found matching 'build' 12:05:55 INFO - no previously-included directories found matching 'dist' 12:05:55 INFO - no previously-included directories found matching '.git*' 12:05:55 INFO - Installing collected packages: jsonschema, functools32 12:05:55 INFO - Running setup.py install for functools32 12:05:55 INFO - warning: no files found matching '*.txt' 12:05:55 INFO - no previously-included directories found matching 'build' 12:05:55 INFO - no previously-included directories found matching 'dist' 12:05:55 INFO - no previously-included directories found matching '.git*' 12:05:55 INFO - Successfully installed jsonschema functools32 12:05:55 INFO - Cleaning up... 12:05:55 INFO - Return code: 0 12:05:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:05:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:05:55 INFO - Using env: {'DISPLAY': ':0', 12:05:55 INFO - 'HOME': '/home/cltbld', 12:05:55 INFO - 'LANG': 'en_US.UTF-8', 12:05:55 INFO - 'LANGUAGE': 'en_US:en', 12:05:55 INFO - 'LOGNAME': 'cltbld', 12:05:55 INFO - 'MAIL': '/var/mail/cltbld', 12:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:55 INFO - 'MOZ_NO_REMOTE': '1', 12:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:55 INFO - 'NO_EM_RESTART': '1', 12:05:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:55 INFO - 'PWD': '/builds/slave/test', 12:05:55 INFO - 'SHELL': '/bin/bash', 12:05:55 INFO - 'SHLVL': '1', 12:05:55 INFO - 'TERM': 'linux', 12:05:55 INFO - 'TMOUT': '86400', 12:05:55 INFO - 'USER': 'cltbld', 12:05:55 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:55 INFO - '_': '/tools/buildbot/bin/python'} 12:05:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:05:55 INFO - Cleaning up... 12:05:55 INFO - Return code: 0 12:05:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:05:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:05:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:05:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:05:55 INFO - Using env: {'DISPLAY': ':0', 12:05:55 INFO - 'HOME': '/home/cltbld', 12:05:55 INFO - 'LANG': 'en_US.UTF-8', 12:05:55 INFO - 'LANGUAGE': 'en_US:en', 12:05:55 INFO - 'LOGNAME': 'cltbld', 12:05:55 INFO - 'MAIL': '/var/mail/cltbld', 12:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:55 INFO - 'MOZ_NO_REMOTE': '1', 12:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:55 INFO - 'NO_EM_RESTART': '1', 12:05:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:55 INFO - 'PWD': '/builds/slave/test', 12:05:55 INFO - 'SHELL': '/bin/bash', 12:05:55 INFO - 'SHLVL': '1', 12:05:55 INFO - 'TERM': 'linux', 12:05:55 INFO - 'TMOUT': '86400', 12:05:55 INFO - 'USER': 'cltbld', 12:05:55 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:55 INFO - '_': '/tools/buildbot/bin/python'} 12:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:56 INFO - Downloading/unpacking blobuploader==1.2.4 12:05: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 12:05: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 12:05: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 12:05: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 12:05:57 INFO - Downloading blobuploader-1.2.4.tar.gz 12:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:05:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:05:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:05:57 INFO - Downloading docopt-0.6.1.tar.gz 12:05:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:05:57 INFO - Installing collected packages: blobuploader, requests, docopt 12:05:57 INFO - Running setup.py install for blobuploader 12:05:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:05:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:05:57 INFO - Running setup.py install for requests 12:05:58 INFO - Running setup.py install for docopt 12:05:58 INFO - Successfully installed blobuploader requests docopt 12:05:58 INFO - Cleaning up... 12:05:58 INFO - Return code: 0 12:05:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:05: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')]} 12:05:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:05: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')]} 12:05:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:05:58 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 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:05:58 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 12:05:58 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 12:05:58 INFO - Using env: {'DISPLAY': ':0', 12:05:58 INFO - 'HOME': '/home/cltbld', 12:05:58 INFO - 'LANG': 'en_US.UTF-8', 12:05:58 INFO - 'LANGUAGE': 'en_US:en', 12:05:58 INFO - 'LOGNAME': 'cltbld', 12:05:58 INFO - 'MAIL': '/var/mail/cltbld', 12:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:05:58 INFO - 'MOZ_NO_REMOTE': '1', 12:05:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:05:58 INFO - 'NO_EM_RESTART': '1', 12:05:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:05:58 INFO - 'PWD': '/builds/slave/test', 12:05:58 INFO - 'SHELL': '/bin/bash', 12:05:58 INFO - 'SHLVL': '1', 12:05:58 INFO - 'TERM': 'linux', 12:05:58 INFO - 'TMOUT': '86400', 12:05:58 INFO - 'USER': 'cltbld', 12:05:58 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:05:58 INFO - '_': '/tools/buildbot/bin/python'} 12:05:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:05:58 INFO - Running setup.py (path:/tmp/pip-FA2Iwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:05:58 INFO - Running setup.py (path:/tmp/pip-s8QcO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:05:58 INFO - Running setup.py (path:/tmp/pip-FGbj09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:05:58 INFO - Running setup.py (path:/tmp/pip-gweTSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:05:58 INFO - Running setup.py (path:/tmp/pip-OHNs_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:05:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:05:58 INFO - Running setup.py (path:/tmp/pip-gy16dK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:05:59 INFO - Running setup.py (path:/tmp/pip-yuTjJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:05:59 INFO - Running setup.py (path:/tmp/pip-Ibv7WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:05:59 INFO - Running setup.py (path:/tmp/pip-vuYWO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:05:59 INFO - Running setup.py (path:/tmp/pip-v4ab4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:05:59 INFO - Running setup.py (path:/tmp/pip-VY2LrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:05:59 INFO - Running setup.py (path:/tmp/pip-QJ5IYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:05:59 INFO - Running setup.py (path:/tmp/pip-OdfM1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:05:59 INFO - Running setup.py (path:/tmp/pip-S0gQ7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:59 INFO - Running setup.py (path:/tmp/pip-D9jD8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:05:59 INFO - Running setup.py (path:/tmp/pip-2wsUDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:05:59 INFO - Running setup.py (path:/tmp/pip-BK5Ov0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:05:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:05:59 INFO - Running setup.py install for manifestparser 12:05:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:05:59 INFO - Running setup.py install for mozcrash 12:05:59 INFO - Running setup.py install for mozdebug 12:06:00 INFO - Running setup.py install for mozdevice 12:06:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozfile 12:06:00 INFO - Running setup.py install for mozhttpd 12:06:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozinfo 12:06:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozInstall 12:06:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozleak 12:06:00 INFO - Running setup.py install for mozlog 12:06:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for moznetwork 12:06:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:06:00 INFO - Running setup.py install for mozprocess 12:06:01 INFO - Running setup.py install for mozprofile 12:06:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Running setup.py install for mozrunner 12:06:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Running setup.py install for mozscreenshot 12:06:01 INFO - Running setup.py install for moztest 12:06:01 INFO - Running setup.py install for mozversion 12:06:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:06:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:06:01 INFO - Cleaning up... 12:06:01 INFO - Return code: 0 12:06:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:01 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')]} 12:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:01 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')]} 12:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:01 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 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:06:01 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 12:06:01 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 12:06:01 INFO - Using env: {'DISPLAY': ':0', 12:06:01 INFO - 'HOME': '/home/cltbld', 12:06:01 INFO - 'LANG': 'en_US.UTF-8', 12:06:01 INFO - 'LANGUAGE': 'en_US:en', 12:06:01 INFO - 'LOGNAME': 'cltbld', 12:06:01 INFO - 'MAIL': '/var/mail/cltbld', 12:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:01 INFO - 'MOZ_NO_REMOTE': '1', 12:06:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:01 INFO - 'NO_EM_RESTART': '1', 12:06:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:01 INFO - 'PWD': '/builds/slave/test', 12:06:01 INFO - 'SHELL': '/bin/bash', 12:06:01 INFO - 'SHLVL': '1', 12:06:01 INFO - 'TERM': 'linux', 12:06:01 INFO - 'TMOUT': '86400', 12:06:01 INFO - 'USER': 'cltbld', 12:06:01 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:01 INFO - '_': '/tools/buildbot/bin/python'} 12:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:06:01 INFO - Running setup.py (path:/tmp/pip-omDD5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:06:01 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)) 12:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:06:01 INFO - Running setup.py (path:/tmp/pip-nouetA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:06:01 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)) 12:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:06:01 INFO - Running setup.py (path:/tmp/pip-h8MR5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:06:02 INFO - Running setup.py (path:/tmp/pip-3GTgPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:06:02 INFO - Running setup.py (path:/tmp/pip-jlmoo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:06:02 INFO - Running setup.py (path:/tmp/pip-8HMe3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:06:02 INFO - Running setup.py (path:/tmp/pip-QBa5Ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:06:02 INFO - Running setup.py (path:/tmp/pip-l_56If-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:06:02 INFO - Running setup.py (path:/tmp/pip-xpYctb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:06:02 INFO - Running setup.py (path:/tmp/pip-W3ija1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:06:02 INFO - Running setup.py (path:/tmp/pip-mSOF1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:06:02 INFO - Running setup.py (path:/tmp/pip-QgQxEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:06:02 INFO - Running setup.py (path:/tmp/pip-K4F9TX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:06:02 INFO - Running setup.py (path:/tmp/pip-aZVMXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:02 INFO - Running setup.py (path:/tmp/pip-_NFmVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:06:02 INFO - Running setup.py (path:/tmp/pip-qQRhkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:06:02 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)) 12:06:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:06:02 INFO - Running setup.py (path:/tmp/pip-QYGNnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:06:03 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)) 12:06:03 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)) 12:06:03 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)) 12:06:03 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)) 12:06:03 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)) 12:06:03 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)) 12:06:03 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)) 12:06:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:06:03 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 12:06:03 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 12:06:03 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 12:06:03 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 12:06:04 INFO - Downloading blessings-1.6.tar.gz 12:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:06:04 INFO - Installing collected packages: blessings 12:06:04 INFO - Running setup.py install for blessings 12:06:04 INFO - Successfully installed blessings 12:06:04 INFO - Cleaning up... 12:06:04 INFO - Return code: 0 12:06:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:06:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:06:04 INFO - Reading from file tmpfile_stdout 12:06:04 INFO - Current package versions: 12:06:04 INFO - argparse == 1.2.1 12:06:04 INFO - blessings == 1.6 12:06:04 INFO - blobuploader == 1.2.4 12:06:04 INFO - docopt == 0.6.1 12:06:04 INFO - functools32 == 3.2.3-2 12:06:04 INFO - jsonschema == 2.5.1 12:06:04 INFO - manifestparser == 1.1 12:06:04 INFO - mozInstall == 1.13 12:06:04 INFO - mozcrash == 1.0 12:06:04 INFO - mozdebug == 0.1 12:06:04 INFO - mozdevice == 0.51 12:06:04 INFO - mozfile == 1.2 12:06:04 INFO - mozhttpd == 0.7 12:06:04 INFO - mozinfo == 0.10 12:06:04 INFO - mozleak == 0.1 12:06:04 INFO - mozlog == 3.5 12:06:04 INFO - moznetwork == 0.27 12:06:04 INFO - mozprocess == 0.25 12:06:04 INFO - mozprofile == 0.28 12:06:04 INFO - mozrunner == 6.13 12:06:04 INFO - mozscreenshot == 0.1 12:06:04 INFO - mozsystemmonitor == 0.3 12:06:04 INFO - moztest == 0.8 12:06:04 INFO - mozversion == 1.4 12:06:04 INFO - psutil == 3.1.1 12:06:04 INFO - requests == 1.2.3 12:06:04 INFO - wsgiref == 0.1.2 12:06:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:04 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 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:06:04 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 12:06:04 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 12:06:04 INFO - Using env: {'DISPLAY': ':0', 12:06:04 INFO - 'HOME': '/home/cltbld', 12:06:04 INFO - 'LANG': 'en_US.UTF-8', 12:06:04 INFO - 'LANGUAGE': 'en_US:en', 12:06:04 INFO - 'LOGNAME': 'cltbld', 12:06:04 INFO - 'MAIL': '/var/mail/cltbld', 12:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:04 INFO - 'MOZ_NO_REMOTE': '1', 12:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:04 INFO - 'NO_EM_RESTART': '1', 12:06:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:04 INFO - 'PWD': '/builds/slave/test', 12:06:04 INFO - 'SHELL': '/bin/bash', 12:06:04 INFO - 'SHLVL': '1', 12:06:04 INFO - 'TERM': 'linux', 12:06:04 INFO - 'TMOUT': '86400', 12:06:04 INFO - 'USER': 'cltbld', 12:06:04 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:04 INFO - '_': '/tools/buildbot/bin/python'} 12:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:04 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)) 12:06:04 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)) 12:06:04 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)) 12:06:04 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)) 12:06:04 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)) 12:06:04 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)) 12:06:04 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)) 12:06:04 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)) 12:06:04 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)) 12:06:04 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:06:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:06:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 12:06:05 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)) 12:06:05 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)) 12:06:05 INFO - Installing collected packages: simplejson 12:06:05 INFO - Running setup.py install for simplejson 12:06:06 INFO - building 'simplejson._speedups' extension 12:06:06 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 12:06:06 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 12:06:06 INFO - Successfully installed simplejson 12:06:06 INFO - Cleaning up... 12:06:07 INFO - Return code: 0 12:06:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:06:07 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')]} 12:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:06:07 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')]} 12:06:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:06:07 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 0x1a891f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c52a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1df5a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, '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': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', '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 12:06:07 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 12:06:07 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 12:06:07 INFO - Using env: {'DISPLAY': ':0', 12:06:07 INFO - 'HOME': '/home/cltbld', 12:06:07 INFO - 'LANG': 'en_US.UTF-8', 12:06:07 INFO - 'LANGUAGE': 'en_US:en', 12:06:07 INFO - 'LOGNAME': 'cltbld', 12:06:07 INFO - 'MAIL': '/var/mail/cltbld', 12:06:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:07 INFO - 'MOZ_NO_REMOTE': '1', 12:06:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:07 INFO - 'NO_EM_RESTART': '1', 12:06:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:07 INFO - 'PWD': '/builds/slave/test', 12:06:07 INFO - 'SHELL': '/bin/bash', 12:06:07 INFO - 'SHLVL': '1', 12:06:07 INFO - 'TERM': 'linux', 12:06:07 INFO - 'TMOUT': '86400', 12:06:07 INFO - 'USER': 'cltbld', 12:06:07 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:06:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:07 INFO - '_': '/tools/buildbot/bin/python'} 12:06:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:06:07 INFO - Cleaning up... 12:06:07 INFO - Return code: 0 12:06:07 INFO - Running post-action listener: _resource_record_post_action 12:06:07 INFO - Running post-action listener: _start_resource_monitoring 12:06:07 INFO - Starting resource monitoring. 12:06:07 INFO - [mozharness: 2017-09-15 19:06:07.277789Z] Finished create-virtualenv step (success) 12:06:07 INFO - [mozharness: 2017-09-15 19:06:07.278252Z] Running install step. 12:06:07 INFO - Running pre-action listener: _resource_record_pre_action 12:06:07 INFO - Running main action method: install 12:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:06:07 INFO - Reading from file tmpfile_stdout 12:06:07 INFO - Detecting whether we're running mozinstall >=1.0... 12:06:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:06:07 INFO - Reading from file tmpfile_stdout 12:06:07 INFO - Output received: 12:06:07 INFO - Usage: mozinstall [options] installer 12:06:07 INFO - Options: 12:06:07 INFO - -h, --help show this help message and exit 12:06:07 INFO - -d DEST, --destination=DEST 12:06:07 INFO - Directory to install application into. [default: 12:06:07 INFO - "/builds/slave/test"] 12:06:07 INFO - --app=APP Application being installed. [default: firefox] 12:06:07 INFO - mkdir: /builds/slave/test/build/application 12:06:07 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'] 12:06:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 12:06:25 INFO - Reading from file tmpfile_stdout 12:06:25 INFO - Output received: 12:06:25 INFO - /builds/slave/test/build/application/firefox/firefox 12:06:25 INFO - Running post-action listener: _resource_record_post_action 12:06:25 INFO - [mozharness: 2017-09-15 19:06:25.376959Z] Finished install step (success) 12:06:25 INFO - [mozharness: 2017-09-15 19:06:25.377299Z] Running setup-mitmproxy step. 12:06:25 INFO - Running pre-action listener: _resource_record_pre_action 12:06:25 INFO - Running main action method: setup_mitmproxy 12:06:25 INFO - Skipping: mitmproxy is not required 12:06:25 INFO - Running post-action listener: _resource_record_post_action 12:06:25 INFO - [mozharness: 2017-09-15 19:06:25.378962Z] Finished setup-mitmproxy step (success) 12:06:25 INFO - [mozharness: 2017-09-15 19:06:25.379272Z] Running run-tests step. 12:06:25 INFO - Running pre-action listener: _resource_record_pre_action 12:06:25 INFO - Running pre-action listener: _set_gcov_prefix 12:06:25 INFO - Running main action method: run_tests 12:06:25 WARNING - Try message not found. 12:06:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:06:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:06:25 INFO - Python 2.7.3 12:06:25 INFO - Return code: 0 12:06:25 INFO - grabbing minidump binary from tooltool 12:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:06:25 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 0x1df9090>, '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 0x1df9d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1df3f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:06:25 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 12:06:25 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 12:06:25 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 12:06:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:06:25 INFO - Return code: 0 12:06:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:06:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:06:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:06:25 INFO - ENV: RUST_BACKTRACE is now full 12:06:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:06:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:06:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 12:06:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 12:06:25 INFO - Using env: {'DISPLAY': ':0', 12:06:25 INFO - 'HOME': '/home/cltbld', 12:06:25 INFO - 'LANG': 'en_US.UTF-8', 12:06:25 INFO - 'LANGUAGE': 'en_US:en', 12:06:25 INFO - 'LOGNAME': 'cltbld', 12:06:25 INFO - 'MAIL': '/var/mail/cltbld', 12:06:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:06:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:06:25 INFO - 'MOZ_ACCELERATED': '1', 12:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:25 INFO - 'MOZ_NO_REMOTE': '1', 12:06:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:06:25 INFO - 'MOZ_WEBRENDER': '1', 12:06:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:25 INFO - 'NO_EM_RESTART': '1', 12:06:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:25 INFO - 'PWD': '/builds/slave/test', 12:06:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:06:25 INFO - 'RUST_BACKTRACE': 'full', 12:06:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:06:25 INFO - 'SHELL': '/bin/bash', 12:06:25 INFO - 'SHLVL': '1', 12:06:25 INFO - 'TERM': 'linux', 12:06:25 INFO - 'TMOUT': '86400', 12:06:25 INFO - 'USER': 'cltbld', 12:06:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:25 INFO - '_': '/tools/buildbot/bin/python'} 12:06:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 12:06:25 INFO - mozversion application_buildid: 20170915181541 12:06:25 INFO - mozversion application_changeset: 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 12:06:25 INFO - mozversion application_display_name: Nightly 12:06:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:06:25 INFO - mozversion application_name: Firefox 12:06:25 INFO - mozversion application_remotingname: firefox 12:06:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:06:25 INFO - mozversion application_vendor: Mozilla 12:06:25 INFO - mozversion application_version: 57.0a1 12:06:25 INFO - mozversion platform_buildid: 20170915181541 12:06:25 INFO - mozversion platform_changeset: 31992a5c3d64ade078accdaab8ac4ffb5e43a9d7 12:06:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:06:25 INFO - mozversion platform_version: 57.0a1 12:06:25 INFO - using testdate: 1505502385 12:06:25 INFO - actual date: 1505502385 12:06:25 INFO - starting webserver on 'localhost:40171' 12:06:25 INFO - SUITE-START | Running 2 tests 12:06:25 INFO - TEST-START | dromaeo_css 12:06:25 INFO - Initialising browser for dromaeo_css test... 12:06:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40171/getInfo.html -profile /tmp/tmpWzmB9Q/profile 12:06:25 INFO - TEST-INFO | started process 15232 (/builds/slave/test/build/application/firefox/firefox http://localhost:40171/getInfo.html) 12:06:32 INFO - TEST-INFO | 15232: exit 0 12:06:32 INFO - Browser initialized. 12:06:32 INFO - Running cycle 1/1 for dromaeo_css test... 12:06:32 INFO - Using env: {'DISPLAY': ':0', 12:06:32 INFO - 'HOME': '/home/cltbld', 12:06:32 INFO - 'JSGC_DISABLE_POISONING': '1', 12:06:32 INFO - 'LANG': 'en_US.UTF-8', 12:06:32 INFO - 'LANGUAGE': 'en_US:en', 12:06:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:06:32 INFO - 'LOGNAME': 'cltbld', 12:06:32 INFO - 'MAIL': '/var/mail/cltbld', 12:06:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:06:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:06:32 INFO - 'MOZ_ACCELERATED': '1', 12:06:32 INFO - 'MOZ_CRASHREPORTER': '1', 12:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:06:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:06:32 INFO - 'MOZ_NO_REMOTE': '1', 12:06:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:06:32 INFO - 'MOZ_WEBRENDER': '1', 12:06:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:06:32 INFO - 'NO_EM_RESTART': '1', 12:06:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:06:32 INFO - 'PWD': '/builds/slave/test', 12:06:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:06:32 INFO - 'RUST_BACKTRACE': 'full', 12:06:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:06:32 INFO - 'SHELL': '/bin/bash', 12:06:32 INFO - 'SHLVL': '1', 12:06:32 INFO - 'TERM': 'linux', 12:06:32 INFO - 'TMOUT': '86400', 12:06:32 INFO - 'USER': 'cltbld', 12:06:32 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:06:32 INFO - '_': '/tools/buildbot/bin/python'} 12:06:32 INFO - TEST-INFO | started process 15478 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWzmB9Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:06:33 INFO - PID 15478 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 12:06:33 INFO - PID 15478 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 12:06:34 INFO - PID 15478 | 12:06:34 INFO - PID 15478 | (/builds/slave/test/build/application/firefox/firefox:15539): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:06:34 INFO - PID 15478 | 12:06:44 INFO - PID 15478 | RSS: Main: 214822912 12:06:44 INFO - PID 15478 | 12:09:41 INFO - PID 15478 | Cycle 1(1): loaded http://localhost:40171/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40171/tests/dromaeo/cssquery-ext.html) 12:09:42 INFO - PID 15478 | RSS: Main: 222613504 12:09:42 INFO - PID 15478 | 12:12:45 INFO - PID 15478 | Cycle 1(1): loaded http://localhost:40171/tests/dromaeo/cssquery-ext.html (next: http://localhost:40171/tests/dromaeo/cssquery-jquery.html) 12:12:45 INFO - PID 15478 | RSS: Main: 224731136 12:12:45 INFO - PID 15478 | 12:15:48 INFO - PID 15478 | Cycle 1(1): loaded http://localhost:40171/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40171/tests/dromaeo/cssquery-mootools.html) 12:15:48 INFO - PID 15478 | RSS: Main: 224739328 12:15:48 INFO - PID 15478 | 12:18:50 INFO - PID 15478 | Cycle 1(1): loaded http://localhost:40171/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40171/tests/dromaeo/cssquery-prototype.html) 12:18:51 INFO - PID 15478 | RSS: Main: 225312768 12:18:51 INFO - PID 15478 | 12:21:55 INFO - PID 15478 | Cycle 1(1): loaded http://localhost:40171/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40171/tests/dromaeo/cssquery-yui.html) 12:21:55 INFO - PID 15478 | RSS: Main: 227078144 12:21:55 INFO - PID 15478 | 12:24:57 INFO - PID 15478 | Cycle 1(1): loaded http://localhost:40171/tests/dromaeo/cssquery-yui.html (next: http://localhost:40171/tests/dromaeo/cssquery-dojo.html) 12:24:57 INFO - PID 15478 | RSS: Main: 229916672 12:24:57 INFO - PID 15478 | 12:24:57 INFO - PID 15478 | __start_tp_report 12:24:57 INFO - PID 15478 | _x_x_mozilla_page_load 12:24:57 INFO - PID 15478 | _x_x_mozilla_page_load_details 12:24:57 INFO - PID 15478 | |i|pagename|runs| 12:24:57 INFO - PID 15478 | |0;dojo.html;3128.8748450062008;3132.3735252949414;3133.7806353555247;3155.6371017333;3185.7292130168935;6319.052142178664;6330.4619107375875;6413.102165696806;6443.323451037644;6443.420092191702;6155.415235552627;6257.5619706620555;6297.244330680313;6318.083877837711;6322.557420980534;5772.826815195541;5781.913271300934;5878.853028674274;5886.087656413263;5893;5847.824565263028;5875.618084824473;5906.084556893688;5908.763649454017;5915.08316210988;5554.1113421852515;5683.687397193153;5744.827655170351;5768.250127483442;5873.383294754053;5800.477956983849;5814.97092514539;5877.265341832271;5888.234529511136;5893.764249430018;5763.538916886639;5800.216970708942;5801.30384353876;5837.854053648651;5855;6265.310815810257;6278.340774218733;6309.3690630936535;6380.202474690664;6408.775692850774;6592.901106483407;6729.1252137221845;6745.797626071226;6750.426213771835;6848.931510684929;6850.794476165723;6867.656617169171;6878.174619045746;6883.036374907517;6884.0362349271145;6310.274318453391;6314.084457753646;6332.683365831736;6336.52476064297;6358.904616430757;6973.895391569131;6982.441404687613;7026.578405295596;7036.753713619999;7079.82300442493;6586.7035983380865;6680.298568650319;6694.263631000586;6721.596704197764;6748.122744043341;6619.238787539398;6710.530262881552;6739.5619284746335;6832.658367081626;6838.111045564145;6664.433523150489;6684.966575167093;6776.356246156706;6825.726970921108;6833.453323734089;6777.220619628707;6777.762778302736;6804.625745583941;6860.108185935796;6881.449484041264;5186.92219616706;5259.158534634516;5268.868278292996;5271.05121078202;5403.70279634616;4582.793774280165;4590.059037897223;4601.608863246594;4626.537346265347;4698.835540756126;5859.296884373903;5863.091220860773;5886.529077673776;5900.76396944126;5906.675132867734;6756.155480564929;6842.076319696876;6878.140232470942;6897.3447522485285;6924.3421874921805;5845.532357411397;5850.619709718854;5933.821985340361;5939.732712027969;5946.226990491295;5569.972150139304;5652.152177173457;5757.337906140764;5813.767449302065;5877.206577112035;6521.771737989147;6548.803535893836;6559.704813283413;6579.342065793382;6599.274079851212;6748.426383757435;6763.560368576027;6779.389854913082;6790.490713196629;6874.9312506876295;6467.514936379696;6487.805365839033;6529.7388104475285;6565.474762018932;6565.638889861103;6412.230532335963;6596.967015165085;6628.27089020217;6629.5359324846795;6739.93260067393;6750.594964302158;6757.22291936414;6804.795856124324;6809.89785153233;6813.0461735358085;6525.119108920235;6719.596824190564;6732.158480189976;6737.124173857564;6745.156855393076;6557.540972131905;6647.036179753812;6659.667016649245;6685.39831415175;6707.228668702966;6499.740010399721;6571.277159512354;6599.340065993362;6630.5027122965;6665.0668906352175;6512.837179070561;6529.477641788741;6595.4393876521035;6606.306337834458;6717.865642687217;6479.254885688206;6496.902546461902;6503.837404064936;6528.183977002875;6529.216494020748 12:24:57 INFO - PID 15478 | |1;ext.html;861.1130535548385;875.2297977779554;876.7545087375537;884.7168905950094;885.4996926736394;37412.81293593532;41177.58822411775;41243.38134927975;41263.381049284246;41273;37699.62300376996;37708;38110.8094459528;38190.42714359287;38198.80900595493;7066.929330706678;7150.642467876585;7186.8562628747395;7189.388901943341;7214.531055481403;6997.755078572251;7119.074520312352;7147.678354474036;7161.391281741058;7161.498695091345;7118.216996130447;7120.679569419389;7131.714731410737;7136.500444968854;7137.357637812595;7632.6183690815515;7831.177726338709;7854.253845884632;7856.685732570691;7866.252705992922;7647.349975252055;7744.070711514598;7765.611719414007;7778.961105194495;7784.104827944802;36431.4535281971;36655;36760;36773;36809.44785828215;3368.023273250779;3403.3533628610485;3412.9658703412897;3413.0955296846355;3417.7607567470286;30910.53634195489;30927.226819329473;31223;31238;31343;4954.603631709455;4971.975140124312;4983.651144419892;5002.524760147817;5008.674436161674;173615;174979;175662.12168939202;176091.99999999872;177814;41400.378994315106;42726.145477090286;42774.71675849729;47815.76092119517;48477.27284090741;50489.49510504922;51438.74280628573;51666;51832.48167518277;51953.220701689504;16101.11443870575;16198.271077801528;16267.674646507003;16300.510984670242;16343.34626614946;13181.934090329487;13246.73506529864;13251.536196233086;13380;13386.39761210748;14188.290585470686;14214.85785142156;14361.281935903164;14366.712665746627;14426.350814213385;5577.330720313588;5732.656040637575;5741.62679425836;5883.7058147092475;5935.376785437467;989.4508547756064;1004.1113614451264;1005.7435353984687;1011.9898801011896;1022.9028242316969;22144;22354.55290894173;22532;22619.43451413695;22630.886845565998;1092.322759888874;1117.7362102660252;1118.7315044389288;1124.943752812356;1128.3906690387098;11932.985696215745;12015.519379224908;12103.152779305365;12128.151029427856;12149.392530373447;206840;208660;209041;209311;209439;51198;51432.22851657228;51624.22563661548;51754.74122629363;51894;1382.6336020954454;1433.9067960582738;1440.2582669924998;1454.534548944349;1454.7308747881675;22171;22235;22350.105995760638;22420.775792242523;22744.31767046991;35969.820150899075;35996.82001589975;36063;36110.63889361178;36218;186235;187422;188664;188930;189240;30485.713571482673;31159.376812464077;31273.37453250968;32432.513512296864;33014;43882.34176487419;44305.778471107435;44564.77717611521;44838.327425088;44841.10317793691;32195.678043219268;32336.191595209366;32429.18927026749;32513.512297316032;32813;31010.68989310078;31190.688093118777;31346.373072538878;31386.68613313929;31392;35739.28521429609;35816.283674326885;35950.64049359473;36395.63604363923;36501.26997460089;30968.53547196839;31183.532247016767;31456.370872582876;31659.366812663156;32302;21904.452388689773;23082.884585576965;23191.79677930594;23776;23984.52030959406 12:24:57 INFO - PID 15478 | |2;jquery.html;550.4742970463208;554.3098841941783;562.9099344104944;564.4533284394205;588.4262843727366;21654.89172554139;22429.214977475793;22445.663315050268;22476.98853551588;22484.213052543164;23548.764512354915;23751.762482375172;23806.88096559519;24068.879655601697;24461.755382446216;6549.738010479575;6653.068570400148;6660.70026848793;6702.631355275482;6708.094407255007;6525.902111468308;6564.474842012628;6609.801705948829;6626.237982632011;6627.138471998657;6212.8446788830415;6395.712192951329;6398.040293955897;6409.198850143732;6416.967915160418;6357.682115894187;6361.6183029018175;6371.267304260023;6413;6413.518986076063;5863.824085277406;5950.523958083343;5957.8808423831715;5974.820755377345;6080.422359875804;26359;26422.339441513923;26588.20235392922;26608.20175394741;26645;2004.6892731626622;2015.4155294964444;2016.8588198826087;2016.9697454538193;2018.0414303206007;18386.448406547825;19068.809311906844;19157.04214789255;19247.807521924882;19319.323823666244;2360.929172124839;2429.283361408371;2440.19473573722;2440.8535487870786;2442.8656423896678;117251;122479.38760306142;123120.38439807833;128338.99999999907;130961.34519327438;13448.865511344762;14123.929380353033;14342.713145737027;14467.710645787025;14624.415023399157;22282.108715651193;22301.442463938532;22346.21788237404;22372.664410033864;22385.888070559544;9471.573779179953;10001.249656344564;10043.849342259726;10102.39385636864;10311.845322320021;9270.07299270081;9448.811023779486;9464.716058518256;9501.952490237505;9515.04849515043;8746.212840844228;9071.183593476619;9071.455712657129;9126.680566180152;9135.817283654289;2661.387880787391;2698.1365962891687;2777.8194417362806;2784.1786672931535;2818.8308701477977;942.4675058592011;964.1900803325241;967.1700067731861;969.4571040217501;977.7115750853516;18488.815111848708;18792.53018674544;19082.04589770506;19124;19318.613627727365;992.6624947517879;997.6607953295915;1003.5684655598017;1006.8892421833592;1018.3838777539482;10185.898141018642;10402.791944161074;10421.53103110362;10472.947635261928;10492.737681558023;423823;426249;435284;437861;447930;103111;106406;106444.46777766061;107433.46283268534;107938;1030.5723124903081;1084.978300433987;1092.0771947704177;1120.9831852522057;1128.4470609401335;19506.804931950497;19576.314828981485;19925.800741992396;20010.89994550018;20034.499137521678;52430.95138097293;55076.72461637666;55388.16917746156;55831.44168558263;55887.44112558822;65000.34999650132;67462;68330.31669683439;68504;69511.65244173747;13751.174929504261;13853.376598053314;14134.222617756122;14150.929245353707;14291.857081429052;22026.559468810854;22210.222642207445;22320;22449.10203591838;22483;17712.46862594124;17788.733169002215;17861;18064.909675451538;18212;15878.523644290228;16039.67920641604;16159.192040397698;16400.343986240416;16501.91749041247;18427;18686.53283667919;18700;18745;18789.34237301688;13672.52146174879;13771.655708607364;13787.931060344634;13812.654683632989;13856.86143138596;16942.40701575488;17555.473335799947;17746.5563360917;17927.551811204823;17993.640127197643 12:24:57 INFO - PID 15478 | |3;mootools.html;1300.1938797945272;1386.4038463460708;1389.1040279020033;1400.7268582626389;1408.2114016150956;14223.288835558233;14797.704045919076;14828.481003164892;14860.702785944275;14915.627109322259;14624.049436786601;14663.853361466383;14669.119852808812;14674.046186997837;14808.925955370236;2752.215618548714;2768.349437882101;2800.4679110968955;2801.8739156737893;2814.2964258935267;2647.8543680097587;2759.9448011039767;2776.1671498550354;2785.916422507328;2789.1771927281397;2693.595960605915;2732.098407525512;2738.6302849115414;2767.2390092724563;2791.916242512718;2745.203890871665;2802.9159125226274;2803.2851622836147;2805.5791631255374;2834.263091596179;2689.126034038926;2697.6223328733995;2718.6329845470805;2718.714534973839;2719.8232114912466;14504.927475362661;14863.628409289746;14904.552863414116;14987.175705336202;15025.699486010328;7933.96033019837;8128.59357032146;8235.258826705565;8237.50574965498;8261.876071858927;5203.4796520348045;5416.241726158341;5495.2856128703415;5549.084401073818;5552.278203833475;1906.5138389710673;1922.7404300419544;1928.399428474377;1946.931857384999;1955.3840540229783;25744;25852.22443326703;25973.870130649226;25978.480430391475;25980.480390392087;6647.966760166169;6670.332966703291;6753.189617245962;6890.483213758988;6955.200151982485;16892;16893.32426702918;16950.660986780196;17003.40488082911;17080.145992700564;7702.73040443593;7974.043114826258;7974.760757177294;7980.68077276914;7986.960065199637;7235.131784185932;7384.667689953965;7485.5508669480005;7526.397888169043;7575.848483030309;5172.224166374998;5228.895422091537;5304.92042619361;5327.227552005015;5335.9466405336225;1441.9206943617992;1492.940282388714;1559.4541910331293;1578.747400415951;1591.1805420208602;725.6661935509725;861.3195575495289;871.0636066228702;884.4914174349775;889.768660148366;8112.594370281462;8130.268275855202;8265.95867020661;8283.8343233135;8598.570071496526;959.7840485890758;961.1397798969883;975.7804493988796;1009.1926458832904;1010.3331801011298;5010.674306170087;5080.060188865072;5088.7786931137;5238.214267859776;5428.864278393072;20532.897335513226;21517.246896358865;21527.78472215289;21631.459213519473;21754.673679894815;20297.594048118954;21052.47368815792;21117.26089586857;21462.24882129149;21764.782352176593;10362.481875906175;10426.113780328757;10597.788044239072;10603.204759643058;10683.73290667724;8125.228103330174;8209.220124088202;8229.094799571923;8325.167483251626;8355.415120941476;18180.000099994628;18310.450686479428;18594.163262652943;18649.347272845385;18692.971886545827;18392.540186495444;18867.245310187438;19295;19500.707489387954;19749.1112899917;10656.733581660521;10812.837807433052;10815.297005694605;10912.61805836792;10918;13296.933515332361;13659.726805464034;13687.11033782801;13797.310134493437;13800.792988105388;10189.847152292574;10247.436390998566;10359.430231337048;11398.601048963246;11524.654260372203;10956.34261944286;11058.115350771757;11253.324800511995;11269.436528173723;11451;10025.74935626586;10030.29787914839;10090.69727908164;10094;10096.444695541522;7591.278828511283;7623;7657.234276572298;7693.307602315818;7717.421193410404;7444.702211911463;7469.141048779243;7493.100827900686;7512.173660897406;7528.811779705551 12:24:57 INFO - PID 15478 | |4;prototype.html;490.6630862411948;506.5441103007292;524.1351769580194;525.4535283305363;530.879137844312;6182.783602573911;6202.968985155079;6209.379062093788;6258.280297765758;6304.968475157618;6148.094090831834;6182.659953702545;6201.883660941029;6202.968985155079;6302.968485157568;5103.106956282654;5161.380634323887;5170.353705786777;5178.5339319461245;5190.558802501791;5185.637005409623;5200.115980283361;5207.843764687065;5228.163493840986;5247.239150323201;4766.6901651392545;5083.6441449098575;5087.186050231963;5159.638825282232;5203.42762296149;4898.53463920927;4912.778924948386;4940.777665005084;4945.7774400152075;4975.527324904128;4397.934030989538;4419.315006174048;4428.269335559628;4458.464984201916;4468.709533880287;5979.1629171915965;5998.980173370537;6022.69886505673;6061.848453788691;6069.241344831896;5591.021571225019;5625.437456254383;5674.580775359522;5709.115087161496;5721.599488035839;6455.064015717695;6485.7405703772265;6485.967570162166;6589;6619.635920024397;4561.498235194126;4818.976154910654;4833.516648335173;4857.562819346276;4879.756012199376;6509.837254068639;6536.901946470807;6577.829316325601;6790.49071319653;6847.417969472651;4875.414950205927;4897.877553061131;4923.581495572845;4923.630727695438;4945.403663076841;4799.18413869643;4939.5554400104165;4940.481249468826;4944.826931057468;4980.452150263468;5545.77816887328;5592.944070559323;5623.184638227434;5633.380328163898;5640.520555752725;6369.426751592379;6548.008572156424;6557.606543607399;6593.439557637558;6603.966980165164;1712.53761484401;1725.9654806904043;1743.886647367917;1748.6677531269017;1749.5713550180287;1149.545929357899;1186.7270527778487;1198.9160758746805;1205.4033253539553;1220.560598184653;5599.608027438122;5623.722392431072;5627.521660658808;5713.485786279255;5731.312242530923;6573.7370505180215;6614.511734859714;6651.567648102955;6697.6986035628515;6730.93269067303;5457.645253058538;5520.420355862577;5637.633553818989;5694.686792226467;5700.686462244534;3427.1774774054047;3460.86156553735;3492.9825350873084;3516.4197907345515;3540.946885796715;6259.311475737665;6403.743850246031;6416.748733996919;6421.325760795143;6426.710798014101;6698.593295407922;6723.462123030244;6753.041617930843;6819.829504262433;6851.417629501449;6282.497400207881;6363.172787537591;6393.328700486475;6418;6454.483641308684;6057.636541807506;6500.6424646643;6517.967410162919;6589.47284217271;6622.8344291393105;6495.960646296759;6499.902501462388;6533.477321814338;6534.869302614016;6648.667566621552;6214.751409943552;6267.749290028348;6312.3056463788025;6367.172267605367;6479.416852483299;4825.346059667098;4853.95146048535;4873.658843881036;4880.511948805091;4924.778384972613;6304.18045654062;6494.318096599789;6580.5722628029025;6591.274959754519;6606.636634985122;6253.249610046823;6312.6528040956355;6476.643784591893;6496.285408604956;6497.9025314619375;6279.466245369194;6365.96817015912;6372.980323148274;6439.678016099083;6453.193350831146;6205.751769929333;6258.311585725475;6313.579444624923;6332.448550105254;6342.88999425108;6451.225852897683;6462.773802917063;6467.41793238592;6486.805395838132;6511.576747511396;6189.96905015472;6195.659238741912;6217.347178546188;6229.12792209082;6267.9373206267355 12:24:57 INFO - PID 15478 | |5;yui.html;894.4275663575316;903.5437104262348;914.3279689428272;920.5627327019666;925.4123631494003;16695.916520417395;17430.825691743077;17532.73700894486;17536.47390578283;17545.12274386282;15736.449224277152;16103.275352609131;16394.09832459217;16397.508074757778;16968.32126714933;900.6037343568826;912.7079334612927;914.4330515080626;915.455304094063;918.2516249256835;961.942283462991;968.8982656821038;976.0629795396428;978.1001478639633;980.7695191629962;955.4506158958627;969.9515024248794;995.9253056020791;1001.1690297053465;1013.0426746724348;950.4819873169174;951.0869565217399;953.0659953245788;953.2088366655694;996.8903420623726;706.929307069294;724.916634587024;726.4733068525329;730.4485113739092;732.3482100930177;17270.56823579408;17413;17544.38594649194;17579.912100439415;17594;21671.891640541853;21817.345479635635;22045.33863984083;22594.774052259432;22756.77243227563;16956.576085597837;17193.140342982802;17528.12359382026;17544.385946491813;17882.463526094238;3752.868649597251;3769.717271204643;3780.489633899416;3780.6030366811365;3780.7542509736777;137264.31367843196;138190;140834;141021.2948935259;143663.28168359195;16466.75299870503;16733.414330498374;16823.495295141165;17200;17337.393191238247;30268.24329391739;31552;31670.841645791625;32008;32310.676893231237;8466.830663386821;8476.830463390697;8530.317574594017;8559.358048146414;8577.95711021441;4040.616141466556;4123.876283711494;4133.648639865645;4142.00591857958;4166.187593419315;5432.945670543323;5488.204210389471;5521.751521181556;5522.0336441122645;5553.583481238923;1004.3371374892536;1020.78563501664;1021.2187676427538;1036.0520124086377;1042.2391654092555;665.1353240786957;672.9865402692017;694.4999600287784;695.6104581434413;701.3512500936715;11896;12093.274403535816;12219.389030548436;12274.75450491003;12377.62867113988;659.2451642868984;660.1714847865898;670.8423520472685;683.45323741007;688.215434404779;5368.302120724281;5465.535429488538;5487.725613719298;5496.395396506381;5513.3108361454815;127734;128431;130063;130592.3470382642;131123;84068;107408;109783.45108274567;112930.43534782274;114978;94358;95086;97275.51362243143;98996.50501747445;100404.49797750964;12717.93641031826;13117.409716562586;13304.201747895157;13355.79966300487;13625;42488;42968.78515607402;43018;43044.13911721685;43389.566104339814;54907.45092549024;56576;56948;57488;57760.42239577718;15558.455454059054;15677.608059798597;15752.921235394208;15791.526254212393;15860;20764.480887977315;20811.271605493734;21019.474513136684;21101.57796844085;21108;17253.741193882353;18212.544686382946;18258.72611910849;18361.3573524926;18474.538136546154;17209.311627534757;17234.569135771708;17374.652506950042;17410.129493525525;17522.82477175212;15594.9220253898;15801.920990394974;15821.208939552746;15899.682006360039;15982.60043498922;11902.583409580624;11975.221610595283;12051.51793928233;12213.816792748294;12218.450169742206;13196.274204918822;13306.800397993844;13316.40076196554;13449.125806822525;13478.326083695973 12:24:57 INFO - PID 15478 | __end_tp_report 12:24:57 INFO - PID 15478 | __start_cc_report 12:24:57 INFO - PID 15478 | _x_x_mozilla_cycle_collect,371 12:24:57 INFO - PID 15478 | __end_cc_report 12:24:57 INFO - PID 15478 | __startTimestamp1505503497983__endTimestamp 12:24:57 INFO - PID 15478 | 12:24:57 INFO - PID 15478 | ------- Summary: start ------- 12:24:57 INFO - PID 15478 | Number of tests: 6 12:24:57 INFO - PID 15478 | 12:24:57 INFO - PID 15478 | [#0] dojo.html Cycles:165 Average:6236.59 Median:6512.84 stddev:760.07 (11.7%) stddev-sans-first:722.24 12:24:57 INFO - PID 15478 | Values: 3128.9 3132.4 3133.8 3155.6 3185.7 6319.1 6330.5 6413.1 6443.3 6443.4 6155.4 6257.6 6297.2 6318.1 6322.6 5772.8 5781.9 5878.9 5886.1 5893.0 5847.8 5875.6 5906.1 5908.8 5915.1 5554.1 5683.7 5744.8 5768.3 5873.4 5800.5 5815.0 5877.3 5888.2 5893.8 5763.5 5800.2 5801.3 5837.9 5855.0 6265.3 6278.3 6309.4 6380.2 6408.8 6592.9 6729.1 6745.8 6750.4 6848.9 6850.8 6867.7 6878.2 6883.0 6884.0 6310.3 6314.1 6332.7 6336.5 6358.9 6973.9 6982.4 7026.6 7036.8 7079.8 6586.7 6680.3 6694.3 6721.6 6748.1 6619.2 6710.5 6739.6 6832.7 6838.1 6664.4 6685.0 6776.4 6825.7 6833.5 6777.2 6777.8 6804.6 6860.1 6881.4 5186.9 5259.2 5268.9 5271.1 5403.7 4582.8 4590.1 4601.6 4626.5 4698.8 5859.3 5863.1 5886.5 5900.8 5906.7 6756.2 6842.1 6878.1 6897.3 6924.3 5845.5 5850.6 5933.8 5939.7 5946.2 5570.0 5652.2 5757.3 5813.8 5877.2 6521.8 6548.8 6559.7 6579.3 6599.3 6748.4 6763.6 6779.4 6790.5 6874.9 6467.5 6487.8 6529.7 6565.5 6565.6 6412.2 6597.0 6628.3 6629.5 6739.9 6750.6 6757.2 6804.8 6809.9 6813.0 6525.1 6719.6 6732.2 6737.1 6745.2 6557.5 6647.0 6659.7 6685.4 6707.2 6499.7 6571.3 6599.3 6630.5 6665.1 6512.8 6529.5 6595.4 6606.3 6717.9 6479.3 6496.9 6503.8 6528.2 6529.2 12:24:57 INFO - PID 15478 | 12:24:57 INFO - PID 15478 | [#1] ext.html Cycles:180 Average:35816.52 Median:3408.16 stddev:49464.14 (1451.3%) stddev-sans-first:49533.26 12:24:57 INFO - PID 15478 | Values: 861.1 875.2 876.8 884.7 885.5 37412.8 41177.6 41243.4 41263.4 41273.0 37699.6 37708.0 38110.8 38190.4 38198.8 7066.9 7150.6 7186.9 7189.4 7214.5 6997.8 7119.1 7147.7 7161.4 7161.5 7118.2 7120.7 7131.7 7136.5 7137.4 7632.6 7831.2 7854.3 7856.7 7866.3 7647.3 7744.1 7765.6 7779.0 7784.1 36431.5 36655.0 36760.0 36773.0 36809.4 3368.0 3403.4 3413.0 3413.1 3417.8 30910.5 30927.2 31223.0 31238.0 31343.0 4954.6 4972.0 4983.7 5002.5 5008.7 173615.0 174979.0 175662.1 176092.0 177814.0 41400.4 42726.1 42774.7 47815.8 48477.3 50489.5 51438.7 51666.0 51832.5 51953.2 16101.1 16198.3 16267.7 16300.5 16343.3 13181.9 13246.7 13251.5 13380.0 13386.4 14188.3 14214.9 14361.3 14366.7 14426.4 5577.3 5732.7 5741.6 5883.7 5935.4 989.5 1004.1 1005.7 1012.0 1022.9 22144.0 22354.6 22532.0 22619.4 22630.9 1092.3 1117.7 1118.7 1124.9 1128.4 11933.0 12015.5 12103.2 12128.2 12149.4 206840.0 208660.0 209041.0 209311.0 209439.0 51198.0 51432.2 51624.2 51754.7 51894.0 1382.6 1433.9 1440.3 1454.5 1454.7 22171.0 22235.0 22350.1 22420.8 22744.3 35969.8 35996.8 36063.0 36110.6 36218.0 186235.0 187422.0 188664.0 188930.0 189240.0 30485.7 31159.4 31273.4 32432.5 33014.0 43882.3 44305.8 44564.8 44838.3 44841.1 32195.7 32336.2 32429.2 32513.5 32813.0 31010.7 31190.7 31346.4 31386.7 31392.0 35739.3 35816.3 35950.6 36395.6 36501.3 30968.5 31183.5 31456.4 31659.4 32302.0 21904.5 23082.9 23191.8 23776.0 23984.5 12:24:57 INFO - PID 15478 | 12:24:57 INFO - PID 15478 | [#2] jquery.html Cycles:180 Average:32135.30 Median:22407.55 stddev:73269.17 (327.0%) stddev-sans-first:73436.33 12:24:57 INFO - PID 15478 | Values: 550.5 554.3 562.9 564.5 588.4 21654.9 22429.2 22445.7 22477.0 22484.2 23548.8 23751.8 23806.9 24068.9 24461.8 6549.7 6653.1 6660.7 6702.6 6708.1 6525.9 6564.5 6609.8 6626.2 6627.1 6212.8 6395.7 6398.0 6409.2 6417.0 6357.7 6361.6 6371.3 6413.0 6413.5 5863.8 5950.5 5957.9 5974.8 6080.4 26359.0 26422.3 26588.2 26608.2 26645.0 2004.7 2015.4 2016.9 2017.0 2018.0 18386.4 19068.8 19157.0 19247.8 19319.3 2360.9 2429.3 2440.2 2440.9 2442.9 117251.0 122479.4 123120.4 128339.0 130961.3 13448.9 14123.9 14342.7 14467.7 14624.4 22282.1 22301.4 22346.2 22372.7 22385.9 9471.6 10001.2 10043.8 10102.4 10311.8 9270.1 9448.8 9464.7 9502.0 9515.0 8746.2 9071.2 9071.5 9126.7 9135.8 2661.4 2698.1 2777.8 2784.2 2818.8 942.5 964.2 967.2 969.5 977.7 18488.8 18792.5 19082.0 19124.0 19318.6 992.7 997.7 1003.6 1006.9 1018.4 10185.9 10402.8 10421.5 10472.9 10492.7 423823.0 426249.0 435284.0 437861.0 447930.0 103111.0 106406.0 106444.5 107433.5 107938.0 1030.6 1085.0 1092.1 1121.0 1128.4 19506.8 19576.3 19925.8 20010.9 20034.5 52431.0 55076.7 55388.2 55831.4 55887.4 65000.3 67462.0 68330.3 68504.0 69511.7 13751.2 13853.4 14134.2 14150.9 14291.9 22026.6 22210.2 22320.0 22449.1 22483.0 17712.5 17788.7 17861.0 18064.9 18212.0 15878.5 16039.7 16159.2 16400.3 16501.9 18427.0 18686.5 18700.0 18745.0 18789.3 13672.5 13771.7 13787.9 13812.7 13856.9 16942.4 17555.5 17746.6 17927.6 17993.6 12:24:58 INFO - PID 15478 | 12:24:58 INFO - PID 15478 | [#3] mootools.html Cycles:180 Average:9240.65 Median:25979.48 stddev:6523.30 (25.1%) stddev-sans-first:6514.32 12:24:58 INFO - PID 15478 | Values: 1300.2 1386.4 1389.1 1400.7 1408.2 14223.3 14797.7 14828.5 14860.7 14915.6 14624.0 14663.9 14669.1 14674.0 14808.9 2752.2 2768.3 2800.5 2801.9 2814.3 2647.9 2759.9 2776.2 2785.9 2789.2 2693.6 2732.1 2738.6 2767.2 2791.9 2745.2 2802.9 2803.3 2805.6 2834.3 2689.1 2697.6 2718.6 2718.7 2719.8 14504.9 14863.6 14904.6 14987.2 15025.7 7934.0 8128.6 8235.3 8237.5 8261.9 5203.5 5416.2 5495.3 5549.1 5552.3 1906.5 1922.7 1928.4 1946.9 1955.4 25744.0 25852.2 25973.9 25978.5 25980.5 6648.0 6670.3 6753.2 6890.5 6955.2 16892.0 16893.3 16950.7 17003.4 17080.1 7702.7 7974.0 7974.8 7980.7 7987.0 7235.1 7384.7 7485.6 7526.4 7575.8 5172.2 5228.9 5304.9 5327.2 5335.9 1441.9 1492.9 1559.5 1578.7 1591.2 725.7 861.3 871.1 884.5 889.8 8112.6 8130.3 8266.0 8283.8 8598.6 959.8 961.1 975.8 1009.2 1010.3 5010.7 5080.1 5088.8 5238.2 5428.9 20532.9 21517.2 21527.8 21631.5 21754.7 20297.6 21052.5 21117.3 21462.2 21764.8 10362.5 10426.1 10597.8 10603.2 10683.7 8125.2 8209.2 8229.1 8325.2 8355.4 18180.0 18310.5 18594.2 18649.3 18693.0 18392.5 18867.2 19295.0 19500.7 19749.1 10656.7 10812.8 10815.3 10912.6 10918.0 13296.9 13659.7 13687.1 13797.3 13800.8 10189.8 10247.4 10359.4 11398.6 11524.7 10956.3 11058.1 11253.3 11269.4 11451.0 10025.7 10030.3 10090.7 10094.0 10096.4 7591.3 7623.0 7657.2 7693.3 7717.4 7444.7 7469.1 7493.1 7512.2 7528.8 12:24:58 INFO - PID 15478 | 12:24:58 INFO - PID 15478 | [#4] prototype.html Cycles:180 Average:5437.64 Median:6108.67 stddev:1512.19 (24.8%) stddev-sans-first:1470.14 12:24:58 INFO - PID 15478 | Values: 490.7 506.5 524.1 525.5 530.9 6182.8 6203.0 6209.4 6258.3 6305.0 6148.1 6182.7 6201.9 6203.0 6303.0 5103.1 5161.4 5170.4 5178.5 5190.6 5185.6 5200.1 5207.8 5228.2 5247.2 4766.7 5083.6 5087.2 5159.6 5203.4 4898.5 4912.8 4940.8 4945.8 4975.5 4397.9 4419.3 4428.3 4458.5 4468.7 5979.2 5999.0 6022.7 6061.8 6069.2 5591.0 5625.4 5674.6 5709.1 5721.6 6455.1 6485.7 6486.0 6589.0 6619.6 4561.5 4819.0 4833.5 4857.6 4879.8 6509.8 6536.9 6577.8 6790.5 6847.4 4875.4 4897.9 4923.6 4923.6 4945.4 4799.2 4939.6 4940.5 4944.8 4980.5 5545.8 5592.9 5623.2 5633.4 5640.5 6369.4 6548.0 6557.6 6593.4 6604.0 1712.5 1726.0 1743.9 1748.7 1749.6 1149.5 1186.7 1198.9 1205.4 1220.6 5599.6 5623.7 5627.5 5713.5 5731.3 6573.7 6614.5 6651.6 6697.7 6730.9 5457.6 5520.4 5637.6 5694.7 5700.7 3427.2 3460.9 3493.0 3516.4 3540.9 6259.3 6403.7 6416.7 6421.3 6426.7 6698.6 6723.5 6753.0 6819.8 6851.4 6282.5 6363.2 6393.3 6418.0 6454.5 6057.6 6500.6 6518.0 6589.5 6622.8 6496.0 6499.9 6533.5 6534.9 6648.7 6214.8 6267.7 6312.3 6367.2 6479.4 4825.3 4854.0 4873.7 4880.5 4924.8 6304.2 6494.3 6580.6 6591.3 6606.6 6253.2 6312.7 6476.6 6496.3 6497.9 6279.5 6366.0 6373.0 6439.7 6453.2 6205.8 6258.3 6313.6 6332.4 6342.9 6451.2 6462.8 6467.4 6486.8 6511.6 6190.0 6195.7 6217.3 6229.1 6267.9 12:24:58 INFO - PID 15478 | 12:24:58 INFO - PID 15478 | [#5] yui.html Cycles:180 Average:24605.78 Median:21104.79 stddev:35785.57 (169.6%) stddev-sans-first:35841.67 12:24:58 INFO - PID 15478 | Values: 894.4 903.5 914.3 920.6 925.4 16695.9 17430.8 17532.7 17536.5 17545.1 15736.4 16103.3 16394.1 16397.5 16968.3 900.6 912.7 914.4 915.5 918.3 961.9 968.9 976.1 978.1 980.8 955.5 970.0 995.9 1001.2 1013.0 950.5 951.1 953.1 953.2 996.9 706.9 724.9 726.5 730.4 732.3 17270.6 17413.0 17544.4 17579.9 17594.0 21671.9 21817.3 22045.3 22594.8 22756.8 16956.6 17193.1 17528.1 17544.4 17882.5 3752.9 3769.7 3780.5 3780.6 3780.8 137264.3 138190.0 140834.0 141021.3 143663.3 16466.8 16733.4 16823.5 17200.0 17337.4 30268.2 31552.0 31670.8 32008.0 32310.7 8466.8 8476.8 8530.3 8559.4 8578.0 4040.6 4123.9 4133.6 4142.0 4166.2 5432.9 5488.2 5521.8 5522.0 5553.6 1004.3 1020.8 1021.2 1036.1 1042.2 665.1 673.0 694.5 695.6 701.4 11896.0 12093.3 12219.4 12274.8 12377.6 659.2 660.2 670.8 683.5 688.2 5368.3 5465.5 5487.7 5496.4 5513.3 127734.0 128431.0 130063.0 130592.3 131123.0 84068.0 107408.0 109783.5 112930.4 114978.0 94358.0 95086.0 97275.5 98996.5 100404.5 12717.9 13117.4 13304.2 13355.8 13625.0 42488.0 42968.8 43018.0 43044.1 43389.6 54907.5 56576.0 56948.0 57488.0 57760.4 15558.5 15677.6 15752.9 15791.5 15860.0 20764.5 20811.3 21019.5 21101.6 21108.0 17253.7 18212.5 18258.7 18361.4 18474.5 17209.3 17234.6 17374.7 17410.1 17522.8 15594.9 15801.9 15821.2 15899.7 15982.6 11902.6 11975.2 12051.5 12213.8 12218.5 13196.3 13306.8 13316.4 13449.1 13478.3 12:24:58 INFO - PID 15478 | -------- Summary: end -------- 12:24:58 INFO - PID 15478 | 12:24:59 INFO - TEST-INFO | 15478: exit 0 12:24:59 INFO - TEST-OK | dromaeo_css | took 1113290ms 12:24:59 INFO - TEST-START | kraken 12:24:59 INFO - Initialising browser for kraken test... 12:24:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40171/getInfo.html -profile /tmp/tmpee4DYj/profile 12:24:59 INFO - TEST-INFO | started process 15861 (/builds/slave/test/build/application/firefox/firefox http://localhost:40171/getInfo.html) 12:25:05 INFO - TEST-INFO | 15861: exit 0 12:25:05 INFO - Browser initialized. 12:25:05 INFO - Running cycle 1/1 for kraken test... 12:25:05 INFO - Using env: {'DISPLAY': ':0', 12:25:05 INFO - 'HOME': '/home/cltbld', 12:25:05 INFO - 'JSGC_DISABLE_POISONING': '1', 12:25:05 INFO - 'LANG': 'en_US.UTF-8', 12:25:05 INFO - 'LANGUAGE': 'en_US:en', 12:25:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 12:25:05 INFO - 'LOGNAME': 'cltbld', 12:25:05 INFO - 'MAIL': '/var/mail/cltbld', 12:25:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:25:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:25:05 INFO - 'MOZ_ACCELERATED': '1', 12:25:05 INFO - 'MOZ_CRASHREPORTER': '1', 12:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:25:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 12:25:05 INFO - 'MOZ_NO_REMOTE': '1', 12:25:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:25:05 INFO - 'MOZ_WEBRENDER': '1', 12:25:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:25:05 INFO - 'NO_EM_RESTART': '1', 12:25:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:25:05 INFO - 'PWD': '/builds/slave/test', 12:25:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:25:05 INFO - 'RUST_BACKTRACE': 'full', 12:25:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:25:05 INFO - 'SHELL': '/bin/bash', 12:25:05 INFO - 'SHLVL': '1', 12:25:05 INFO - 'TERM': 'linux', 12:25:05 INFO - 'TMOUT': '86400', 12:25:05 INFO - 'USER': 'cltbld', 12:25:05 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370', 12:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:25:05 INFO - '_': '/tools/buildbot/bin/python'} 12:25:05 INFO - TEST-INFO | started process 16109 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpee4DYj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:25:06 INFO - PID 16109 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 12:25:06 INFO - PID 16109 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 12:25:06 INFO - PID 16109 | 12:25:06 INFO - PID 16109 | (/builds/slave/test/build/application/firefox/firefox:16168): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:25:06 INFO - PID 16109 | 12:25:17 INFO - PID 16109 | RSS: Main: 214364160 12:25:17 INFO - PID 16109 | 12:25:19 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40171/tests/kraken/driver.html?testName=audio-beat-detection) 12:25:20 INFO - PID 16109 | RSS: Main: 220176384 12:25:20 INFO - PID 16109 | 12:25:23 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40171/tests/kraken/driver.html?testName=audio-dft) 12:25:24 INFO - PID 16109 | RSS: Main: 220299264 12:25:24 INFO - PID 16109 | 12:25:27 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40171/tests/kraken/driver.html?testName=audio-fft) 12:25:27 INFO - PID 16109 | RSS: Main: 217272320 12:25:27 INFO - PID 16109 | 12:25:30 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40171/tests/kraken/driver.html?testName=audio-oscillator) 12:25:30 INFO - PID 16109 | RSS: Main: 216612864 12:25:30 INFO - PID 16109 | 12:25:32 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40171/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:25:33 INFO - PID 16109 | RSS: Main: 216948736 12:25:33 INFO - PID 16109 | 12:25:36 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40171/tests/kraken/driver.html?testName=imaging-darkroom) 12:25:37 INFO - PID 16109 | RSS: Main: 220282880 12:25:37 INFO - PID 16109 | 12:25:41 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40171/tests/kraken/driver.html?testName=imaging-desaturate) 12:25:41 INFO - PID 16109 | RSS: Main: 219136000 12:25:41 INFO - PID 16109 | 12:25:44 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40171/tests/kraken/driver.html?testName=json-parse-financial) 12:25:45 INFO - PID 16109 | RSS: Main: 219779072 12:25:45 INFO - PID 16109 | 12:25:47 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40171/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:25:47 INFO - PID 16109 | RSS: Main: 221003776 12:25:47 INFO - PID 16109 | 12:25:49 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40171/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:25:49 INFO - PID 16109 | RSS: Main: 221175808 12:25:49 INFO - PID 16109 | 12:25:52 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40171/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:25:52 INFO - PID 16109 | RSS: Main: 219910144 12:25:52 INFO - PID 16109 | 12:25:55 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40171/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:25:56 INFO - PID 16109 | RSS: Main: 220626944 12:25:56 INFO - PID 16109 | 12:25:59 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40171/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:25:59 INFO - PID 16109 | RSS: Main: 221335552 12:25:59 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | Cycle 1(1): loaded http://localhost:40171/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40171/tests/kraken/driver.html?testName=ai-astar) 12:26:01 INFO - PID 16109 | RSS: Main: 223453184 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | __start_tp_report 12:26:01 INFO - PID 16109 | _x_x_mozilla_page_load 12:26:01 INFO - PID 16109 | _x_x_mozilla_page_load_details 12:26:01 INFO - PID 16109 | |i|pagename|runs| 12:26:01 INFO - PID 16109 | |0;ai-astar;108;117;119;110;112;108;109;109;118;109 12:26:01 INFO - PID 16109 | |1;audio-beat-detection;127;124;123;117;146;120;121;117;129;128 12:26:01 INFO - PID 16109 | |2;audio-dft;171;167;166;162;182;172;164;165;178;163 12:26:01 INFO - PID 16109 | |3;audio-fft;81;74;81;82;82;77;72;75;78;109 12:26:01 INFO - PID 16109 | |4;audio-oscillator;84;81;89;87;78;88;83;83;85;82 12:26:01 INFO - PID 16109 | |5;imaging-gaussian-blur;111;114;111;115;113;113;116;122;114;116 12:26:01 INFO - PID 16109 | |6;imaging-darkroom;148;154;148;148;148;151;148;153;154;147 12:26:01 INFO - PID 16109 | |7;imaging-desaturate;99;94;94;104;94;95;94;97;95;94 12:26:01 INFO - PID 16109 | |8;json-parse-financial;54;53;53;54;54;53;54;53;55;61 12:26:01 INFO - PID 16109 | |9;json-stringify-tinderbox;42;38;48;38;41;44;38;39;38;42 12:26:01 INFO - PID 16109 | |10;stanford-crypto-aes;80;77;77;74;77;79;79;75;74;75 12:26:01 INFO - PID 16109 | |11;stanford-crypto-ccm;119;122;119;119;128;117;120;122;114;113 12:26:01 INFO - PID 16109 | |12;stanford-crypto-pbkdf2;178;186;182;172;175;182;174;177;170;171 12:26:01 INFO - PID 16109 | |13;stanford-crypto-sha256-iterative;73;67;65;66;67;67;70;70;65;66 12:26:01 INFO - PID 16109 | __end_tp_report 12:26:01 INFO - PID 16109 | __start_cc_report 12:26:01 INFO - PID 16109 | _x_x_mozilla_cycle_collect,928 12:26:01 INFO - PID 16109 | __end_cc_report 12:26:01 INFO - PID 16109 | __startTimestamp1505503561943__endTimestamp 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | ------- Summary: start ------- 12:26:01 INFO - PID 16109 | Number of tests: 14 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.00 stddev:4.38 (3.9%) stddev-sans-first:4.42 12:26:01 INFO - PID 16109 | Values: 108.0 117.0 119.0 110.0 112.0 108.0 109.0 109.0 118.0 109.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:125.50 stddev:8.46 (6.7%) stddev-sans-first:8.94 12:26:01 INFO - PID 16109 | Values: 127.0 124.0 123.0 117.0 146.0 120.0 121.0 117.0 129.0 128.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#2] audio-dft Cycles:10 Average:169.00 Median:169.00 stddev:6.68 (4.0%) stddev-sans-first:7.05 12:26:01 INFO - PID 16109 | Values: 171.0 167.0 166.0 162.0 182.0 172.0 164.0 165.0 178.0 163.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#3] audio-fft Cycles:10 Average:81.10 Median:79.50 stddev:10.42 (13.1%) stddev-sans-first:11.05 12:26:01 INFO - PID 16109 | Values: 81.0 74.0 81.0 82.0 82.0 77.0 72.0 75.0 78.0 109.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:84.50 stddev:3.37 (4.0%) stddev-sans-first:3.57 12:26:01 INFO - PID 16109 | Values: 84.0 81.0 89.0 87.0 78.0 88.0 83.0 83.0 85.0 82.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#5] imaging-gaussian-blur Cycles:10 Average:114.50 Median:114.50 stddev:3.17 (2.8%) stddev-sans-first:3.10 12:26:01 INFO - PID 16109 | Values: 111.0 114.0 111.0 115.0 113.0 113.0 116.0 122.0 114.0 116.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#6] imaging-darkroom Cycles:10 Average:149.90 Median:149.50 stddev:2.81 (1.9%) stddev-sans-first:2.89 12:26:01 INFO - PID 16109 | Values: 148.0 154.0 148.0 148.0 148.0 151.0 148.0 153.0 154.0 147.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:94.50 stddev:3.27 (3.5%) stddev-sans-first:3.28 12:26:01 INFO - PID 16109 | Values: 99.0 94.0 94.0 104.0 94.0 95.0 94.0 97.0 95.0 94.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:2.41 (4.5%) stddev-sans-first:2.55 12:26:01 INFO - PID 16109 | Values: 54.0 53.0 53.0 54.0 54.0 53.0 54.0 53.0 55.0 61.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#9] json-stringify-tinderbox Cycles:10 Average:40.80 Median:41.50 stddev:3.33 (8.0%) stddev-sans-first:3.50 12:26:01 INFO - PID 16109 | Values: 42.0 38.0 48.0 38.0 41.0 44.0 38.0 39.0 38.0 42.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:77.00 stddev:2.16 (2.8%) stddev-sans-first:1.94 12:26:01 INFO - PID 16109 | Values: 80.0 77.0 77.0 74.0 77.0 79.0 79.0 75.0 74.0 75.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#11] stanford-crypto-ccm Cycles:10 Average:119.30 Median:119.50 stddev:4.27 (3.6%) stddev-sans-first:4.53 12:26:01 INFO - PID 16109 | Values: 119.0 122.0 119.0 119.0 128.0 117.0 120.0 122.0 114.0 113.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.70 Median:177.50 stddev:5.31 (3.0%) stddev-sans-first:5.61 12:26:01 INFO - PID 16109 | Values: 178.0 186.0 182.0 172.0 175.0 182.0 174.0 177.0 170.0 171.0 12:26:01 INFO - PID 16109 | 12:26:01 INFO - PID 16109 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.00 stddev:2.59 (3.9%) stddev-sans-first:1.87 12:26:01 INFO - PID 16109 | Values: 73.0 67.0 65.0 66.0 67.0 67.0 70.0 70.0 65.0 66.0 12:26:01 INFO - PID 16109 | -------- Summary: end -------- 12:26:01 INFO - PID 16109 | 12:26:02 INFO - PID 16109 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:26:02 INFO - TEST-INFO | 16109: exit 0 12:26:02 INFO - TEST-OK | kraken | took 63866ms 12:26:02 INFO - SUITE-END | took 1177s 12:26:03 INFO - Completed test suite (00:19:37) 12:26:03 INFO - javascript benchmark 12:26:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8427.515962280904, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.8748450062008, 3132.3735252949414, 3133.7806353555247, 3155.6371017333, 3185.7292130168935, 6319.052142178664, 6330.4619107375875, 6413.102165696806, 6443.323451037644, 6443.420092191702, 6155.415235552627, 6257.5619706620555, 6297.244330680313, 6318.083877837711, 6322.557420980534, 5772.826815195541, 5781.913271300934, 5878.853028674274, 5886.087656413263, 5893.0, 5847.824565263028, 5875.618084824473, 5906.084556893688, 5908.763649454017, 5915.08316210988, 5554.1113421852515, 5683.687397193153, 5744.827655170351, 5768.250127483442, 5873.383294754053, 5800.477956983849, 5814.97092514539, 5877.265341832271, 5888.234529511136, 5893.764249430018, 5763.538916886639, 5800.216970708942, 5801.30384353876, 5837.854053648651, 5855.0, 6265.310815810257, 6278.340774218733, 6309.3690630936535, 6380.202474690664, 6408.775692850774, 6592.901106483407, 6729.1252137221845, 6745.797626071226, 6750.426213771835, 6848.931510684929, 6850.794476165723, 6867.656617169171, 6878.174619045746, 6883.036374907517, 6884.0362349271145, 6310.274318453391, 6314.084457753646, 6332.683365831736, 6336.52476064297, 6358.904616430757, 6973.895391569131, 6982.441404687613, 7026.578405295596, 7036.753713619999, 7079.82300442493, 6586.7035983380865, 6680.298568650319, 6694.263631000586, 6721.596704197764, 6748.122744043341, 6619.238787539398, 6710.530262881552, 6739.5619284746335, 6832.658367081626, 6838.111045564145, 6664.433523150489, 6684.966575167093, 6776.356246156706, 6825.726970921108, 6833.453323734089, 6777.220619628707, 6777.762778302736, 6804.625745583941, 6860.108185935796, 6881.449484041264, 5186.92219616706, 5259.158534634516, 5268.868278292996, 5271.05121078202, 5403.70279634616, 4582.793774280165, 4590.059037897223, 4601.608863246594, 4626.537346265347, 4698.835540756126, 5859.296884373903, 5863.091220860773, 5886.529077673776, 5900.76396944126, 5906.675132867734, 6756.155480564929, 6842.076319696876, 6878.140232470942, 6897.3447522485285, 6924.3421874921805, 5845.532357411397, 5850.619709718854, 5933.821985340361, 5939.732712027969, 5946.226990491295, 5569.972150139304, 5652.152177173457, 5757.337906140764, 5813.767449302065, 5877.206577112035, 6521.771737989147, 6548.803535893836, 6559.704813283413, 6579.342065793382, 6599.274079851212, 6748.426383757435, 6763.560368576027, 6779.389854913082, 6790.490713196629, 6874.9312506876295, 6467.514936379696, 6487.805365839033, 6529.7388104475285, 6565.474762018932, 6565.638889861103, 6412.230532335963, 6596.967015165085, 6628.27089020217, 6629.5359324846795, 6739.93260067393, 6750.594964302158, 6757.22291936414, 6804.795856124324, 6809.89785153233, 6813.0461735358085, 6525.119108920235, 6719.596824190564, 6732.158480189976, 6737.124173857564, 6745.156855393076, 6557.540972131905, 6647.036179753812, 6659.667016649245, 6685.39831415175, 6707.228668702966, 6499.740010399721, 6571.277159512354, 6599.340065993362, 6630.5027122965, 6665.0668906352175, 6512.837179070561, 6529.477641788741, 6595.4393876521035, 6606.306337834458, 6717.865642687217, 6479.254885688206, 6496.902546461902, 6503.837404064936, 6528.183977002875, 6529.216494020748], "value": 6177.474916902532, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [861.1130535548385, 875.2297977779554, 876.7545087375537, 884.7168905950094, 885.4996926736394, 37412.81293593532, 41177.58822411775, 41243.38134927975, 41263.381049284246, 41273.0, 37699.62300376996, 37708.0, 38110.8094459528, 38190.42714359287, 38198.80900595493, 7066.929330706678, 7150.642467876585, 7186.8562628747395, 7189.388901943341, 7214.531055481403, 6997.755078572251, 7119.074520312352, 7147.678354474036, 7161.391281741058, 7161.498695091345, 7118.216996130447, 7120.679569419389, 7131.714731410737, 7136.500444968854, 7137.357637812595, 7632.6183690815515, 7831.177726338709, 7854.253845884632, 7856.685732570691, 7866.252705992922, 7647.349975252055, 7744.070711514598, 7765.611719414007, 7778.961105194495, 7784.104827944802, 36431.4535281971, 36655.0, 36760.0, 36773.0, 36809.44785828215, 3368.023273250779, 3403.3533628610485, 3412.9658703412897, 3413.0955296846355, 3417.7607567470286, 30910.53634195489, 30927.226819329473, 31223.0, 31238.0, 31343.0, 4954.603631709455, 4971.975140124312, 4983.651144419892, 5002.524760147817, 5008.674436161674, 173615.0, 174979.0, 175662.12168939202, 176091.99999999872, 177814.0, 41400.378994315106, 42726.145477090286, 42774.71675849729, 47815.76092119517, 48477.27284090741, 50489.49510504922, 51438.74280628573, 51666.0, 51832.48167518277, 51953.220701689504, 16101.11443870575, 16198.271077801528, 16267.674646507003, 16300.510984670242, 16343.34626614946, 13181.934090329487, 13246.73506529864, 13251.536196233086, 13380.0, 13386.39761210748, 14188.290585470686, 14214.85785142156, 14361.281935903164, 14366.712665746627, 14426.350814213385, 5577.330720313588, 5732.656040637575, 5741.62679425836, 5883.7058147092475, 5935.376785437467, 989.4508547756064, 1004.1113614451264, 1005.7435353984687, 1011.9898801011896, 1022.9028242316969, 22144.0, 22354.55290894173, 22532.0, 22619.43451413695, 22630.886845565998, 1092.322759888874, 1117.7362102660252, 1118.7315044389288, 1124.943752812356, 1128.3906690387098, 11932.985696215745, 12015.519379224908, 12103.152779305365, 12128.151029427856, 12149.392530373447, 206840.0, 208660.0, 209041.0, 209311.0, 209439.0, 51198.0, 51432.22851657228, 51624.22563661548, 51754.74122629363, 51894.0, 1382.6336020954454, 1433.9067960582738, 1440.2582669924998, 1454.534548944349, 1454.7308747881675, 22171.0, 22235.0, 22350.105995760638, 22420.775792242523, 22744.31767046991, 35969.820150899075, 35996.82001589975, 36063.0, 36110.63889361178, 36218.0, 186235.0, 187422.0, 188664.0, 188930.0, 189240.0, 30485.713571482673, 31159.376812464077, 31273.37453250968, 32432.513512296864, 33014.0, 43882.34176487419, 44305.778471107435, 44564.77717611521, 44838.327425088, 44841.10317793691, 32195.678043219268, 32336.191595209366, 32429.18927026749, 32513.512297316032, 32813.0, 31010.68989310078, 31190.688093118777, 31346.373072538878, 31386.68613313929, 31392.0, 35739.28521429609, 35816.283674326885, 35950.64049359473, 36395.63604363923, 36501.26997460089, 30968.53547196839, 31183.532247016767, 31456.370872582876, 31659.366812663156, 32302.0, 21904.452388689773, 23082.884585576965, 23191.79677930594, 23776.0, 23984.52030959406], "value": 16731.43108723994, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.4742970463208, 554.3098841941783, 562.9099344104944, 564.4533284394205, 588.4262843727366, 21654.89172554139, 22429.214977475793, 22445.663315050268, 22476.98853551588, 22484.213052543164, 23548.764512354915, 23751.762482375172, 23806.88096559519, 24068.879655601697, 24461.755382446216, 6549.738010479575, 6653.068570400148, 6660.70026848793, 6702.631355275482, 6708.094407255007, 6525.902111468308, 6564.474842012628, 6609.801705948829, 6626.237982632011, 6627.138471998657, 6212.8446788830415, 6395.712192951329, 6398.040293955897, 6409.198850143732, 6416.967915160418, 6357.682115894187, 6361.6183029018175, 6371.267304260023, 6413.0, 6413.518986076063, 5863.824085277406, 5950.523958083343, 5957.8808423831715, 5974.820755377345, 6080.422359875804, 26359.0, 26422.339441513923, 26588.20235392922, 26608.20175394741, 26645.0, 2004.6892731626622, 2015.4155294964444, 2016.8588198826087, 2016.9697454538193, 2018.0414303206007, 18386.448406547825, 19068.809311906844, 19157.04214789255, 19247.807521924882, 19319.323823666244, 2360.929172124839, 2429.283361408371, 2440.19473573722, 2440.8535487870786, 2442.8656423896678, 117251.0, 122479.38760306142, 123120.38439807833, 128338.99999999907, 130961.34519327438, 13448.865511344762, 14123.929380353033, 14342.713145737027, 14467.710645787025, 14624.415023399157, 22282.108715651193, 22301.442463938532, 22346.21788237404, 22372.664410033864, 22385.888070559544, 9471.573779179953, 10001.249656344564, 10043.849342259726, 10102.39385636864, 10311.845322320021, 9270.07299270081, 9448.811023779486, 9464.716058518256, 9501.952490237505, 9515.04849515043, 8746.212840844228, 9071.183593476619, 9071.455712657129, 9126.680566180152, 9135.817283654289, 2661.387880787391, 2698.1365962891687, 2777.8194417362806, 2784.1786672931535, 2818.8308701477977, 942.4675058592011, 964.1900803325241, 967.1700067731861, 969.4571040217501, 977.7115750853516, 18488.815111848708, 18792.53018674544, 19082.04589770506, 19124.0, 19318.613627727365, 992.6624947517879, 997.6607953295915, 1003.5684655598017, 1006.8892421833592, 1018.3838777539482, 10185.898141018642, 10402.791944161074, 10421.53103110362, 10472.947635261928, 10492.737681558023, 423823.0, 426249.0, 435284.0, 437861.0, 447930.0, 103111.0, 106406.0, 106444.46777766061, 107433.46283268534, 107938.0, 1030.5723124903081, 1084.978300433987, 1092.0771947704177, 1120.9831852522057, 1128.4470609401335, 19506.804931950497, 19576.314828981485, 19925.800741992396, 20010.89994550018, 20034.499137521678, 52430.95138097293, 55076.72461637666, 55388.16917746156, 55831.44168558263, 55887.44112558822, 65000.34999650132, 67462.0, 68330.31669683439, 68504.0, 69511.65244173747, 13751.174929504261, 13853.376598053314, 14134.222617756122, 14150.929245353707, 14291.857081429052, 22026.559468810854, 22210.222642207445, 22320.0, 22449.10203591838, 22483.0, 17712.46862594124, 17788.733169002215, 17861.0, 18064.909675451538, 18212.0, 15878.523644290228, 16039.67920641604, 16159.192040397698, 16400.343986240416, 16501.91749041247, 18427.0, 18686.53283667919, 18700.0, 18745.0, 18789.34237301688, 13672.52146174879, 13771.655708607364, 13787.931060344634, 13812.654683632989, 13856.86143138596, 16942.40701575488, 17555.473335799947, 17746.5563360917, 17927.551811204823, 17993.640127197643], "value": 11606.982942034598, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1300.1938797945272, 1386.4038463460708, 1389.1040279020033, 1400.7268582626389, 1408.2114016150956, 14223.288835558233, 14797.704045919076, 14828.481003164892, 14860.702785944275, 14915.627109322259, 14624.049436786601, 14663.853361466383, 14669.119852808812, 14674.046186997837, 14808.925955370236, 2752.215618548714, 2768.349437882101, 2800.4679110968955, 2801.8739156737893, 2814.2964258935267, 2647.8543680097587, 2759.9448011039767, 2776.1671498550354, 2785.916422507328, 2789.1771927281397, 2693.595960605915, 2732.098407525512, 2738.6302849115414, 2767.2390092724563, 2791.916242512718, 2745.203890871665, 2802.9159125226274, 2803.2851622836147, 2805.5791631255374, 2834.263091596179, 2689.126034038926, 2697.6223328733995, 2718.6329845470805, 2718.714534973839, 2719.8232114912466, 14504.927475362661, 14863.628409289746, 14904.552863414116, 14987.175705336202, 15025.699486010328, 7933.96033019837, 8128.59357032146, 8235.258826705565, 8237.50574965498, 8261.876071858927, 5203.4796520348045, 5416.241726158341, 5495.2856128703415, 5549.084401073818, 5552.278203833475, 1906.5138389710673, 1922.7404300419544, 1928.399428474377, 1946.931857384999, 1955.3840540229783, 25744.0, 25852.22443326703, 25973.870130649226, 25978.480430391475, 25980.480390392087, 6647.966760166169, 6670.332966703291, 6753.189617245962, 6890.483213758988, 6955.200151982485, 16892.0, 16893.32426702918, 16950.660986780196, 17003.40488082911, 17080.145992700564, 7702.73040443593, 7974.043114826258, 7974.760757177294, 7980.68077276914, 7986.960065199637, 7235.131784185932, 7384.667689953965, 7485.5508669480005, 7526.397888169043, 7575.848483030309, 5172.224166374998, 5228.895422091537, 5304.92042619361, 5327.227552005015, 5335.9466405336225, 1441.9206943617992, 1492.940282388714, 1559.4541910331293, 1578.747400415951, 1591.1805420208602, 725.6661935509725, 861.3195575495289, 871.0636066228702, 884.4914174349775, 889.768660148366, 8112.594370281462, 8130.268275855202, 8265.95867020661, 8283.8343233135, 8598.570071496526, 959.7840485890758, 961.1397798969883, 975.7804493988796, 1009.1926458832904, 1010.3331801011298, 5010.674306170087, 5080.060188865072, 5088.7786931137, 5238.214267859776, 5428.864278393072, 20532.897335513226, 21517.246896358865, 21527.78472215289, 21631.459213519473, 21754.673679894815, 20297.594048118954, 21052.47368815792, 21117.26089586857, 21462.24882129149, 21764.782352176593, 10362.481875906175, 10426.113780328757, 10597.788044239072, 10603.204759643058, 10683.73290667724, 8125.228103330174, 8209.220124088202, 8229.094799571923, 8325.167483251626, 8355.415120941476, 18180.000099994628, 18310.450686479428, 18594.163262652943, 18649.347272845385, 18692.971886545827, 18392.540186495444, 18867.245310187438, 19295.0, 19500.707489387954, 19749.1112899917, 10656.733581660521, 10812.837807433052, 10815.297005694605, 10912.61805836792, 10918.0, 13296.933515332361, 13659.726805464034, 13687.11033782801, 13797.310134493437, 13800.792988105388, 10189.847152292574, 10247.436390998566, 10359.430231337048, 11398.601048963246, 11524.654260372203, 10956.34261944286, 11058.115350771757, 11253.324800511995, 11269.436528173723, 11451.0, 10025.74935626586, 10030.29787914839, 10090.69727908164, 10094.0, 10096.444695541522, 7591.278828511283, 7623.0, 7657.234276572298, 7693.307602315818, 7717.421193410404, 7444.702211911463, 7469.141048779243, 7493.100827900686, 7512.173660897406, 7528.811779705551], "value": 6658.99668988072, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.6630862411948, 506.5441103007292, 524.1351769580194, 525.4535283305363, 530.879137844312, 6182.783602573911, 6202.968985155079, 6209.379062093788, 6258.280297765758, 6304.968475157618, 6148.094090831834, 6182.659953702545, 6201.883660941029, 6202.968985155079, 6302.968485157568, 5103.106956282654, 5161.380634323887, 5170.353705786777, 5178.5339319461245, 5190.558802501791, 5185.637005409623, 5200.115980283361, 5207.843764687065, 5228.163493840986, 5247.239150323201, 4766.6901651392545, 5083.6441449098575, 5087.186050231963, 5159.638825282232, 5203.42762296149, 4898.53463920927, 4912.778924948386, 4940.777665005084, 4945.7774400152075, 4975.527324904128, 4397.934030989538, 4419.315006174048, 4428.269335559628, 4458.464984201916, 4468.709533880287, 5979.1629171915965, 5998.980173370537, 6022.69886505673, 6061.848453788691, 6069.241344831896, 5591.021571225019, 5625.437456254383, 5674.580775359522, 5709.115087161496, 5721.599488035839, 6455.064015717695, 6485.7405703772265, 6485.967570162166, 6589.0, 6619.635920024397, 4561.498235194126, 4818.976154910654, 4833.516648335173, 4857.562819346276, 4879.756012199376, 6509.837254068639, 6536.901946470807, 6577.829316325601, 6790.49071319653, 6847.417969472651, 4875.414950205927, 4897.877553061131, 4923.581495572845, 4923.630727695438, 4945.403663076841, 4799.18413869643, 4939.5554400104165, 4940.481249468826, 4944.826931057468, 4980.452150263468, 5545.77816887328, 5592.944070559323, 5623.184638227434, 5633.380328163898, 5640.520555752725, 6369.426751592379, 6548.008572156424, 6557.606543607399, 6593.439557637558, 6603.966980165164, 1712.53761484401, 1725.9654806904043, 1743.886647367917, 1748.6677531269017, 1749.5713550180287, 1149.545929357899, 1186.7270527778487, 1198.9160758746805, 1205.4033253539553, 1220.560598184653, 5599.608027438122, 5623.722392431072, 5627.521660658808, 5713.485786279255, 5731.312242530923, 6573.7370505180215, 6614.511734859714, 6651.567648102955, 6697.6986035628515, 6730.93269067303, 5457.645253058538, 5520.420355862577, 5637.633553818989, 5694.686792226467, 5700.686462244534, 3427.1774774054047, 3460.86156553735, 3492.9825350873084, 3516.4197907345515, 3540.946885796715, 6259.311475737665, 6403.743850246031, 6416.748733996919, 6421.325760795143, 6426.710798014101, 6698.593295407922, 6723.462123030244, 6753.041617930843, 6819.829504262433, 6851.417629501449, 6282.497400207881, 6363.172787537591, 6393.328700486475, 6418.0, 6454.483641308684, 6057.636541807506, 6500.6424646643, 6517.967410162919, 6589.47284217271, 6622.8344291393105, 6495.960646296759, 6499.902501462388, 6533.477321814338, 6534.869302614016, 6648.667566621552, 6214.751409943552, 6267.749290028348, 6312.3056463788025, 6367.172267605367, 6479.416852483299, 4825.346059667098, 4853.95146048535, 4873.658843881036, 4880.511948805091, 4924.778384972613, 6304.18045654062, 6494.318096599789, 6580.5722628029025, 6591.274959754519, 6606.636634985122, 6253.249610046823, 6312.6528040956355, 6476.643784591893, 6496.285408604956, 6497.9025314619375, 6279.466245369194, 6365.96817015912, 6372.980323148274, 6439.678016099083, 6453.193350831146, 6205.751769929333, 6258.311585725475, 6313.579444624923, 6332.448550105254, 6342.88999425108, 6451.225852897683, 6462.773802917063, 6467.41793238592, 6486.805395838132, 6511.576747511396, 6189.96905015472, 6195.659238741912, 6217.347178546188, 6229.12792209082, 6267.9373206267355], "value": 4992.976164867469, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [894.4275663575316, 903.5437104262348, 914.3279689428272, 920.5627327019666, 925.4123631494003, 16695.916520417395, 17430.825691743077, 17532.73700894486, 17536.47390578283, 17545.12274386282, 15736.449224277152, 16103.275352609131, 16394.09832459217, 16397.508074757778, 16968.32126714933, 900.6037343568826, 912.7079334612927, 914.4330515080626, 915.455304094063, 918.2516249256835, 961.942283462991, 968.8982656821038, 976.0629795396428, 978.1001478639633, 980.7695191629962, 955.4506158958627, 969.9515024248794, 995.9253056020791, 1001.1690297053465, 1013.0426746724348, 950.4819873169174, 951.0869565217399, 953.0659953245788, 953.2088366655694, 996.8903420623726, 706.929307069294, 724.916634587024, 726.4733068525329, 730.4485113739092, 732.3482100930177, 17270.56823579408, 17413.0, 17544.38594649194, 17579.912100439415, 17594.0, 21671.891640541853, 21817.345479635635, 22045.33863984083, 22594.774052259432, 22756.77243227563, 16956.576085597837, 17193.140342982802, 17528.12359382026, 17544.385946491813, 17882.463526094238, 3752.868649597251, 3769.717271204643, 3780.489633899416, 3780.6030366811365, 3780.7542509736777, 137264.31367843196, 138190.0, 140834.0, 141021.2948935259, 143663.28168359195, 16466.75299870503, 16733.414330498374, 16823.495295141165, 17200.0, 17337.393191238247, 30268.24329391739, 31552.0, 31670.841645791625, 32008.0, 32310.676893231237, 8466.830663386821, 8476.830463390697, 8530.317574594017, 8559.358048146414, 8577.95711021441, 4040.616141466556, 4123.876283711494, 4133.648639865645, 4142.00591857958, 4166.187593419315, 5432.945670543323, 5488.204210389471, 5521.751521181556, 5522.0336441122645, 5553.583481238923, 1004.3371374892536, 1020.78563501664, 1021.2187676427538, 1036.0520124086377, 1042.2391654092555, 665.1353240786957, 672.9865402692017, 694.4999600287784, 695.6104581434413, 701.3512500936715, 11896.0, 12093.274403535816, 12219.389030548436, 12274.75450491003, 12377.62867113988, 659.2451642868984, 660.1714847865898, 670.8423520472685, 683.45323741007, 688.215434404779, 5368.302120724281, 5465.535429488538, 5487.725613719298, 5496.395396506381, 5513.3108361454815, 127734.0, 128431.0, 130063.0, 130592.3470382642, 131123.0, 84068.0, 107408.0, 109783.45108274567, 112930.43534782274, 114978.0, 94358.0, 95086.0, 97275.51362243143, 98996.50501747445, 100404.49797750964, 12717.93641031826, 13117.409716562586, 13304.201747895157, 13355.79966300487, 13625.0, 42488.0, 42968.78515607402, 43018.0, 43044.13911721685, 43389.566104339814, 54907.45092549024, 56576.0, 56948.0, 57488.0, 57760.42239577718, 15558.455454059054, 15677.608059798597, 15752.921235394208, 15791.526254212393, 15860.0, 20764.480887977315, 20811.271605493734, 21019.474513136684, 21101.57796844085, 21108.0, 17253.741193882353, 18212.544686382946, 18258.72611910849, 18361.3573524926, 18474.538136546154, 17209.311627534757, 17234.569135771708, 17374.652506950042, 17410.129493525525, 17522.82477175212, 15594.9220253898, 15801.920990394974, 15821.208939552746, 15899.682006360039, 15982.60043498922, 11902.583409580624, 11975.221610595283, 12051.51793928233, 12213.816792748294, 12218.450169742206, 13196.274204918822, 13306.800397993844, 13316.40076196554, 13449.125806822525, 13478.326083695973], "value": 8981.332122935919, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1467.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 119.0, 110.0, 112.0, 108.0, 109.0, 109.0, 118.0, 109.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 124.0, 123.0, 117.0, 146.0, 120.0, 121.0, 117.0, 129.0, 128.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 167.0, 166.0, 162.0, 182.0, 172.0, 164.0, 165.0, 178.0, 163.0], "value": 169.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 81.0, 82.0, 82.0, 77.0, 72.0, 75.0, 78.0, 109.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 89.0, 87.0, 78.0, 88.0, 83.0, 83.0, 85.0, 82.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 111.0, 115.0, 113.0, 113.0, 116.0, 122.0, 114.0, 116.0], "value": 114.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 154.0, 148.0, 148.0, 148.0, 151.0, 148.0, 153.0, 154.0, 147.0], "value": 149.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 94.0, 104.0, 94.0, 95.0, 94.0, 97.0, 95.0, 94.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 54.0, 54.0, 53.0, 54.0, 53.0, 55.0, 61.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [42.0, 38.0, 48.0, 38.0, 41.0, 44.0, 38.0, 39.0, 38.0, 42.0], "value": 40.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 77.0, 77.0, 74.0, 77.0, 79.0, 79.0, 75.0, 74.0, 75.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 122.0, 119.0, 119.0, 128.0, 117.0, 120.0, 122.0, 114.0, 113.0], "value": 119.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 186.0, 182.0, 172.0, 175.0, 182.0, 174.0, 177.0, 170.0, 171.0], "value": 176.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 67.0, 65.0, 66.0, 67.0, 67.0, 70.0, 70.0, 65.0, 66.0], "value": 67.6, "unit": "score"}]}]} 12:26:03 INFO - Return code: 0 12:26:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:26:03 INFO - # TBPL SUCCESS # 12:26:03 INFO - Running post-action listener: _package_coverage_data 12:26:03 INFO - Running post-action listener: _resource_record_post_action 12:26:03 INFO - [mozharness: 2017-09-15 19:26:03.250944Z] Finished run-tests step (success) 12:26:03 INFO - Running post-run listener: _resource_record_post_run 12:26:03 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 184320; Write bytes: 337661952; Read time: 76; Write time: 274420 12:26:03 INFO - TinderboxPrint: CPU usage
13.3% 12:26:03 INFO - TinderboxPrint: I/O read bytes / time
184,320 / 76 12:26:03 INFO - TinderboxPrint: I/O write bytes / time
337,661,952 / 274,420 12:26:03 INFO - TinderboxPrint: CPU idle
8,258.1 (86.7%) 12:26:03 INFO - TinderboxPrint: CPU user
1,202.3 (12.6%) 12:26:03 INFO - TinderboxPrint: Swap in / out
0 / 0 12:26:03 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13213696; Read time: 0; Write time: 2624 12:26:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:26:03 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 180224; Write bytes: 324448256; Read time: 60; Write time: 271796 12:26:03 INFO - Running post-run listener: _upload_blobber_files 12:26:03 INFO - Blob upload gear active. 12:26:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:26:03 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 12:26:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:26:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:26:04 INFO - (blobuploader) - INFO - Open directory for files ... 12:26:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:26:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:05 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:26:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:05 INFO - (blobuploader) - INFO - Done attempting. 12:26:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:26:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:26:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:06 INFO - (blobuploader) - INFO - Done attempting. 12:26:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:26:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:26:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:12 INFO - (blobuploader) - INFO - Done attempting. 12:26:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:26:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:26:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:26:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:26:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:26:13 INFO - (blobuploader) - INFO - Done attempting. 12:26:13 INFO - (blobuploader) - INFO - Iteration through files over. 12:26:13 INFO - Return code: 0 12:26:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:26:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:26:13 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33598e278e9e769630f0fb1aabb528048c8751768ca019a24a337fcfe9fad944d1d545dd5e4376c1bbde77cc67db68302719bce91a6d54e3d9b42961daefc895", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77694749e37f44c091fefeb809e86f80b10188b92f8d5dad6e5c04d1fb5230497d59c0080aa28f32695755015a81e07daaafec29765331932f7ff4cbabcefd96", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0546d830b8b5a07604f0f5a5bfc11a49899c6e42501b5f39741f6caa457a88ba5314b0dc27d5187782bf62fa239b0a01fddd8cd0f479057f38d59498f44b94e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8f1b6c8cbded4e790371bd066e19161a6bda8e683fe1c00ab48ee0bb440c2d11fe8946f032a56756ab92f70fefc2392457bd1799b5bee523e0d79749c91991"} 12:26:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:26:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:26:13 INFO - Contents: 12:26:13 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33598e278e9e769630f0fb1aabb528048c8751768ca019a24a337fcfe9fad944d1d545dd5e4376c1bbde77cc67db68302719bce91a6d54e3d9b42961daefc895", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77694749e37f44c091fefeb809e86f80b10188b92f8d5dad6e5c04d1fb5230497d59c0080aa28f32695755015a81e07daaafec29765331932f7ff4cbabcefd96", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0546d830b8b5a07604f0f5a5bfc11a49899c6e42501b5f39741f6caa457a88ba5314b0dc27d5187782bf62fa239b0a01fddd8cd0f479057f38d59498f44b94e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8f1b6c8cbded4e790371bd066e19161a6bda8e683fe1c00ab48ee0bb440c2d11fe8946f032a56756ab92f70fefc2392457bd1799b5bee523e0d79749c91991"} 12:26:13 INFO - Running post-run listener: copy_logs_to_upload_dir 12:26:13 INFO - Copying logs to upload dir... 12:26:13 INFO - mkdir: /builds/slave/test/build/upload/logs 12:26:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.120415 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2017-09-15 12:26:13.636593) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 12:26:13.641166) ========= 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=826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33598e278e9e769630f0fb1aabb528048c8751768ca019a24a337fcfe9fad944d1d545dd5e4376c1bbde77cc67db68302719bce91a6d54e3d9b42961daefc895", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77694749e37f44c091fefeb809e86f80b10188b92f8d5dad6e5c04d1fb5230497d59c0080aa28f32695755015a81e07daaafec29765331932f7ff4cbabcefd96", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0546d830b8b5a07604f0f5a5bfc11a49899c6e42501b5f39741f6caa457a88ba5314b0dc27d5187782bf62fa239b0a01fddd8cd0f479057f38d59498f44b94e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8f1b6c8cbded4e790371bd066e19161a6bda8e683fe1c00ab48ee0bb440c2d11fe8946f032a56756ab92f70fefc2392457bd1799b5bee523e0d79749c91991"} build_url:https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011395 build_url: 'https://queue.taskcluster.net/v1/task/fA4-U6SkTAuYHvLWxXHwTQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/33598e278e9e769630f0fb1aabb528048c8751768ca019a24a337fcfe9fad944d1d545dd5e4376c1bbde77cc67db68302719bce91a6d54e3d9b42961daefc895", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77694749e37f44c091fefeb809e86f80b10188b92f8d5dad6e5c04d1fb5230497d59c0080aa28f32695755015a81e07daaafec29765331932f7ff4cbabcefd96", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0546d830b8b5a07604f0f5a5bfc11a49899c6e42501b5f39741f6caa457a88ba5314b0dc27d5187782bf62fa239b0a01fddd8cd0f479057f38d59498f44b94e", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d8f1b6c8cbded4e790371bd066e19161a6bda8e683fe1c00ab48ee0bb440c2d11fe8946f032a56756ab92f70fefc2392457bd1799b5bee523e0d79749c91991"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 12:26:13.681300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:26:13.681625) ========= 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=826874ccc97bd7bf541b4875000001d5-1505501765.485277-1347936370 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004565 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 12:26:13.733596) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 12:26:13.733916) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 12:26:13.734202) ========= ========= Total master_lag: 0.24 =========