builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-026 starttime: 1505621105.47 results: success (0) revision: 015f852e93fe8887e666ee5a1ff246d3d4e8063b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:05.465809) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:05.466267) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:05.466562) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:05.487211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:05.487593) ========= 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=41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-16 21:05:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 57.7K=0.2s 2017-09-16 21:05:05 (57.7 KB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.315970 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:05.820266) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:05.821651) ========= 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=41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015932 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:05.871276) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-16 21:05:05.874665) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 015f852e93fe8887e666ee5a1ff246d3d4e8063b --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 015f852e93fe8887e666ee5a1ff246d3d4e8063b --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=41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-16 21:05:05,880 truncating revision to first 12 chars 2017-09-16 21:05:05,880 Setting DEBUG logging. 2017-09-16 21:05:05,880 attempt 1/10 2017-09-16 21:05:05,880 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/015f852e93fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-16 21:05:06,952 unpacking tar archive at: mozilla-central-015f852e93fe/testing/mozharness/ program finished with exit code 0 elapsedTime=1.364321 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-16 21:05:07.255219) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:07.258538) ========= script_repo_revision: 015f852e93fe8887e666ee5a1ff246d3d4e8063b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:07.258902) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:07.259154) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-16 21:05:07.272619) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-09-16 21:05:07.272930) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:05:07 INFO - MultiFileLogger online at 20170916 21:05:07 in /builds/slave/test 21:05:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 21:05:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:05:07 INFO - {'append_to_log': False, 21:05:07 INFO - 'base_work_dir': '/builds/slave/test', 21:05:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 21:05:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:05:07 INFO - 'branch': 'Firefox-Non-PGO', 21:05:07 INFO - 'buildbot_json_path': 'buildprops.json', 21:05:07 INFO - 'code_coverage': False, 21:05:07 INFO - 'config_files': ('talos/linux_config.py',), 21:05:07 INFO - 'default_actions': ('clobber', 21:05:07 INFO - 'read-buildbot-config', 21:05:07 INFO - 'download-and-extract', 21:05:07 INFO - 'populate-webroot', 21:05:07 INFO - 'create-virtualenv', 21:05:07 INFO - 'install', 21:05:07 INFO - 'setup-mitmproxy', 21:05:07 INFO - 'run-tests'), 21:05:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:05:07 INFO - 'disable_ccov_upload': False, 21:05:07 INFO - 'disable_stylo': False, 21:05:07 INFO - 'download_minidump_stackwalk': True, 21:05:07 INFO - 'download_symbols': 'ondemand', 21:05:07 INFO - 'enable_stylo': False, 21:05:07 INFO - 'enable_webrender': False, 21:05:07 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 21:05:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:05:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:05:07 INFO - 'gecko_profile': False, 21:05:07 INFO - 'gecko_profile_interval': 0, 21:05:07 INFO - 'installer_path': 'installer.exe', 21:05:07 INFO - 'log_level': 'info', 21:05:07 INFO - 'log_name': 'talos', 21:05:07 INFO - 'log_to_console': True, 21:05:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:05:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:05:07 INFO - 'opt_config_files': (), 21:05:07 INFO - 'pip_index': False, 21:05:07 INFO - 'suite': 'dromaeojs-e10s', 21:05:07 INFO - 'system_bits': '32', 21:05:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:05:07 INFO - 'title': 'talos-linux64-ix-026', 21:05:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:05:07 INFO - 'use_talos_json': True, 21:05:07 INFO - 'verify': 'False', 21:05:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:05:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:05:07 INFO - 'work_dir': 'build'} 21:05:07 INFO - [mozharness: 2017-09-17 04:05:07.422873Z] Running clobber step. 21:05:07 INFO - Running pre-action listener: _resource_record_pre_action 21:05:07 INFO - Running main action method: clobber 21:05:07 INFO - rmtree: /builds/slave/test/build 21:05:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:05:08 INFO - Running post-action listener: _resource_record_post_action 21:05:08 INFO - [mozharness: 2017-09-17 04:05:08.425227Z] Finished clobber step (success) 21:05:08 INFO - [mozharness: 2017-09-17 04:05:08.425326Z] Running read-buildbot-config step. 21:05:08 INFO - Running pre-action listener: _resource_record_pre_action 21:05:08 INFO - Running main action method: read_buildbot_config 21:05:08 INFO - Using buildbot properties: 21:05:08 INFO - { 21:05:08 INFO - "project": "", 21:05:08 INFO - "product": "firefox", 21:05:08 INFO - "who": "ryanvm@gmail.com", 21:05:08 INFO - "installer_path": "public/build/target.tar.bz2", 21:05:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 21:05:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 21:05:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 21:05:08 INFO - "stage_platform": "linux64", 21:05:08 INFO - "basedir": "/builds/slave/test", 21:05:08 INFO - "buildnumber": 837, 21:05:08 INFO - "platform": "ubuntu64_hw", 21:05:08 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:05:08 INFO - "slavebuilddir": "test", 21:05:08 INFO - "taskId": "FQ-L-BePQ_6gEyi3akNt5Q", 21:05:08 INFO - "branch": "mozilla-central", 21:05:08 INFO - "script_repo_revision": "production", 21:05:08 INFO - "revision": "015f852e93fe8887e666ee5a1ff246d3d4e8063b", 21:05:08 INFO - "slavename": "talos-linux64-ix-026", 21:05:08 INFO - "repo_path": "mozilla-central" 21:05:08 INFO - } 21:05:08 INFO - Finding installer, test and symbols from parent task. 21:05:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FQ-L-BePQ_6gEyi3akNt5Q'}, attempt #1 21:05:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FQ-L-BePQ_6gEyi3akNt5Q'}, attempt #1 21:05:09 INFO - Task dependencies: HL4DSwsdQYWbDYpmGciB1w 21:05:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w'}, attempt #1 21:05:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.tar.bz2 21:05:09 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.test_packages.json 21:05:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.crashreporter-symbols.zip 21:05:09 INFO - Running post-action listener: _resource_record_post_action 21:05:09 INFO - [mozharness: 2017-09-17 04:05:09.778188Z] Finished read-buildbot-config step (success) 21:05:09 INFO - [mozharness: 2017-09-17 04:05:09.778407Z] Running download-and-extract step. 21:05:09 INFO - Running pre-action listener: _resource_record_pre_action 21:05:09 INFO - Running main action method: download_and_extract 21:05:09 INFO - mkdir: /builds/slave/test/build/tests 21:05: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')]} 21:05:09 INFO - trying https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.test_packages.json 21:05:09 INFO - Downloading https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 21:05:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 21:05:10 INFO - Downloaded 989 bytes. 21:05:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 21:05:10 INFO - Using the following test package requirements: 21:05:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 21:05:10 INFO - u'common': [u'target.common.tests.zip'], 21:05:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 21:05:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 21:05:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 21:05:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 21:05:10 INFO - u'mozbase': [u'target.common.tests.zip'], 21:05:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 21:05:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 21:05:10 INFO - u'web-platform': [u'target.common.tests.zip', 21:05:10 INFO - u'target.web-platform.tests.tar.gz'], 21:05:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 21:05:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 21:05:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.common.tests.zip 21:05:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.common.tests.zip'}, attempt #1 21:05:10 INFO - Fetch https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.common.tests.zip into memory 21:05:11 INFO - Content-Length response header: 54731171 21:05:11 INFO - Bytes received: 54731171 21:05:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.talos.tests.zip 21:05:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 21:05:22 INFO - Fetch https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.talos.tests.zip into memory 21:05:22 INFO - Content-Length response header: 14508748 21:05:22 INFO - Bytes received: 14508748 21:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:05:23 INFO - trying https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.tar.bz2 21:05:23 INFO - Downloading https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 21:05:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 21:05:24 INFO - Downloaded 61424265 bytes. 21:05:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.tar.bz2 21:05:24 INFO - mkdir: /builds/slave/test/properties 21:05:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:05:24 INFO - Writing to file /builds/slave/test/properties/build_url 21:05:24 INFO - Contents: 21:05:24 INFO - build_url:https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.tar.bz2 21:05:24 INFO - Running post-action listener: _resource_record_post_action 21:05:24 INFO - Running post-action listener: find_tests_for_verification 21:05:24 INFO - Running post-action listener: set_extra_try_arguments 21:05:24 INFO - [mozharness: 2017-09-17 04:05:24.265100Z] Finished download-and-extract step (success) 21:05:24 INFO - [mozharness: 2017-09-17 04:05:24.265221Z] Running populate-webroot step. 21:05:24 INFO - Running pre-action listener: _resource_record_pre_action 21:05:24 INFO - Running main action method: populate_webroot 21:05:24 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:05:24 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['tresize', 21:05:24 INFO - 'tcanvasmark']}, 21:05:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:05:24 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['dromaeo_css', 21:05:24 INFO - 'kraken']}, 21:05:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:05:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:05:24 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:05:24 INFO - 'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:05:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:05:24 INFO - 'tests': ['damp', 'tps']}, 21:05:24 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:05:24 INFO - 'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['damp', 'tps']}, 21:05:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:05:24 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['dromaeo_dom']}, 21:05:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 21:05:24 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['basic_compositor_video', 21:05:24 INFO - 'glvideo']}, 21:05:24 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 21:05:24 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 21:05:24 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:05:24 INFO - 'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['ts_paint_webext', 21:05:24 INFO - 'tp5o_webext']}, 21:05:24 INFO - 'other-e10s': {'tests': ['a11yr', 21:05:24 INFO - 'ts_paint', 21:05:24 INFO - 'tpaint', 21:05:24 INFO - 'sessionrestore', 21:05:24 INFO - 'sessionrestore_many_windows', 21:05:24 INFO - 'sessionrestore_no_auto_restore', 21:05:24 INFO - 'tabpaint']}, 21:05:24 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['a11yr', 21:05:24 INFO - 'ts_paint', 21:05:24 INFO - 'tpaint', 21:05:24 INFO - 'sessionrestore', 21:05:24 INFO - 'sessionrestore_many_windows', 21:05:24 INFO - 'sessionrestore_no_auto_restore', 21:05:24 INFO - 'tabpaint']}, 21:05:24 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 21:05:24 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 21:05:24 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['perf_reftest_singletons']}, 21:05:24 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['bloom_basic']}, 21:05:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:05:24 INFO - 'tsvgr_opacity', 21:05:24 INFO - 'tart', 21:05:24 INFO - 'tscrollx', 21:05:24 INFO - 'tsvg_static']}, 21:05:24 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['tsvgx', 21:05:24 INFO - 'tsvgr_opacity', 21:05:24 INFO - 'tart', 21:05:24 INFO - 'tscrollx', 21:05:24 INFO - 'tsvg_static']}, 21:05:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:05:24 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:05:24 INFO - 'talos_options': ['--disable-stylo'], 21:05:24 INFO - 'tests': ['tp5o']}, 21:05:24 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:05:24 INFO - 'talos_options': ['--mitmproxy', 21:05:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:05:24 INFO - '--firstNonBlankPaint'], 21:05:24 INFO - 'tests': ['tp6_google', 21:05:24 INFO - 'tp6_youtube', 21:05:24 INFO - 'tp6_amazon', 21:05:24 INFO - 'tp6_facebook']}, 21:05:24 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:05:24 INFO - 'talos_options': ['--disable-stylo', 21:05:24 INFO - '--mitmproxy', 21:05:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:05:24 INFO - '--firstNonBlankPaint'], 21:05:24 INFO - 'tests': ['tp6_google', 21:05:24 INFO - 'tp6_youtube', 21:05:24 INFO - 'tp6_amazon', 21:05:24 INFO - 'tp6_facebook']}, 21:05:24 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 21:05:24 INFO - 'talos_options': ['--stylo-threads=1', 21:05:24 INFO - '--mitmproxy', 21:05:24 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 21:05:24 INFO - '--firstNonBlankPaint'], 21:05:24 INFO - 'tests': ['tp6_google', 21:05:24 INFO - 'tp6_youtube', 21:05:24 INFO - 'tp6_amazon', 21:05:24 INFO - 'tp6_facebook']}, 21:05:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:05:24 INFO - 'talos_options': ['--xperf_path', 21:05:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:05:24 INFO - 'tests': ['tp5n']}, 21:05:24 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 21:05:24 INFO - 'talos_options': ['--disable-stylo', 21:05:24 INFO - '--xperf_path', 21:05:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:05:24 INFO - 'tests': ['tp5n']}}} 21:05:24 INFO - Running post-action listener: _resource_record_post_action 21:05:24 INFO - [mozharness: 2017-09-17 04:05:24.280159Z] Finished populate-webroot step (success) 21:05:24 INFO - [mozharness: 2017-09-17 04:05:24.280249Z] Running create-virtualenv step. 21:05:24 INFO - Running pre-action listener: _resource_record_pre_action 21:05:24 INFO - Running main action method: create_virtualenv 21:05:24 INFO - Creating virtualenv /builds/slave/test/build/venv 21:05:24 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 21:05:24 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 21:05:24 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 21:05:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:05:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:05:24 INFO - Installing distribute.............................................................................................................................................................................................done. 21:05:25 INFO - Installing pip...............done. 21:05:25 INFO - Return code: 0 21:05:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:05:25 INFO - Reading from file tmpfile_stdout 21:05:25 INFO - Output received: 21:05:25 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 21:05:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:05:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:05:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:25 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:25 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 21:05:25 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 21:05:25 INFO - Using env: {'DISPLAY': ':0', 21:05:25 INFO - 'HOME': '/home/cltbld', 21:05:25 INFO - 'LANG': 'en_US.UTF-8', 21:05:25 INFO - 'LANGUAGE': 'en_US:en', 21:05:25 INFO - 'LOGNAME': 'cltbld', 21:05:25 INFO - 'MAIL': '/var/mail/cltbld', 21:05:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:25 INFO - 'MOZ_NO_REMOTE': '1', 21:05:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:25 INFO - 'NO_EM_RESTART': '1', 21:05:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:25 INFO - 'PWD': '/builds/slave/test', 21:05:25 INFO - 'SHELL': '/bin/bash', 21:05:25 INFO - 'SHLVL': '1', 21:05:25 INFO - 'TERM': 'linux', 21:05:25 INFO - 'TMOUT': '86400', 21:05:25 INFO - 'USER': 'cltbld', 21:05:25 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:25 INFO - '_': '/tools/buildbot/bin/python'} 21:05:26 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:05:26 INFO - Downloading/unpacking pip>=1.5 21:05:26 INFO - Running setup.py egg_info for package pip 21:05:26 INFO - warning: no files found matching 'pip/cacert.pem' 21:05:26 INFO - warning: no files found matching '*.html' under directory 'docs' 21:05:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:05:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:05:26 INFO - Installing collected packages: pip 21:05:26 INFO - Found existing installation: pip 1.1 21:05:26 INFO - Uninstalling pip: 21:05:26 INFO - Successfully uninstalled pip 21:05:26 INFO - Running setup.py install for pip 21:05:26 INFO - warning: no files found matching 'pip/cacert.pem' 21:05:26 INFO - warning: no files found matching '*.html' under directory 'docs' 21:05:26 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 21:05:26 INFO - no previously-included directories found matching 'docs/_build/_sources' 21:05:26 INFO - Installing pip script to /builds/slave/test/build/venv/bin 21:05:26 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 21:05:26 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 21:05:26 INFO - Successfully installed pip 21:05:26 INFO - Cleaning up... 21:05:26 INFO - Return code: 0 21:05:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:26 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:26 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 21:05:26 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 21:05:26 INFO - Using env: {'DISPLAY': ':0', 21:05:26 INFO - 'HOME': '/home/cltbld', 21:05:26 INFO - 'LANG': 'en_US.UTF-8', 21:05:26 INFO - 'LANGUAGE': 'en_US:en', 21:05:26 INFO - 'LOGNAME': 'cltbld', 21:05:26 INFO - 'MAIL': '/var/mail/cltbld', 21:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:26 INFO - 'MOZ_NO_REMOTE': '1', 21:05:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:26 INFO - 'NO_EM_RESTART': '1', 21:05:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:26 INFO - 'PWD': '/builds/slave/test', 21:05:26 INFO - 'SHELL': '/bin/bash', 21:05:26 INFO - 'SHLVL': '1', 21:05:26 INFO - 'TERM': 'linux', 21:05:26 INFO - 'TMOUT': '86400', 21:05:26 INFO - 'USER': 'cltbld', 21:05:26 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:26 INFO - '_': '/tools/buildbot/bin/python'} 21:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:26 INFO - Downloading/unpacking psutil>=3.1.1 21:05:26 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 21:05:26 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 21:05:26 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 21:05:26 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 21:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:05:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:05:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:05:27 INFO - Installing collected packages: psutil 21:05:27 INFO - Running setup.py install for psutil 21:05:28 INFO - building 'psutil._psutil_linux' extension 21:05:28 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 21:05:28 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 21:05:28 INFO - building 'psutil._psutil_posix' extension 21:05:28 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 21:05:28 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 21:05:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:05:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:05:28 INFO - Successfully installed psutil 21:05:28 INFO - Cleaning up... 21:05:28 INFO - Return code: 0 21:05:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:05:28 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')]} 21:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:28 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')]} 21:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:28 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:28 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 21:05:28 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 21:05:28 INFO - Using env: {'DISPLAY': ':0', 21:05:28 INFO - 'HOME': '/home/cltbld', 21:05:28 INFO - 'LANG': 'en_US.UTF-8', 21:05:28 INFO - 'LANGUAGE': 'en_US:en', 21:05:28 INFO - 'LOGNAME': 'cltbld', 21:05:28 INFO - 'MAIL': '/var/mail/cltbld', 21:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:28 INFO - 'MOZ_NO_REMOTE': '1', 21:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:28 INFO - 'NO_EM_RESTART': '1', 21:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:28 INFO - 'PWD': '/builds/slave/test', 21:05:28 INFO - 'SHELL': '/bin/bash', 21:05:28 INFO - 'SHLVL': '1', 21:05:28 INFO - 'TERM': 'linux', 21:05:28 INFO - 'TMOUT': '86400', 21:05:28 INFO - 'USER': 'cltbld', 21:05:28 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:28 INFO - '_': '/tools/buildbot/bin/python'} 21:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:05:28 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 21:05:28 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 21:05:28 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 21:05:28 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 21:05:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:05:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:05:29 INFO - Installing collected packages: mozsystemmonitor 21:05:29 INFO - Running setup.py install for mozsystemmonitor 21:05:30 INFO - Successfully installed mozsystemmonitor 21:05:30 INFO - Cleaning up... 21:05:30 INFO - Return code: 0 21:05:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:30 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:30 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 21:05:30 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 21:05:30 INFO - Using env: {'DISPLAY': ':0', 21:05:30 INFO - 'HOME': '/home/cltbld', 21:05:30 INFO - 'LANG': 'en_US.UTF-8', 21:05:30 INFO - 'LANGUAGE': 'en_US:en', 21:05:30 INFO - 'LOGNAME': 'cltbld', 21:05:30 INFO - 'MAIL': '/var/mail/cltbld', 21:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:30 INFO - 'MOZ_NO_REMOTE': '1', 21:05:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:30 INFO - 'NO_EM_RESTART': '1', 21:05:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:30 INFO - 'PWD': '/builds/slave/test', 21:05:30 INFO - 'SHELL': '/bin/bash', 21:05:30 INFO - 'SHLVL': '1', 21:05:30 INFO - 'TERM': 'linux', 21:05:30 INFO - 'TMOUT': '86400', 21:05:30 INFO - 'USER': 'cltbld', 21:05:30 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:30 INFO - '_': '/tools/buildbot/bin/python'} 21:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:30 INFO - Downloading/unpacking jsonschema==2.5.1 21:05:30 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 21:05:30 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 21:05:30 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 21:05:30 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 21:05:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:05:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:05:32 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 21:05:32 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 21:05:32 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 21:05:32 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 21:05:32 INFO - Downloading functools32-3.2.3-2.tar.gz 21:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:05:32 INFO - warning: no files found matching '*.txt' 21:05:32 INFO - no previously-included directories found matching 'build' 21:05:32 INFO - no previously-included directories found matching 'dist' 21:05:32 INFO - no previously-included directories found matching '.git*' 21:05:32 INFO - Installing collected packages: jsonschema, functools32 21:05:32 INFO - Running setup.py install for functools32 21:05:32 INFO - warning: no files found matching '*.txt' 21:05:32 INFO - no previously-included directories found matching 'build' 21:05:32 INFO - no previously-included directories found matching 'dist' 21:05:32 INFO - no previously-included directories found matching '.git*' 21:05:32 INFO - Successfully installed jsonschema functools32 21:05:32 INFO - Cleaning up... 21:05:32 INFO - Return code: 0 21:05:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:05:32 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')]} 21:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:32 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')]} 21:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:32 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:32 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 21:05:32 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 21:05:32 INFO - Using env: {'DISPLAY': ':0', 21:05:32 INFO - 'HOME': '/home/cltbld', 21:05:32 INFO - 'LANG': 'en_US.UTF-8', 21:05:32 INFO - 'LANGUAGE': 'en_US:en', 21:05:32 INFO - 'LOGNAME': 'cltbld', 21:05:32 INFO - 'MAIL': '/var/mail/cltbld', 21:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:32 INFO - 'MOZ_NO_REMOTE': '1', 21:05:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:32 INFO - 'NO_EM_RESTART': '1', 21:05:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:32 INFO - 'PWD': '/builds/slave/test', 21:05:32 INFO - 'SHELL': '/bin/bash', 21:05:32 INFO - 'SHLVL': '1', 21:05:32 INFO - 'TERM': 'linux', 21:05:32 INFO - 'TMOUT': '86400', 21:05:32 INFO - 'USER': 'cltbld', 21:05:32 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:32 INFO - '_': '/tools/buildbot/bin/python'} 21:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:05:33 INFO - Cleaning up... 21:05:33 INFO - Return code: 0 21:05:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:05: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')]} 21:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05: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')]} 21:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:33 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:33 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 21:05:33 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 21:05:33 INFO - Using env: {'DISPLAY': ':0', 21:05:33 INFO - 'HOME': '/home/cltbld', 21:05:33 INFO - 'LANG': 'en_US.UTF-8', 21:05:33 INFO - 'LANGUAGE': 'en_US:en', 21:05:33 INFO - 'LOGNAME': 'cltbld', 21:05:33 INFO - 'MAIL': '/var/mail/cltbld', 21:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:33 INFO - 'MOZ_NO_REMOTE': '1', 21:05:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:33 INFO - 'NO_EM_RESTART': '1', 21:05:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:33 INFO - 'PWD': '/builds/slave/test', 21:05:33 INFO - 'SHELL': '/bin/bash', 21:05:33 INFO - 'SHLVL': '1', 21:05:33 INFO - 'TERM': 'linux', 21:05:33 INFO - 'TMOUT': '86400', 21:05:33 INFO - 'USER': 'cltbld', 21:05:33 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:33 INFO - '_': '/tools/buildbot/bin/python'} 21:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:33 INFO - Downloading/unpacking blobuploader==1.2.4 21:05:33 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 21:05:33 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 21:05:33 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 21:05:33 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 21:05:34 INFO - Downloading blobuploader-1.2.4.tar.gz 21:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:05:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:05:34 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 21:05:34 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 21:05:34 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 21:05:34 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 21:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:05:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:05:34 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 21:05:34 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 21:05:34 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 21:05:34 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 21:05:34 INFO - Downloading docopt-0.6.1.tar.gz 21:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:05:35 INFO - Installing collected packages: blobuploader, requests, docopt 21:05:35 INFO - Running setup.py install for blobuploader 21:05:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:05:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:05:35 INFO - Running setup.py install for requests 21:05:35 INFO - Running setup.py install for docopt 21:05:35 INFO - Successfully installed blobuploader requests docopt 21:05:35 INFO - Cleaning up... 21:05:35 INFO - Return code: 0 21:05:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:05:35 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')]} 21:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:35 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')]} 21:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:35 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:35 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 21:05:35 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 21:05:35 INFO - Using env: {'DISPLAY': ':0', 21:05:35 INFO - 'HOME': '/home/cltbld', 21:05:35 INFO - 'LANG': 'en_US.UTF-8', 21:05:35 INFO - 'LANGUAGE': 'en_US:en', 21:05:35 INFO - 'LOGNAME': 'cltbld', 21:05:35 INFO - 'MAIL': '/var/mail/cltbld', 21:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:35 INFO - 'MOZ_NO_REMOTE': '1', 21:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:35 INFO - 'NO_EM_RESTART': '1', 21:05:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:35 INFO - 'PWD': '/builds/slave/test', 21:05:35 INFO - 'SHELL': '/bin/bash', 21:05:35 INFO - 'SHLVL': '1', 21:05:35 INFO - 'TERM': 'linux', 21:05:35 INFO - 'TMOUT': '86400', 21:05:35 INFO - 'USER': 'cltbld', 21:05:35 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:35 INFO - '_': '/tools/buildbot/bin/python'} 21:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:05:35 INFO - Running setup.py (path:/tmp/pip-IzbIeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:05:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:05:35 INFO - Running setup.py (path:/tmp/pip-WhcF4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:05:36 INFO - Running setup.py (path:/tmp/pip-tqFago-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:05:36 INFO - Running setup.py (path:/tmp/pip-DwmsFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:05:36 INFO - Running setup.py (path:/tmp/pip-jN9ulF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:05:36 INFO - Running setup.py (path:/tmp/pip-Gm7wx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:05:36 INFO - Running setup.py (path:/tmp/pip-HnIEAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:05:36 INFO - Running setup.py (path:/tmp/pip-JFGqS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:05:36 INFO - Running setup.py (path:/tmp/pip-IA0DEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:05:36 INFO - Running setup.py (path:/tmp/pip-XRFlWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:05:36 INFO - Running setup.py (path:/tmp/pip-8QMJMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:05:36 INFO - Running setup.py (path:/tmp/pip-Fi3umz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:05:36 INFO - Running setup.py (path:/tmp/pip-zH9EAA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:05:36 INFO - Running setup.py (path:/tmp/pip-GlARvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:36 INFO - Running setup.py (path:/tmp/pip-8iUxQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:05:36 INFO - Running setup.py (path:/tmp/pip-v4lMSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:05:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:05:36 INFO - Running setup.py (path:/tmp/pip-jvS2e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:05:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:05:37 INFO - Running setup.py install for manifestparser 21:05:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:05:37 INFO - Running setup.py install for mozcrash 21:05:37 INFO - Running setup.py install for mozdebug 21:05:37 INFO - Running setup.py install for mozdevice 21:05:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:05:37 INFO - Running setup.py install for mozfile 21:05:37 INFO - Running setup.py install for mozhttpd 21:05:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:05:37 INFO - Running setup.py install for mozinfo 21:05:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:05:37 INFO - Running setup.py install for mozInstall 21:05:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:05:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:05:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:05:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:05:37 INFO - Running setup.py install for mozleak 21:05:37 INFO - Running setup.py install for mozlog 21:05:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:05:38 INFO - Running setup.py install for moznetwork 21:05:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:05:38 INFO - Running setup.py install for mozprocess 21:05:38 INFO - Running setup.py install for mozprofile 21:05:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:05:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:05:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:05:38 INFO - Running setup.py install for mozrunner 21:05:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:05:38 INFO - Running setup.py install for mozscreenshot 21:05:38 INFO - Running setup.py install for moztest 21:05:38 INFO - Running setup.py install for mozversion 21:05:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:05:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:05:38 INFO - Cleaning up... 21:05:38 INFO - Return code: 0 21:05:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:05:38 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')]} 21:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:38 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')]} 21:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:38 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:38 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 21:05:38 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 21:05:38 INFO - Using env: {'DISPLAY': ':0', 21:05:38 INFO - 'HOME': '/home/cltbld', 21:05:38 INFO - 'LANG': 'en_US.UTF-8', 21:05:38 INFO - 'LANGUAGE': 'en_US:en', 21:05:38 INFO - 'LOGNAME': 'cltbld', 21:05:38 INFO - 'MAIL': '/var/mail/cltbld', 21:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:38 INFO - 'MOZ_NO_REMOTE': '1', 21:05:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:38 INFO - 'NO_EM_RESTART': '1', 21:05:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:38 INFO - 'PWD': '/builds/slave/test', 21:05:38 INFO - 'SHELL': '/bin/bash', 21:05:38 INFO - 'SHLVL': '1', 21:05:38 INFO - 'TERM': 'linux', 21:05:38 INFO - 'TMOUT': '86400', 21:05:38 INFO - 'USER': 'cltbld', 21:05:38 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:38 INFO - '_': '/tools/buildbot/bin/python'} 21:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:05:39 INFO - Running setup.py (path:/tmp/pip-YgKNl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:05:39 INFO - Running setup.py (path:/tmp/pip-KduSu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:05:39 INFO - Running setup.py (path:/tmp/pip-uf3rfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:05:39 INFO - Running setup.py (path:/tmp/pip-5GWS_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:05:39 INFO - Running setup.py (path:/tmp/pip-aOh7C4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:05:39 INFO - Running setup.py (path:/tmp/pip-cLEgDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:05:39 INFO - Running setup.py (path:/tmp/pip-sd_1OX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:05:39 INFO - Running setup.py (path:/tmp/pip-3DoG1t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:05:39 INFO - Running setup.py (path:/tmp/pip-QAN_RR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:05:39 INFO - Running setup.py (path:/tmp/pip-stDV4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:05:39 INFO - Running setup.py (path:/tmp/pip-ObARxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:05:39 INFO - Running setup.py (path:/tmp/pip-jXVrs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:05:39 INFO - Running setup.py (path:/tmp/pip-m_KNCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:05:39 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)) 21:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:05:39 INFO - Running setup.py (path:/tmp/pip-xGYd2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:05:40 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)) 21:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:40 INFO - Running setup.py (path:/tmp/pip-opWFIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:05:40 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)) 21:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:05:40 INFO - Running setup.py (path:/tmp/pip-NhjHF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:05:40 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)) 21:05:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:05:40 INFO - Running setup.py (path:/tmp/pip-9DNpo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:05:40 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)) 21:05:40 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)) 21:05:40 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)) 21:05:40 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)) 21:05:40 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)) 21:05:40 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)) 21:05:40 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)) 21:05:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:05:40 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 21:05:40 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 21:05:40 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 21:05:40 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 21:05:41 INFO - Downloading blessings-1.6.tar.gz 21:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:05:41 INFO - Installing collected packages: blessings 21:05:41 INFO - Running setup.py install for blessings 21:05:41 INFO - Successfully installed blessings 21:05:41 INFO - Cleaning up... 21:05:41 INFO - Return code: 0 21:05:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:05:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:05:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:05:41 INFO - Reading from file tmpfile_stdout 21:05:41 INFO - Current package versions: 21:05:41 INFO - argparse == 1.2.1 21:05:41 INFO - blessings == 1.6 21:05:41 INFO - blobuploader == 1.2.4 21:05:41 INFO - docopt == 0.6.1 21:05:41 INFO - functools32 == 3.2.3-2 21:05:41 INFO - jsonschema == 2.5.1 21:05:41 INFO - manifestparser == 1.1 21:05:41 INFO - mozInstall == 1.13 21:05:41 INFO - mozcrash == 1.0 21:05:41 INFO - mozdebug == 0.1 21:05:41 INFO - mozdevice == 0.51 21:05:41 INFO - mozfile == 1.2 21:05:41 INFO - mozhttpd == 0.7 21:05:41 INFO - mozinfo == 0.10 21:05:41 INFO - mozleak == 0.1 21:05:41 INFO - mozlog == 3.5 21:05:41 INFO - moznetwork == 0.27 21:05:41 INFO - mozprocess == 0.25 21:05:41 INFO - mozprofile == 0.28 21:05:41 INFO - mozrunner == 6.13 21:05:41 INFO - mozscreenshot == 0.1 21:05:41 INFO - mozsystemmonitor == 0.3 21:05:41 INFO - moztest == 0.8 21:05:41 INFO - mozversion == 1.4 21:05:41 INFO - psutil == 3.1.1 21:05:41 INFO - requests == 1.2.3 21:05:41 INFO - wsgiref == 0.1.2 21:05:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:05:41 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')]} 21:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:41 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')]} 21:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:41 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:41 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 21:05:41 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 21:05:41 INFO - Using env: {'DISPLAY': ':0', 21:05:41 INFO - 'HOME': '/home/cltbld', 21:05:41 INFO - 'LANG': 'en_US.UTF-8', 21:05:41 INFO - 'LANGUAGE': 'en_US:en', 21:05:41 INFO - 'LOGNAME': 'cltbld', 21:05:41 INFO - 'MAIL': '/var/mail/cltbld', 21:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:41 INFO - 'MOZ_NO_REMOTE': '1', 21:05:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:41 INFO - 'NO_EM_RESTART': '1', 21:05:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:41 INFO - 'PWD': '/builds/slave/test', 21:05:41 INFO - 'SHELL': '/bin/bash', 21:05:41 INFO - 'SHLVL': '1', 21:05:41 INFO - 'TERM': 'linux', 21:05:41 INFO - 'TMOUT': '86400', 21:05:41 INFO - 'USER': 'cltbld', 21:05:41 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:41 INFO - '_': '/tools/buildbot/bin/python'} 21:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:42 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)) 21:05:42 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)) 21:05:42 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)) 21:05:42 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)) 21:05:42 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)) 21:05:42 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)) 21:05:42 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)) 21:05:42 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)) 21:05:42 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)) 21:05:42 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 21:05:42 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 21:05:42 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 21:05:42 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 21:05:42 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 21:05:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 21:05:43 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)) 21:05:43 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)) 21:05:43 INFO - Installing collected packages: simplejson 21:05:43 INFO - Running setup.py install for simplejson 21:05:43 INFO - building 'simplejson._speedups' extension 21:05:43 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 21:05:44 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 21:05:44 INFO - Successfully installed simplejson 21:05:44 INFO - Cleaning up... 21:05:44 INFO - Return code: 0 21:05:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:05:44 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 0x1aa11f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c6aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e0da60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, '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': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', '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 21:05:44 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 21:05:44 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 21:05:44 INFO - Using env: {'DISPLAY': ':0', 21:05:44 INFO - 'HOME': '/home/cltbld', 21:05:44 INFO - 'LANG': 'en_US.UTF-8', 21:05:44 INFO - 'LANGUAGE': 'en_US:en', 21:05:44 INFO - 'LOGNAME': 'cltbld', 21:05:44 INFO - 'MAIL': '/var/mail/cltbld', 21:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:05:44 INFO - 'MOZ_NO_REMOTE': '1', 21:05:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:05:44 INFO - 'NO_EM_RESTART': '1', 21:05:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:05:44 INFO - 'PWD': '/builds/slave/test', 21:05:44 INFO - 'SHELL': '/bin/bash', 21:05:44 INFO - 'SHLVL': '1', 21:05:44 INFO - 'TERM': 'linux', 21:05:44 INFO - 'TMOUT': '86400', 21:05:44 INFO - 'USER': 'cltbld', 21:05:44 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:05:44 INFO - '_': '/tools/buildbot/bin/python'} 21:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:05:44 INFO - Cleaning up... 21:05:44 INFO - Return code: 0 21:05:44 INFO - Running post-action listener: _resource_record_post_action 21:05:44 INFO - Running post-action listener: _start_resource_monitoring 21:05:44 INFO - Starting resource monitoring. 21:05:44 INFO - [mozharness: 2017-09-17 04:05:44.534493Z] Finished create-virtualenv step (success) 21:05:44 INFO - [mozharness: 2017-09-17 04:05:44.535012Z] Running install step. 21:05:44 INFO - Running pre-action listener: _resource_record_pre_action 21:05:44 INFO - Running main action method: install 21:05:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:05:44 INFO - Reading from file tmpfile_stdout 21:05:44 INFO - Detecting whether we're running mozinstall >=1.0... 21:05:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:05:44 INFO - Reading from file tmpfile_stdout 21:05:44 INFO - Output received: 21:05:44 INFO - Usage: mozinstall [options] installer 21:05:44 INFO - Options: 21:05:44 INFO - -h, --help show this help message and exit 21:05:44 INFO - -d DEST, --destination=DEST 21:05:44 INFO - Directory to install application into. [default: 21:05:44 INFO - "/builds/slave/test"] 21:05:44 INFO - --app=APP Application being installed. [default: firefox] 21:05:44 INFO - mkdir: /builds/slave/test/build/application 21:05:44 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'] 21:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 21:06:02 INFO - Reading from file tmpfile_stdout 21:06:02 INFO - Output received: 21:06:02 INFO - /builds/slave/test/build/application/firefox/firefox 21:06:02 INFO - Running post-action listener: _resource_record_post_action 21:06:02 INFO - [mozharness: 2017-09-17 04:06:02.652988Z] Finished install step (success) 21:06:02 INFO - [mozharness: 2017-09-17 04:06:02.653136Z] Running setup-mitmproxy step. 21:06:02 INFO - Running pre-action listener: _resource_record_pre_action 21:06:02 INFO - Running main action method: setup_mitmproxy 21:06:02 INFO - Skipping: mitmproxy is not required 21:06:02 INFO - Running post-action listener: _resource_record_post_action 21:06:02 INFO - [mozharness: 2017-09-17 04:06:02.653875Z] Finished setup-mitmproxy step (success) 21:06:02 INFO - [mozharness: 2017-09-17 04:06:02.654008Z] Running run-tests step. 21:06:02 INFO - Running pre-action listener: _resource_record_pre_action 21:06:02 INFO - Running pre-action listener: _set_gcov_prefix 21:06:02 INFO - Running main action method: run_tests 21:06:02 WARNING - Try message not found. 21:06:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:06:02 INFO - Python 2.7.3 21:06:02 INFO - Return code: 0 21:06:02 INFO - grabbing minidump binary from tooltool 21:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:06:02 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e11090>, '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 0x1e11d80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e0bf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:06:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 21:06:02 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 21:06:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 21:06:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:06:02 INFO - Return code: 0 21:06:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:06:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:06:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:06:02 INFO - ENV: RUST_BACKTRACE is now full 21:06:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:06:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:06:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 21:06:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 21:06:02 INFO - Using env: {'DISPLAY': ':0', 21:06:02 INFO - 'HOME': '/home/cltbld', 21:06:02 INFO - 'LANG': 'en_US.UTF-8', 21:06:02 INFO - 'LANGUAGE': 'en_US:en', 21:06:02 INFO - 'LOGNAME': 'cltbld', 21:06:02 INFO - 'MAIL': '/var/mail/cltbld', 21:06:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:06:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:06:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:06:02 INFO - 'MOZ_NO_REMOTE': '1', 21:06:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:06:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:06:02 INFO - 'NO_EM_RESTART': '1', 21:06:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:06:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:06:02 INFO - 'PWD': '/builds/slave/test', 21:06:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:06:02 INFO - 'RUST_BACKTRACE': 'full', 21:06:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:06:02 INFO - 'SHELL': '/bin/bash', 21:06:02 INFO - 'SHLVL': '1', 21:06:02 INFO - 'TERM': 'linux', 21:06:02 INFO - 'TMOUT': '86400', 21:06:02 INFO - 'USER': 'cltbld', 21:06:02 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:06:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:06:02 INFO - '_': '/tools/buildbot/bin/python'} 21:06:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 21:06:03 INFO - mozversion application_buildid: 20170917031052 21:06:03 INFO - mozversion application_changeset: 015f852e93fe8887e666ee5a1ff246d3d4e8063b 21:06:03 INFO - mozversion application_display_name: Nightly 21:06:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:06:03 INFO - mozversion application_name: Firefox 21:06:03 INFO - mozversion application_remotingname: firefox 21:06:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:06:03 INFO - mozversion application_vendor: Mozilla 21:06:03 INFO - mozversion application_version: 57.0a1 21:06:03 INFO - mozversion platform_buildid: 20170917031052 21:06:03 INFO - mozversion platform_changeset: 015f852e93fe8887e666ee5a1ff246d3d4e8063b 21:06:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:06:03 INFO - mozversion platform_version: 57.0a1 21:06:03 INFO - using testdate: 1505621163 21:06:03 INFO - actual date: 1505621163 21:06:03 INFO - starting webserver on 'localhost:39005' 21:06:03 INFO - SUITE-START | Running 2 tests 21:06:03 INFO - TEST-START | dromaeo_css 21:06:03 INFO - Initialising browser for dromaeo_css test... 21:06:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39005/getInfo.html -profile /tmp/tmpiXoiDN/profile 21:06:03 INFO - TEST-INFO | started process 29831 (/builds/slave/test/build/application/firefox/firefox http://localhost:39005/getInfo.html) 21:06:08 INFO - TEST-INFO | 29831: exit 0 21:06:08 INFO - Browser initialized. 21:06:08 INFO - Running cycle 1/1 for dromaeo_css test... 21:06:08 INFO - Using env: {'DISPLAY': ':0', 21:06:08 INFO - 'HOME': '/home/cltbld', 21:06:08 INFO - 'JSGC_DISABLE_POISONING': '1', 21:06:08 INFO - 'LANG': 'en_US.UTF-8', 21:06:08 INFO - 'LANGUAGE': 'en_US:en', 21:06:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:06:08 INFO - 'LOGNAME': 'cltbld', 21:06:08 INFO - 'MAIL': '/var/mail/cltbld', 21:06:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:06:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:06:08 INFO - 'MOZ_CRASHREPORTER': '1', 21:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:06:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:06:08 INFO - 'MOZ_NO_REMOTE': '1', 21:06:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:06:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:06:08 INFO - 'NO_EM_RESTART': '1', 21:06:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:06:08 INFO - 'PWD': '/builds/slave/test', 21:06:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:06:08 INFO - 'RUST_BACKTRACE': 'full', 21:06:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:06:08 INFO - 'SHELL': '/bin/bash', 21:06:08 INFO - 'SHLVL': '1', 21:06:08 INFO - 'TERM': 'linux', 21:06:08 INFO - 'TMOUT': '86400', 21:06:08 INFO - 'USER': 'cltbld', 21:06:08 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:06:08 INFO - '_': '/tools/buildbot/bin/python'} 21:06:08 INFO - TEST-INFO | started process 30065 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiXoiDN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:06:09 INFO - PID 30065 | 21:06:09 INFO - PID 30065 | (/builds/slave/test/build/application/firefox/firefox:30114): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:06:09 INFO - PID 30065 | 21:06:19 INFO - PID 30065 | RSS: Main: 156536832 21:06:19 INFO - PID 30065 | 21:09:16 INFO - PID 30065 | Cycle 1(1): loaded http://localhost:39005/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39005/tests/dromaeo/cssquery-ext.html) 21:09:17 INFO - PID 30065 | RSS: Main: 156139520 21:09:17 INFO - PID 30065 | 21:12:19 INFO - PID 30065 | Cycle 1(1): loaded http://localhost:39005/tests/dromaeo/cssquery-ext.html (next: http://localhost:39005/tests/dromaeo/cssquery-jquery.html) 21:12:20 INFO - PID 30065 | RSS: Main: 155774976 21:12:20 INFO - PID 30065 | 21:15:22 INFO - PID 30065 | Cycle 1(1): loaded http://localhost:39005/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39005/tests/dromaeo/cssquery-mootools.html) 21:15:22 INFO - PID 30065 | RSS: Main: 157851648 21:15:22 INFO - PID 30065 | 21:18:24 INFO - PID 30065 | Cycle 1(1): loaded http://localhost:39005/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39005/tests/dromaeo/cssquery-prototype.html) 21:18:24 INFO - PID 30065 | RSS: Main: 155467776 21:18:24 INFO - PID 30065 | 21:21:28 INFO - PID 30065 | Cycle 1(1): loaded http://localhost:39005/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39005/tests/dromaeo/cssquery-yui.html) 21:21:28 INFO - PID 30065 | RSS: Main: 156475392 21:21:28 INFO - PID 30065 | 21:24:30 INFO - PID 30065 | Cycle 1(1): loaded http://localhost:39005/tests/dromaeo/cssquery-yui.html (next: http://localhost:39005/tests/dromaeo/cssquery-dojo.html) 21:24:30 INFO - PID 30065 | RSS: Main: 160051200 21:24:30 INFO - PID 30065 | 21:24:30 INFO - PID 30065 | __start_tp_report 21:24:30 INFO - PID 30065 | _x_x_mozilla_page_load 21:24:30 INFO - PID 30065 | _x_x_mozilla_page_load_details 21:24:30 INFO - PID 30065 | |i|pagename|runs| 21:24:30 INFO - PID 30065 | |0;dojo.html;3079.645840728316;3128.124125244932;3143.057082875137;3170.302533442642;3174.1588479053057;6050.152978582991;6161.630302181872;6163.599366041215;6215.471684906783;6267.780627678032;6073.3926607339245;6079.05774604937;6089.573729838913;6134.049222370529;6185.6597887116195;5615.326160860682;5669.149627555858;5685.431456854323;5696.658200507983;5733.598648094635;5772.711364431782;5816.418358164192;5821.476067153955;5828.970855145718;5855.61938474;5740.397258287882;5822.301323841145;5838.182654428383;5878.588498805086;5889.263842019747;839.1947429122661;5676.971615141939;5684.175794509814;5710.314762228518;5730.713464326767;5677.37548869624;5712.828615141552;5733.598648094635;5763.711814409263;5772.913406298909;6162;6287.49700023997;6289.0880822280515;6326.5255105867245;6362.936370636281;6671.93328066718;6688.431483323923;6733.05737196788;6760.661966901635;6781.186257649114;6747.865042699168;6768.966155169242;6791.083203767477;6849.863002739967;6854.9657251713925;6079.483243924272;6148.938510614881;6163.2604087509335;6164.198654174928;6334.556581039329;6861.759838405632;6863.245043045261;6863.6568171591225;6869.0383346330555;6870;6130.386961303834;6512.055751915946;6520.380563846427;6533.18335208099;6606.075149479077;6611.768588099393;6731.360520750521;6752.22349429812;6770.796876093725;6787.694553745093;6583;6730.293319201512;6736.797896063126;6746.797596072126;6749.460043196533;6707.429868461138;6709.329067093349;6730.427913627299;6809.38715515606;6813.931860681329;5065.8733531662;5146.099432599281;5165.74171291434;5170.767315470737;5177.689338639693;4523.276275795923;4641.3734145889875;4648.697834640738;4676.08816280822;4687.507811679793;5860.912086318709;5872.354041055481;5909.29088509373;5911.142884281755;5922.3485416604135;6653.367930046637;6737.393634572912;6745.797626071226;6837.62393068386;6839.452843772487;5736.569757268222;5801.419858014165;5837.270341207349;5909.5863289569315;5922.1412895130825;5679.602427830012;5701.771929122789;5720;5727.083666613322;5812.447817457335;6577.901331480031;6585.374389432996;6608.636524991076;6662.700178491979;6671.266160722316;6733.9326606734285;6743.561668491532;6826.283240259801;6828.2830302819475;6834.2482326943;6432;6488.448481879093;6507.837304067436;6571.244306904767;6662.267150613526;6637.269900311058;6651.268360480247;6657.367550082734;6658.234303055211;6664.266930637529;6792.117024786746;6799.082123913407;6851.520393572402;6884.552504087218;6892.104026476726;6553.574017689026;6555.737770489127;6561.343865613591;6659.167604049494;6660.500462465431;6327.841803954938;6449.677516124269;6462.224533056064;6542.247641521286;6619.073329733745;6461.095446637569;6462.321456247027;6463.450606698295;6468.8059358219325;6495.642739649365;6509.088727578048;6583.670816459254;6635.867282654223;6641.169853768279;6652.567583107082;6329.17720696325;6361.90457143134;6429.22849258092;6429.58207716497;6435.292117866938 21:24:30 INFO - PID 30065 | |1;ext.html;784.4822417204645;803.4897839871682;805.5448671500602;808.543173107194;808.5593351623369;38686.61313386869;42029.36955945663;42403.78798106009;42416.72749817502;42458.150836983245;38403.039924001845;38415;38484.807575962084;38535.421968670424;38695.226095478145;6818.829529261783;7669.463137580372;7950.045994480671;7951.363890888715;7958.721444749435;7764.3788496920115;7889.171636978121;7890.092639346462;7892.052953645571;7904.05151381835;7846.215378462165;7923.683052677886;7927.643256053491;7934.166912474194;7937.682492700285;7858.803529911741;7883.487573307717;7887;7898.526088434713;7899.605019749047;7653.425993050488;7796.415268854858;7800.297973182441;7809.140994490602;7825.295723384923;36798;36807.4478882817;36995.815020924994;37146.62853371459;37149.442758358375;3566.2154326048226;3600.47793070006;3602.657747513982;3604.711623070148;3606.3688854450365;30851;31002;31026.534601980988;31164.220894477592;31355.529667054783;5050.393952725696;5161.922571161435;5254.4220135785035;5285.2600635911;5291.391489978663;189772.05113974342;190072;190252;190372.9999999986;191098;41620.58379416167;42154.36768448476;48173;48745.268820967714;49229;51338;51803.7409812956;51899;51921;51955.740221299406;15913.204339783197;15986.920065399598;16019.199040047952;16022.359105635644;16067;12879.098463107492;12904.870951290555;12949.417276222594;13053.543125990545;13062.150960187748;14318.498852540111;14359.210243436712;14387.208703521197;14395.496157634432;14528.346224419927;5969.2243106955475;5984.640921544721;5987.64074155552;6003.849903752441;6059.394060593993;984.4388698441962;1001.8096561653263;1006.833872411044;1019.1184625299211;1030.5877648940484;22894.084236630348;23093;23110;23285.3014409568;23349.533009339717;1099.5766629847567;1103.3104309806372;1117.5473933057124;1125.1673761416494;1130.9943450282697;11908.642740717793;11966.22219555726;12024.759504809856;12072.517099316105;12092.879071209176;219535;219804;219975;220374;220935;52976.20535691968;53221.46778532165;53360.73319633377;53465;53512.46487535153;1418.4609848257596;1430.0918916488106;1442.0986883198002;1454.0403333799852;1454.4836583012877;22409.77590224077;22509.43726406853;22607.660885086407;22793.430164246027;22849.885750571142;37663.81168094142;37793.05517362088;37815.24369512539;37924.999999998894;37934.810325948194;200746;201156;201963;201990;202548;33767.49348759722;35240.47139292964;35914.64085359217;36010.27979440449;36060.81969590135;46102.769486152356;46854.29718554156;46928.53071469242;47002.76498617485;47148.764256178496;33852.66147338495;33858;33878.661213388536;33881.49177762385;34280.82859585686;33554.83222583871;33671;33730.325393491505;33786.15534611556;33845.83077084599;37561.43657845189;37614.81192594129;38055;38220.42669360017;38369.42445863259;32700.83649581737;33062.66937330596;33084.33831323408;33197;33206.501902471966;24234.030638774253;24399.75600243975;24797.752022479544;24975.125870594442;25182.874085629453 21:24:30 INFO - PID 30065 | |2;jquery.html;568.2300482845744;573.368765861125;575.8761866198766;581.13120884278;605.3946053946054;21784.78215217847;22521.662175067388;22547.774522254793;22759.31722048337;22797.202097926576;23470;24188.274351769433;24351.686589773944;24410.511789764194;24560.140395086175;6443.259025212114;6686.297938716413;6690.096836927026;6698.229703584101;6716.966415167942;6382.872342553146;6541.21505419348;6600.93399066008;6699.866002679943;6738.865222695543;6637.037629543714;6681.231658359302;6683.331666833326;6683.665816709169;6710.899336509956;6430.774922877724;6626.536142470029;6634.369734875179;6652.567583107082;6657.700403481855;6161.353057928943;6198.163247961557;6204.472619827275;6206.534509911729;6208.5033197344555;27196.184114476597;27634.170974870787;27674.169774906786;27679.30801729953;27683.169504914884;2004.3185316992146;2042.4689580709123;2042.7038079478393;2045.2125931516325;2058.0121541659937;18639.81360186394;19048.238070477164;19266.22935082595;19335.226590936345;19382.70925936112;2453.2149712092137;2461.163204510475;2500.962485562718;2502.074232533956;2508.548461276969;116986;121169.39415302956;129812;133390;134676;13933.930330348487;14084.647883802782;14090.900001999544;14237.288135593179;14473.664766989865;20438.897805511173;20475.180992760124;20489.2828750993;20505.38483845487;20600.27899023557;9496.24030077606;9710.707664322961;9733.2213422926;9844.655437059811;10016.39901605906;9099.863502047474;9191.724248272563;9247.213986811197;9271.304652151115;9341.205997490153;8550.07623284756;8550.572471376407;8646.43798153118;8697.521636310064;8728.869066964;2546.465242299138;2571.06898136125;2612.8301660392012;2681.9004208274514;2702.3649442381025;961.6682244625594;964.2478866484154;981.7300242433388;991.965281215154;1003.9648612298535;18926;19065.42803715891;19267.22931082741;19366.70949935752;19425.417237482616;1035.4770840725384;1039.6205385034407;1048.292402628238;1050.1703654113164;1052.5421441672743;10257.487125643687;10581.62964296246;10705.571777128827;10720.195985301134;10899.237053406345;460186;471680;482245;484367;486506;110007.44996274967;110432.44783776191;110871;111145;114409;1153.6712037069562;1175.8236264560385;1207.4626791077885;1208.6434501821984;1209.5826939705964;16795.740319476332;16902.23939922731;17143.399981000606;17425.128743562516;17435.389761358292;53271.20093198682;53979.190312144565;54831.45168548423;54928.450715493935;55658.44341556533;68385.65807170932;68738.65630671814;68871.65564172347;68965.65517172382;69502.65248673927;13813.240271784747;14054.929725351309;14248.073875198079;14268.928655357073;14279;21752.129914803863;21798.564028719018;21835.890820545796;21963.011664474816;22373.77626223717;16469.34122635081;16490;16739.91630041842;16761.664766704354;17328;15604.609884752972;15755.842441575438;15891.443799466962;16483.093429861474;16521.504354869372;17126;18031.368902088365;18227.270909164014;18292.45122646323;18351.265949361878;13864.584062478141;13883.722325553634;13972.231527266098;13998.860011400164;14007.439702412197;16995.915020424818;17357.392491262744;17551.122443878012;17755.11224438747;17965.281388744304 21:24:30 INFO - PID 30065 | |3;mootools.html;1289.3746532931527;1363.8772510474055;1378.9241591712455;1391.5338361648846;1398.5944076217897;14636.56090317291;15348.232588370578;15360.769588456169;15364.385424583003;15370.769438458432;14996.700065998673;15096.924515377435;15137.091774493538;15170.089794612331;15194.240287985584;2711.810173287861;2715.551933930899;2770.3074231442138;2771.974369483294;2772.8336299821976;2735.029064682029;2735.029064682039;2738.9863050684717;2741.7258274172623;2746.0388863897674;2721.972780272192;2841.2186648671573;2842.9289267768368;2844.7013063628333;2847.245479947805;2740.0957683964243;2740.273827435731;2761.047438633668;2767.6125342452074;2770.1551026936777;2672.652555167835;2707.539718247903;2721.088435374152;2725.427660191362;2727.6317697110835;14522.564323070324;14771.113733176044;14859.257037148207;14862.256887155707;14874.628134296621;7706.65320060593;7882.487638303549;7891.644875980594;7899.605019749047;7947.562884041376;5340.54605358545;5428.728563571805;5511.724413779295;5541.168824676331;5574.080276754331;1797.1463554811492;1811.8369346758855;1864.4313484387255;1877.183425210038;1891.4136617648574;23530.64704029441;24240.636390454158;25843.61234581483;25907.09325173629;25944.48111037769;6672.5329226953645;6853.4174595158975;6861.382475577222;6906.8618627628175;6910.136232970879;17142.742858857127;17187.742183867253;17215;17238.396656117227;17252.568685782706;7734.110577283572;7788.260115289038;7978.321842643324;7982.361411087215;8016.759497215093;7136.464765142635;7201.279872012861;7202.49582529218;7203.27967203286;7259.6007219603425;5067.707734527672;5078.847634570969;5114.5652619527755;5160.251763494272;5207.114790485626;1451.325133812782;1503.9624009399633;1507.163524244055;1517.681286929735;1537.5541093082882;880.7313769300297;884;902.9277657787361;907.2016625369632;909.1771946388529;7238.203797582261;7321.231270716605;7408.333250007525;7422.851542969111;7438.814029649194;709.4678990756933;715.9498835081598;722.342668171961;722.8229083874379;727.0947670150696;4857.951420485821;5081.110805609078;5131.7690703918415;5235.214717792362;5274.498922602346;21409.250676226256;21903.342899713036;22098.668519972212;22172.667409988862;22192.88903555472;20820.895895520425;21627.2430464933;22312.330630081124;22379.776202238096;22405.88797056004;10785.191110666732;11037.668869933916;11056.391898445501;11058.11535077192;11195;8217.79455513617;8492.957535212285;8525.104863989192;8576.957115214384;8682.218600326001;18119.72820407721;18267.451976440727;18276.45170644883;18458.63082738311;18587;18479.353222637143;18536.721949171042;18666.440006799818;18681.43955681332;18739;11144.264728591477;11146.219764616724;11315.773684526428;11337.149713771336;11372.488238029475;13867.167969921837;14000.859991399955;14027.649308767363;14033.578992630237;14043;11157.776844462904;11201.271917325348;11298.548058077586;11515.366654834064;11644.708882278012;10942.507587158756;11358.204925655125;11527.308361498337;11543.538258469567;11780.823287650863;10083.546240419346;10327.225458090523;10328.225383096447;10333.173346132142;10335.534900929326;6697.7655782047395;6710.798676039727;6796.1843181864015;6822.317768223138;6928.515003949877;6512.6092434454085;6583.111279977142;6601.306862779531;6624.271330153583;6650.83372915681 21:24:30 INFO - PID 30065 | |4;prototype.html;501.6463393307721;506.77955089536044;507.34045740537294;526.2159382520045;529.3356837169355;6117.938820611793;6140.20177376941;6160.888751180648;6164.828682550318;6203.8138855834395;5973;5992.880142397149;5996.52027837773;6039.758409663619;6083.482903953158;4880.55972013993;4945.258211268302;5082.695038297705;5090.160123579605;5138.922916156261;4966;4989.476105008976;5064.56951159152;5076.695398276097;5079.771410286546;4917.72952487613;4966.652334336598;4979.47715489872;4990.975045124769;5005.324281222043;4948;4950.4307004694565;4957.5042495750495;4971.850844474671;4990.351254336949;4462.3752674625575;4470.396496472967;4486.170058539181;4501.234790085693;4505.211587972091;6092.08618707193;6164.84587885302;6176.6911654417545;6205;6262.530310226705;5630.605857589971;5641.68970706611;5645.2096706461125;5664.546836253131;5707.172459993278;6432.581882177644;6465.515086368541;6520.510961677893;6520.739170433177;6527.086207930894;4867.34290870735;4872.415310162768;4873.366462359871;4873.488283730193;4888.437829649564;6824.488163387716;6864.279250678656;6869.244383117853;6888.965555172192;6927.515073944826;4928.8767780805765;4951.306817045616;4975.502449755068;4985.750712464363;5029;5037.924431133536;5052.242163675404;5062.569681577107;5078.466760990117;5080.221922327156;5593.636413633101;5630.23991761107;5648;5702.258706368146;5730.484256416942;6049.667268300198;6160.507159427235;6384.680765961683;6477.514186436036;6559.770408035696;1709.538424625344;1728.8503145408163;1749.7550342951997;1753.7369394590914;1763.8147994460653;1186.5787645385765;1187.5249900040053;1195.6353312239676;1217.7503611759569;1223.7578857459685;5560.499555040065;5590.440955904377;5617.662940223599;5800.854978625483;5859.121131830259;6526;6636.473611069481;6646.53474256807;6647.302033286532;6799.898001529981;5637.23897273871;5690.77236910519;5757.524264566193;5792.739326730307;5802.796902108406;3520.8239588020497;3521.2605352876185;3550.3254381667402;3552.342816578941;3568.1436455250555;6459.935400646027;6512.967435162794;6523.967380163069;6529.706163222666;6606.306337834554;6848.246692863781;6887.311268873073;6904.3095690430555;6915.135608048994;6922.134733158356;6492.707828147651;6554.967225163844;6555.6066636002;6579.83550411234;6628.436582890508;6650.268470468342;6713.0196592004495;6718.875722788926;6724.193096828412;6738.7304507819135;6569.441597464269;6607.438367738604;6660.167479065117;6673.566218195801;6693.330666933268;6539.967300163468;6542.214934207926;6551.279359270572;6553.672316384257;6614.636195009129;4901.921577252999;4918.458969513279;4924.975375123101;4930.926036109533;4997.550220480174;6418.839529011812;6466.482681385572;6507.934920650733;6622.966885165543;6643.3356664335115;6231.594946328474;6445.484361250995;6492.220933488012;6492.448141907991;6572.047053177167;6314.737052589408;6419.679016049272;6455;6489.253735820441;6535.379138981789;6064;6235.625862448268;6321.52588555851;6371.750407292222;6388;6451.2903580607035;6486.383793539606;6531.542792004514;6553.344665533408;6568.507361947969;6217.191765070512;6231.844203894939;6271.33809540463;6299.370062993664;6326.588771729823 21:24:30 INFO - PID 30065 | |5;yui.html;905.3255324783038;908.4049947284523;921.6267411698265;928.8420968435365;934.5747684803413;19038.99999999998;19727.40817775467;19760.80239197611;19775.604487910234;19792.604147917053;17988.82011179888;18073.096345182756;18146;18189;18202.817971820277;966.2318456826822;980.5587485631459;982.5774916785779;986.7286496213527;987.2151639446621;971.8299297622888;973.3332667123008;974.1086905481476;976.6728146071039;977.6773664690635;992.4095163377815;1028.7376718936662;1031.0823367203195;1033.7880734449457;1040.3289878028668;1021.596469394585;1024.9590016399336;1026.0867827633374;1030.5208078243577;1030.634124885666;716.5987047253554;737.0602481835703;740.4742632730768;746.7946314763429;747.1743723185856;19450.319238826716;19602.607947841105;19676;19694.606107877906;19739;20103.89948050265;20372.69440958387;20401.8979905101;20910;20934.47663808402;16873.746893796604;16885.40901068469;16968.660626787394;17210.82789172105;17211.05339206343;3752.061984503874;3773.1699026214187;3775.3959366501504;3775.6035616260174;3780.6597406233295;101287.49356253246;125284;139850.30074849562;140280;142095;16606.584835379213;17151.313947442213;17247.655046899243;17249.741253881202;17287.65424691499;28761.42477150487;29492.115236542937;29577;29610.40779184404;29755.702442975293;9111;9177.678781242757;9218;9223.769405764775;9224.953875230714;4001.3397789365013;4113.568075352105;4126.938095928564;4148.7303325284365;4164.229617520768;5177.042247184283;5259.342582177228;5260.552853007537;5262.500062494057;5283.550898173621;1043.3948309980194;1064.1699474409972;1075.7471994081384;1078.5901357484263;1081.7998670245872;691.9550229235084;712.4300559552337;719.4496210399049;733.6991833348302;737.2295950731499;11829.585964491202;12294.938525307494;12494.687632809071;12605.93697031509;12863.421146048264;668.1414382518441;676.9187697476236;677.2381071294793;693.8646963842002;704.9365557099885;5426.2674538936735;5428.565714742811;5440.211169380497;5451.509364157245;5497.780088796403;124404.37797810954;125415;126687;127518;130761.34619327032;106599;109061;109825;111234.44382778034;117811;96181;96503.51748241074;97180;97870.51064744631;99486;12768.425420855898;13205.075644705163;13272.734545309233;13437.395317210945;13601;43468.347974781034;43505.56494435015;43701.78149109234;44193.55806442023;45769.54230457653;56055.43944560502;57319.7134014344;57384.71307643435;58720.412795871496;58725;18668.15993280333;18775.90612046931;18807.153678084247;18889.527761806065;19191.136398862363;20301.59396812085;20396.89801550983;20398.28605998783;20667.069981850553;20692;18522;18718.625627487647;18724.7191292128;18742.344017959305;18865.15106820169;17399.304027838745;17566.560835979202;17647.911760441115;17669.38157164493;17750.556236094202;16868.156592170588;16919.407820726214;16968.66062678764;17016.404425845038;17040.40358587493;12996.740065198454;13281.203127812361;13426.932865335611;13461.125026873222;13579.52471663487;13713;13970.511032113827;13988.37052332668;14048.859511405164;14049.789253161414 21:24:30 INFO - PID 30065 | __end_tp_report 21:24:30 INFO - PID 30065 | __start_cc_report 21:24:30 INFO - PID 30065 | _x_x_mozilla_cycle_collect,271 21:24:30 INFO - PID 30065 | __end_cc_report 21:24:30 INFO - PID 30065 | __startTimestamp1505622270627__endTimestamp 21:24:30 INFO - PID 30065 | 21:24:30 INFO - PID 30065 | ------- Summary: start ------- 21:24:30 INFO - PID 30065 | Number of tests: 6 21:24:30 INFO - PID 30065 | 21:24:30 INFO - PID 30065 | [#0] dojo.html Cycles:165 Average:6143.40 Median:6449.68 stddev:859.02 (13.3%) stddev-sans-first:827.35 21:24:30 INFO - PID 30065 | Values: 3079.6 3128.1 3143.1 3170.3 3174.2 6050.2 6161.6 6163.6 6215.5 6267.8 6073.4 6079.1 6089.6 6134.0 6185.7 5615.3 5669.1 5685.4 5696.7 5733.6 5772.7 5816.4 5821.5 5829.0 5855.6 5740.4 5822.3 5838.2 5878.6 5889.3 839.2 5677.0 5684.2 5710.3 5730.7 5677.4 5712.8 5733.6 5763.7 5772.9 6162.0 6287.5 6289.1 6326.5 6362.9 6671.9 6688.4 6733.1 6760.7 6781.2 6747.9 6769.0 6791.1 6849.9 6855.0 6079.5 6148.9 6163.3 6164.2 6334.6 6861.8 6863.2 6863.7 6869.0 6870.0 6130.4 6512.1 6520.4 6533.2 6606.1 6611.8 6731.4 6752.2 6770.8 6787.7 6583.0 6730.3 6736.8 6746.8 6749.5 6707.4 6709.3 6730.4 6809.4 6813.9 5065.9 5146.1 5165.7 5170.8 5177.7 4523.3 4641.4 4648.7 4676.1 4687.5 5860.9 5872.4 5909.3 5911.1 5922.3 6653.4 6737.4 6745.8 6837.6 6839.5 5736.6 5801.4 5837.3 5909.6 5922.1 5679.6 5701.8 5720.0 5727.1 5812.4 6577.9 6585.4 6608.6 6662.7 6671.3 6733.9 6743.6 6826.3 6828.3 6834.2 6432.0 6488.4 6507.8 6571.2 6662.3 6637.3 6651.3 6657.4 6658.2 6664.3 6792.1 6799.1 6851.5 6884.6 6892.1 6553.6 6555.7 6561.3 6659.2 6660.5 6327.8 6449.7 6462.2 6542.2 6619.1 6461.1 6462.3 6463.5 6468.8 6495.6 6509.1 6583.7 6635.9 6641.2 6652.6 6329.2 6361.9 6429.2 6429.6 6435.3 21:24:30 INFO - PID 30065 | 21:24:30 INFO - PID 30065 | [#1] ext.html Cycles:180 Average:37617.77 Median:3603.68 stddev:52884.91 (1467.5%) stddev-sans-first:52960.94 21:24:30 INFO - PID 30065 | Values: 784.5 803.5 805.5 808.5 808.6 38686.6 42029.4 42403.8 42416.7 42458.2 38403.0 38415.0 38484.8 38535.4 38695.2 6818.8 7669.5 7950.0 7951.4 7958.7 7764.4 7889.2 7890.1 7892.1 7904.1 7846.2 7923.7 7927.6 7934.2 7937.7 7858.8 7883.5 7887.0 7898.5 7899.6 7653.4 7796.4 7800.3 7809.1 7825.3 36798.0 36807.4 36995.8 37146.6 37149.4 3566.2 3600.5 3602.7 3604.7 3606.4 30851.0 31002.0 31026.5 31164.2 31355.5 5050.4 5161.9 5254.4 5285.3 5291.4 189772.1 190072.0 190252.0 190373.0 191098.0 41620.6 42154.4 48173.0 48745.3 49229.0 51338.0 51803.7 51899.0 51921.0 51955.7 15913.2 15986.9 16019.2 16022.4 16067.0 12879.1 12904.9 12949.4 13053.5 13062.2 14318.5 14359.2 14387.2 14395.5 14528.3 5969.2 5984.6 5987.6 6003.8 6059.4 984.4 1001.8 1006.8 1019.1 1030.6 22894.1 23093.0 23110.0 23285.3 23349.5 1099.6 1103.3 1117.5 1125.2 1131.0 11908.6 11966.2 12024.8 12072.5 12092.9 219535.0 219804.0 219975.0 220374.0 220935.0 52976.2 53221.5 53360.7 53465.0 53512.5 1418.5 1430.1 1442.1 1454.0 1454.5 22409.8 22509.4 22607.7 22793.4 22849.9 37663.8 37793.1 37815.2 37925.0 37934.8 200746.0 201156.0 201963.0 201990.0 202548.0 33767.5 35240.5 35914.6 36010.3 36060.8 46102.8 46854.3 46928.5 47002.8 47148.8 33852.7 33858.0 33878.7 33881.5 34280.8 33554.8 33671.0 33730.3 33786.2 33845.8 37561.4 37614.8 38055.0 38220.4 38369.4 32700.8 33062.7 33084.3 33197.0 33206.5 24234.0 24399.8 24797.8 24975.1 25182.9 21:24:30 INFO - PID 30065 | 21:24:30 INFO - PID 30065 | [#2] jquery.html Cycles:180 Average:33435.72 Median:21899.45 stddev:80133.44 (365.9%) stddev-sans-first:80320.24 21:24:30 INFO - PID 30065 | Values: 568.2 573.4 575.9 581.1 605.4 21784.8 22521.7 22547.8 22759.3 22797.2 23470.0 24188.3 24351.7 24410.5 24560.1 6443.3 6686.3 6690.1 6698.2 6717.0 6382.9 6541.2 6600.9 6699.9 6738.9 6637.0 6681.2 6683.3 6683.7 6710.9 6430.8 6626.5 6634.4 6652.6 6657.7 6161.4 6198.2 6204.5 6206.5 6208.5 27196.2 27634.2 27674.2 27679.3 27683.2 2004.3 2042.5 2042.7 2045.2 2058.0 18639.8 19048.2 19266.2 19335.2 19382.7 2453.2 2461.2 2501.0 2502.1 2508.5 116986.0 121169.4 129812.0 133390.0 134676.0 13933.9 14084.6 14090.9 14237.3 14473.7 20438.9 20475.2 20489.3 20505.4 20600.3 9496.2 9710.7 9733.2 9844.7 10016.4 9099.9 9191.7 9247.2 9271.3 9341.2 8550.1 8550.6 8646.4 8697.5 8728.9 2546.5 2571.1 2612.8 2681.9 2702.4 961.7 964.2 981.7 992.0 1004.0 18926.0 19065.4 19267.2 19366.7 19425.4 1035.5 1039.6 1048.3 1050.2 1052.5 10257.5 10581.6 10705.6 10720.2 10899.2 460186.0 471680.0 482245.0 484367.0 486506.0 110007.4 110432.4 110871.0 111145.0 114409.0 1153.7 1175.8 1207.5 1208.6 1209.6 16795.7 16902.2 17143.4 17425.1 17435.4 53271.2 53979.2 54831.5 54928.5 55658.4 68385.7 68738.7 68871.7 68965.7 69502.7 13813.2 14054.9 14248.1 14268.9 14279.0 21752.1 21798.6 21835.9 21963.0 22373.8 16469.3 16490.0 16739.9 16761.7 17328.0 15604.6 15755.8 15891.4 16483.1 16521.5 17126.0 18031.4 18227.3 18292.5 18351.3 13864.6 13883.7 13972.2 13998.9 14007.4 16995.9 17357.4 17551.1 17755.1 17965.3 21:24:30 INFO - PID 30065 | 21:24:30 INFO - PID 30065 | [#3] mootools.html Cycles:180 Average:9235.72 Median:2690.10 stddev:6598.42 (245.3%) stddev-sans-first:6589.92 21:24:30 INFO - PID 30065 | Values: 1289.4 1363.9 1378.9 1391.5 1398.6 14636.6 15348.2 15360.8 15364.4 15370.8 14996.7 15096.9 15137.1 15170.1 15194.2 2711.8 2715.6 2770.3 2772.0 2772.8 2735.0 2735.0 2739.0 2741.7 2746.0 2722.0 2841.2 2842.9 2844.7 2847.2 2740.1 2740.3 2761.0 2767.6 2770.2 2672.7 2707.5 2721.1 2725.4 2727.6 14522.6 14771.1 14859.3 14862.3 14874.6 7706.7 7882.5 7891.6 7899.6 7947.6 5340.5 5428.7 5511.7 5541.2 5574.1 1797.1 1811.8 1864.4 1877.2 1891.4 23530.6 24240.6 25843.6 25907.1 25944.5 6672.5 6853.4 6861.4 6906.9 6910.1 17142.7 17187.7 17215.0 17238.4 17252.6 7734.1 7788.3 7978.3 7982.4 8016.8 7136.5 7201.3 7202.5 7203.3 7259.6 5067.7 5078.8 5114.6 5160.3 5207.1 1451.3 1504.0 1507.2 1517.7 1537.6 880.7 884.0 902.9 907.2 909.2 7238.2 7321.2 7408.3 7422.9 7438.8 709.5 715.9 722.3 722.8 727.1 4858.0 5081.1 5131.8 5235.2 5274.5 21409.3 21903.3 22098.7 22172.7 22192.9 20820.9 21627.2 22312.3 22379.8 22405.9 10785.2 11037.7 11056.4 11058.1 11195.0 8217.8 8493.0 8525.1 8577.0 8682.2 18119.7 18267.5 18276.5 18458.6 18587.0 18479.4 18536.7 18666.4 18681.4 18739.0 11144.3 11146.2 11315.8 11337.1 11372.5 13867.2 14000.9 14027.6 14033.6 14043.0 11157.8 11201.3 11298.5 11515.4 11644.7 10942.5 11358.2 11527.3 11543.5 11780.8 10083.5 10327.2 10328.2 10333.2 10335.5 6697.8 6710.8 6796.2 6822.3 6928.5 6512.6 6583.1 6601.3 6624.3 6650.8 21:24:30 INFO - PID 30065 | 21:24:30 INFO - PID 30065 | [#4] prototype.html Cycles:180 Average:5476.42 Median:6073.74 stddev:1531.00 (25.2%) stddev-sans-first:1489.06 21:24:30 INFO - PID 30065 | Values: 501.6 506.8 507.3 526.2 529.3 6117.9 6140.2 6160.9 6164.8 6203.8 5973.0 5992.9 5996.5 6039.8 6083.5 4880.6 4945.3 5082.7 5090.2 5138.9 4966.0 4989.5 5064.6 5076.7 5079.8 4917.7 4966.7 4979.5 4991.0 5005.3 4948.0 4950.4 4957.5 4971.9 4990.4 4462.4 4470.4 4486.2 4501.2 4505.2 6092.1 6164.8 6176.7 6205.0 6262.5 5630.6 5641.7 5645.2 5664.5 5707.2 6432.6 6465.5 6520.5 6520.7 6527.1 4867.3 4872.4 4873.4 4873.5 4888.4 6824.5 6864.3 6869.2 6889.0 6927.5 4928.9 4951.3 4975.5 4985.8 5029.0 5037.9 5052.2 5062.6 5078.5 5080.2 5593.6 5630.2 5648.0 5702.3 5730.5 6049.7 6160.5 6384.7 6477.5 6559.8 1709.5 1728.9 1749.8 1753.7 1763.8 1186.6 1187.5 1195.6 1217.8 1223.8 5560.5 5590.4 5617.7 5800.9 5859.1 6526.0 6636.5 6646.5 6647.3 6799.9 5637.2 5690.8 5757.5 5792.7 5802.8 3520.8 3521.3 3550.3 3552.3 3568.1 6459.9 6513.0 6524.0 6529.7 6606.3 6848.2 6887.3 6904.3 6915.1 6922.1 6492.7 6555.0 6555.6 6579.8 6628.4 6650.3 6713.0 6718.9 6724.2 6738.7 6569.4 6607.4 6660.2 6673.6 6693.3 6540.0 6542.2 6551.3 6553.7 6614.6 4901.9 4918.5 4925.0 4930.9 4997.6 6418.8 6466.5 6507.9 6623.0 6643.3 6231.6 6445.5 6492.2 6492.4 6572.0 6314.7 6419.7 6455.0 6489.3 6535.4 6064.0 6235.6 6321.5 6371.8 6388.0 6451.3 6486.4 6531.5 6553.3 6568.5 6217.2 6231.8 6271.3 6299.4 6326.6 21:24:30 INFO - PID 30065 | 21:24:30 INFO - PID 30065 | [#5] yui.html Cycles:180 Average:24718.24 Median:19749.90 stddev:35003.70 (177.2%) stddev-sans-first:35056.23 21:24:30 INFO - PID 30065 | Values: 905.3 908.4 921.6 928.8 934.6 19039.0 19727.4 19760.8 19775.6 19792.6 17988.8 18073.1 18146.0 18189.0 18202.8 966.2 980.6 982.6 986.7 987.2 971.8 973.3 974.1 976.7 977.7 992.4 1028.7 1031.1 1033.8 1040.3 1021.6 1025.0 1026.1 1030.5 1030.6 716.6 737.1 740.5 746.8 747.2 19450.3 19602.6 19676.0 19694.6 19739.0 20103.9 20372.7 20401.9 20910.0 20934.5 16873.7 16885.4 16968.7 17210.8 17211.1 3752.1 3773.2 3775.4 3775.6 3780.7 101287.5 125284.0 139850.3 140280.0 142095.0 16606.6 17151.3 17247.7 17249.7 17287.7 28761.4 29492.1 29577.0 29610.4 29755.7 9111.0 9177.7 9218.0 9223.8 9225.0 4001.3 4113.6 4126.9 4148.7 4164.2 5177.0 5259.3 5260.6 5262.5 5283.6 1043.4 1064.2 1075.7 1078.6 1081.8 692.0 712.4 719.4 733.7 737.2 11829.6 12294.9 12494.7 12605.9 12863.4 668.1 676.9 677.2 693.9 704.9 5426.3 5428.6 5440.2 5451.5 5497.8 124404.4 125415.0 126687.0 127518.0 130761.3 106599.0 109061.0 109825.0 111234.4 117811.0 96181.0 96503.5 97180.0 97870.5 99486.0 12768.4 13205.1 13272.7 13437.4 13601.0 43468.3 43505.6 43701.8 44193.6 45769.5 56055.4 57319.7 57384.7 58720.4 58725.0 18668.2 18775.9 18807.2 18889.5 19191.1 20301.6 20396.9 20398.3 20667.1 20692.0 18522.0 18718.6 18724.7 18742.3 18865.2 17399.3 17566.6 17647.9 17669.4 17750.6 16868.2 16919.4 16968.7 17016.4 17040.4 12996.7 13281.2 13426.9 13461.1 13579.5 13713.0 13970.5 13988.4 14048.9 14049.8 21:24:30 INFO - PID 30065 | -------- Summary: end -------- 21:24:30 INFO - PID 30065 | 21:24:31 INFO - TEST-INFO | 30065: exit 0 21:24:31 INFO - TEST-OK | dromaeo_css | took 1108297ms 21:24:31 INFO - TEST-START | kraken 21:24:31 INFO - Initialising browser for kraken test... 21:24:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39005/getInfo.html -profile /tmp/tmpJGtWXk/profile 21:24:31 INFO - TEST-INFO | started process 30445 (/builds/slave/test/build/application/firefox/firefox http://localhost:39005/getInfo.html) 21:24:36 INFO - TEST-INFO | 30445: exit 0 21:24:36 INFO - Browser initialized. 21:24:36 INFO - Running cycle 1/1 for kraken test... 21:24:36 INFO - Using env: {'DISPLAY': ':0', 21:24:36 INFO - 'HOME': '/home/cltbld', 21:24:36 INFO - 'JSGC_DISABLE_POISONING': '1', 21:24:36 INFO - 'LANG': 'en_US.UTF-8', 21:24:36 INFO - 'LANGUAGE': 'en_US:en', 21:24:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 21:24:36 INFO - 'LOGNAME': 'cltbld', 21:24:36 INFO - 'MAIL': '/var/mail/cltbld', 21:24:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:24:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:24:36 INFO - 'MOZ_CRASHREPORTER': '1', 21:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:24:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 21:24:36 INFO - 'MOZ_NO_REMOTE': '1', 21:24:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:24:36 INFO - 'NO_EM_RESTART': '1', 21:24:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:24:36 INFO - 'PWD': '/builds/slave/test', 21:24:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:24:36 INFO - 'RUST_BACKTRACE': 'full', 21:24:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 21:24:36 INFO - 'SHELL': '/bin/bash', 21:24:36 INFO - 'SHLVL': '1', 21:24:36 INFO - 'TERM': 'linux', 21:24:36 INFO - 'TMOUT': '86400', 21:24:36 INFO - 'USER': 'cltbld', 21:24:36 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710', 21:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:24:36 INFO - '_': '/tools/buildbot/bin/python'} 21:24:36 INFO - TEST-INFO | started process 30683 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJGtWXk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 21:24:37 INFO - PID 30683 | 21:24:37 INFO - PID 30683 | (/builds/slave/test/build/application/firefox/firefox:30732): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 21:24:37 INFO - PID 30683 | 21:24:47 INFO - PID 30683 | RSS: Main: 151449600 21:24:47 INFO - PID 30683 | 21:24:50 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39005/tests/kraken/driver.html?testName=audio-beat-detection) 21:24:51 INFO - PID 30683 | RSS: Main: 156995584 21:24:51 INFO - PID 30683 | 21:24:54 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39005/tests/kraken/driver.html?testName=audio-dft) 21:24:54 INFO - PID 30683 | RSS: Main: 157126656 21:24:54 INFO - PID 30683 | 21:24:57 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39005/tests/kraken/driver.html?testName=audio-fft) 21:24:58 INFO - PID 30683 | RSS: Main: 153985024 21:24:58 INFO - PID 30683 | 21:25:00 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39005/tests/kraken/driver.html?testName=audio-oscillator) 21:25:01 INFO - PID 30683 | RSS: Main: 154169344 21:25:01 INFO - PID 30683 | 21:25:03 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39005/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:25:03 INFO - PID 30683 | RSS: Main: 154124288 21:25:03 INFO - PID 30683 | 21:25:07 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39005/tests/kraken/driver.html?testName=imaging-darkroom) 21:25:07 INFO - PID 30683 | RSS: Main: 156606464 21:25:07 INFO - PID 30683 | 21:25:11 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39005/tests/kraken/driver.html?testName=imaging-desaturate) 21:25:11 INFO - PID 30683 | RSS: Main: 157065216 21:25:11 INFO - PID 30683 | 21:25:14 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39005/tests/kraken/driver.html?testName=json-parse-financial) 21:25:15 INFO - PID 30683 | RSS: Main: 156860416 21:25:15 INFO - PID 30683 | 21:25:17 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39005/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:25:17 INFO - PID 30683 | RSS: Main: 157069312 21:25:17 INFO - PID 30683 | 21:25:19 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39005/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:25:19 INFO - PID 30683 | RSS: Main: 157290496 21:25:19 INFO - PID 30683 | 21:25:22 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39005/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:25:22 INFO - PID 30683 | RSS: Main: 157298688 21:25:22 INFO - PID 30683 | 21:25:25 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:25:25 INFO - PID 30683 | RSS: Main: 157732864 21:25:25 INFO - PID 30683 | 21:25:28 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:25:29 INFO - PID 30683 | RSS: Main: 158064640 21:25:29 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | Cycle 1(1): loaded http://localhost:39005/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39005/tests/kraken/driver.html?testName=ai-astar) 21:25:31 INFO - PID 30683 | RSS: Main: 159326208 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | __start_tp_report 21:25:31 INFO - PID 30683 | _x_x_mozilla_page_load 21:25:31 INFO - PID 30683 | _x_x_mozilla_page_load_details 21:25:31 INFO - PID 30683 | |i|pagename|runs| 21:25:31 INFO - PID 30683 | |0;ai-astar;108;108;107;108;107;96;107;108;106;107 21:25:31 INFO - PID 30683 | |1;audio-beat-detection;121;121;117;116;120;117;119;120;116;117 21:25:31 INFO - PID 30683 | |2;audio-dft;162;162;165;163;166;162;163;165;169;163 21:25:31 INFO - PID 30683 | |3;audio-fft;75;75;79;77;73;75;74;74;73;75 21:25:31 INFO - PID 30683 | |4;audio-oscillator;80;82;80;79;81;79;80;83;80;80 21:25:31 INFO - PID 30683 | |5;imaging-gaussian-blur;109;113;113;110;114;115;115;113;114;114 21:25:31 INFO - PID 30683 | |6;imaging-darkroom;148;148;148;147;148;149;149;151;149;149 21:25:31 INFO - PID 30683 | |7;imaging-desaturate;93;93;93;94;93;99;94;95;94;94 21:25:31 INFO - PID 30683 | |8;json-parse-financial;54;55;55;56;54;55;55;55;55;56 21:25:31 INFO - PID 30683 | |9;json-stringify-tinderbox;37;37;37;37;37;37;37;37;37;37 21:25:31 INFO - PID 30683 | |10;stanford-crypto-aes;75;76;74;74;79;74;75;81;73;76 21:25:31 INFO - PID 30683 | |11;stanford-crypto-ccm;113;111;118;111;112;114;113;113;117;115 21:25:31 INFO - PID 30683 | |12;stanford-crypto-pbkdf2;184;188;175;172;168;171;167;175;170;171 21:25:31 INFO - PID 30683 | |13;stanford-crypto-sha256-iterative;67;68;64;69;69;69;68;68;66;73 21:25:31 INFO - PID 30683 | __end_tp_report 21:25:31 INFO - PID 30683 | __start_cc_report 21:25:31 INFO - PID 30683 | _x_x_mozilla_cycle_collect,739 21:25:31 INFO - PID 30683 | __end_cc_report 21:25:31 INFO - PID 30683 | __startTimestamp1505622331566__endTimestamp 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | ------- Summary: start ------- 21:25:31 INFO - PID 30683 | Number of tests: 14 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#0] ai-astar Cycles:10 Average:106.20 Median:108.00 stddev:3.65 (3.4%) stddev-sans-first:3.81 21:25:31 INFO - PID 30683 | Values: 108.0 108.0 107.0 108.0 107.0 96.0 107.0 108.0 106.0 107.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#1] audio-beat-detection Cycles:10 Average:118.40 Median:119.50 stddev:2.01 (1.7%) stddev-sans-first:1.90 21:25:31 INFO - PID 30683 | Values: 121.0 121.0 117.0 116.0 120.0 117.0 119.0 120.0 116.0 117.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.00 stddev:2.26 (1.4%) stddev-sans-first:2.28 21:25:31 INFO - PID 30683 | Values: 162.0 162.0 165.0 163.0 166.0 162.0 163.0 165.0 169.0 163.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#3] audio-fft Cycles:10 Average:75.00 Median:75.00 stddev:1.83 (2.4%) stddev-sans-first:1.94 21:25:31 INFO - PID 30683 | Values: 75.0 75.0 79.0 77.0 73.0 75.0 74.0 74.0 73.0 75.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#4] audio-oscillator Cycles:10 Average:80.40 Median:80.00 stddev:1.26 (1.6%) stddev-sans-first:1.33 21:25:31 INFO - PID 30683 | Values: 80.0 82.0 80.0 79.0 81.0 79.0 80.0 83.0 80.0 80.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#5] imaging-gaussian-blur Cycles:10 Average:113.00 Median:114.00 stddev:2.00 (1.8%) stddev-sans-first:1.51 21:25:31 INFO - PID 30683 | Values: 109.0 113.0 113.0 110.0 114.0 115.0 115.0 113.0 114.0 114.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#6] imaging-darkroom Cycles:10 Average:148.60 Median:149.00 stddev:1.07 (0.7%) stddev-sans-first:1.12 21:25:31 INFO - PID 30683 | Values: 148.0 148.0 148.0 147.0 148.0 149.0 149.0 151.0 149.0 149.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:94.00 stddev:1.81 (1.9%) stddev-sans-first:1.87 21:25:31 INFO - PID 30683 | Values: 93.0 93.0 93.0 94.0 93.0 99.0 94.0 95.0 94.0 94.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:0.67 (1.2%) stddev-sans-first:0.60 21:25:31 INFO - PID 30683 | Values: 54.0 55.0 55.0 56.0 54.0 55.0 55.0 55.0 55.0 56.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#9] json-stringify-tinderbox Cycles:10 Average:37.00 Median:37.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 21:25:31 INFO - PID 30683 | Values: 37.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 37.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:75.50 stddev:2.50 (3.3%) stddev-sans-first:2.64 21:25:31 INFO - PID 30683 | Values: 75.0 76.0 74.0 74.0 79.0 74.0 75.0 81.0 73.0 76.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#11] stanford-crypto-ccm Cycles:10 Average:113.70 Median:113.50 stddev:2.36 (2.1%) stddev-sans-first:2.49 21:25:31 INFO - PID 30683 | Values: 113.0 111.0 118.0 111.0 112.0 114.0 113.0 113.0 117.0 115.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.10 Median:173.50 stddev:6.84 (3.9%) stddev-sans-first:6.24 21:25:31 INFO - PID 30683 | Values: 184.0 188.0 175.0 172.0 168.0 171.0 167.0 175.0 170.0 171.0 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:2.33 (3.4%) stddev-sans-first:2.44 21:25:31 INFO - PID 30683 | Values: 67.0 68.0 64.0 69.0 69.0 69.0 68.0 68.0 66.0 73.0 21:25:31 INFO - PID 30683 | -------- Summary: end -------- 21:25:31 INFO - PID 30683 | 21:25:31 INFO - PID 30683 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 21:25:32 INFO - TEST-INFO | 30683: exit 0 21:25:32 INFO - TEST-OK | kraken | took 60912ms 21:25:32 INFO - SUITE-END | took 1169s 21:25:32 INFO - Completed test suite (00:19:29) 21:25:32 INFO - javascript benchmark 21:25:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8482.396587242758, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3079.645840728316, 3128.124125244932, 3143.057082875137, 3170.302533442642, 3174.1588479053057, 6050.152978582991, 6161.630302181872, 6163.599366041215, 6215.471684906783, 6267.780627678032, 6073.3926607339245, 6079.05774604937, 6089.573729838913, 6134.049222370529, 6185.6597887116195, 5615.326160860682, 5669.149627555858, 5685.431456854323, 5696.658200507983, 5733.598648094635, 5772.711364431782, 5816.418358164192, 5821.476067153955, 5828.970855145718, 5855.61938474, 5740.397258287882, 5822.301323841145, 5838.182654428383, 5878.588498805086, 5889.263842019747, 839.1947429122661, 5676.971615141939, 5684.175794509814, 5710.314762228518, 5730.713464326767, 5677.37548869624, 5712.828615141552, 5733.598648094635, 5763.711814409263, 5772.913406298909, 6162.0, 6287.49700023997, 6289.0880822280515, 6326.5255105867245, 6362.936370636281, 6671.93328066718, 6688.431483323923, 6733.05737196788, 6760.661966901635, 6781.186257649114, 6747.865042699168, 6768.966155169242, 6791.083203767477, 6849.863002739967, 6854.9657251713925, 6079.483243924272, 6148.938510614881, 6163.2604087509335, 6164.198654174928, 6334.556581039329, 6861.759838405632, 6863.245043045261, 6863.6568171591225, 6869.0383346330555, 6870.0, 6130.386961303834, 6512.055751915946, 6520.380563846427, 6533.18335208099, 6606.075149479077, 6611.768588099393, 6731.360520750521, 6752.22349429812, 6770.796876093725, 6787.694553745093, 6583.0, 6730.293319201512, 6736.797896063126, 6746.797596072126, 6749.460043196533, 6707.429868461138, 6709.329067093349, 6730.427913627299, 6809.38715515606, 6813.931860681329, 5065.8733531662, 5146.099432599281, 5165.74171291434, 5170.767315470737, 5177.689338639693, 4523.276275795923, 4641.3734145889875, 4648.697834640738, 4676.08816280822, 4687.507811679793, 5860.912086318709, 5872.354041055481, 5909.29088509373, 5911.142884281755, 5922.3485416604135, 6653.367930046637, 6737.393634572912, 6745.797626071226, 6837.62393068386, 6839.452843772487, 5736.569757268222, 5801.419858014165, 5837.270341207349, 5909.5863289569315, 5922.1412895130825, 5679.602427830012, 5701.771929122789, 5720.0, 5727.083666613322, 5812.447817457335, 6577.901331480031, 6585.374389432996, 6608.636524991076, 6662.700178491979, 6671.266160722316, 6733.9326606734285, 6743.561668491532, 6826.283240259801, 6828.2830302819475, 6834.2482326943, 6432.0, 6488.448481879093, 6507.837304067436, 6571.244306904767, 6662.267150613526, 6637.269900311058, 6651.268360480247, 6657.367550082734, 6658.234303055211, 6664.266930637529, 6792.117024786746, 6799.082123913407, 6851.520393572402, 6884.552504087218, 6892.104026476726, 6553.574017689026, 6555.737770489127, 6561.343865613591, 6659.167604049494, 6660.500462465431, 6327.841803954938, 6449.677516124269, 6462.224533056064, 6542.247641521286, 6619.073329733745, 6461.095446637569, 6462.321456247027, 6463.450606698295, 6468.8059358219325, 6495.642739649365, 6509.088727578048, 6583.670816459254, 6635.867282654223, 6641.169853768279, 6652.567583107082, 6329.17720696325, 6361.90457143134, 6429.22849258092, 6429.58207716497, 6435.292117866938], "value": 6080.242710822855, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.4822417204645, 803.4897839871682, 805.5448671500602, 808.543173107194, 808.5593351623369, 38686.61313386869, 42029.36955945663, 42403.78798106009, 42416.72749817502, 42458.150836983245, 38403.039924001845, 38415.0, 38484.807575962084, 38535.421968670424, 38695.226095478145, 6818.829529261783, 7669.463137580372, 7950.045994480671, 7951.363890888715, 7958.721444749435, 7764.3788496920115, 7889.171636978121, 7890.092639346462, 7892.052953645571, 7904.05151381835, 7846.215378462165, 7923.683052677886, 7927.643256053491, 7934.166912474194, 7937.682492700285, 7858.803529911741, 7883.487573307717, 7887.0, 7898.526088434713, 7899.605019749047, 7653.425993050488, 7796.415268854858, 7800.297973182441, 7809.140994490602, 7825.295723384923, 36798.0, 36807.4478882817, 36995.815020924994, 37146.62853371459, 37149.442758358375, 3566.2154326048226, 3600.47793070006, 3602.657747513982, 3604.711623070148, 3606.3688854450365, 30851.0, 31002.0, 31026.534601980988, 31164.220894477592, 31355.529667054783, 5050.393952725696, 5161.922571161435, 5254.4220135785035, 5285.2600635911, 5291.391489978663, 189772.05113974342, 190072.0, 190252.0, 190372.9999999986, 191098.0, 41620.58379416167, 42154.36768448476, 48173.0, 48745.268820967714, 49229.0, 51338.0, 51803.7409812956, 51899.0, 51921.0, 51955.740221299406, 15913.204339783197, 15986.920065399598, 16019.199040047952, 16022.359105635644, 16067.0, 12879.098463107492, 12904.870951290555, 12949.417276222594, 13053.543125990545, 13062.150960187748, 14318.498852540111, 14359.210243436712, 14387.208703521197, 14395.496157634432, 14528.346224419927, 5969.2243106955475, 5984.640921544721, 5987.64074155552, 6003.849903752441, 6059.394060593993, 984.4388698441962, 1001.8096561653263, 1006.833872411044, 1019.1184625299211, 1030.5877648940484, 22894.084236630348, 23093.0, 23110.0, 23285.3014409568, 23349.533009339717, 1099.5766629847567, 1103.3104309806372, 1117.5473933057124, 1125.1673761416494, 1130.9943450282697, 11908.642740717793, 11966.22219555726, 12024.759504809856, 12072.517099316105, 12092.879071209176, 219535.0, 219804.0, 219975.0, 220374.0, 220935.0, 52976.20535691968, 53221.46778532165, 53360.73319633377, 53465.0, 53512.46487535153, 1418.4609848257596, 1430.0918916488106, 1442.0986883198002, 1454.0403333799852, 1454.4836583012877, 22409.77590224077, 22509.43726406853, 22607.660885086407, 22793.430164246027, 22849.885750571142, 37663.81168094142, 37793.05517362088, 37815.24369512539, 37924.999999998894, 37934.810325948194, 200746.0, 201156.0, 201963.0, 201990.0, 202548.0, 33767.49348759722, 35240.47139292964, 35914.64085359217, 36010.27979440449, 36060.81969590135, 46102.769486152356, 46854.29718554156, 46928.53071469242, 47002.76498617485, 47148.764256178496, 33852.66147338495, 33858.0, 33878.661213388536, 33881.49177762385, 34280.82859585686, 33554.83222583871, 33671.0, 33730.325393491505, 33786.15534611556, 33845.83077084599, 37561.43657845189, 37614.81192594129, 38055.0, 38220.42669360017, 38369.42445863259, 32700.83649581737, 33062.66937330596, 33084.33831323408, 33197.0, 33206.501902471966, 24234.030638774253, 24399.75600243975, 24797.752022479544, 24975.125870594442, 25182.874085629453], "value": 17278.09757849389, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.2300482845744, 573.368765861125, 575.8761866198766, 581.13120884278, 605.3946053946054, 21784.78215217847, 22521.662175067388, 22547.774522254793, 22759.31722048337, 22797.202097926576, 23470.0, 24188.274351769433, 24351.686589773944, 24410.511789764194, 24560.140395086175, 6443.259025212114, 6686.297938716413, 6690.096836927026, 6698.229703584101, 6716.966415167942, 6382.872342553146, 6541.21505419348, 6600.93399066008, 6699.866002679943, 6738.865222695543, 6637.037629543714, 6681.231658359302, 6683.331666833326, 6683.665816709169, 6710.899336509956, 6430.774922877724, 6626.536142470029, 6634.369734875179, 6652.567583107082, 6657.700403481855, 6161.353057928943, 6198.163247961557, 6204.472619827275, 6206.534509911729, 6208.5033197344555, 27196.184114476597, 27634.170974870787, 27674.169774906786, 27679.30801729953, 27683.169504914884, 2004.3185316992146, 2042.4689580709123, 2042.7038079478393, 2045.2125931516325, 2058.0121541659937, 18639.81360186394, 19048.238070477164, 19266.22935082595, 19335.226590936345, 19382.70925936112, 2453.2149712092137, 2461.163204510475, 2500.962485562718, 2502.074232533956, 2508.548461276969, 116986.0, 121169.39415302956, 129812.0, 133390.0, 134676.0, 13933.930330348487, 14084.647883802782, 14090.900001999544, 14237.288135593179, 14473.664766989865, 20438.897805511173, 20475.180992760124, 20489.2828750993, 20505.38483845487, 20600.27899023557, 9496.24030077606, 9710.707664322961, 9733.2213422926, 9844.655437059811, 10016.39901605906, 9099.863502047474, 9191.724248272563, 9247.213986811197, 9271.304652151115, 9341.205997490153, 8550.07623284756, 8550.572471376407, 8646.43798153118, 8697.521636310064, 8728.869066964, 2546.465242299138, 2571.06898136125, 2612.8301660392012, 2681.9004208274514, 2702.3649442381025, 961.6682244625594, 964.2478866484154, 981.7300242433388, 991.965281215154, 1003.9648612298535, 18926.0, 19065.42803715891, 19267.22931082741, 19366.70949935752, 19425.417237482616, 1035.4770840725384, 1039.6205385034407, 1048.292402628238, 1050.1703654113164, 1052.5421441672743, 10257.487125643687, 10581.62964296246, 10705.571777128827, 10720.195985301134, 10899.237053406345, 460186.0, 471680.0, 482245.0, 484367.0, 486506.0, 110007.44996274967, 110432.44783776191, 110871.0, 111145.0, 114409.0, 1153.6712037069562, 1175.8236264560385, 1207.4626791077885, 1208.6434501821984, 1209.5826939705964, 16795.740319476332, 16902.23939922731, 17143.399981000606, 17425.128743562516, 17435.389761358292, 53271.20093198682, 53979.190312144565, 54831.45168548423, 54928.450715493935, 55658.44341556533, 68385.65807170932, 68738.65630671814, 68871.65564172347, 68965.65517172382, 69502.65248673927, 13813.240271784747, 14054.929725351309, 14248.073875198079, 14268.928655357073, 14279.0, 21752.129914803863, 21798.564028719018, 21835.890820545796, 21963.011664474816, 22373.77626223717, 16469.34122635081, 16490.0, 16739.91630041842, 16761.664766704354, 17328.0, 15604.609884752972, 15755.842441575438, 15891.443799466962, 16483.093429861474, 16521.504354869372, 17126.0, 18031.368902088365, 18227.270909164014, 18292.45122646323, 18351.265949361878, 13864.584062478141, 13883.722325553634, 13972.231527266098, 13998.860011400164, 14007.439702412197, 16995.915020424818, 17357.392491262744, 17551.122443878012, 17755.11224438747, 17965.281388744304], "value": 11644.051341538197, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.3746532931527, 1363.8772510474055, 1378.9241591712455, 1391.5338361648846, 1398.5944076217897, 14636.56090317291, 15348.232588370578, 15360.769588456169, 15364.385424583003, 15370.769438458432, 14996.700065998673, 15096.924515377435, 15137.091774493538, 15170.089794612331, 15194.240287985584, 2711.810173287861, 2715.551933930899, 2770.3074231442138, 2771.974369483294, 2772.8336299821976, 2735.029064682029, 2735.029064682039, 2738.9863050684717, 2741.7258274172623, 2746.0388863897674, 2721.972780272192, 2841.2186648671573, 2842.9289267768368, 2844.7013063628333, 2847.245479947805, 2740.0957683964243, 2740.273827435731, 2761.047438633668, 2767.6125342452074, 2770.1551026936777, 2672.652555167835, 2707.539718247903, 2721.088435374152, 2725.427660191362, 2727.6317697110835, 14522.564323070324, 14771.113733176044, 14859.257037148207, 14862.256887155707, 14874.628134296621, 7706.65320060593, 7882.487638303549, 7891.644875980594, 7899.605019749047, 7947.562884041376, 5340.54605358545, 5428.728563571805, 5511.724413779295, 5541.168824676331, 5574.080276754331, 1797.1463554811492, 1811.8369346758855, 1864.4313484387255, 1877.183425210038, 1891.4136617648574, 23530.64704029441, 24240.636390454158, 25843.61234581483, 25907.09325173629, 25944.48111037769, 6672.5329226953645, 6853.4174595158975, 6861.382475577222, 6906.8618627628175, 6910.136232970879, 17142.742858857127, 17187.742183867253, 17215.0, 17238.396656117227, 17252.568685782706, 7734.110577283572, 7788.260115289038, 7978.321842643324, 7982.361411087215, 8016.759497215093, 7136.464765142635, 7201.279872012861, 7202.49582529218, 7203.27967203286, 7259.6007219603425, 5067.707734527672, 5078.847634570969, 5114.5652619527755, 5160.251763494272, 5207.114790485626, 1451.325133812782, 1503.9624009399633, 1507.163524244055, 1517.681286929735, 1537.5541093082882, 880.7313769300297, 884.0, 902.9277657787361, 907.2016625369632, 909.1771946388529, 7238.203797582261, 7321.231270716605, 7408.333250007525, 7422.851542969111, 7438.814029649194, 709.4678990756933, 715.9498835081598, 722.342668171961, 722.8229083874379, 727.0947670150696, 4857.951420485821, 5081.110805609078, 5131.7690703918415, 5235.214717792362, 5274.498922602346, 21409.250676226256, 21903.342899713036, 22098.668519972212, 22172.667409988862, 22192.88903555472, 20820.895895520425, 21627.2430464933, 22312.330630081124, 22379.776202238096, 22405.88797056004, 10785.191110666732, 11037.668869933916, 11056.391898445501, 11058.11535077192, 11195.0, 8217.79455513617, 8492.957535212285, 8525.104863989192, 8576.957115214384, 8682.218600326001, 18119.72820407721, 18267.451976440727, 18276.45170644883, 18458.63082738311, 18587.0, 18479.353222637143, 18536.721949171042, 18666.440006799818, 18681.43955681332, 18739.0, 11144.264728591477, 11146.219764616724, 11315.773684526428, 11337.149713771336, 11372.488238029475, 13867.167969921837, 14000.859991399955, 14027.649308767363, 14033.578992630237, 14043.0, 11157.776844462904, 11201.271917325348, 11298.548058077586, 11515.366654834064, 11644.708882278012, 10942.507587158756, 11358.204925655125, 11527.308361498337, 11543.538258469567, 11780.823287650863, 10083.546240419346, 10327.225458090523, 10328.225383096447, 10333.173346132142, 10335.534900929326, 6697.7655782047395, 6710.798676039727, 6796.1843181864015, 6822.317768223138, 6928.515003949877, 6512.6092434454085, 6583.111279977142, 6601.306862779531, 6624.271330153583, 6650.83372915681], "value": 6568.755627481545, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.6463393307721, 506.77955089536044, 507.34045740537294, 526.2159382520045, 529.3356837169355, 6117.938820611793, 6140.20177376941, 6160.888751180648, 6164.828682550318, 6203.8138855834395, 5973.0, 5992.880142397149, 5996.52027837773, 6039.758409663619, 6083.482903953158, 4880.55972013993, 4945.258211268302, 5082.695038297705, 5090.160123579605, 5138.922916156261, 4966.0, 4989.476105008976, 5064.56951159152, 5076.695398276097, 5079.771410286546, 4917.72952487613, 4966.652334336598, 4979.47715489872, 4990.975045124769, 5005.324281222043, 4948.0, 4950.4307004694565, 4957.5042495750495, 4971.850844474671, 4990.351254336949, 4462.3752674625575, 4470.396496472967, 4486.170058539181, 4501.234790085693, 4505.211587972091, 6092.08618707193, 6164.84587885302, 6176.6911654417545, 6205.0, 6262.530310226705, 5630.605857589971, 5641.68970706611, 5645.2096706461125, 5664.546836253131, 5707.172459993278, 6432.581882177644, 6465.515086368541, 6520.510961677893, 6520.739170433177, 6527.086207930894, 4867.34290870735, 4872.415310162768, 4873.366462359871, 4873.488283730193, 4888.437829649564, 6824.488163387716, 6864.279250678656, 6869.244383117853, 6888.965555172192, 6927.515073944826, 4928.8767780805765, 4951.306817045616, 4975.502449755068, 4985.750712464363, 5029.0, 5037.924431133536, 5052.242163675404, 5062.569681577107, 5078.466760990117, 5080.221922327156, 5593.636413633101, 5630.23991761107, 5648.0, 5702.258706368146, 5730.484256416942, 6049.667268300198, 6160.507159427235, 6384.680765961683, 6477.514186436036, 6559.770408035696, 1709.538424625344, 1728.8503145408163, 1749.7550342951997, 1753.7369394590914, 1763.8147994460653, 1186.5787645385765, 1187.5249900040053, 1195.6353312239676, 1217.7503611759569, 1223.7578857459685, 5560.499555040065, 5590.440955904377, 5617.662940223599, 5800.854978625483, 5859.121131830259, 6526.0, 6636.473611069481, 6646.53474256807, 6647.302033286532, 6799.898001529981, 5637.23897273871, 5690.77236910519, 5757.524264566193, 5792.739326730307, 5802.796902108406, 3520.8239588020497, 3521.2605352876185, 3550.3254381667402, 3552.342816578941, 3568.1436455250555, 6459.935400646027, 6512.967435162794, 6523.967380163069, 6529.706163222666, 6606.306337834554, 6848.246692863781, 6887.311268873073, 6904.3095690430555, 6915.135608048994, 6922.134733158356, 6492.707828147651, 6554.967225163844, 6555.6066636002, 6579.83550411234, 6628.436582890508, 6650.268470468342, 6713.0196592004495, 6718.875722788926, 6724.193096828412, 6738.7304507819135, 6569.441597464269, 6607.438367738604, 6660.167479065117, 6673.566218195801, 6693.330666933268, 6539.967300163468, 6542.214934207926, 6551.279359270572, 6553.672316384257, 6614.636195009129, 4901.921577252999, 4918.458969513279, 4924.975375123101, 4930.926036109533, 4997.550220480174, 6418.839529011812, 6466.482681385572, 6507.934920650733, 6622.966885165543, 6643.3356664335115, 6231.594946328474, 6445.484361250995, 6492.220933488012, 6492.448141907991, 6572.047053177167, 6314.737052589408, 6419.679016049272, 6455.0, 6489.253735820441, 6535.379138981789, 6064.0, 6235.625862448268, 6321.52588555851, 6371.750407292222, 6388.0, 6451.2903580607035, 6486.383793539606, 6531.542792004514, 6553.344665533408, 6568.507361947969, 6217.191765070512, 6231.844203894939, 6271.33809540463, 6299.370062993664, 6326.588771729823], "value": 5025.7102865299785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [905.3255324783038, 908.4049947284523, 921.6267411698265, 928.8420968435365, 934.5747684803413, 19038.99999999998, 19727.40817775467, 19760.80239197611, 19775.604487910234, 19792.604147917053, 17988.82011179888, 18073.096345182756, 18146.0, 18189.0, 18202.817971820277, 966.2318456826822, 980.5587485631459, 982.5774916785779, 986.7286496213527, 987.2151639446621, 971.8299297622888, 973.3332667123008, 974.1086905481476, 976.6728146071039, 977.6773664690635, 992.4095163377815, 1028.7376718936662, 1031.0823367203195, 1033.7880734449457, 1040.3289878028668, 1021.596469394585, 1024.9590016399336, 1026.0867827633374, 1030.5208078243577, 1030.634124885666, 716.5987047253554, 737.0602481835703, 740.4742632730768, 746.7946314763429, 747.1743723185856, 19450.319238826716, 19602.607947841105, 19676.0, 19694.606107877906, 19739.0, 20103.89948050265, 20372.69440958387, 20401.8979905101, 20910.0, 20934.47663808402, 16873.746893796604, 16885.40901068469, 16968.660626787394, 17210.82789172105, 17211.05339206343, 3752.061984503874, 3773.1699026214187, 3775.3959366501504, 3775.6035616260174, 3780.6597406233295, 101287.49356253246, 125284.0, 139850.30074849562, 140280.0, 142095.0, 16606.584835379213, 17151.313947442213, 17247.655046899243, 17249.741253881202, 17287.65424691499, 28761.42477150487, 29492.115236542937, 29577.0, 29610.40779184404, 29755.702442975293, 9111.0, 9177.678781242757, 9218.0, 9223.769405764775, 9224.953875230714, 4001.3397789365013, 4113.568075352105, 4126.938095928564, 4148.7303325284365, 4164.229617520768, 5177.042247184283, 5259.342582177228, 5260.552853007537, 5262.500062494057, 5283.550898173621, 1043.3948309980194, 1064.1699474409972, 1075.7471994081384, 1078.5901357484263, 1081.7998670245872, 691.9550229235084, 712.4300559552337, 719.4496210399049, 733.6991833348302, 737.2295950731499, 11829.585964491202, 12294.938525307494, 12494.687632809071, 12605.93697031509, 12863.421146048264, 668.1414382518441, 676.9187697476236, 677.2381071294793, 693.8646963842002, 704.9365557099885, 5426.2674538936735, 5428.565714742811, 5440.211169380497, 5451.509364157245, 5497.780088796403, 124404.37797810954, 125415.0, 126687.0, 127518.0, 130761.34619327032, 106599.0, 109061.0, 109825.0, 111234.44382778034, 117811.0, 96181.0, 96503.51748241074, 97180.0, 97870.51064744631, 99486.0, 12768.425420855898, 13205.075644705163, 13272.734545309233, 13437.395317210945, 13601.0, 43468.347974781034, 43505.56494435015, 43701.78149109234, 44193.55806442023, 45769.54230457653, 56055.43944560502, 57319.7134014344, 57384.71307643435, 58720.412795871496, 58725.0, 18668.15993280333, 18775.90612046931, 18807.153678084247, 18889.527761806065, 19191.136398862363, 20301.59396812085, 20396.89801550983, 20398.28605998783, 20667.069981850553, 20692.0, 18522.0, 18718.625627487647, 18724.7191292128, 18742.344017959305, 18865.15106820169, 17399.304027838745, 17566.560835979202, 17647.911760441115, 17669.38157164493, 17750.556236094202, 16868.156592170588, 16919.407820726214, 16968.66062678764, 17016.404425845038, 17040.40358587493, 12996.740065198454, 13281.203127812361, 13426.932865335611, 13461.125026873222, 13579.52471663487, 13713.0, 13970.511032113827, 13988.37052332668, 14048.859511405164, 14049.789253161414], "value": 9223.243268964667, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1423.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 108.0, 107.0, 108.0, 107.0, 96.0, 107.0, 108.0, 106.0, 107.0], "value": 106.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 117.0, 116.0, 120.0, 117.0, 119.0, 120.0, 116.0, 117.0], "value": 118.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 165.0, 163.0, 166.0, 162.0, 163.0, 165.0, 169.0, 163.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 79.0, 77.0, 73.0, 75.0, 74.0, 74.0, 73.0, 75.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 80.0, 79.0, 81.0, 79.0, 80.0, 83.0, 80.0, 80.0], "value": 80.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 113.0, 113.0, 110.0, 114.0, 115.0, 115.0, 113.0, 114.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 148.0, 149.0, 149.0, 151.0, 149.0, 149.0], "value": 148.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 93.0, 99.0, 94.0, 95.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 55.0, 56.0, 54.0, 55.0, 55.0, 55.0, 55.0, 56.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0], "value": 37.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 74.0, 79.0, 74.0, 75.0, 81.0, 73.0, 76.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 118.0, 111.0, 112.0, 114.0, 113.0, 113.0, 117.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 175.0, 172.0, 168.0, 171.0, 167.0, 175.0, 170.0, 171.0], "value": 174.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 64.0, 69.0, 69.0, 69.0, 68.0, 68.0, 66.0, 73.0], "value": 68.1, "unit": "score"}]}]} 21:25:32 INFO - Return code: 0 21:25:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:25:32 INFO - # TBPL SUCCESS # 21:25:32 INFO - Running post-action listener: _package_coverage_data 21:25:32 INFO - Running post-action listener: _resource_record_post_action 21:25:32 INFO - [mozharness: 2017-09-17 04:25:32.841006Z] Finished run-tests step (success) 21:25:32 INFO - Running post-run listener: _resource_record_post_run 21:25:32 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 347361280; Read time: 0; Write time: 273940 21:25:32 INFO - TinderboxPrint: CPU usage
13.1% 21:25:32 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 21:25:32 INFO - TinderboxPrint: I/O write bytes / time
347,361,280 / 273,940 21:25:32 INFO - TinderboxPrint: CPU idle
8,235.2 (86.9%) 21:25:32 INFO - TinderboxPrint: CPU user
1,191.8 (12.6%) 21:25:32 INFO - TinderboxPrint: Swap in / out
0 / 0 21:25:32 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 10092544; Read time: 0; Write time: 756 21:25:32 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 21:25:32 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 0; Write bytes: 337268736; Read time: 0; Write time: 273184 21:25:33 INFO - Running post-run listener: _upload_blobber_files 21:25:33 INFO - Blob upload gear active. 21:25:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:25:33 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 21:25:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:25:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:25:33 INFO - (blobuploader) - INFO - Open directory for files ... 21:25:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:25:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:25:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:35 INFO - (blobuploader) - INFO - Done attempting. 21:25:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:25:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:25:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:35 INFO - (blobuploader) - INFO - Done attempting. 21:25:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:25:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:25:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:39 INFO - (blobuploader) - INFO - Done attempting. 21:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:25:40 INFO - (blobuploader) - INFO - Done attempting. 21:25:40 INFO - (blobuploader) - INFO - Iteration through files over. 21:25:40 INFO - Return code: 0 21:25:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:25:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:25:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf8b6a59aa3c26372332cb92d4277130d89c15cfaf013569aea42a643eb83eda7ffef83371479a4bfac56bb6f9421bf6a23e5524a6e718da817c7d994b2d1fda", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a3fb939aef5d947912875169d682271a8a29891bb96c6146e5c8962f22e468c8f953d557a6952e6bdd3d016f0cd86c0d41c546648571fef733751a90fbf2881", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/283142f65aad6d8526313745d1ff594dfe3b0e1cf9ddd51dba59f3d1e544f3ea49f6bf0aab790fd5df51b23c36bcc0893bee4bddb775cfe6cd62c28da4392bd6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/171b3d1fc370de85c1bf7b12de8d3bb856acff8a45d6ae50fdeb5913911145c1e7dd69ffefce54dc15b4af5d358f409eaf05c50038a529607c55ce2c028f80c1"} 21:25:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:25:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:25:40 INFO - Contents: 21:25:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf8b6a59aa3c26372332cb92d4277130d89c15cfaf013569aea42a643eb83eda7ffef83371479a4bfac56bb6f9421bf6a23e5524a6e718da817c7d994b2d1fda", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a3fb939aef5d947912875169d682271a8a29891bb96c6146e5c8962f22e468c8f953d557a6952e6bdd3d016f0cd86c0d41c546648571fef733751a90fbf2881", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/283142f65aad6d8526313745d1ff594dfe3b0e1cf9ddd51dba59f3d1e544f3ea49f6bf0aab790fd5df51b23c36bcc0893bee4bddb775cfe6cd62c28da4392bd6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/171b3d1fc370de85c1bf7b12de8d3bb856acff8a45d6ae50fdeb5913911145c1e7dd69ffefce54dc15b4af5d358f409eaf05c50038a529607c55ce2c028f80c1"} 21:25:40 INFO - Running post-run listener: copy_logs_to_upload_dir 21:25:40 INFO - Copying logs to upload dir... 21:25:40 INFO - mkdir: /builds/slave/test/build/upload/logs 21:25:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.269603 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2017-09-16 21:25:40.613716) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 21:25:40.616800) ========= 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=41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf8b6a59aa3c26372332cb92d4277130d89c15cfaf013569aea42a643eb83eda7ffef83371479a4bfac56bb6f9421bf6a23e5524a6e718da817c7d994b2d1fda", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a3fb939aef5d947912875169d682271a8a29891bb96c6146e5c8962f22e468c8f953d557a6952e6bdd3d016f0cd86c0d41c546648571fef733751a90fbf2881", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/283142f65aad6d8526313745d1ff594dfe3b0e1cf9ddd51dba59f3d1e544f3ea49f6bf0aab790fd5df51b23c36bcc0893bee4bddb775cfe6cd62c28da4392bd6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/171b3d1fc370de85c1bf7b12de8d3bb856acff8a45d6ae50fdeb5913911145c1e7dd69ffefce54dc15b4af5d358f409eaf05c50038a529607c55ce2c028f80c1"} build_url:https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011345 build_url: 'https://queue.taskcluster.net/v1/task/HL4DSwsdQYWbDYpmGciB1w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bf8b6a59aa3c26372332cb92d4277130d89c15cfaf013569aea42a643eb83eda7ffef83371479a4bfac56bb6f9421bf6a23e5524a6e718da817c7d994b2d1fda", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a3fb939aef5d947912875169d682271a8a29891bb96c6146e5c8962f22e468c8f953d557a6952e6bdd3d016f0cd86c0d41c546648571fef733751a90fbf2881", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/283142f65aad6d8526313745d1ff594dfe3b0e1cf9ddd51dba59f3d1e544f3ea49f6bf0aab790fd5df51b23c36bcc0893bee4bddb775cfe6cd62c28da4392bd6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/171b3d1fc370de85c1bf7b12de8d3bb856acff8a45d6ae50fdeb5913911145c1e7dd69ffefce54dc15b4af5d358f409eaf05c50038a529607c55ce2c028f80c1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-16 21:25:40.648746) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:25:40.649136) ========= 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=41bf3e9aed84707956354da8000001c5-1505621103.774689-1624502710 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004537 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-16 21:25:40.730343) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 21:25:40.730657) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-16 21:25:40.730968) ========= ========= Total master_lag: 0.24 =========