builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-074 starttime: 1506292890.35 results: success (0) revision: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:30.350589) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:30.351138) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:30.351441) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:30.386693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:30.387093) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-24 15:41:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 111M=0s 2017-09-24 15:41:30 (111 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.230267 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:30.648669) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:30.649062) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015193 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:30.700320) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-24 15:41:30.700661) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 --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 d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 --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=23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-24 15:41:30,787 truncating revision to first 12 chars 2017-09-24 15:41:30,787 Setting DEBUG logging. 2017-09-24 15:41:30,788 attempt 1/10 2017-09-24 15:41:30,788 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d9cfaad3b11e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-24 15:41:31,827 unpacking tar archive at: mozilla-central-d9cfaad3b11e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.342527 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-24 15:41:32.059736) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:32.060063) ========= script_repo_revision: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:32.060456) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:32.060720) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-24 15:41:32.074185) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-24 15:41:32.074723) ========= /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=23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:41:32 INFO - MultiFileLogger online at 20170924 15:41:32 in /builds/slave/test 15:41:32 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 15:41:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:41:32 INFO - {'append_to_log': False, 15:41:32 INFO - 'base_work_dir': '/builds/slave/test', 15:41:32 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 15:41:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:41:32 INFO - 'branch': 'Firefox-Non-PGO', 15:41:32 INFO - 'buildbot_json_path': 'buildprops.json', 15:41:32 INFO - 'code_coverage': False, 15:41:32 INFO - 'config_files': ('talos/linux_config.py',), 15:41:32 INFO - 'default_actions': ('clobber', 15:41:32 INFO - 'read-buildbot-config', 15:41:32 INFO - 'download-and-extract', 15:41:32 INFO - 'populate-webroot', 15:41:32 INFO - 'create-virtualenv', 15:41:32 INFO - 'install', 15:41:32 INFO - 'setup-mitmproxy', 15:41:32 INFO - 'run-tests'), 15:41:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:41:32 INFO - 'disable_ccov_upload': False, 15:41:32 INFO - 'disable_stylo': False, 15:41:32 INFO - 'download_minidump_stackwalk': True, 15:41:32 INFO - 'download_symbols': 'ondemand', 15:41:32 INFO - 'enable_stylo': False, 15:41:32 INFO - 'enable_webrender': False, 15:41:32 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 15:41:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:41:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:41:32 INFO - 'gecko_profile': False, 15:41:32 INFO - 'gecko_profile_interval': 0, 15:41:32 INFO - 'installer_path': 'installer.exe', 15:41:32 INFO - 'log_level': 'info', 15:41:32 INFO - 'log_name': 'talos', 15:41:32 INFO - 'log_to_console': True, 15:41:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:41:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:41:32 INFO - 'opt_config_files': (), 15:41:32 INFO - 'pip_index': False, 15:41:32 INFO - 'suite': 'dromaeojs-e10s', 15:41:32 INFO - 'system_bits': '32', 15:41:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:41:32 INFO - 'title': 'talos-linux64-ix-074', 15:41:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:41:32 INFO - 'use_talos_json': True, 15:41:32 INFO - 'verify': 'False', 15:41:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:41:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:41:32 INFO - 'work_dir': 'build'} 15:41:32 INFO - [mozharness: 2017-09-24 22:41:32.394748Z] Running clobber step. 15:41:32 INFO - Running pre-action listener: _resource_record_pre_action 15:41:32 INFO - Running main action method: clobber 15:41:32 INFO - rmtree: /builds/slave/test/build 15:41:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:41:33 INFO - Running post-action listener: _resource_record_post_action 15:41:33 INFO - [mozharness: 2017-09-24 22:41:33.345725Z] Finished clobber step (success) 15:41:33 INFO - [mozharness: 2017-09-24 22:41:33.345822Z] Running read-buildbot-config step. 15:41:33 INFO - Running pre-action listener: _resource_record_pre_action 15:41:33 INFO - Running main action method: read_buildbot_config 15:41:33 INFO - Using buildbot properties: 15:41:33 INFO - { 15:41:33 INFO - "project": "", 15:41:33 INFO - "product": "firefox", 15:41:33 INFO - "who": "archaeopteryx@coole-files.de", 15:41:33 INFO - "installer_path": "public/build/target.tar.bz2", 15:41:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 15:41:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 15:41:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 15:41:33 INFO - "stage_platform": "linux64", 15:41:33 INFO - "basedir": "/builds/slave/test", 15:41:33 INFO - "buildnumber": 1174, 15:41:33 INFO - "platform": "ubuntu64_hw", 15:41:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:41:33 INFO - "slavebuilddir": "test", 15:41:33 INFO - "taskId": "DEAuy6qITLmqEcbPCw5ONg", 15:41:33 INFO - "branch": "mozilla-central", 15:41:33 INFO - "script_repo_revision": "production", 15:41:33 INFO - "revision": "d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4", 15:41:33 INFO - "slavename": "talos-linux64-ix-074", 15:41:33 INFO - "repo_path": "mozilla-central" 15:41:33 INFO - } 15:41:33 INFO - Finding installer, test and symbols from parent task. 15:41:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DEAuy6qITLmqEcbPCw5ONg'}, attempt #1 15:41:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DEAuy6qITLmqEcbPCw5ONg'}, attempt #1 15:41:34 INFO - Task dependencies: fh1savD2Q1aQeqxvlInuLA 15:41:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA'}, attempt #1 15:41:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 15:41:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.test_packages.json 15:41:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.crashreporter-symbols.zip 15:41:34 INFO - Running post-action listener: _resource_record_post_action 15:41:34 INFO - [mozharness: 2017-09-24 22:41:34.723238Z] Finished read-buildbot-config step (success) 15:41:34 INFO - [mozharness: 2017-09-24 22:41:34.723457Z] Running download-and-extract step. 15:41:34 INFO - Running pre-action listener: _resource_record_pre_action 15:41:34 INFO - Running main action method: download_and_extract 15:41:34 INFO - mkdir: /builds/slave/test/build/tests 15:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:41:34 INFO - trying https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.test_packages.json 15:41:34 INFO - Downloading https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 15:41:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 15:41:35 INFO - Downloaded 989 bytes. 15:41:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 15:41:35 INFO - Using the following test package requirements: 15:41:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 15:41:35 INFO - u'common': [u'target.common.tests.zip'], 15:41:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 15:41:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 15:41:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 15:41:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 15:41:35 INFO - u'mozbase': [u'target.common.tests.zip'], 15:41:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 15:41:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 15:41:35 INFO - u'web-platform': [u'target.common.tests.zip', 15:41:35 INFO - u'target.web-platform.tests.tar.gz'], 15:41:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 15:41:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 15:41:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.common.tests.zip 15:41:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.common.tests.zip'}, attempt #1 15:41:35 INFO - Fetch https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.common.tests.zip into memory 15:41:37 INFO - Content-Length response header: 54944009 15:41:37 INFO - Bytes received: 54944009 15:41:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.talos.tests.zip 15:41:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 15:41:48 INFO - Fetch https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.talos.tests.zip into memory 15:41:49 INFO - Content-Length response header: 14513823 15:41:49 INFO - Bytes received: 14513823 15:41:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:41:49 INFO - trying https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 15:41:49 INFO - Downloading https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 15:41:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 15:41:52 INFO - Downloaded 61624893 bytes. 15:41:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 15:41:52 INFO - mkdir: /builds/slave/test/properties 15:41:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:41:52 INFO - Writing to file /builds/slave/test/properties/build_url 15:41:52 INFO - Contents: 15:41:52 INFO - build_url:https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 15:41:52 INFO - Running post-action listener: _resource_record_post_action 15:41:52 INFO - Running post-action listener: find_tests_for_verification 15:41:52 INFO - Running post-action listener: set_extra_try_arguments 15:41:52 INFO - [mozharness: 2017-09-24 22:41:52.241999Z] Finished download-and-extract step (success) 15:41:52 INFO - [mozharness: 2017-09-24 22:41:52.242099Z] Running populate-webroot step. 15:41:52 INFO - Running pre-action listener: _resource_record_pre_action 15:41:52 INFO - Running main action method: populate_webroot 15:41:52 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:41:52 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['tresize', 15:41:52 INFO - 'tcanvasmark']}, 15:41:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:41:52 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['dromaeo_css', 15:41:52 INFO - 'kraken']}, 15:41:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:52 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:41:52 INFO - 'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:41:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:41:52 INFO - 'tests': ['damp', 'tps']}, 15:41:52 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:41:52 INFO - 'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['damp', 'tps']}, 15:41:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:41:52 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['dromaeo_dom']}, 15:41:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:41:52 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['basic_compositor_video', 15:41:52 INFO - 'glvideo']}, 15:41:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:41:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:41:52 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:41:52 INFO - 'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['ts_paint_webext', 15:41:52 INFO - 'tp5o_webext']}, 15:41:52 INFO - 'other-e10s': {'tests': ['a11yr', 15:41:52 INFO - 'ts_paint', 15:41:52 INFO - 'tpaint', 15:41:52 INFO - 'sessionrestore', 15:41:52 INFO - 'sessionrestore_many_windows', 15:41:52 INFO - 'sessionrestore_no_auto_restore', 15:41:52 INFO - 'tabpaint']}, 15:41:52 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['a11yr', 15:41:52 INFO - 'ts_paint', 15:41:52 INFO - 'tpaint', 15:41:52 INFO - 'sessionrestore', 15:41:52 INFO - 'sessionrestore_many_windows', 15:41:52 INFO - 'sessionrestore_no_auto_restore', 15:41:52 INFO - 'tabpaint']}, 15:41:52 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 15:41:52 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 15:41:52 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['perf_reftest_singletons']}, 15:41:52 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['perf_reftest']}, 15:41:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:41:52 INFO - 'tsvgr_opacity', 15:41:52 INFO - 'tart', 15:41:52 INFO - 'tscrollx', 15:41:52 INFO - 'tsvg_static']}, 15:41:52 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['tsvgx', 15:41:52 INFO - 'tsvgr_opacity', 15:41:52 INFO - 'tart', 15:41:52 INFO - 'tscrollx', 15:41:52 INFO - 'tsvg_static']}, 15:41:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:41:52 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:41:52 INFO - 'talos_options': ['--disable-stylo'], 15:41:52 INFO - 'tests': ['tp5o']}, 15:41:52 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:41:52 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:41:52 INFO - 'talos_options': ['--mitmproxy', 15:41:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:41:52 INFO - '--firstNonBlankPaint'], 15:41:52 INFO - 'tests': ['tp6_google', 15:41:52 INFO - 'tp6_youtube', 15:41:52 INFO - 'tp6_amazon', 15:41:52 INFO - 'tp6_facebook']}, 15:41:52 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:41:52 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:41:52 INFO - 'talos_options': ['--disable-stylo', 15:41:52 INFO - '--mitmproxy', 15:41:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:41:52 INFO - '--firstNonBlankPaint'], 15:41:52 INFO - 'tests': ['tp6_google', 15:41:52 INFO - 'tp6_youtube', 15:41:52 INFO - 'tp6_amazon', 15:41:52 INFO - 'tp6_facebook']}, 15:41:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:41:52 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 15:41:52 INFO - 'talos_options': ['--stylo-threads=1', 15:41:52 INFO - '--mitmproxy', 15:41:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:41:52 INFO - '--firstNonBlankPaint'], 15:41:52 INFO - 'tests': ['tp6_google', 15:41:52 INFO - 'tp6_youtube', 15:41:52 INFO - 'tp6_amazon', 15:41:52 INFO - 'tp6_facebook']}, 15:41:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:41:52 INFO - 'talos_options': ['--xperf_path', 15:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:52 INFO - 'tests': ['tp5n']}, 15:41:52 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 15:41:52 INFO - 'talos_options': ['--disable-stylo', 15:41:52 INFO - '--xperf_path', 15:41:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:41:52 INFO - 'tests': ['tp5n']}}} 15:41:52 INFO - Running post-action listener: _resource_record_post_action 15:41:52 INFO - [mozharness: 2017-09-24 22:41:52.257545Z] Finished populate-webroot step (success) 15:41:52 INFO - [mozharness: 2017-09-24 22:41:52.257634Z] Running create-virtualenv step. 15:41:52 INFO - Running pre-action listener: _resource_record_pre_action 15:41:52 INFO - Running main action method: create_virtualenv 15:41:52 INFO - Creating virtualenv /builds/slave/test/build/venv 15:41:52 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:41:52 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 15:41:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:41:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:41:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:41:53 INFO - Installing distribute.............................................................................................................................................................................................done. 15:41:53 INFO - Installing pip...............done. 15:41:53 INFO - Return code: 0 15:41:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:41:53 INFO - Reading from file tmpfile_stdout 15:41:53 INFO - Output received: 15:41:53 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 15:41:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:41:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:41:53 INFO - Using env: {'DISPLAY': ':0', 15:41:53 INFO - 'HOME': '/home/cltbld', 15:41:53 INFO - 'LANG': 'en_US.UTF-8', 15:41:53 INFO - 'LANGUAGE': 'en_US:en', 15:41:53 INFO - 'LOGNAME': 'cltbld', 15:41:53 INFO - 'MAIL': '/var/mail/cltbld', 15:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:53 INFO - 'MOZ_NO_REMOTE': '1', 15:41:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:41:53 INFO - 'NO_EM_RESTART': '1', 15:41:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:53 INFO - 'PWD': '/builds/slave/test', 15:41:53 INFO - 'SHELL': '/bin/bash', 15:41:53 INFO - 'SHLVL': '1', 15:41:53 INFO - 'TERM': 'linux', 15:41:53 INFO - 'TMOUT': '86400', 15:41:53 INFO - 'USER': 'cltbld', 15:41:53 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:53 INFO - '_': '/tools/buildbot/bin/python'} 15:41:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 15:41:54 INFO - Downloading/unpacking pip>=1.5 15:41:54 INFO - Running setup.py egg_info for package pip 15:41:54 INFO - warning: no files found matching 'pip/cacert.pem' 15:41:54 INFO - warning: no files found matching '*.html' under directory 'docs' 15:41:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:41:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:41:54 INFO - Installing collected packages: pip 15:41:54 INFO - Found existing installation: pip 1.1 15:41:54 INFO - Uninstalling pip: 15:41:54 INFO - Successfully uninstalled pip 15:41:54 INFO - Running setup.py install for pip 15:41:54 INFO - warning: no files found matching 'pip/cacert.pem' 15:41:54 INFO - warning: no files found matching '*.html' under directory 'docs' 15:41:54 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 15:41:54 INFO - no previously-included directories found matching 'docs/_build/_sources' 15:41:54 INFO - Installing pip script to /builds/slave/test/build/venv/bin 15:41:54 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 15:41:54 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 15:41:54 INFO - Successfully installed pip 15:41:54 INFO - Cleaning up... 15:41:54 INFO - Return code: 0 15:41:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:41:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:41:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:41:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:54 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 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:41:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:41:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:41:54 INFO - Using env: {'DISPLAY': ':0', 15:41:54 INFO - 'HOME': '/home/cltbld', 15:41:54 INFO - 'LANG': 'en_US.UTF-8', 15:41:54 INFO - 'LANGUAGE': 'en_US:en', 15:41:54 INFO - 'LOGNAME': 'cltbld', 15:41:54 INFO - 'MAIL': '/var/mail/cltbld', 15:41:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:54 INFO - 'MOZ_NO_REMOTE': '1', 15:41:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:41:54 INFO - 'NO_EM_RESTART': '1', 15:41:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:41:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:54 INFO - 'PWD': '/builds/slave/test', 15:41:54 INFO - 'SHELL': '/bin/bash', 15:41:54 INFO - 'SHLVL': '1', 15:41:54 INFO - 'TERM': 'linux', 15:41:54 INFO - 'TMOUT': '86400', 15:41:54 INFO - 'USER': 'cltbld', 15:41:54 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:41:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:54 INFO - '_': '/tools/buildbot/bin/python'} 15:41:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:55 INFO - Downloading/unpacking psutil>=3.1.1 15:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:41:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:41:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:41:56 INFO - Installing collected packages: psutil 15:41:56 INFO - Running setup.py install for psutil 15:41:56 INFO - building 'psutil._psutil_linux' extension 15:41:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:41:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:41:58 INFO - building 'psutil._psutil_posix' extension 15:41:58 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 15:41:58 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:41:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:41:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:41:58 INFO - Successfully installed psutil 15:41:58 INFO - Cleaning up... 15:41:58 INFO - Return code: 0 15:41:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:41:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:41:58 INFO - Using env: {'DISPLAY': ':0', 15:41:58 INFO - 'HOME': '/home/cltbld', 15:41:58 INFO - 'LANG': 'en_US.UTF-8', 15:41:58 INFO - 'LANGUAGE': 'en_US:en', 15:41:58 INFO - 'LOGNAME': 'cltbld', 15:41:58 INFO - 'MAIL': '/var/mail/cltbld', 15:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:58 INFO - 'MOZ_NO_REMOTE': '1', 15:41:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:41:58 INFO - 'NO_EM_RESTART': '1', 15:41:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:58 INFO - 'PWD': '/builds/slave/test', 15:41:58 INFO - 'SHELL': '/bin/bash', 15:41:58 INFO - 'SHLVL': '1', 15:41:58 INFO - 'TERM': 'linux', 15:41:58 INFO - 'TMOUT': '86400', 15:41:58 INFO - 'USER': 'cltbld', 15:41:58 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:58 INFO - '_': '/tools/buildbot/bin/python'} 15:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:41:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:41:59 INFO - Installing collected packages: mozsystemmonitor 15:41:59 INFO - Running setup.py install for mozsystemmonitor 15:42:00 INFO - Successfully installed mozsystemmonitor 15:42:00 INFO - Cleaning up... 15:42:00 INFO - Return code: 0 15:42:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:42:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:42:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:42:00 INFO - Using env: {'DISPLAY': ':0', 15:42:00 INFO - 'HOME': '/home/cltbld', 15:42:00 INFO - 'LANG': 'en_US.UTF-8', 15:42:00 INFO - 'LANGUAGE': 'en_US:en', 15:42:00 INFO - 'LOGNAME': 'cltbld', 15:42:00 INFO - 'MAIL': '/var/mail/cltbld', 15:42:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:00 INFO - 'MOZ_NO_REMOTE': '1', 15:42:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:00 INFO - 'NO_EM_RESTART': '1', 15:42:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:00 INFO - 'PWD': '/builds/slave/test', 15:42:00 INFO - 'SHELL': '/bin/bash', 15:42:00 INFO - 'SHLVL': '1', 15:42:00 INFO - 'TERM': 'linux', 15:42:00 INFO - 'TMOUT': '86400', 15:42:00 INFO - 'USER': 'cltbld', 15:42:00 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:42:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:00 INFO - '_': '/tools/buildbot/bin/python'} 15:42:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:00 INFO - Downloading/unpacking jsonschema==2.5.1 15:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:42:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:01 INFO - Downloading functools32-3.2.3-2.tar.gz 15:42:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:42:01 INFO - warning: no files found matching '*.txt' 15:42:01 INFO - no previously-included directories found matching 'build' 15:42:01 INFO - no previously-included directories found matching 'dist' 15:42:01 INFO - no previously-included directories found matching '.git*' 15:42:01 INFO - Installing collected packages: jsonschema, functools32 15:42:01 INFO - Running setup.py install for functools32 15:42:01 INFO - warning: no files found matching '*.txt' 15:42:01 INFO - no previously-included directories found matching 'build' 15:42:01 INFO - no previously-included directories found matching 'dist' 15:42:01 INFO - no previously-included directories found matching '.git*' 15:42:01 INFO - Successfully installed jsonschema functools32 15:42:01 INFO - Cleaning up... 15:42:01 INFO - Return code: 0 15:42:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:42:01 INFO - Using env: {'DISPLAY': ':0', 15:42:01 INFO - 'HOME': '/home/cltbld', 15:42:01 INFO - 'LANG': 'en_US.UTF-8', 15:42:01 INFO - 'LANGUAGE': 'en_US:en', 15:42:01 INFO - 'LOGNAME': 'cltbld', 15:42:01 INFO - 'MAIL': '/var/mail/cltbld', 15:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:01 INFO - 'MOZ_NO_REMOTE': '1', 15:42:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:01 INFO - 'NO_EM_RESTART': '1', 15:42:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:01 INFO - 'PWD': '/builds/slave/test', 15:42:01 INFO - 'SHELL': '/bin/bash', 15:42:01 INFO - 'SHLVL': '1', 15:42:01 INFO - 'TERM': 'linux', 15:42:01 INFO - 'TMOUT': '86400', 15:42:01 INFO - 'USER': 'cltbld', 15:42:01 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:01 INFO - '_': '/tools/buildbot/bin/python'} 15:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:42:02 INFO - Cleaning up... 15:42:02 INFO - Return code: 0 15:42:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:42: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')]} 15:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42: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')]} 15:42:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:02 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 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:42:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:42:02 INFO - Using env: {'DISPLAY': ':0', 15:42:02 INFO - 'HOME': '/home/cltbld', 15:42:02 INFO - 'LANG': 'en_US.UTF-8', 15:42:02 INFO - 'LANGUAGE': 'en_US:en', 15:42:02 INFO - 'LOGNAME': 'cltbld', 15:42:02 INFO - 'MAIL': '/var/mail/cltbld', 15:42:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:02 INFO - 'MOZ_NO_REMOTE': '1', 15:42:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:02 INFO - 'NO_EM_RESTART': '1', 15:42:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:02 INFO - 'PWD': '/builds/slave/test', 15:42:02 INFO - 'SHELL': '/bin/bash', 15:42:02 INFO - 'SHLVL': '1', 15:42:02 INFO - 'TERM': 'linux', 15:42:02 INFO - 'TMOUT': '86400', 15:42:02 INFO - 'USER': 'cltbld', 15:42:02 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:42:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:02 INFO - '_': '/tools/buildbot/bin/python'} 15:42:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:02 INFO - Downloading/unpacking blobuploader==1.2.4 15:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:03 INFO - Downloading blobuploader-1.2.4.tar.gz 15:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:42:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:42:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:03 INFO - Downloading docopt-0.6.1.tar.gz 15:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:42:03 INFO - Installing collected packages: blobuploader, requests, docopt 15:42:03 INFO - Running setup.py install for blobuploader 15:42:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:42:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:42:04 INFO - Running setup.py install for requests 15:42:04 INFO - Running setup.py install for docopt 15:42:04 INFO - Successfully installed blobuploader requests docopt 15:42:04 INFO - Cleaning up... 15:42:04 INFO - Return code: 0 15:42:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:04 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 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:42:04 INFO - Using env: {'DISPLAY': ':0', 15:42:04 INFO - 'HOME': '/home/cltbld', 15:42:04 INFO - 'LANG': 'en_US.UTF-8', 15:42:04 INFO - 'LANGUAGE': 'en_US:en', 15:42:04 INFO - 'LOGNAME': 'cltbld', 15:42:04 INFO - 'MAIL': '/var/mail/cltbld', 15:42:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:04 INFO - 'MOZ_NO_REMOTE': '1', 15:42:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:04 INFO - 'NO_EM_RESTART': '1', 15:42:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:04 INFO - 'PWD': '/builds/slave/test', 15:42:04 INFO - 'SHELL': '/bin/bash', 15:42:04 INFO - 'SHLVL': '1', 15:42:04 INFO - 'TERM': 'linux', 15:42:04 INFO - 'TMOUT': '86400', 15:42:04 INFO - 'USER': 'cltbld', 15:42:04 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:42:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:04 INFO - '_': '/tools/buildbot/bin/python'} 15:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:42:04 INFO - Running setup.py (path:/tmp/pip-D8Dx46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:42:04 INFO - Running setup.py (path:/tmp/pip-jf5fhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:42:04 INFO - Running setup.py (path:/tmp/pip-HAUCwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:42:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:42:04 INFO - Running setup.py (path:/tmp/pip-RONCsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:42:05 INFO - Running setup.py (path:/tmp/pip-9koOQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:42:05 INFO - Running setup.py (path:/tmp/pip-aIwyGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:42:05 INFO - Running setup.py (path:/tmp/pip-2YCSAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:42:05 INFO - Running setup.py (path:/tmp/pip-zdnr34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:42:05 INFO - Running setup.py (path:/tmp/pip-wjO308-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:42:05 INFO - Running setup.py (path:/tmp/pip-2q8AJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:42:05 INFO - Running setup.py (path:/tmp/pip-BGIQga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:42:05 INFO - Running setup.py (path:/tmp/pip-c_uiSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:42:05 INFO - Running setup.py (path:/tmp/pip-O7xrqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:42:05 INFO - Running setup.py (path:/tmp/pip-6XYvXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:05 INFO - Running setup.py (path:/tmp/pip-eou4qR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:42:05 INFO - Running setup.py (path:/tmp/pip-1Rw10u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:42:05 INFO - Running setup.py (path:/tmp/pip-mGVHbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:42:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:42:05 INFO - Running setup.py install for manifestparser 15:42:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:42:06 INFO - Running setup.py install for mozcrash 15:42:06 INFO - Running setup.py install for mozdebug 15:42:06 INFO - Running setup.py install for mozdevice 15:42:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:42:06 INFO - Running setup.py install for mozfile 15:42:06 INFO - Running setup.py install for mozhttpd 15:42:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:42:06 INFO - Running setup.py install for mozinfo 15:42:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:42:06 INFO - Running setup.py install for mozInstall 15:42:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:42:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:42:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:42:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:42:06 INFO - Running setup.py install for mozleak 15:42:06 INFO - Running setup.py install for mozlog 15:42:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:42:06 INFO - Running setup.py install for moznetwork 15:42:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:42:07 INFO - Running setup.py install for mozprocess 15:42:07 INFO - Running setup.py install for mozprofile 15:42:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:42:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:42:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:42:07 INFO - Running setup.py install for mozrunner 15:42:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:42:07 INFO - Running setup.py install for mozscreenshot 15:42:07 INFO - Running setup.py install for moztest 15:42:07 INFO - Running setup.py install for mozversion 15:42:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:42:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:42:07 INFO - Cleaning up... 15:42:07 INFO - Return code: 0 15:42:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:07 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 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:42:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:42:07 INFO - Using env: {'DISPLAY': ':0', 15:42:07 INFO - 'HOME': '/home/cltbld', 15:42:07 INFO - 'LANG': 'en_US.UTF-8', 15:42:07 INFO - 'LANGUAGE': 'en_US:en', 15:42:07 INFO - 'LOGNAME': 'cltbld', 15:42:07 INFO - 'MAIL': '/var/mail/cltbld', 15:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:07 INFO - 'MOZ_NO_REMOTE': '1', 15:42:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:07 INFO - 'NO_EM_RESTART': '1', 15:42:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:07 INFO - 'PWD': '/builds/slave/test', 15:42:07 INFO - 'SHELL': '/bin/bash', 15:42:07 INFO - 'SHLVL': '1', 15:42:07 INFO - 'TERM': 'linux', 15:42:07 INFO - 'TMOUT': '86400', 15:42:07 INFO - 'USER': 'cltbld', 15:42:07 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:07 INFO - '_': '/tools/buildbot/bin/python'} 15:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:42:07 INFO - Running setup.py (path:/tmp/pip-taZmtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:42:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:42:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:42:07 INFO - Running setup.py (path:/tmp/pip-rkTrqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:42:08 INFO - Running setup.py (path:/tmp/pip-U1kiZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:42:08 INFO - Running setup.py (path:/tmp/pip-W4CPzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:42:08 INFO - Running setup.py (path:/tmp/pip-XNt82U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:42:08 INFO - Running setup.py (path:/tmp/pip-8dQdpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:42:08 INFO - Running setup.py (path:/tmp/pip-uWmCFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:42:08 INFO - Running setup.py (path:/tmp/pip-w9HpVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:42:08 INFO - Running setup.py (path:/tmp/pip-AqnYGs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:42:08 INFO - Running setup.py (path:/tmp/pip-Degv70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:42:08 INFO - Running setup.py (path:/tmp/pip-MTjB0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:42:08 INFO - Running setup.py (path:/tmp/pip-ANT2MM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:42:08 INFO - Running setup.py (path:/tmp/pip-8mqCGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:42:08 INFO - Running setup.py (path:/tmp/pip-qvEZmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:42:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:08 INFO - Running setup.py (path:/tmp/pip-T6t5Kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:42:09 INFO - Running setup.py (path:/tmp/pip-PUzwwq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:42:09 INFO - Running setup.py (path:/tmp/pip-GzdWg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:42:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:10 INFO - Downloading blessings-1.6.tar.gz 15:42:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:42:10 INFO - Installing collected packages: blessings 15:42:10 INFO - Running setup.py install for blessings 15:42:10 INFO - Successfully installed blessings 15:42:10 INFO - Cleaning up... 15:42:10 INFO - Return code: 0 15:42:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:42:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:42:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:42:10 INFO - Reading from file tmpfile_stdout 15:42:10 INFO - Current package versions: 15:42:10 INFO - argparse == 1.2.1 15:42:10 INFO - blessings == 1.6 15:42:10 INFO - blobuploader == 1.2.4 15:42:10 INFO - docopt == 0.6.1 15:42:10 INFO - functools32 == 3.2.3-2 15:42:10 INFO - jsonschema == 2.5.1 15:42:10 INFO - manifestparser == 1.1 15:42:10 INFO - mozInstall == 1.13 15:42:10 INFO - mozcrash == 1.0 15:42:10 INFO - mozdebug == 0.1 15:42:10 INFO - mozdevice == 0.51 15:42:10 INFO - mozfile == 1.2 15:42:10 INFO - mozhttpd == 0.7 15:42:10 INFO - mozinfo == 0.10 15:42:10 INFO - mozleak == 0.1 15:42:10 INFO - mozlog == 3.5 15:42:10 INFO - moznetwork == 0.27 15:42:10 INFO - mozprocess == 0.25 15:42:10 INFO - mozprofile == 0.28 15:42:10 INFO - mozrunner == 6.13 15:42:10 INFO - mozscreenshot == 0.1 15:42:10 INFO - mozsystemmonitor == 0.3 15:42:10 INFO - moztest == 0.8 15:42:10 INFO - mozversion == 1.4 15:42:10 INFO - psutil == 3.1.1 15:42:10 INFO - requests == 1.2.3 15:42:10 INFO - wsgiref == 0.1.2 15:42:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:10 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 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:42:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:42:10 INFO - Using env: {'DISPLAY': ':0', 15:42:10 INFO - 'HOME': '/home/cltbld', 15:42:10 INFO - 'LANG': 'en_US.UTF-8', 15:42:10 INFO - 'LANGUAGE': 'en_US:en', 15:42:10 INFO - 'LOGNAME': 'cltbld', 15:42:10 INFO - 'MAIL': '/var/mail/cltbld', 15:42:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:10 INFO - 'MOZ_NO_REMOTE': '1', 15:42:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:10 INFO - 'NO_EM_RESTART': '1', 15:42:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:10 INFO - 'PWD': '/builds/slave/test', 15:42:10 INFO - 'SHELL': '/bin/bash', 15:42:10 INFO - 'SHLVL': '1', 15:42:10 INFO - 'TERM': 'linux', 15:42:10 INFO - 'TMOUT': '86400', 15:42:10 INFO - 'USER': 'cltbld', 15:42:10 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:42:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:10 INFO - '_': '/tools/buildbot/bin/python'} 15:42:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:42:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:42:10 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 15:42:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:42:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:42:12 INFO - Installing collected packages: simplejson 15:42:12 INFO - Running setup.py install for simplejson 15:42:12 INFO - building 'simplejson._speedups' extension 15:42:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 15:42:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 15:42:13 INFO - Successfully installed simplejson 15:42:13 INFO - Cleaning up... 15:42:13 INFO - Return code: 0 15:42:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:13 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 0x22161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23deb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25839c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, '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': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 15:42:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:42:13 INFO - Using env: {'DISPLAY': ':0', 15:42:13 INFO - 'HOME': '/home/cltbld', 15:42:13 INFO - 'LANG': 'en_US.UTF-8', 15:42:13 INFO - 'LANGUAGE': 'en_US:en', 15:42:13 INFO - 'LOGNAME': 'cltbld', 15:42:13 INFO - 'MAIL': '/var/mail/cltbld', 15:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:13 INFO - 'MOZ_NO_REMOTE': '1', 15:42:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:13 INFO - 'NO_EM_RESTART': '1', 15:42:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:13 INFO - 'PWD': '/builds/slave/test', 15:42:13 INFO - 'SHELL': '/bin/bash', 15:42:13 INFO - 'SHLVL': '1', 15:42:13 INFO - 'TERM': 'linux', 15:42:13 INFO - 'TMOUT': '86400', 15:42:13 INFO - 'USER': 'cltbld', 15:42:13 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:13 INFO - '_': '/tools/buildbot/bin/python'} 15:42:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:42:13 INFO - Cleaning up... 15:42:13 INFO - Return code: 0 15:42:13 INFO - Running post-action listener: _resource_record_post_action 15:42:13 INFO - Running post-action listener: _start_resource_monitoring 15:42:13 INFO - Starting resource monitoring. 15:42:13 INFO - [mozharness: 2017-09-24 22:42:13.453168Z] Finished create-virtualenv step (success) 15:42:13 INFO - [mozharness: 2017-09-24 22:42:13.454222Z] Running install step. 15:42:13 INFO - Running pre-action listener: _resource_record_pre_action 15:42:13 INFO - Running main action method: install 15:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:42:13 INFO - Reading from file tmpfile_stdout 15:42:13 INFO - Detecting whether we're running mozinstall >=1.0... 15:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:42:13 INFO - Reading from file tmpfile_stdout 15:42:13 INFO - Output received: 15:42:13 INFO - Usage: mozinstall [options] installer 15:42:13 INFO - Options: 15:42:13 INFO - -h, --help show this help message and exit 15:42:13 INFO - -d DEST, --destination=DEST 15:42:13 INFO - Directory to install application into. [default: 15:42:13 INFO - "/builds/slave/test"] 15:42:13 INFO - --app=APP Application being installed. [default: firefox] 15:42:13 INFO - mkdir: /builds/slave/test/build/application 15:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 15:42:31 INFO - Reading from file tmpfile_stdout 15:42:31 INFO - Output received: 15:42:31 INFO - /builds/slave/test/build/application/firefox/firefox 15:42:31 INFO - Running post-action listener: _resource_record_post_action 15:42:31 INFO - [mozharness: 2017-09-24 22:42:31.579018Z] Finished install step (success) 15:42:31 INFO - [mozharness: 2017-09-24 22:42:31.579268Z] Running setup-mitmproxy step. 15:42:31 INFO - Running pre-action listener: _resource_record_pre_action 15:42:31 INFO - Running main action method: setup_mitmproxy 15:42:31 INFO - Skipping: mitmproxy is not required 15:42:31 INFO - Running post-action listener: _resource_record_post_action 15:42:31 INFO - [mozharness: 2017-09-24 22:42:31.580307Z] Finished setup-mitmproxy step (success) 15:42:31 INFO - [mozharness: 2017-09-24 22:42:31.580526Z] Running run-tests step. 15:42:31 INFO - Running pre-action listener: _resource_record_pre_action 15:42:31 INFO - Running pre-action listener: _set_gcov_prefix 15:42:31 INFO - Running main action method: run_tests 15:42:31 WARNING - Try message not found. 15:42:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:42:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:42:31 INFO - Python 2.7.3 15:42:31 INFO - Return code: 0 15:42:31 INFO - grabbing minidump binary from tooltool 15:42:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:31 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 0x2580d60>, '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 0x2581a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2581ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:42:31 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 15:42:31 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 15:42:31 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 15:42:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:42:31 INFO - Return code: 0 15:42:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:42:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:42:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:42:31 INFO - ENV: RUST_BACKTRACE is now full 15:42:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:42:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:42:31 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/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:42:31 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/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-074 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:42:31 INFO - Using env: {'DISPLAY': ':0', 15:42:31 INFO - 'HOME': '/home/cltbld', 15:42:31 INFO - 'LANG': 'en_US.UTF-8', 15:42:31 INFO - 'LANGUAGE': 'en_US:en', 15:42:31 INFO - 'LOGNAME': 'cltbld', 15:42:31 INFO - 'MAIL': '/var/mail/cltbld', 15:42:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:42:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:42:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:31 INFO - 'MOZ_NO_REMOTE': '1', 15:42:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:42:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:31 INFO - 'NO_EM_RESTART': '1', 15:42:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:31 INFO - 'PWD': '/builds/slave/test', 15:42:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:42:31 INFO - 'RUST_BACKTRACE': 'full', 15:42:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:42:31 INFO - 'SHELL': '/bin/bash', 15:42:31 INFO - 'SHLVL': '1', 15:42:31 INFO - 'TERM': 'linux', 15:42:31 INFO - 'TMOUT': '86400', 15:42:31 INFO - 'USER': 'cltbld', 15:42:31 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:42:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:31 INFO - '_': '/tools/buildbot/bin/python'} 15:42:31 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/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:42:32 INFO - mozversion application_buildid: 20170924215115 15:42:32 INFO - mozversion application_changeset: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 15:42:32 INFO - mozversion application_display_name: Nightly 15:42:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:42:32 INFO - mozversion application_name: Firefox 15:42:32 INFO - mozversion application_remotingname: firefox 15:42:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:42:32 INFO - mozversion application_vendor: Mozilla 15:42:32 INFO - mozversion application_version: 58.0a1 15:42:32 INFO - mozversion platform_buildid: 20170924215115 15:42:32 INFO - mozversion platform_changeset: d9cfaad3b11ef5cde2a55782ad67aa7eb5fd55e4 15:42:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:42:32 INFO - mozversion platform_version: 58.0a1 15:42:32 INFO - using testdate: 1506292952 15:42:32 INFO - actual date: 1506292952 15:42:32 INFO - starting webserver on 'localhost:46711' 15:42:32 INFO - SUITE-START | Running 2 tests 15:42:32 INFO - TEST-START | dromaeo_css 15:42:32 INFO - Initialising browser for dromaeo_css test... 15:42:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46711/getInfo.html -profile /tmp/tmpeSpt8r/profile 15:42:32 INFO - TEST-INFO | started process 3584 (/builds/slave/test/build/application/firefox/firefox http://localhost:46711/getInfo.html) 15:42:39 INFO - TEST-INFO | 3584: exit 0 15:42:39 INFO - Browser initialized. 15:42:39 INFO - Running cycle 1/1 for dromaeo_css test... 15:42:39 INFO - Using env: {'DISPLAY': ':0', 15:42:39 INFO - 'HOME': '/home/cltbld', 15:42:39 INFO - 'JSGC_DISABLE_POISONING': '1', 15:42:39 INFO - 'LANG': 'en_US.UTF-8', 15:42:39 INFO - 'LANGUAGE': 'en_US:en', 15:42:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 15:42:39 INFO - 'LOGNAME': 'cltbld', 15:42:39 INFO - 'MAIL': '/var/mail/cltbld', 15:42:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:42:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:42:39 INFO - 'MOZ_CRASHREPORTER': '1', 15:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 15:42:39 INFO - 'MOZ_NO_REMOTE': '1', 15:42:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:42:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:42:39 INFO - 'NO_EM_RESTART': '1', 15:42:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:39 INFO - 'PWD': '/builds/slave/test', 15:42:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:42:39 INFO - 'RUST_BACKTRACE': 'full', 15:42:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:42:39 INFO - 'SHELL': '/bin/bash', 15:42:39 INFO - 'SHLVL': '1', 15:42:39 INFO - 'TERM': 'linux', 15:42:39 INFO - 'TMOUT': '86400', 15:42:39 INFO - 'USER': 'cltbld', 15:42:39 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 15:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:39 INFO - '_': '/tools/buildbot/bin/python'} 15:42:39 INFO - TEST-INFO | started process 3825 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpeSpt8r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 15:42:40 INFO - PID 3825 | 15:42:40 INFO - PID 3825 | (/builds/slave/test/build/application/firefox/firefox:3874): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 15:42:40 INFO - PID 3825 | 15:42:50 INFO - PID 3825 | RSS: Main: 150810624 15:42:50 INFO - PID 3825 | 15:45:47 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:46711/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46711/tests/dromaeo/cssquery-ext.html) 15:45:49 INFO - PID 3825 | RSS: Main: 152600576 15:45:49 INFO - PID 3825 | 15:48:50 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:46711/tests/dromaeo/cssquery-ext.html (next: http://localhost:46711/tests/dromaeo/cssquery-jquery.html) 15:48:51 INFO - PID 3825 | RSS: Main: 152772608 15:48:51 INFO - PID 3825 | 15:51:53 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:46711/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46711/tests/dromaeo/cssquery-mootools.html) 15:51:53 INFO - PID 3825 | RSS: Main: 154300416 15:51:53 INFO - PID 3825 | 15:54:55 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:46711/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46711/tests/dromaeo/cssquery-prototype.html) 15:54:56 INFO - PID 3825 | RSS: Main: 153153536 15:54:56 INFO - PID 3825 | 15:57:59 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:46711/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46711/tests/dromaeo/cssquery-yui.html) 15:57:59 INFO - PID 3825 | RSS: Main: 154251264 15:57:59 INFO - PID 3825 | 16:01:01 INFO - PID 3825 | Cycle 1(1): loaded http://localhost:46711/tests/dromaeo/cssquery-yui.html (next: http://localhost:46711/tests/dromaeo/cssquery-dojo.html) 16:01:01 INFO - PID 3825 | RSS: Main: 156409856 16:01:01 INFO - PID 3825 | 16:01:01 INFO - PID 3825 | __start_tp_report 16:01:01 INFO - PID 3825 | _x_x_mozilla_page_load 16:01:01 INFO - PID 3825 | _x_x_mozilla_page_load_details 16:01:01 INFO - PID 3825 | |i|pagename|runs| 16:01:01 INFO - PID 3825 | |0;dojo.html;3137.764667649926;3158.763092768043;3172.6827317268267;3175.253815353393;3182.1249156481963;6228.346023667517;6286.214223222098;6329.620222786631;6372.617642941426;6443.355664433554;6278.717457714402;6312.652804095773;6316.336784637604;6325.620462772225;6338.366163383671;5801.564882633809;5810.883782324372;5816.825495235129;5899.557533185029;5964.343922168558;5852.824415267528;5882.441168089024;5925.7925972590965;5934.703264836741;5955.047192449209;1001.2190246331278;5737.082066869322;5865.296164460249;5925.851853703649;5946.375630558772;5319.734013299359;5724.427557244284;5796.594238403313;5851.473367396955;5879.323877754027;5758.42415758425;5769.221155144086;5815.9709201453725;5817.767289308423;5822.155787410802;6283.371662833726;6304.27500837405;6323.209598800104;6325.304216536178;6403.391677790602;6774;6778.491613129035;6857.348551887562;6869.278725733776;6870.31296870314;6649.135612370409;6852.6916288767125;6866.828329291758;6945.826354341131;6949.235584085746;6222.160008398853;6257.530685198628;6282.874342513124;6366.236051673783;6371.267304260023;7026.473014523931;7037;7054.906489494137;7071.8939215911805;7110;6666.600003999775;6674.733010679615;6702.463802895757;6756.425703815231;6766.526343156031;6552.279249282575;6675.032120342523;6822.556533825385;6888.345607167311;6897.586144831326;6610.933890661128;6628.370304821035;6688.531802773857;6690.02994565795;6693.933060669428;6804.047433359334;6873.621950792654;6889.758858439931;6924.23833378328;6928.376446119873;5311.389190243094;5375.354957405137;5380.219868119101;5384.892302153935;5389;4538.047010127844;4613.331066995267;4619.076184762994;4665.6034237090225;4675.064987002613;5778.27771528559;5794.8551286217335;5850.385709500441;5859.677717725566;5877.706114694248;6803.523753337317;6820.93179068203;6875.724971001204;6896.137982752156;6897.689603967833;5774.335951365562;5815.680137592473;5873.177755114288;5883.117532370093;5889.851478961647;5707.600467967285;5796.884062318814;5820.156077368758;5881.735321910524;5892.381299963527;6576.04647326135;6601.834954126185;6657.966710166418;6663.466922646274;6665.6000639960785;6604.966975165094;6750.594964302256;6765.695543700644;6824.897626535507;6899.655017249218;6558.409743123142;6627.701753421204;6641.966790166019;6644.435223006099;6645.501587380869;6563.409293163638;6683.997400389981;6687.866242675022;6755.716413881543;6776.559523630948;6785.89821152693;6819.283975182534;6930.896036559355;6934.133233345832;6935.029095926473;6603.702833372414;6690.163729533809;6698.497612679166;6699.229588597374;6703.698333575098;6599.274079851117;6619.073329733745;6691.230508491586;6692.59844409337;6703.631300278336;6363.96818015907;6471.352864713491;6532.346765323429;6545.836354091186;6617.040529123154;6697.7655782047395;6732.528722989539;6734.461243100638;6750.3924646782025;6757.358050985148;6303.432691057644;6386.074019267273;6477.805665830033;6482.546221764431;6485.286618472059 16:01:01 INFO - PID 3825 | |1;ext.html;860.5181098584792;895.7357579514043;899.8965119011308;906.8549032154853;910.4582773249914;38216.808915955415;41965;41974.16051678965;42010.159796804044;42061.57938420619;39043.80478097613;39292.80353598228;39415.60584394162;39436;39476.60523394765;7301.342879140863;7438.404927605806;7442.292982166686;7951.681932722684;8152.062512811014;8022.318102961255;8130.796730081736;8132.26809587137;8132.552709600969;8133.146019667938;8078.474899131567;8106.351491880665;8155.877661835078;8159.143289954529;8162.224588664068;8058.5567793771315;8082.999999999971;8083.191680831899;8118.878216826753;8127.674893004273;7965.2831245187635;8029.919700802976;8067.515949043018;8073.233042860918;8077.192280771876;37722;37849.62150378516;37979;38112.23775524474;38159.47362105513;3519.560054993126;3525.7355698322726;3529.4529347951107;3532.1699400640828;3570.0539357070397;31454;31513;31609.367812643617;31620.841895790603;31676.841615792004;5129.12804823181;5169.689818610857;5205.7397130143345;5215.16557350822;5297.390800058004;182550;182639;182661;183038;183267.16732832635;42479;43206.783966079965;47800.7609961948;47965;48094;49718;50378.49621503811;50420.24369634459;50474.49525504698;50568.747156263984;16020;16106.275217615235;16138.031718096718;16169.757453638205;16172.838271617133;13018.088733788732;13053.216806991612;13090.14914030585;13120.540781072808;13128.540501082416;13674.726505469835;13684.794728079087;13717.931410343084;13723.588292351245;13861.168329900238;5523.28197334344;5550.111982082847;5558.33300003994;5559.444055594408;5572.916406253909;994.4331730913455;1007.1036777268237;1009.4145395670494;1019.9235057370727;1020.1940467031111;22948;23027.194048208232;23049.539009219723;23065.88467057654;23151.305460836204;1102.7408558988632;1109.1071687291646;1117.1788735279563;1117.7708569743183;1129.1982692288402;12005.75988480243;12010.459529321202;12046.337451440255;12069.275843449246;12130;213882;216050;216217;216527;216935;52684.47315526872;52811.73594132005;52814.47185528096;52820;53055.469445305054;1392.1438315435935;1439.8560143985728;1447.667036581571;1451.934662940149;1452.201289290907;22178.223762168247;22330.441738956655;22430.102795888633;22779.08883644702;22866.19968301168;37442.62557374391;37458.43812342762;37462.43806342962;37510.43734344042;37621.81189094037;180377;181800;181809;181986;182184;31282.21794455066;31615;33305.16737081592;34201.82899085488;34442;42728.5727142737;42875.78562107169;43164.568354316056;43610.345844811716;43721.344179837964;31855;31945.201369965936;32057.839710801298;32363.838180808943;32533.837330813196;31296.530552042193;31592.526112107877;31596.842015789774;31960;32539.837300813346;34523.13692157716;34859.651403486656;35104.12239693926;35474.29051418906;35560.110997225274;30543.778248869796;30977.535336970413;31246;31303.373932520768;31427.685723142476;23127.76872231256;23220.535589288458;23310;23415.88292058529;23497.647535286644 16:01:01 INFO - PID 3825 | |2;jquery.html;586.8972929737295;588.6350462713116;593.462916060965;600.2167171840747;616.6824085595919;21753.564928701417;22448.102075916962;22509;22512.774872251273;22533.774662253392;24074;26636;26843.32891677704;26847.597286040724;26903.461930761372;6819.5226334156505;6957.991091291759;6961.23426423093;6971.3028697130385;6971.511994160411;6500.284968653445;6824.215215250235;6843.555168914005;7216.675249613753;7227.241139680336;7216.350528452438;7217.3865221456235;7220.097487814023;7223.819404514877;7224.783256502314;7190.065291512122;7192.388646965014;7215.350618444339;7229.457790665669;7236.71053157873;7071.469639776986;7074.575525468488;7075.752348667824;7091.503594748369;7109.928900710978;30318.393632127456;30323.545146822817;32674.183145421317;32861.17847053819;32894.506582401285;2023.3828682251908;2035.5725297687504;2037.3989673046487;2039.9902048486094;2054.342610364684;20708.896455517624;21225;21332.253371131937;21372.038258278415;21377.35867923965;2462.729099799021;2498.5877330240487;2569.3448170716533;2604.960925586118;2605.778508826752;144619;148036;148045.99999999892;148049;148162;18743.126787035468;21823.23618673339;21858.757569409125;22469.662955055686;22786;42947.34947529495;43305.35041974373;43374;43406.310463610316;43446.78276608597;11507.309561426342;11623.418829058513;11736.354500502384;11791.941040294916;11859;9658.32391732586;9684.41893486393;9741.707748767549;9774.804503909882;9848.704538863845;9638.469884156439;11370.94314528422;11435.828462573068;11743.41282935867;11767.411629418495;3554.8222588870453;3621.511096002059;3688.7786732796117;3943.369060950234;4286.249906266391;960.1358777100666;979.8334283171878;987.471702639099;993.1409330928694;994.4629899853998;19730.210791568177;19776.802231977497;19895.10472028762;20076.79923200749;20430.897845510677;1051.747580580655;1057.2440704895882;1058.5977328615077;1059.4702648675661;1070.1920050361928;10886.346819190874;11158;11198.21612487118;11263.04264137541;11403.486843092081;439668;474335;491471;494187;495752;111974;115835.42082289535;116716;116727;118663.40668296603;1176.3706417066999;1182.402886542308;1186.0630102219322;1190.7797057544208;1207.879212078785;17716.202770875083;18038.458846234633;18060.72908906341;18111.818881811014;18154.183061762516;56034.439655602924;56053;56887;57119.71440142773;59094.40905591061;70810.645946772;72156.63921680358;72336;72348;72490.27509724835;15901.681966360838;16566.83433165653;19652.705209422154;19779.406617801487;20360.185592576723;22982;23118.884405577865;23408.414789630395;23489;23518.412039699146;18109.36617218391;18400.723989139908;18458.81541184571;18518.722219166993;18652.626947460703;16499.83500164983;16899.91550042242;17102.572435689206;17127;17242.482725518254;18014.2794288227;19193.136308866415;19214.615707686047;19290.517737056685;19293.517662058563;19916.800831992074;20158.29445969384;20283.290084846958;20320.79679203248;20354.89822550937;18031.819681803016;18326.26694932188;18377.264909403475;18501.72247416263;18536.814631853507 16:01:01 INFO - PID 3825 | |3;mootools.html;1362.1758835904277;1412.1668215752711;1419.8367187773408;1423.5302350224426;1431.7995480632712;14956.925215373922;16292;16319.428819991303;16324.102174380405;16357.918210408947;16029.198540073008;16249.837501624981;16290.185490725476;16302.755458668129;16311.91844040778;2794.2315863137646;2837.574363845429;2851.144656603011;2854.7430731234185;2857.22854829196;2740.5204089284393;2830.0943698016645;2835.3053501892064;2844.288927768058;2846.3168839478585;2792.1204820481485;2819.379736457976;2836.659600847901;2838.68774434812;2839.0914907229694;2759.351552385188;2802.985985070072;2809.1291699572994;2809.90165344213;2810.18504633656;2690.408110215769;2724.155511791351;2729.4404647047504;2730.331068888135;2731.904383346593;15069.397224111022;15101.244937753068;15128.621784455367;15164.317605707769;15190.316435760416;8090.716824911159;8656.177663121993;8666.956665216696;8674.089220631806;8676.175763302475;5230.21546767984;5459.836204913859;5539.279893613845;5560.7219639017885;5562.0266453370905;1971.9802801972085;1999.290251960562;2005.8896760678158;2040.4898775306174;2059.567490826928;26380.73619263821;26499.205023849314;26608.60087098695;26644.46711065768;26664.333391664975;6837.35045170708;7105.111861017373;7158.141023077264;7280.563166210044;7285.70857165718;16724.49826505207;16774.58063548421;16784.496465106065;16796;16854;7968;8284.544350060683;8307.6676932922;8328.042275138316;8340.958295208606;6326.367363263729;6467.353264673589;6759.121314229217;6760.898586521206;7451.403887688972;5240.502152295526;5383.380911195184;5434.3207099112615;5445.428230035868;5530.198121272393;1573.2369800646677;1601.8157911840055;1610.2351383092937;1617.4500669772333;1618.9757153642704;902.192537678787;917.2845180759018;919.1681528216841;924.778053267211;927.1655510040882;7610.885836712454;8549.102344253888;8636.136386361313;8683.652653893774;8735.213830755263;732.1543617122232;747.5552046532305;748.7416841189781;750.084896426359;757.6817736550662;4588.931166032512;4721.976390118096;4746.311784791186;4748.358971538867;4798.520147985243;21537.892310538347;22241.332760016903;22443.438914026952;22491.550168996528;22560.774392256197;21878.781212187674;22115.778842211694;22140.2250921217;22276.220332288292;22322.665160022614;10792.568297268179;11387;11444.256123351955;11514;11521.251118677259;8704.78238044054;8712.346574007102;8794.120587941155;8794.120587941155;8897.466152030898;18088.547786305448;18621.162047707614;18705.906470467562;18719.532011699815;19120.33078842234;19413.223471061;19466.221351146363;19507;19576.804231958067;19627.214911403957;10989.670309890716;11004.944975275072;11027.724306892393;11056.170787191153;11065.059469944823;14240.786388204393;14282.857171428153;14326.856731432552;14372.425102995763;14409.279536022947;11053.226274160732;11387;11464.598739044093;11486.712832179262;11617.0706343494;11206.103511719206;11232.66302010941;11487.770244594894;11634;11673.299602023906;10498.21263405263;10517.05346518817;10550.947245263724;10560.419176945341;10580.523876425425;7588.506747061423;7758.3017528422715;7782.688692452239;7812.101608315116;7833;7783.299503044753;7786.805329866798;7851.725189618336;7855.7250496232355;7883.605819709106 16:01:01 INFO - PID 3825 | |4;prototype.html;500.6970782676482;513.5609054258612;534.599050711966;540.2274747111629;541.2909089093286;6156.938430615698;6222.751089956402;6225.34633863444;6296.331472159882;6316.210473690789;6131.2642482902;6135.785247516338;6155.6306621602735;6216.378362163781;6216.627002379861;5143.871403214912;5148.253503242027;5154.556724117235;5171.948280517203;5180.4042535108565;5113.488651134894;5119.360079990001;5144.202648589456;5154.819581314655;5185.611079169059;4938.80244790208;5099.388073431175;5102.948970510284;5104.821331253388;5135.871603209931;5074.82238121664;5093.974530127362;5125.282460455539;5164.406093299281;5179.792808287664;4776.928346074812;4798.952010479886;4799.808007679723;4828.203346447832;4850.757462126915;5873.207117039187;6014.578979471439;6054.969725151346;6067.514598832083;6166.7533298667995;5837.357890632027;5888.175655408247;5961.035911119101;5968.671723055274;5995.130706047642;6572.967135164294;6591.340865913418;6620.801375958681;6627.536072474929;6646.435053020451;5065.468125846806;5276.261323414725;5281.181416880389;5283.86790330241;5315.096433606296;6712.731490740413;6740.494462915301;6837.179538455367;6842.178938527359;6846.760363387308;5379.973100134474;5420.810271640474;5449.4005659377435;5458.645188062763;5479.0959491683425;5459.836204913859;5493.285872836505;5528.115501519737;5543.113101903676;5555.138953462219;5936.9406305937255;5942.286925568873;5959.404059594006;5960.523158147338;5983.431574000463;6552.3775241352;6559.540832141704;6606.240282367493;6608.7026083825385;6663.333666633298;1914.6840771272584;1923.884566925989;1930.9186855361042;1948.366780796247;1966.8985368193858;1380.8412032616177;1411.3789932429904;1423.2030063164661;1436.2029073863896;1455.505128256398;5574.721263936787;5579.721013949286;5597.63615365;5599.384067752544;5599.412061733541;6393.328700486475;6556;6572.342765723485;6584.341565843377;6645.368689974542;5567.192757050205;5598.664080155204;5627.902559000955;5636.915446268309;5669.177969194444;3478.530398396235;3498.6851183393615;3500.2824420993634;3502.8598856045473;3512.6838584527513;6341.651209183447;6372.522060845455;6372.58578192416;6417.454516366161;6423.775167869102;6477.967610161919;6669.933300666931;6752.763653272112;6842.4868134890085;6904.378605925491;6346.333634968354;6348.619082855043;6351.491880649444;6355.142055822497;6469.191351081115;6628.7017084230365;6637.203535575762;6646.667666616553;6738.561993470409;6760.864782704417;6577.106073327721;6591.220370500034;6624.867502649824;6642.568233065029;6690.632015239208;6509.414152726277;6514.4788416925585;6517.4134327910715;6528;6530.967345163243;4927.531884470969;4961.156603377362;5013.423456302572;5042.344495215599;5045.477261369316;6347.460465860454;6652.201735791736;6672.8999065013095;6677.998300255001;6681.162680262966;6309.558330916791;6462.7414903405215;6473.158489396348;6474.708638111202;6513.609183449008;6372.713227904663;6377.489800816016;6445.12990746262;6451.548391612542;6469.676516174079;6288.528360373082;6304.968475157595;6309.823111026689;6346.270178929482;6390.712417941111;6346.9682651586445;6448.613083215022;6470.870582588416;6487.124238227779;6494.935050649433;6152.846178845564;6173.475254603409;6188.412100850412;6282.120503129657;6321.715522801393 16:01:01 INFO - PID 3825 | |5;yui.html;899.3839220134208;918.7243826851947;920.9033051529585;922.5387306346827;938.1041105744015;19047.52381190471;19977.300794472198;20003.099860506274;20021;20025;18350.724739128924;18353.724694129567;18466.353677621286;18558.164882580284;18588.721169182474;949.5062567464897;965.5944503308627;966.3670295956125;966.9516524173798;967.0957654592943;987.0869445762677;988.426712506748;988.85661579071;989.3420875118073;993.4585650820284;986.9555869985832;1029.5675816157195;1037.419045334615;1047.3401756893147;1048.2871647279846;1032.726327523207;1034.5034383495927;1039.3711804358368;1041.4324193314624;1041.9791604167874;723.8190452386904;751.5565816168432;754.226917409654;756.7200135949679;757.0536828963795;20275.08762105709;20399.89800051005;20529.384118476468;20540.486487837774;20554.69167962482;21436.5712685747;21624.783752162435;22032.44918877025;22133.557328853494;22165.113395464163;17070.658586828195;17495.737563936553;17507.73738393925;17523.73714394285;17676.469705908843;3737.906552336186;3746.2132952079824;3767.830447629863;3767.830447629863;3767.924641507182;101141;115651;143448;144988;147680;17536.56158596045;17639.559011024827;17990.550236243937;18045.368412105512;18131.818681813278;31590.684093159234;31681.683183168334;31689.366212675617;31755.20611984719;32501.18747031343;9104.408213466104;9142.954285228532;9156.404833685923;9169.08309169091;9169.541522923828;4350.1734670412525;4379.890502737457;4427.911441771146;4431.8670439886855;4439.600435960714;5595.328560572676;5630.718464076862;5672.177534257592;5672.461116193955;5697.658140511582;1121.0639116337786;1141.8001849676273;1144.3648774929993;1147.0937959012454;1163.209017867858;648.3807963394946;656.917885264342;663.326723375774;664.9401553860176;678.6810199206365;12183.390830458442;12194.329311887932;12412.503499859904;12826.78596393124;13323.467061317633;681.9011243369683;687.0964681443985;693.9236683964759;695.3706895259797;695.7286658203304;5678.517326027251;5781.277340332458;5802.738876750472;5873.735681894325;5915.201447804491;125700.37149814375;126384.36807815902;127452.36273818757;128660.35669821779;131776;114170;114752;115240.20621124233;116267;118090.40954795343;95014.5249273763;97434;97657.5117124424;97662.5116874411;99280;10628.627998020032;10689.198310126616;10904.727381815517;10923.016928476476;10962.068224201032;45309.546904530536;46330.53669463397;46713;46747.065058699314;46806.99999999864;58235.70882145562;58936;60844.08733869084;61037.69481152566;61091.08363374464;15380.307886145374;15533.145676987422;15610.063396196265;15754;15762.68474630478;17044.914775426045;17315.047672377637;17356.739648905528;17423.738643920096;17478.91260543689;13460.730785384041;13720;13758.036937414685;13818.032737708667;13864.376103075587;15076.547703568911;15278.923605381902;15426.30581623852;15439.99640023395;15634.06195628266;15879.12664803447;16040.919795401414;16093.678126437171;16219.35122595083;16306.26621801998;11614.941925290319;11716.531338746354;11913.880861191277;11924.70188245266;11987;11873.465694043616;11947.701307467383;12018.278903265833;12168.330741809286;12187.573434929735 16:01:01 INFO - PID 3825 | __end_tp_report 16:01:01 INFO - PID 3825 | __start_cc_report 16:01:01 INFO - PID 3825 | _x_x_mozilla_cycle_collect,303 16:01:01 INFO - PID 3825 | __end_cc_report 16:01:01 INFO - PID 3825 | __startTimestamp1506294061589__endTimestamp 16:01:01 INFO - PID 3825 | 16:01:01 INFO - PID 3825 | ------- Summary: start ------- 16:01:01 INFO - PID 3825 | Number of tests: 6 16:01:01 INFO - PID 3825 | 16:01:01 INFO - PID 3825 | [#0] dojo.html Cycles:165 Average:6222.44 Median:6532.35 stddev:866.29 (13.3%) stddev-sans-first:834.46 16:01:01 INFO - PID 3825 | Values: 3137.8 3158.8 3172.7 3175.3 3182.1 6228.3 6286.2 6329.6 6372.6 6443.4 6278.7 6312.7 6316.3 6325.6 6338.4 5801.6 5810.9 5816.8 5899.6 5964.3 5852.8 5882.4 5925.8 5934.7 5955.0 1001.2 5737.1 5865.3 5925.9 5946.4 5319.7 5724.4 5796.6 5851.5 5879.3 5758.4 5769.2 5816.0 5817.8 5822.2 6283.4 6304.3 6323.2 6325.3 6403.4 6774.0 6778.5 6857.3 6869.3 6870.3 6649.1 6852.7 6866.8 6945.8 6949.2 6222.2 6257.5 6282.9 6366.2 6371.3 7026.5 7037.0 7054.9 7071.9 7110.0 6666.6 6674.7 6702.5 6756.4 6766.5 6552.3 6675.0 6822.6 6888.3 6897.6 6610.9 6628.4 6688.5 6690.0 6693.9 6804.0 6873.6 6889.8 6924.2 6928.4 5311.4 5375.4 5380.2 5384.9 5389.0 4538.0 4613.3 4619.1 4665.6 4675.1 5778.3 5794.9 5850.4 5859.7 5877.7 6803.5 6820.9 6875.7 6896.1 6897.7 5774.3 5815.7 5873.2 5883.1 5889.9 5707.6 5796.9 5820.2 5881.7 5892.4 6576.0 6601.8 6658.0 6663.5 6665.6 6605.0 6750.6 6765.7 6824.9 6899.7 6558.4 6627.7 6642.0 6644.4 6645.5 6563.4 6684.0 6687.9 6755.7 6776.6 6785.9 6819.3 6930.9 6934.1 6935.0 6603.7 6690.2 6698.5 6699.2 6703.7 6599.3 6619.1 6691.2 6692.6 6703.6 6364.0 6471.4 6532.3 6545.8 6617.0 6697.8 6732.5 6734.5 6750.4 6757.4 6303.4 6386.1 6477.8 6482.5 6485.3 16:01:01 INFO - PID 3825 | 16:01:01 INFO - PID 3825 | [#1] ext.html Cycles:180 Average:36279.29 Median:34981.89 stddev:50177.16 (143.4%) stddev-sans-first:50247.43 16:01:01 INFO - PID 3825 | Values: 860.5 895.7 899.9 906.9 910.5 38216.8 41965.0 41974.2 42010.2 42061.6 39043.8 39292.8 39415.6 39436.0 39476.6 7301.3 7438.4 7442.3 7951.7 8152.1 8022.3 8130.8 8132.3 8132.6 8133.1 8078.5 8106.4 8155.9 8159.1 8162.2 8058.6 8083.0 8083.2 8118.9 8127.7 7965.3 8029.9 8067.5 8073.2 8077.2 37722.0 37849.6 37979.0 38112.2 38159.5 3519.6 3525.7 3529.5 3532.2 3570.1 31454.0 31513.0 31609.4 31620.8 31676.8 5129.1 5169.7 5205.7 5215.2 5297.4 182550.0 182639.0 182661.0 183038.0 183267.2 42479.0 43206.8 47800.8 47965.0 48094.0 49718.0 50378.5 50420.2 50474.5 50568.7 16020.0 16106.3 16138.0 16169.8 16172.8 13018.1 13053.2 13090.1 13120.5 13128.5 13674.7 13684.8 13717.9 13723.6 13861.2 5523.3 5550.1 5558.3 5559.4 5572.9 994.4 1007.1 1009.4 1019.9 1020.2 22948.0 23027.2 23049.5 23065.9 23151.3 1102.7 1109.1 1117.2 1117.8 1129.2 12005.8 12010.5 12046.3 12069.3 12130.0 213882.0 216050.0 216217.0 216527.0 216935.0 52684.5 52811.7 52814.5 52820.0 53055.5 1392.1 1439.9 1447.7 1451.9 1452.2 22178.2 22330.4 22430.1 22779.1 22866.2 37442.6 37458.4 37462.4 37510.4 37621.8 180377.0 181800.0 181809.0 181986.0 182184.0 31282.2 31615.0 33305.2 34201.8 34442.0 42728.6 42875.8 43164.6 43610.3 43721.3 31855.0 31945.2 32057.8 32363.8 32533.8 31296.5 31592.5 31596.8 31960.0 32539.8 34523.1 34859.7 35104.1 35474.3 35560.1 30543.8 30977.5 31246.0 31303.4 31427.7 23127.8 23220.5 23310.0 23415.9 23497.6 16:01:01 INFO - PID 3825 | 16:01:01 INFO - PID 3825 | [#2] jquery.html Cycles:180 Average:36170.10 Median:22510.89 stddev:81122.50 (360.4%) stddev-sans-first:81306.07 16:01:01 INFO - PID 3825 | Values: 586.9 588.6 593.5 600.2 616.7 21753.6 22448.1 22509.0 22512.8 22533.8 24074.0 26636.0 26843.3 26847.6 26903.5 6819.5 6958.0 6961.2 6971.3 6971.5 6500.3 6824.2 6843.6 7216.7 7227.2 7216.4 7217.4 7220.1 7223.8 7224.8 7190.1 7192.4 7215.4 7229.5 7236.7 7071.5 7074.6 7075.8 7091.5 7109.9 30318.4 30323.5 32674.2 32861.2 32894.5 2023.4 2035.6 2037.4 2040.0 2054.3 20708.9 21225.0 21332.3 21372.0 21377.4 2462.7 2498.6 2569.3 2605.0 2605.8 144619.0 148036.0 148046.0 148049.0 148162.0 18743.1 21823.2 21858.8 22469.7 22786.0 42947.3 43305.4 43374.0 43406.3 43446.8 11507.3 11623.4 11736.4 11791.9 11859.0 9658.3 9684.4 9741.7 9774.8 9848.7 9638.5 11370.9 11435.8 11743.4 11767.4 3554.8 3621.5 3688.8 3943.4 4286.2 960.1 979.8 987.5 993.1 994.5 19730.2 19776.8 19895.1 20076.8 20430.9 1051.7 1057.2 1058.6 1059.5 1070.2 10886.3 11158.0 11198.2 11263.0 11403.5 439668.0 474335.0 491471.0 494187.0 495752.0 111974.0 115835.4 116716.0 116727.0 118663.4 1176.4 1182.4 1186.1 1190.8 1207.9 17716.2 18038.5 18060.7 18111.8 18154.2 56034.4 56053.0 56887.0 57119.7 59094.4 70810.6 72156.6 72336.0 72348.0 72490.3 15901.7 16566.8 19652.7 19779.4 20360.2 22982.0 23118.9 23408.4 23489.0 23518.4 18109.4 18400.7 18458.8 18518.7 18652.6 16499.8 16899.9 17102.6 17127.0 17242.5 18014.3 19193.1 19214.6 19290.5 19293.5 19916.8 20158.3 20283.3 20320.8 20354.9 18031.8 18326.3 18377.3 18501.7 18536.8 16:01:01 INFO - PID 3825 | 16:01:01 INFO - PID 3825 | [#3] mootools.html Cycles:180 Average:9533.43 Median:2707.28 stddev:6782.58 (250.5%) stddev-sans-first:6773.82 16:01:01 INFO - PID 3825 | Values: 1362.2 1412.2 1419.8 1423.5 1431.8 14956.9 16292.0 16319.4 16324.1 16357.9 16029.2 16249.8 16290.2 16302.8 16311.9 2794.2 2837.6 2851.1 2854.7 2857.2 2740.5 2830.1 2835.3 2844.3 2846.3 2792.1 2819.4 2836.7 2838.7 2839.1 2759.4 2803.0 2809.1 2809.9 2810.2 2690.4 2724.2 2729.4 2730.3 2731.9 15069.4 15101.2 15128.6 15164.3 15190.3 8090.7 8656.2 8667.0 8674.1 8676.2 5230.2 5459.8 5539.3 5560.7 5562.0 1972.0 1999.3 2005.9 2040.5 2059.6 26380.7 26499.2 26608.6 26644.5 26664.3 6837.4 7105.1 7158.1 7280.6 7285.7 16724.5 16774.6 16784.5 16796.0 16854.0 7968.0 8284.5 8307.7 8328.0 8341.0 6326.4 6467.4 6759.1 6760.9 7451.4 5240.5 5383.4 5434.3 5445.4 5530.2 1573.2 1601.8 1610.2 1617.5 1619.0 902.2 917.3 919.2 924.8 927.2 7610.9 8549.1 8636.1 8683.7 8735.2 732.2 747.6 748.7 750.1 757.7 4588.9 4722.0 4746.3 4748.4 4798.5 21537.9 22241.3 22443.4 22491.6 22560.8 21878.8 22115.8 22140.2 22276.2 22322.7 10792.6 11387.0 11444.3 11514.0 11521.3 8704.8 8712.3 8794.1 8794.1 8897.5 18088.5 18621.2 18705.9 18719.5 19120.3 19413.2 19466.2 19507.0 19576.8 19627.2 10989.7 11004.9 11027.7 11056.2 11065.1 14240.8 14282.9 14326.9 14372.4 14409.3 11053.2 11387.0 11464.6 11486.7 11617.1 11206.1 11232.7 11487.8 11634.0 11673.3 10498.2 10517.1 10550.9 10560.4 10580.5 7588.5 7758.3 7782.7 7812.1 7833.0 7783.3 7786.8 7851.7 7855.7 7883.6 16:01:01 INFO - PID 3825 | 16:01:01 INFO - PID 3825 | [#4] prototype.html Cycles:180 Average:5549.86 Median:6144.32 stddev:1478.32 (24.1%) stddev-sans-first:1433.07 16:01:01 INFO - PID 3825 | Values: 500.7 513.6 534.6 540.2 541.3 6156.9 6222.8 6225.3 6296.3 6316.2 6131.3 6135.8 6155.6 6216.4 6216.6 5143.9 5148.3 5154.6 5171.9 5180.4 5113.5 5119.4 5144.2 5154.8 5185.6 4938.8 5099.4 5102.9 5104.8 5135.9 5074.8 5094.0 5125.3 5164.4 5179.8 4776.9 4799.0 4799.8 4828.2 4850.8 5873.2 6014.6 6055.0 6067.5 6166.8 5837.4 5888.2 5961.0 5968.7 5995.1 6573.0 6591.3 6620.8 6627.5 6646.4 5065.5 5276.3 5281.2 5283.9 5315.1 6712.7 6740.5 6837.2 6842.2 6846.8 5380.0 5420.8 5449.4 5458.6 5479.1 5459.8 5493.3 5528.1 5543.1 5555.1 5936.9 5942.3 5959.4 5960.5 5983.4 6552.4 6559.5 6606.2 6608.7 6663.3 1914.7 1923.9 1930.9 1948.4 1966.9 1380.8 1411.4 1423.2 1436.2 1455.5 5574.7 5579.7 5597.6 5599.4 5599.4 6393.3 6556.0 6572.3 6584.3 6645.4 5567.2 5598.7 5627.9 5636.9 5669.2 3478.5 3498.7 3500.3 3502.9 3512.7 6341.7 6372.5 6372.6 6417.5 6423.8 6478.0 6669.9 6752.8 6842.5 6904.4 6346.3 6348.6 6351.5 6355.1 6469.2 6628.7 6637.2 6646.7 6738.6 6760.9 6577.1 6591.2 6624.9 6642.6 6690.6 6509.4 6514.5 6517.4 6528.0 6531.0 4927.5 4961.2 5013.4 5042.3 5045.5 6347.5 6652.2 6672.9 6678.0 6681.2 6309.6 6462.7 6473.2 6474.7 6513.6 6372.7 6377.5 6445.1 6451.5 6469.7 6288.5 6305.0 6309.8 6346.3 6390.7 6347.0 6448.6 6470.9 6487.1 6494.9 6152.8 6173.5 6188.4 6282.1 6321.7 16:01:01 INFO - PID 3825 | 16:01:01 INFO - PID 3825 | [#5] yui.html Cycles:180 Average:24673.92 Median:19990.20 stddev:35751.21 (178.8%) stddev-sans-first:35806.94 16:01:01 INFO - PID 3825 | Values: 899.4 918.7 920.9 922.5 938.1 19047.5 19977.3 20003.1 20021.0 20025.0 18350.7 18353.7 18466.4 18558.2 18588.7 949.5 965.6 966.4 967.0 967.1 987.1 988.4 988.9 989.3 993.5 987.0 1029.6 1037.4 1047.3 1048.3 1032.7 1034.5 1039.4 1041.4 1042.0 723.8 751.6 754.2 756.7 757.1 20275.1 20399.9 20529.4 20540.5 20554.7 21436.6 21624.8 22032.4 22133.6 22165.1 17070.7 17495.7 17507.7 17523.7 17676.5 3737.9 3746.2 3767.8 3767.8 3767.9 101141.0 115651.0 143448.0 144988.0 147680.0 17536.6 17639.6 17990.6 18045.4 18131.8 31590.7 31681.7 31689.4 31755.2 32501.2 9104.4 9143.0 9156.4 9169.1 9169.5 4350.2 4379.9 4427.9 4431.9 4439.6 5595.3 5630.7 5672.2 5672.5 5697.7 1121.1 1141.8 1144.4 1147.1 1163.2 648.4 656.9 663.3 664.9 678.7 12183.4 12194.3 12412.5 12826.8 13323.5 681.9 687.1 693.9 695.4 695.7 5678.5 5781.3 5802.7 5873.7 5915.2 125700.4 126384.4 127452.4 128660.4 131776.0 114170.0 114752.0 115240.2 116267.0 118090.4 95014.5 97434.0 97657.5 97662.5 99280.0 10628.6 10689.2 10904.7 10923.0 10962.1 45309.5 46330.5 46713.0 46747.1 46807.0 58235.7 58936.0 60844.1 61037.7 61091.1 15380.3 15533.1 15610.1 15754.0 15762.7 17044.9 17315.0 17356.7 17423.7 17478.9 13460.7 13720.0 13758.0 13818.0 13864.4 15076.5 15278.9 15426.3 15440.0 15634.1 15879.1 16040.9 16093.7 16219.4 16306.3 11614.9 11716.5 11913.9 11924.7 11987.0 11873.5 11947.7 12018.3 12168.3 12187.6 16:01:01 INFO - PID 3825 | -------- Summary: end -------- 16:01:01 INFO - PID 3825 | 16:01:02 INFO - TEST-INFO | 3825: exit 0 16:01:02 INFO - TEST-OK | dromaeo_css | took 1109924ms 16:01:02 INFO - TEST-START | kraken 16:01:02 INFO - Initialising browser for kraken test... 16:01:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46711/getInfo.html -profile /tmp/tmp2RhLnp/profile 16:01:02 INFO - TEST-INFO | started process 4196 (/builds/slave/test/build/application/firefox/firefox http://localhost:46711/getInfo.html) 16:01:07 INFO - TEST-INFO | 4196: exit 0 16:01:07 INFO - Browser initialized. 16:01:07 INFO - Running cycle 1/1 for kraken test... 16:01:07 INFO - Using env: {'DISPLAY': ':0', 16:01:07 INFO - 'HOME': '/home/cltbld', 16:01:07 INFO - 'JSGC_DISABLE_POISONING': '1', 16:01:07 INFO - 'LANG': 'en_US.UTF-8', 16:01:07 INFO - 'LANGUAGE': 'en_US:en', 16:01:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 16:01:07 INFO - 'LOGNAME': 'cltbld', 16:01:07 INFO - 'MAIL': '/var/mail/cltbld', 16:01:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:01:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:01:07 INFO - 'MOZ_CRASHREPORTER': '1', 16:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 16:01:07 INFO - 'MOZ_NO_REMOTE': '1', 16:01:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:07 INFO - 'NO_EM_RESTART': '1', 16:01:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:01:07 INFO - 'PWD': '/builds/slave/test', 16:01:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:01:07 INFO - 'RUST_BACKTRACE': 'full', 16:01:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:01:07 INFO - 'SHELL': '/bin/bash', 16:01:07 INFO - 'SHLVL': '1', 16:01:07 INFO - 'TERM': 'linux', 16:01:07 INFO - 'TMOUT': '86400', 16:01:07 INFO - 'USER': 'cltbld', 16:01:07 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549', 16:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:07 INFO - '_': '/tools/buildbot/bin/python'} 16:01:07 INFO - TEST-INFO | started process 4427 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2RhLnp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:08 INFO - PID 4427 | 16:01:08 INFO - PID 4427 | (/builds/slave/test/build/application/firefox/firefox:4478): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 16:01:08 INFO - PID 4427 | 16:01:18 INFO - PID 4427 | RSS: Main: 153264128 16:01:18 INFO - PID 4427 | 16:01:21 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46711/tests/kraken/driver.html?testName=audio-beat-detection) 16:01:22 INFO - PID 4427 | RSS: Main: 159490048 16:01:22 INFO - PID 4427 | 16:01:25 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46711/tests/kraken/driver.html?testName=audio-dft) 16:01:25 INFO - PID 4427 | RSS: Main: 159981568 16:01:25 INFO - PID 4427 | 16:01:28 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46711/tests/kraken/driver.html?testName=audio-fft) 16:01:29 INFO - PID 4427 | RSS: Main: 156078080 16:01:29 INFO - PID 4427 | 16:01:31 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46711/tests/kraken/driver.html?testName=audio-oscillator) 16:01:31 INFO - PID 4427 | RSS: Main: 157356032 16:01:31 INFO - PID 4427 | 16:01:34 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46711/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:01:34 INFO - PID 4427 | RSS: Main: 157282304 16:01:34 INFO - PID 4427 | 16:01:37 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46711/tests/kraken/driver.html?testName=imaging-darkroom) 16:01:38 INFO - PID 4427 | RSS: Main: 159457280 16:01:38 INFO - PID 4427 | 16:01:42 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46711/tests/kraken/driver.html?testName=imaging-desaturate) 16:01:42 INFO - PID 4427 | RSS: Main: 160079872 16:01:42 INFO - PID 4427 | 16:01:45 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46711/tests/kraken/driver.html?testName=json-parse-financial) 16:01:46 INFO - PID 4427 | RSS: Main: 160133120 16:01:46 INFO - PID 4427 | 16:01:47 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46711/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:01:48 INFO - PID 4427 | RSS: Main: 159854592 16:01:48 INFO - PID 4427 | 16:01:50 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46711/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:01:50 INFO - PID 4427 | RSS: Main: 159850496 16:01:50 INFO - PID 4427 | 16:01:52 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46711/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:01:53 INFO - PID 4427 | RSS: Main: 156721152 16:01:53 INFO - PID 4427 | 16:01:56 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46711/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:01:56 INFO - PID 4427 | RSS: Main: 157212672 16:01:56 INFO - PID 4427 | 16:01:59 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46711/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:02:00 INFO - PID 4427 | RSS: Main: 157192192 16:02:00 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | Cycle 1(1): loaded http://localhost:46711/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46711/tests/kraken/driver.html?testName=ai-astar) 16:02:02 INFO - PID 4427 | RSS: Main: 158441472 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | __start_tp_report 16:02:02 INFO - PID 4427 | _x_x_mozilla_page_load 16:02:02 INFO - PID 4427 | _x_x_mozilla_page_load_details 16:02:02 INFO - PID 4427 | |i|pagename|runs| 16:02:02 INFO - PID 4427 | |0;ai-astar;107;108;107;106;110;109;108;107;108;108 16:02:02 INFO - PID 4427 | |1;audio-beat-detection;120;127;118;117;143;117;117;116;131;151 16:02:02 INFO - PID 4427 | |2;audio-dft;161;162;162;162;162;164;165;165;165;166 16:02:02 INFO - PID 4427 | |3;audio-fft;74;74;74;74;73;73;76;74;80;74 16:02:02 INFO - PID 4427 | |4;audio-oscillator;79;79;80;79;78;79;78;80;80;79 16:02:02 INFO - PID 4427 | |5;imaging-gaussian-blur;114;115;113;116;114;116;114;115;114;113 16:02:02 INFO - PID 4427 | |6;imaging-darkroom;148;152;147;149;147;148;149;149;150;148 16:02:02 INFO - PID 4427 | |7;imaging-desaturate;95;100;94;97;94;93;94;94;94;94 16:02:02 INFO - PID 4427 | |8;json-parse-financial;55;56;54;57;52;53;53;53;53;57 16:02:02 INFO - PID 4427 | |9;json-stringify-tinderbox;37;38;39;37;38;38;37;38;38;38 16:02:02 INFO - PID 4427 | |10;stanford-crypto-aes;82;76;75;73;76;75;74;75;75;81 16:02:02 INFO - PID 4427 | |11;stanford-crypto-ccm;117;114;116;110;119;116;115;110;115;108 16:02:02 INFO - PID 4427 | |12;stanford-crypto-pbkdf2;172;169;169;179;172;174;175;173;177;164 16:02:02 INFO - PID 4427 | |13;stanford-crypto-sha256-iterative;71;68;68;69;64;63;69;64;66;68 16:02:02 INFO - PID 4427 | __end_tp_report 16:02:02 INFO - PID 4427 | __start_cc_report 16:02:02 INFO - PID 4427 | _x_x_mozilla_cycle_collect,730 16:02:02 INFO - PID 4427 | __end_cc_report 16:02:02 INFO - PID 4427 | __startTimestamp1506294122427__endTimestamp 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | ------- Summary: start ------- 16:02:02 INFO - PID 4427 | Number of tests: 14 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#0] ai-astar Cycles:10 Average:107.80 Median:108.00 stddev:1.14 (1.1%) stddev-sans-first:1.17 16:02:02 INFO - PID 4427 | Values: 107.0 108.0 107.0 106.0 110.0 109.0 108.0 107.0 108.0 108.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:123.50 stddev:12.39 (10.0%) stddev-sans-first:12.97 16:02:02 INFO - PID 4427 | Values: 120.0 127.0 118.0 117.0 143.0 117.0 117.0 116.0 131.0 151.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#2] audio-dft Cycles:10 Average:163.40 Median:164.50 stddev:1.78 (1.1%) stddev-sans-first:1.66 16:02:02 INFO - PID 4427 | Values: 161.0 162.0 162.0 162.0 162.0 164.0 165.0 165.0 165.0 166.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#3] audio-fft Cycles:10 Average:74.60 Median:74.00 stddev:2.07 (2.8%) stddev-sans-first:2.18 16:02:02 INFO - PID 4427 | Values: 74.0 74.0 74.0 74.0 73.0 73.0 76.0 74.0 80.0 74.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#4] audio-oscillator Cycles:10 Average:79.10 Median:79.00 stddev:0.74 (0.9%) stddev-sans-first:0.78 16:02:02 INFO - PID 4427 | Values: 79.0 79.0 80.0 79.0 78.0 79.0 78.0 80.0 80.0 79.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#5] imaging-gaussian-blur Cycles:10 Average:114.40 Median:114.50 stddev:1.07 (0.9%) stddev-sans-first:1.13 16:02:02 INFO - PID 4427 | Values: 114.0 115.0 113.0 116.0 114.0 116.0 114.0 115.0 114.0 113.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#6] imaging-darkroom Cycles:10 Average:148.70 Median:149.00 stddev:1.49 (1.0%) stddev-sans-first:1.56 16:02:02 INFO - PID 4427 | Values: 148.0 152.0 147.0 149.0 147.0 148.0 149.0 149.0 150.0 148.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:94.00 stddev:2.08 (2.2%) stddev-sans-first:2.20 16:02:02 INFO - PID 4427 | Values: 95.0 100.0 94.0 97.0 94.0 93.0 94.0 94.0 94.0 94.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:1.83 (3.4%) stddev-sans-first:1.92 16:02:02 INFO - PID 4427 | Values: 55.0 56.0 54.0 57.0 52.0 53.0 53.0 53.0 53.0 57.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#9] json-stringify-tinderbox Cycles:10 Average:37.80 Median:38.00 stddev:0.63 (1.7%) stddev-sans-first:0.60 16:02:02 INFO - PID 4427 | Values: 37.0 38.0 39.0 37.0 38.0 38.0 37.0 38.0 38.0 38.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#10] stanford-crypto-aes Cycles:10 Average:76.20 Median:75.50 stddev:2.94 (3.9%) stddev-sans-first:2.24 16:02:02 INFO - PID 4427 | Values: 82.0 76.0 75.0 73.0 76.0 75.0 74.0 75.0 75.0 81.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#11] stanford-crypto-ccm Cycles:10 Average:114.00 Median:115.50 stddev:3.53 (3.1%) stddev-sans-first:3.57 16:02:02 INFO - PID 4427 | Values: 117.0 114.0 116.0 110.0 119.0 116.0 115.0 110.0 115.0 108.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:173.50 stddev:4.33 (2.5%) stddev-sans-first:4.59 16:02:02 INFO - PID 4427 | Values: 172.0 169.0 169.0 179.0 172.0 174.0 175.0 173.0 177.0 164.0 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:68.00 stddev:2.62 (3.9%) stddev-sans-first:2.35 16:02:02 INFO - PID 4427 | Values: 71.0 68.0 68.0 69.0 64.0 63.0 69.0 64.0 66.0 68.0 16:02:02 INFO - PID 4427 | -------- Summary: end -------- 16:02:02 INFO - PID 4427 | 16:02:02 INFO - PID 4427 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 16:02:02 INFO - TEST-INFO | 4427: exit 0 16:02:03 INFO - TEST-OK | kraken | took 60726ms 16:02:03 INFO - SUITE-END | took 1170s 16:02:03 INFO - Completed test suite (00:19:30) 16:02:03 INFO - javascript benchmark 16:02:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8697.033602554584, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3137.764667649926, 3158.763092768043, 3172.6827317268267, 3175.253815353393, 3182.1249156481963, 6228.346023667517, 6286.214223222098, 6329.620222786631, 6372.617642941426, 6443.355664433554, 6278.717457714402, 6312.652804095773, 6316.336784637604, 6325.620462772225, 6338.366163383671, 5801.564882633809, 5810.883782324372, 5816.825495235129, 5899.557533185029, 5964.343922168558, 5852.824415267528, 5882.441168089024, 5925.7925972590965, 5934.703264836741, 5955.047192449209, 1001.2190246331278, 5737.082066869322, 5865.296164460249, 5925.851853703649, 5946.375630558772, 5319.734013299359, 5724.427557244284, 5796.594238403313, 5851.473367396955, 5879.323877754027, 5758.42415758425, 5769.221155144086, 5815.9709201453725, 5817.767289308423, 5822.155787410802, 6283.371662833726, 6304.27500837405, 6323.209598800104, 6325.304216536178, 6403.391677790602, 6774.0, 6778.491613129035, 6857.348551887562, 6869.278725733776, 6870.31296870314, 6649.135612370409, 6852.6916288767125, 6866.828329291758, 6945.826354341131, 6949.235584085746, 6222.160008398853, 6257.530685198628, 6282.874342513124, 6366.236051673783, 6371.267304260023, 7026.473014523931, 7037.0, 7054.906489494137, 7071.8939215911805, 7110.0, 6666.600003999775, 6674.733010679615, 6702.463802895757, 6756.425703815231, 6766.526343156031, 6552.279249282575, 6675.032120342523, 6822.556533825385, 6888.345607167311, 6897.586144831326, 6610.933890661128, 6628.370304821035, 6688.531802773857, 6690.02994565795, 6693.933060669428, 6804.047433359334, 6873.621950792654, 6889.758858439931, 6924.23833378328, 6928.376446119873, 5311.389190243094, 5375.354957405137, 5380.219868119101, 5384.892302153935, 5389.0, 4538.047010127844, 4613.331066995267, 4619.076184762994, 4665.6034237090225, 4675.064987002613, 5778.27771528559, 5794.8551286217335, 5850.385709500441, 5859.677717725566, 5877.706114694248, 6803.523753337317, 6820.93179068203, 6875.724971001204, 6896.137982752156, 6897.689603967833, 5774.335951365562, 5815.680137592473, 5873.177755114288, 5883.117532370093, 5889.851478961647, 5707.600467967285, 5796.884062318814, 5820.156077368758, 5881.735321910524, 5892.381299963527, 6576.04647326135, 6601.834954126185, 6657.966710166418, 6663.466922646274, 6665.6000639960785, 6604.966975165094, 6750.594964302256, 6765.695543700644, 6824.897626535507, 6899.655017249218, 6558.409743123142, 6627.701753421204, 6641.966790166019, 6644.435223006099, 6645.501587380869, 6563.409293163638, 6683.997400389981, 6687.866242675022, 6755.716413881543, 6776.559523630948, 6785.89821152693, 6819.283975182534, 6930.896036559355, 6934.133233345832, 6935.029095926473, 6603.702833372414, 6690.163729533809, 6698.497612679166, 6699.229588597374, 6703.698333575098, 6599.274079851117, 6619.073329733745, 6691.230508491586, 6692.59844409337, 6703.631300278336, 6363.96818015907, 6471.352864713491, 6532.346765323429, 6545.836354091186, 6617.040529123154, 6697.7655782047395, 6732.528722989539, 6734.461243100638, 6750.3924646782025, 6757.358050985148, 6303.432691057644, 6386.074019267273, 6477.805665830033, 6482.546221764431, 6485.286618472059], "value": 6158.76574029346, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [860.5181098584792, 895.7357579514043, 899.8965119011308, 906.8549032154853, 910.4582773249914, 38216.808915955415, 41965.0, 41974.16051678965, 42010.159796804044, 42061.57938420619, 39043.80478097613, 39292.80353598228, 39415.60584394162, 39436.0, 39476.60523394765, 7301.342879140863, 7438.404927605806, 7442.292982166686, 7951.681932722684, 8152.062512811014, 8022.318102961255, 8130.796730081736, 8132.26809587137, 8132.552709600969, 8133.146019667938, 8078.474899131567, 8106.351491880665, 8155.877661835078, 8159.143289954529, 8162.224588664068, 8058.5567793771315, 8082.999999999971, 8083.191680831899, 8118.878216826753, 8127.674893004273, 7965.2831245187635, 8029.919700802976, 8067.515949043018, 8073.233042860918, 8077.192280771876, 37722.0, 37849.62150378516, 37979.0, 38112.23775524474, 38159.47362105513, 3519.560054993126, 3525.7355698322726, 3529.4529347951107, 3532.1699400640828, 3570.0539357070397, 31454.0, 31513.0, 31609.367812643617, 31620.841895790603, 31676.841615792004, 5129.12804823181, 5169.689818610857, 5205.7397130143345, 5215.16557350822, 5297.390800058004, 182550.0, 182639.0, 182661.0, 183038.0, 183267.16732832635, 42479.0, 43206.783966079965, 47800.7609961948, 47965.0, 48094.0, 49718.0, 50378.49621503811, 50420.24369634459, 50474.49525504698, 50568.747156263984, 16020.0, 16106.275217615235, 16138.031718096718, 16169.757453638205, 16172.838271617133, 13018.088733788732, 13053.216806991612, 13090.14914030585, 13120.540781072808, 13128.540501082416, 13674.726505469835, 13684.794728079087, 13717.931410343084, 13723.588292351245, 13861.168329900238, 5523.28197334344, 5550.111982082847, 5558.33300003994, 5559.444055594408, 5572.916406253909, 994.4331730913455, 1007.1036777268237, 1009.4145395670494, 1019.9235057370727, 1020.1940467031111, 22948.0, 23027.194048208232, 23049.539009219723, 23065.88467057654, 23151.305460836204, 1102.7408558988632, 1109.1071687291646, 1117.1788735279563, 1117.7708569743183, 1129.1982692288402, 12005.75988480243, 12010.459529321202, 12046.337451440255, 12069.275843449246, 12130.0, 213882.0, 216050.0, 216217.0, 216527.0, 216935.0, 52684.47315526872, 52811.73594132005, 52814.47185528096, 52820.0, 53055.469445305054, 1392.1438315435935, 1439.8560143985728, 1447.667036581571, 1451.934662940149, 1452.201289290907, 22178.223762168247, 22330.441738956655, 22430.102795888633, 22779.08883644702, 22866.19968301168, 37442.62557374391, 37458.43812342762, 37462.43806342962, 37510.43734344042, 37621.81189094037, 180377.0, 181800.0, 181809.0, 181986.0, 182184.0, 31282.21794455066, 31615.0, 33305.16737081592, 34201.82899085488, 34442.0, 42728.5727142737, 42875.78562107169, 43164.568354316056, 43610.345844811716, 43721.344179837964, 31855.0, 31945.201369965936, 32057.839710801298, 32363.838180808943, 32533.837330813196, 31296.530552042193, 31592.526112107877, 31596.842015789774, 31960.0, 32539.837300813346, 34523.13692157716, 34859.651403486656, 35104.12239693926, 35474.29051418906, 35560.110997225274, 30543.778248869796, 30977.535336970413, 31246.0, 31303.373932520768, 31427.685723142476, 23127.76872231256, 23220.535589288458, 23310.0, 23415.88292058529, 23497.647535286644], "value": 17026.64098596014, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.8972929737295, 588.6350462713116, 593.462916060965, 600.2167171840747, 616.6824085595919, 21753.564928701417, 22448.102075916962, 22509.0, 22512.774872251273, 22533.774662253392, 24074.0, 26636.0, 26843.32891677704, 26847.597286040724, 26903.461930761372, 6819.5226334156505, 6957.991091291759, 6961.23426423093, 6971.3028697130385, 6971.511994160411, 6500.284968653445, 6824.215215250235, 6843.555168914005, 7216.675249613753, 7227.241139680336, 7216.350528452438, 7217.3865221456235, 7220.097487814023, 7223.819404514877, 7224.783256502314, 7190.065291512122, 7192.388646965014, 7215.350618444339, 7229.457790665669, 7236.71053157873, 7071.469639776986, 7074.575525468488, 7075.752348667824, 7091.503594748369, 7109.928900710978, 30318.393632127456, 30323.545146822817, 32674.183145421317, 32861.17847053819, 32894.506582401285, 2023.3828682251908, 2035.5725297687504, 2037.3989673046487, 2039.9902048486094, 2054.342610364684, 20708.896455517624, 21225.0, 21332.253371131937, 21372.038258278415, 21377.35867923965, 2462.729099799021, 2498.5877330240487, 2569.3448170716533, 2604.960925586118, 2605.778508826752, 144619.0, 148036.0, 148045.99999999892, 148049.0, 148162.0, 18743.126787035468, 21823.23618673339, 21858.757569409125, 22469.662955055686, 22786.0, 42947.34947529495, 43305.35041974373, 43374.0, 43406.310463610316, 43446.78276608597, 11507.309561426342, 11623.418829058513, 11736.354500502384, 11791.941040294916, 11859.0, 9658.32391732586, 9684.41893486393, 9741.707748767549, 9774.804503909882, 9848.704538863845, 9638.469884156439, 11370.94314528422, 11435.828462573068, 11743.41282935867, 11767.411629418495, 3554.8222588870453, 3621.511096002059, 3688.7786732796117, 3943.369060950234, 4286.249906266391, 960.1358777100666, 979.8334283171878, 987.471702639099, 993.1409330928694, 994.4629899853998, 19730.210791568177, 19776.802231977497, 19895.10472028762, 20076.79923200749, 20430.897845510677, 1051.747580580655, 1057.2440704895882, 1058.5977328615077, 1059.4702648675661, 1070.1920050361928, 10886.346819190874, 11158.0, 11198.21612487118, 11263.04264137541, 11403.486843092081, 439668.0, 474335.0, 491471.0, 494187.0, 495752.0, 111974.0, 115835.42082289535, 116716.0, 116727.0, 118663.40668296603, 1176.3706417066999, 1182.402886542308, 1186.0630102219322, 1190.7797057544208, 1207.879212078785, 17716.202770875083, 18038.458846234633, 18060.72908906341, 18111.818881811014, 18154.183061762516, 56034.439655602924, 56053.0, 56887.0, 57119.71440142773, 59094.40905591061, 70810.645946772, 72156.63921680358, 72336.0, 72348.0, 72490.27509724835, 15901.681966360838, 16566.83433165653, 19652.705209422154, 19779.406617801487, 20360.185592576723, 22982.0, 23118.884405577865, 23408.414789630395, 23489.0, 23518.412039699146, 18109.36617218391, 18400.723989139908, 18458.81541184571, 18518.722219166993, 18652.626947460703, 16499.83500164983, 16899.91550042242, 17102.572435689206, 17127.0, 17242.482725518254, 18014.2794288227, 19193.136308866415, 19214.615707686047, 19290.517737056685, 19293.517662058563, 19916.800831992074, 20158.29445969384, 20283.290084846958, 20320.79679203248, 20354.89822550937, 18031.819681803016, 18326.26694932188, 18377.264909403475, 18501.72247416263, 18536.814631853507], "value": 13080.292574926367, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1362.1758835904277, 1412.1668215752711, 1419.8367187773408, 1423.5302350224426, 1431.7995480632712, 14956.925215373922, 16292.0, 16319.428819991303, 16324.102174380405, 16357.918210408947, 16029.198540073008, 16249.837501624981, 16290.185490725476, 16302.755458668129, 16311.91844040778, 2794.2315863137646, 2837.574363845429, 2851.144656603011, 2854.7430731234185, 2857.22854829196, 2740.5204089284393, 2830.0943698016645, 2835.3053501892064, 2844.288927768058, 2846.3168839478585, 2792.1204820481485, 2819.379736457976, 2836.659600847901, 2838.68774434812, 2839.0914907229694, 2759.351552385188, 2802.985985070072, 2809.1291699572994, 2809.90165344213, 2810.18504633656, 2690.408110215769, 2724.155511791351, 2729.4404647047504, 2730.331068888135, 2731.904383346593, 15069.397224111022, 15101.244937753068, 15128.621784455367, 15164.317605707769, 15190.316435760416, 8090.716824911159, 8656.177663121993, 8666.956665216696, 8674.089220631806, 8676.175763302475, 5230.21546767984, 5459.836204913859, 5539.279893613845, 5560.7219639017885, 5562.0266453370905, 1971.9802801972085, 1999.290251960562, 2005.8896760678158, 2040.4898775306174, 2059.567490826928, 26380.73619263821, 26499.205023849314, 26608.60087098695, 26644.46711065768, 26664.333391664975, 6837.35045170708, 7105.111861017373, 7158.141023077264, 7280.563166210044, 7285.70857165718, 16724.49826505207, 16774.58063548421, 16784.496465106065, 16796.0, 16854.0, 7968.0, 8284.544350060683, 8307.6676932922, 8328.042275138316, 8340.958295208606, 6326.367363263729, 6467.353264673589, 6759.121314229217, 6760.898586521206, 7451.403887688972, 5240.502152295526, 5383.380911195184, 5434.3207099112615, 5445.428230035868, 5530.198121272393, 1573.2369800646677, 1601.8157911840055, 1610.2351383092937, 1617.4500669772333, 1618.9757153642704, 902.192537678787, 917.2845180759018, 919.1681528216841, 924.778053267211, 927.1655510040882, 7610.885836712454, 8549.102344253888, 8636.136386361313, 8683.652653893774, 8735.213830755263, 732.1543617122232, 747.5552046532305, 748.7416841189781, 750.084896426359, 757.6817736550662, 4588.931166032512, 4721.976390118096, 4746.311784791186, 4748.358971538867, 4798.520147985243, 21537.892310538347, 22241.332760016903, 22443.438914026952, 22491.550168996528, 22560.774392256197, 21878.781212187674, 22115.778842211694, 22140.2250921217, 22276.220332288292, 22322.665160022614, 10792.568297268179, 11387.0, 11444.256123351955, 11514.0, 11521.251118677259, 8704.78238044054, 8712.346574007102, 8794.120587941155, 8794.120587941155, 8897.466152030898, 18088.547786305448, 18621.162047707614, 18705.906470467562, 18719.532011699815, 19120.33078842234, 19413.223471061, 19466.221351146363, 19507.0, 19576.804231958067, 19627.214911403957, 10989.670309890716, 11004.944975275072, 11027.724306892393, 11056.170787191153, 11065.059469944823, 14240.786388204393, 14282.857171428153, 14326.856731432552, 14372.425102995763, 14409.279536022947, 11053.226274160732, 11387.0, 11464.598739044093, 11486.712832179262, 11617.0706343494, 11206.103511719206, 11232.66302010941, 11487.770244594894, 11634.0, 11673.299602023906, 10498.21263405263, 10517.05346518817, 10550.947245263724, 10560.419176945341, 10580.523876425425, 7588.506747061423, 7758.3017528422715, 7782.688692452239, 7812.101608315116, 7833.0, 7783.299503044753, 7786.805329866798, 7851.725189618336, 7855.7250496232355, 7883.605819709106], "value": 6792.338619325788, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.6970782676482, 513.5609054258612, 534.599050711966, 540.2274747111629, 541.2909089093286, 6156.938430615698, 6222.751089956402, 6225.34633863444, 6296.331472159882, 6316.210473690789, 6131.2642482902, 6135.785247516338, 6155.6306621602735, 6216.378362163781, 6216.627002379861, 5143.871403214912, 5148.253503242027, 5154.556724117235, 5171.948280517203, 5180.4042535108565, 5113.488651134894, 5119.360079990001, 5144.202648589456, 5154.819581314655, 5185.611079169059, 4938.80244790208, 5099.388073431175, 5102.948970510284, 5104.821331253388, 5135.871603209931, 5074.82238121664, 5093.974530127362, 5125.282460455539, 5164.406093299281, 5179.792808287664, 4776.928346074812, 4798.952010479886, 4799.808007679723, 4828.203346447832, 4850.757462126915, 5873.207117039187, 6014.578979471439, 6054.969725151346, 6067.514598832083, 6166.7533298667995, 5837.357890632027, 5888.175655408247, 5961.035911119101, 5968.671723055274, 5995.130706047642, 6572.967135164294, 6591.340865913418, 6620.801375958681, 6627.536072474929, 6646.435053020451, 5065.468125846806, 5276.261323414725, 5281.181416880389, 5283.86790330241, 5315.096433606296, 6712.731490740413, 6740.494462915301, 6837.179538455367, 6842.178938527359, 6846.760363387308, 5379.973100134474, 5420.810271640474, 5449.4005659377435, 5458.645188062763, 5479.0959491683425, 5459.836204913859, 5493.285872836505, 5528.115501519737, 5543.113101903676, 5555.138953462219, 5936.9406305937255, 5942.286925568873, 5959.404059594006, 5960.523158147338, 5983.431574000463, 6552.3775241352, 6559.540832141704, 6606.240282367493, 6608.7026083825385, 6663.333666633298, 1914.6840771272584, 1923.884566925989, 1930.9186855361042, 1948.366780796247, 1966.8985368193858, 1380.8412032616177, 1411.3789932429904, 1423.2030063164661, 1436.2029073863896, 1455.505128256398, 5574.721263936787, 5579.721013949286, 5597.63615365, 5599.384067752544, 5599.412061733541, 6393.328700486475, 6556.0, 6572.342765723485, 6584.341565843377, 6645.368689974542, 5567.192757050205, 5598.664080155204, 5627.902559000955, 5636.915446268309, 5669.177969194444, 3478.530398396235, 3498.6851183393615, 3500.2824420993634, 3502.8598856045473, 3512.6838584527513, 6341.651209183447, 6372.522060845455, 6372.58578192416, 6417.454516366161, 6423.775167869102, 6477.967610161919, 6669.933300666931, 6752.763653272112, 6842.4868134890085, 6904.378605925491, 6346.333634968354, 6348.619082855043, 6351.491880649444, 6355.142055822497, 6469.191351081115, 6628.7017084230365, 6637.203535575762, 6646.667666616553, 6738.561993470409, 6760.864782704417, 6577.106073327721, 6591.220370500034, 6624.867502649824, 6642.568233065029, 6690.632015239208, 6509.414152726277, 6514.4788416925585, 6517.4134327910715, 6528.0, 6530.967345163243, 4927.531884470969, 4961.156603377362, 5013.423456302572, 5042.344495215599, 5045.477261369316, 6347.460465860454, 6652.201735791736, 6672.8999065013095, 6677.998300255001, 6681.162680262966, 6309.558330916791, 6462.7414903405215, 6473.158489396348, 6474.708638111202, 6513.609183449008, 6372.713227904663, 6377.489800816016, 6445.12990746262, 6451.548391612542, 6469.676516174079, 6288.528360373082, 6304.968475157595, 6309.823111026689, 6346.270178929482, 6390.712417941111, 6346.9682651586445, 6448.613083215022, 6470.870582588416, 6487.124238227779, 6494.935050649433, 6152.846178845564, 6173.475254603409, 6188.412100850412, 6282.120503129657, 6321.715522801393], "value": 5129.442632406997, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [899.3839220134208, 918.7243826851947, 920.9033051529585, 922.5387306346827, 938.1041105744015, 19047.52381190471, 19977.300794472198, 20003.099860506274, 20021.0, 20025.0, 18350.724739128924, 18353.724694129567, 18466.353677621286, 18558.164882580284, 18588.721169182474, 949.5062567464897, 965.5944503308627, 966.3670295956125, 966.9516524173798, 967.0957654592943, 987.0869445762677, 988.426712506748, 988.85661579071, 989.3420875118073, 993.4585650820284, 986.9555869985832, 1029.5675816157195, 1037.419045334615, 1047.3401756893147, 1048.2871647279846, 1032.726327523207, 1034.5034383495927, 1039.3711804358368, 1041.4324193314624, 1041.9791604167874, 723.8190452386904, 751.5565816168432, 754.226917409654, 756.7200135949679, 757.0536828963795, 20275.08762105709, 20399.89800051005, 20529.384118476468, 20540.486487837774, 20554.69167962482, 21436.5712685747, 21624.783752162435, 22032.44918877025, 22133.557328853494, 22165.113395464163, 17070.658586828195, 17495.737563936553, 17507.73738393925, 17523.73714394285, 17676.469705908843, 3737.906552336186, 3746.2132952079824, 3767.830447629863, 3767.830447629863, 3767.924641507182, 101141.0, 115651.0, 143448.0, 144988.0, 147680.0, 17536.56158596045, 17639.559011024827, 17990.550236243937, 18045.368412105512, 18131.818681813278, 31590.684093159234, 31681.683183168334, 31689.366212675617, 31755.20611984719, 32501.18747031343, 9104.408213466104, 9142.954285228532, 9156.404833685923, 9169.08309169091, 9169.541522923828, 4350.1734670412525, 4379.890502737457, 4427.911441771146, 4431.8670439886855, 4439.600435960714, 5595.328560572676, 5630.718464076862, 5672.177534257592, 5672.461116193955, 5697.658140511582, 1121.0639116337786, 1141.8001849676273, 1144.3648774929993, 1147.0937959012454, 1163.209017867858, 648.3807963394946, 656.917885264342, 663.326723375774, 664.9401553860176, 678.6810199206365, 12183.390830458442, 12194.329311887932, 12412.503499859904, 12826.78596393124, 13323.467061317633, 681.9011243369683, 687.0964681443985, 693.9236683964759, 695.3706895259797, 695.7286658203304, 5678.517326027251, 5781.277340332458, 5802.738876750472, 5873.735681894325, 5915.201447804491, 125700.37149814375, 126384.36807815902, 127452.36273818757, 128660.35669821779, 131776.0, 114170.0, 114752.0, 115240.20621124233, 116267.0, 118090.40954795343, 95014.5249273763, 97434.0, 97657.5117124424, 97662.5116874411, 99280.0, 10628.627998020032, 10689.198310126616, 10904.727381815517, 10923.016928476476, 10962.068224201032, 45309.546904530536, 46330.53669463397, 46713.0, 46747.065058699314, 46806.99999999864, 58235.70882145562, 58936.0, 60844.08733869084, 61037.69481152566, 61091.08363374464, 15380.307886145374, 15533.145676987422, 15610.063396196265, 15754.0, 15762.68474630478, 17044.914775426045, 17315.047672377637, 17356.739648905528, 17423.738643920096, 17478.91260543689, 13460.730785384041, 13720.0, 13758.036937414685, 13818.032737708667, 13864.376103075587, 15076.547703568911, 15278.923605381902, 15426.30581623852, 15439.99640023395, 15634.06195628266, 15879.12664803447, 16040.919795401414, 16093.678126437171, 16219.35122595083, 16306.26621801998, 11614.941925290319, 11716.531338746354, 11913.880861191277, 11924.70188245266, 11987.0, 11873.465694043616, 11947.701307467383, 12018.278903265833, 12168.330741809286, 12187.573434929735], "value": 9054.637891524068, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1430.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 108.0, 107.0, 106.0, 110.0, 109.0, 108.0, 107.0, 108.0, 108.0], "value": 107.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 127.0, 118.0, 117.0, 143.0, 117.0, 117.0, 116.0, 131.0, 151.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 162.0, 162.0, 164.0, 165.0, 165.0, 165.0, 166.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 74.0, 73.0, 73.0, 76.0, 74.0, 80.0, 74.0], "value": 74.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 79.0, 78.0, 79.0, 78.0, 80.0, 80.0, 79.0], "value": 79.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 113.0, 116.0, 114.0, 116.0, 114.0, 115.0, 114.0, 113.0], "value": 114.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 152.0, 147.0, 149.0, 147.0, 148.0, 149.0, 149.0, 150.0, 148.0], "value": 148.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 94.0, 97.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 57.0, 52.0, 53.0, 53.0, 53.0, 53.0, 57.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 38.0, 39.0, 37.0, 38.0, 38.0, 37.0, 38.0, 38.0, 38.0], "value": 37.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 76.0, 75.0, 73.0, 76.0, 75.0, 74.0, 75.0, 75.0, 81.0], "value": 76.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 116.0, 110.0, 119.0, 116.0, 115.0, 110.0, 115.0, 108.0], "value": 114.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 169.0, 179.0, 172.0, 174.0, 175.0, 173.0, 177.0, 164.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 68.0, 69.0, 64.0, 63.0, 69.0, 64.0, 66.0, 68.0], "value": 67.0, "unit": "score"}]}]} 16:02:03 INFO - Return code: 0 16:02:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:02:03 INFO - # TBPL SUCCESS # 16:02:03 INFO - Running post-action listener: _package_coverage_data 16:02:03 INFO - Running post-action listener: _resource_record_post_action 16:02:03 INFO - [mozharness: 2017-09-24 23:02:03.207107Z] Finished run-tests step (success) 16:02:03 INFO - Running post-run listener: _resource_record_post_run 16:02:03 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 9854976; Write bytes: 400535552; Read time: 3596; Write time: 284564 16:02:03 INFO - TinderboxPrint: CPU usage
13.1% 16:02:03 INFO - TinderboxPrint: I/O read bytes / time
9,854,976 / 3,596 16:02:03 INFO - TinderboxPrint: I/O write bytes / time
400,535,552 / 284,564 16:02:03 INFO - TinderboxPrint: CPU idle
8,234.3 (86.9%) 16:02:03 INFO - TinderboxPrint: CPU user
1,192.2 (12.6%) 16:02:03 INFO - TinderboxPrint: Swap in / out
0 / 0 16:02:03 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 75767808; Read time: 0; Write time: 30960 16:02:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:02:03 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 7667712; Write bytes: 264232960; Read time: 3052; Write time: 186844 16:02:03 INFO - Running post-run listener: _upload_blobber_files 16:02:03 INFO - Blob upload gear active. 16:02:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:02:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:02:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:02:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:02:04 INFO - (blobuploader) - INFO - Open directory for files ... 16:02:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:02:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:02:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:13 INFO - (blobuploader) - INFO - Done attempting. 16:02:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:02:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:02:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:14 INFO - (blobuploader) - INFO - Done attempting. 16:02:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:02:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:02:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:15 INFO - (blobuploader) - INFO - Done attempting. 16:02:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:02:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:02:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:02:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:02:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:02:16 INFO - (blobuploader) - INFO - Done attempting. 16:02:16 INFO - (blobuploader) - INFO - Iteration through files over. 16:02:16 INFO - Return code: 0 16:02:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:02:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:02:16 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32a5797f7403d43a11b5f91c52da0a6c7b7ee28eeaffcbce8e1e4c55673ee35e743b66cecccdbdadbaf82f821414453599abf87acd94352499752dc8ee2f2f02", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a5a357bcea44e1ab063c5ebd31e0b62656a24448d55561cb5404295719a75c15164f21757ca52e82cf68ce869ca7ec85296930d519c587c3bdcc51d5fabb6bf", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c16af697378e0134e771cc7ec7b2a6dac5e3960b9d18d6df12ea04a78c4b4a29fb25058b498f69716cd3ce8f559cc4eff4bf330adf0aabc3ea79c3208b56bb6c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3860865742895a98267ddcfad18b692ee267669ebf3129ed83a856f8b68f4fea7a8413b5fa74c19be1f97f41a289edb0a9202fd588ce292c13ccb8801fc55577"} 16:02:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:02:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:02:16 INFO - Contents: 16:02:16 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32a5797f7403d43a11b5f91c52da0a6c7b7ee28eeaffcbce8e1e4c55673ee35e743b66cecccdbdadbaf82f821414453599abf87acd94352499752dc8ee2f2f02", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a5a357bcea44e1ab063c5ebd31e0b62656a24448d55561cb5404295719a75c15164f21757ca52e82cf68ce869ca7ec85296930d519c587c3bdcc51d5fabb6bf", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c16af697378e0134e771cc7ec7b2a6dac5e3960b9d18d6df12ea04a78c4b4a29fb25058b498f69716cd3ce8f559cc4eff4bf330adf0aabc3ea79c3208b56bb6c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3860865742895a98267ddcfad18b692ee267669ebf3129ed83a856f8b68f4fea7a8413b5fa74c19be1f97f41a289edb0a9202fd588ce292c13ccb8801fc55577"} 16:02:16 INFO - Running post-run listener: copy_logs_to_upload_dir 16:02:16 INFO - Copying logs to upload dir... 16:02:16 INFO - mkdir: /builds/slave/test/build/upload/logs 16:02:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.036047 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2017-09-24 16:02:16.155305) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 16:02:16.159323) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549 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/32a5797f7403d43a11b5f91c52da0a6c7b7ee28eeaffcbce8e1e4c55673ee35e743b66cecccdbdadbaf82f821414453599abf87acd94352499752dc8ee2f2f02", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a5a357bcea44e1ab063c5ebd31e0b62656a24448d55561cb5404295719a75c15164f21757ca52e82cf68ce869ca7ec85296930d519c587c3bdcc51d5fabb6bf", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c16af697378e0134e771cc7ec7b2a6dac5e3960b9d18d6df12ea04a78c4b4a29fb25058b498f69716cd3ce8f559cc4eff4bf330adf0aabc3ea79c3208b56bb6c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3860865742895a98267ddcfad18b692ee267669ebf3129ed83a856f8b68f4fea7a8413b5fa74c19be1f97f41a289edb0a9202fd588ce292c13ccb8801fc55577"} build_url:https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011484 build_url: 'https://queue.taskcluster.net/v1/task/fh1savD2Q1aQeqxvlInuLA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/32a5797f7403d43a11b5f91c52da0a6c7b7ee28eeaffcbce8e1e4c55673ee35e743b66cecccdbdadbaf82f821414453599abf87acd94352499752dc8ee2f2f02", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/1a5a357bcea44e1ab063c5ebd31e0b62656a24448d55561cb5404295719a75c15164f21757ca52e82cf68ce869ca7ec85296930d519c587c3bdcc51d5fabb6bf", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c16af697378e0134e771cc7ec7b2a6dac5e3960b9d18d6df12ea04a78c4b4a29fb25058b498f69716cd3ce8f559cc4eff4bf330adf0aabc3ea79c3208b56bb6c", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3860865742895a98267ddcfad18b692ee267669ebf3129ed83a856f8b68f4fea7a8413b5fa74c19be1f97f41a289edb0a9202fd588ce292c13ccb8801fc55577"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-24 16:02:16.190717) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:02:16.191311) ========= 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=23a51e5d4d00092b8466c6c0000001c3-1506290016.971137-587160549 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-24 16:02:16.273108) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 16:02:16.273457) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-24 16:02:16.273785) ========= ========= Total master_lag: 0.23 =========