builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-021 starttime: 1505493085.04 results: success (0) revision: ef07d617a48866f0ec06e357142255ca1e1e6b87 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:25.043713) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:25.044160) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:25.044443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:25.183494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:25.183860) ========= 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=df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-15 09:31:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 39.3M=0s 2017-09-15 09:31:25 (39.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095841 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:25.299760) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:25.300056) ========= 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=df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017001 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:25.349822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-15 09:31:25.350152) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ef07d617a48866f0ec06e357142255ca1e1e6b87 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ef07d617a48866f0ec06e357142255ca1e1e6b87 --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=df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-15 09:31:25,413 truncating revision to first 12 chars 2017-09-15 09:31:25,413 Setting DEBUG logging. 2017-09-15 09:31:25,413 attempt 1/10 2017-09-15 09:31:25,413 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ef07d617a488?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-15 09:31:26,468 unpacking tar archive at: mozilla-beta-ef07d617a488/testing/mozharness/ program finished with exit code 0 elapsedTime=1.449298 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-15 09:31:26.815439) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:26.815740) ========= script_repo_revision: ef07d617a48866f0ec06e357142255ca1e1e6b87 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:26.819141) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:26.819411) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-15 09:31:26.833091) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-15 09:31:26.833430) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-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', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-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=df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:31:27 INFO - MultiFileLogger online at 20170915 09:31:27 in /builds/slave/test 09:31:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO 09:31:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:31:27 INFO - {'append_to_log': False, 09:31:27 INFO - 'base_work_dir': '/builds/slave/test', 09:31:27 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO', 09:31:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:31:27 INFO - 'branch': 'Mozilla-Beta-Non-PGO', 09:31:27 INFO - 'buildbot_json_path': 'buildprops.json', 09:31:27 INFO - 'code_coverage': False, 09:31:27 INFO - 'config_files': ('talos/linux_config.py',), 09:31:27 INFO - 'default_actions': ('clobber', 09:31:27 INFO - 'read-buildbot-config', 09:31:27 INFO - 'download-and-extract', 09:31:27 INFO - 'populate-webroot', 09:31:27 INFO - 'create-virtualenv', 09:31:27 INFO - 'install', 09:31:27 INFO - 'setup-mitmproxy', 09:31:27 INFO - 'run-tests'), 09:31:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:31:27 INFO - 'disable_ccov_upload': False, 09:31:27 INFO - 'disable_stylo': False, 09:31:27 INFO - 'download_minidump_stackwalk': True, 09:31:27 INFO - 'download_symbols': 'ondemand', 09:31:27 INFO - 'enable_stylo': False, 09:31:27 INFO - 'enable_webrender': False, 09:31:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 09:31:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:31:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:31:27 INFO - 'gecko_profile': False, 09:31:27 INFO - 'gecko_profile_interval': 0, 09:31:27 INFO - 'installer_path': 'installer.exe', 09:31:27 INFO - 'log_level': 'info', 09:31:27 INFO - 'log_name': 'talos', 09:31:27 INFO - 'log_to_console': True, 09:31:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:31:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:31:27 INFO - 'opt_config_files': (), 09:31:27 INFO - 'pip_index': False, 09:31:27 INFO - 'suite': 'dromaeojs-e10s', 09:31:27 INFO - 'system_bits': '32', 09:31:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:31:27 INFO - 'title': 'talos-linux64-ix-021', 09:31:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:31:27 INFO - 'use_talos_json': True, 09:31:27 INFO - 'verify': 'False', 09:31:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:31:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:31:27 INFO - 'work_dir': 'build'} 09:31:27 INFO - [mozharness: 2017-09-15 16:31:27.031561Z] Running clobber step. 09:31:27 INFO - Running pre-action listener: _resource_record_pre_action 09:31:27 INFO - Running main action method: clobber 09:31:27 INFO - rmtree: /builds/slave/test/build 09:31:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:31:28 INFO - Running post-action listener: _resource_record_post_action 09:31:28 INFO - [mozharness: 2017-09-15 16:31:28.027274Z] Finished clobber step (success) 09:31:28 INFO - [mozharness: 2017-09-15 16:31:28.027372Z] Running read-buildbot-config step. 09:31:28 INFO - Running pre-action listener: _resource_record_pre_action 09:31:28 INFO - Running main action method: read_buildbot_config 09:31:28 INFO - Using buildbot properties: 09:31:28 INFO - { 09:31:28 INFO - "project": "", 09:31:28 INFO - "product": "firefox", 09:31:28 INFO - "who": "jlorenzo@mozilla.com", 09:31:28 INFO - "installer_path": "public/build/target.tar.bz2", 09:31:28 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 09:31:28 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta", 09:31:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos dromaeojs-e10s", 09:31:28 INFO - "stage_platform": "linux64", 09:31:28 INFO - "basedir": "/builds/slave/test", 09:31:28 INFO - "buildnumber": 87, 09:31:28 INFO - "platform": "ubuntu64_hw", 09:31:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:31:28 INFO - "slavebuilddir": "test", 09:31:28 INFO - "taskId": "eMCzo7YVTK6T45Ida0uPCQ", 09:31:28 INFO - "branch": "mozilla-beta", 09:31:28 INFO - "script_repo_revision": "production", 09:31:28 INFO - "revision": "ef07d617a48866f0ec06e357142255ca1e1e6b87", 09:31:28 INFO - "slavename": "talos-linux64-ix-021", 09:31:28 INFO - "repo_path": "releases/mozilla-beta" 09:31:28 INFO - } 09:31:28 INFO - Finding installer, test and symbols from parent task. 09:31:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eMCzo7YVTK6T45Ida0uPCQ'}, attempt #1 09:31:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eMCzo7YVTK6T45Ida0uPCQ'}, attempt #1 09:31:28 INFO - Task dependencies: BhEkn1lqSQCb7wRJIBD7Xw 09:31:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw'}, attempt #1 09:31:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.tar.bz2 09:31:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.test_packages.json 09:31:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.crashreporter-symbols.zip 09:31:29 INFO - Running post-action listener: _resource_record_post_action 09:31:29 INFO - [mozharness: 2017-09-15 16:31:29.396843Z] Finished read-buildbot-config step (success) 09:31:29 INFO - [mozharness: 2017-09-15 16:31:29.397061Z] Running download-and-extract step. 09:31:29 INFO - Running pre-action listener: _resource_record_pre_action 09:31:29 INFO - Running main action method: download_and_extract 09:31:29 INFO - mkdir: /builds/slave/test/build/tests 09:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:29 INFO - trying https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.test_packages.json 09:31:29 INFO - Downloading https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:31:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:31:29 INFO - Downloaded 989 bytes. 09:31:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:31:29 INFO - Using the following test package requirements: 09:31:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:31:29 INFO - u'common': [u'target.common.tests.zip'], 09:31:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:31:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:31:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:31:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:31:29 INFO - u'mozbase': [u'target.common.tests.zip'], 09:31:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:31:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:31:29 INFO - u'web-platform': [u'target.common.tests.zip', 09:31:29 INFO - u'target.web-platform.tests.tar.gz'], 09:31:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:31:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 09:31:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.common.tests.zip 09:31:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:31:29 INFO - Fetch https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.common.tests.zip into memory 09:31:33 INFO - Content-Length response header: 55172262 09:31:33 INFO - Bytes received: 55172262 09:31:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.talos.tests.zip 09:31:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:31:44 INFO - Fetch https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.talos.tests.zip into memory 09:31:45 INFO - Content-Length response header: 14500356 09:31:45 INFO - Bytes received: 14500356 09:31:46 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')]} 09:31:46 INFO - trying https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.tar.bz2 09:31:46 INFO - Downloading https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:31:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:31:48 INFO - Downloaded 53288121 bytes. 09:31:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.tar.bz2 09:31:48 INFO - mkdir: /builds/slave/test/properties 09:31:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:31:48 INFO - Writing to file /builds/slave/test/properties/build_url 09:31:48 INFO - Contents: 09:31:48 INFO - build_url:https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.tar.bz2 09:31:48 INFO - Running post-action listener: _resource_record_post_action 09:31:48 INFO - Running post-action listener: find_tests_for_verification 09:31:48 INFO - Running post-action listener: set_extra_try_arguments 09:31:48 INFO - [mozharness: 2017-09-15 16:31:48.497986Z] Finished download-and-extract step (success) 09:31:48 INFO - [mozharness: 2017-09-15 16:31:48.498110Z] Running populate-webroot step. 09:31:48 INFO - Running pre-action listener: _resource_record_pre_action 09:31:48 INFO - Running main action method: populate_webroot 09:31:48 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:31:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['tresize', 09:31:48 INFO - 'tcanvasmark']}, 09:31:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:31:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['dromaeo_css', 09:31:48 INFO - 'kraken']}, 09:31:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:31:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:31:48 INFO - 'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:31:48 INFO - 'tests': ['damp', 'tps']}, 09:31:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:31:48 INFO - 'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['damp', 'tps']}, 09:31:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:31:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['dromaeo_dom']}, 09:31:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:31:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['basic_compositor_video', 09:31:48 INFO - 'glvideo']}, 09:31:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:31:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:31:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:31:48 INFO - 'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['ts_paint_webext', 09:31:48 INFO - 'tp5o_webext']}, 09:31:48 INFO - 'other-e10s': {'tests': ['a11yr', 09:31:48 INFO - 'ts_paint', 09:31:48 INFO - 'tpaint', 09:31:48 INFO - 'sessionrestore', 09:31:48 INFO - 'sessionrestore_many_windows', 09:31:48 INFO - 'sessionrestore_no_auto_restore', 09:31:48 INFO - 'tabpaint']}, 09:31:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['a11yr', 09:31:48 INFO - 'ts_paint', 09:31:48 INFO - 'tpaint', 09:31:48 INFO - 'sessionrestore', 09:31:48 INFO - 'sessionrestore_many_windows', 09:31:48 INFO - 'sessionrestore_no_auto_restore', 09:31:48 INFO - 'tabpaint']}, 09:31:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 09:31:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 09:31:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['bloom_basic_singleton']}, 09:31:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['bloom_basic']}, 09:31:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:31:48 INFO - 'tsvgr_opacity', 09:31:48 INFO - 'tart', 09:31:48 INFO - 'tscrollx', 09:31:48 INFO - 'tsvg_static']}, 09:31:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['tsvgx', 09:31:48 INFO - 'tsvgr_opacity', 09:31:48 INFO - 'tart', 09:31:48 INFO - 'tscrollx', 09:31:48 INFO - 'tsvg_static']}, 09:31:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:31:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:31:48 INFO - 'talos_options': ['--disable-stylo'], 09:31:48 INFO - 'tests': ['tp5o']}, 09:31:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:31:48 INFO - 'talos_options': ['--mitmproxy', 09:31:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:31:48 INFO - '--firstNonBlankPaint'], 09:31:48 INFO - 'tests': ['tp6_google', 09:31:48 INFO - 'tp6_youtube', 09:31:48 INFO - 'tp6_amazon', 09:31:48 INFO - 'tp6_facebook']}, 09:31:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:31:48 INFO - 'talos_options': ['--disable-stylo', 09:31:48 INFO - '--mitmproxy', 09:31:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:31:48 INFO - '--firstNonBlankPaint'], 09:31:48 INFO - 'tests': ['tp6_google', 09:31:48 INFO - 'tp6_youtube', 09:31:48 INFO - 'tp6_amazon', 09:31:48 INFO - 'tp6_facebook']}, 09:31:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 09:31:48 INFO - 'talos_options': ['--stylo-threads=1', 09:31:48 INFO - '--mitmproxy', 09:31:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 09:31:48 INFO - '--firstNonBlankPaint'], 09:31:48 INFO - 'tests': ['tp6_google', 09:31:48 INFO - 'tp6_youtube', 09:31:48 INFO - 'tp6_amazon', 09:31:48 INFO - 'tp6_facebook']}, 09:31:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:31:48 INFO - 'talos_options': ['--xperf_path', 09:31:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:48 INFO - 'tests': ['tp5n']}, 09:31:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 09:31:48 INFO - 'talos_options': ['--disable-stylo', 09:31:48 INFO - '--xperf_path', 09:31:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:48 INFO - 'tests': ['tp5n']}}} 09:31:48 INFO - Running post-action listener: _resource_record_post_action 09:31:48 INFO - [mozharness: 2017-09-15 16:31:48.514758Z] Finished populate-webroot step (success) 09:31:48 INFO - [mozharness: 2017-09-15 16:31:48.514848Z] Running create-virtualenv step. 09:31:48 INFO - Running pre-action listener: _resource_record_pre_action 09:31:48 INFO - Running main action method: create_virtualenv 09:31:48 INFO - Creating virtualenv /builds/slave/test/build/venv 09:31:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:31:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 09:31:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:31:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:31:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:31:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:31:49 INFO - Installing pip...............done. 09:31:49 INFO - Return code: 0 09:31:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:31:49 INFO - Reading from file tmpfile_stdout 09:31:49 INFO - Output received: 09:31:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 09:31:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31: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')]} 09:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:49 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 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:31:49 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 09:31:49 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 09:31:49 INFO - Using env: {'DISPLAY': ':0', 09:31:49 INFO - 'HOME': '/home/cltbld', 09:31:49 INFO - 'LANG': 'en_US.UTF-8', 09:31:49 INFO - 'LANGUAGE': 'en_US:en', 09:31:49 INFO - 'LOGNAME': 'cltbld', 09:31:49 INFO - 'MAIL': '/var/mail/cltbld', 09:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:49 INFO - 'MOZ_NO_REMOTE': '1', 09:31:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:49 INFO - 'NO_EM_RESTART': '1', 09:31:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:49 INFO - 'PWD': '/builds/slave/test', 09:31:49 INFO - 'SHELL': '/bin/bash', 09:31:49 INFO - 'SHLVL': '1', 09:31:49 INFO - 'TERM': 'linux', 09:31:49 INFO - 'TMOUT': '86400', 09:31:49 INFO - 'USER': 'cltbld', 09:31:49 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:49 INFO - '_': '/tools/buildbot/bin/python'} 09:31:52 INFO - Ignoring indexes: http://pypi.python.org/simple/ 09:31:52 INFO - Downloading/unpacking pip>=1.5 09:31:52 INFO - Running setup.py egg_info for package pip 09:31:52 INFO - warning: no files found matching 'pip/cacert.pem' 09:31:52 INFO - warning: no files found matching '*.html' under directory 'docs' 09:31:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:31:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:31:52 INFO - Installing collected packages: pip 09:31:52 INFO - Found existing installation: pip 1.1 09:31:52 INFO - Uninstalling pip: 09:31:52 INFO - Successfully uninstalled pip 09:31:52 INFO - Running setup.py install for pip 09:31:52 INFO - warning: no files found matching 'pip/cacert.pem' 09:31:52 INFO - warning: no files found matching '*.html' under directory 'docs' 09:31:52 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 09:31:52 INFO - no previously-included directories found matching 'docs/_build/_sources' 09:31:52 INFO - Installing pip script to /builds/slave/test/build/venv/bin 09:31:52 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 09:31:52 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 09:31:52 INFO - Successfully installed pip 09:31:52 INFO - Cleaning up... 09:31:52 INFO - Return code: 0 09:31:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:52 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 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:31:52 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 09:31:52 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 09:31:52 INFO - Using env: {'DISPLAY': ':0', 09:31:52 INFO - 'HOME': '/home/cltbld', 09:31:52 INFO - 'LANG': 'en_US.UTF-8', 09:31:52 INFO - 'LANGUAGE': 'en_US:en', 09:31:52 INFO - 'LOGNAME': 'cltbld', 09:31:52 INFO - 'MAIL': '/var/mail/cltbld', 09:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:52 INFO - 'MOZ_NO_REMOTE': '1', 09:31:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:52 INFO - 'NO_EM_RESTART': '1', 09:31:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:52 INFO - 'PWD': '/builds/slave/test', 09:31:52 INFO - 'SHELL': '/bin/bash', 09:31:52 INFO - 'SHLVL': '1', 09:31:52 INFO - 'TERM': 'linux', 09:31:52 INFO - 'TMOUT': '86400', 09:31:52 INFO - 'USER': 'cltbld', 09:31:52 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:52 INFO - '_': '/tools/buildbot/bin/python'} 09:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:52 INFO - Downloading/unpacking psutil>=3.1.1 09:31:52 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 09:31:52 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 09:31:52 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 09:31:52 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 09:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:31:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:54 INFO - Installing collected packages: psutil 09:31:54 INFO - Running setup.py install for psutil 09:31:54 INFO - building 'psutil._psutil_linux' extension 09:31:54 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 09:31:54 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 09:31:54 INFO - building 'psutil._psutil_posix' extension 09:31:54 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 09:31:54 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 09:31:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:55 INFO - Successfully installed psutil 09:31:55 INFO - Cleaning up... 09:31:56 INFO - Return code: 0 09:31:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:56 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 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:31:56 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 09:31:56 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 09:31:56 INFO - Using env: {'DISPLAY': ':0', 09:31:56 INFO - 'HOME': '/home/cltbld', 09:31:56 INFO - 'LANG': 'en_US.UTF-8', 09:31:56 INFO - 'LANGUAGE': 'en_US:en', 09:31:56 INFO - 'LOGNAME': 'cltbld', 09:31:56 INFO - 'MAIL': '/var/mail/cltbld', 09:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:56 INFO - 'MOZ_NO_REMOTE': '1', 09:31:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:56 INFO - 'NO_EM_RESTART': '1', 09:31:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:56 INFO - 'PWD': '/builds/slave/test', 09:31:56 INFO - 'SHELL': '/bin/bash', 09:31:56 INFO - 'SHLVL': '1', 09:31:56 INFO - 'TERM': 'linux', 09:31:56 INFO - 'TMOUT': '86400', 09:31:56 INFO - 'USER': 'cltbld', 09:31:56 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:56 INFO - '_': '/tools/buildbot/bin/python'} 09:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:31:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:31:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:31:58 INFO - Installing collected packages: mozsystemmonitor 09:31:58 INFO - Running setup.py install for mozsystemmonitor 09:31:58 INFO - Successfully installed mozsystemmonitor 09:31:58 INFO - Cleaning up... 09:31:58 INFO - Return code: 0 09:31:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31: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')]} 09:31:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:31:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:31:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:31:58 INFO - Using env: {'DISPLAY': ':0', 09:31:58 INFO - 'HOME': '/home/cltbld', 09:31:58 INFO - 'LANG': 'en_US.UTF-8', 09:31:58 INFO - 'LANGUAGE': 'en_US:en', 09:31:58 INFO - 'LOGNAME': 'cltbld', 09:31:58 INFO - 'MAIL': '/var/mail/cltbld', 09:31:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:58 INFO - 'MOZ_NO_REMOTE': '1', 09:31:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:58 INFO - 'NO_EM_RESTART': '1', 09:31:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:58 INFO - 'PWD': '/builds/slave/test', 09:31:58 INFO - 'SHELL': '/bin/bash', 09:31:58 INFO - 'SHLVL': '1', 09:31:58 INFO - 'TERM': 'linux', 09:31:58 INFO - 'TMOUT': '86400', 09:31:58 INFO - 'USER': 'cltbld', 09:31:58 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:31:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:58 INFO - '_': '/tools/buildbot/bin/python'} 09:31:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:59 INFO - Downloading/unpacking jsonschema==2.5.1 09:31:59 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 09:31:59 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 09:31:59 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 09:31:59 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 09:32:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:32:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:32: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 09:32: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 09:32: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 09:32: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 09:32:00 INFO - Downloading functools32-3.2.3-2.tar.gz 09:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:32:00 INFO - warning: no files found matching '*.txt' 09:32:00 INFO - no previously-included directories found matching 'build' 09:32:00 INFO - no previously-included directories found matching 'dist' 09:32:00 INFO - no previously-included directories found matching '.git*' 09:32:00 INFO - Installing collected packages: jsonschema, functools32 09:32:00 INFO - Running setup.py install for functools32 09:32:00 INFO - warning: no files found matching '*.txt' 09:32:00 INFO - no previously-included directories found matching 'build' 09:32:00 INFO - no previously-included directories found matching 'dist' 09:32:00 INFO - no previously-included directories found matching '.git*' 09:32:00 INFO - Successfully installed jsonschema functools32 09:32:00 INFO - Cleaning up... 09:32:00 INFO - Return code: 0 09:32:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:32: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')]} 09:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32: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')]} 09:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32: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', '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 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:32: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:32: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 functools32==3.2.3-2 09:32:00 INFO - Using env: {'DISPLAY': ':0', 09:32:00 INFO - 'HOME': '/home/cltbld', 09:32:00 INFO - 'LANG': 'en_US.UTF-8', 09:32:00 INFO - 'LANGUAGE': 'en_US:en', 09:32:00 INFO - 'LOGNAME': 'cltbld', 09:32:00 INFO - 'MAIL': '/var/mail/cltbld', 09:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:00 INFO - 'MOZ_NO_REMOTE': '1', 09:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:32:00 INFO - 'NO_EM_RESTART': '1', 09:32:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:00 INFO - 'PWD': '/builds/slave/test', 09:32:00 INFO - 'SHELL': '/bin/bash', 09:32:00 INFO - 'SHLVL': '1', 09:32:00 INFO - 'TERM': 'linux', 09:32:00 INFO - 'TMOUT': '86400', 09:32:00 INFO - 'USER': 'cltbld', 09:32:00 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:00 INFO - '_': '/tools/buildbot/bin/python'} 09:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:32:00 INFO - Cleaning up... 09:32:00 INFO - Return code: 0 09:32:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:32: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')]} 09:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32: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')]} 09:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32: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', '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 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:32: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 blobuploader==1.2.4 09:32:00 INFO - Using env: {'DISPLAY': ':0', 09:32:00 INFO - 'HOME': '/home/cltbld', 09:32:00 INFO - 'LANG': 'en_US.UTF-8', 09:32:00 INFO - 'LANGUAGE': 'en_US:en', 09:32:00 INFO - 'LOGNAME': 'cltbld', 09:32:00 INFO - 'MAIL': '/var/mail/cltbld', 09:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:00 INFO - 'MOZ_NO_REMOTE': '1', 09:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:32:00 INFO - 'NO_EM_RESTART': '1', 09:32:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:00 INFO - 'PWD': '/builds/slave/test', 09:32:00 INFO - 'SHELL': '/bin/bash', 09:32:00 INFO - 'SHLVL': '1', 09:32:00 INFO - 'TERM': 'linux', 09:32:00 INFO - 'TMOUT': '86400', 09:32:00 INFO - 'USER': 'cltbld', 09:32:00 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:00 INFO - '_': '/tools/buildbot/bin/python'} 09:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:00 INFO - Downloading/unpacking blobuploader==1.2.4 09:32: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 09:32: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 09:32: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 09:32: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 09:32:02 INFO - Downloading blobuploader-1.2.4.tar.gz 09:32:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:32:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:32: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 09:32: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 09:32: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 09:32: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 09:32:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:32:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:32: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 09:32: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 09:32: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 09:32: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 09:32:02 INFO - Downloading docopt-0.6.1.tar.gz 09:32:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:32:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:32:02 INFO - Running setup.py install for blobuploader 09:32:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:32:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:32:02 INFO - Running setup.py install for requests 09:32:03 INFO - Running setup.py install for docopt 09:32:03 INFO - Successfully installed blobuploader requests docopt 09:32:03 INFO - Cleaning up... 09:32:03 INFO - Return code: 0 09:32:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:03 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 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:32:03 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 09:32:03 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 09:32:03 INFO - Using env: {'DISPLAY': ':0', 09:32:03 INFO - 'HOME': '/home/cltbld', 09:32:03 INFO - 'LANG': 'en_US.UTF-8', 09:32:03 INFO - 'LANGUAGE': 'en_US:en', 09:32:03 INFO - 'LOGNAME': 'cltbld', 09:32:03 INFO - 'MAIL': '/var/mail/cltbld', 09:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:03 INFO - 'MOZ_NO_REMOTE': '1', 09:32:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:32:03 INFO - 'NO_EM_RESTART': '1', 09:32:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:03 INFO - 'PWD': '/builds/slave/test', 09:32:03 INFO - 'SHELL': '/bin/bash', 09:32:03 INFO - 'SHLVL': '1', 09:32:03 INFO - 'TERM': 'linux', 09:32:03 INFO - 'TMOUT': '86400', 09:32:03 INFO - 'USER': 'cltbld', 09:32:03 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:03 INFO - '_': '/tools/buildbot/bin/python'} 09:32:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:03 INFO - Running setup.py (path:/tmp/pip-5pPWyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:03 INFO - Running setup.py (path:/tmp/pip-Kntye8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:03 INFO - Running setup.py (path:/tmp/pip-1aF_kA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:03 INFO - Running setup.py (path:/tmp/pip-BVBEpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:03 INFO - Running setup.py (path:/tmp/pip-B502Ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:03 INFO - Running setup.py (path:/tmp/pip-5N1St3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:03 INFO - Running setup.py (path:/tmp/pip-AXwmvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:04 INFO - Running setup.py (path:/tmp/pip-9g6Bcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:04 INFO - Running setup.py (path:/tmp/pip-wIv6fs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:04 INFO - Running setup.py (path:/tmp/pip-f_khbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:04 INFO - Running setup.py (path:/tmp/pip-PwzcVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:04 INFO - Running setup.py (path:/tmp/pip-ayJkoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:04 INFO - Running setup.py (path:/tmp/pip-C57OJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:04 INFO - Running setup.py (path:/tmp/pip-6H3rxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:04 INFO - Running setup.py (path:/tmp/pip-J6R8L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:04 INFO - Running setup.py (path:/tmp/pip-hhHWDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:04 INFO - Running setup.py (path:/tmp/pip-dH9r0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:32:04 INFO - Running setup.py install for manifestparser 09:32:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:32:04 INFO - Running setup.py install for mozcrash 09:32:04 INFO - Running setup.py install for mozdebug 09:32:05 INFO - Running setup.py install for mozdevice 09:32:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:32:05 INFO - Running setup.py install for mozfile 09:32:05 INFO - Running setup.py install for mozhttpd 09:32:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:32:05 INFO - Running setup.py install for mozinfo 09:32:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:32:05 INFO - Running setup.py install for mozInstall 09:32:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:32:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:32:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:32:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:32:05 INFO - Running setup.py install for mozleak 09:32:05 INFO - Running setup.py install for mozlog 09:32:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:32:05 INFO - Running setup.py install for moznetwork 09:32:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:32:05 INFO - Running setup.py install for mozprocess 09:32:05 INFO - Running setup.py install for mozprofile 09:32:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:32:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:32:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:32:06 INFO - Running setup.py install for mozrunner 09:32:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:32:06 INFO - Running setup.py install for mozscreenshot 09:32:06 INFO - Running setup.py install for moztest 09:32:06 INFO - Running setup.py install for mozversion 09:32:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:32:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:32:06 INFO - Cleaning up... 09:32:06 INFO - Return code: 0 09:32:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:06 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 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:32:06 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 09:32:06 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 09:32:06 INFO - Using env: {'DISPLAY': ':0', 09:32:06 INFO - 'HOME': '/home/cltbld', 09:32:06 INFO - 'LANG': 'en_US.UTF-8', 09:32:06 INFO - 'LANGUAGE': 'en_US:en', 09:32:06 INFO - 'LOGNAME': 'cltbld', 09:32:06 INFO - 'MAIL': '/var/mail/cltbld', 09:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:06 INFO - 'MOZ_NO_REMOTE': '1', 09:32:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:32:06 INFO - 'NO_EM_RESTART': '1', 09:32:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:06 INFO - 'PWD': '/builds/slave/test', 09:32:06 INFO - 'SHELL': '/bin/bash', 09:32:06 INFO - 'SHLVL': '1', 09:32:06 INFO - 'TERM': 'linux', 09:32:06 INFO - 'TMOUT': '86400', 09:32:06 INFO - 'USER': 'cltbld', 09:32:06 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:06 INFO - '_': '/tools/buildbot/bin/python'} 09:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:32:06 INFO - Running setup.py (path:/tmp/pip-8o_S1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:32:06 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)) 09:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:32:06 INFO - Running setup.py (path:/tmp/pip-0TYFI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:32:06 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)) 09:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:32:06 INFO - Running setup.py (path:/tmp/pip-yYMvQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:32:06 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)) 09:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:32:06 INFO - Running setup.py (path:/tmp/pip-4b25ud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:32:07 INFO - Running setup.py (path:/tmp/pip-AkeAQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:32:07 INFO - Running setup.py (path:/tmp/pip-NE3ovl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:32:07 INFO - Running setup.py (path:/tmp/pip-eq8V2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:32:07 INFO - Running setup.py (path:/tmp/pip-0RnTxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:32:07 INFO - Running setup.py (path:/tmp/pip-W7xuIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:32:07 INFO - Running setup.py (path:/tmp/pip-eqIKL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:32:07 INFO - Running setup.py (path:/tmp/pip-pjFN8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:32:07 INFO - Running setup.py (path:/tmp/pip-10L_Wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:32:07 INFO - Running setup.py (path:/tmp/pip-w2yxwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:32:07 INFO - Running setup.py (path:/tmp/pip-PMowkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:07 INFO - Running setup.py (path:/tmp/pip-_dDqqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:32:07 INFO - Running setup.py (path:/tmp/pip-pA3yX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:32:07 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)) 09:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:32:07 INFO - Running setup.py (path:/tmp/pip-gK0EJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:32:07 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)) 09:32:07 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)) 09:32:07 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)) 09:32:07 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)) 09:32:07 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)) 09:32:07 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)) 09:32:07 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)) 09:32:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:32:07 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 09:32:07 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 09:32:07 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 09:32:07 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 09:32:09 INFO - Downloading blessings-1.6.tar.gz 09:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:32:09 INFO - Installing collected packages: blessings 09:32:09 INFO - Running setup.py install for blessings 09:32:09 INFO - Successfully installed blessings 09:32:09 INFO - Cleaning up... 09:32:09 INFO - Return code: 0 09:32:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:32:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:09 INFO - Reading from file tmpfile_stdout 09:32:09 INFO - Current package versions: 09:32:09 INFO - argparse == 1.2.1 09:32:09 INFO - blessings == 1.6 09:32:09 INFO - blobuploader == 1.2.4 09:32:09 INFO - docopt == 0.6.1 09:32:09 INFO - functools32 == 3.2.3-2 09:32:09 INFO - jsonschema == 2.5.1 09:32:09 INFO - manifestparser == 1.1 09:32:09 INFO - mozInstall == 1.13 09:32:09 INFO - mozcrash == 1.0 09:32:09 INFO - mozdebug == 0.1 09:32:09 INFO - mozdevice == 0.51 09:32:09 INFO - mozfile == 1.2 09:32:09 INFO - mozhttpd == 0.7 09:32:09 INFO - mozinfo == 0.10 09:32:09 INFO - mozleak == 0.1 09:32:09 INFO - mozlog == 3.5 09:32:09 INFO - moznetwork == 0.27 09:32:09 INFO - mozprocess == 0.25 09:32:09 INFO - mozprofile == 0.28 09:32:09 INFO - mozrunner == 6.13 09:32:09 INFO - mozscreenshot == 0.1 09:32:09 INFO - mozsystemmonitor == 0.3 09:32:09 INFO - moztest == 0.8 09:32:09 INFO - mozversion == 1.4 09:32:09 INFO - psutil == 3.1.1 09:32:09 INFO - requests == 1.2.3 09:32:09 INFO - wsgiref == 0.1.2 09:32:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:09 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 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:32:09 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 09:32:09 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 09:32:09 INFO - Using env: {'DISPLAY': ':0', 09:32:09 INFO - 'HOME': '/home/cltbld', 09:32:09 INFO - 'LANG': 'en_US.UTF-8', 09:32:09 INFO - 'LANGUAGE': 'en_US:en', 09:32:09 INFO - 'LOGNAME': 'cltbld', 09:32:09 INFO - 'MAIL': '/var/mail/cltbld', 09:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:09 INFO - 'MOZ_NO_REMOTE': '1', 09:32:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:32:09 INFO - 'NO_EM_RESTART': '1', 09:32:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:09 INFO - 'PWD': '/builds/slave/test', 09:32:09 INFO - 'SHELL': '/bin/bash', 09:32:09 INFO - 'SHLVL': '1', 09:32:09 INFO - 'TERM': 'linux', 09:32:09 INFO - 'TMOUT': '86400', 09:32:09 INFO - 'USER': 'cltbld', 09:32:09 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:09 INFO - '_': '/tools/buildbot/bin/python'} 09:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:09 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)) 09:32:09 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)) 09:32:09 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)) 09:32:09 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)) 09:32:09 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)) 09:32:09 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)) 09:32:09 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)) 09:32:09 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)) 09:32:09 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)) 09:32:09 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:32: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 09:32: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 09:32: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 09:32: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 09:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 09:32:10 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)) 09:32:10 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)) 09:32:10 INFO - Installing collected packages: simplejson 09:32:10 INFO - Running setup.py install for simplejson 09:32:10 INFO - building 'simplejson._speedups' extension 09:32:10 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 09:32:11 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 09:32:11 INFO - Successfully installed simplejson 09:32:11 INFO - Cleaning up... 09:32:11 INFO - Return code: 0 09:32:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:32:11 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')]} 09:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:32:11 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')]} 09:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:32:11 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 0x1bcf1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d97a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f3aa60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, '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': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', '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 09:32:11 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 09:32:11 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 09:32:11 INFO - Using env: {'DISPLAY': ':0', 09:32:11 INFO - 'HOME': '/home/cltbld', 09:32:11 INFO - 'LANG': 'en_US.UTF-8', 09:32:11 INFO - 'LANGUAGE': 'en_US:en', 09:32:11 INFO - 'LOGNAME': 'cltbld', 09:32:11 INFO - 'MAIL': '/var/mail/cltbld', 09:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:11 INFO - 'MOZ_NO_REMOTE': '1', 09:32:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:32:11 INFO - 'NO_EM_RESTART': '1', 09:32:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:11 INFO - 'PWD': '/builds/slave/test', 09:32:11 INFO - 'SHELL': '/bin/bash', 09:32:11 INFO - 'SHLVL': '1', 09:32:11 INFO - 'TERM': 'linux', 09:32:11 INFO - 'TMOUT': '86400', 09:32:11 INFO - 'USER': 'cltbld', 09:32:11 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:11 INFO - '_': '/tools/buildbot/bin/python'} 09:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:32:12 INFO - Cleaning up... 09:32:12 INFO - Return code: 0 09:32:12 INFO - Running post-action listener: _resource_record_post_action 09:32:12 INFO - Running post-action listener: _start_resource_monitoring 09:32:12 INFO - Starting resource monitoring. 09:32:12 INFO - [mozharness: 2017-09-15 16:32:12.174348Z] Finished create-virtualenv step (success) 09:32:12 INFO - [mozharness: 2017-09-15 16:32:12.174866Z] Running install step. 09:32:12 INFO - Running pre-action listener: _resource_record_pre_action 09:32:12 INFO - Running main action method: install 09:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:32:12 INFO - Reading from file tmpfile_stdout 09:32:12 INFO - Detecting whether we're running mozinstall >=1.0... 09:32:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:32:12 INFO - Reading from file tmpfile_stdout 09:32:12 INFO - Output received: 09:32:12 INFO - Usage: mozinstall [options] installer 09:32:12 INFO - Options: 09:32:12 INFO - -h, --help show this help message and exit 09:32:12 INFO - -d DEST, --destination=DEST 09:32:12 INFO - Directory to install application into. [default: 09:32:12 INFO - "/builds/slave/test"] 09:32:12 INFO - --app=APP Application being installed. [default: firefox] 09:32:12 INFO - mkdir: /builds/slave/test/build/application 09:32:12 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'] 09:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:32:27 INFO - Reading from file tmpfile_stdout 09:32:27 INFO - Output received: 09:32:27 INFO - /builds/slave/test/build/application/firefox/firefox 09:32:27 INFO - Running post-action listener: _resource_record_post_action 09:32:27 INFO - [mozharness: 2017-09-15 16:32:27.084543Z] Finished install step (success) 09:32:27 INFO - [mozharness: 2017-09-15 16:32:27.084739Z] Running setup-mitmproxy step. 09:32:27 INFO - Running pre-action listener: _resource_record_pre_action 09:32:27 INFO - Running main action method: setup_mitmproxy 09:32:27 INFO - Skipping: mitmproxy is not required 09:32:27 INFO - Running post-action listener: _resource_record_post_action 09:32:27 INFO - [mozharness: 2017-09-15 16:32:27.085878Z] Finished setup-mitmproxy step (success) 09:32:27 INFO - [mozharness: 2017-09-15 16:32:27.086099Z] Running run-tests step. 09:32:27 INFO - Running pre-action listener: _resource_record_pre_action 09:32:27 INFO - Running pre-action listener: _set_gcov_prefix 09:32:27 INFO - Running main action method: run_tests 09:32:27 WARNING - Try message not found. 09:32:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:32:27 INFO - Python 2.7.3 09:32:27 INFO - Return code: 0 09:32:27 INFO - grabbing minidump binary from tooltool 09:32:27 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')]} 09:32:27 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 0x1f3e090>, '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 0x1f3ed80>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f38f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:32:27 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 09:32:27 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 09:32:27 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 09:32:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:32:27 INFO - Return code: 0 09:32:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:32:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:32:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:32:27 INFO - ENV: RUST_BACKTRACE is now full 09:32:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:32:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:32:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 09:32:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 09:32:27 INFO - Using env: {'DISPLAY': ':0', 09:32:27 INFO - 'HOME': '/home/cltbld', 09:32:27 INFO - 'LANG': 'en_US.UTF-8', 09:32:27 INFO - 'LANGUAGE': 'en_US:en', 09:32:27 INFO - 'LOGNAME': 'cltbld', 09:32:27 INFO - 'MAIL': '/var/mail/cltbld', 09:32:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:32:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:32:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:27 INFO - 'MOZ_NO_REMOTE': '1', 09:32:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:32:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:32:27 INFO - 'NO_EM_RESTART': '1', 09:32:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:32:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:27 INFO - 'PWD': '/builds/slave/test', 09:32:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:32:27 INFO - 'RUST_BACKTRACE': 'full', 09:32:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:32:27 INFO - 'SHELL': '/bin/bash', 09:32:27 INFO - 'SHLVL': '1', 09:32:27 INFO - 'TERM': 'linux', 09:32:27 INFO - 'TMOUT': '86400', 09:32:27 INFO - 'USER': 'cltbld', 09:32:27 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:32:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:27 INFO - '_': '/tools/buildbot/bin/python'} 09:32:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 09:32:27 INFO - mozversion application_buildid: 20170915151657 09:32:27 INFO - mozversion application_changeset: ef07d617a48866f0ec06e357142255ca1e1e6b87 09:32:27 INFO - mozversion application_display_name: Firefox 09:32:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:32:27 INFO - mozversion application_name: Firefox 09:32:27 INFO - mozversion application_remotingname: firefox 09:32:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:32:27 INFO - mozversion application_vendor: Mozilla 09:32:27 INFO - mozversion application_version: 57.0 09:32:27 INFO - mozversion platform_buildid: 20170915151657 09:32:27 INFO - mozversion platform_changeset: ef07d617a48866f0ec06e357142255ca1e1e6b87 09:32:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:32:27 INFO - mozversion platform_version: 57.0 09:32:27 INFO - using testdate: 1505493147 09:32:27 INFO - actual date: 1505493147 09:32:27 INFO - starting webserver on 'localhost:57320' 09:32:27 INFO - SUITE-START | Running 2 tests 09:32:27 INFO - TEST-START | dromaeo_css 09:32:27 INFO - Initialising browser for dromaeo_css test... 09:32:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57320/getInfo.html -profile /tmp/tmp0kte9Y/profile 09:32:27 INFO - TEST-INFO | started process 869 (/builds/slave/test/build/application/firefox/firefox http://localhost:57320/getInfo.html) 09:32:33 INFO - TEST-INFO | 869: exit 0 09:32:34 INFO - Browser initialized. 09:32:34 INFO - Running cycle 1/1 for dromaeo_css test... 09:32:34 INFO - Using env: {'DISPLAY': ':0', 09:32:34 INFO - 'HOME': '/home/cltbld', 09:32:34 INFO - 'JSGC_DISABLE_POISONING': '1', 09:32:34 INFO - 'LANG': 'en_US.UTF-8', 09:32:34 INFO - 'LANGUAGE': 'en_US:en', 09:32:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:32:34 INFO - 'LOGNAME': 'cltbld', 09:32:34 INFO - 'MAIL': '/var/mail/cltbld', 09:32:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:32:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:32:34 INFO - 'MOZ_CRASHREPORTER': '1', 09:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:32:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:32:34 INFO - 'MOZ_NO_REMOTE': '1', 09:32:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:32:34 INFO - 'NO_EM_RESTART': '1', 09:32:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:32:34 INFO - 'PWD': '/builds/slave/test', 09:32:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:32:34 INFO - 'RUST_BACKTRACE': 'full', 09:32:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:32:34 INFO - 'SHELL': '/bin/bash', 09:32:34 INFO - 'SHLVL': '1', 09:32:34 INFO - 'TERM': 'linux', 09:32:34 INFO - 'TMOUT': '86400', 09:32:34 INFO - 'USER': 'cltbld', 09:32:34 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:32:34 INFO - '_': '/tools/buildbot/bin/python'} 09:32:34 INFO - TEST-INFO | started process 1065 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0kte9Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:45 INFO - PID 1065 | RSS: Main: 160362496 09:32:45 INFO - PID 1065 | 09:35:42 INFO - PID 1065 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57320/tests/dromaeo/cssquery-ext.html) 09:35:44 INFO - PID 1065 | RSS: Main: 166043648 09:35:44 INFO - PID 1065 | 09:38:45 INFO - PID 1065 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-ext.html (next: http://localhost:57320/tests/dromaeo/cssquery-jquery.html) 09:38:46 INFO - PID 1065 | RSS: Main: 162713600 09:38:46 INFO - PID 1065 | 09:41:47 INFO - PID 1065 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57320/tests/dromaeo/cssquery-mootools.html) 09:41:48 INFO - PID 1065 | RSS: Main: 163803136 09:41:48 INFO - PID 1065 | 09:44:50 INFO - PID 1065 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57320/tests/dromaeo/cssquery-prototype.html) 09:44:50 INFO - PID 1065 | RSS: Main: 164286464 09:44:50 INFO - PID 1065 | 09:47:53 INFO - PID 1065 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57320/tests/dromaeo/cssquery-yui.html) 09:47:54 INFO - PID 1065 | RSS: Main: 165654528 09:47:54 INFO - PID 1065 | 09:50:55 INFO - PID 1065 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-yui.html (next: http://localhost:57320/tests/dromaeo/cssquery-dojo.html) 09:50:55 INFO - PID 1065 | RSS: Main: 168271872 09:50:55 INFO - PID 1065 | 09:50:55 INFO - PID 1065 | __start_tp_report 09:50:55 INFO - PID 1065 | _x_x_mozilla_page_load 09:50:55 INFO - PID 1065 | _x_x_mozilla_page_load_details 09:50:55 INFO - PID 1065 | |i|pagename|runs| 09:50:55 INFO - PID 1065 | |0;dojo.html;4063.4311196432504;4103.36397858332;4103.974006498375;4108.527519335276;4119.50565932088;6507.011736983725;6549.738010479575;6642.667866606675;6667.566608170471;6675.098861653675;6569.8029059128185;6593.373629505201;6634.0712300278;6688.398044176023;6710.731570737177;5913.408659134074;5940.643561386309;5966.16473693681;5976.910346344808;5994.130851026599;5836.97081514594;5881.441263080001;5909.556783241253;5927.436893495111;6024.246969128859;5923.08192230205;5930.88138237235;5973.581849270553;5993.340732519419;6070.423309785563;5822.82531524055;5909.4090590941;5963.642181469082;6110.144579758838;6132.938670613304;5856.092305692582;5883.441073098049;5917.970410147965;5955.255593050869;5978.252718410199;6540.574862633914;6544.770933017369;6591.835204119888;6599.175103112111;6603.339666033358;7096.432285417206;7120.216776154619;7185.317394847481;7216.494845360827;7233.132024157135;6924.515283930127;7140.107486564179;7267.927320726831;7317.451191160684;7331.633418329116;6730.562513436611;6778.186617605918;6778.457723382117;6791.864162716767;6792.150981127359;7385.483016188815;7395.371393431618;7401.444891633149;7414.14737305206;7489.28851759082;7041.401480874183;7107.573545587281;7178.641067946582;7218.314260145278;7244.891326630105;7187.640617969185;7192.28077192287;7209.89185162223;7250.4199664026755;7275.345218930322;7098.3256590623805;7149.356557909709;7191.17301510333;7198.92801071993;7217.2421895701245;7138.535995160298;7140.250273721184;7159.284071592903;7214.206437291894;7239.022731931227;5351.25082488452;5365.731713414314;5382.757775900094;5412.7550663346865;5426.565874730012;4880.658353915192;4912.38595175603;4943.8022479101155;4973.229149481836;4992.15133427311;6689.297623749533;6706.865862682719;6735.865282694416;6792.743342481656;6833.111695479556;7016.368526832507;7121.82195445118;7216.819579510449;7218.927810721931;7300.269973002763;6760.459163266927;6769.695363708645;6809.863802723918;6820.658967051628;6825.9317406826285;6068.666223357669;6070.6357618543025;6135.631862088289;6144.354842741448;6152.969235153795;7268.843363842742;7332.96333518329;7368.226336234726;7447.88828167578;7520.699172033167;7144.67848946809;7189.424846012202;7198.136223653195;7207.423406127392;7207.459440541876;7378.778636640909;7382.667779950022;7495.587742674205;7504.774856754306;7518.774436766906;7074.398676112382;7161.104861892263;7190.06529151207;7194.244604316471;7199.640017999184;7182.569045857265;7202.927970720226;7272.945422913754;7283.453740969342;7308.1595616502655;6872.140982377203;6961.095057642474;6975.372216500539;7046.788596342118;7071.43428525727;6895.4138898194215;6926.411255043176;6935.133108361455;6939.965300173467;6960.408365288805;6887.380135787603;6908.896366554606;6973.023776671169;6976.127984002;6977.860442791014;7001.964990175016;7017;7055.400290975119;7115.679794409368;7120.608366539683;6763.695633696397;6815.386615204656;6824.5564038339335;6874.6562671867205;6912.723491060502 09:50:55 INFO - PID 1065 | |1;ext.html;971.5142428785607;1017.6845177994818;1020.6631811502202;1020.9081182693556;1028.239103063732;46475;47201.527984720145;47247.76376118123;47362.289565656545;47426.525734742645;44577.554224457745;44793.77603111988;44952.3257151143;44965.77517112418;44987.77506112465;7797.610119494031;7900.130985591581;7907.169747176521;7932.563708996003;7932.841343173133;7731.22687731228;7834.647440865147;7840.647170877296;7845.372370210398;7845.843083138334;7762.650680719381;8294.751157465256;8423.326133909302;8527.360447966395;8530.616122274481;8434.45176063557;8452.070272270106;8496.362772792065;8503.702370417006;8524.977002759646;8064.758057258292;8112.797180070486;8132.308753755879;8139.959300203461;8205.261526462587;42001.1599768006;42192.78903605462;42208.3668744969;42311.57688423138;42363.788181058895;3830.3296923038356;3857.382818749015;3874.380099184117;3875.9806200969097;3882.475865758143;34627.82686086579;34749;34759.30481390384;34871.82564087189;34911.30177396463;5445.264889239981;5610.831675049755;5677.261955945742;5698.088305871082;5718.914216286759;236308;237150;238264.99999999825;238645;238718;48884;49806.750966245665;56195;56268.03061892814;56894.43105568974;59352;59534.70232648809;59663.22694184455;59666;59673;18562.814371856108;18632.627347452973;18700.06499675011;18753.062346882874;18772.906135469235;15300.846991530165;15514;15524.146171960425;15532.068075915482;15545.37818487248;15739.13434761099;15937.20313984296;15958.281877315549;15967.760483592756;15981.28084236212;6125.571210015256;6180.168295656632;6190.473809726223;6191.318954914956;6253.96873015632;1087.7770057138268;1100.884407137255;1103.1175059952006;1112.8386383974278;1113.582406597526;26419;26425.73574264271;26522;26561.86719066431;26652.333691657477;1226.3439060102803;1232.383808095952;1237.672016915518;1243.104964425612;1253.7116463213513;13973.650658733613;14043.36804843764;14110.365033573515;14120.71758564823;14288.428462861577;297868;297961;298221;299069;300698;62692.68653656794;62784.37215627785;62947.37052629415;63280.68359658172;63402.36597634057;1538.638419971306;1541.3757428241356;1589.1418633937776;1599.8640115590306;1611.4118346803566;26582;26722.732772672804;26778.866105669345;26858.462830742887;26943.326416839736;43955.78022109997;43990.56009439864;44106;44132;44136.77931610322;261328;261727;263364;263792;264382;37694.434583481816;38926.610733892296;40078.198436030536;40324.3951340736;40330.79834600808;52491.737541312046;54114.18828717651;54346.72826635841;54382.72808635931;55220.44779552153;38512.03719907025;39261.214775704895;39290.410643839794;39381;39615.603843961195;38501.61498385092;38594;38784.61215387923;38932.41601375925;39129.2174156521;44004.77997609991;44589.108217834815;45113.0977380444;45287.77356113198;45642;35965.460518092776;36312;36802;37050;37130.44304335491;27242;27324.726752733015;27359.589606155525;27712.861435692692;27943 09:50:55 INFO - PID 1065 | |2;jquery.html;580.6283978253919;592.123656987658;595.4164918379987;596.3797650443541;606.299723818989;24039.278821635336;24908.626370604412;24949.87525062372;25034.499310013787;25058.24825255243;25297.494050118985;26058.348541286476;26429.735702642967;26770.19689409316;26785.062522811706;6558.704858281377;6599.406053455188;6625.933740662604;6637.336266373348;6637.634930078843;6463.515236357292;6559.639219842907;6722.260551339373;6944.4097251733665;6951.374376306131;6898.206706228772;6917.7578784742545;6918.861622767542;6921.065656136408;6921.826954326157;6824.976253561956;6868.347506986828;6890.552114112566;6890.896636550456;6897.241303456616;6355.364463553654;6359.014352775327;6426.93573064268;6447.4197322241225;6462.612243265419;26536.60195097075;29561.852190739122;30189.245268868235;30274.69725302763;30414.239644008856;2371.549405612945;2391.7727815857465;2406.602910519762;2407.735149133594;2409.4458274596764;20822;21085.262015829525;21114.577708445744;21150.365489035354;21205.25781597652;2506.5112303100905;2525.1793167220726;2556.399246177147;2564.666593342872;2622.1740151852296;138272;143739.28130359386;165060;171713.14143429327;172111;15714.764278535831;16043.679126417406;16248.675026499404;16463.91768041176;17032.829671703374;25955.74044259571;25982;25988.480230395286;26023.609645855326;26067.739322606532;10997.395143267197;11182.552697892155;11273.830892536618;11282.210245282915;11295.3787541686;10091.444970526545;10117.392956422638;10148.340357876714;10163.085322321027;10254.948725256476;9959.253056020827;9964.701058968243;9972.551235194434;9994.850077248848;10354.689359319233;2783.9304017399727;2899.231703598506;2910.3888183481704;2927.8828846845886;3016.1705530978716;1041.9010194031555;1045.1690905729963;1050.9842352364722;1057.6721216422934;1059.380262546419;21822.236221732164;21826.890865545887;21975.890120549615;22073.448163796034;22138.88930555337;1115.765689205276;1118.731504438945;1119.216548416112;1123.5393488669606;1125.9493322800495;11596.536138554533;12037.157398981988;12065.276083435023;12106.152569320064;12291.631251062483;600442;612311;613668;622242;622789;128842;134246.32876835554;134482;135146;135250;1116.4760102460245;1240.7704574653717;1265.6266401411606;1296.3064760353166;1309.9062282993616;22460.32619021432;22728.772712272665;22949.196778112684;22988.655170172126;23126.190583329502;60383.396166037775;65707.67146164239;66683.33316666771;67018.66490667516;69297.65351173212;82401.17598824175;83520.58239709005;87043;88040.55979720317;89686.55156724175;21976.34070977873;22109.557808844056;22208.333749987527;22226.44433889166;22251.77748222497;24676.876615616806;24827.751722483266;24890.50218995646;25184.49631007406;25278.620820687338;20232.696509552075;20558.238863164283;20628.896855515628;20630;20961.056752446482;17058.914705426392;17220.913895430444;17230.827691722923;17583.208755606283;17839;20481.59036819285;20499.8975005124;20688.896555517127;20947.266845660328;21013.789862101185;21439.571208576053;21537.892310538347;21648.24231151902;21840;21844.126234951058;20029.198832046553;20116.195352186332;20302;20435;20445.48886277855 09:50:55 INFO - PID 1065 | |3;mootools.html;1393.6655202751342;1459.489178787425;1478.48253111411;1482.1773915476906;1485.9702805943882;18232.72650910236;18786;18821.058947052643;18834.529136771587;18834.905825470854;18050.63898722025;18057.458276251702;18179.36372226972;18227.453176404728;18254.81745182548;2896.706661232439;2961.3781105967664;2974.7768917331177;2979.2849716068094;2981.6869228731;2817.1830169250893;2887.523558612837;2903.2451562593665;2924.8683809228637;2927.9853600731967;2817.4083442477104;2844.715528447149;2845.6585209774858;2849.7577705895023;2853.4863724529578;2751.7110703376156;2772.50094982903;2779.805413621047;2780.193743814312;2787.7351651593067;2719.8504082275467;2795.4269374778323;2795.6086147939304;2836.9148925532268;2851.258672745101;17009.404670836586;17208.56978575533;17250.741238881426;17309.701772367123;17377.913110434492;8882.200601945857;9144.679936202201;9150.86273705895;9156.404833685856;9159.725208243763;5992.8501787455225;6130.570860039799;6190.102435146923;6286.685665716695;6316.147320111772;2025.7872923342984;2054.969175462354;2055.1779288284656;2117.9894100529555;2138.700581918533;32454.513182302286;32801.6719832801;32847.83576082104;33073.3385332292;33086.83456582702;7411.370033547209;7633.961830190813;7642;7776.183500732454;7791.454598178073;20466.38600841977;20471.795282046987;20501.17995280202;20502.077406516742;20590.794092059186;8675.739727808175;8803.647854085764;8837.116288371111;8878.86681699775;8883.200511953955;7620.961895190489;7976.401769867283;7986.840263194704;8125.146859579777;8160.551169685606;5942.019566771434;6092.116643086816;6128.754849805958;6142.539309551801;6145.938540614537;1593.7609358596071;1691.2389424758908;1697.5586347549727;1723.758673785671;1765.622123026223;948.1940350701987;949.378157306965;959.947202903847;963.3738070254391;964.1756298364937;9047.547622618842;9428.811423771485;9468.526573671288;9695;9717.076877696607;1061.8407238914224;1065.2702898514542;1070.4540684250933;1072.14228617106;1080.8540846985557;5254.448282930313;5827.805299913508;5844.444777746107;5921.99326114553;5923.940760592425;25505.61741573878;26387.73612263891;26572.069977550695;26584.335391615365;26646.333841654116;24304.270871873872;25817.612735808976;25844;25925.74074259271;26015.73984260133;12746.49014039428;12782.23306601607;12967.027472939379;12978.415971281127;12979.28613926243;9247.815043699224;9346.299027572823;9407.341486095907;9431.669891553762;9475.431474111576;21879.781202187773;21997.340079797632;22010.88994555017;22139.1144354224;22581.43546411353;22925.312240632808;22931.88534057319;23061.19285824988;23344.066237350315;23561.29316120519;12899.484020639069;13012.479500819862;13053.151545149534;13119.540816071392;13165.210087394787;15999.920000399923;16228.432004879773;16240.26918788681;16241.918790406446;16273.349066037226;12876.291803950498;13148.34258287101;13276.269805160422;13278.734425311246;13347.66630834198;12617.927476164628;12680.6829829255;13176.879965203065;13184;13309.068365214342;10175.084242418217;10254.333468324536;10331.535080921227;10375.169986400919;10388.324758890649;7895.407844411807;8237.217464340878;8244.258016778518;8259.752207433787;8274.834503310021;8111.9594402027615;8254.587270636324;8271.793205169919;8272.214139656722;8325.916740832756 09:50:55 INFO - PID 1065 | |4;prototype.html;546.5327145290775;551.7710150287628;578.4215784215784;580.8983427900116;585.2538014032109;6374.3944325289085;6421.325760795114;6495.707693153801;6498.187726534184;6536.150300460945;6350.460210882069;6356.396142366468;6382.840428989278;6399.3600639935985;6479.1253180820695;5062.822801201959;5128.5640720538795;5192.844214673566;5228.61441717945;5264.947350526503;5118.206677964921;5165.74171291434;5198.402183748861;5223.242629818674;5226.55574276187;5145.511176438251;5147.0992576298995;5148.665336753118;5160.096983027974;5192.584593232551;5159.613029022819;5199.298094757235;5206.661566998133;5234.319538459995;5239.869003274948;4682.461516925563;4715.882102947419;4740.857774266743;4749.168895443284;4751.429828420577;6291.496680265614;6362.713677884506;6367.204099487564;6398.9040164397575;6401.935980640181;6079.726412311456;6120.234970628671;6122.816315510542;6127.908081378739;6134.141220229171;6918.238993710688;6940.479464040217;6948.44412447003;6956.65216739161;6975.058367120425;5203.089459344599;5211.7394130293715;5249.685018898878;5254.238135470336;5256.4768910922585;7074.610896400644;7180.712771489134;7181.533200341961;7224.241454647239;7281.745138920165;5384.91922621161;5432.29380180574;5434.310016541837;5449.509544141047;5464.972675136599;5456.563474921997;5488.451154884559;5509.118541033416;5520.420355862657;5541.8085111701175;5798.594098413155;6054.122152287893;6064.181335519736;6112.358202388685;6122.163350994684;6594.274629790719;6790;6816.591004539743;6886.758963436256;6965.825854353681;1853.1290293562004;1876.7935527092009;1880.7084901840237;1889.3198448558508;1894.7726272847071;1309.306067784076;1310.0329472304218;1315.5395611536078;1345.3385069439234;1353.7766268565774;6480.157579514727;6518;6534.7386104556235;6553.2136143663065;6612.239592446834;6966.9303306967295;6971.163460384786;6978.5812851228075;7015.193252775996;7019.403350715246;6449.5485316027425;6483.773067942694;6487.870242595121;6492.285848556655;6498.317676643885;3638.6173254163778;3677.79772112531;3678.6137455567314;3693.372126738407;3713;7250.891236631454;7274.709011639581;7303.6348182590655;7311.574243022559;7368.489459660756;7090.822729431806;7194.136703595602;7197.352238298578;7227.349538541556;7249.347558719741;7217.133943926762;7332.046833911558;7384.630768461556;7396;7402.9629851850395;6924.372772398583;6949.7567585134275;6970.407515361048;7000.529888723425;7035.6430595751;6803.217629972616;6859.519833611599;6881.174259088742;6894.413974812197;6932.272111428228;7191;7212.278772122956;7213.422926165789;7216.494845360774;7224.855502890018;5315.840524784263;5370.731463426891;5389.811356602657;5425.755840987086;5431.646942948696;7002.509824312249;7004.824879378057;7023.262557431396;7025.121859767529;7061.046758687511;6751.088603038527;6776.390124888783;6793.354631310018;6840.657967101724;6878.759243426656;6687.832804179934;6712.630805305755;6743.426808721314;6757;6774.3225677431865;6363.77726779579;6605.438537724155;6727.73089076451;6769.966150169218;6778.186617605918;6796.791042910975;6840.144981877265;6845.486588505982;6861.485388595976;6862.897056544056;6419.871602568016;6498.155239818793;6547;6613.702383392662;6619.801405957829 09:50:55 INFO - PID 1065 | |5;yui.html;993.9055789699976;1009.8182327181108;1024.354656566363;1030.124394264875;1032.9225308101895;20810.6878396824;21192.576148477023;21277.468063298424;21316.786832131693;21333;19723;20402;20500.794992050076;20513.897430512865;20524.179032838667;1026.1944373666672;1069.6737495064003;1074.248026381532;1078.8381742738572;1078.9028987391134;1080.7028067281522;1085.1210519479248;1103.5806393570433;1127.0476447401932;1135.4436326200143;1173.606841708029;1174.1252766688829;1175.0482109491297;1175.2948231061337;1177.2289150606323;1162.767446510697;1168.0422053915793;1169.4152923538231;1169.4854264123758;1175.7060734816296;852.6589364254286;857.185673610073;859.4198915731856;860.3461369359331;861.3410740783306;21816.345509634735;21876.45308867275;22183.778162218332;22194.11223551056;22323;23343.182988595487;23417.180398685963;23499;23693.407664808346;23757.287281381414;19509.317173898846;19687.507812304662;19926;19928.501787455283;19950.70073948892;3953.169834334793;4001.659858911996;4013.13717550728;4018.578049304839;4022.3966405039187;159199;161992;164298.17850910788;164889;165245.17377413035;17909.462716118538;18098;18257.269709211483;18391.172397242157;18405.355812546495;35787;36060.27879442449;36127.63872361295;36149.45775813365;36278.637213628055;9554.952225238969;9633.229341652652;9647.228221742243;9723.611055557698;9744.415335079917;4972.8508144755715;5030.597552195815;5033.421156566968;5093.261477085802;5097.413797413271;6379.330170332141;6448.613083215022;6459.580127291805;6538.640374779337;6548.083268342436;1199.4899179086697;1222.8349172861729;1232.353014667289;1234.160770675931;1241.3110723548336;734.7465124532027;745.7203548669249;750.4409215134759;769.2499812682589;769.6421164158693;13122.803157952447;13252.20486770797;13549;14152.929235353757;14177;735.3418690272176;735.8344372516248;749.4341771962243;757.6628400361786;763.3435245688703;6080.7567697291615;6131.233595800525;6173.506119510428;6188.8762224755255;6223.097650840603;163125;168443;169016.15491922462;169199;169865;138260.30869845787;141439;142702;144410;150345;126703.36648316884;127875;128289;128780.35609822079;129793.35103324235;11687.18189726711;11713.590024349107;11733.823992639946;12051.51793928233;12198.634040978784;59531.02344882652;59787.40212597993;59931;60392.396076038676;60499.39500604937;74258.62870685612;75463;76021.61989190019;76961.61519192369;78524.21475785397;17193.14034298305;17385.82614173842;17767.73348399801;18038.819611803712;18174.27302907869;18009.72985405246;18623.627527449647;18742.625147497245;18855.622887542446;18889.772164809245;17889.19498622591;18381.356652517097;18432.447026589223;18484.260629574666;18495.352662656744;17717.291308348038;17949;18075.638487230444;18100.81899181044;18161.27354905842;16462.176891155155;16485.83514164891;16644.916775416044;16770.496885093467;16784.496465106065;13370.395552533759;13482.865171348554;13560.45758169662;13640.249786261851;13718.382672779948;13211.207327560378;13330;13524.79712804289;13538.72922541524;13562.59312220635 09:50:55 INFO - PID 1065 | __end_tp_report 09:50:55 INFO - PID 1065 | __start_cc_report 09:50:55 INFO - PID 1065 | _x_x_mozilla_cycle_collect,312 09:50:55 INFO - PID 1065 | __end_cc_report 09:50:55 INFO - PID 1065 | __startTimestamp1505494255876__endTimestamp 09:50:55 INFO - PID 1065 | 09:50:55 INFO - PID 1065 | ------- Summary: start ------- 09:50:55 INFO - PID 1065 | Number of tests: 6 09:50:55 INFO - PID 1065 | 09:50:55 INFO - PID 1065 | [#0] dojo.html Cycles:165 Average:6646.84 Median:6908.90 stddev:762.74 (11.0%) stddev-sans-first:737.66 09:50:55 INFO - PID 1065 | Values: 4063.4 4103.4 4104.0 4108.5 4119.5 6507.0 6549.7 6642.7 6667.6 6675.1 6569.8 6593.4 6634.1 6688.4 6710.7 5913.4 5940.6 5966.2 5976.9 5994.1 5837.0 5881.4 5909.6 5927.4 6024.2 5923.1 5930.9 5973.6 5993.3 6070.4 5822.8 5909.4 5963.6 6110.1 6132.9 5856.1 5883.4 5918.0 5955.3 5978.3 6540.6 6544.8 6591.8 6599.2 6603.3 7096.4 7120.2 7185.3 7216.5 7233.1 6924.5 7140.1 7267.9 7317.5 7331.6 6730.6 6778.2 6778.5 6791.9 6792.2 7385.5 7395.4 7401.4 7414.1 7489.3 7041.4 7107.6 7178.6 7218.3 7244.9 7187.6 7192.3 7209.9 7250.4 7275.3 7098.3 7149.4 7191.2 7198.9 7217.2 7138.5 7140.3 7159.3 7214.2 7239.0 5351.3 5365.7 5382.8 5412.8 5426.6 4880.7 4912.4 4943.8 4973.2 4992.2 6689.3 6706.9 6735.9 6792.7 6833.1 7016.4 7121.8 7216.8 7218.9 7300.3 6760.5 6769.7 6809.9 6820.7 6825.9 6068.7 6070.6 6135.6 6144.4 6153.0 7268.8 7333.0 7368.2 7447.9 7520.7 7144.7 7189.4 7198.1 7207.4 7207.5 7378.8 7382.7 7495.6 7504.8 7518.8 7074.4 7161.1 7190.1 7194.2 7199.6 7182.6 7202.9 7272.9 7283.5 7308.2 6872.1 6961.1 6975.4 7046.8 7071.4 6895.4 6926.4 6935.1 6940.0 6960.4 6887.4 6908.9 6973.0 6976.1 6977.9 7002.0 7017.0 7055.4 7115.7 7120.6 6763.7 6815.4 6824.6 6874.7 6912.7 09:50:55 INFO - PID 1065 | 09:50:55 INFO - PID 1065 | [#1] ext.html Cycles:180 Average:45782.79 Median:39030.82 stddev:69635.27 (178.4%) stddev-sans-first:69749.33 09:50:55 INFO - PID 1065 | Values: 971.5 1017.7 1020.7 1020.9 1028.2 46475.0 47201.5 47247.8 47362.3 47426.5 44577.6 44793.8 44952.3 44965.8 44987.8 7797.6 7900.1 7907.2 7932.6 7932.8 7731.2 7834.6 7840.6 7845.4 7845.8 7762.7 8294.8 8423.3 8527.4 8530.6 8434.5 8452.1 8496.4 8503.7 8525.0 8064.8 8112.8 8132.3 8140.0 8205.3 42001.2 42192.8 42208.4 42311.6 42363.8 3830.3 3857.4 3874.4 3876.0 3882.5 34627.8 34749.0 34759.3 34871.8 34911.3 5445.3 5610.8 5677.3 5698.1 5718.9 236308.0 237150.0 238265.0 238645.0 238718.0 48884.0 49806.8 56195.0 56268.0 56894.4 59352.0 59534.7 59663.2 59666.0 59673.0 18562.8 18632.6 18700.1 18753.1 18772.9 15300.8 15514.0 15524.1 15532.1 15545.4 15739.1 15937.2 15958.3 15967.8 15981.3 6125.6 6180.2 6190.5 6191.3 6254.0 1087.8 1100.9 1103.1 1112.8 1113.6 26419.0 26425.7 26522.0 26561.9 26652.3 1226.3 1232.4 1237.7 1243.1 1253.7 13973.7 14043.4 14110.4 14120.7 14288.4 297868.0 297961.0 298221.0 299069.0 300698.0 62692.7 62784.4 62947.4 63280.7 63402.4 1538.6 1541.4 1589.1 1599.9 1611.4 26582.0 26722.7 26778.9 26858.5 26943.3 43955.8 43990.6 44106.0 44132.0 44136.8 261328.0 261727.0 263364.0 263792.0 264382.0 37694.4 38926.6 40078.2 40324.4 40330.8 52491.7 54114.2 54346.7 54382.7 55220.4 38512.0 39261.2 39290.4 39381.0 39615.6 38501.6 38594.0 38784.6 38932.4 39129.2 44004.8 44589.1 45113.1 45287.8 45642.0 35965.5 36312.0 36802.0 37050.0 37130.4 27242.0 27324.7 27359.6 27712.9 27943.0 09:50:55 INFO - PID 1065 | 09:50:55 INFO - PID 1065 | [#2] jquery.html Cycles:180 Average:41202.48 Median:12748.87 stddev:102971.17 (807.7%) stddev-sans-first:103214.86 09:50:55 INFO - PID 1065 | Values: 580.6 592.1 595.4 596.4 606.3 24039.3 24908.6 24949.9 25034.5 25058.2 25297.5 26058.3 26429.7 26770.2 26785.1 6558.7 6599.4 6625.9 6637.3 6637.6 6463.5 6559.6 6722.3 6944.4 6951.4 6898.2 6917.8 6918.9 6921.1 6921.8 6825.0 6868.3 6890.6 6890.9 6897.2 6355.4 6359.0 6426.9 6447.4 6462.6 26536.6 29561.9 30189.2 30274.7 30414.2 2371.5 2391.8 2406.6 2407.7 2409.4 20822.0 21085.3 21114.6 21150.4 21205.3 2506.5 2525.2 2556.4 2564.7 2622.2 138272.0 143739.3 165060.0 171713.1 172111.0 15714.8 16043.7 16248.7 16463.9 17032.8 25955.7 25982.0 25988.5 26023.6 26067.7 10997.4 11182.6 11273.8 11282.2 11295.4 10091.4 10117.4 10148.3 10163.1 10254.9 9959.3 9964.7 9972.6 9994.9 10354.7 2783.9 2899.2 2910.4 2927.9 3016.2 1041.9 1045.2 1051.0 1057.7 1059.4 21822.2 21826.9 21975.9 22073.4 22138.9 1115.8 1118.7 1119.2 1123.5 1125.9 11596.5 12037.2 12065.3 12106.2 12291.6 600442.0 612311.0 613668.0 622242.0 622789.0 128842.0 134246.3 134482.0 135146.0 135250.0 1116.5 1240.8 1265.6 1296.3 1309.9 22460.3 22728.8 22949.2 22988.7 23126.2 60383.4 65707.7 66683.3 67018.7 69297.7 82401.2 83520.6 87043.0 88040.6 89686.6 21976.3 22109.6 22208.3 22226.4 22251.8 24676.9 24827.8 24890.5 25184.5 25278.6 20232.7 20558.2 20628.9 20630.0 20961.1 17058.9 17220.9 17230.8 17583.2 17839.0 20481.6 20499.9 20688.9 20947.3 21013.8 21439.6 21537.9 21648.2 21840.0 21844.1 20029.2 20116.2 20302.0 20435.0 20445.5 09:50:55 INFO - PID 1065 | 09:50:55 INFO - PID 1065 | [#3] mootools.html Cycles:180 Average:10879.24 Median:2735.78 stddev:8177.74 (298.9%) stddev-sans-first:8169.63 09:50:55 INFO - PID 1065 | Values: 1393.7 1459.5 1478.5 1482.2 1486.0 18232.7 18786.0 18821.1 18834.5 18834.9 18050.6 18057.5 18179.4 18227.5 18254.8 2896.7 2961.4 2974.8 2979.3 2981.7 2817.2 2887.5 2903.2 2924.9 2928.0 2817.4 2844.7 2845.7 2849.8 2853.5 2751.7 2772.5 2779.8 2780.2 2787.7 2719.9 2795.4 2795.6 2836.9 2851.3 17009.4 17208.6 17250.7 17309.7 17377.9 8882.2 9144.7 9150.9 9156.4 9159.7 5992.9 6130.6 6190.1 6286.7 6316.1 2025.8 2055.0 2055.2 2118.0 2138.7 32454.5 32801.7 32847.8 33073.3 33086.8 7411.4 7634.0 7642.0 7776.2 7791.5 20466.4 20471.8 20501.2 20502.1 20590.8 8675.7 8803.6 8837.1 8878.9 8883.2 7621.0 7976.4 7986.8 8125.1 8160.6 5942.0 6092.1 6128.8 6142.5 6145.9 1593.8 1691.2 1697.6 1723.8 1765.6 948.2 949.4 959.9 963.4 964.2 9047.5 9428.8 9468.5 9695.0 9717.1 1061.8 1065.3 1070.5 1072.1 1080.9 5254.4 5827.8 5844.4 5922.0 5923.9 25505.6 26387.7 26572.1 26584.3 26646.3 24304.3 25817.6 25844.0 25925.7 26015.7 12746.5 12782.2 12967.0 12978.4 12979.3 9247.8 9346.3 9407.3 9431.7 9475.4 21879.8 21997.3 22010.9 22139.1 22581.4 22925.3 22931.9 23061.2 23344.1 23561.3 12899.5 13012.5 13053.2 13119.5 13165.2 15999.9 16228.4 16240.3 16241.9 16273.3 12876.3 13148.3 13276.3 13278.7 13347.7 12617.9 12680.7 13176.9 13184.0 13309.1 10175.1 10254.3 10331.5 10375.2 10388.3 7895.4 8237.2 8244.3 8259.8 8274.8 8112.0 8254.6 8271.8 8272.2 8325.9 09:50:55 INFO - PID 1065 | 09:50:55 INFO - PID 1065 | [#4] prototype.html Cycles:180 Average:5841.61 Median:6481.97 stddev:1633.24 (25.2%) stddev-sans-first:1588.73 09:50:55 INFO - PID 1065 | Values: 546.5 551.8 578.4 580.9 585.3 6374.4 6421.3 6495.7 6498.2 6536.2 6350.5 6356.4 6382.8 6399.4 6479.1 5062.8 5128.6 5192.8 5228.6 5264.9 5118.2 5165.7 5198.4 5223.2 5226.6 5145.5 5147.1 5148.7 5160.1 5192.6 5159.6 5199.3 5206.7 5234.3 5239.9 4682.5 4715.9 4740.9 4749.2 4751.4 6291.5 6362.7 6367.2 6398.9 6401.9 6079.7 6120.2 6122.8 6127.9 6134.1 6918.2 6940.5 6948.4 6956.7 6975.1 5203.1 5211.7 5249.7 5254.2 5256.5 7074.6 7180.7 7181.5 7224.2 7281.7 5384.9 5432.3 5434.3 5449.5 5465.0 5456.6 5488.5 5509.1 5520.4 5541.8 5798.6 6054.1 6064.2 6112.4 6122.2 6594.3 6790.0 6816.6 6886.8 6965.8 1853.1 1876.8 1880.7 1889.3 1894.8 1309.3 1310.0 1315.5 1345.3 1353.8 6480.2 6518.0 6534.7 6553.2 6612.2 6966.9 6971.2 6978.6 7015.2 7019.4 6449.5 6483.8 6487.9 6492.3 6498.3 3638.6 3677.8 3678.6 3693.4 3713.0 7250.9 7274.7 7303.6 7311.6 7368.5 7090.8 7194.1 7197.4 7227.3 7249.3 7217.1 7332.0 7384.6 7396.0 7403.0 6924.4 6949.8 6970.4 7000.5 7035.6 6803.2 6859.5 6881.2 6894.4 6932.3 7191.0 7212.3 7213.4 7216.5 7224.9 5315.8 5370.7 5389.8 5425.8 5431.6 7002.5 7004.8 7023.3 7025.1 7061.0 6751.1 6776.4 6793.4 6840.7 6878.8 6687.8 6712.6 6743.4 6757.0 6774.3 6363.8 6605.4 6727.7 6770.0 6778.2 6796.8 6840.1 6845.5 6861.5 6862.9 6419.9 6498.2 6547.0 6613.7 6619.8 09:50:55 INFO - PID 1065 | 09:50:55 INFO - PID 1065 | [#5] yui.html Cycles:180 Average:30071.19 Median:18398.26 stddev:45824.91 (249.1%) stddev-sans-first:45901.45 09:50:55 INFO - PID 1065 | Values: 993.9 1009.8 1024.4 1030.1 1032.9 20810.7 21192.6 21277.5 21316.8 21333.0 19723.0 20402.0 20500.8 20513.9 20524.2 1026.2 1069.7 1074.2 1078.8 1078.9 1080.7 1085.1 1103.6 1127.0 1135.4 1173.6 1174.1 1175.0 1175.3 1177.2 1162.8 1168.0 1169.4 1169.5 1175.7 852.7 857.2 859.4 860.3 861.3 21816.3 21876.5 22183.8 22194.1 22323.0 23343.2 23417.2 23499.0 23693.4 23757.3 19509.3 19687.5 19926.0 19928.5 19950.7 3953.2 4001.7 4013.1 4018.6 4022.4 159199.0 161992.0 164298.2 164889.0 165245.2 17909.5 18098.0 18257.3 18391.2 18405.4 35787.0 36060.3 36127.6 36149.5 36278.6 9555.0 9633.2 9647.2 9723.6 9744.4 4972.9 5030.6 5033.4 5093.3 5097.4 6379.3 6448.6 6459.6 6538.6 6548.1 1199.5 1222.8 1232.4 1234.2 1241.3 734.7 745.7 750.4 769.2 769.6 13122.8 13252.2 13549.0 14152.9 14177.0 735.3 735.8 749.4 757.7 763.3 6080.8 6131.2 6173.5 6188.9 6223.1 163125.0 168443.0 169016.2 169199.0 169865.0 138260.3 141439.0 142702.0 144410.0 150345.0 126703.4 127875.0 128289.0 128780.4 129793.4 11687.2 11713.6 11733.8 12051.5 12198.6 59531.0 59787.4 59931.0 60392.4 60499.4 74258.6 75463.0 76021.6 76961.6 78524.2 17193.1 17385.8 17767.7 18038.8 18174.3 18009.7 18623.6 18742.6 18855.6 18889.8 17889.2 18381.4 18432.4 18484.3 18495.4 17717.3 17949.0 18075.6 18100.8 18161.3 16462.2 16485.8 16644.9 16770.5 16784.5 13370.4 13482.9 13560.5 13640.2 13718.4 13211.2 13330.0 13524.8 13538.7 13562.6 09:50:55 INFO - PID 1065 | -------- Summary: end -------- 09:50:55 INFO - PID 1065 | 09:50:56 INFO - TEST-INFO | 1065: exit 0 09:50:56 INFO - TEST-OK | dromaeo_css | took 1109147ms 09:50:56 INFO - TEST-START | kraken 09:50:56 INFO - Initialising browser for kraken test... 09:50:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57320/getInfo.html -profile /tmp/tmpCYqGcD/profile 09:50:56 INFO - TEST-INFO | started process 1511 (/builds/slave/test/build/application/firefox/firefox http://localhost:57320/getInfo.html) 09:51:02 INFO - TEST-INFO | 1511: exit 0 09:51:02 INFO - Browser initialized. 09:51:02 INFO - Running cycle 1/1 for kraken test... 09:51:02 INFO - Using env: {'DISPLAY': ':0', 09:51:02 INFO - 'HOME': '/home/cltbld', 09:51:02 INFO - 'JSGC_DISABLE_POISONING': '1', 09:51:02 INFO - 'LANG': 'en_US.UTF-8', 09:51:02 INFO - 'LANGUAGE': 'en_US:en', 09:51:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 09:51:02 INFO - 'LOGNAME': 'cltbld', 09:51:02 INFO - 'MAIL': '/var/mail/cltbld', 09:51:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:51:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:51:02 INFO - 'MOZ_CRASHREPORTER': '1', 09:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 09:51:02 INFO - 'MOZ_NO_REMOTE': '1', 09:51:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:51:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:51:02 INFO - 'NO_EM_RESTART': '1', 09:51:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:02 INFO - 'PWD': '/builds/slave/test', 09:51:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:51:02 INFO - 'RUST_BACKTRACE': 'full', 09:51:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:51:02 INFO - 'SHELL': '/bin/bash', 09:51:02 INFO - 'SHLVL': '1', 09:51:02 INFO - 'TERM': 'linux', 09:51:02 INFO - 'TMOUT': '86400', 09:51:02 INFO - 'USER': 'cltbld', 09:51:02 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614', 09:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:02 INFO - '_': '/tools/buildbot/bin/python'} 09:51:02 INFO - TEST-INFO | started process 1702 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCYqGcD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 09:51:14 INFO - PID 1702 | RSS: Main: 160215040 09:51:14 INFO - PID 1702 | 09:51:16 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57320/tests/kraken/driver.html?testName=audio-beat-detection) 09:51:17 INFO - PID 1702 | RSS: Main: 165892096 09:51:17 INFO - PID 1702 | 09:51:20 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57320/tests/kraken/driver.html?testName=audio-dft) 09:51:20 INFO - PID 1702 | RSS: Main: 165302272 09:51:20 INFO - PID 1702 | 09:51:23 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57320/tests/kraken/driver.html?testName=audio-fft) 09:51:24 INFO - PID 1702 | RSS: Main: 167686144 09:51:24 INFO - PID 1702 | 09:51:26 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57320/tests/kraken/driver.html?testName=audio-oscillator) 09:51:26 INFO - PID 1702 | RSS: Main: 168058880 09:51:26 INFO - PID 1702 | 09:51:28 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57320/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:51:29 INFO - PID 1702 | RSS: Main: 167497728 09:51:29 INFO - PID 1702 | 09:51:32 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57320/tests/kraken/driver.html?testName=imaging-darkroom) 09:51:33 INFO - PID 1702 | RSS: Main: 168099840 09:51:33 INFO - PID 1702 | 09:51:36 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57320/tests/kraken/driver.html?testName=imaging-desaturate) 09:51:37 INFO - PID 1702 | RSS: Main: 168624128 09:51:37 INFO - PID 1702 | 09:51:40 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57320/tests/kraken/driver.html?testName=json-parse-financial) 09:51:40 INFO - PID 1702 | RSS: Main: 170053632 09:51:40 INFO - PID 1702 | 09:51:42 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57320/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:51:42 INFO - PID 1702 | RSS: Main: 170360832 09:51:42 INFO - PID 1702 | 09:51:44 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:51:45 INFO - PID 1702 | RSS: Main: 170668032 09:51:45 INFO - PID 1702 | 09:51:47 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:51:47 INFO - PID 1702 | RSS: Main: 167612416 09:51:47 INFO - PID 1702 | 09:51:50 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:51:50 INFO - PID 1702 | RSS: Main: 167256064 09:51:50 INFO - PID 1702 | 09:51:53 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:51:54 INFO - PID 1702 | RSS: Main: 167686144 09:51:54 INFO - PID 1702 | 09:51:55 INFO - PID 1702 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57320/tests/kraken/driver.html?testName=ai-astar) 09:51:56 INFO - PID 1702 | RSS: Main: 169660416 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | __start_tp_report 09:51:56 INFO - PID 1702 | _x_x_mozilla_page_load 09:51:56 INFO - PID 1702 | _x_x_mozilla_page_load_details 09:51:56 INFO - PID 1702 | |i|pagename|runs| 09:51:56 INFO - PID 1702 | |0;ai-astar;105;105;105;106;105;106;108;106;105;106 09:51:56 INFO - PID 1702 | |1;audio-beat-detection;113;113;111;110;112;109;111;114;139;111 09:51:56 INFO - PID 1702 | |2;audio-dft;160;162;164;162;166;163;162;161;161;160 09:51:56 INFO - PID 1702 | |3;audio-fft;75;72;72;72;74;73;73;72;74;73 09:51:56 INFO - PID 1702 | |4;audio-oscillator;77;78;78;76;78;78;77;82;78;77 09:51:56 INFO - PID 1702 | |5;imaging-gaussian-blur;113;114;116;113;114;113;113;114;114;113 09:51:56 INFO - PID 1702 | |6;imaging-darkroom;149;148;147;148;148;148;148;149;149;149 09:51:56 INFO - PID 1702 | |7;imaging-desaturate;94;94;94;94;94;95;96;94;95;95 09:51:56 INFO - PID 1702 | |8;json-parse-financial;46;45;48;42;46;42;44;43;45;42 09:51:56 INFO - PID 1702 | |9;json-stringify-tinderbox;39;38;39;38;38;42;39;38;38;39 09:51:56 INFO - PID 1702 | |10;stanford-crypto-aes;72;69;70;69;70;70;71;73;69;72 09:51:56 INFO - PID 1702 | |11;stanford-crypto-ccm;104;102;103;108;102;104;101;103;103;101 09:51:56 INFO - PID 1702 | |12;stanford-crypto-pbkdf2;159;160;148;155;152;147;148;148;147;158 09:51:56 INFO - PID 1702 | |13;stanford-crypto-sha256-iterative;59;58;62;61;61;57;59;58;58;66 09:51:56 INFO - PID 1702 | __end_tp_report 09:51:56 INFO - PID 1702 | __start_cc_report 09:51:56 INFO - PID 1702 | _x_x_mozilla_cycle_collect,852 09:51:56 INFO - PID 1702 | __end_cc_report 09:51:56 INFO - PID 1702 | __startTimestamp1505494316087__endTimestamp 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | ------- Summary: start ------- 09:51:56 INFO - PID 1702 | Number of tests: 14 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#0] ai-astar Cycles:10 Average:105.70 Median:106.00 stddev:0.95 (0.9%) stddev-sans-first:0.97 09:51:56 INFO - PID 1702 | Values: 105.0 105.0 105.0 106.0 105.0 106.0 108.0 106.0 105.0 106.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#1] audio-beat-detection Cycles:10 Average:114.30 Median:112.50 stddev:8.81 (7.8%) stddev-sans-first:9.33 09:51:56 INFO - PID 1702 | Values: 113.0 113.0 111.0 110.0 112.0 109.0 111.0 114.0 139.0 111.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:1.85 (1.1%) stddev-sans-first:1.80 09:51:56 INFO - PID 1702 | Values: 160.0 162.0 164.0 162.0 166.0 163.0 162.0 161.0 161.0 160.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#3] audio-fft Cycles:10 Average:73.00 Median:73.00 stddev:1.05 (1.4%) stddev-sans-first:0.83 09:51:56 INFO - PID 1702 | Values: 75.0 72.0 72.0 72.0 74.0 73.0 73.0 72.0 74.0 73.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:78.00 stddev:1.60 (2.0%) stddev-sans-first:1.66 09:51:56 INFO - PID 1702 | Values: 77.0 78.0 78.0 76.0 78.0 78.0 77.0 82.0 78.0 77.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#5] imaging-gaussian-blur Cycles:10 Average:113.70 Median:114.00 stddev:0.95 (0.8%) stddev-sans-first:0.97 09:51:56 INFO - PID 1702 | Values: 113.0 114.0 116.0 113.0 114.0 113.0 113.0 114.0 114.0 113.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 09:51:56 INFO - PID 1702 | Values: 149.0 148.0 147.0 148.0 148.0 148.0 148.0 149.0 149.0 149.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 09:51:56 INFO - PID 1702 | Values: 94.0 94.0 94.0 94.0 94.0 95.0 96.0 94.0 95.0 95.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#8] json-parse-financial Cycles:10 Average:44.30 Median:45.00 stddev:2.06 (4.6%) stddev-sans-first:2.09 09:51:56 INFO - PID 1702 | Values: 46.0 45.0 48.0 42.0 46.0 42.0 44.0 43.0 45.0 42.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#9] json-stringify-tinderbox Cycles:10 Average:38.80 Median:39.00 stddev:1.23 (3.2%) stddev-sans-first:1.30 09:51:56 INFO - PID 1702 | Values: 39.0 38.0 39.0 38.0 38.0 42.0 39.0 38.0 38.0 39.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#10] stanford-crypto-aes Cycles:10 Average:70.50 Median:70.50 stddev:1.43 (2.0%) stddev-sans-first:1.41 09:51:56 INFO - PID 1702 | Values: 72.0 69.0 70.0 69.0 70.0 70.0 71.0 73.0 69.0 72.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#11] stanford-crypto-ccm Cycles:10 Average:103.10 Median:103.00 stddev:2.02 (2.0%) stddev-sans-first:2.12 09:51:56 INFO - PID 1702 | Values: 104.0 102.0 103.0 108.0 102.0 104.0 101.0 103.0 103.0 101.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.20 Median:153.50 stddev:5.33 (3.5%) stddev-sans-first:5.05 09:51:56 INFO - PID 1702 | Values: 159.0 160.0 148.0 155.0 152.0 147.0 148.0 148.0 147.0 158.0 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:59.90 Median:60.00 stddev:2.69 (4.5%) stddev-sans-first:2.83 09:51:56 INFO - PID 1702 | Values: 59.0 58.0 62.0 61.0 61.0 57.0 59.0 58.0 58.0 66.0 09:51:56 INFO - PID 1702 | -------- Summary: end -------- 09:51:56 INFO - PID 1702 | 09:51:56 INFO - PID 1702 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 09:51:56 INFO - TEST-INFO | 1702: exit 0 09:51:56 INFO - TEST-OK | kraken | took 60281ms 09:51:56 INFO - SUITE-END | took 1169s 09:51:57 INFO - Completed test suite (00:19:29) 09:51:57 INFO - javascript benchmark 09:51:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9471.888892497096, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [4063.4311196432504, 4103.36397858332, 4103.974006498375, 4108.527519335276, 4119.50565932088, 6507.011736983725, 6549.738010479575, 6642.667866606675, 6667.566608170471, 6675.098861653675, 6569.8029059128185, 6593.373629505201, 6634.0712300278, 6688.398044176023, 6710.731570737177, 5913.408659134074, 5940.643561386309, 5966.16473693681, 5976.910346344808, 5994.130851026599, 5836.97081514594, 5881.441263080001, 5909.556783241253, 5927.436893495111, 6024.246969128859, 5923.08192230205, 5930.88138237235, 5973.581849270553, 5993.340732519419, 6070.423309785563, 5822.82531524055, 5909.4090590941, 5963.642181469082, 6110.144579758838, 6132.938670613304, 5856.092305692582, 5883.441073098049, 5917.970410147965, 5955.255593050869, 5978.252718410199, 6540.574862633914, 6544.770933017369, 6591.835204119888, 6599.175103112111, 6603.339666033358, 7096.432285417206, 7120.216776154619, 7185.317394847481, 7216.494845360827, 7233.132024157135, 6924.515283930127, 7140.107486564179, 7267.927320726831, 7317.451191160684, 7331.633418329116, 6730.562513436611, 6778.186617605918, 6778.457723382117, 6791.864162716767, 6792.150981127359, 7385.483016188815, 7395.371393431618, 7401.444891633149, 7414.14737305206, 7489.28851759082, 7041.401480874183, 7107.573545587281, 7178.641067946582, 7218.314260145278, 7244.891326630105, 7187.640617969185, 7192.28077192287, 7209.89185162223, 7250.4199664026755, 7275.345218930322, 7098.3256590623805, 7149.356557909709, 7191.17301510333, 7198.92801071993, 7217.2421895701245, 7138.535995160298, 7140.250273721184, 7159.284071592903, 7214.206437291894, 7239.022731931227, 5351.25082488452, 5365.731713414314, 5382.757775900094, 5412.7550663346865, 5426.565874730012, 4880.658353915192, 4912.38595175603, 4943.8022479101155, 4973.229149481836, 4992.15133427311, 6689.297623749533, 6706.865862682719, 6735.865282694416, 6792.743342481656, 6833.111695479556, 7016.368526832507, 7121.82195445118, 7216.819579510449, 7218.927810721931, 7300.269973002763, 6760.459163266927, 6769.695363708645, 6809.863802723918, 6820.658967051628, 6825.9317406826285, 6068.666223357669, 6070.6357618543025, 6135.631862088289, 6144.354842741448, 6152.969235153795, 7268.843363842742, 7332.96333518329, 7368.226336234726, 7447.88828167578, 7520.699172033167, 7144.67848946809, 7189.424846012202, 7198.136223653195, 7207.423406127392, 7207.459440541876, 7378.778636640909, 7382.667779950022, 7495.587742674205, 7504.774856754306, 7518.774436766906, 7074.398676112382, 7161.104861892263, 7190.06529151207, 7194.244604316471, 7199.640017999184, 7182.569045857265, 7202.927970720226, 7272.945422913754, 7283.453740969342, 7308.1595616502655, 6872.140982377203, 6961.095057642474, 6975.372216500539, 7046.788596342118, 7071.43428525727, 6895.4138898194215, 6926.411255043176, 6935.133108361455, 6939.965300173467, 6960.408365288805, 6887.380135787603, 6908.896366554606, 6973.023776671169, 6976.127984002, 6977.860442791014, 7001.964990175016, 7017.0, 7055.400290975119, 7115.679794409368, 7120.608366539683, 6763.695633696397, 6815.386615204656, 6824.5564038339335, 6874.6562671867205, 6912.723491060502], "value": 6596.726231233772, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [971.5142428785607, 1017.6845177994818, 1020.6631811502202, 1020.9081182693556, 1028.239103063732, 46475.0, 47201.527984720145, 47247.76376118123, 47362.289565656545, 47426.525734742645, 44577.554224457745, 44793.77603111988, 44952.3257151143, 44965.77517112418, 44987.77506112465, 7797.610119494031, 7900.130985591581, 7907.169747176521, 7932.563708996003, 7932.841343173133, 7731.22687731228, 7834.647440865147, 7840.647170877296, 7845.372370210398, 7845.843083138334, 7762.650680719381, 8294.751157465256, 8423.326133909302, 8527.360447966395, 8530.616122274481, 8434.45176063557, 8452.070272270106, 8496.362772792065, 8503.702370417006, 8524.977002759646, 8064.758057258292, 8112.797180070486, 8132.308753755879, 8139.959300203461, 8205.261526462587, 42001.1599768006, 42192.78903605462, 42208.3668744969, 42311.57688423138, 42363.788181058895, 3830.3296923038356, 3857.382818749015, 3874.380099184117, 3875.9806200969097, 3882.475865758143, 34627.82686086579, 34749.0, 34759.30481390384, 34871.82564087189, 34911.30177396463, 5445.264889239981, 5610.831675049755, 5677.261955945742, 5698.088305871082, 5718.914216286759, 236308.0, 237150.0, 238264.99999999825, 238645.0, 238718.0, 48884.0, 49806.750966245665, 56195.0, 56268.03061892814, 56894.43105568974, 59352.0, 59534.70232648809, 59663.22694184455, 59666.0, 59673.0, 18562.814371856108, 18632.627347452973, 18700.06499675011, 18753.062346882874, 18772.906135469235, 15300.846991530165, 15514.0, 15524.146171960425, 15532.068075915482, 15545.37818487248, 15739.13434761099, 15937.20313984296, 15958.281877315549, 15967.760483592756, 15981.28084236212, 6125.571210015256, 6180.168295656632, 6190.473809726223, 6191.318954914956, 6253.96873015632, 1087.7770057138268, 1100.884407137255, 1103.1175059952006, 1112.8386383974278, 1113.582406597526, 26419.0, 26425.73574264271, 26522.0, 26561.86719066431, 26652.333691657477, 1226.3439060102803, 1232.383808095952, 1237.672016915518, 1243.104964425612, 1253.7116463213513, 13973.650658733613, 14043.36804843764, 14110.365033573515, 14120.71758564823, 14288.428462861577, 297868.0, 297961.0, 298221.0, 299069.0, 300698.0, 62692.68653656794, 62784.37215627785, 62947.37052629415, 63280.68359658172, 63402.36597634057, 1538.638419971306, 1541.3757428241356, 1589.1418633937776, 1599.8640115590306, 1611.4118346803566, 26582.0, 26722.732772672804, 26778.866105669345, 26858.462830742887, 26943.326416839736, 43955.78022109997, 43990.56009439864, 44106.0, 44132.0, 44136.77931610322, 261328.0, 261727.0, 263364.0, 263792.0, 264382.0, 37694.434583481816, 38926.610733892296, 40078.198436030536, 40324.3951340736, 40330.79834600808, 52491.737541312046, 54114.18828717651, 54346.72826635841, 54382.72808635931, 55220.44779552153, 38512.03719907025, 39261.214775704895, 39290.410643839794, 39381.0, 39615.603843961195, 38501.61498385092, 38594.0, 38784.61215387923, 38932.41601375925, 39129.2174156521, 44004.77997609991, 44589.108217834815, 45113.0977380444, 45287.77356113198, 45642.0, 35965.460518092776, 36312.0, 36802.0, 37050.0, 37130.44304335491, 27242.0, 27324.726752733015, 27359.589606155525, 27712.861435692692, 27943.0], "value": 19649.755897012306, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.6283978253919, 592.123656987658, 595.4164918379987, 596.3797650443541, 606.299723818989, 24039.278821635336, 24908.626370604412, 24949.87525062372, 25034.499310013787, 25058.24825255243, 25297.494050118985, 26058.348541286476, 26429.735702642967, 26770.19689409316, 26785.062522811706, 6558.704858281377, 6599.406053455188, 6625.933740662604, 6637.336266373348, 6637.634930078843, 6463.515236357292, 6559.639219842907, 6722.260551339373, 6944.4097251733665, 6951.374376306131, 6898.206706228772, 6917.7578784742545, 6918.861622767542, 6921.065656136408, 6921.826954326157, 6824.976253561956, 6868.347506986828, 6890.552114112566, 6890.896636550456, 6897.241303456616, 6355.364463553654, 6359.014352775327, 6426.93573064268, 6447.4197322241225, 6462.612243265419, 26536.60195097075, 29561.852190739122, 30189.245268868235, 30274.69725302763, 30414.239644008856, 2371.549405612945, 2391.7727815857465, 2406.602910519762, 2407.735149133594, 2409.4458274596764, 20822.0, 21085.262015829525, 21114.577708445744, 21150.365489035354, 21205.25781597652, 2506.5112303100905, 2525.1793167220726, 2556.399246177147, 2564.666593342872, 2622.1740151852296, 138272.0, 143739.28130359386, 165060.0, 171713.14143429327, 172111.0, 15714.764278535831, 16043.679126417406, 16248.675026499404, 16463.91768041176, 17032.829671703374, 25955.74044259571, 25982.0, 25988.480230395286, 26023.609645855326, 26067.739322606532, 10997.395143267197, 11182.552697892155, 11273.830892536618, 11282.210245282915, 11295.3787541686, 10091.444970526545, 10117.392956422638, 10148.340357876714, 10163.085322321027, 10254.948725256476, 9959.253056020827, 9964.701058968243, 9972.551235194434, 9994.850077248848, 10354.689359319233, 2783.9304017399727, 2899.231703598506, 2910.3888183481704, 2927.8828846845886, 3016.1705530978716, 1041.9010194031555, 1045.1690905729963, 1050.9842352364722, 1057.6721216422934, 1059.380262546419, 21822.236221732164, 21826.890865545887, 21975.890120549615, 22073.448163796034, 22138.88930555337, 1115.765689205276, 1118.731504438945, 1119.216548416112, 1123.5393488669606, 1125.9493322800495, 11596.536138554533, 12037.157398981988, 12065.276083435023, 12106.152569320064, 12291.631251062483, 600442.0, 612311.0, 613668.0, 622242.0, 622789.0, 128842.0, 134246.32876835554, 134482.0, 135146.0, 135250.0, 1116.4760102460245, 1240.7704574653717, 1265.6266401411606, 1296.3064760353166, 1309.9062282993616, 22460.32619021432, 22728.772712272665, 22949.196778112684, 22988.655170172126, 23126.190583329502, 60383.396166037775, 65707.67146164239, 66683.33316666771, 67018.66490667516, 69297.65351173212, 82401.17598824175, 83520.58239709005, 87043.0, 88040.55979720317, 89686.55156724175, 21976.34070977873, 22109.557808844056, 22208.333749987527, 22226.44433889166, 22251.77748222497, 24676.876615616806, 24827.751722483266, 24890.50218995646, 25184.49631007406, 25278.620820687338, 20232.696509552075, 20558.238863164283, 20628.896855515628, 20630.0, 20961.056752446482, 17058.914705426392, 17220.913895430444, 17230.827691722923, 17583.208755606283, 17839.0, 20481.59036819285, 20499.8975005124, 20688.896555517127, 20947.266845660328, 21013.789862101185, 21439.571208576053, 21537.892310538347, 21648.24231151902, 21840.0, 21844.126234951058, 20029.198832046553, 20116.195352186332, 20302.0, 20435.0, 20445.48886277855], "value": 13352.376820554666, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.6655202751342, 1459.489178787425, 1478.48253111411, 1482.1773915476906, 1485.9702805943882, 18232.72650910236, 18786.0, 18821.058947052643, 18834.529136771587, 18834.905825470854, 18050.63898722025, 18057.458276251702, 18179.36372226972, 18227.453176404728, 18254.81745182548, 2896.706661232439, 2961.3781105967664, 2974.7768917331177, 2979.2849716068094, 2981.6869228731, 2817.1830169250893, 2887.523558612837, 2903.2451562593665, 2924.8683809228637, 2927.9853600731967, 2817.4083442477104, 2844.715528447149, 2845.6585209774858, 2849.7577705895023, 2853.4863724529578, 2751.7110703376156, 2772.50094982903, 2779.805413621047, 2780.193743814312, 2787.7351651593067, 2719.8504082275467, 2795.4269374778323, 2795.6086147939304, 2836.9148925532268, 2851.258672745101, 17009.404670836586, 17208.56978575533, 17250.741238881426, 17309.701772367123, 17377.913110434492, 8882.200601945857, 9144.679936202201, 9150.86273705895, 9156.404833685856, 9159.725208243763, 5992.8501787455225, 6130.570860039799, 6190.102435146923, 6286.685665716695, 6316.147320111772, 2025.7872923342984, 2054.969175462354, 2055.1779288284656, 2117.9894100529555, 2138.700581918533, 32454.513182302286, 32801.6719832801, 32847.83576082104, 33073.3385332292, 33086.83456582702, 7411.370033547209, 7633.961830190813, 7642.0, 7776.183500732454, 7791.454598178073, 20466.38600841977, 20471.795282046987, 20501.17995280202, 20502.077406516742, 20590.794092059186, 8675.739727808175, 8803.647854085764, 8837.116288371111, 8878.86681699775, 8883.200511953955, 7620.961895190489, 7976.401769867283, 7986.840263194704, 8125.146859579777, 8160.551169685606, 5942.019566771434, 6092.116643086816, 6128.754849805958, 6142.539309551801, 6145.938540614537, 1593.7609358596071, 1691.2389424758908, 1697.5586347549727, 1723.758673785671, 1765.622123026223, 948.1940350701987, 949.378157306965, 959.947202903847, 963.3738070254391, 964.1756298364937, 9047.547622618842, 9428.811423771485, 9468.526573671288, 9695.0, 9717.076877696607, 1061.8407238914224, 1065.2702898514542, 1070.4540684250933, 1072.14228617106, 1080.8540846985557, 5254.448282930313, 5827.805299913508, 5844.444777746107, 5921.99326114553, 5923.940760592425, 25505.61741573878, 26387.73612263891, 26572.069977550695, 26584.335391615365, 26646.333841654116, 24304.270871873872, 25817.612735808976, 25844.0, 25925.74074259271, 26015.73984260133, 12746.49014039428, 12782.23306601607, 12967.027472939379, 12978.415971281127, 12979.28613926243, 9247.815043699224, 9346.299027572823, 9407.341486095907, 9431.669891553762, 9475.431474111576, 21879.781202187773, 21997.340079797632, 22010.88994555017, 22139.1144354224, 22581.43546411353, 22925.312240632808, 22931.88534057319, 23061.19285824988, 23344.066237350315, 23561.29316120519, 12899.484020639069, 13012.479500819862, 13053.151545149534, 13119.540816071392, 13165.210087394787, 15999.920000399923, 16228.432004879773, 16240.26918788681, 16241.918790406446, 16273.349066037226, 12876.291803950498, 13148.34258287101, 13276.269805160422, 13278.734425311246, 13347.66630834198, 12617.927476164628, 12680.6829829255, 13176.879965203065, 13184.0, 13309.068365214342, 10175.084242418217, 10254.333468324536, 10331.535080921227, 10375.169986400919, 10388.324758890649, 7895.407844411807, 8237.217464340878, 8244.258016778518, 8259.752207433787, 8274.834503310021, 8111.9594402027615, 8254.587270636324, 8271.793205169919, 8272.214139656722, 8325.916740832756], "value": 7555.964223682435, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.5327145290775, 551.7710150287628, 578.4215784215784, 580.8983427900116, 585.2538014032109, 6374.3944325289085, 6421.325760795114, 6495.707693153801, 6498.187726534184, 6536.150300460945, 6350.460210882069, 6356.396142366468, 6382.840428989278, 6399.3600639935985, 6479.1253180820695, 5062.822801201959, 5128.5640720538795, 5192.844214673566, 5228.61441717945, 5264.947350526503, 5118.206677964921, 5165.74171291434, 5198.402183748861, 5223.242629818674, 5226.55574276187, 5145.511176438251, 5147.0992576298995, 5148.665336753118, 5160.096983027974, 5192.584593232551, 5159.613029022819, 5199.298094757235, 5206.661566998133, 5234.319538459995, 5239.869003274948, 4682.461516925563, 4715.882102947419, 4740.857774266743, 4749.168895443284, 4751.429828420577, 6291.496680265614, 6362.713677884506, 6367.204099487564, 6398.9040164397575, 6401.935980640181, 6079.726412311456, 6120.234970628671, 6122.816315510542, 6127.908081378739, 6134.141220229171, 6918.238993710688, 6940.479464040217, 6948.44412447003, 6956.65216739161, 6975.058367120425, 5203.089459344599, 5211.7394130293715, 5249.685018898878, 5254.238135470336, 5256.4768910922585, 7074.610896400644, 7180.712771489134, 7181.533200341961, 7224.241454647239, 7281.745138920165, 5384.91922621161, 5432.29380180574, 5434.310016541837, 5449.509544141047, 5464.972675136599, 5456.563474921997, 5488.451154884559, 5509.118541033416, 5520.420355862657, 5541.8085111701175, 5798.594098413155, 6054.122152287893, 6064.181335519736, 6112.358202388685, 6122.163350994684, 6594.274629790719, 6790.0, 6816.591004539743, 6886.758963436256, 6965.825854353681, 1853.1290293562004, 1876.7935527092009, 1880.7084901840237, 1889.3198448558508, 1894.7726272847071, 1309.306067784076, 1310.0329472304218, 1315.5395611536078, 1345.3385069439234, 1353.7766268565774, 6480.157579514727, 6518.0, 6534.7386104556235, 6553.2136143663065, 6612.239592446834, 6966.9303306967295, 6971.163460384786, 6978.5812851228075, 7015.193252775996, 7019.403350715246, 6449.5485316027425, 6483.773067942694, 6487.870242595121, 6492.285848556655, 6498.317676643885, 3638.6173254163778, 3677.79772112531, 3678.6137455567314, 3693.372126738407, 3713.0, 7250.891236631454, 7274.709011639581, 7303.6348182590655, 7311.574243022559, 7368.489459660756, 7090.822729431806, 7194.136703595602, 7197.352238298578, 7227.349538541556, 7249.347558719741, 7217.133943926762, 7332.046833911558, 7384.630768461556, 7396.0, 7402.9629851850395, 6924.372772398583, 6949.7567585134275, 6970.407515361048, 7000.529888723425, 7035.6430595751, 6803.217629972616, 6859.519833611599, 6881.174259088742, 6894.413974812197, 6932.272111428228, 7191.0, 7212.278772122956, 7213.422926165789, 7216.494845360774, 7224.855502890018, 5315.840524784263, 5370.731463426891, 5389.811356602657, 5425.755840987086, 5431.646942948696, 7002.509824312249, 7004.824879378057, 7023.262557431396, 7025.121859767529, 7061.046758687511, 6751.088603038527, 6776.390124888783, 6793.354631310018, 6840.657967101724, 6878.759243426656, 6687.832804179934, 6712.630805305755, 6743.426808721314, 6757.0, 6774.3225677431865, 6363.77726779579, 6605.438537724155, 6727.73089076451, 6769.966150169218, 6778.186617605918, 6796.791042910975, 6840.144981877265, 6845.486588505982, 6861.485388595976, 6862.897056544056, 6419.871602568016, 6498.155239818793, 6547.0, 6613.702383392662, 6619.801405957829], "value": 5368.185495195045, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [993.9055789699976, 1009.8182327181108, 1024.354656566363, 1030.124394264875, 1032.9225308101895, 20810.6878396824, 21192.576148477023, 21277.468063298424, 21316.786832131693, 21333.0, 19723.0, 20402.0, 20500.794992050076, 20513.897430512865, 20524.179032838667, 1026.1944373666672, 1069.6737495064003, 1074.248026381532, 1078.8381742738572, 1078.9028987391134, 1080.7028067281522, 1085.1210519479248, 1103.5806393570433, 1127.0476447401932, 1135.4436326200143, 1173.606841708029, 1174.1252766688829, 1175.0482109491297, 1175.2948231061337, 1177.2289150606323, 1162.767446510697, 1168.0422053915793, 1169.4152923538231, 1169.4854264123758, 1175.7060734816296, 852.6589364254286, 857.185673610073, 859.4198915731856, 860.3461369359331, 861.3410740783306, 21816.345509634735, 21876.45308867275, 22183.778162218332, 22194.11223551056, 22323.0, 23343.182988595487, 23417.180398685963, 23499.0, 23693.407664808346, 23757.287281381414, 19509.317173898846, 19687.507812304662, 19926.0, 19928.501787455283, 19950.70073948892, 3953.169834334793, 4001.659858911996, 4013.13717550728, 4018.578049304839, 4022.3966405039187, 159199.0, 161992.0, 164298.17850910788, 164889.0, 165245.17377413035, 17909.462716118538, 18098.0, 18257.269709211483, 18391.172397242157, 18405.355812546495, 35787.0, 36060.27879442449, 36127.63872361295, 36149.45775813365, 36278.637213628055, 9554.952225238969, 9633.229341652652, 9647.228221742243, 9723.611055557698, 9744.415335079917, 4972.8508144755715, 5030.597552195815, 5033.421156566968, 5093.261477085802, 5097.413797413271, 6379.330170332141, 6448.613083215022, 6459.580127291805, 6538.640374779337, 6548.083268342436, 1199.4899179086697, 1222.8349172861729, 1232.353014667289, 1234.160770675931, 1241.3110723548336, 734.7465124532027, 745.7203548669249, 750.4409215134759, 769.2499812682589, 769.6421164158693, 13122.803157952447, 13252.20486770797, 13549.0, 14152.929235353757, 14177.0, 735.3418690272176, 735.8344372516248, 749.4341771962243, 757.6628400361786, 763.3435245688703, 6080.7567697291615, 6131.233595800525, 6173.506119510428, 6188.8762224755255, 6223.097650840603, 163125.0, 168443.0, 169016.15491922462, 169199.0, 169865.0, 138260.30869845787, 141439.0, 142702.0, 144410.0, 150345.0, 126703.36648316884, 127875.0, 128289.0, 128780.35609822079, 129793.35103324235, 11687.18189726711, 11713.590024349107, 11733.823992639946, 12051.51793928233, 12198.634040978784, 59531.02344882652, 59787.40212597993, 59931.0, 60392.396076038676, 60499.39500604937, 74258.62870685612, 75463.0, 76021.61989190019, 76961.61519192369, 78524.21475785397, 17193.14034298305, 17385.82614173842, 17767.73348399801, 18038.819611803712, 18174.27302907869, 18009.72985405246, 18623.627527449647, 18742.625147497245, 18855.622887542446, 18889.772164809245, 17889.19498622591, 18381.356652517097, 18432.447026589223, 18484.260629574666, 18495.352662656744, 17717.291308348038, 17949.0, 18075.638487230444, 18100.81899181044, 18161.27354905842, 16462.176891155155, 16485.83514164891, 16644.916775416044, 16770.496885093467, 16784.496465106065, 13370.395552533759, 13482.865171348554, 13560.45758169662, 13640.249786261851, 13718.382672779948, 13211.207327560378, 13330.0, 13524.79712804289, 13538.72922541524, 13562.59312220635], "value": 10285.656322849845, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1358.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 105.0, 105.0, 106.0, 105.0, 106.0, 108.0, 106.0, 105.0, 106.0], "value": 105.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 111.0, 110.0, 112.0, 109.0, 111.0, 114.0, 139.0, 111.0], "value": 114.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 164.0, 162.0, 166.0, 163.0, 162.0, 161.0, 161.0, 160.0], "value": 162.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 72.0, 72.0, 74.0, 73.0, 73.0, 72.0, 74.0, 73.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 78.0, 78.0, 76.0, 78.0, 78.0, 77.0, 82.0, 78.0, 77.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 116.0, 113.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0], "value": 113.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 147.0, 148.0, 148.0, 148.0, 148.0, 149.0, 149.0, 149.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 95.0, 96.0, 94.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 45.0, 48.0, 42.0, 46.0, 42.0, 44.0, 43.0, 45.0, 42.0], "value": 44.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [39.0, 38.0, 39.0, 38.0, 38.0, 42.0, 39.0, 38.0, 38.0, 39.0], "value": 38.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 70.0, 69.0, 70.0, 70.0, 71.0, 73.0, 69.0, 72.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 102.0, 103.0, 108.0, 102.0, 104.0, 101.0, 103.0, 103.0, 101.0], "value": 103.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 148.0, 155.0, 152.0, 147.0, 148.0, 148.0, 147.0, 158.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 62.0, 61.0, 61.0, 57.0, 59.0, 58.0, 58.0, 66.0], "value": 59.9, "unit": "score"}]}]} 09:51:57 INFO - Return code: 0 09:51:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:51:57 INFO - # TBPL SUCCESS # 09:51:57 INFO - Running post-action listener: _package_coverage_data 09:51:57 INFO - Running post-action listener: _resource_record_post_action 09:51:57 INFO - [mozharness: 2017-09-15 16:51:57.363132Z] Finished run-tests step (success) 09:51:57 INFO - Running post-run listener: _resource_record_post_run 09:51:57 INFO - Total resource usage - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293875712; Read time: 28; Write time: 253376 09:51:57 INFO - TinderboxPrint: CPU usage
13.1% 09:51:57 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 28 09:51:57 INFO - TinderboxPrint: I/O write bytes / time
293,875,712 / 253,376 09:51:57 INFO - TinderboxPrint: CPU idle
8,214.7 (86.9%) 09:51:57 INFO - TinderboxPrint: CPU user
1,182.4 (12.5%) 09:51:57 INFO - TinderboxPrint: Swap in / out
0 / 0 09:51:57 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 9424896; Read time: 0; Write time: 2600 09:51:57 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:51:57 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284450816; Read time: 28; Write time: 250776 09:51:57 INFO - Running post-run listener: _upload_blobber_files 09:51:57 INFO - Blob upload gear active. 09:51:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:51:57 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 09:51:57 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:51:58 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:51:58 INFO - (blobuploader) - INFO - Open directory for files ... 09:51:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:51:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:51:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:59 INFO - (blobuploader) - INFO - Done attempting. 09:51:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:51:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:52:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:03 INFO - (blobuploader) - INFO - Done attempting. 09:52:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:52:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:52:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:04 INFO - (blobuploader) - INFO - Done attempting. 09:52:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:52:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:52:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:52:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:52:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:52:05 INFO - (blobuploader) - INFO - Done attempting. 09:52:05 INFO - (blobuploader) - INFO - Iteration through files over. 09:52:05 INFO - Return code: 0 09:52:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:52:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:52:05 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/14542284fce401ece31d30b6d9667370b06feb21ef2fd422986152e786c6452244ce8342da6c8cabe593a976195dc315f8a2a2ea9c580bb4e0f222332a8d0a9b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c63bcb0c9231fde42ac8aee7ee52a64343871468ef5b24689d5c5987b05f6f762c164e2bef251bbdf578534a9d454b38f446de287b359568c32d5cad7896a80d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/aeccc4f891f29642a106e68474f5b67ad1b2ac589847edd5ee0c7cc3b98a1ea08236a76e1db45553c2233e07bd3b02f30cce0265649173505cc176c94ca4cae4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b2137e6c3c3b272b6bfb9e84fbd677cc9ae96a3488d07b4d08b0d1dfd6903641a02db57b1c6d755ba234a9d010a9fefd73ece419371e181a70374664bd1d43f2"} 09:52:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:52:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:52:05 INFO - Contents: 09:52:05 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/14542284fce401ece31d30b6d9667370b06feb21ef2fd422986152e786c6452244ce8342da6c8cabe593a976195dc315f8a2a2ea9c580bb4e0f222332a8d0a9b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c63bcb0c9231fde42ac8aee7ee52a64343871468ef5b24689d5c5987b05f6f762c164e2bef251bbdf578534a9d454b38f446de287b359568c32d5cad7896a80d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/aeccc4f891f29642a106e68474f5b67ad1b2ac589847edd5ee0c7cc3b98a1ea08236a76e1db45553c2233e07bd3b02f30cce0265649173505cc176c94ca4cae4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b2137e6c3c3b272b6bfb9e84fbd677cc9ae96a3488d07b4d08b0d1dfd6903641a02db57b1c6d755ba234a9d010a9fefd73ece419371e181a70374664bd1d43f2"} 09:52:05 INFO - Running post-run listener: copy_logs_to_upload_dir 09:52:05 INFO - Copying logs to upload dir... 09:52:05 INFO - mkdir: /builds/slave/test/build/upload/logs 09:52:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.604793 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-15 09:52:05.467428) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 09:52:05.475206) ========= 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=df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/14542284fce401ece31d30b6d9667370b06feb21ef2fd422986152e786c6452244ce8342da6c8cabe593a976195dc315f8a2a2ea9c580bb4e0f222332a8d0a9b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c63bcb0c9231fde42ac8aee7ee52a64343871468ef5b24689d5c5987b05f6f762c164e2bef251bbdf578534a9d454b38f446de287b359568c32d5cad7896a80d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/aeccc4f891f29642a106e68474f5b67ad1b2ac589847edd5ee0c7cc3b98a1ea08236a76e1db45553c2233e07bd3b02f30cce0265649173505cc176c94ca4cae4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b2137e6c3c3b272b6bfb9e84fbd677cc9ae96a3488d07b4d08b0d1dfd6903641a02db57b1c6d755ba234a9d010a9fefd73ece419371e181a70374664bd1d43f2"} build_url:https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011408 build_url: 'https://queue.taskcluster.net/v1/task/BhEkn1lqSQCb7wRJIBD7Xw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/14542284fce401ece31d30b6d9667370b06feb21ef2fd422986152e786c6452244ce8342da6c8cabe593a976195dc315f8a2a2ea9c580bb4e0f222332a8d0a9b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/c63bcb0c9231fde42ac8aee7ee52a64343871468ef5b24689d5c5987b05f6f762c164e2bef251bbdf578534a9d454b38f446de287b359568c32d5cad7896a80d", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/aeccc4f891f29642a106e68474f5b67ad1b2ac589847edd5ee0c7cc3b98a1ea08236a76e1db45553c2233e07bd3b02f30cce0265649173505cc176c94ca4cae4", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/b2137e6c3c3b272b6bfb9e84fbd677cc9ae96a3488d07b4d08b0d1dfd6903641a02db57b1c6d755ba234a9d010a9fefd73ece419371e181a70374664bd1d43f2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-15 09:52:05.503326) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 09:52:05.503610) ========= 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=df17445c3590ff7897617a0e00000225-1505484922.722656-2002788614 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004560 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-15 09:52:05.586431) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 09:52:05.586721) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-15 09:52:05.590033) ========= ========= Total master_lag: 0.19 =========