builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-066 starttime: 1502836760.26 results: success (0) revision: d25db0546c92afa72d61685c9310104ef28235bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:20.256051) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:20.256737) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:20.257097) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:20.286896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:20.287345) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-15 15:39:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 23.3M=0s 2017-08-15 15:39:20 (23.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119180 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:20.439744) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:20.440105) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018375 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:20.506926) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:20.507303) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d25db0546c92afa72d61685c9310104ef28235bd --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 d25db0546c92afa72d61685c9310104ef28235bd --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=82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-15 15:39:20,763 truncating revision to first 12 chars 2017-08-15 15:39:20,763 Setting DEBUG logging. 2017-08-15 15:39:20,763 attempt 1/10 2017-08-15 15:39:20,763 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d25db0546c92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-15 15:39:21,254 unpacking tar archive at: mozilla-central-d25db0546c92/testing/mozharness/ program finished with exit code 0 elapsedTime=0.778287 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:21.303607) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:21.304018) ========= script_repo_revision: d25db0546c92afa72d61685c9310104ef28235bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:21.304499) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:21.304818) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-15 15:39:21.317185) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-15 15:39:21.317918) ========= /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=82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:39:21 INFO - MultiFileLogger online at 20170815 15:39:21 in /builds/slave/test 15:39:21 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 15:39:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:39:21 INFO - {'append_to_log': False, 15:39:21 INFO - 'base_work_dir': '/builds/slave/test', 15:39:21 INFO - 'blob_upload_branch': 'Firefox', 15:39:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:39:21 INFO - 'branch': 'Firefox', 15:39:21 INFO - 'buildbot_json_path': 'buildprops.json', 15:39:21 INFO - 'code_coverage': False, 15:39:21 INFO - 'config_files': ('talos/linux_config.py',), 15:39:21 INFO - 'default_actions': ('clobber', 15:39:21 INFO - 'read-buildbot-config', 15:39:21 INFO - 'download-and-extract', 15:39:21 INFO - 'populate-webroot', 15:39:21 INFO - 'create-virtualenv', 15:39:21 INFO - 'install', 15:39:21 INFO - 'setup-mitmproxy', 15:39:21 INFO - 'run-tests'), 15:39:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:39:21 INFO - 'disable_ccov_upload': False, 15:39:21 INFO - 'download_minidump_stackwalk': True, 15:39:21 INFO - 'download_symbols': 'ondemand', 15:39:21 INFO - 'enable_stylo': False, 15:39:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:39:21 INFO - 'tooltool.py': '/tools/tooltool.py', 15:39:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:39:21 INFO - '/tools/misc-python/virtualenv.py')}, 15:39:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:39:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:39:21 INFO - 'gecko_profile': False, 15:39:21 INFO - 'gecko_profile_interval': 0, 15:39:21 INFO - 'installer_path': 'installer.exe', 15:39:21 INFO - 'log_level': 'info', 15:39:21 INFO - 'log_name': 'talos', 15:39:21 INFO - 'log_to_console': True, 15:39:21 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:39:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:39:21 INFO - 'opt_config_files': (), 15:39:21 INFO - 'pip_index': False, 15:39:21 INFO - 'suite': 'dromaeojs-e10s', 15:39:21 INFO - 'system_bits': '32', 15:39:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:39:21 INFO - 'title': 'talos-linux64-ix-066', 15:39:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:39:21 INFO - 'use_talos_json': True, 15:39:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:39:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:39:21 INFO - 'work_dir': 'build'} 15:39:21 INFO - [mozharness: 2017-08-15 22:39:21.679856Z] Running clobber step. 15:39:21 INFO - Running pre-action listener: _resource_record_pre_action 15:39:21 INFO - Running main action method: clobber 15:39:21 INFO - rmtree: /builds/slave/test/build 15:39:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:39:22 INFO - Running post-action listener: _resource_record_post_action 15:39:22 INFO - [mozharness: 2017-08-15 22:39:22.717053Z] Finished clobber step (success) 15:39:22 INFO - [mozharness: 2017-08-15 22:39:22.717156Z] Running read-buildbot-config step. 15:39:22 INFO - Running pre-action listener: _resource_record_pre_action 15:39:22 INFO - Running main action method: read_buildbot_config 15:39:22 INFO - Using buildbot properties: 15:39:22 INFO - { 15:39:22 INFO - "project": "", 15:39:22 INFO - "product": "firefox", 15:39:22 INFO - "who": "asasaki@mozilla.com", 15:39:22 INFO - "installer_path": "public/build/target.tar.bz2", 15:39:22 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:39:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:39:22 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 15:39:22 INFO - "stage_platform": "linux64-pgo", 15:39:22 INFO - "basedir": "/builds/slave/test", 15:39:22 INFO - "buildnumber": 119, 15:39:22 INFO - "platform": "ubuntu64_hw", 15:39:22 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:39:22 INFO - "slavebuilddir": "test", 15:39:22 INFO - "taskId": "QzDLjGykS2eVHFQYCWSthA", 15:39:22 INFO - "branch": "mozilla-central", 15:39:22 INFO - "script_repo_revision": "production", 15:39:22 INFO - "revision": "d25db0546c92afa72d61685c9310104ef28235bd", 15:39:22 INFO - "slavename": "talos-linux64-ix-066", 15:39:22 INFO - "repo_path": "mozilla-central" 15:39:22 INFO - } 15:39:22 INFO - Finding installer, test and symbols from parent task. 15:39:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QzDLjGykS2eVHFQYCWSthA'}, attempt #1 15:39:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QzDLjGykS2eVHFQYCWSthA'}, attempt #1 15:39:23 INFO - Task dependencies: PuGxb2cmSQSD5PpSReO_vw 15:39:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw'}, attempt #1 15:39:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.tar.bz2 15:39:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.test_packages.json 15:39:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.crashreporter-symbols.zip 15:39:24 INFO - Running post-action listener: _resource_record_post_action 15:39:24 INFO - [mozharness: 2017-08-15 22:39:24.093265Z] Finished read-buildbot-config step (success) 15:39:24 INFO - [mozharness: 2017-08-15 22:39:24.093522Z] Running download-and-extract step. 15:39:24 INFO - Running pre-action listener: _resource_record_pre_action 15:39:24 INFO - Running main action method: download_and_extract 15:39:24 INFO - mkdir: /builds/slave/test/build/tests 15:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:24 INFO - trying https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.test_packages.json 15:39:24 INFO - Downloading https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:39:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:39:24 INFO - Downloaded 989 bytes. 15:39:24 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:39:24 INFO - Using the following test package requirements: 15:39:24 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:39:24 INFO - u'common': [u'target.common.tests.zip'], 15:39:24 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:39:24 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:39:24 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:39:24 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:39:24 INFO - u'mozbase': [u'target.common.tests.zip'], 15:39:24 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:39:24 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:39:24 INFO - u'web-platform': [u'target.common.tests.zip', 15:39:24 INFO - u'target.web-platform.tests.tar.gz'], 15:39:24 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:39:24 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 15:39:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.common.tests.zip 15:39:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:39:24 INFO - Fetch https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.common.tests.zip into memory 15:39:27 INFO - Content-Length response header: 54614366 15:39:27 INFO - Bytes received: 54614366 15:39:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 15:39:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.common.tests.zip 15:39:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:39:37 INFO - Fetch https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.common.tests.zip into memory 15:39:39 INFO - Content-Length response header: 54614366 15:39:39 INFO - Bytes received: 54614366 15:39:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.talos.tests.zip 15:39:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:39:51 INFO - Fetch https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.talos.tests.zip into memory 15:39:52 INFO - Content-Length response header: 14496050 15:39:52 INFO - Bytes received: 14496050 15:39:52 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')]} 15:39:52 INFO - trying https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.tar.bz2 15:39:52 INFO - Downloading https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:39:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:39:55 INFO - Downloaded 62643757 bytes. 15:39:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.tar.bz2 15:39:55 INFO - mkdir: /builds/slave/test/properties 15:39:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:39:55 INFO - Writing to file /builds/slave/test/properties/build_url 15:39:55 INFO - Contents: 15:39:55 INFO - build_url:https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.tar.bz2 15:39:55 INFO - Running post-action listener: _resource_record_post_action 15:39:55 INFO - Running post-action listener: set_extra_try_arguments 15:39:55 INFO - [mozharness: 2017-08-15 22:39:55.042834Z] Finished download-and-extract step (success) 15:39:55 INFO - [mozharness: 2017-08-15 22:39:55.043061Z] Running populate-webroot step. 15:39:55 INFO - Running pre-action listener: _resource_record_pre_action 15:39:55 INFO - Running main action method: populate_webroot 15:39:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:39:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:39:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:39:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:39:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:39:55 INFO - 'tests': ['damp', 'tps']}, 15:39:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:39:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:39:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:39:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:39:55 INFO - 'other-e10s': {'tests': ['a11yr', 15:39:55 INFO - 'ts_paint', 15:39:55 INFO - 'tpaint', 15:39:55 INFO - 'sessionrestore', 15:39:55 INFO - 'sessionrestore_many_windows', 15:39:55 INFO - 'sessionrestore_no_auto_restore', 15:39:55 INFO - 'tabpaint']}, 15:39:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 15:39:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 15:39:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:39:55 INFO - 'tsvgr_opacity', 15:39:55 INFO - 'tart', 15:39:55 INFO - 'tscrollx', 15:39:55 INFO - 'tsvg_static']}, 15:39:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:39:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:39:55 INFO - 'talos_options': ['--mitmproxy', 15:39:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:39:55 INFO - '--firstNonBlankPaint'], 15:39:55 INFO - 'tests': ['tp6_google', 15:39:55 INFO - 'tp6_youtube', 15:39:55 INFO - 'tp6_amazon', 15:39:55 INFO - 'tp6_facebook']}, 15:39:55 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:39:55 INFO - 'talos_options': ['--stylo', 15:39:55 INFO - '--mitmproxy', 15:39:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:39:55 INFO - '--firstNonBlankPaint'], 15:39:55 INFO - 'tests': ['tp6_google', 15:39:55 INFO - 'tp6_youtube', 15:39:55 INFO - 'tp6_amazon', 15:39:55 INFO - 'tp6_facebook']}, 15:39:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:39:55 INFO - 'talos_options': ['--stylo', 15:39:55 INFO - '--stylo-threads=1', 15:39:55 INFO - '--mitmproxy', 15:39:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:39:55 INFO - '--firstNonBlankPaint'], 15:39:55 INFO - 'tests': ['tp6_google', 15:39:55 INFO - 'tp6_youtube', 15:39:55 INFO - 'tp6_amazon', 15:39:55 INFO - 'tp6_facebook']}, 15:39:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:39:55 INFO - 'talos_options': ['--xperf_path', 15:39:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:39:55 INFO - 'tests': ['tp5n']}}} 15:39:55 INFO - Running post-action listener: _resource_record_post_action 15:39:55 INFO - [mozharness: 2017-08-15 22:39:55.055464Z] Finished populate-webroot step (success) 15:39:55 INFO - [mozharness: 2017-08-15 22:39:55.055554Z] Running create-virtualenv step. 15:39:55 INFO - Running pre-action listener: _resource_record_pre_action 15:39:55 INFO - Running main action method: create_virtualenv 15:39:55 INFO - Creating virtualenv /builds/slave/test/build/venv 15:39:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:39:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:39:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:39:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:39:55 INFO - Using real prefix '/usr' 15:39:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:39:55 INFO - Installing distribute.............................................................................................................................................................................................done. 15:39:57 INFO - Installing pip.................done. 15:39:57 INFO - Return code: 0 15:39:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:39:57 INFO - Reading from file tmpfile_stdout 15:39:57 INFO - Output received: 15:39:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:39:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:39:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:39:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:39:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:39:57 INFO - Using env: {'DISPLAY': ':0', 15:39:57 INFO - 'HOME': '/home/cltbld', 15:39:57 INFO - 'LANG': 'en_US.UTF-8', 15:39:57 INFO - 'LANGUAGE': 'en_US:en', 15:39:57 INFO - 'LOGNAME': 'cltbld', 15:39:57 INFO - 'MAIL': '/var/mail/cltbld', 15:39:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:57 INFO - 'MOZ_NO_REMOTE': '1', 15:39:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:57 INFO - 'NO_EM_RESTART': '1', 15:39:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:57 INFO - 'PWD': '/builds/slave/test', 15:39:57 INFO - 'SHELL': '/bin/bash', 15:39:57 INFO - 'SHLVL': '1', 15:39:57 INFO - 'TERM': 'linux', 15:39:57 INFO - 'TMOUT': '86400', 15:39:57 INFO - 'USER': 'cltbld', 15:39:57 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:39:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:57 INFO - '_': '/tools/buildbot/bin/python'} 15:39:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:39:58 INFO - Cleaning up... 15:39:58 INFO - Return code: 0 15:39:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:39: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')]} 15:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39: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')]} 15:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:39:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:39:58 INFO - Using env: {'DISPLAY': ':0', 15:39:58 INFO - 'HOME': '/home/cltbld', 15:39:58 INFO - 'LANG': 'en_US.UTF-8', 15:39:58 INFO - 'LANGUAGE': 'en_US:en', 15:39:58 INFO - 'LOGNAME': 'cltbld', 15:39:58 INFO - 'MAIL': '/var/mail/cltbld', 15:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:58 INFO - 'MOZ_NO_REMOTE': '1', 15:39:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:58 INFO - 'NO_EM_RESTART': '1', 15:39:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:58 INFO - 'PWD': '/builds/slave/test', 15:39:58 INFO - 'SHELL': '/bin/bash', 15:39:58 INFO - 'SHLVL': '1', 15:39:58 INFO - 'TERM': 'linux', 15:39:58 INFO - 'TMOUT': '86400', 15:39:58 INFO - 'USER': 'cltbld', 15:39:58 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:58 INFO - '_': '/tools/buildbot/bin/python'} 15:39:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:58 INFO - Downloading/unpacking psutil>=3.1.1 15:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:39:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:59 INFO - Installing collected packages: psutil 15:39:59 INFO - Running setup.py install for psutil 15:39:59 INFO - building 'psutil._psutil_linux' extension 15:39:59 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 15:39:59 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 15:39:59 INFO - building 'psutil._psutil_posix' extension 15:39:59 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 15:39:59 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 15:39:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:39:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:39:59 INFO - Successfully installed psutil 15:39:59 INFO - Cleaning up... 15:40:00 INFO - Return code: 0 15:40:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:40:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:40:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:40:00 INFO - Using env: {'DISPLAY': ':0', 15:40:00 INFO - 'HOME': '/home/cltbld', 15:40:00 INFO - 'LANG': 'en_US.UTF-8', 15:40:00 INFO - 'LANGUAGE': 'en_US:en', 15:40:00 INFO - 'LOGNAME': 'cltbld', 15:40:00 INFO - 'MAIL': '/var/mail/cltbld', 15:40:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:00 INFO - 'MOZ_NO_REMOTE': '1', 15:40:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:00 INFO - 'NO_EM_RESTART': '1', 15:40:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:00 INFO - 'PWD': '/builds/slave/test', 15:40:00 INFO - 'SHELL': '/bin/bash', 15:40:00 INFO - 'SHLVL': '1', 15:40:00 INFO - 'TERM': 'linux', 15:40:00 INFO - 'TMOUT': '86400', 15:40:00 INFO - 'USER': 'cltbld', 15:40:00 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:40:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:00 INFO - '_': '/tools/buildbot/bin/python'} 15:40:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:40:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:40:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:40:01 INFO - Installing collected packages: mozsystemmonitor 15:40:01 INFO - Running setup.py install for mozsystemmonitor 15:40:01 INFO - Successfully installed mozsystemmonitor 15:40:01 INFO - Cleaning up... 15:40:01 INFO - Return code: 0 15:40:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40: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')]} 15:40:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:01 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 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:40:01 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 15:40:01 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 15:40:01 INFO - Using env: {'DISPLAY': ':0', 15:40:01 INFO - 'HOME': '/home/cltbld', 15:40:01 INFO - 'LANG': 'en_US.UTF-8', 15:40:01 INFO - 'LANGUAGE': 'en_US:en', 15:40:01 INFO - 'LOGNAME': 'cltbld', 15:40:01 INFO - 'MAIL': '/var/mail/cltbld', 15:40:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:01 INFO - 'MOZ_NO_REMOTE': '1', 15:40:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:01 INFO - 'NO_EM_RESTART': '1', 15:40:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:01 INFO - 'PWD': '/builds/slave/test', 15:40:01 INFO - 'SHELL': '/bin/bash', 15:40:01 INFO - 'SHLVL': '1', 15:40:01 INFO - 'TERM': 'linux', 15:40:01 INFO - 'TMOUT': '86400', 15:40:01 INFO - 'USER': 'cltbld', 15:40:01 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:40:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:01 INFO - '_': '/tools/buildbot/bin/python'} 15:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:01 INFO - Downloading/unpacking jsonschema==2.5.1 15:40:01 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 15:40:01 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 15:40:01 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 15:40:01 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 15:40:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:40:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:02 INFO - Downloading functools32-3.2.3-2.tar.gz 15:40:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:40:03 INFO - warning: no files found matching '*.txt' 15:40:03 INFO - no previously-included directories found matching 'build' 15:40:03 INFO - no previously-included directories found matching 'dist' 15:40:03 INFO - no previously-included directories found matching '.git*' 15:40:03 INFO - Installing collected packages: jsonschema, functools32 15:40:03 INFO - Running setup.py install for functools32 15:40:03 INFO - warning: no files found matching '*.txt' 15:40:03 INFO - no previously-included directories found matching 'build' 15:40:03 INFO - no previously-included directories found matching 'dist' 15:40:03 INFO - no previously-included directories found matching '.git*' 15:40:03 INFO - Successfully installed jsonschema functools32 15:40:03 INFO - Cleaning up... 15:40:03 INFO - Return code: 0 15:40:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:03 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 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:40:03 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 15:40:03 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 15:40:03 INFO - Using env: {'DISPLAY': ':0', 15:40:03 INFO - 'HOME': '/home/cltbld', 15:40:03 INFO - 'LANG': 'en_US.UTF-8', 15:40:03 INFO - 'LANGUAGE': 'en_US:en', 15:40:03 INFO - 'LOGNAME': 'cltbld', 15:40:03 INFO - 'MAIL': '/var/mail/cltbld', 15:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:03 INFO - 'MOZ_NO_REMOTE': '1', 15:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:03 INFO - 'NO_EM_RESTART': '1', 15:40:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:03 INFO - 'PWD': '/builds/slave/test', 15:40:03 INFO - 'SHELL': '/bin/bash', 15:40:03 INFO - 'SHLVL': '1', 15:40:03 INFO - 'TERM': 'linux', 15:40:03 INFO - 'TMOUT': '86400', 15:40:03 INFO - 'USER': 'cltbld', 15:40:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:03 INFO - '_': '/tools/buildbot/bin/python'} 15:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:40:03 INFO - Cleaning up... 15:40:03 INFO - Return code: 0 15:40:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:03 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 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:40:03 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 15:40:03 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 15:40:03 INFO - Using env: {'DISPLAY': ':0', 15:40:03 INFO - 'HOME': '/home/cltbld', 15:40:03 INFO - 'LANG': 'en_US.UTF-8', 15:40:03 INFO - 'LANGUAGE': 'en_US:en', 15:40:03 INFO - 'LOGNAME': 'cltbld', 15:40:03 INFO - 'MAIL': '/var/mail/cltbld', 15:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:03 INFO - 'MOZ_NO_REMOTE': '1', 15:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:03 INFO - 'NO_EM_RESTART': '1', 15:40:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:03 INFO - 'PWD': '/builds/slave/test', 15:40:03 INFO - 'SHELL': '/bin/bash', 15:40:03 INFO - 'SHLVL': '1', 15:40:03 INFO - 'TERM': 'linux', 15:40:03 INFO - 'TMOUT': '86400', 15:40:03 INFO - 'USER': 'cltbld', 15:40:03 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:03 INFO - '_': '/tools/buildbot/bin/python'} 15:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:03 INFO - Downloading/unpacking blobuploader==1.2.4 15:40: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 15:40: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 15:40: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 15:40: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 15:40:04 INFO - Downloading blobuploader-1.2.4.tar.gz 15:40:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:40:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:40: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 15:40: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 15:40: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 15:40: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 15:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:40:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:40:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:40:05 INFO - Downloading docopt-0.6.1.tar.gz 15:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:40:05 INFO - Installing collected packages: blobuploader, requests, docopt 15:40:05 INFO - Running setup.py install for blobuploader 15:40:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:40:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:40:05 INFO - Running setup.py install for requests 15:40:06 INFO - Running setup.py install for docopt 15:40:06 INFO - Successfully installed blobuploader requests docopt 15:40:06 INFO - Cleaning up... 15:40:06 INFO - Return code: 0 15:40:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:06 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 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:40:06 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 15:40:06 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 15:40:06 INFO - Using env: {'DISPLAY': ':0', 15:40:06 INFO - 'HOME': '/home/cltbld', 15:40:06 INFO - 'LANG': 'en_US.UTF-8', 15:40:06 INFO - 'LANGUAGE': 'en_US:en', 15:40:06 INFO - 'LOGNAME': 'cltbld', 15:40:06 INFO - 'MAIL': '/var/mail/cltbld', 15:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:06 INFO - 'MOZ_NO_REMOTE': '1', 15:40:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:06 INFO - 'NO_EM_RESTART': '1', 15:40:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:06 INFO - 'PWD': '/builds/slave/test', 15:40:06 INFO - 'SHELL': '/bin/bash', 15:40:06 INFO - 'SHLVL': '1', 15:40:06 INFO - 'TERM': 'linux', 15:40:06 INFO - 'TMOUT': '86400', 15:40:06 INFO - 'USER': 'cltbld', 15:40:06 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:06 INFO - '_': '/tools/buildbot/bin/python'} 15:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:06 INFO - Running setup.py (path:/tmp/pip-i1Ilub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:06 INFO - Running setup.py (path:/tmp/pip-AprJQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:06 INFO - Running setup.py (path:/tmp/pip-yGnseq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:06 INFO - Running setup.py (path:/tmp/pip-QKQXnc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:06 INFO - Running setup.py (path:/tmp/pip-ibXVYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:06 INFO - Running setup.py (path:/tmp/pip-7N0cf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:06 INFO - Running setup.py (path:/tmp/pip-3e79OF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:06 INFO - Running setup.py (path:/tmp/pip-fZAOQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:07 INFO - Running setup.py (path:/tmp/pip-cEjX1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:07 INFO - Running setup.py (path:/tmp/pip-Qc3qWa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:07 INFO - Running setup.py (path:/tmp/pip-On_a9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:07 INFO - Running setup.py (path:/tmp/pip-rc4hb_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:07 INFO - Running setup.py (path:/tmp/pip-kYjL4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:07 INFO - Running setup.py (path:/tmp/pip-zBqJtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:07 INFO - Running setup.py (path:/tmp/pip-tuJ0eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:07 INFO - Running setup.py (path:/tmp/pip-GVRYVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:07 INFO - Running setup.py (path:/tmp/pip-W1A5SF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:40:07 INFO - Running setup.py install for manifestparser 15:40:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:40:07 INFO - Running setup.py install for mozcrash 15:40:07 INFO - Running setup.py install for mozdebug 15:40:07 INFO - Running setup.py install for mozdevice 15:40:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:40:08 INFO - Running setup.py install for mozfile 15:40:08 INFO - Running setup.py install for mozhttpd 15:40:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:40:08 INFO - Running setup.py install for mozinfo 15:40:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:40:08 INFO - Running setup.py install for mozInstall 15:40:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:40:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:40:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:40:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:40:08 INFO - Running setup.py install for mozleak 15:40:08 INFO - Running setup.py install for mozlog 15:40:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:40:08 INFO - Running setup.py install for moznetwork 15:40:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:40:08 INFO - Running setup.py install for mozprocess 15:40:08 INFO - Running setup.py install for mozprofile 15:40:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:40:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:40:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:40:09 INFO - Running setup.py install for mozrunner 15:40:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:40:09 INFO - Running setup.py install for mozscreenshot 15:40:09 INFO - Running setup.py install for moztest 15:40:09 INFO - Running setup.py install for mozversion 15:40:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:40:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:40:09 INFO - Cleaning up... 15:40:09 INFO - Return code: 0 15:40:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:09 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 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:40:09 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 15:40:09 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 15:40:09 INFO - Using env: {'DISPLAY': ':0', 15:40:09 INFO - 'HOME': '/home/cltbld', 15:40:09 INFO - 'LANG': 'en_US.UTF-8', 15:40:09 INFO - 'LANGUAGE': 'en_US:en', 15:40:09 INFO - 'LOGNAME': 'cltbld', 15:40:09 INFO - 'MAIL': '/var/mail/cltbld', 15:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:09 INFO - 'MOZ_NO_REMOTE': '1', 15:40:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:09 INFO - 'NO_EM_RESTART': '1', 15:40:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:09 INFO - 'PWD': '/builds/slave/test', 15:40:09 INFO - 'SHELL': '/bin/bash', 15:40:09 INFO - 'SHLVL': '1', 15:40:09 INFO - 'TERM': 'linux', 15:40:09 INFO - 'TMOUT': '86400', 15:40:09 INFO - 'USER': 'cltbld', 15:40:09 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:09 INFO - '_': '/tools/buildbot/bin/python'} 15:40:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:40:09 INFO - Running setup.py (path:/tmp/pip-MU1VYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:40:09 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)) 15:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:40:09 INFO - Running setup.py (path:/tmp/pip-DCp0eF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:40:09 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)) 15:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:40:09 INFO - Running setup.py (path:/tmp/pip-pH6Mw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:40:09 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)) 15:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:40:09 INFO - Running setup.py (path:/tmp/pip-VciwQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:40:10 INFO - Running setup.py (path:/tmp/pip-X8Yn6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:40:10 INFO - Running setup.py (path:/tmp/pip-qehnUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:40:10 INFO - Running setup.py (path:/tmp/pip-R0U935-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:40:10 INFO - Running setup.py (path:/tmp/pip-d_4c74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:40:10 INFO - Running setup.py (path:/tmp/pip-86nwdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:40:10 INFO - Running setup.py (path:/tmp/pip-s0QZ5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:40:10 INFO - Running setup.py (path:/tmp/pip-AwEFD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:40:10 INFO - Running setup.py (path:/tmp/pip-4KXEYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:40:10 INFO - Running setup.py (path:/tmp/pip-IMyXIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:40:10 INFO - Running setup.py (path:/tmp/pip-OlKTOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:10 INFO - Running setup.py (path:/tmp/pip-gwhWq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:40:10 INFO - Running setup.py (path:/tmp/pip-sKkian-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:40:10 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)) 15:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:40:10 INFO - Running setup.py (path:/tmp/pip-XdWeN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:40:10 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)) 15:40:10 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)) 15:40:10 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)) 15:40:10 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)) 15:40:10 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)) 15:40:10 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)) 15:40:10 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)) 15:40:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:40:10 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 15:40:10 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 15:40:10 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 15:40:10 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 15:40:12 INFO - Downloading blessings-1.6.tar.gz 15:40:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:40:12 INFO - Installing collected packages: blessings 15:40:12 INFO - Running setup.py install for blessings 15:40:12 INFO - Successfully installed blessings 15:40:12 INFO - Cleaning up... 15:40:12 INFO - Return code: 0 15:40:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:40:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:12 INFO - Reading from file tmpfile_stdout 15:40:12 INFO - Current package versions: 15:40:12 INFO - argparse == 1.2.1 15:40:12 INFO - blessings == 1.6 15:40:12 INFO - blobuploader == 1.2.4 15:40:12 INFO - docopt == 0.6.1 15:40:12 INFO - functools32 == 3.2.3-2 15:40:12 INFO - jsonschema == 2.5.1 15:40:12 INFO - manifestparser == 1.1 15:40:12 INFO - mozInstall == 1.13 15:40:12 INFO - mozcrash == 1.0 15:40:12 INFO - mozdebug == 0.1 15:40:12 INFO - mozdevice == 0.51 15:40:12 INFO - mozfile == 1.2 15:40:12 INFO - mozhttpd == 0.7 15:40:12 INFO - mozinfo == 0.10 15:40:12 INFO - mozleak == 0.1 15:40:12 INFO - mozlog == 3.5 15:40:12 INFO - moznetwork == 0.27 15:40:12 INFO - mozprocess == 0.25 15:40:12 INFO - mozprofile == 0.28 15:40:12 INFO - mozrunner == 6.13 15:40:12 INFO - mozscreenshot == 0.1 15:40:12 INFO - mozsystemmonitor == 0.3 15:40:12 INFO - moztest == 0.8 15:40:12 INFO - mozversion == 1.4 15:40:12 INFO - psutil == 3.1.1 15:40:12 INFO - requests == 1.2.3 15:40:12 INFO - wsgiref == 0.1.2 15:40:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40:12 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')]} 15:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:12 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')]} 15:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:12 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 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:40:12 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 15:40:12 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 15:40:12 INFO - Using env: {'DISPLAY': ':0', 15:40:12 INFO - 'HOME': '/home/cltbld', 15:40:12 INFO - 'LANG': 'en_US.UTF-8', 15:40:12 INFO - 'LANGUAGE': 'en_US:en', 15:40:12 INFO - 'LOGNAME': 'cltbld', 15:40:12 INFO - 'MAIL': '/var/mail/cltbld', 15:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:12 INFO - 'MOZ_NO_REMOTE': '1', 15:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:12 INFO - 'NO_EM_RESTART': '1', 15:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:12 INFO - 'PWD': '/builds/slave/test', 15:40:12 INFO - 'SHELL': '/bin/bash', 15:40:12 INFO - 'SHLVL': '1', 15:40:12 INFO - 'TERM': 'linux', 15:40:12 INFO - 'TMOUT': '86400', 15:40:12 INFO - 'USER': 'cltbld', 15:40:12 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:12 INFO - '_': '/tools/buildbot/bin/python'} 15:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:12 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)) 15:40:12 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)) 15:40:12 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)) 15:40:12 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)) 15:40:12 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)) 15:40:12 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)) 15:40:12 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)) 15:40:12 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)) 15:40:12 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)) 15:40:12 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:40:12 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 15:40:12 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 15:40:12 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 15:40:12 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 15:40:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:40:14 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)) 15:40:14 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)) 15:40:14 INFO - Installing collected packages: simplejson 15:40:14 INFO - Running setup.py install for simplejson 15:40:14 INFO - building 'simplejson._speedups' extension 15:40:14 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 15:40:15 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 15:40:15 INFO - Successfully installed simplejson 15:40:15 INFO - Cleaning up... 15:40:15 INFO - Return code: 0 15:40:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:15 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 0x163d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1809a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19aab20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, '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': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', '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 15:40:15 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 15:40:15 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 15:40:15 INFO - Using env: {'DISPLAY': ':0', 15:40:15 INFO - 'HOME': '/home/cltbld', 15:40:15 INFO - 'LANG': 'en_US.UTF-8', 15:40:15 INFO - 'LANGUAGE': 'en_US:en', 15:40:15 INFO - 'LOGNAME': 'cltbld', 15:40:15 INFO - 'MAIL': '/var/mail/cltbld', 15:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:15 INFO - 'MOZ_NO_REMOTE': '1', 15:40:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:15 INFO - 'NO_EM_RESTART': '1', 15:40:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:15 INFO - 'PWD': '/builds/slave/test', 15:40:15 INFO - 'SHELL': '/bin/bash', 15:40:15 INFO - 'SHLVL': '1', 15:40:15 INFO - 'TERM': 'linux', 15:40:15 INFO - 'TMOUT': '86400', 15:40:15 INFO - 'USER': 'cltbld', 15:40:15 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:15 INFO - '_': '/tools/buildbot/bin/python'} 15:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:40:15 INFO - Cleaning up... 15:40:15 INFO - Return code: 0 15:40:15 INFO - Running post-action listener: _resource_record_post_action 15:40:15 INFO - Running post-action listener: _start_resource_monitoring 15:40:15 INFO - Starting resource monitoring. 15:40:15 INFO - [mozharness: 2017-08-15 22:40:15.429456Z] Finished create-virtualenv step (success) 15:40:15 INFO - [mozharness: 2017-08-15 22:40:15.430041Z] Running install step. 15:40:15 INFO - Running pre-action listener: _resource_record_pre_action 15:40:15 INFO - Running main action method: install 15:40:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:40:15 INFO - Reading from file tmpfile_stdout 15:40:15 INFO - Detecting whether we're running mozinstall >=1.0... 15:40:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:40:15 INFO - Reading from file tmpfile_stdout 15:40:15 INFO - Output received: 15:40:15 INFO - Usage: mozinstall [options] installer 15:40:15 INFO - Options: 15:40:15 INFO - -h, --help show this help message and exit 15:40:15 INFO - -d DEST, --destination=DEST 15:40:15 INFO - Directory to install application into. [default: 15:40:15 INFO - "/builds/slave/test"] 15:40:15 INFO - --app=APP Application being installed. [default: firefox] 15:40:15 INFO - mkdir: /builds/slave/test/build/application 15:40:15 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'] 15:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:40:33 INFO - Reading from file tmpfile_stdout 15:40:33 INFO - Output received: 15:40:33 INFO - /builds/slave/test/build/application/firefox/firefox 15:40:33 INFO - Running post-action listener: _resource_record_post_action 15:40:33 INFO - [mozharness: 2017-08-15 22:40:33.739726Z] Finished install step (success) 15:40:33 INFO - [mozharness: 2017-08-15 22:40:33.740009Z] Running setup-mitmproxy step. 15:40:33 INFO - Running pre-action listener: _resource_record_pre_action 15:40:33 INFO - Running main action method: setup_mitmproxy 15:40:33 INFO - Skipping: mitmproxy is not required 15:40:33 INFO - Running post-action listener: _resource_record_post_action 15:40:33 INFO - [mozharness: 2017-08-15 22:40:33.741153Z] Finished setup-mitmproxy step (success) 15:40:33 INFO - [mozharness: 2017-08-15 22:40:33.741374Z] Running run-tests step. 15:40:33 INFO - Running pre-action listener: _resource_record_pre_action 15:40:33 INFO - Running pre-action listener: _set_gcov_prefix 15:40:33 INFO - Running main action method: run_tests 15:40:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:40:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:40:33 INFO - Python 2.7.3 15:40:33 INFO - Return code: 0 15:40:33 INFO - grabbing minidump binary from tooltool 15:40:33 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')]} 15:40:33 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x19adf00>, '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 0x19a8ba0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a9050>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:40:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 15:40:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:40:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 15:40:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:40:33 INFO - Return code: 0 15:40:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:40:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:40:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:40:33 INFO - ENV: RUST_BACKTRACE is now full 15:40:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:40:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:40:33 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/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 15:40:33 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/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --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 15:40:33 INFO - Using env: {'DISPLAY': ':0', 15:40:33 INFO - 'HOME': '/home/cltbld', 15:40:33 INFO - 'LANG': 'en_US.UTF-8', 15:40:33 INFO - 'LANGUAGE': 'en_US:en', 15:40:33 INFO - 'LOGNAME': 'cltbld', 15:40:33 INFO - 'MAIL': '/var/mail/cltbld', 15:40:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:40:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:40:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:33 INFO - 'MOZ_NO_REMOTE': '1', 15:40:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:40:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:40:33 INFO - 'NO_EM_RESTART': '1', 15:40:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:40:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:33 INFO - 'PWD': '/builds/slave/test', 15:40:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:40:33 INFO - 'RUST_BACKTRACE': 'full', 15:40:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:40:33 INFO - 'SHELL': '/bin/bash', 15:40:33 INFO - 'SHLVL': '1', 15:40:33 INFO - 'TERM': 'linux', 15:40:33 INFO - 'TMOUT': '86400', 15:40:33 INFO - 'USER': 'cltbld', 15:40:33 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231', 15:40:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:33 INFO - '_': '/tools/buildbot/bin/python'} 15:40:33 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/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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 15:40:34 INFO - mozversion application_buildid: 20170815212701 15:40:34 INFO - mozversion application_changeset: d25db0546c92afa72d61685c9310104ef28235bd 15:40:34 INFO - mozversion application_display_name: Nightly 15:40:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:40:34 INFO - mozversion application_name: Firefox 15:40:34 INFO - mozversion application_remotingname: firefox 15:40:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:40:34 INFO - mozversion application_vendor: Mozilla 15:40:34 INFO - mozversion application_version: 57.0a1 15:40:34 INFO - mozversion platform_buildid: 20170815212701 15:40:34 INFO - mozversion platform_changeset: d25db0546c92afa72d61685c9310104ef28235bd 15:40:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:40:34 INFO - mozversion platform_version: 57.0a1 15:40:34 INFO - using testdate: 1502836834 15:40:34 INFO - actual date: 1502836834 15:40:34 INFO - starting webserver on 'localhost:51690' 15:40:34 INFO - SUITE-START | Running 2 tests 15:40:34 INFO - TEST-START | dromaeo_css 15:40:34 INFO - Initialising browser for dromaeo_css test... 15:40:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51690/getInfo.html -profile /tmp/tmpyF5G63/profile 15:40:34 INFO - TEST-INFO | started process 27993 (/builds/slave/test/build/application/firefox/firefox http://localhost:51690/getInfo.html) 15:40:40 INFO - TEST-INFO | 27993: exit 0 15:40:40 INFO - Browser initialized. 15:40:40 INFO - Running cycle 1/1 for dromaeo_css test... 15:40:40 INFO - TEST-INFO | started process 28206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyF5G63/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:40:41 INFO - PID 28206 | 15:40:41 INFO - PID 28206 | (/builds/slave/test/build/application/firefox/firefox:28254): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:40:41 INFO - PID 28206 | 15:40:51 INFO - PID 28206 | RSS: Main: 157343744 15:40:51 INFO - PID 28206 | 15:43:49 INFO - PID 28206 | Cycle 1(1): loaded http://localhost:51690/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51690/tests/dromaeo/cssquery-ext.html) 15:43:50 INFO - PID 28206 | RSS: Main: 165478400 15:43:50 INFO - PID 28206 | 15:46:52 INFO - PID 28206 | Cycle 1(1): loaded http://localhost:51690/tests/dromaeo/cssquery-ext.html (next: http://localhost:51690/tests/dromaeo/cssquery-jquery.html) 15:46:52 INFO - PID 28206 | RSS: Main: 162680832 15:46:52 INFO - PID 28206 | 15:49:54 INFO - PID 28206 | Cycle 1(1): loaded http://localhost:51690/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51690/tests/dromaeo/cssquery-mootools.html) 15:49:54 INFO - PID 28206 | RSS: Main: 164868096 15:49:54 INFO - PID 28206 | 15:52:56 INFO - PID 28206 | Cycle 1(1): loaded http://localhost:51690/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51690/tests/dromaeo/cssquery-prototype.html) 15:52:57 INFO - PID 28206 | RSS: Main: 163545088 15:52:57 INFO - PID 28206 | 15:56:00 INFO - PID 28206 | Cycle 1(1): loaded http://localhost:51690/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51690/tests/dromaeo/cssquery-yui.html) 15:56:00 INFO - PID 28206 | RSS: Main: 166694912 15:56:00 INFO - PID 28206 | 15:59:02 INFO - PID 28206 | Cycle 1(1): loaded http://localhost:51690/tests/dromaeo/cssquery-yui.html (next: http://localhost:51690/tests/dromaeo/cssquery-dojo.html) 15:59:02 INFO - PID 28206 | RSS: Main: 167047168 15:59:02 INFO - PID 28206 | 15:59:02 INFO - PID 28206 | __start_tp_report 15:59:02 INFO - PID 28206 | _x_x_mozilla_page_load 15:59:02 INFO - PID 28206 | _x_x_mozilla_page_load_details 15:59:02 INFO - PID 28206 | |i|pagename|runs| 15:59:02 INFO - PID 28206 | |0;dojo.html;3495.737819663524;3503.5795704515463;3539.769914955526;3541.486484459753;3560.857565697372;6177.166082578856;6231.781887633928;6273.937260627404;6278.748850045999;6283.214598175216;6127.142200091991;6241.750329986795;6248.968755156229;6256.030315301125;6256.436920677138;5465.535429488498;5471.425500322469;5512.531434828044;5514.228008078852;5527.336719593654;5488.313960754906;5559.221708960748;5577.44225577441;5594.216809646653;5632.408597097307;855.1856107869778;5392.865178370572;5459.836204913859;5480.80817171401;5602.7758889644365;5486.780528778844;5548.973439913629;5555.916661250085;5557.6109672322955;5559.27729395176;5453.100238460649;5455.318085239345;5522.475364840334;5526.557875369961;5531.612787104904;6173.382661733835;6213.347598502182;6235.376462353728;6308.148399965991;6315.305316415191;6750.392464678153;6766.560173588701;6774.458043356569;6854.760083397057;6865.794026179223;6774.695138718769;6842.55523390978;6852.828679283008;6862.553933994274;6932.688029038655;6243;6302.96848515759;6310.148130002437;6314;6327.987521996459;6941.722331106699;6956;6979.581225126509;7006.859862802818;7009.9299007010295;6674.899876501856;6743.662816859138;6746.561473504206;6753.527253092237;6759.797206083826;6783.491238157059;6785.219699734495;6807.149106361705;6879.58722476643;6887.965560172167;6600.86798264032;6704.966475167593;6710.798676039727;6712.597244165366;6798.558093723892;6782.728690852409;6794.660266986631;6806.115205023315;6812.352826481477;6832.965835170792;4880.267959805987;4952.182889823211;5007.974960125176;5017.9247311290355;5020.3724534433195;4365.454318210223;4398.4941731701265;4408.977955110204;4430.246858034142;4462.598366147063;6457.773977910751;6513.023046542961;6514.348565143448;6534.411902928759;6535.411812936763;6843.931560684329;6852.246252912176;6896.344847239504;6909.689063992132;6925.688344024531;6343.777967771106;6415.871682566322;6419.935800642027;6424.839379015469;6430.77492287777;5620.831375058755;5648.491635752802;5668.348139963957;5679.858003549944;5682.374938756733;7175.712971481186;7176.7129314826825;7215.278472152742;7250.6374681265725;7265;6783.084283621845;6800;6879.449644028466;6884.070650462324;6894.345037221456;7047.119110111236;7166.140063192451;7228.421726261781;7269.854602907806;7273.3453989141235;6657.201135863727;6718.596884186769;6741.325867413219;6743.797686069426;6774.559653622498;6839.965800170968;6846.4522838173825;6889.965550172217;6922.7923162305215;6942.479314051366;6617.172853393326;6635.336466353519;6686.930091185387;6701.128853249046;6702.966485167543;6420.967895160495;6513.934860651333;6538.280789113099;6612.603243805387;6643.368879956396;6540.182477190351;6565.573237739723;6614.6361950093205;6615.966920165369;6620.933790662032;6605.207375115017;6619.470442364695;6683.43190828785;6694.799156025327;6708.597484150967;6333.96833015832;6392.808215753535;6432.067350234283;6457.4188323049275;6479.287278399278 15:59:02 INFO - PID 28206 | |1;ext.html;817.284875733733;843.1526316052366;875.4484674654966;877.1754550722325;1197.4551579031543;34378.31243375131;45137;45294.32058519125;45444.54555454444;45619.543804561945;45639.54360456394;46019;46053.30920036202;46077.539224607746;46120.308195377096;7505.324520793155;7838.098618658842;7954.642041108164;8079.0709068457;8099.959500202491;4534.1158474097565;4560.3843481130125;4565.360849481075;4594.609458196057;5005.199168133099;4513.548645135476;4547.045120524694;4564.8174073037035;4568.497465278817;4576.519465456129;4560.498345182027;4568.931466028012;4571.954280457219;4579.931301030487;4608.101420223058;7863.646135923897;7923.326517246041;7930.484518506279;7933.167017463141;7935.127136015034;41982.37026444606;42018;42275.154496910196;42355.36466952967;42408;3454.7236221102253;3478.808665523395;3479.373712731707;3485.5643044619424;3490.301939612062;32547.837260813543;32659.836700816344;32714.182145446553;32729;32737.345253094805;5075.111855425286;5183.352080989876;5203.427622961472;5204.089284375257;5223.660462069991;209811;210006;210534;212175;212803;52797;52974.73512632412;53259.73370133124;53313.73343133259;53363.19955200675;57090.42909570934;57103.71448142733;57110.714446427504;57163;57189.71405143031;15838.683226335408;16052.197390130681;16066.919665401598;16068.276927538289;16077.517674469784;12089.395530223454;12224.938875305566;13820.723585528232;13978.02153849241;14065.367058482392;15749.763753543706;15777.68444631101;15778.368865245491;15810.814188935876;15817.604559886095;5148.700834802907;5246.97376513115;5277.419483856773;5309.681419114865;5358.169483730028;1069.668402795136;1087.7117563845586;1103.45379037377;1110.7945030169444;1110.9944450277696;24704.5059098817;24792.752072479045;24816.751832481805;24976.75023249744;25004.749952500606;1176.8587769467547;1182.131133616791;1191.368574655434;1194.75507520958;1203.9398030098635;12935.935320323526;13188.261787895266;13327.400266988008;13333.200007999358;13526.18842869431;259043;260892;274828;277023;277647;59435.10847337294;59678;59831.40168598345;59882.40117598768;60037;1488.78412630168;1530.709165258575;1531.111955066059;1535.155664384584;1563.0309208290932;24676.259712208663;24799.876000619883;24841.006359745406;24853.25440236796;24884.253472395856;38606.61393386142;42097.78951105225;42139.367909481996;42187.7890610545;42214.788926055175;221069;223495;224088;224173;224515;34444.48333274953;35078.824605876805;37521.24957500889;37611.2477750438;37850.810745946095;48365.758171208916;48387;48663.756681216364;48719.26921096257;49067;34975.82512087423;36561;37888.81055594704;37982.620173797906;38175.6182438172;36232.27535449329;36570.63429365672;36720;36778;36903.261934760616;41830.79084604557;41859.7907010463;41990;42037.57962420459;42221.36667950045;36098.819505902306;36117.819410902775;36233.45649815308;36276.63723362838;36639.45040824443;26646;26743.06399276016;26770.866145669148;26850.865745671148;26964.595531066658 15:59:02 INFO - PID 28206 | |2;jquery.html;568.1591244957463;579.5537436174146;580.5848818095061;583.5856541855284;591.952643788497;22135.778642213572;22940.311790646265;22941.42646433838;22945.082196712112;23370.298891033253;27359.179224623294;27672.169834904933;27797.861010694967;27842;27843.86078069612;6373.7131829067575;6484.157059582248;6525.347465253484;6526.379993900572;6532.738690452376;6128.785492507763;6386.776462823802;6427.421532062113;6432.45324147448;6440.6135631862235;6354.618722876642;6405.391487808651;6411.743530258784;6430.871382572346;6431.42117209451;6438.452731517827;6443.516736244777;6443.581167224115;6447.806565803034;6450.7742229022;5966.492848107872;5972.044472884361;5998.940010599882;6010.489108425789;6011.909821352683;30598;30627.23431914198;31044.068677939696;31650.841745791353;31686.524702129485;2230.2751605728204;2286.6455699366625;2286.9771302287095;2287.4853158039473;2287.6911616931834;20177.495562610904;20373.185072597225;21205.469863253387;21215.68176477354;21221.257255995966;2480.6899137607797;2506.9749302506925;2551.4386834896295;2554.5529532331952;2558.4243545202366;162734;165291;166968;167061;167574.64850702917;15699.215039247993;15823.446179383667;15978.52332888404;16084.85284708882;16146.354145834035;24184.153554625504;24230.7576924232;24275.63586546203;24311.513769724508;24333.39166520851;10398.480075996169;10481.266311358284;10499.317544359592;10531.94734026325;10627.893721062845;8819.29445644347;9328.626854925862;9545.284103692251;9602.66390676323;9721.319507634538;9508.47703376307;10081.596736130472;10124.797504049879;10154.441505717108;10180.287379883484;2786.3591373984095;2809.269589906598;2824.8587570621385;2834.6031555582235;2918.3725499017387;1045.6235755128148;1052.6894566103017;1058.539535302146;1063.4948399510326;1067.1036329483272;21400.357989260036;21656.20991370069;21674.566508669737;21747;21932.342029739135;1106.8284415915546;1107.4407424250862;1114.1142791480793;1114.7046032801313;1115.520326259716;11582.131340149353;12110.636680899586;12116;12126.514939402325;12138.27170369763;564443;569736;573882;582154;597058;117367;120215;120586;121042;132133.33933330272;1217.3000524698157;1239.962801115968;1247.0647014738765;1255.3660401497186;1261.6404324767582;18903.621927561646;19077.332293369665;19387.2245110194;19445.61108777845;19565.8043419564;63660.68169659122;64158.679206605535;64847.35152648413;64990;65582.34417655763;76493;76842;77224;77227.61386193032;77847;15409.84590154084;15542;15667.764983525029;15796.289166987282;15822.68354632924;25067.74932250654;25313;25496.362590935376;25552.616710748982;25738.613920790827;20345.28791492291;20612.278570249968;20716.274930377363;20854.270100547015;20896.37310880676;17604.91197544004;18103.004334761186;18316.90841545837;19083;19299.421017369503;21036.474088147308;21207.8939605301;21925.232616858335;21938.793366365004;21976.230831920806;22253.22113726012;22823.31530054101;23241.418964526023;23300.53398932046;23343.883280584167;20349;20718;20825.37523874286;21036.158553657682;21048.579028419652 15:59:02 INFO - PID 28206 | |3;mootools.html;1416.8937329700273;1457.0092337210692;1478.2460944918091;1481.6147801571587;1486.5243122200893;15726.764098538517;16288.755668664964;16332.591685207875;16396.34414623415;16536.33854645816;16520.669586608292;16824;16836.410725624573;16843.41048063318;16854.83145168548;2515.811314151437;2562.205716227966;2570.8200425970144;2604.4009877728136;2605.413781899067;2626.2383908666466;2647.6028595710604;2651.5624921887866;2652.694940081886;2660.5610074337806;2620.6462127612813;2631.697354804203;2632.921012369632;2633.73662633737;2657.6412184355154;2526.355779276282;2536.759007894247;2538.1624064058815;2542.478791847667;2544.465662210938;2458.373114855718;2464.667269918556;2465.4822487277693;2479.355367604418;2481.5781317176125;15948.2823272953;16198.433054843024;16213;16225.51323460298;16241.837581624151;8000.9199908000755;8299.709510167175;8300.833983320299;8305.916940830575;8307.335413166993;4815.5906747926465;4985.35190425246;5027.2207807789855;5043.344365232496;5044.697318160922;1901.1634880652607;1912.741779859729;1929.5947850951318;1959.9314024009234;1966.5771859050374;28304.43391132166;28489.430211395655;28560.28599285014;28632;28690.71309286922;6484.935150648527;6490.935090649127;6549.148610680581;6638.5684930479365;6645;18631.254749809857;18685.15916783748;18695.439136825917;18750.906245468686;18759.624807503773;7904.486208396436;7998.200179981955;7999.28006479408;8000.839983200303;8005;7301.744438944612;7310.268973102648;7377.594232317167;7407.259274072658;7415.480916335912;5186.196139598368;5378.112611419072;5385.192221166856;5429.348478182565;5479.479449452296;1503.225838693074;1505.4204131409485;1516.5071351810705;1526.9083854968515;1536.8232653244797;895.1451363947544;906.9909300906907;910.5765818894246;924.7410724997012;934.0332755597924;7798.142204357516;7864.960675196702;7948.920510794934;7956.641951112214;8038.276555110068;730.1858427853011;730.397422126737;734.4895297767999;734.8016035670446;742.4876834983844;4875.268709693574;5229.764660590283;5382.708150043961;5529.806456773994;5752.338481074647;22668.206612768474;22778;23381.298561042855;23719;23909.52180956371;22950.311490655306;22972.65541016886;22980.885095574416;23147.421314467272;23160.3051908443;11815.940920295343;12472.69036751128;12729.809052864215;12799.296038717774;12819.29493877827;9817.165540929102;9886.851697224403;9904.752381190528;10040.64857729976;10081.14310283634;20487.79512204859;20501;20816.687749684068;21360.78639213588;21587.13651453924;20633.277835275694;20955.8952205238;21087.8945605271;21150.788492114883;21666.458338541663;12862.871371286168;13018.869811301765;13065.346732663138;13117.147385419918;13137.868621313664;14731.558053258419;14800.037997530126;14865.331060102106;14872.628184295047;14891.851081489047;11543.653690389301;12011.399430028638;12048.156629035884;12080.093992950387;12128.454219559964;11333.943330283295;11401.258918170292;11770.470328835394;11777.352245626573;11818.704532386415;10437.32157409766;10643.574257029943;10658.987396197348;10682.359058456517;10692.732681683021;7578.280063393969;7827.530348179127;7835.138134805053;7895.973523441916;7972;7842.568658723597;7894.289513943772;7899.84200315979;7982.321502672338;8027.839443210986 15:59:02 INFO - PID 28206 | |4;prototype.html;533.706461446205;539.2100572661051;546.0525987411839;547.4908335248217;551.8620344913769;6148.907766383508;6185.783497577586;6191.4737247333915;6201.59689620175;6215;6114.449699527042;6138;6148.508119350452;6173.9073913891325;6192.876142477147;4909.937826113023;4914.336564563791;4919.188333924907;4919.680220785655;4923.986950635239;4729.881752956169;4815.133276010317;4895.926561101586;4907.852764417074;4924.2121260598315;4773.284007398901;4779.784909679055;4787.353707249529;4800.8319708810195;4807.351007613962;4776.761161941889;4809.735464549449;4811.158047341736;4816.590589799871;4836.331465644347;4247.150569886036;4312.956870431287;4324.827006919751;4341.45731783527;4344.326629372453;5996.940030599725;5997.1304160896425;6015.699215039231;6030.819075427744;6063.332583539518;5704.486596206361;5721.284839395075;5738.8278351649515;5745.770169193269;5767;6506.707198176046;6554.541182117254;6591.571547849375;6610.504212184105;6611.206655201358;4920.3111564381015;4960.784607771119;4961.8511444656715;4963.950360496421;4983.053219888209;6467.676616169173;6633.237177724575;6693.665316734193;6742.966285168592;6894.620795856226;4999.500049995044;5015.623828212899;5017.82437614689;5053.231369020813;5059.215821547666;5060.051880026216;5069.214271787879;5104.336436263262;5108.489151084862;5123.666961647481;5669.801556945569;5672.014794821779;5676.807870347273;5684.658920464785;5695;6496.603230305507;6496.765614533224;6499.805005849832;6508.251551071593;6567.704453299613;1760.850327722158;1761.216258764863;1763.5326638440824;1765.8675599330102;1804.2512357371807;1187.5962172861268;1241.4289426863563;1248.7752204603078;1263.1536870296923;1264.1846009323936;6152.261728592597;6227.501799856001;6259.18630578031;6260;6283.711839072979;6511.939388487403;6598.27418983921;6601.570897891717;6632.50256230775;6642.3394151462135;6198.5907750736915;6200.906986395117;6241.625502469776;6286.214223222098;6315.305316415191;3492.301539692072;3496.7727097739075;3511.613722490524;3531.234382808596;3532.523109380252;7063.011178435023;7076.752313668997;7110.1823290321245;7116.181639111568;7116.64416779159;6194.5973511721595;6789.1174147360525;6802.353776391334;6825.829354266084;6851.862962740817;6834.760783372558;6949.687264073129;6987.510874238857;7024.929750702529;7044;6516.837079073061;6636.900446493403;6664.600123992576;6669.799906002828;6720.361565651256;6586.2755096940255;6600.53796234259;6617.966910165418;6645.471541545375;6676.432503237085;7148.249433809375;7173.350129470146;7176.576963617637;7182.3535881770895;7212.350888420067;4974.253861920741;5046;5059.873503162303;5074.492550744896;5101.7704203310195;6444.581102228341;6507;6620.271770105381;6752.966235168793;6809.5573787705625;6398.648074355955;6412.35876412355;6440.194975628046;6531.11829902957;6543.312952140147;6447.838804029937;6484.902726459012;6527.738890444519;6548.607083575001;6556.704948277244;6453.193350831146;6482.35176482348;6532.150820393318;6540.934590654223;6601.966990165019;6493.512986526124;6547.148870646785;6570.8028759137305;6602;6608.669566521751;6348.746050157942;6375.075614036032;6377.23473183221;6396.104545363746;6422 15:59:02 INFO - PID 28206 | |5;yui.html;923.3813345058815;943.1652987106413;950.9001554836742;951.6478902805962;972.4311277902425;18313.908430457846;19119.426417207487;19136.904315478405;19147.42557723267;19896;18280.634387312246;18373.173207205673;18474.90762546189;18500.907495462503;18563;1015.4618052432223;1038.376973815712;1038.3977293169944;1039.0856046678912;1040.9479526023667;1033.8552602635637;1066.7119877633074;1079.0288740133904;1079.913606911449;1080.1250986700798;1147;1147.5123072694114;1147.7589706161716;1147.8163493840987;1154.2497376705167;1131.6378758797189;1131.6661584832452;1132.3488993828541;1133.9546418143264;1140.0822338017892;827.4786884262936;827.7475370012144;829.9004119505638;830.6303694855792;839.7774589733725;19256.623368834462;19447.902760486108;19528.31650892226;19572.21711131553;19716.901415492976;21714.674279885814;21739.34781956544;22134.55730885373;22195.22316718907;22435.214767483223;15845.445409410615;16080.356785728556;16112.11383373914;16163.757543636855;16183.919080404641;3673.94489082664;3682.3371793077504;3694.131879008431;3694.3165514379925;3696.31618150643;137213;137986;138275;138778;142322.99999999895;17718;17884.642307154045;18184.545386365447;18206.544836378933;18241.63516729658;32150.678493215237;32829.507557386656;33111;33161.66838331586;33183.834080829925;9528.380655257386;9659.613615455444;9833.852492212622;9962.800743985224;9976.102150806462;4241.554636763157;4367.344898265285;4378.890527736832;4682.742449165328;4765.070811191785;6161.507079433634;6359.046143078575;6401.3598640135615;6406.4554512866;6424.903626445607;1132.2866594045863;1169.7134202120535;1185.1940680337282;1185.727282724978;1188.9524419023141;728.2790037862478;735.8969744235811;744.7318965172534;750.6584504050644;756.08136114621;11331.943340283411;12016.819747703792;13016.479340826261;13589.167041422956;13609.31953402326;678.3420082519948;686.4885660183096;696.0985523746659;707.3598393453932;725.1189804387694;5769.134629805562;5834.591578589458;6128.938710612926;6175.228096487939;6242.5942313749465;127776;130564.3471782654;134150;139944;139976;117395;120052.39973800075;123453.38273308576;123566.38216809038;125062;103032.9999999985;103172;103251;110029.44985275023;110669.44665276783;13266.071375003658;13667.726645466835;13798.724025519634;13800.171989680652;13806.585802425943;48745;49877.50122498874;50341;50804;51740.482595175075;82371;84075.5796221015;84479;84750;86475;19237.61524769525;19439.319623813666;19595.70606440876;19619.803801962367;19658.41024769259;20815.1673933041;21027.15891364328;21267.680984784933;21459.570808584052;21725.456863578536;17923.462296131136;18002.279908803503;18460.538486537946;18995.145218464924;19600.509987250432;16213.35146594123;16653;16847.15764211809;16978.320867165174;17238;16790.412335568195;17244.482665520056;18993.14530846143;19386.407689586315;19607.901960490108;14493.347799349265;14570.854291457374;14738.11571305725;14773;14803.407863685332;14830;14999.400023998918;15233.466828660943;15279.159646219565;15502.612434689223 15:59:02 INFO - PID 28206 | __end_tp_report 15:59:02 INFO - PID 28206 | __start_cc_report 15:59:02 INFO - PID 28206 | _x_x_mozilla_cycle_collect,342 15:59:02 INFO - PID 28206 | __end_cc_report 15:59:02 INFO - PID 28206 | __startTimestamp1502837942542__endTimestamp 15:59:02 INFO - PID 28206 | 15:59:02 INFO - PID 28206 | ------- Summary: start ------- 15:59:02 INFO - PID 28206 | Number of tests: 6 15:59:02 INFO - PID 28206 | 15:59:02 INFO - PID 28206 | [#0] dojo.html Cycles:165 Average:6228.65 Median:6540.18 stddev:913.61 (14.0%) stddev-sans-first:890.90 15:59:02 INFO - PID 28206 | Values: 3495.7 3503.6 3539.8 3541.5 3560.9 6177.2 6231.8 6273.9 6278.7 6283.2 6127.1 6241.8 6249.0 6256.0 6256.4 5465.5 5471.4 5512.5 5514.2 5527.3 5488.3 5559.2 5577.4 5594.2 5632.4 855.2 5392.9 5459.8 5480.8 5602.8 5486.8 5549.0 5555.9 5557.6 5559.3 5453.1 5455.3 5522.5 5526.6 5531.6 6173.4 6213.3 6235.4 6308.1 6315.3 6750.4 6766.6 6774.5 6854.8 6865.8 6774.7 6842.6 6852.8 6862.6 6932.7 6243.0 6303.0 6310.1 6314.0 6328.0 6941.7 6956.0 6979.6 7006.9 7009.9 6674.9 6743.7 6746.6 6753.5 6759.8 6783.5 6785.2 6807.1 6879.6 6888.0 6600.9 6705.0 6710.8 6712.6 6798.6 6782.7 6794.7 6806.1 6812.4 6833.0 4880.3 4952.2 5008.0 5017.9 5020.4 4365.5 4398.5 4409.0 4430.2 4462.6 6457.8 6513.0 6514.3 6534.4 6535.4 6843.9 6852.2 6896.3 6909.7 6925.7 6343.8 6415.9 6419.9 6424.8 6430.8 5620.8 5648.5 5668.3 5679.9 5682.4 7175.7 7176.7 7215.3 7250.6 7265.0 6783.1 6800.0 6879.4 6884.1 6894.3 7047.1 7166.1 7228.4 7269.9 7273.3 6657.2 6718.6 6741.3 6743.8 6774.6 6840.0 6846.5 6890.0 6922.8 6942.5 6617.2 6635.3 6686.9 6701.1 6703.0 6421.0 6513.9 6538.3 6612.6 6643.4 6540.2 6565.6 6614.6 6616.0 6620.9 6605.2 6619.5 6683.4 6694.8 6708.6 6334.0 6392.8 6432.1 6457.4 6479.3 15:59:02 INFO - PID 28206 | 15:59:02 INFO - PID 28206 | [#1] ext.html Cycles:180 Average:41670.76 Median:37212.26 stddev:61563.05 (165.4%) stddev-sans-first:61659.33 15:59:02 INFO - PID 28206 | Values: 817.3 843.2 875.4 877.2 1197.5 34378.3 45137.0 45294.3 45444.5 45619.5 45639.5 46019.0 46053.3 46077.5 46120.3 7505.3 7838.1 7954.6 8079.1 8100.0 4534.1 4560.4 4565.4 4594.6 5005.2 4513.5 4547.0 4564.8 4568.5 4576.5 4560.5 4568.9 4572.0 4579.9 4608.1 7863.6 7923.3 7930.5 7933.2 7935.1 41982.4 42018.0 42275.2 42355.4 42408.0 3454.7 3478.8 3479.4 3485.6 3490.3 32547.8 32659.8 32714.2 32729.0 32737.3 5075.1 5183.4 5203.4 5204.1 5223.7 209811.0 210006.0 210534.0 212175.0 212803.0 52797.0 52974.7 53259.7 53313.7 53363.2 57090.4 57103.7 57110.7 57163.0 57189.7 15838.7 16052.2 16066.9 16068.3 16077.5 12089.4 12224.9 13820.7 13978.0 14065.4 15749.8 15777.7 15778.4 15810.8 15817.6 5148.7 5247.0 5277.4 5309.7 5358.2 1069.7 1087.7 1103.5 1110.8 1111.0 24704.5 24792.8 24816.8 24976.8 25004.7 1176.9 1182.1 1191.4 1194.8 1203.9 12935.9 13188.3 13327.4 13333.2 13526.2 259043.0 260892.0 274828.0 277023.0 277647.0 59435.1 59678.0 59831.4 59882.4 60037.0 1488.8 1530.7 1531.1 1535.2 1563.0 24676.3 24799.9 24841.0 24853.3 24884.3 38606.6 42097.8 42139.4 42187.8 42214.8 221069.0 223495.0 224088.0 224173.0 224515.0 34444.5 35078.8 37521.2 37611.2 37850.8 48365.8 48387.0 48663.8 48719.3 49067.0 34975.8 36561.0 37888.8 37982.6 38175.6 36232.3 36570.6 36720.0 36778.0 36903.3 41830.8 41859.8 41990.0 42037.6 42221.4 36098.8 36117.8 36233.5 36276.6 36639.5 26646.0 26743.1 26770.9 26850.9 26964.6 15:59:02 INFO - PID 28206 | 15:59:02 INFO - PID 28206 | [#2] jquery.html Cycles:180 Average:39501.21 Median:2287.59 stddev:97193.49 (4248.7%) stddev-sans-first:97422.18 15:59:02 INFO - PID 28206 | Values: 568.2 579.6 580.6 583.6 592.0 22135.8 22940.3 22941.4 22945.1 23370.3 27359.2 27672.2 27797.9 27842.0 27843.9 6373.7 6484.2 6525.3 6526.4 6532.7 6128.8 6386.8 6427.4 6432.5 6440.6 6354.6 6405.4 6411.7 6430.9 6431.4 6438.5 6443.5 6443.6 6447.8 6450.8 5966.5 5972.0 5998.9 6010.5 6011.9 30598.0 30627.2 31044.1 31650.8 31686.5 2230.3 2286.6 2287.0 2287.5 2287.7 20177.5 20373.2 21205.5 21215.7 21221.3 2480.7 2507.0 2551.4 2554.6 2558.4 162734.0 165291.0 166968.0 167061.0 167574.6 15699.2 15823.4 15978.5 16084.9 16146.4 24184.2 24230.8 24275.6 24311.5 24333.4 10398.5 10481.3 10499.3 10531.9 10627.9 8819.3 9328.6 9545.3 9602.7 9721.3 9508.5 10081.6 10124.8 10154.4 10180.3 2786.4 2809.3 2824.9 2834.6 2918.4 1045.6 1052.7 1058.5 1063.5 1067.1 21400.4 21656.2 21674.6 21747.0 21932.3 1106.8 1107.4 1114.1 1114.7 1115.5 11582.1 12110.6 12116.0 12126.5 12138.3 564443.0 569736.0 573882.0 582154.0 597058.0 117367.0 120215.0 120586.0 121042.0 132133.3 1217.3 1240.0 1247.1 1255.4 1261.6 18903.6 19077.3 19387.2 19445.6 19565.8 63660.7 64158.7 64847.4 64990.0 65582.3 76493.0 76842.0 77224.0 77227.6 77847.0 15409.8 15542.0 15667.8 15796.3 15822.7 25067.7 25313.0 25496.4 25552.6 25738.6 20345.3 20612.3 20716.3 20854.3 20896.4 17604.9 18103.0 18316.9 19083.0 19299.4 21036.5 21207.9 21925.2 21938.8 21976.2 22253.2 22823.3 23241.4 23300.5 23343.9 20349.0 20718.0 20825.4 21036.2 21048.6 15:59:02 INFO - PID 28206 | 15:59:02 INFO - PID 28206 | [#3] mootools.html Cycles:180 Average:9953.59 Median:2480.47 stddev:7362.79 (296.8%) stddev-sans-first:7355.51 15:59:02 INFO - PID 28206 | Values: 1416.9 1457.0 1478.2 1481.6 1486.5 15726.8 16288.8 16332.6 16396.3 16536.3 16520.7 16824.0 16836.4 16843.4 16854.8 2515.8 2562.2 2570.8 2604.4 2605.4 2626.2 2647.6 2651.6 2652.7 2660.6 2620.6 2631.7 2632.9 2633.7 2657.6 2526.4 2536.8 2538.2 2542.5 2544.5 2458.4 2464.7 2465.5 2479.4 2481.6 15948.3 16198.4 16213.0 16225.5 16241.8 8000.9 8299.7 8300.8 8305.9 8307.3 4815.6 4985.4 5027.2 5043.3 5044.7 1901.2 1912.7 1929.6 1959.9 1966.6 28304.4 28489.4 28560.3 28632.0 28690.7 6484.9 6490.9 6549.1 6638.6 6645.0 18631.3 18685.2 18695.4 18750.9 18759.6 7904.5 7998.2 7999.3 8000.8 8005.0 7301.7 7310.3 7377.6 7407.3 7415.5 5186.2 5378.1 5385.2 5429.3 5479.5 1503.2 1505.4 1516.5 1526.9 1536.8 895.1 907.0 910.6 924.7 934.0 7798.1 7865.0 7948.9 7956.6 8038.3 730.2 730.4 734.5 734.8 742.5 4875.3 5229.8 5382.7 5529.8 5752.3 22668.2 22778.0 23381.3 23719.0 23909.5 22950.3 22972.7 22980.9 23147.4 23160.3 11815.9 12472.7 12729.8 12799.3 12819.3 9817.2 9886.9 9904.8 10040.6 10081.1 20487.8 20501.0 20816.7 21360.8 21587.1 20633.3 20955.9 21087.9 21150.8 21666.5 12862.9 13018.9 13065.3 13117.1 13137.9 14731.6 14800.0 14865.3 14872.6 14891.9 11543.7 12011.4 12048.2 12080.1 12128.5 11333.9 11401.3 11770.5 11777.4 11818.7 10437.3 10643.6 10659.0 10682.4 10692.7 7578.3 7827.5 7835.1 7896.0 7972.0 7842.6 7894.3 7899.8 7982.3 8027.8 15:59:02 INFO - PID 28206 | 15:59:02 INFO - PID 28206 | [#4] prototype.html Cycles:180 Average:5550.01 Median:6199.75 stddev:1581.12 (25.5%) stddev-sans-first:1540.08 15:59:02 INFO - PID 28206 | Values: 533.7 539.2 546.1 547.5 551.9 6148.9 6185.8 6191.5 6201.6 6215.0 6114.4 6138.0 6148.5 6173.9 6192.9 4909.9 4914.3 4919.2 4919.7 4924.0 4729.9 4815.1 4895.9 4907.9 4924.2 4773.3 4779.8 4787.4 4800.8 4807.4 4776.8 4809.7 4811.2 4816.6 4836.3 4247.2 4313.0 4324.8 4341.5 4344.3 5996.9 5997.1 6015.7 6030.8 6063.3 5704.5 5721.3 5738.8 5745.8 5767.0 6506.7 6554.5 6591.6 6610.5 6611.2 4920.3 4960.8 4961.9 4964.0 4983.1 6467.7 6633.2 6693.7 6743.0 6894.6 4999.5 5015.6 5017.8 5053.2 5059.2 5060.1 5069.2 5104.3 5108.5 5123.7 5669.8 5672.0 5676.8 5684.7 5695.0 6496.6 6496.8 6499.8 6508.3 6567.7 1760.9 1761.2 1763.5 1765.9 1804.3 1187.6 1241.4 1248.8 1263.2 1264.2 6152.3 6227.5 6259.2 6260.0 6283.7 6511.9 6598.3 6601.6 6632.5 6642.3 6198.6 6200.9 6241.6 6286.2 6315.3 3492.3 3496.8 3511.6 3531.2 3532.5 7063.0 7076.8 7110.2 7116.2 7116.6 6194.6 6789.1 6802.4 6825.8 6851.9 6834.8 6949.7 6987.5 7024.9 7044.0 6516.8 6636.9 6664.6 6669.8 6720.4 6586.3 6600.5 6618.0 6645.5 6676.4 7148.2 7173.4 7176.6 7182.4 7212.4 4974.3 5046.0 5059.9 5074.5 5101.8 6444.6 6507.0 6620.3 6753.0 6809.6 6398.6 6412.4 6440.2 6531.1 6543.3 6447.8 6484.9 6527.7 6548.6 6556.7 6453.2 6482.4 6532.2 6540.9 6602.0 6493.5 6547.1 6570.8 6602.0 6608.7 6348.7 6375.1 6377.2 6396.1 6422.0 15:59:02 INFO - PID 28206 | 15:59:02 INFO - PID 28206 | [#5] yui.html Cycles:180 Average:27002.64 Median:18778.07 stddev:38474.94 (204.9%) stddev-sans-first:38533.04 15:59:02 INFO - PID 28206 | Values: 923.4 943.2 950.9 951.6 972.4 18313.9 19119.4 19136.9 19147.4 19896.0 18280.6 18373.2 18474.9 18500.9 18563.0 1015.5 1038.4 1038.4 1039.1 1040.9 1033.9 1066.7 1079.0 1079.9 1080.1 1147.0 1147.5 1147.8 1147.8 1154.2 1131.6 1131.7 1132.3 1134.0 1140.1 827.5 827.7 829.9 830.6 839.8 19256.6 19447.9 19528.3 19572.2 19716.9 21714.7 21739.3 22134.6 22195.2 22435.2 15845.4 16080.4 16112.1 16163.8 16183.9 3673.9 3682.3 3694.1 3694.3 3696.3 137213.0 137986.0 138275.0 138778.0 142323.0 17718.0 17884.6 18184.5 18206.5 18241.6 32150.7 32829.5 33111.0 33161.7 33183.8 9528.4 9659.6 9833.9 9962.8 9976.1 4241.6 4367.3 4378.9 4682.7 4765.1 6161.5 6359.0 6401.4 6406.5 6424.9 1132.3 1169.7 1185.2 1185.7 1189.0 728.3 735.9 744.7 750.7 756.1 11331.9 12016.8 13016.5 13589.2 13609.3 678.3 686.5 696.1 707.4 725.1 5769.1 5834.6 6128.9 6175.2 6242.6 127776.0 130564.3 134150.0 139944.0 139976.0 117395.0 120052.4 123453.4 123566.4 125062.0 103033.0 103172.0 103251.0 110029.4 110669.4 13266.1 13667.7 13798.7 13800.2 13806.6 48745.0 49877.5 50341.0 50804.0 51740.5 82371.0 84075.6 84479.0 84750.0 86475.0 19237.6 19439.3 19595.7 19619.8 19658.4 20815.2 21027.2 21267.7 21459.6 21725.5 17923.5 18002.3 18460.5 18995.1 19600.5 16213.4 16653.0 16847.2 16978.3 17238.0 16790.4 17244.5 18993.1 19386.4 19607.9 14493.3 14570.9 14738.1 14773.0 14803.4 14830.0 14999.4 15233.5 15279.2 15502.6 15:59:02 INFO - PID 28206 | -------- Summary: end -------- 15:59:02 INFO - PID 28206 | 15:59:03 INFO - TEST-INFO | 28206: exit 0 15:59:03 INFO - TEST-OK | dromaeo_css | took 1109181ms 15:59:03 INFO - TEST-START | kraken 15:59:03 INFO - Initialising browser for kraken test... 15:59:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51690/getInfo.html -profile /tmp/tmptymUzn/profile 15:59:03 INFO - TEST-INFO | started process 28565 (/builds/slave/test/build/application/firefox/firefox http://localhost:51690/getInfo.html) 15:59:09 INFO - TEST-INFO | 28565: exit 0 15:59:09 INFO - Browser initialized. 15:59:09 INFO - Running cycle 1/1 for kraken test... 15:59:09 INFO - TEST-INFO | started process 28778 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptymUzn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:59:10 INFO - PID 28778 | 15:59:10 INFO - PID 28778 | (/builds/slave/test/build/application/firefox/firefox:28828): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:59:10 INFO - PID 28778 | 15:59:20 INFO - PID 28778 | RSS: Main: 157388800 15:59:20 INFO - PID 28778 | 15:59:23 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51690/tests/kraken/driver.html?testName=audio-beat-detection) 15:59:24 INFO - PID 28778 | RSS: Main: 164085760 15:59:24 INFO - PID 28778 | 15:59:26 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51690/tests/kraken/driver.html?testName=audio-dft) 15:59:27 INFO - PID 28778 | RSS: Main: 167985152 15:59:27 INFO - PID 28778 | 15:59:30 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51690/tests/kraken/driver.html?testName=audio-fft) 15:59:30 INFO - PID 28778 | RSS: Main: 160681984 15:59:30 INFO - PID 28778 | 15:59:32 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51690/tests/kraken/driver.html?testName=audio-oscillator) 15:59:33 INFO - PID 28778 | RSS: Main: 162123776 15:59:33 INFO - PID 28778 | 15:59:35 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51690/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:59:36 INFO - PID 28778 | RSS: Main: 161853440 15:59:36 INFO - PID 28778 | 15:59:39 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51690/tests/kraken/driver.html?testName=imaging-darkroom) 15:59:39 INFO - PID 28778 | RSS: Main: 164126720 15:59:39 INFO - PID 28778 | 15:59:43 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51690/tests/kraken/driver.html?testName=imaging-desaturate) 15:59:43 INFO - PID 28778 | RSS: Main: 165842944 15:59:43 INFO - PID 28778 | 15:59:46 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51690/tests/kraken/driver.html?testName=json-parse-financial) 15:59:47 INFO - PID 28778 | RSS: Main: 164339712 15:59:47 INFO - PID 28778 | 15:59:48 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51690/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:59:49 INFO - PID 28778 | RSS: Main: 164122624 15:59:49 INFO - PID 28778 | 15:59:51 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51690/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:59:51 INFO - PID 28778 | RSS: Main: 164384768 15:59:51 INFO - PID 28778 | 15:59:53 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51690/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:59:54 INFO - PID 28778 | RSS: Main: 164712448 15:59:54 INFO - PID 28778 | 15:59:57 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51690/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:59:57 INFO - PID 28778 | RSS: Main: 164499456 15:59:57 INFO - PID 28778 | 16:00:00 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51690/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:00:01 INFO - PID 28778 | RSS: Main: 164622336 16:00:01 INFO - PID 28778 | 16:00:02 INFO - PID 28778 | Cycle 1(1): loaded http://localhost:51690/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51690/tests/kraken/driver.html?testName=ai-astar) 16:00:03 INFO - PID 28778 | RSS: Main: 165937152 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | __start_tp_report 16:00:03 INFO - PID 28778 | _x_x_mozilla_page_load 16:00:03 INFO - PID 28778 | _x_x_mozilla_page_load_details 16:00:03 INFO - PID 28778 | |i|pagename|runs| 16:00:03 INFO - PID 28778 | |0;ai-astar;96;108;100;107;105;108;116;98;107;108 16:00:03 INFO - PID 28778 | |1;audio-beat-detection;140;112;148;179;113;138;142;114;165;114 16:00:03 INFO - PID 28778 | |2;audio-dft;164;163;164;164;164;164;163;163;166;167 16:00:03 INFO - PID 28778 | |3;audio-fft;74;74;75;129;74;74;73;98;101;74 16:00:03 INFO - PID 28778 | |4;audio-oscillator;79;82;138;77;78;79;135;77;78;124 16:00:03 INFO - PID 28778 | |5;imaging-gaussian-blur;117;114;113;113;114;114;114;115;113;114 16:00:03 INFO - PID 28778 | |6;imaging-darkroom;148;148;148;147;148;147;149;150;148;147 16:00:03 INFO - PID 28778 | |7;imaging-desaturate;94;94;93;95;94;94;94;94;95;94 16:00:03 INFO - PID 28778 | |8;json-parse-financial;51;48;48;49;51;48;50;51;52;50 16:00:03 INFO - PID 28778 | |9;json-stringify-tinderbox;53;54;54;54;54;55;54;54;57;54 16:00:03 INFO - PID 28778 | |10;stanford-crypto-aes;74;76;73;80;74;76;73;73;75;73 16:00:03 INFO - PID 28778 | |11;stanford-crypto-ccm;132;121;127;117;120;116;118;114;115;162 16:00:03 INFO - PID 28778 | |12;stanford-crypto-pbkdf2;159;162;159;160;163;162;161;166;161;162 16:00:03 INFO - PID 28778 | |13;stanford-crypto-sha256-iterative;63;60;62;62;65;60;61;67;61;61 16:00:03 INFO - PID 28778 | __end_tp_report 16:00:03 INFO - PID 28778 | __start_cc_report 16:00:03 INFO - PID 28778 | _x_x_mozilla_cycle_collect,836 16:00:03 INFO - PID 28778 | __end_cc_report 16:00:03 INFO - PID 28778 | __startTimestamp1502838003082__endTimestamp 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | ------- Summary: start ------- 16:00:03 INFO - PID 28778 | Number of tests: 14 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#0] ai-astar Cycles:10 Average:105.30 Median:108.00 stddev:5.87 (5.4%) stddev-sans-first:5.17 16:00:03 INFO - PID 28778 | Values: 96.0 108.0 100.0 107.0 105.0 108.0 116.0 98.0 107.0 108.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#1] audio-beat-detection Cycles:10 Average:136.50 Median:141.00 stddev:23.48 (16.7%) stddev-sans-first:24.87 16:00:03 INFO - PID 28778 | Values: 140.0 112.0 148.0 179.0 113.0 138.0 142.0 114.0 165.0 114.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#2] audio-dft Cycles:10 Average:164.20 Median:164.00 stddev:1.32 (0.8%) stddev-sans-first:1.39 16:00:03 INFO - PID 28778 | Values: 164.0 163.0 164.0 164.0 164.0 164.0 163.0 163.0 166.0 167.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#3] audio-fft Cycles:10 Average:84.60 Median:74.00 stddev:18.88 (25.5%) stddev-sans-first:19.63 16:00:03 INFO - PID 28778 | Values: 74.0 74.0 75.0 129.0 74.0 74.0 73.0 98.0 101.0 74.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:78.00 stddev:26.24 (33.6%) stddev-sans-first:27.21 16:00:03 INFO - PID 28778 | Values: 79.0 82.0 138.0 77.0 78.0 79.0 135.0 77.0 78.0 124.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#5] imaging-gaussian-blur Cycles:10 Average:114.10 Median:114.00 stddev:1.20 (1.1%) stddev-sans-first:0.67 16:00:03 INFO - PID 28778 | Values: 117.0 114.0 113.0 113.0 114.0 114.0 114.0 115.0 113.0 114.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 16:00:03 INFO - PID 28778 | Values: 148.0 148.0 148.0 147.0 148.0 147.0 149.0 150.0 148.0 147.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 16:00:03 INFO - PID 28778 | Values: 94.0 94.0 93.0 95.0 94.0 94.0 94.0 94.0 95.0 94.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#8] json-parse-financial Cycles:10 Average:49.80 Median:50.50 stddev:1.48 (2.9%) stddev-sans-first:1.50 16:00:03 INFO - PID 28778 | Values: 51.0 48.0 48.0 49.0 51.0 48.0 50.0 51.0 52.0 50.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#9] json-stringify-tinderbox Cycles:10 Average:54.30 Median:54.00 stddev:1.06 (2.0%) stddev-sans-first:1.01 16:00:03 INFO - PID 28778 | Values: 53.0 54.0 54.0 54.0 54.0 55.0 54.0 54.0 57.0 54.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:74.50 stddev:2.21 (3.0%) stddev-sans-first:2.33 16:00:03 INFO - PID 28778 | Values: 74.0 76.0 73.0 80.0 74.0 76.0 73.0 73.0 75.0 73.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#11] stanford-crypto-ccm Cycles:10 Average:124.20 Median:120.50 stddev:14.42 (12.0%) stddev-sans-first:15.02 16:00:03 INFO - PID 28778 | Values: 132.0 121.0 127.0 117.0 120.0 116.0 118.0 114.0 115.0 162.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:162.00 stddev:2.07 (1.3%) stddev-sans-first:1.99 16:00:03 INFO - PID 28778 | Values: 159.0 162.0 159.0 160.0 163.0 162.0 161.0 166.0 161.0 162.0 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:2.25 (3.6%) stddev-sans-first:2.37 16:00:03 INFO - PID 28778 | Values: 63.0 60.0 62.0 62.0 65.0 60.0 61.0 67.0 61.0 61.0 16:00:03 INFO - PID 28778 | -------- Summary: end -------- 16:00:03 INFO - PID 28778 | 16:00:03 INFO - PID 28778 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:00:03 INFO - TEST-INFO | 28778: exit 0 16:00:03 INFO - TEST-OK | kraken | took 60423ms 16:00:03 INFO - SUITE-END | took 1169s 16:00:03 INFO - Completed test suite (00:19:29) 16:00:03 INFO - javascript benchmark 16:00:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8864.681413998014, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3495.737819663524, 3503.5795704515463, 3539.769914955526, 3541.486484459753, 3560.857565697372, 6177.166082578856, 6231.781887633928, 6273.937260627404, 6278.748850045999, 6283.214598175216, 6127.142200091991, 6241.750329986795, 6248.968755156229, 6256.030315301125, 6256.436920677138, 5465.535429488498, 5471.425500322469, 5512.531434828044, 5514.228008078852, 5527.336719593654, 5488.313960754906, 5559.221708960748, 5577.44225577441, 5594.216809646653, 5632.408597097307, 855.1856107869778, 5392.865178370572, 5459.836204913859, 5480.80817171401, 5602.7758889644365, 5486.780528778844, 5548.973439913629, 5555.916661250085, 5557.6109672322955, 5559.27729395176, 5453.100238460649, 5455.318085239345, 5522.475364840334, 5526.557875369961, 5531.612787104904, 6173.382661733835, 6213.347598502182, 6235.376462353728, 6308.148399965991, 6315.305316415191, 6750.392464678153, 6766.560173588701, 6774.458043356569, 6854.760083397057, 6865.794026179223, 6774.695138718769, 6842.55523390978, 6852.828679283008, 6862.553933994274, 6932.688029038655, 6243.0, 6302.96848515759, 6310.148130002437, 6314.0, 6327.987521996459, 6941.722331106699, 6956.0, 6979.581225126509, 7006.859862802818, 7009.9299007010295, 6674.899876501856, 6743.662816859138, 6746.561473504206, 6753.527253092237, 6759.797206083826, 6783.491238157059, 6785.219699734495, 6807.149106361705, 6879.58722476643, 6887.965560172167, 6600.86798264032, 6704.966475167593, 6710.798676039727, 6712.597244165366, 6798.558093723892, 6782.728690852409, 6794.660266986631, 6806.115205023315, 6812.352826481477, 6832.965835170792, 4880.267959805987, 4952.182889823211, 5007.974960125176, 5017.9247311290355, 5020.3724534433195, 4365.454318210223, 4398.4941731701265, 4408.977955110204, 4430.246858034142, 4462.598366147063, 6457.773977910751, 6513.023046542961, 6514.348565143448, 6534.411902928759, 6535.411812936763, 6843.931560684329, 6852.246252912176, 6896.344847239504, 6909.689063992132, 6925.688344024531, 6343.777967771106, 6415.871682566322, 6419.935800642027, 6424.839379015469, 6430.77492287777, 5620.831375058755, 5648.491635752802, 5668.348139963957, 5679.858003549944, 5682.374938756733, 7175.712971481186, 7176.7129314826825, 7215.278472152742, 7250.6374681265725, 7265.0, 6783.084283621845, 6800.0, 6879.449644028466, 6884.070650462324, 6894.345037221456, 7047.119110111236, 7166.140063192451, 7228.421726261781, 7269.854602907806, 7273.3453989141235, 6657.201135863727, 6718.596884186769, 6741.325867413219, 6743.797686069426, 6774.559653622498, 6839.965800170968, 6846.4522838173825, 6889.965550172217, 6922.7923162305215, 6942.479314051366, 6617.172853393326, 6635.336466353519, 6686.930091185387, 6701.128853249046, 6702.966485167543, 6420.967895160495, 6513.934860651333, 6538.280789113099, 6612.603243805387, 6643.368879956396, 6540.182477190351, 6565.573237739723, 6614.6361950093205, 6615.966920165369, 6620.933790662032, 6605.207375115017, 6619.470442364695, 6683.43190828785, 6694.799156025327, 6708.597484150967, 6333.96833015832, 6392.808215753535, 6432.067350234283, 6457.4188323049275, 6479.287278399278], "value": 6159.760388434433, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.284875733733, 843.1526316052366, 875.4484674654966, 877.1754550722325, 1197.4551579031543, 34378.31243375131, 45137.0, 45294.32058519125, 45444.54555454444, 45619.543804561945, 45639.54360456394, 46019.0, 46053.30920036202, 46077.539224607746, 46120.308195377096, 7505.324520793155, 7838.098618658842, 7954.642041108164, 8079.0709068457, 8099.959500202491, 4534.1158474097565, 4560.3843481130125, 4565.360849481075, 4594.609458196057, 5005.199168133099, 4513.548645135476, 4547.045120524694, 4564.8174073037035, 4568.497465278817, 4576.519465456129, 4560.498345182027, 4568.931466028012, 4571.954280457219, 4579.931301030487, 4608.101420223058, 7863.646135923897, 7923.326517246041, 7930.484518506279, 7933.167017463141, 7935.127136015034, 41982.37026444606, 42018.0, 42275.154496910196, 42355.36466952967, 42408.0, 3454.7236221102253, 3478.808665523395, 3479.373712731707, 3485.5643044619424, 3490.301939612062, 32547.837260813543, 32659.836700816344, 32714.182145446553, 32729.0, 32737.345253094805, 5075.111855425286, 5183.352080989876, 5203.427622961472, 5204.089284375257, 5223.660462069991, 209811.0, 210006.0, 210534.0, 212175.0, 212803.0, 52797.0, 52974.73512632412, 53259.73370133124, 53313.73343133259, 53363.19955200675, 57090.42909570934, 57103.71448142733, 57110.714446427504, 57163.0, 57189.71405143031, 15838.683226335408, 16052.197390130681, 16066.919665401598, 16068.276927538289, 16077.517674469784, 12089.395530223454, 12224.938875305566, 13820.723585528232, 13978.02153849241, 14065.367058482392, 15749.763753543706, 15777.68444631101, 15778.368865245491, 15810.814188935876, 15817.604559886095, 5148.700834802907, 5246.97376513115, 5277.419483856773, 5309.681419114865, 5358.169483730028, 1069.668402795136, 1087.7117563845586, 1103.45379037377, 1110.7945030169444, 1110.9944450277696, 24704.5059098817, 24792.752072479045, 24816.751832481805, 24976.75023249744, 25004.749952500606, 1176.8587769467547, 1182.131133616791, 1191.368574655434, 1194.75507520958, 1203.9398030098635, 12935.935320323526, 13188.261787895266, 13327.400266988008, 13333.200007999358, 13526.18842869431, 259043.0, 260892.0, 274828.0, 277023.0, 277647.0, 59435.10847337294, 59678.0, 59831.40168598345, 59882.40117598768, 60037.0, 1488.78412630168, 1530.709165258575, 1531.111955066059, 1535.155664384584, 1563.0309208290932, 24676.259712208663, 24799.876000619883, 24841.006359745406, 24853.25440236796, 24884.253472395856, 38606.61393386142, 42097.78951105225, 42139.367909481996, 42187.7890610545, 42214.788926055175, 221069.0, 223495.0, 224088.0, 224173.0, 224515.0, 34444.48333274953, 35078.824605876805, 37521.24957500889, 37611.2477750438, 37850.810745946095, 48365.758171208916, 48387.0, 48663.756681216364, 48719.26921096257, 49067.0, 34975.82512087423, 36561.0, 37888.81055594704, 37982.620173797906, 38175.6182438172, 36232.27535449329, 36570.63429365672, 36720.0, 36778.0, 36903.261934760616, 41830.79084604557, 41859.7907010463, 41990.0, 42037.57962420459, 42221.36667950045, 36098.819505902306, 36117.819410902775, 36233.45649815308, 36276.63723362838, 36639.45040824443, 26646.0, 26743.06399276016, 26770.866145669148, 26850.865745671148, 26964.595531066658], "value": 17642.28585531287, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.1591244957463, 579.5537436174146, 580.5848818095061, 583.5856541855284, 591.952643788497, 22135.778642213572, 22940.311790646265, 22941.42646433838, 22945.082196712112, 23370.298891033253, 27359.179224623294, 27672.169834904933, 27797.861010694967, 27842.0, 27843.86078069612, 6373.7131829067575, 6484.157059582248, 6525.347465253484, 6526.379993900572, 6532.738690452376, 6128.785492507763, 6386.776462823802, 6427.421532062113, 6432.45324147448, 6440.6135631862235, 6354.618722876642, 6405.391487808651, 6411.743530258784, 6430.871382572346, 6431.42117209451, 6438.452731517827, 6443.516736244777, 6443.581167224115, 6447.806565803034, 6450.7742229022, 5966.492848107872, 5972.044472884361, 5998.940010599882, 6010.489108425789, 6011.909821352683, 30598.0, 30627.23431914198, 31044.068677939696, 31650.841745791353, 31686.524702129485, 2230.2751605728204, 2286.6455699366625, 2286.9771302287095, 2287.4853158039473, 2287.6911616931834, 20177.495562610904, 20373.185072597225, 21205.469863253387, 21215.68176477354, 21221.257255995966, 2480.6899137607797, 2506.9749302506925, 2551.4386834896295, 2554.5529532331952, 2558.4243545202366, 162734.0, 165291.0, 166968.0, 167061.0, 167574.64850702917, 15699.215039247993, 15823.446179383667, 15978.52332888404, 16084.85284708882, 16146.354145834035, 24184.153554625504, 24230.7576924232, 24275.63586546203, 24311.513769724508, 24333.39166520851, 10398.480075996169, 10481.266311358284, 10499.317544359592, 10531.94734026325, 10627.893721062845, 8819.29445644347, 9328.626854925862, 9545.284103692251, 9602.66390676323, 9721.319507634538, 9508.47703376307, 10081.596736130472, 10124.797504049879, 10154.441505717108, 10180.287379883484, 2786.3591373984095, 2809.269589906598, 2824.8587570621385, 2834.6031555582235, 2918.3725499017387, 1045.6235755128148, 1052.6894566103017, 1058.539535302146, 1063.4948399510326, 1067.1036329483272, 21400.357989260036, 21656.20991370069, 21674.566508669737, 21747.0, 21932.342029739135, 1106.8284415915546, 1107.4407424250862, 1114.1142791480793, 1114.7046032801313, 1115.520326259716, 11582.131340149353, 12110.636680899586, 12116.0, 12126.514939402325, 12138.27170369763, 564443.0, 569736.0, 573882.0, 582154.0, 597058.0, 117367.0, 120215.0, 120586.0, 121042.0, 132133.33933330272, 1217.3000524698157, 1239.962801115968, 1247.0647014738765, 1255.3660401497186, 1261.6404324767582, 18903.621927561646, 19077.332293369665, 19387.2245110194, 19445.61108777845, 19565.8043419564, 63660.68169659122, 64158.679206605535, 64847.35152648413, 64990.0, 65582.34417655763, 76493.0, 76842.0, 77224.0, 77227.61386193032, 77847.0, 15409.84590154084, 15542.0, 15667.764983525029, 15796.289166987282, 15822.68354632924, 25067.74932250654, 25313.0, 25496.362590935376, 25552.616710748982, 25738.613920790827, 20345.28791492291, 20612.278570249968, 20716.274930377363, 20854.270100547015, 20896.37310880676, 17604.91197544004, 18103.004334761186, 18316.90841545837, 19083.0, 19299.421017369503, 21036.474088147308, 21207.8939605301, 21925.232616858335, 21938.793366365004, 21976.230831920806, 22253.22113726012, 22823.31530054101, 23241.418964526023, 23300.53398932046, 23343.883280584167, 20349.0, 20718.0, 20825.37523874286, 21036.158553657682, 21048.579028419652], "value": 12926.902390010986, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.8937329700273, 1457.0092337210692, 1478.2460944918091, 1481.6147801571587, 1486.5243122200893, 15726.764098538517, 16288.755668664964, 16332.591685207875, 16396.34414623415, 16536.33854645816, 16520.669586608292, 16824.0, 16836.410725624573, 16843.41048063318, 16854.83145168548, 2515.811314151437, 2562.205716227966, 2570.8200425970144, 2604.4009877728136, 2605.413781899067, 2626.2383908666466, 2647.6028595710604, 2651.5624921887866, 2652.694940081886, 2660.5610074337806, 2620.6462127612813, 2631.697354804203, 2632.921012369632, 2633.73662633737, 2657.6412184355154, 2526.355779276282, 2536.759007894247, 2538.1624064058815, 2542.478791847667, 2544.465662210938, 2458.373114855718, 2464.667269918556, 2465.4822487277693, 2479.355367604418, 2481.5781317176125, 15948.2823272953, 16198.433054843024, 16213.0, 16225.51323460298, 16241.837581624151, 8000.9199908000755, 8299.709510167175, 8300.833983320299, 8305.916940830575, 8307.335413166993, 4815.5906747926465, 4985.35190425246, 5027.2207807789855, 5043.344365232496, 5044.697318160922, 1901.1634880652607, 1912.741779859729, 1929.5947850951318, 1959.9314024009234, 1966.5771859050374, 28304.43391132166, 28489.430211395655, 28560.28599285014, 28632.0, 28690.71309286922, 6484.935150648527, 6490.935090649127, 6549.148610680581, 6638.5684930479365, 6645.0, 18631.254749809857, 18685.15916783748, 18695.439136825917, 18750.906245468686, 18759.624807503773, 7904.486208396436, 7998.200179981955, 7999.28006479408, 8000.839983200303, 8005.0, 7301.744438944612, 7310.268973102648, 7377.594232317167, 7407.259274072658, 7415.480916335912, 5186.196139598368, 5378.112611419072, 5385.192221166856, 5429.348478182565, 5479.479449452296, 1503.225838693074, 1505.4204131409485, 1516.5071351810705, 1526.9083854968515, 1536.8232653244797, 895.1451363947544, 906.9909300906907, 910.5765818894246, 924.7410724997012, 934.0332755597924, 7798.142204357516, 7864.960675196702, 7948.920510794934, 7956.641951112214, 8038.276555110068, 730.1858427853011, 730.397422126737, 734.4895297767999, 734.8016035670446, 742.4876834983844, 4875.268709693574, 5229.764660590283, 5382.708150043961, 5529.806456773994, 5752.338481074647, 22668.206612768474, 22778.0, 23381.298561042855, 23719.0, 23909.52180956371, 22950.311490655306, 22972.65541016886, 22980.885095574416, 23147.421314467272, 23160.3051908443, 11815.940920295343, 12472.69036751128, 12729.809052864215, 12799.296038717774, 12819.29493877827, 9817.165540929102, 9886.851697224403, 9904.752381190528, 10040.64857729976, 10081.14310283634, 20487.79512204859, 20501.0, 20816.687749684068, 21360.78639213588, 21587.13651453924, 20633.277835275694, 20955.8952205238, 21087.8945605271, 21150.788492114883, 21666.458338541663, 12862.871371286168, 13018.869811301765, 13065.346732663138, 13117.147385419918, 13137.868621313664, 14731.558053258419, 14800.037997530126, 14865.331060102106, 14872.628184295047, 14891.851081489047, 11543.653690389301, 12011.399430028638, 12048.156629035884, 12080.093992950387, 12128.454219559964, 11333.943330283295, 11401.258918170292, 11770.470328835394, 11777.352245626573, 11818.704532386415, 10437.32157409766, 10643.574257029943, 10658.987396197348, 10682.359058456517, 10692.732681683021, 7578.280063393969, 7827.530348179127, 7835.138134805053, 7895.973523441916, 7972.0, 7842.568658723597, 7894.289513943772, 7899.84200315979, 7982.321502672338, 8027.839443210986], "value": 6883.360565703418, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.706461446205, 539.2100572661051, 546.0525987411839, 547.4908335248217, 551.8620344913769, 6148.907766383508, 6185.783497577586, 6191.4737247333915, 6201.59689620175, 6215.0, 6114.449699527042, 6138.0, 6148.508119350452, 6173.9073913891325, 6192.876142477147, 4909.937826113023, 4914.336564563791, 4919.188333924907, 4919.680220785655, 4923.986950635239, 4729.881752956169, 4815.133276010317, 4895.926561101586, 4907.852764417074, 4924.2121260598315, 4773.284007398901, 4779.784909679055, 4787.353707249529, 4800.8319708810195, 4807.351007613962, 4776.761161941889, 4809.735464549449, 4811.158047341736, 4816.590589799871, 4836.331465644347, 4247.150569886036, 4312.956870431287, 4324.827006919751, 4341.45731783527, 4344.326629372453, 5996.940030599725, 5997.1304160896425, 6015.699215039231, 6030.819075427744, 6063.332583539518, 5704.486596206361, 5721.284839395075, 5738.8278351649515, 5745.770169193269, 5767.0, 6506.707198176046, 6554.541182117254, 6591.571547849375, 6610.504212184105, 6611.206655201358, 4920.3111564381015, 4960.784607771119, 4961.8511444656715, 4963.950360496421, 4983.053219888209, 6467.676616169173, 6633.237177724575, 6693.665316734193, 6742.966285168592, 6894.620795856226, 4999.500049995044, 5015.623828212899, 5017.82437614689, 5053.231369020813, 5059.215821547666, 5060.051880026216, 5069.214271787879, 5104.336436263262, 5108.489151084862, 5123.666961647481, 5669.801556945569, 5672.014794821779, 5676.807870347273, 5684.658920464785, 5695.0, 6496.603230305507, 6496.765614533224, 6499.805005849832, 6508.251551071593, 6567.704453299613, 1760.850327722158, 1761.216258764863, 1763.5326638440824, 1765.8675599330102, 1804.2512357371807, 1187.5962172861268, 1241.4289426863563, 1248.7752204603078, 1263.1536870296923, 1264.1846009323936, 6152.261728592597, 6227.501799856001, 6259.18630578031, 6260.0, 6283.711839072979, 6511.939388487403, 6598.27418983921, 6601.570897891717, 6632.50256230775, 6642.3394151462135, 6198.5907750736915, 6200.906986395117, 6241.625502469776, 6286.214223222098, 6315.305316415191, 3492.301539692072, 3496.7727097739075, 3511.613722490524, 3531.234382808596, 3532.523109380252, 7063.011178435023, 7076.752313668997, 7110.1823290321245, 7116.181639111568, 7116.64416779159, 6194.5973511721595, 6789.1174147360525, 6802.353776391334, 6825.829354266084, 6851.862962740817, 6834.760783372558, 6949.687264073129, 6987.510874238857, 7024.929750702529, 7044.0, 6516.837079073061, 6636.900446493403, 6664.600123992576, 6669.799906002828, 6720.361565651256, 6586.2755096940255, 6600.53796234259, 6617.966910165418, 6645.471541545375, 6676.432503237085, 7148.249433809375, 7173.350129470146, 7176.576963617637, 7182.3535881770895, 7212.350888420067, 4974.253861920741, 5046.0, 5059.873503162303, 5074.492550744896, 5101.7704203310195, 6444.581102228341, 6507.0, 6620.271770105381, 6752.966235168793, 6809.5573787705625, 6398.648074355955, 6412.35876412355, 6440.194975628046, 6531.11829902957, 6543.312952140147, 6447.838804029937, 6484.902726459012, 6527.738890444519, 6548.607083575001, 6556.704948277244, 6453.193350831146, 6482.35176482348, 6532.150820393318, 6540.934590654223, 6601.966990165019, 6493.512986526124, 6547.148870646785, 6570.8028759137305, 6602.0, 6608.669566521751, 6348.746050157942, 6375.075614036032, 6377.23473183221, 6396.104545363746, 6422.0], "value": 5092.062054808893, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [923.3813345058815, 943.1652987106413, 950.9001554836742, 951.6478902805962, 972.4311277902425, 18313.908430457846, 19119.426417207487, 19136.904315478405, 19147.42557723267, 19896.0, 18280.634387312246, 18373.173207205673, 18474.90762546189, 18500.907495462503, 18563.0, 1015.4618052432223, 1038.376973815712, 1038.3977293169944, 1039.0856046678912, 1040.9479526023667, 1033.8552602635637, 1066.7119877633074, 1079.0288740133904, 1079.913606911449, 1080.1250986700798, 1147.0, 1147.5123072694114, 1147.7589706161716, 1147.8163493840987, 1154.2497376705167, 1131.6378758797189, 1131.6661584832452, 1132.3488993828541, 1133.9546418143264, 1140.0822338017892, 827.4786884262936, 827.7475370012144, 829.9004119505638, 830.6303694855792, 839.7774589733725, 19256.623368834462, 19447.902760486108, 19528.31650892226, 19572.21711131553, 19716.901415492976, 21714.674279885814, 21739.34781956544, 22134.55730885373, 22195.22316718907, 22435.214767483223, 15845.445409410615, 16080.356785728556, 16112.11383373914, 16163.757543636855, 16183.919080404641, 3673.94489082664, 3682.3371793077504, 3694.131879008431, 3694.3165514379925, 3696.31618150643, 137213.0, 137986.0, 138275.0, 138778.0, 142322.99999999895, 17718.0, 17884.642307154045, 18184.545386365447, 18206.544836378933, 18241.63516729658, 32150.678493215237, 32829.507557386656, 33111.0, 33161.66838331586, 33183.834080829925, 9528.380655257386, 9659.613615455444, 9833.852492212622, 9962.800743985224, 9976.102150806462, 4241.554636763157, 4367.344898265285, 4378.890527736832, 4682.742449165328, 4765.070811191785, 6161.507079433634, 6359.046143078575, 6401.3598640135615, 6406.4554512866, 6424.903626445607, 1132.2866594045863, 1169.7134202120535, 1185.1940680337282, 1185.727282724978, 1188.9524419023141, 728.2790037862478, 735.8969744235811, 744.7318965172534, 750.6584504050644, 756.08136114621, 11331.943340283411, 12016.819747703792, 13016.479340826261, 13589.167041422956, 13609.31953402326, 678.3420082519948, 686.4885660183096, 696.0985523746659, 707.3598393453932, 725.1189804387694, 5769.134629805562, 5834.591578589458, 6128.938710612926, 6175.228096487939, 6242.5942313749465, 127776.0, 130564.3471782654, 134150.0, 139944.0, 139976.0, 117395.0, 120052.39973800075, 123453.38273308576, 123566.38216809038, 125062.0, 103032.9999999985, 103172.0, 103251.0, 110029.44985275023, 110669.44665276783, 13266.071375003658, 13667.726645466835, 13798.724025519634, 13800.171989680652, 13806.585802425943, 48745.0, 49877.50122498874, 50341.0, 50804.0, 51740.482595175075, 82371.0, 84075.5796221015, 84479.0, 84750.0, 86475.0, 19237.61524769525, 19439.319623813666, 19595.70606440876, 19619.803801962367, 19658.41024769259, 20815.1673933041, 21027.15891364328, 21267.680984784933, 21459.570808584052, 21725.456863578536, 17923.462296131136, 18002.279908803503, 18460.538486537946, 18995.145218464924, 19600.509987250432, 16213.35146594123, 16653.0, 16847.15764211809, 16978.320867165174, 17238.0, 16790.412335568195, 17244.482665520056, 18993.14530846143, 19386.407689586315, 19607.901960490108, 14493.347799349265, 14570.854291457374, 14738.11571305725, 14773.0, 14803.407863685332, 14830.0, 14999.400023998918, 15233.466828660943, 15279.159646219565, 15502.612434689223], "value": 9854.718642481716, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1468.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 108.0, 100.0, 107.0, 105.0, 108.0, 116.0, 98.0, 107.0, 108.0], "value": 105.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 112.0, 148.0, 179.0, 113.0, 138.0, 142.0, 114.0, 165.0, 114.0], "value": 136.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 164.0, 164.0, 164.0, 164.0, 163.0, 163.0, 166.0, 167.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 129.0, 74.0, 74.0, 73.0, 98.0, 101.0, 74.0], "value": 84.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 138.0, 77.0, 78.0, 79.0, 135.0, 77.0, 78.0, 124.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 113.0, 113.0, 114.0, 114.0, 114.0, 115.0, 113.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 148.0, 147.0, 149.0, 150.0, 148.0, 147.0], "value": 148.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 95.0, 94.0, 94.0, 94.0, 94.0, 95.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 48.0, 49.0, 51.0, 48.0, 50.0, 51.0, 52.0, 50.0], "value": 49.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 57.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 73.0, 80.0, 74.0, 76.0, 73.0, 73.0, 75.0, 73.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 121.0, 127.0, 117.0, 120.0, 116.0, 118.0, 114.0, 115.0, 162.0], "value": 124.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 159.0, 160.0, 163.0, 162.0, 161.0, 166.0, 161.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 62.0, 62.0, 65.0, 60.0, 61.0, 67.0, 61.0, 61.0], "value": 62.2, "unit": "score"}]}]} 16:00:03 INFO - Return code: 0 16:00:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:00:03 INFO - # TBPL SUCCESS # 16:00:03 INFO - Running post-action listener: _package_coverage_data 16:00:03 INFO - Running post-action listener: _resource_record_post_action 16:00:03 INFO - [mozharness: 2017-08-15 23:00:03.873991Z] Finished run-tests step (success) 16:00:03 INFO - Running post-run listener: _resource_record_post_run 16:00:03 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 8192; Write bytes: 426717184; Read time: 60; Write time: 320584 16:00:03 INFO - TinderboxPrint: CPU usage
13.1% 16:00:03 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 60 16:00:03 INFO - TinderboxPrint: I/O write bytes / time
426,717,184 / 320,584 16:00:03 INFO - TinderboxPrint: CPU idle
8,235.5 (86.9%) 16:00:03 INFO - TinderboxPrint: CPU user
1,187.9 (12.5%) 16:00:03 INFO - TinderboxPrint: Swap in / out
0 / 0 16:00:03 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 107995136; Read time: 48; Write time: 135556 16:00:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:00:03 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 318722048; Read time: 0; Write time: 185028 16:00:04 INFO - Running post-run listener: _upload_blobber_files 16:00:04 INFO - Blob upload gear active. 16:00:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:00:04 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 16:00:04 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'] 16:00:04 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 16:00:04 INFO - (blobuploader) - INFO - Open directory for files ... 16:00:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:00:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:00:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:06 INFO - (blobuploader) - INFO - Done attempting. 16:00:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:00:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:00:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:08 INFO - (blobuploader) - INFO - Done attempting. 16:00:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:00:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:00:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:14 INFO - (blobuploader) - INFO - Done attempting. 16:00:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:00:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:00:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:00:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:00:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:00:15 INFO - (blobuploader) - INFO - Done attempting. 16:00:15 INFO - (blobuploader) - INFO - Iteration through files over. 16:00:15 INFO - Return code: 0 16:00:15 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:00:15 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:00:15 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9a75d4f5425de5cb4eabc50c1c5cb2453c07e82a008ca0ea176b52643c676fc61c0dda934d856e783ec14999e381bcdf701ee6fe586011abf3718a91a5ed415", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b8107fb54beb736d6845895509f2db0dd611c9b2975980310fea5702fa335abb04bddcaf8488908b32a9ad767c75321af629b3c009007043c6ad89be5f7a0d0", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2948a84846a6a897f8e69410e5c7c3627e4b2044d81672d063849e291055adb2829d5467c256bf9cb379d71e9b716275564520ad869deb0cddf0537c5b6f3294", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d83d6104c946882ad099841be04ba85b36cedc041ef543e79f32f48ae911787069f2d506d37c3039412eb266f93c4da6649981a0b4994a280f5ff903cb00a00a"} 16:00:15 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:00:15 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:00:15 INFO - Contents: 16:00:15 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9a75d4f5425de5cb4eabc50c1c5cb2453c07e82a008ca0ea176b52643c676fc61c0dda934d856e783ec14999e381bcdf701ee6fe586011abf3718a91a5ed415", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b8107fb54beb736d6845895509f2db0dd611c9b2975980310fea5702fa335abb04bddcaf8488908b32a9ad767c75321af629b3c009007043c6ad89be5f7a0d0", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2948a84846a6a897f8e69410e5c7c3627e4b2044d81672d063849e291055adb2829d5467c256bf9cb379d71e9b716275564520ad869deb0cddf0537c5b6f3294", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d83d6104c946882ad099841be04ba85b36cedc041ef543e79f32f48ae911787069f2d506d37c3039412eb266f93c4da6649981a0b4994a280f5ff903cb00a00a"} 16:00:15 INFO - Running post-run listener: copy_logs_to_upload_dir 16:00:15 INFO - Copying logs to upload dir... 16:00:15 INFO - mkdir: /builds/slave/test/build/upload/logs 16:00:15 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.616859 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-08-15 16:00:14.933469) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-15 16:00:14.937695) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9a75d4f5425de5cb4eabc50c1c5cb2453c07e82a008ca0ea176b52643c676fc61c0dda934d856e783ec14999e381bcdf701ee6fe586011abf3718a91a5ed415", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b8107fb54beb736d6845895509f2db0dd611c9b2975980310fea5702fa335abb04bddcaf8488908b32a9ad767c75321af629b3c009007043c6ad89be5f7a0d0", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2948a84846a6a897f8e69410e5c7c3627e4b2044d81672d063849e291055adb2829d5467c256bf9cb379d71e9b716275564520ad869deb0cddf0537c5b6f3294", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d83d6104c946882ad099841be04ba85b36cedc041ef543e79f32f48ae911787069f2d506d37c3039412eb266f93c4da6649981a0b4994a280f5ff903cb00a00a"} build_url:https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011969 build_url: 'https://queue.taskcluster.net/v1/task/PuGxb2cmSQSD5PpSReO_vw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9a75d4f5425de5cb4eabc50c1c5cb2453c07e82a008ca0ea176b52643c676fc61c0dda934d856e783ec14999e381bcdf701ee6fe586011abf3718a91a5ed415", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b8107fb54beb736d6845895509f2db0dd611c9b2975980310fea5702fa335abb04bddcaf8488908b32a9ad767c75321af629b3c009007043c6ad89be5f7a0d0", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2948a84846a6a897f8e69410e5c7c3627e4b2044d81672d063849e291055adb2829d5467c256bf9cb379d71e9b716275564520ad869deb0cddf0537c5b6f3294", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d83d6104c946882ad099841be04ba85b36cedc041ef543e79f32f48ae911787069f2d506d37c3039412eb266f93c4da6649981a0b4994a280f5ff903cb00a00a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-15 16:00:14.965785) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 16:00:14.966214) ========= 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=82c74bd3bc9a11c5833d2010000001e6-1502836387.61255-1525891231 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-15 16:00:15.054671) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-15 16:00:15.055135) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-15 16:00:15.055552) ========= ========= Total master_lag: 0.20 =========