builder: mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s slave: talos-linux64-ix-072 starttime: 1503799857.16 results: success (0) revision: f819969d7619f01e806e2685b8b3196f64624551 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:57.155947) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:57.156408) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:57.156697) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:57.173136) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:57.173515) ========= 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=199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-26 19:10:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2017-08-26 19:10:57 (220 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.174102 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:57.362878) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:57.363192) ========= 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=199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015403 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:57.412486) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:57.412831) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f819969d7619f01e806e2685b8b3196f64624551 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev f819969d7619f01e806e2685b8b3196f64624551 --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=199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-26 19:10:57,480 truncating revision to first 12 chars 2017-08-26 19:10:57,480 Setting DEBUG logging. 2017-08-26 19:10:57,480 attempt 1/10 2017-08-26 19:10:57,480 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f819969d7619?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-26 19:10:57,918 unpacking tar archive at: mozilla-central-f819969d7619/testing/mozharness/ program finished with exit code 0 elapsedTime=0.842120 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:58.269569) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:58.269889) ========= script_repo_revision: f819969d7619f01e806e2685b8b3196f64624551 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:58.270271) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:58.270533) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-26 19:10:58.281421) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-26 19:10:58.281806) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:10:58 INFO - MultiFileLogger online at 20170826 19:10:58 in /builds/slave/test 19:10:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:10:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:10:58 INFO - {'append_to_log': False, 19:10:58 INFO - 'base_work_dir': '/builds/slave/test', 19:10:58 INFO - 'blob_upload_branch': 'Firefox', 19:10:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:10:58 INFO - 'branch': 'Firefox', 19:10:58 INFO - 'buildbot_json_path': 'buildprops.json', 19:10:58 INFO - 'code_coverage': False, 19:10:58 INFO - 'config_files': ('talos/linux_config.py',), 19:10:58 INFO - 'default_actions': ('clobber', 19:10:58 INFO - 'read-buildbot-config', 19:10:58 INFO - 'download-and-extract', 19:10:58 INFO - 'populate-webroot', 19:10:58 INFO - 'create-virtualenv', 19:10:58 INFO - 'install', 19:10:58 INFO - 'setup-mitmproxy', 19:10:58 INFO - 'run-tests'), 19:10:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:10:58 INFO - 'disable_ccov_upload': False, 19:10:58 INFO - 'download_minidump_stackwalk': True, 19:10:58 INFO - 'download_symbols': 'ondemand', 19:10:58 INFO - 'enable_stylo': False, 19:10:58 INFO - 'enable_webrender': False, 19:10:58 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 19:10:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:10:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:10:58 INFO - 'gecko_profile': False, 19:10:58 INFO - 'gecko_profile_interval': 0, 19:10:58 INFO - 'installer_path': 'installer.exe', 19:10:58 INFO - 'log_level': 'info', 19:10:58 INFO - 'log_name': 'talos', 19:10:58 INFO - 'log_to_console': True, 19:10:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:10:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:10:58 INFO - 'opt_config_files': (), 19:10:58 INFO - 'pip_index': False, 19:10:58 INFO - 'suite': 'dromaeojs-e10s', 19:10:58 INFO - 'system_bits': '32', 19:10:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:10:58 INFO - 'title': 'talos-linux64-ix-072', 19:10:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:10:58 INFO - 'use_talos_json': True, 19:10:58 INFO - 'verify': 'False', 19:10:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:10:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:10:58 INFO - 'work_dir': 'build'} 19:10:58 INFO - [mozharness: 2017-08-27 02:10:58.492068Z] Running clobber step. 19:10:58 INFO - Running pre-action listener: _resource_record_pre_action 19:10:58 INFO - Running main action method: clobber 19:10:58 INFO - rmtree: /builds/slave/test/build 19:10:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:10:59 INFO - Running post-action listener: _resource_record_post_action 19:10:59 INFO - [mozharness: 2017-08-27 02:10:59.521576Z] Finished clobber step (success) 19:10:59 INFO - [mozharness: 2017-08-27 02:10:59.521674Z] Running read-buildbot-config step. 19:10:59 INFO - Running pre-action listener: _resource_record_pre_action 19:10:59 INFO - Running main action method: read_buildbot_config 19:10:59 INFO - Using buildbot properties: 19:10:59 INFO - { 19:10:59 INFO - "project": "", 19:10:59 INFO - "product": "firefox", 19:10:59 INFO - "who": "philringnalda@gmail.com", 19:10:59 INFO - "installer_path": "public/build/target.tar.bz2", 19:10:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-dromaeojs-e10s", 19:10:59 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 19:10:59 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos dromaeojs-e10s", 19:10:59 INFO - "stage_platform": "linux64-qr", 19:10:59 INFO - "basedir": "/builds/slave/test", 19:10:59 INFO - "buildnumber": 17, 19:10:59 INFO - "platform": "ubuntu64_hw_qr", 19:10:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:10:59 INFO - "slavebuilddir": "test", 19:10:59 INFO - "taskId": "J115yTg_QGuXkQ4SjUlaxQ", 19:10:59 INFO - "branch": "mozilla-central", 19:10:59 INFO - "script_repo_revision": "production", 19:10:59 INFO - "revision": "f819969d7619f01e806e2685b8b3196f64624551", 19:10:59 INFO - "slavename": "talos-linux64-ix-072", 19:10:59 INFO - "repo_path": "mozilla-central" 19:10:59 INFO - } 19:10:59 INFO - Finding installer, test and symbols from parent task. 19:10:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J115yTg_QGuXkQ4SjUlaxQ'}, attempt #1 19:10:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J115yTg_QGuXkQ4SjUlaxQ'}, attempt #1 19:11:00 INFO - Task dependencies: TahGLksQSDWoPnEX28P56w 19:11:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w'}, attempt #1 19:11:00 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 19:11:00 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.test_packages.json 19:11:00 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.crashreporter-symbols.zip 19:11:00 INFO - Running post-action listener: _resource_record_post_action 19:11:00 INFO - [mozharness: 2017-08-27 02:11:00.892324Z] Finished read-buildbot-config step (success) 19:11:00 INFO - [mozharness: 2017-08-27 02:11:00.892543Z] Running download-and-extract step. 19:11:00 INFO - Running pre-action listener: _resource_record_pre_action 19:11:00 INFO - Running main action method: download_and_extract 19:11:00 INFO - mkdir: /builds/slave/test/build/tests 19:11: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')]} 19:11:00 INFO - trying https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.test_packages.json 19:11:00 INFO - Downloading https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 19:11:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 19:11:01 INFO - Downloaded 989 bytes. 19:11:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:11:01 INFO - Using the following test package requirements: 19:11:01 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:11:01 INFO - u'common': [u'target.common.tests.zip'], 19:11:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:11:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:11:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:11:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:11:01 INFO - u'mozbase': [u'target.common.tests.zip'], 19:11:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:11:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:11:01 INFO - u'web-platform': [u'target.common.tests.zip', 19:11:01 INFO - u'target.web-platform.tests.tar.gz'], 19:11:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:11:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:11:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.common.tests.zip 19:11:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:11:01 INFO - Fetch https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.common.tests.zip into memory 19:11:02 INFO - Content-Length response header: 54978016 19:11:02 INFO - Bytes received: 54978016 19:11:13 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:11:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.common.tests.zip 19:11:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.common.tests.zip'}, attempt #1 19:11:13 INFO - Fetch https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.common.tests.zip into memory 19:11:14 INFO - Content-Length response header: 54978016 19:11:14 INFO - Bytes received: 54978016 19:11:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.talos.tests.zip 19:11:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 19:11:26 INFO - Fetch https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.talos.tests.zip into memory 19:11:27 INFO - Content-Length response header: 14496611 19:11:27 INFO - Bytes received: 14496611 19:11: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')]} 19:11:27 INFO - trying https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 19:11:27 INFO - Downloading https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:11:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 19:11:28 INFO - Downloaded 61167640 bytes. 19:11:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 19:11:28 INFO - mkdir: /builds/slave/test/properties 19:11:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:11:28 INFO - Writing to file /builds/slave/test/properties/build_url 19:11:28 INFO - Contents: 19:11:28 INFO - build_url:https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 19:11:28 INFO - Running post-action listener: _resource_record_post_action 19:11:28 INFO - Running post-action listener: find_tests_for_verification 19:11:28 INFO - Running post-action listener: set_extra_try_arguments 19:11:28 INFO - [mozharness: 2017-08-27 02:11:28.703355Z] Finished download-and-extract step (success) 19:11:28 INFO - [mozharness: 2017-08-27 02:11:28.703450Z] Running populate-webroot step. 19:11:28 INFO - Running pre-action listener: _resource_record_pre_action 19:11:28 INFO - Running main action method: populate_webroot 19:11:28 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:11:28 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:11:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:11:28 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:11:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:11:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:11:28 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:11:28 INFO - 'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:11:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:11:28 INFO - 'tests': ['damp', 'tps']}, 19:11:28 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:11:28 INFO - 'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['damp', 'tps']}, 19:11:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:11:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:11:28 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:11:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:11:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:11:28 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:11:28 INFO - 'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:11:28 INFO - 'other-e10s': {'tests': ['a11yr', 19:11:28 INFO - 'ts_paint', 19:11:28 INFO - 'tpaint', 19:11:28 INFO - 'sessionrestore', 19:11:28 INFO - 'sessionrestore_many_windows', 19:11:28 INFO - 'sessionrestore_no_auto_restore', 19:11:28 INFO - 'tabpaint']}, 19:11:28 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['a11yr', 19:11:28 INFO - 'ts_paint', 19:11:28 INFO - 'tpaint', 19:11:28 INFO - 'sessionrestore', 19:11:28 INFO - 'sessionrestore_many_windows', 19:11:28 INFO - 'sessionrestore_no_auto_restore', 19:11:28 INFO - 'tabpaint']}, 19:11:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 19:11:28 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 19:11:28 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['bloom_basic_singleton']}, 19:11:28 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['bloom_basic']}, 19:11:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:11:28 INFO - 'tsvgr_opacity', 19:11:28 INFO - 'tart', 19:11:28 INFO - 'tscrollx', 19:11:28 INFO - 'tsvg_static']}, 19:11:28 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['tsvgx', 19:11:28 INFO - 'tsvgr_opacity', 19:11:28 INFO - 'tart', 19:11:28 INFO - 'tscrollx', 19:11:28 INFO - 'tsvg_static']}, 19:11:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:11:28 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:11:28 INFO - 'talos_options': ['--stylo'], 19:11:28 INFO - 'tests': ['tp5o']}, 19:11:28 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:11:28 INFO - 'talos_options': ['--mitmproxy', 19:11:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:11:28 INFO - '--firstNonBlankPaint'], 19:11:28 INFO - 'tests': ['tp6_google', 19:11:28 INFO - 'tp6_youtube', 19:11:28 INFO - 'tp6_amazon', 19:11:28 INFO - 'tp6_facebook']}, 19:11:28 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:11:28 INFO - 'talos_options': ['--stylo', 19:11:28 INFO - '--mitmproxy', 19:11:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:11:28 INFO - '--firstNonBlankPaint'], 19:11:28 INFO - 'tests': ['tp6_google', 19:11:28 INFO - 'tp6_youtube', 19:11:28 INFO - 'tp6_amazon', 19:11:28 INFO - 'tp6_facebook']}, 19:11:28 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:11:28 INFO - 'talos_options': ['--stylo', 19:11:28 INFO - '--stylo-threads=1', 19:11:28 INFO - '--mitmproxy', 19:11:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:11:28 INFO - '--firstNonBlankPaint'], 19:11:28 INFO - 'tests': ['tp6_google', 19:11:28 INFO - 'tp6_youtube', 19:11:28 INFO - 'tp6_amazon', 19:11:28 INFO - 'tp6_facebook']}, 19:11:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:11:28 INFO - 'talos_options': ['--xperf_path', 19:11:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:11:28 INFO - 'tests': ['tp5n']}, 19:11:28 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:11:28 INFO - 'talos_options': ['--stylo', 19:11:28 INFO - '--xperf_path', 19:11:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:11:28 INFO - 'tests': ['tp5n']}}} 19:11:28 INFO - Running post-action listener: _resource_record_post_action 19:11:28 INFO - [mozharness: 2017-08-27 02:11:28.717550Z] Finished populate-webroot step (success) 19:11:28 INFO - [mozharness: 2017-08-27 02:11:28.717639Z] Running create-virtualenv step. 19:11:28 INFO - Running pre-action listener: _resource_record_pre_action 19:11:28 INFO - Running main action method: create_virtualenv 19:11:28 INFO - Creating virtualenv /builds/slave/test/build/venv 19:11:28 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:11:28 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 19:11:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:11:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:11:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:11:29 INFO - Installing distribute.............................................................................................................................................................................................done. 19:11:29 INFO - Installing pip...............done. 19:11:29 INFO - Return code: 0 19:11:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:11:29 INFO - Reading from file tmpfile_stdout 19:11:29 INFO - Output received: 19:11:29 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 19:11:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:11: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')]} 19:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11: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')]} 19:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:29 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 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:29 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 19:11:29 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 19:11:29 INFO - Using env: {'DISPLAY': ':0', 19:11:29 INFO - 'HOME': '/home/cltbld', 19:11:29 INFO - 'LANG': 'en_US.UTF-8', 19:11:29 INFO - 'LANGUAGE': 'en_US:en', 19:11:29 INFO - 'LOGNAME': 'cltbld', 19:11:29 INFO - 'MAIL': '/var/mail/cltbld', 19:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:29 INFO - 'MOZ_NO_REMOTE': '1', 19:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:29 INFO - 'NO_EM_RESTART': '1', 19:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:29 INFO - 'PWD': '/builds/slave/test', 19:11:29 INFO - 'SHELL': '/bin/bash', 19:11:29 INFO - 'SHLVL': '1', 19:11:29 INFO - 'TERM': 'linux', 19:11:29 INFO - 'TMOUT': '86400', 19:11:29 INFO - 'USER': 'cltbld', 19:11:29 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:29 INFO - '_': '/tools/buildbot/bin/python'} 19:11:30 INFO - Ignoring indexes: http://pypi.python.org/simple/ 19:11:30 INFO - Downloading/unpacking pip>=1.5 19:11:30 INFO - Running setup.py egg_info for package pip 19:11:30 INFO - warning: no files found matching 'pip/cacert.pem' 19:11:30 INFO - warning: no files found matching '*.html' under directory 'docs' 19:11:30 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:11:30 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:11:30 INFO - Installing collected packages: pip 19:11:30 INFO - Found existing installation: pip 1.1 19:11:30 INFO - Uninstalling pip: 19:11:30 INFO - Successfully uninstalled pip 19:11:30 INFO - Running setup.py install for pip 19:11:30 INFO - warning: no files found matching 'pip/cacert.pem' 19:11:30 INFO - warning: no files found matching '*.html' under directory 'docs' 19:11:30 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:11:30 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:11:30 INFO - Installing pip script to /builds/slave/test/build/venv/bin 19:11:30 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 19:11:30 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 19:11:30 INFO - Successfully installed pip 19:11:30 INFO - Cleaning up... 19:11:30 INFO - Return code: 0 19:11:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:11:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:11:30 INFO - Using env: {'DISPLAY': ':0', 19:11:30 INFO - 'HOME': '/home/cltbld', 19:11:30 INFO - 'LANG': 'en_US.UTF-8', 19:11:30 INFO - 'LANGUAGE': 'en_US:en', 19:11:30 INFO - 'LOGNAME': 'cltbld', 19:11:30 INFO - 'MAIL': '/var/mail/cltbld', 19:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:30 INFO - 'MOZ_NO_REMOTE': '1', 19:11:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:30 INFO - 'NO_EM_RESTART': '1', 19:11:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:30 INFO - 'PWD': '/builds/slave/test', 19:11:30 INFO - 'SHELL': '/bin/bash', 19:11:30 INFO - 'SHLVL': '1', 19:11:30 INFO - 'TERM': 'linux', 19:11:30 INFO - 'TMOUT': '86400', 19:11:30 INFO - 'USER': 'cltbld', 19:11:30 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:30 INFO - '_': '/tools/buildbot/bin/python'} 19:11:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:31 INFO - Downloading/unpacking psutil>=3.1.1 19:11:31 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 19:11:31 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 19:11:31 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 19:11:31 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 19:11:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:11:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:11:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:11:32 INFO - Installing collected packages: psutil 19:11:32 INFO - Running setup.py install for psutil 19:11:32 INFO - building 'psutil._psutil_linux' extension 19:11:32 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 19:11:32 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 19:11:32 INFO - building 'psutil._psutil_posix' extension 19:11:32 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 19:11:32 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 19:11:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:11:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:11:32 INFO - Successfully installed psutil 19:11:32 INFO - Cleaning up... 19:11:32 INFO - Return code: 0 19:11:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:11:32 INFO - Using env: {'DISPLAY': ':0', 19:11:32 INFO - 'HOME': '/home/cltbld', 19:11:32 INFO - 'LANG': 'en_US.UTF-8', 19:11:32 INFO - 'LANGUAGE': 'en_US:en', 19:11:32 INFO - 'LOGNAME': 'cltbld', 19:11:32 INFO - 'MAIL': '/var/mail/cltbld', 19:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:32 INFO - 'MOZ_NO_REMOTE': '1', 19:11:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:32 INFO - 'NO_EM_RESTART': '1', 19:11:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:32 INFO - 'PWD': '/builds/slave/test', 19:11:32 INFO - 'SHELL': '/bin/bash', 19:11:32 INFO - 'SHLVL': '1', 19:11:32 INFO - 'TERM': 'linux', 19:11:32 INFO - 'TMOUT': '86400', 19:11:32 INFO - 'USER': 'cltbld', 19:11:32 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:32 INFO - '_': '/tools/buildbot/bin/python'} 19:11:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:11:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:11:34 INFO - Installing collected packages: mozsystemmonitor 19:11:34 INFO - Running setup.py install for mozsystemmonitor 19:11:34 INFO - Successfully installed mozsystemmonitor 19:11:34 INFO - Cleaning up... 19:11:34 INFO - Return code: 0 19:11:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:34 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 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:34 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 19:11:34 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 19:11:34 INFO - Using env: {'DISPLAY': ':0', 19:11:34 INFO - 'HOME': '/home/cltbld', 19:11:34 INFO - 'LANG': 'en_US.UTF-8', 19:11:34 INFO - 'LANGUAGE': 'en_US:en', 19:11:34 INFO - 'LOGNAME': 'cltbld', 19:11:34 INFO - 'MAIL': '/var/mail/cltbld', 19:11:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:34 INFO - 'MOZ_NO_REMOTE': '1', 19:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:34 INFO - 'NO_EM_RESTART': '1', 19:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:34 INFO - 'PWD': '/builds/slave/test', 19:11:34 INFO - 'SHELL': '/bin/bash', 19:11:34 INFO - 'SHLVL': '1', 19:11:34 INFO - 'TERM': 'linux', 19:11:34 INFO - 'TMOUT': '86400', 19:11:34 INFO - 'USER': 'cltbld', 19:11:34 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:34 INFO - '_': '/tools/buildbot/bin/python'} 19:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:34 INFO - Downloading/unpacking jsonschema==2.5.1 19:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:11:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:11:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:11:35 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 19:11:35 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 19:11:35 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 19:11:35 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 19:11:35 INFO - Downloading functools32-3.2.3-2.tar.gz 19:11:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:11:35 INFO - warning: no files found matching '*.txt' 19:11:35 INFO - no previously-included directories found matching 'build' 19:11:35 INFO - no previously-included directories found matching 'dist' 19:11:35 INFO - no previously-included directories found matching '.git*' 19:11:35 INFO - Installing collected packages: jsonschema, functools32 19:11:35 INFO - Running setup.py install for functools32 19:11:35 INFO - warning: no files found matching '*.txt' 19:11:35 INFO - no previously-included directories found matching 'build' 19:11:35 INFO - no previously-included directories found matching 'dist' 19:11:35 INFO - no previously-included directories found matching '.git*' 19:11:35 INFO - Successfully installed jsonschema functools32 19:11:35 INFO - Cleaning up... 19:11:36 INFO - Return code: 0 19:11:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:11:36 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')]} 19:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:36 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')]} 19:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:36 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 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:36 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 19:11:36 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 19:11:36 INFO - Using env: {'DISPLAY': ':0', 19:11:36 INFO - 'HOME': '/home/cltbld', 19:11:36 INFO - 'LANG': 'en_US.UTF-8', 19:11:36 INFO - 'LANGUAGE': 'en_US:en', 19:11:36 INFO - 'LOGNAME': 'cltbld', 19:11:36 INFO - 'MAIL': '/var/mail/cltbld', 19:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:36 INFO - 'MOZ_NO_REMOTE': '1', 19:11:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:36 INFO - 'NO_EM_RESTART': '1', 19:11:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:36 INFO - 'PWD': '/builds/slave/test', 19:11:36 INFO - 'SHELL': '/bin/bash', 19:11:36 INFO - 'SHLVL': '1', 19:11:36 INFO - 'TERM': 'linux', 19:11:36 INFO - 'TMOUT': '86400', 19:11:36 INFO - 'USER': 'cltbld', 19:11:36 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:36 INFO - '_': '/tools/buildbot/bin/python'} 19:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:11:36 INFO - Cleaning up... 19:11:36 INFO - Return code: 0 19:11:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:11:36 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')]} 19:11:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:36 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')]} 19:11:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:36 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 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:36 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 19:11:36 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 19:11:36 INFO - Using env: {'DISPLAY': ':0', 19:11:36 INFO - 'HOME': '/home/cltbld', 19:11:36 INFO - 'LANG': 'en_US.UTF-8', 19:11:36 INFO - 'LANGUAGE': 'en_US:en', 19:11:36 INFO - 'LOGNAME': 'cltbld', 19:11:36 INFO - 'MAIL': '/var/mail/cltbld', 19:11:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:36 INFO - 'MOZ_NO_REMOTE': '1', 19:11:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:36 INFO - 'NO_EM_RESTART': '1', 19:11:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:36 INFO - 'PWD': '/builds/slave/test', 19:11:36 INFO - 'SHELL': '/bin/bash', 19:11:36 INFO - 'SHLVL': '1', 19:11:36 INFO - 'TERM': 'linux', 19:11:36 INFO - 'TMOUT': '86400', 19:11:36 INFO - 'USER': 'cltbld', 19:11:36 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:36 INFO - '_': '/tools/buildbot/bin/python'} 19:11:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:36 INFO - Downloading/unpacking blobuploader==1.2.4 19:11:36 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 19:11:36 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 19:11:36 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 19:11:36 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 19:11:37 INFO - Downloading blobuploader-1.2.4.tar.gz 19:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:11:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:11:37 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 19:11:37 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 19:11:37 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 19:11:37 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 19:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:11:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:11:37 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 19:11:37 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 19:11:37 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 19:11:37 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 19:11:38 INFO - Downloading docopt-0.6.1.tar.gz 19:11:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:11:38 INFO - Installing collected packages: blobuploader, requests, docopt 19:11:38 INFO - Running setup.py install for blobuploader 19:11:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:11:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:11:38 INFO - Running setup.py install for requests 19:11:38 INFO - Running setup.py install for docopt 19:11:38 INFO - Successfully installed blobuploader requests docopt 19:11:38 INFO - Cleaning up... 19:11:38 INFO - Return code: 0 19:11:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:38 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 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:38 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 19:11:38 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 19:11:38 INFO - Using env: {'DISPLAY': ':0', 19:11:38 INFO - 'HOME': '/home/cltbld', 19:11:38 INFO - 'LANG': 'en_US.UTF-8', 19:11:38 INFO - 'LANGUAGE': 'en_US:en', 19:11:38 INFO - 'LOGNAME': 'cltbld', 19:11:38 INFO - 'MAIL': '/var/mail/cltbld', 19:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:38 INFO - 'MOZ_NO_REMOTE': '1', 19:11:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:38 INFO - 'NO_EM_RESTART': '1', 19:11:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:38 INFO - 'PWD': '/builds/slave/test', 19:11:38 INFO - 'SHELL': '/bin/bash', 19:11:38 INFO - 'SHLVL': '1', 19:11:38 INFO - 'TERM': 'linux', 19:11:38 INFO - 'TMOUT': '86400', 19:11:38 INFO - 'USER': 'cltbld', 19:11:38 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:38 INFO - '_': '/tools/buildbot/bin/python'} 19:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:11:38 INFO - Running setup.py (path:/tmp/pip-p3XWPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:11:39 INFO - Running setup.py (path:/tmp/pip-PVcZOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:11:39 INFO - Running setup.py (path:/tmp/pip-UuAH1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:11:39 INFO - Running setup.py (path:/tmp/pip-hXMgWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:11:39 INFO - Running setup.py (path:/tmp/pip-48fiHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:11:39 INFO - Running setup.py (path:/tmp/pip-17pdAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:11:39 INFO - Running setup.py (path:/tmp/pip-i5xObZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:11:39 INFO - Running setup.py (path:/tmp/pip-ti02nB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:11:39 INFO - Running setup.py (path:/tmp/pip-NcCQgL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:11:39 INFO - Running setup.py (path:/tmp/pip-4IpOWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:11:39 INFO - Running setup.py (path:/tmp/pip-I0x_56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:11:39 INFO - Running setup.py (path:/tmp/pip-aUm3Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:11:39 INFO - Running setup.py (path:/tmp/pip-68upKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:11:39 INFO - Running setup.py (path:/tmp/pip-ttxh1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:39 INFO - Running setup.py (path:/tmp/pip-iU6TWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:11:40 INFO - Running setup.py (path:/tmp/pip-Ivd6Cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:11:40 INFO - Running setup.py (path:/tmp/pip-EI3AEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:11:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:11:40 INFO - Running setup.py install for manifestparser 19:11:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:11:40 INFO - Running setup.py install for mozcrash 19:11:40 INFO - Running setup.py install for mozdebug 19:11:40 INFO - Running setup.py install for mozdevice 19:11:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:11:40 INFO - Running setup.py install for mozfile 19:11:40 INFO - Running setup.py install for mozhttpd 19:11:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:11:40 INFO - Running setup.py install for mozinfo 19:11:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:11:40 INFO - Running setup.py install for mozInstall 19:11:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:11:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:11:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:11:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:11:40 INFO - Running setup.py install for mozleak 19:11:41 INFO - Running setup.py install for mozlog 19:11:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:11:41 INFO - Running setup.py install for moznetwork 19:11:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:11:41 INFO - Running setup.py install for mozprocess 19:11:41 INFO - Running setup.py install for mozprofile 19:11:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:11:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:11:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:11:41 INFO - Running setup.py install for mozrunner 19:11:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:11:41 INFO - Running setup.py install for mozscreenshot 19:11:41 INFO - Running setup.py install for moztest 19:11:41 INFO - Running setup.py install for mozversion 19:11:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:11:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:11:41 INFO - Cleaning up... 19:11:41 INFO - Return code: 0 19:11:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:41 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 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:41 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 19:11:41 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 19:11:41 INFO - Using env: {'DISPLAY': ':0', 19:11:41 INFO - 'HOME': '/home/cltbld', 19:11:41 INFO - 'LANG': 'en_US.UTF-8', 19:11:41 INFO - 'LANGUAGE': 'en_US:en', 19:11:41 INFO - 'LOGNAME': 'cltbld', 19:11:41 INFO - 'MAIL': '/var/mail/cltbld', 19:11:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:41 INFO - 'MOZ_NO_REMOTE': '1', 19:11:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:41 INFO - 'NO_EM_RESTART': '1', 19:11:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:41 INFO - 'PWD': '/builds/slave/test', 19:11:41 INFO - 'SHELL': '/bin/bash', 19:11:41 INFO - 'SHLVL': '1', 19:11:41 INFO - 'TERM': 'linux', 19:11:41 INFO - 'TMOUT': '86400', 19:11:41 INFO - 'USER': 'cltbld', 19:11:41 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:41 INFO - '_': '/tools/buildbot/bin/python'} 19:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:11:42 INFO - Running setup.py (path:/tmp/pip-9R2Mcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:11:42 INFO - Running setup.py (path:/tmp/pip-XXulkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:11:42 INFO - Running setup.py (path:/tmp/pip-WcPupf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:11:42 INFO - Running setup.py (path:/tmp/pip-YFGs95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:11:42 INFO - Running setup.py (path:/tmp/pip-PI9Hq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:11:42 INFO - Running setup.py (path:/tmp/pip-J_qZfv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:11:42 INFO - Running setup.py (path:/tmp/pip-oBuH7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:11:42 INFO - Running setup.py (path:/tmp/pip-9SbsX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:11:42 INFO - Running setup.py (path:/tmp/pip-hZ6cGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:11:42 INFO - Running setup.py (path:/tmp/pip-RAkhod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:11:42 INFO - Running setup.py (path:/tmp/pip-rAAMRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:11:42 INFO - Running setup.py (path:/tmp/pip-BsHxFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:11:42 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)) 19:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:11:43 INFO - Running setup.py (path:/tmp/pip-oHraC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:11:43 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)) 19:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:11:43 INFO - Running setup.py (path:/tmp/pip-FI3it0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:11:43 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)) 19:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:43 INFO - Running setup.py (path:/tmp/pip-iX6MS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:11:43 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)) 19:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:11:43 INFO - Running setup.py (path:/tmp/pip-Q8DGdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:11:43 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)) 19:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:11:43 INFO - Running setup.py (path:/tmp/pip-dD_5Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:11:43 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)) 19:11:43 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)) 19:11:43 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)) 19:11:43 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)) 19:11:43 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)) 19:11:43 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)) 19:11:43 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)) 19:11:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:11:43 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 19:11:43 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 19:11:43 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 19:11:43 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 19:11:44 INFO - Downloading blessings-1.6.tar.gz 19:11:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:11:44 INFO - Installing collected packages: blessings 19:11:44 INFO - Running setup.py install for blessings 19:11:44 INFO - Successfully installed blessings 19:11:44 INFO - Cleaning up... 19:11:44 INFO - Return code: 0 19:11:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:11:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:11:44 INFO - Reading from file tmpfile_stdout 19:11:44 INFO - Current package versions: 19:11:44 INFO - argparse == 1.2.1 19:11:44 INFO - blessings == 1.6 19:11:44 INFO - blobuploader == 1.2.4 19:11:44 INFO - docopt == 0.6.1 19:11:44 INFO - functools32 == 3.2.3-2 19:11:44 INFO - jsonschema == 2.5.1 19:11:44 INFO - manifestparser == 1.1 19:11:44 INFO - mozInstall == 1.13 19:11:44 INFO - mozcrash == 1.0 19:11:44 INFO - mozdebug == 0.1 19:11:44 INFO - mozdevice == 0.51 19:11:44 INFO - mozfile == 1.2 19:11:44 INFO - mozhttpd == 0.7 19:11:44 INFO - mozinfo == 0.10 19:11:44 INFO - mozleak == 0.1 19:11:44 INFO - mozlog == 3.5 19:11:44 INFO - moznetwork == 0.27 19:11:44 INFO - mozprocess == 0.25 19:11:44 INFO - mozprofile == 0.28 19:11:44 INFO - mozrunner == 6.13 19:11:44 INFO - mozscreenshot == 0.1 19:11:44 INFO - mozsystemmonitor == 0.3 19:11:44 INFO - moztest == 0.8 19:11:44 INFO - mozversion == 1.4 19:11:44 INFO - psutil == 3.1.1 19:11:44 INFO - requests == 1.2.3 19:11:44 INFO - wsgiref == 0.1.2 19:11:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:44 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 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:44 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 19:11:44 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 19:11:44 INFO - Using env: {'DISPLAY': ':0', 19:11:44 INFO - 'HOME': '/home/cltbld', 19:11:44 INFO - 'LANG': 'en_US.UTF-8', 19:11:44 INFO - 'LANGUAGE': 'en_US:en', 19:11:44 INFO - 'LOGNAME': 'cltbld', 19:11:44 INFO - 'MAIL': '/var/mail/cltbld', 19:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:44 INFO - 'MOZ_NO_REMOTE': '1', 19:11:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:44 INFO - 'NO_EM_RESTART': '1', 19:11:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:44 INFO - 'PWD': '/builds/slave/test', 19:11:44 INFO - 'SHELL': '/bin/bash', 19:11:44 INFO - 'SHLVL': '1', 19:11:44 INFO - 'TERM': 'linux', 19:11:44 INFO - 'TMOUT': '86400', 19:11:44 INFO - 'USER': 'cltbld', 19:11:44 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:44 INFO - '_': '/tools/buildbot/bin/python'} 19:11:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:45 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)) 19:11:45 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)) 19:11:45 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)) 19:11:45 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)) 19:11:45 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)) 19:11:45 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)) 19:11:45 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)) 19:11:45 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)) 19:11:45 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)) 19:11:45 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:11:45 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 19:11:45 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 19:11:45 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 19:11:45 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 19:11:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 19:11:46 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)) 19:11:46 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)) 19:11:46 INFO - Installing collected packages: simplejson 19:11:46 INFO - Running setup.py install for simplejson 19:11:46 INFO - building 'simplejson._speedups' extension 19:11:46 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 19:11:47 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 19:11:47 INFO - Successfully installed simplejson 19:11:47 INFO - Cleaning up... 19:11:47 INFO - Return code: 0 19:11:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:11:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:11:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26161f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27e1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2984f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, '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': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', '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 19:11:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:11:47 INFO - Using env: {'DISPLAY': ':0', 19:11:47 INFO - 'HOME': '/home/cltbld', 19:11:47 INFO - 'LANG': 'en_US.UTF-8', 19:11:47 INFO - 'LANGUAGE': 'en_US:en', 19:11:47 INFO - 'LOGNAME': 'cltbld', 19:11:47 INFO - 'MAIL': '/var/mail/cltbld', 19:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:11:47 INFO - 'MOZ_NO_REMOTE': '1', 19:11:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:11:47 INFO - 'NO_EM_RESTART': '1', 19:11:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:11:47 INFO - 'PWD': '/builds/slave/test', 19:11:47 INFO - 'SHELL': '/bin/bash', 19:11:47 INFO - 'SHLVL': '1', 19:11:47 INFO - 'TERM': 'linux', 19:11:47 INFO - 'TMOUT': '86400', 19:11:47 INFO - 'USER': 'cltbld', 19:11:47 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:11:47 INFO - '_': '/tools/buildbot/bin/python'} 19:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:11:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:11:47 INFO - Cleaning up... 19:11:47 INFO - Return code: 0 19:11:47 INFO - Running post-action listener: _resource_record_post_action 19:11:47 INFO - Running post-action listener: _start_resource_monitoring 19:11:47 INFO - Starting resource monitoring. 19:11:47 INFO - [mozharness: 2017-08-27 02:11:47.584978Z] Finished create-virtualenv step (success) 19:11:47 INFO - [mozharness: 2017-08-27 02:11:47.585489Z] Running install step. 19:11:47 INFO - Running pre-action listener: _resource_record_pre_action 19:11:47 INFO - Running main action method: install 19:11:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:11:47 INFO - Reading from file tmpfile_stdout 19:11:47 INFO - Detecting whether we're running mozinstall >=1.0... 19:11:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:11:47 INFO - Reading from file tmpfile_stdout 19:11:47 INFO - Output received: 19:11:47 INFO - Usage: mozinstall [options] installer 19:11:47 INFO - Options: 19:11:47 INFO - -h, --help show this help message and exit 19:11:47 INFO - -d DEST, --destination=DEST 19:11:47 INFO - Directory to install application into. [default: 19:11:47 INFO - "/builds/slave/test"] 19:11:47 INFO - --app=APP Application being installed. [default: firefox] 19:11:47 INFO - mkdir: /builds/slave/test/build/application 19:11:47 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'] 19:11:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 19:12:05 INFO - Reading from file tmpfile_stdout 19:12:05 INFO - Output received: 19:12:05 INFO - /builds/slave/test/build/application/firefox/firefox 19:12:05 INFO - Running post-action listener: _resource_record_post_action 19:12:05 INFO - [mozharness: 2017-08-27 02:12:05.632425Z] Finished install step (success) 19:12:05 INFO - [mozharness: 2017-08-27 02:12:05.632572Z] Running setup-mitmproxy step. 19:12:05 INFO - Running pre-action listener: _resource_record_pre_action 19:12:05 INFO - Running main action method: setup_mitmproxy 19:12:05 INFO - Skipping: mitmproxy is not required 19:12:05 INFO - Running post-action listener: _resource_record_post_action 19:12:05 INFO - [mozharness: 2017-08-27 02:12:05.633215Z] Finished setup-mitmproxy step (success) 19:12:05 INFO - [mozharness: 2017-08-27 02:12:05.633318Z] Running run-tests step. 19:12:05 INFO - Running pre-action listener: _resource_record_pre_action 19:12:05 INFO - Running pre-action listener: _set_gcov_prefix 19:12:05 INFO - Running main action method: run_tests 19:12:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:12:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:12:05 INFO - Python 2.7.3 19:12:05 INFO - Return code: 0 19:12:05 INFO - grabbing minidump binary from tooltool 19:12:05 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')]} 19:12:05 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 0x2982130>, '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 0x2983030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29834c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:12:05 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 19:12:05 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 19:12:05 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 19:12:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:12:05 INFO - Return code: 0 19:12:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:12:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:12:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:12:05 INFO - ENV: RUST_BACKTRACE is now full 19:12:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:12:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:12:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 19:12:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-072 --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 19:12:05 INFO - Using env: {'DISPLAY': ':0', 19:12:05 INFO - 'HOME': '/home/cltbld', 19:12:05 INFO - 'LANG': 'en_US.UTF-8', 19:12:05 INFO - 'LANGUAGE': 'en_US:en', 19:12:05 INFO - 'LOGNAME': 'cltbld', 19:12:05 INFO - 'MAIL': '/var/mail/cltbld', 19:12:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:12:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:12:05 INFO - 'MOZ_ACCELERATED': '1', 19:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:05 INFO - 'MOZ_NO_REMOTE': '1', 19:12:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:12:05 INFO - 'MOZ_WEBRENDER': '1', 19:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:05 INFO - 'NO_EM_RESTART': '1', 19:12:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:05 INFO - 'PWD': '/builds/slave/test', 19:12:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:12:05 INFO - 'RUST_BACKTRACE': 'full', 19:12:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:12:05 INFO - 'SHELL': '/bin/bash', 19:12:05 INFO - 'SHLVL': '1', 19:12:05 INFO - 'TERM': 'linux', 19:12:05 INFO - 'TMOUT': '86400', 19:12:05 INFO - 'USER': 'cltbld', 19:12:05 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:05 INFO - '_': '/tools/buildbot/bin/python'} 19:12:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-072', '--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 19:12:06 INFO - mozversion application_buildid: 20170827011816 19:12:06 INFO - mozversion application_changeset: f819969d7619f01e806e2685b8b3196f64624551 19:12:06 INFO - mozversion application_display_name: Nightly 19:12:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:12:06 INFO - mozversion application_name: Firefox 19:12:06 INFO - mozversion application_remotingname: firefox 19:12:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:12:06 INFO - mozversion application_vendor: Mozilla 19:12:06 INFO - mozversion application_version: 57.0a1 19:12:06 INFO - mozversion platform_buildid: 20170827011816 19:12:06 INFO - mozversion platform_changeset: f819969d7619f01e806e2685b8b3196f64624551 19:12:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:12:06 INFO - mozversion platform_version: 57.0a1 19:12:06 INFO - using testdate: 1503799926 19:12:06 INFO - actual date: 1503799926 19:12:06 INFO - starting webserver on 'localhost:46201' 19:12:06 INFO - SUITE-START | Running 2 tests 19:12:06 INFO - TEST-START | dromaeo_css 19:12:06 INFO - Initialising browser for dromaeo_css test... 19:12:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46201/getInfo.html -profile /tmp/tmpyynrCT/profile 19:12:06 INFO - TEST-INFO | started process 17856 (/builds/slave/test/build/application/firefox/firefox http://localhost:46201/getInfo.html) 19:12:12 INFO - TEST-INFO | 17856: exit 0 19:12:12 INFO - Browser initialized. 19:12:12 INFO - Running cycle 1/1 for dromaeo_css test... 19:12:12 INFO - Using env: {'DISPLAY': ':0', 19:12:12 INFO - 'HOME': '/home/cltbld', 19:12:12 INFO - 'JSGC_DISABLE_POISONING': '1', 19:12:12 INFO - 'LANG': 'en_US.UTF-8', 19:12:12 INFO - 'LANGUAGE': 'en_US:en', 19:12:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:12:12 INFO - 'LOGNAME': 'cltbld', 19:12:12 INFO - 'MAIL': '/var/mail/cltbld', 19:12:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:12:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:12:12 INFO - 'MOZ_ACCELERATED': '1', 19:12:12 INFO - 'MOZ_CRASHREPORTER': '1', 19:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:12:12 INFO - 'MOZ_NO_REMOTE': '1', 19:12:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:12:12 INFO - 'MOZ_WEBRENDER': '1', 19:12:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:12 INFO - 'NO_EM_RESTART': '1', 19:12:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:12 INFO - 'PWD': '/builds/slave/test', 19:12:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:12:12 INFO - 'RUST_BACKTRACE': 'full', 19:12:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:12:12 INFO - 'SHELL': '/bin/bash', 19:12:12 INFO - 'SHLVL': '1', 19:12:12 INFO - 'TERM': 'linux', 19:12:12 INFO - 'TMOUT': '86400', 19:12:12 INFO - 'USER': 'cltbld', 19:12:12 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:12 INFO - '_': '/tools/buildbot/bin/python'} 19:12:12 INFO - TEST-INFO | started process 18040 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyynrCT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:12:13 INFO - PID 18040 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 19:12:13 INFO - PID 18040 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 19:12:13 INFO - PID 18040 | 19:12:13 INFO - PID 18040 | (/builds/slave/test/build/application/firefox/firefox:18101): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:12:13 INFO - PID 18040 | 19:12:14 INFO - PID 18040 | 19:12:14 INFO - PID 18040 | (/builds/slave/test/build/application/firefox/firefox:18158): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:12:14 INFO - PID 18040 | 19:12:14 INFO - PID 18040 | __metrics Screen width/height:1600/1200 19:12:14 INFO - PID 18040 | colorDepth:24 19:12:14 INFO - PID 18040 | Browser inner width/height: 1024/768 19:12:14 INFO - PID 18040 | __metrics 19:12:23 INFO - PID 18040 | RSS: Main: 201990144 19:12:23 INFO - PID 18040 | 19:12:27 INFO - PID 18040 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 19:15:22 INFO - PID 18040 | Cycle 1(1): loaded http://localhost:46201/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46201/tests/dromaeo/cssquery-ext.html) 19:15:24 INFO - PID 18040 | RSS: Main: 214179840 19:15:24 INFO - PID 18040 | 19:18:26 INFO - PID 18040 | Cycle 1(1): loaded http://localhost:46201/tests/dromaeo/cssquery-ext.html (next: http://localhost:46201/tests/dromaeo/cssquery-jquery.html) 19:18:27 INFO - PID 18040 | RSS: Main: 209727488 19:18:27 INFO - PID 18040 | 19:21:29 INFO - PID 18040 | Cycle 1(1): loaded http://localhost:46201/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46201/tests/dromaeo/cssquery-mootools.html) 19:21:29 INFO - PID 18040 | RSS: Main: 213491712 19:21:29 INFO - PID 18040 | 19:24:31 INFO - PID 18040 | Cycle 1(1): loaded http://localhost:46201/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46201/tests/dromaeo/cssquery-prototype.html) 19:24:32 INFO - PID 18040 | RSS: Main: 209268736 19:24:32 INFO - PID 18040 | 19:27:36 INFO - PID 18040 | Cycle 1(1): loaded http://localhost:46201/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46201/tests/dromaeo/cssquery-yui.html) 19:27:36 INFO - PID 18040 | RSS: Main: 216256512 19:27:36 INFO - PID 18040 | 19:30:39 INFO - PID 18040 | Cycle 1(1): loaded http://localhost:46201/tests/dromaeo/cssquery-yui.html (next: http://localhost:46201/tests/dromaeo/cssquery-dojo.html) 19:30:39 INFO - PID 18040 | RSS: Main: 215990272 19:30:39 INFO - PID 18040 | 19:30:39 INFO - PID 18040 | __start_tp_report 19:30:39 INFO - PID 18040 | _x_x_mozilla_page_load 19:30:39 INFO - PID 18040 | _x_x_mozilla_page_load_details 19:30:39 INFO - PID 18040 | |i|pagename|runs| 19:30:39 INFO - PID 18040 | |0;dojo.html;3247.123276715287;3305.520699498573;3307.58655168104;3315.0054983504942;3352.1452029732413;6238.157848690425;6320.715567799454;6333.5883167594075;6336.683165841707;6348.58734182279;6262;6269.905951410732;6370.267419246788;6408.327125651809;6444.96777516113;5786.971065144689;5833.649981001143;5858.297004359483;5905.852353691149;5929.644221346712;5794.536437085023;5855.677937713424;5868.413158684119;5924.7333869975955;5999.160117583542;5711.057675483561;5755.942440575583;5828.97085514574;5900.734466948998;5905.881882362351;5647.519960803336;5733.340665823442;5741.655500669973;5748.856278593069;5749.82750517481;5780.797672081499;5796.797112101056;5798.971005144989;5852.356240813507;5888.617239879394;6218.440340369389;6254.781082662085;6282.560220784547;6311.400416960381;6370.681465926685;6788.083608712859;6830.965845170792;6832.419244364235;6835.897461538081;6840.31596840317;6760.898586521206;6799.660016999131;6826.829329266759;6876.724931002754;6909.2745261747295;6215.844603884849;6268.1851359323;6345.8413539661415;6355.173827402455;6371.139896114058;6899.206591241971;6915.101036865175;7004.089468369079;7004.649767511604;7041.40148087408;6499.870002600016;6614.305497922745;6650.168728908887;6651;6653;6680.699368528428;6771.72913083481;6831.009503621947;6843.349881761224;6868.416184624263;6672.599644021374;6695.631740254333;6696.43080338167;6756.425703815231;6793.388595026471;6614.669266536654;6716.932830671729;6721.462283017347;6736;6823.590584564942;5396.4333744957;5412.648177868426;5429.375621803464;5442.047641662693;5446.455354464521;4717.3159891816185;4729.148753224453;4732.716037037789;4737.265723812815;4742.5731684148595;5952.791652292149;5980.760769569256;5996.220491336186;6065.272167339947;6068.089786532056;6725.226598941184;6791.864162716817;6816.113905192294;6893.7931862044215;6893.896591551131;5980.700964951735;6011.879762404727;6029.216201893726;6031.4270144336215;6033.276006879202;5788.652680839163;5835.503982161479;5915.645061296336;5924.259467566554;5925.822225333247;6387.968060159669;6420.871582568322;6591.967040164865;6602.900956485657;6606.339366063356;6729.394354508117;6762.188537375547;6771.492138089565;6869.622170780455;6871.9656401717675;6326.272478665012;6598.670066496752;6599.241087274832;6638.269790322964;6639.734410623521;6653.800385988428;6654.600723956578;6668.733250669919;6672.232693240339;6699.330066993261;6742.123523941857;6789.762358317633;6790.558613690095;6796.932030679828;6798;6565.44193743518;6567.802965911031;6589.604623722592;6594.967025165035;6601.910684737157;6581.177352830896;6586;6591.077249185022;6593.274739778716;6603.108580341785;6498.675066246763;6504.609723416421;6518.152640156749;6604.537682362188;6622.437092847162;6509.088727578238;6547.181602299713;6646.567973081735;6688.130543029375;6735.663216839236;6358.109864619049;6360.936390636034;6362.713677884413;6382.840428989312;6394.552381333262 19:30:39 INFO - PID 18040 | |1;ext.html;719.7768691705571;734.3904559215852;750.5534207146748;775.1318523253957;777.8249893773898;40507.79746101269;40763.38854917175;41272.58727412733;41325.586744132546;41463;37216.25567488655;37225.62774372256;37343.06642333943;37489.812550937204;37557.061073473174;6993.300669933004;7087.397571206454;7108.85782284354;7114.181869085044;7124.821879453003;6939.5836249824915;7055;7070.363667269945;7073.823154421129;7074.292570742936;7024.121984751906;7051.717931282743;7053.753118640849;7057.505974581782;7664.655090520912;7792.376609871225;7862.842743145162;7870.488418252795;7879.409044321642;7882.605869706492;7697.30724234816;7725;7767.145613982458;7812.375009999243;7830.060392752906;37407.4388884167;37542.62457375418;37634.62365376366;37737.622623773685;38481.23037539262;3491.4064609016527;3494.0391392367046;3514.9297014059575;3530.682238598513;3540.345036168317;31258.21854453634;31452.37095258082;31475.685243147505;31491.685083149103;31547;5080.872978175538;5092.134337162691;5139.15203991341;5155.690658560461;5231.738413079369;219063;222629;223566;224349;225131.99999999837;47071.29393059107;47462;47533.57399278027;47569.286460703115;47603.52396475991;50259.49740502548;50321.24518132231;50331.74834125879;50389.49610503848;50439.49560504348;16048.598785030234;16209.351625935067;16231.350745970029;16287.51137465878;16310;13231.66920826987;13324;13334.533291334757;13346;13347.599572012856;13897.652558686113;13986.790198147037;14107;14164.645883852987;14308.785368219485;5609.1305847593685;5652.8869422611315;5657.151427285858;5686.5735069869925;5813.680247586423;975.4683697384801;985.4875464758286;992.1920963672789;1000.2947921715173;1002.8696269485066;27733;27988;28149.43701125966;28411.28971775681;28583.857080714464;1048.632978457549;1080.778440419712;1089.428050273597;1090.929089609173;1104.376027544435;12102.576409825613;12211.206271592495;12256.264624122581;12340.382980850922;12351.25892446456;228641;232979;233426;233875;234487;42105.78947105245;42968.78515607464;50103.74948125236;50143.49856501461;50208.74895625498;1409.7180563887264;1418.0003097816016;1421.4456362019027;1423.7366087273888;1424.622475044114;26678.199654010412;26820;26919.865400672872;27100.593491098043;27182.592261116493;35313.29373412466;35459;35685.64314356927;35704.464433034045;36004;184894;185160;185878;185974;186565;32841;32897;32909.50635740418;32954;33082.33835323328;39748;41374.58625413707;41542.376864347665;41570.376444352754;44853;33096.33807323888;33432.1641958953;33438.32493400473;33498.330033398706;33693;32880.83559582187;33154.668453316124;33223.00330990074;33560.66439335576;33880.66119338775;38242.235155296185;38308.23383532369;38431.61568384281;38439.231215376094;38593.807030965785;32794.18014549655;32952.34095318128;32986.34027319488;33022.83488582542;33203.169920752174;23812.761872381056;24118.51762964766;24119.15582954588;24121.39696507531;24495.51008979846 19:30:39 INFO - PID 18040 | |2;jquery.html;552.3344370034109;563.7463141586287;564.785381555009;569.8176583493282;586.5952492779982;21597.352079437624;22409.775902240974;22449;22477.43806404841;22547.887260563675;23576.29271121869;25980.480390392182;26049.088281910135;26065.739342606616;26266.60600091;6676.599404035761;6842.760503382383;6843.965780171092;6844.076049733272;6846.5549739267035;6606.075149479077;6674;6722.93277067228;6731.394174524292;6732.25945146036;4128.21563902859;4130.752154870703;5186.014657215136;6337.207849018872;6693.564918280321;6386.9041964370335;6416.743330266784;6425;6429.678516074177;6433.646149461778;5912.793052243151;5929.199558059693;5946.5540084493405;5953.58324917256;5962.672053037125;27733.168004959884;28219.435611287863;28665.426691466055;28720.425591488263;28780.568291475643;2070.8861012644447;2090.864093833896;2100.674395468705;2101.13853320138;2106.3786183075877;19267;19783.307584234626;19795.30716424918;19859.205631774712;19877.30429434977;2412.7345991940874;2446.425090103824;2502.399424138212;2508.3102146909564;2529.3297276221815;129639;130422;133598;134378.3281083598;134749.32625336907;13888.58334249954;14343.78484322736;14379.281035948161;14864.581146336403;14953.925230373778;22724.97737601812;22763.317100487013;23022.7697723024;23026.309210723706;23062.65406018911;9835.114839664464;10012.199024078056;10222.182225421948;10295.536700848479;10350;9548.236141108695;9594.376365536358;9676.322657413913;9677.612895484242;9724.75688107803;8647.221750042527;8671.306295496344;8958.86561701575;9154;9239.491827949392;2623.3048242215827;2729.481398534272;2760;2765.294849813325;2774.625425567563;941.1811723800338;967.4388854464394;968.651285537206;974.8204672346399;988.2983082011708;19077.90461047686;19452;19559.217631294872;19759.703604445946;19799.60400791976;999.3304485994402;1026.9024442677933;1039.932404393712;1041.8853926068127;1048.99475502622;10707.571697132184;10709.732256693645;10721;11067.889321106846;11103.167262455348;449380;462655;463474;463931;468392;111734.44132779284;112500.43749781199;113212.43393782979;113547;115189;1148.9253198542235;1161.8663853656938;1189.8096304591224;1193.964181074569;1199.8560172779323;17087.82912170912;17458.563535911704;17612;17748.822511774717;18157.36449224271;56217.71891140518;56332;56349.71825140848;57537;58614.4138558626;72514.27485725076;72788;73102;74025;74133;13893.374798133906;14185.290735463392;14247.358868851134;14312.928435357757;14356.353964071433;22010.559788803814;22081.668774968068;22114.88942555277;22117.889410552845;22240.88879555592;17685.38101166453;17765.200565974304;18023.188956496728;18025.819741802414;18028.459146225636;16182.352705891632;16323.59191020234;16359.836401636307;16689.49931502057;17476.912615436842;18806.717899231797;19307.51731206731;19388.903055484632;19446.902765486084;19709.60580788405;13738.10702304347;14406.55934406551;14408.351624177147;14417.42330306776;14679.045862018935;18020.18909149118;18204;18378.540536486697;18429;18709 19:30:39 INFO - PID 18040 | |3;mootools.html;1280.5518068675963;1312.2979206124726;1336.7059246965664;1339.3838834136297;1344.0322967463424;14212.431502739879;14679.559613211608;14758.704825903476;14773.187474688888;14789.408423663053;12179.086568507375;12631.684207894807;14337.641558961031;14876.404943802261;14892.925535372335;2757.296889293227;2761.64098667174;2781.193453898367;2784.8050636455355;2785.832850029005;2682.450097729961;2737.712540183282;2764.04640399063;2766.2392841968417;2768.5708715149185;2653.3764565327133;2858.757005654522;2866.641669791276;2869.469148207588;2871.1530098620956;2796.4267325198284;2827.7737780977473;2835.0219174384806;2854.314964408548;2892.681805001448;2713.8778754956074;2735.6717193936656;2737.931551711203;2758.3517873299757;2773.1541879726674;14426.567202983822;14561.563153105319;14600.634984125376;14705.191214483199;14736;7831.6475758591005;8111;8129.715459958932;8142.674293028272;8161.55111468869;5354.9196762048605;5389.380221274564;5402.9729851350885;5413.404525502231;5515.393306736256;1866.9066546672611;1884.7172924061363;1903.4194570655945;1932.8357089647398;1936.1868015433552;25312.367190820194;25612.615810762854;25633;25685.74314256871;25753.35616609581;6421.839454013687;6590.044443555657;6645.734170633121;6776.796696099125;6819.977003449522;16652.250648720837;16764.329426823035;16782;16784.741144414216;16810.579735506464;7378.40972722181;7448.73929412468;7448.813779655444;7472.663730132157;7490.250974902466;6802.217744959294;6869.416099631588;6895.724171033203;6951.409130223988;7070.540414873017;5035.212499562603;5142.87142821425;5204.375474943031;5225.980933717965;5270.051390749702;1367.6375760423496;1400.8319001719656;1416.7237388709102;1445.1329202478428;1474.8525147485166;796.9083555391088;821.3429256594819;829.6847198064818;850.37497439382;852.8976522817302;7340.192578816326;7643.7324693635455;7708.344790692854;7774.922250777533;7875.881861772078;955.6702937486556;970.0638883477478;972.91243788058;976.9853452198223;978.0366339155926;4054.5525237521115;4131.958680413218;4150.252954468165;4237.533871274158;4240.279152544075;19477.610447790965;20513.794862051487;20534.69197962032;20645.380638580868;20656.793432065788;19036.952967586916;19674.508137296394;20040.398788036382;20294.594108117755;20381.898090509454;10870.782584348426;10929.453527323602;11021.83467247976;11138.3873886937;11188.776224475465;7187.9640601796655;7434.7769566913075;7443.218462061622;7516.774496765106;7550.131734850563;17116;17416.47750567485;17639.911800440917;17802.37691680785;18003.639927201646;17898.91050544739;17975;17999.910000449912;18085.457436276934;18152.45542633723;10662;10683.94658026705;10700.197485188486;10741.087007604128;10757.784844303227;13280.667983300495;13330.800037999245;13392.665183370495;13452.058355915384;13593.252371119683;10822.080123189617;10879.619213327496;11132.1650876183;11142.832857507306;11321.09431245482;10638.787224255626;10743.623973160902;10804.189685773441;10925.617603383844;11076.612318568812;8343.249107580346;8493.617787199468;8516.063233044484;8520.914790852012;8523.573821309034;6733.764318248838;6792.422644075111;6826.897596536155;6907.620080895599;6938.444924405934;6331.96834015827;6486.708098135501;6614.140161778938;6644.169478815148;6655.101561289164 19:30:39 INFO - PID 18040 | |4;prototype.html;499.84100484935203;499.8745393626597;515.7988384530032;520.6485622205012;522.5401646551035;6217.347178546255;6252.3122456529745;6379.17070780799;6383.297837237912;6402.199725034371;6050.788222412217;6184.226971628546;6201.379862013797;6202.627842329463;6305.621662700241;5059;5094.834695254502;5116.462771409004;5120.771014956402;5147.639665223436;5027.949720502785;5061.595072394218;5113.181890897457;5113.335266415379;5119.897602047957;4764.331974094501;4966.428860681014;4996.425411077718;5015.523525265112;5017.397912250517;5017.774200161001;5022.447530771612;5033.169527028036;5039.420466646346;5064.215046667772;4495.505494395647;4496.2581174106235;4504.8873778155485;4506.819727210907;4531.705439146478;6084;6180.227471566054;6215.850067737483;6245.781397651106;6257.186565746424;5780.075187969905;5781.075027995543;5801.374141161774;5827.621204621729;5839.328477225131;6594.175728033996;6614.511734859666;6630.933690663128;6653.733850645968;6656.633885136315;4811.75941202942;4843.636727245471;4853.466118726938;4860.125177468054;4869.245266983623;6880.277570855038;6955.930440695579;6973.93026069743;7019.543729657556;7020.473464490184;5032.622553308516;5060.3927528696795;5065.746712664352;5079;5105.7191854448365;5054.772535235923;5161.561267292323;5168.715720635326;5173.586113110942;5181.53366197044;5617.775288988395;5720.342160651495;5761.3950535194035;5762.135679648002;5823.679697616587;6583.80248592543;6589.86820263592;6646.468282537386;6655.833604159838;6712.765053223114;1631.5105468359445;1693.4073074423852;1732.618823858749;1741.921613527369;1751.7722696049432;1178.263585259213;1198.4726720243066;1206.957756478519;1213.769383817072;1225.3138242584182;5910.52715782745;5914.556408269397;5975.342712301644;5984;5985.910211346833;6506.60960342381;6716.361945615159;6753.696083676148;6793.830154246183;6795.320467953264;5738.254026976467;5881.941180588225;5920.259967504062;5939.792107276225;5960.254968128984;3582.265635544707;3626.0934766308424;3627.818609069536;3630.927381452409;3667.94498082529;6562.507811914125;6580.309067547934;6616.371444712745;6633.303503132198;6637.236717777518;7007.8598428031155;7015.965370042631;7021.717941668978;7057.752978645723;7085.2206257311655;6453.386928241904;6500.219973603009;6580.80257592273;6582.564451153008;6592.274849766522;6776.407544227004;6842.521023528504;6860.211075726355;6899.655017249017;6916.55042422241;6746.595204287759;6749.907528666129;6756.662166891538;6769.830754231184;6771.830704232433;6498.415142637185;6501.187351581052;6553.06815910469;6601.240857301472;6608.537402381787;4954.355933728592;4988.301637770787;5016.172331565397;5023.598112151092;5075.568576670877;6790.7623233186605;6854.588724676336;6907.792766217021;6912.096919294009;6912.377885990285;6453.386928241809;6550.181227346582;6656.5340426169705;6659.567128136656;6681.832954176184;6411.3909178627955;6413.390727880844;6573.3426657333885;6626.966865165643;6654.042738160102;6223.502119830493;6468.3855033771715;6517.706703198272;6541.443977261795;6557.540972131905;6143.569950103629;6685.197776266879;6701.396874281338;6709;6764.695588698422;6432.35676432353;6435.356464353527;6483.319251478715;6537.542372033912;6538.705758240795 19:30:39 INFO - PID 18040 | |5;yui.html;880.5773228850152;883.849745543258;906.3157316226249;909.0909090909091;911.9407238529495;18327.725084123733;19203.23187072519;19249.518762030955;19251.614967700654;19277.614447711072;16728.41450549231;16816.32734690614;17319.48041558752;20804.375868723924;21099.789002109974;918.673870775875;946.6828612414849;952.5808644196566;953.918916892065;954.8329042417583;906.2930913887145;955.0497255231055;958.0993865766193;959.4818797849153;959.5633986536109;989.4508547756028;1002.578916854919;1002.6340385759182;1002.9548670309817;1003.372891942536;990.0990099009938;990.9702708918744;993.5231089077212;994.443111857355;995.1839491616877;734.4895297768053;735.0187499687923;735.2757533829174;735.3822788857385;735.5696917303384;18633.53416164593;18983.90508047451;19021.524461888424;19342.129604167847;19350.225990960345;19705.507362315915;19960.10179541924;20080;20146.899265503725;20190.49523761903;16117.113558754381;16453.25960331776;16514.2568584414;16664.41674541385;16668.166591670488;3698.4822124902535;3711.1649878777057;3723.478712980185;3736.7757934524016;3737.5141231639986;122736;124997.37501312436;125585;126148.36925815404;127987;15123.621909452131;15394.769078463833;15530.145841978574;15593.376264949502;15813.288402021937;28556.286092847844;28950.855245723636;29351.706482934896;29506.55740163899;29555.556666650016;8307.584620768937;8639;8644.049154592989;8647.913520864837;8665.393422460493;4125.154343359604;4227.133437645275;4256.893577660521;4268.996785755345;4278.422412974271;5058.392992840809;5170.250313704491;5215.947840521546;5259.211118332204;5264.842054738364;1000.7348052766023;1006.8791744990648;1015.619142821442;1017.5878769098379;1031.4017869635593;678.091357580844;690.7444245629149;702.7083760239443;709.7480394459892;716.2407847681475;10770.73073173177;10997;10999.890001100046;11065.778684426266;11182.552697892155;679.3240725478082;685.2120061928722;691.789004353677;699.9615021173782;704.7181127549021;5561.416051314634;5579.358373786985;5592.049351610236;5596.300462442195;5658.462446067617;107889;108513;112798.43600781943;116682;118516;91045.54477227571;92126.53936730273;96820.51589742006;97031;103059;59355.40644593499;81894;83147.58426207952;85619;91450;10949.87795198947;11166.72083197894;11191.720206994565;11225.382603956861;11309.660710178709;39690.60309396869;40647.796761016005;40733.38899916445;41014.38478422886;42413.93965150896;51940.74029629979;52353.47646523486;53643.46356536541;54066.72966635141;54132.72933635306;17119.433612765723;17340.56648583745;17550.12249387551;17564.82435175632;17830.10849457496;19286.324978625682;19379.321723739602;19391.903040484707;19423.223071077566;19482.123304451616;15679.216039197767;15985.920070399574;16181.75727364114;16253;16451.58871028186;15441.459548915735;15579.688406231586;15681.137537435094;15730.764038539202;15875.28561214725;14892.180930048518;14953.177575233467;14996.47512337063;15201.467948621745;15298.388064477742;10425.9478702606;10565.154787617126;10694.358338499715;11109.555617775199;11136.665900023012;11288.604898828502;11310.830337545109;11411.486483108447;11527;11682.94158529202 19:30:39 INFO - PID 18040 | __end_tp_report 19:30:39 INFO - PID 18040 | __start_cc_report 19:30:39 INFO - PID 18040 | _x_x_mozilla_cycle_collect,367 19:30:39 INFO - PID 18040 | __end_cc_report 19:30:39 INFO - PID 18040 | __startTimestamp1503801039290__endTimestamp 19:30:39 INFO - PID 18040 | 19:30:39 INFO - PID 18040 | ------- Summary: start ------- 19:30:39 INFO - PID 18040 | Number of tests: 6 19:30:39 INFO - PID 18040 | 19:30:39 INFO - PID 18040 | [#0] dojo.html Cycles:165 Average:6246.35 Median:6444.97 stddev:714.83 (11.1%) stddev-sans-first:677.19 19:30:39 INFO - PID 18040 | Values: 3247.1 3305.5 3307.6 3315.0 3352.1 6238.2 6320.7 6333.6 6336.7 6348.6 6262.0 6269.9 6370.3 6408.3 6445.0 5787.0 5833.6 5858.3 5905.9 5929.6 5794.5 5855.7 5868.4 5924.7 5999.2 5711.1 5755.9 5829.0 5900.7 5905.9 5647.5 5733.3 5741.7 5748.9 5749.8 5780.8 5796.8 5799.0 5852.4 5888.6 6218.4 6254.8 6282.6 6311.4 6370.7 6788.1 6831.0 6832.4 6835.9 6840.3 6760.9 6799.7 6826.8 6876.7 6909.3 6215.8 6268.2 6345.8 6355.2 6371.1 6899.2 6915.1 7004.1 7004.6 7041.4 6499.9 6614.3 6650.2 6651.0 6653.0 6680.7 6771.7 6831.0 6843.3 6868.4 6672.6 6695.6 6696.4 6756.4 6793.4 6614.7 6716.9 6721.5 6736.0 6823.6 5396.4 5412.6 5429.4 5442.0 5446.5 4717.3 4729.1 4732.7 4737.3 4742.6 5952.8 5980.8 5996.2 6065.3 6068.1 6725.2 6791.9 6816.1 6893.8 6893.9 5980.7 6011.9 6029.2 6031.4 6033.3 5788.7 5835.5 5915.6 5924.3 5925.8 6388.0 6420.9 6592.0 6602.9 6606.3 6729.4 6762.2 6771.5 6869.6 6872.0 6326.3 6598.7 6599.2 6638.3 6639.7 6653.8 6654.6 6668.7 6672.2 6699.3 6742.1 6789.8 6790.6 6796.9 6798.0 6565.4 6567.8 6589.6 6595.0 6601.9 6581.2 6586.0 6591.1 6593.3 6603.1 6498.7 6504.6 6518.2 6604.5 6622.4 6509.1 6547.2 6646.6 6688.1 6735.7 6358.1 6360.9 6362.7 6382.8 6394.6 19:30:39 INFO - PID 18040 | 19:30:39 INFO - PID 18040 | [#1] ext.html Cycles:180 Average:38140.30 Median:19421.99 stddev:55588.64 (286.2%) stddev-sans-first:55673.57 19:30:39 INFO - PID 18040 | Values: 719.8 734.4 750.6 775.1 777.8 40507.8 40763.4 41272.6 41325.6 41463.0 37216.3 37225.6 37343.1 37489.8 37557.1 6993.3 7087.4 7108.9 7114.2 7124.8 6939.6 7055.0 7070.4 7073.8 7074.3 7024.1 7051.7 7053.8 7057.5 7664.7 7792.4 7862.8 7870.5 7879.4 7882.6 7697.3 7725.0 7767.1 7812.4 7830.1 37407.4 37542.6 37634.6 37737.6 38481.2 3491.4 3494.0 3514.9 3530.7 3540.3 31258.2 31452.4 31475.7 31491.7 31547.0 5080.9 5092.1 5139.2 5155.7 5231.7 219063.0 222629.0 223566.0 224349.0 225132.0 47071.3 47462.0 47533.6 47569.3 47603.5 50259.5 50321.2 50331.7 50389.5 50439.5 16048.6 16209.4 16231.4 16287.5 16310.0 13231.7 13324.0 13334.5 13346.0 13347.6 13897.7 13986.8 14107.0 14164.6 14308.8 5609.1 5652.9 5657.2 5686.6 5813.7 975.5 985.5 992.2 1000.3 1002.9 27733.0 27988.0 28149.4 28411.3 28583.9 1048.6 1080.8 1089.4 1090.9 1104.4 12102.6 12211.2 12256.3 12340.4 12351.3 228641.0 232979.0 233426.0 233875.0 234487.0 42105.8 42968.8 50103.7 50143.5 50208.7 1409.7 1418.0 1421.4 1423.7 1424.6 26678.2 26820.0 26919.9 27100.6 27182.6 35313.3 35459.0 35685.6 35704.5 36004.0 184894.0 185160.0 185878.0 185974.0 186565.0 32841.0 32897.0 32909.5 32954.0 33082.3 39748.0 41374.6 41542.4 41570.4 44853.0 33096.3 33432.2 33438.3 33498.3 33693.0 32880.8 33154.7 33223.0 33560.7 33880.7 38242.2 38308.2 38431.6 38439.2 38593.8 32794.2 32952.3 32986.3 33022.8 33203.2 23812.8 24118.5 24119.2 24121.4 24495.5 19:30:39 INFO - PID 18040 | 19:30:39 INFO - PID 18040 | [#2] jquery.html Cycles:180 Average:33691.17 Median:22463.22 stddev:78005.84 (347.3%) stddev-sans-first:78184.99 19:30:39 INFO - PID 18040 | Values: 552.3 563.7 564.8 569.8 586.6 21597.4 22409.8 22449.0 22477.4 22547.9 23576.3 25980.5 26049.1 26065.7 26266.6 6676.6 6842.8 6844.0 6844.1 6846.6 6606.1 6674.0 6722.9 6731.4 6732.3 4128.2 4130.8 5186.0 6337.2 6693.6 6386.9 6416.7 6425.0 6429.7 6433.6 5912.8 5929.2 5946.6 5953.6 5962.7 27733.2 28219.4 28665.4 28720.4 28780.6 2070.9 2090.9 2100.7 2101.1 2106.4 19267.0 19783.3 19795.3 19859.2 19877.3 2412.7 2446.4 2502.4 2508.3 2529.3 129639.0 130422.0 133598.0 134378.3 134749.3 13888.6 14343.8 14379.3 14864.6 14953.9 22725.0 22763.3 23022.8 23026.3 23062.7 9835.1 10012.2 10222.2 10295.5 10350.0 9548.2 9594.4 9676.3 9677.6 9724.8 8647.2 8671.3 8958.9 9154.0 9239.5 2623.3 2729.5 2760.0 2765.3 2774.6 941.2 967.4 968.7 974.8 988.3 19077.9 19452.0 19559.2 19759.7 19799.6 999.3 1026.9 1039.9 1041.9 1049.0 10707.6 10709.7 10721.0 11067.9 11103.2 449380.0 462655.0 463474.0 463931.0 468392.0 111734.4 112500.4 113212.4 113547.0 115189.0 1148.9 1161.9 1189.8 1194.0 1199.9 17087.8 17458.6 17612.0 17748.8 18157.4 56217.7 56332.0 56349.7 57537.0 58614.4 72514.3 72788.0 73102.0 74025.0 74133.0 13893.4 14185.3 14247.4 14312.9 14356.4 22010.6 22081.7 22114.9 22117.9 22240.9 17685.4 17765.2 18023.2 18025.8 18028.5 16182.4 16323.6 16359.8 16689.5 17476.9 18806.7 19307.5 19388.9 19446.9 19709.6 13738.1 14406.6 14408.4 14417.4 14679.0 18020.2 18204.0 18378.5 18429.0 18709.0 19:30:39 INFO - PID 18040 | 19:30:39 INFO - PID 18040 | [#3] mootools.html Cycles:180 Average:8874.69 Median:2747.61 stddev:6321.98 (230.1%) stddev-sans-first:6313.96 19:30:39 INFO - PID 18040 | Values: 1280.6 1312.3 1336.7 1339.4 1344.0 14212.4 14679.6 14758.7 14773.2 14789.4 12179.1 12631.7 14337.6 14876.4 14892.9 2757.3 2761.6 2781.2 2784.8 2785.8 2682.5 2737.7 2764.0 2766.2 2768.6 2653.4 2858.8 2866.6 2869.5 2871.2 2796.4 2827.8 2835.0 2854.3 2892.7 2713.9 2735.7 2737.9 2758.4 2773.2 14426.6 14561.6 14600.6 14705.2 14736.0 7831.6 8111.0 8129.7 8142.7 8161.6 5354.9 5389.4 5403.0 5413.4 5515.4 1866.9 1884.7 1903.4 1932.8 1936.2 25312.4 25612.6 25633.0 25685.7 25753.4 6421.8 6590.0 6645.7 6776.8 6820.0 16652.3 16764.3 16782.0 16784.7 16810.6 7378.4 7448.7 7448.8 7472.7 7490.3 6802.2 6869.4 6895.7 6951.4 7070.5 5035.2 5142.9 5204.4 5226.0 5270.1 1367.6 1400.8 1416.7 1445.1 1474.9 796.9 821.3 829.7 850.4 852.9 7340.2 7643.7 7708.3 7774.9 7875.9 955.7 970.1 972.9 977.0 978.0 4054.6 4132.0 4150.3 4237.5 4240.3 19477.6 20513.8 20534.7 20645.4 20656.8 19037.0 19674.5 20040.4 20294.6 20381.9 10870.8 10929.5 11021.8 11138.4 11188.8 7188.0 7434.8 7443.2 7516.8 7550.1 17116.0 17416.5 17639.9 17802.4 18003.6 17898.9 17975.0 17999.9 18085.5 18152.5 10662.0 10683.9 10700.2 10741.1 10757.8 13280.7 13330.8 13392.7 13452.1 13593.3 10822.1 10879.6 11132.2 11142.8 11321.1 10638.8 10743.6 10804.2 10925.6 11076.6 8343.2 8493.6 8516.1 8520.9 8523.6 6733.8 6792.4 6826.9 6907.6 6938.4 6332.0 6486.7 6614.1 6644.2 6655.1 19:30:39 INFO - PID 18040 | 19:30:39 INFO - PID 18040 | [#4] prototype.html Cycles:180 Average:5577.31 Median:6202.00 stddev:1574.99 (25.4%) stddev-sans-first:1532.61 19:30:39 INFO - PID 18040 | Values: 499.8 499.9 515.8 520.6 522.5 6217.3 6252.3 6379.2 6383.3 6402.2 6050.8 6184.2 6201.4 6202.6 6305.6 5059.0 5094.8 5116.5 5120.8 5147.6 5027.9 5061.6 5113.2 5113.3 5119.9 4764.3 4966.4 4996.4 5015.5 5017.4 5017.8 5022.4 5033.2 5039.4 5064.2 4495.5 4496.3 4504.9 4506.8 4531.7 6084.0 6180.2 6215.9 6245.8 6257.2 5780.1 5781.1 5801.4 5827.6 5839.3 6594.2 6614.5 6630.9 6653.7 6656.6 4811.8 4843.6 4853.5 4860.1 4869.2 6880.3 6955.9 6973.9 7019.5 7020.5 5032.6 5060.4 5065.7 5079.0 5105.7 5054.8 5161.6 5168.7 5173.6 5181.5 5617.8 5720.3 5761.4 5762.1 5823.7 6583.8 6589.9 6646.5 6655.8 6712.8 1631.5 1693.4 1732.6 1741.9 1751.8 1178.3 1198.5 1207.0 1213.8 1225.3 5910.5 5914.6 5975.3 5984.0 5985.9 6506.6 6716.4 6753.7 6793.8 6795.3 5738.3 5881.9 5920.3 5939.8 5960.3 3582.3 3626.1 3627.8 3630.9 3667.9 6562.5 6580.3 6616.4 6633.3 6637.2 7007.9 7016.0 7021.7 7057.8 7085.2 6453.4 6500.2 6580.8 6582.6 6592.3 6776.4 6842.5 6860.2 6899.7 6916.6 6746.6 6749.9 6756.7 6769.8 6771.8 6498.4 6501.2 6553.1 6601.2 6608.5 4954.4 4988.3 5016.2 5023.6 5075.6 6790.8 6854.6 6907.8 6912.1 6912.4 6453.4 6550.2 6656.5 6659.6 6681.8 6411.4 6413.4 6573.3 6627.0 6654.0 6223.5 6468.4 6517.7 6541.4 6557.5 6143.6 6685.2 6701.4 6709.0 6764.7 6432.4 6435.4 6483.3 6537.5 6538.7 19:30:39 INFO - PID 18040 | 19:30:39 INFO - PID 18040 | [#5] yui.html Cycles:180 Average:22500.88 Median:20113.45 stddev:31496.20 (156.6%) stddev-sans-first:31542.71 19:30:39 INFO - PID 18040 | Values: 880.6 883.8 906.3 909.1 911.9 18327.7 19203.2 19249.5 19251.6 19277.6 16728.4 16816.3 17319.5 20804.4 21099.8 918.7 946.7 952.6 953.9 954.8 906.3 955.0 958.1 959.5 959.6 989.5 1002.6 1002.6 1003.0 1003.4 990.1 991.0 993.5 994.4 995.2 734.5 735.0 735.3 735.4 735.6 18633.5 18983.9 19021.5 19342.1 19350.2 19705.5 19960.1 20080.0 20146.9 20190.5 16117.1 16453.3 16514.3 16664.4 16668.2 3698.5 3711.2 3723.5 3736.8 3737.5 122736.0 124997.4 125585.0 126148.4 127987.0 15123.6 15394.8 15530.1 15593.4 15813.3 28556.3 28950.9 29351.7 29506.6 29555.6 8307.6 8639.0 8644.0 8647.9 8665.4 4125.2 4227.1 4256.9 4269.0 4278.4 5058.4 5170.3 5215.9 5259.2 5264.8 1000.7 1006.9 1015.6 1017.6 1031.4 678.1 690.7 702.7 709.7 716.2 10770.7 10997.0 10999.9 11065.8 11182.6 679.3 685.2 691.8 700.0 704.7 5561.4 5579.4 5592.0 5596.3 5658.5 107889.0 108513.0 112798.4 116682.0 118516.0 91045.5 92126.5 96820.5 97031.0 103059.0 59355.4 81894.0 83147.6 85619.0 91450.0 10949.9 11166.7 11191.7 11225.4 11309.7 39690.6 40647.8 40733.4 41014.4 42413.9 51940.7 52353.5 53643.5 54066.7 54132.7 17119.4 17340.6 17550.1 17564.8 17830.1 19286.3 19379.3 19391.9 19423.2 19482.1 15679.2 15985.9 16181.8 16253.0 16451.6 15441.5 15579.7 15681.1 15730.8 15875.3 14892.2 14953.2 14996.5 15201.5 15298.4 10425.9 10565.2 10694.4 11109.6 11136.7 11288.6 11310.8 11411.5 11527.0 11682.9 19:30:39 INFO - PID 18040 | -------- Summary: end -------- 19:30:39 INFO - PID 18040 | 19:30:40 INFO - TEST-INFO | 18040: exit 0 19:30:40 INFO - TEST-OK | dromaeo_css | took 1114130ms 19:30:40 INFO - TEST-START | kraken 19:30:40 INFO - Initialising browser for kraken test... 19:30:40 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46201/getInfo.html -profile /tmp/tmp8r4rq5/profile 19:30:40 INFO - TEST-INFO | started process 18468 (/builds/slave/test/build/application/firefox/firefox http://localhost:46201/getInfo.html) 19:30:46 INFO - TEST-INFO | 18468: exit 0 19:30:46 INFO - Browser initialized. 19:30:46 INFO - Running cycle 1/1 for kraken test... 19:30:46 INFO - Using env: {'DISPLAY': ':0', 19:30:46 INFO - 'HOME': '/home/cltbld', 19:30:46 INFO - 'JSGC_DISABLE_POISONING': '1', 19:30:46 INFO - 'LANG': 'en_US.UTF-8', 19:30:46 INFO - 'LANGUAGE': 'en_US:en', 19:30:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:30:46 INFO - 'LOGNAME': 'cltbld', 19:30:46 INFO - 'MAIL': '/var/mail/cltbld', 19:30:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:30:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:30:46 INFO - 'MOZ_ACCELERATED': '1', 19:30:46 INFO - 'MOZ_CRASHREPORTER': '1', 19:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:30:46 INFO - 'MOZ_NO_REMOTE': '1', 19:30:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:30:46 INFO - 'MOZ_WEBRENDER': '1', 19:30:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:30:46 INFO - 'NO_EM_RESTART': '1', 19:30:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:46 INFO - 'PWD': '/builds/slave/test', 19:30:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:30:46 INFO - 'RUST_BACKTRACE': 'full', 19:30:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:30:46 INFO - 'SHELL': '/bin/bash', 19:30:46 INFO - 'SHLVL': '1', 19:30:46 INFO - 'TERM': 'linux', 19:30:46 INFO - 'TMOUT': '86400', 19:30:46 INFO - 'USER': 'cltbld', 19:30:46 INFO - 'XDG_SESSION_COOKIE': '199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226', 19:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:46 INFO - '_': '/tools/buildbot/bin/python'} 19:30:46 INFO - TEST-INFO | started process 18655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8r4rq5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:30:47 INFO - PID 18655 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 19:30:47 INFO - PID 18655 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42 19:30:47 INFO - PID 18655 | 19:30:47 INFO - PID 18655 | (/builds/slave/test/build/application/firefox/firefox:18714): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:30:47 INFO - PID 18655 | 19:30:48 INFO - PID 18655 | 19:30:48 INFO - PID 18655 | (/builds/slave/test/build/application/firefox/firefox:18767): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:30:48 INFO - PID 18655 | 19:30:48 INFO - PID 18655 | __metrics Screen width/height:1600/1200 19:30:48 INFO - PID 18655 | colorDepth:24 19:30:48 INFO - PID 18655 | Browser inner width/height: 1024/768 19:30:48 INFO - PID 18655 | __metrics 19:30:57 INFO - PID 18655 | RSS: Main: 201773056 19:30:57 INFO - PID 18655 | 19:31:00 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46201/tests/kraken/driver.html?testName=audio-beat-detection) 19:31:01 INFO - PID 18655 | RSS: Main: 207757312 19:31:01 INFO - PID 18655 | 19:31:04 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46201/tests/kraken/driver.html?testName=audio-dft) 19:31:04 INFO - PID 18655 | RSS: Main: 206434304 19:31:04 INFO - PID 18655 | 19:31:07 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46201/tests/kraken/driver.html?testName=audio-fft) 19:31:08 INFO - PID 18655 | RSS: Main: 202174464 19:31:08 INFO - PID 18655 | 19:31:10 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46201/tests/kraken/driver.html?testName=audio-oscillator) 19:31:11 INFO - PID 18655 | RSS: Main: 202502144 19:31:11 INFO - PID 18655 | 19:31:13 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46201/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:31:14 INFO - PID 18655 | RSS: Main: 202563584 19:31:14 INFO - PID 18655 | 19:31:17 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46201/tests/kraken/driver.html?testName=imaging-darkroom) 19:31:17 INFO - PID 18655 | RSS: Main: 204320768 19:31:17 INFO - PID 18655 | 19:31:21 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46201/tests/kraken/driver.html?testName=imaging-desaturate) 19:31:22 INFO - PID 18655 | RSS: Main: 203796480 19:31:22 INFO - PID 18655 | 19:31:25 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46201/tests/kraken/driver.html?testName=json-parse-financial) 19:31:25 INFO - PID 18655 | RSS: Main: 205107200 19:31:25 INFO - PID 18655 | 19:31:27 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46201/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:31:27 INFO - PID 18655 | RSS: Main: 204365824 19:31:27 INFO - PID 18655 | 19:31:29 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46201/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:31:30 INFO - PID 18655 | RSS: Main: 204591104 19:31:30 INFO - PID 18655 | 19:31:32 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46201/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:31:33 INFO - PID 18655 | RSS: Main: 204304384 19:31:33 INFO - PID 18655 | 19:31:35 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46201/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:31:36 INFO - PID 18655 | RSS: Main: 205713408 19:31:36 INFO - PID 18655 | 19:31:39 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46201/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:31:40 INFO - PID 18655 | RSS: Main: 204177408 19:31:40 INFO - PID 18655 | 19:31:41 INFO - PID 18655 | Cycle 1(1): loaded http://localhost:46201/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46201/tests/kraken/driver.html?testName=ai-astar) 19:31:42 INFO - PID 18655 | RSS: Main: 208035840 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | __start_tp_report 19:31:42 INFO - PID 18655 | _x_x_mozilla_page_load 19:31:42 INFO - PID 18655 | _x_x_mozilla_page_load_details 19:31:42 INFO - PID 18655 | |i|pagename|runs| 19:31:42 INFO - PID 18655 | |0;ai-astar;102;113;101;115;117;115;109;97;113;119 19:31:42 INFO - PID 18655 | |1;audio-beat-detection;148;118;147;178;119;167;144;121;178;125 19:31:42 INFO - PID 18655 | |2;audio-dft;164;169;175;170;164;167;170;165;177;167 19:31:42 INFO - PID 18655 | |3;audio-fft;74;78;73;170;78;74;82;98;95;75 19:31:42 INFO - PID 18655 | |4;audio-oscillator;79;79;136;80;79;83;122;90;80;124 19:31:42 INFO - PID 18655 | |5;imaging-gaussian-blur;118;114;118;113;115;119;120;121;114;117 19:31:42 INFO - PID 18655 | |6;imaging-darkroom;149;157;148;149;149;149;150;149;158;149 19:31:42 INFO - PID 18655 | |7;imaging-desaturate;96;94;102;95;94;104;94;100;100;94 19:31:42 INFO - PID 18655 | |8;json-parse-financial;52;58;54;57;52;53;54;53;55;52 19:31:42 INFO - PID 18655 | |9;json-stringify-tinderbox;40;38;42;38;39;39;37;42;38;45 19:31:42 INFO - PID 18655 | |10;stanford-crypto-aes;78;81;79;80;75;83;74;89;78;76 19:31:42 INFO - PID 18655 | |11;stanford-crypto-ccm;127;133;126;118;135;135;126;119;116;156 19:31:42 INFO - PID 18655 | |12;stanford-crypto-pbkdf2;176;184;182;173;173;172;171;174;174;178 19:31:42 INFO - PID 18655 | |13;stanford-crypto-sha256-iterative;68;64;64;64;67;65;66;65;64;72 19:31:42 INFO - PID 18655 | __end_tp_report 19:31:42 INFO - PID 18655 | __start_cc_report 19:31:42 INFO - PID 18655 | _x_x_mozilla_cycle_collect,951 19:31:42 INFO - PID 18655 | __end_cc_report 19:31:42 INFO - PID 18655 | __startTimestamp1503801102215__endTimestamp 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | ------- Summary: start ------- 19:31:42 INFO - PID 18655 | Number of tests: 14 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#0] ai-astar Cycles:10 Average:110.10 Median:115.00 stddev:7.55 (6.6%) stddev-sans-first:7.42 19:31:42 INFO - PID 18655 | Values: 102.0 113.0 101.0 115.0 117.0 115.0 109.0 97.0 113.0 119.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#1] audio-beat-detection Cycles:10 Average:144.50 Median:147.50 stddev:23.70 (16.1%) stddev-sans-first:25.10 19:31:42 INFO - PID 18655 | Values: 148.0 118.0 147.0 178.0 119.0 167.0 144.0 121.0 178.0 125.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#2] audio-dft Cycles:10 Average:168.80 Median:169.50 stddev:4.42 (2.6%) stddev-sans-first:4.33 19:31:42 INFO - PID 18655 | Values: 164.0 169.0 175.0 170.0 164.0 167.0 170.0 165.0 177.0 167.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#3] audio-fft Cycles:10 Average:89.70 Median:78.00 stddev:29.56 (37.9%) stddev-sans-first:30.81 19:31:42 INFO - PID 18655 | Values: 74.0 78.0 73.0 170.0 78.0 74.0 82.0 98.0 95.0 75.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:79.50 stddev:22.70 (28.6%) stddev-sans-first:23.31 19:31:42 INFO - PID 18655 | Values: 79.0 79.0 136.0 80.0 79.0 83.0 122.0 90.0 80.0 124.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:118.00 stddev:2.77 (2.3%) stddev-sans-first:2.91 19:31:42 INFO - PID 18655 | Values: 118.0 114.0 118.0 113.0 115.0 119.0 120.0 121.0 114.0 117.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#6] imaging-darkroom Cycles:10 Average:150.70 Median:149.00 stddev:3.62 (2.4%) stddev-sans-first:3.79 19:31:42 INFO - PID 18655 | Values: 149.0 157.0 148.0 149.0 149.0 149.0 150.0 149.0 158.0 149.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:94.00 stddev:3.83 (4.1%) stddev-sans-first:4.03 19:31:42 INFO - PID 18655 | Values: 96.0 94.0 102.0 95.0 94.0 104.0 94.0 100.0 100.0 94.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:2.11 (3.9%) stddev-sans-first:2.11 19:31:42 INFO - PID 18655 | Values: 52.0 58.0 54.0 57.0 52.0 53.0 54.0 53.0 55.0 52.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#9] json-stringify-tinderbox Cycles:10 Average:39.80 Median:39.50 stddev:2.49 (6.3%) stddev-sans-first:2.64 19:31:42 INFO - PID 18655 | Values: 40.0 38.0 42.0 38.0 39.0 39.0 37.0 42.0 38.0 45.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#10] stanford-crypto-aes Cycles:10 Average:79.30 Median:79.50 stddev:4.37 (5.5%) stddev-sans-first:4.61 19:31:42 INFO - PID 18655 | Values: 78.0 81.0 79.0 80.0 75.0 83.0 74.0 89.0 78.0 76.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#11] stanford-crypto-ccm Cycles:10 Average:129.10 Median:130.00 stddev:11.69 (9.0%) stddev-sans-first:12.37 19:31:42 INFO - PID 18655 | Values: 127.0 133.0 126.0 118.0 135.0 135.0 126.0 119.0 116.0 156.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:175.00 stddev:4.35 (2.5%) stddev-sans-first:4.61 19:31:42 INFO - PID 18655 | Values: 176.0 184.0 182.0 173.0 173.0 172.0 171.0 174.0 174.0 178.0 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:65.50 stddev:2.56 (3.9%) stddev-sans-first:2.60 19:31:42 INFO - PID 18655 | Values: 68.0 64.0 64.0 64.0 67.0 65.0 66.0 65.0 64.0 72.0 19:31:42 INFO - PID 18655 | -------- Summary: end -------- 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 19:31:42 INFO - PID 18655 | 19:31:42 INFO - PID 18655 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:31:43 INFO - TEST-INFO | 18655: exit 0 19:31:43 INFO - TEST-OK | kraken | took 63114ms 19:31:43 INFO - SUITE-END | took 1177s 19:31:43 INFO - Completed test suite (00:19:37) 19:31:43 INFO - javascript benchmark 19:31:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8410.343816965791, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3247.123276715287, 3305.520699498573, 3307.58655168104, 3315.0054983504942, 3352.1452029732413, 6238.157848690425, 6320.715567799454, 6333.5883167594075, 6336.683165841707, 6348.58734182279, 6262.0, 6269.905951410732, 6370.267419246788, 6408.327125651809, 6444.96777516113, 5786.971065144689, 5833.649981001143, 5858.297004359483, 5905.852353691149, 5929.644221346712, 5794.536437085023, 5855.677937713424, 5868.413158684119, 5924.7333869975955, 5999.160117583542, 5711.057675483561, 5755.942440575583, 5828.97085514574, 5900.734466948998, 5905.881882362351, 5647.519960803336, 5733.340665823442, 5741.655500669973, 5748.856278593069, 5749.82750517481, 5780.797672081499, 5796.797112101056, 5798.971005144989, 5852.356240813507, 5888.617239879394, 6218.440340369389, 6254.781082662085, 6282.560220784547, 6311.400416960381, 6370.681465926685, 6788.083608712859, 6830.965845170792, 6832.419244364235, 6835.897461538081, 6840.31596840317, 6760.898586521206, 6799.660016999131, 6826.829329266759, 6876.724931002754, 6909.2745261747295, 6215.844603884849, 6268.1851359323, 6345.8413539661415, 6355.173827402455, 6371.139896114058, 6899.206591241971, 6915.101036865175, 7004.089468369079, 7004.649767511604, 7041.40148087408, 6499.870002600016, 6614.305497922745, 6650.168728908887, 6651.0, 6653.0, 6680.699368528428, 6771.72913083481, 6831.009503621947, 6843.349881761224, 6868.416184624263, 6672.599644021374, 6695.631740254333, 6696.43080338167, 6756.425703815231, 6793.388595026471, 6614.669266536654, 6716.932830671729, 6721.462283017347, 6736.0, 6823.590584564942, 5396.4333744957, 5412.648177868426, 5429.375621803464, 5442.047641662693, 5446.455354464521, 4717.3159891816185, 4729.148753224453, 4732.716037037789, 4737.265723812815, 4742.5731684148595, 5952.791652292149, 5980.760769569256, 5996.220491336186, 6065.272167339947, 6068.089786532056, 6725.226598941184, 6791.864162716817, 6816.113905192294, 6893.7931862044215, 6893.896591551131, 5980.700964951735, 6011.879762404727, 6029.216201893726, 6031.4270144336215, 6033.276006879202, 5788.652680839163, 5835.503982161479, 5915.645061296336, 5924.259467566554, 5925.822225333247, 6387.968060159669, 6420.871582568322, 6591.967040164865, 6602.900956485657, 6606.339366063356, 6729.394354508117, 6762.188537375547, 6771.492138089565, 6869.622170780455, 6871.9656401717675, 6326.272478665012, 6598.670066496752, 6599.241087274832, 6638.269790322964, 6639.734410623521, 6653.800385988428, 6654.600723956578, 6668.733250669919, 6672.232693240339, 6699.330066993261, 6742.123523941857, 6789.762358317633, 6790.558613690095, 6796.932030679828, 6798.0, 6565.44193743518, 6567.802965911031, 6589.604623722592, 6594.967025165035, 6601.910684737157, 6581.177352830896, 6586.0, 6591.077249185022, 6593.274739778716, 6603.108580341785, 6498.675066246763, 6504.609723416421, 6518.152640156749, 6604.537682362188, 6622.437092847162, 6509.088727578238, 6547.181602299713, 6646.567973081735, 6688.130543029375, 6735.663216839236, 6358.109864619049, 6360.936390636034, 6362.713677884413, 6382.840428989312, 6394.552381333262], "value": 6194.892247282501, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [719.7768691705571, 734.3904559215852, 750.5534207146748, 775.1318523253957, 777.8249893773898, 40507.79746101269, 40763.38854917175, 41272.58727412733, 41325.586744132546, 41463.0, 37216.25567488655, 37225.62774372256, 37343.06642333943, 37489.812550937204, 37557.061073473174, 6993.300669933004, 7087.397571206454, 7108.85782284354, 7114.181869085044, 7124.821879453003, 6939.5836249824915, 7055.0, 7070.363667269945, 7073.823154421129, 7074.292570742936, 7024.121984751906, 7051.717931282743, 7053.753118640849, 7057.505974581782, 7664.655090520912, 7792.376609871225, 7862.842743145162, 7870.488418252795, 7879.409044321642, 7882.605869706492, 7697.30724234816, 7725.0, 7767.145613982458, 7812.375009999243, 7830.060392752906, 37407.4388884167, 37542.62457375418, 37634.62365376366, 37737.622623773685, 38481.23037539262, 3491.4064609016527, 3494.0391392367046, 3514.9297014059575, 3530.682238598513, 3540.345036168317, 31258.21854453634, 31452.37095258082, 31475.685243147505, 31491.685083149103, 31547.0, 5080.872978175538, 5092.134337162691, 5139.15203991341, 5155.690658560461, 5231.738413079369, 219063.0, 222629.0, 223566.0, 224349.0, 225131.99999999837, 47071.29393059107, 47462.0, 47533.57399278027, 47569.286460703115, 47603.52396475991, 50259.49740502548, 50321.24518132231, 50331.74834125879, 50389.49610503848, 50439.49560504348, 16048.598785030234, 16209.351625935067, 16231.350745970029, 16287.51137465878, 16310.0, 13231.66920826987, 13324.0, 13334.533291334757, 13346.0, 13347.599572012856, 13897.652558686113, 13986.790198147037, 14107.0, 14164.645883852987, 14308.785368219485, 5609.1305847593685, 5652.8869422611315, 5657.151427285858, 5686.5735069869925, 5813.680247586423, 975.4683697384801, 985.4875464758286, 992.1920963672789, 1000.2947921715173, 1002.8696269485066, 27733.0, 27988.0, 28149.43701125966, 28411.28971775681, 28583.857080714464, 1048.632978457549, 1080.778440419712, 1089.428050273597, 1090.929089609173, 1104.376027544435, 12102.576409825613, 12211.206271592495, 12256.264624122581, 12340.382980850922, 12351.25892446456, 228641.0, 232979.0, 233426.0, 233875.0, 234487.0, 42105.78947105245, 42968.78515607464, 50103.74948125236, 50143.49856501461, 50208.74895625498, 1409.7180563887264, 1418.0003097816016, 1421.4456362019027, 1423.7366087273888, 1424.622475044114, 26678.199654010412, 26820.0, 26919.865400672872, 27100.593491098043, 27182.592261116493, 35313.29373412466, 35459.0, 35685.64314356927, 35704.464433034045, 36004.0, 184894.0, 185160.0, 185878.0, 185974.0, 186565.0, 32841.0, 32897.0, 32909.50635740418, 32954.0, 33082.33835323328, 39748.0, 41374.58625413707, 41542.376864347665, 41570.376444352754, 44853.0, 33096.33807323888, 33432.1641958953, 33438.32493400473, 33498.330033398706, 33693.0, 32880.83559582187, 33154.668453316124, 33223.00330990074, 33560.66439335576, 33880.66119338775, 38242.235155296185, 38308.23383532369, 38431.61568384281, 38439.231215376094, 38593.807030965785, 32794.18014549655, 32952.34095318128, 32986.34027319488, 33022.83488582542, 33203.169920752174, 23812.761872381056, 24118.51762964766, 24119.15582954588, 24121.39696507531, 24495.51008979846], "value": 17093.607749862964, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.3344370034109, 563.7463141586287, 564.785381555009, 569.8176583493282, 586.5952492779982, 21597.352079437624, 22409.775902240974, 22449.0, 22477.43806404841, 22547.887260563675, 23576.29271121869, 25980.480390392182, 26049.088281910135, 26065.739342606616, 26266.60600091, 6676.599404035761, 6842.760503382383, 6843.965780171092, 6844.076049733272, 6846.5549739267035, 6606.075149479077, 6674.0, 6722.93277067228, 6731.394174524292, 6732.25945146036, 4128.21563902859, 4130.752154870703, 5186.014657215136, 6337.207849018872, 6693.564918280321, 6386.9041964370335, 6416.743330266784, 6425.0, 6429.678516074177, 6433.646149461778, 5912.793052243151, 5929.199558059693, 5946.5540084493405, 5953.58324917256, 5962.672053037125, 27733.168004959884, 28219.435611287863, 28665.426691466055, 28720.425591488263, 28780.568291475643, 2070.8861012644447, 2090.864093833896, 2100.674395468705, 2101.13853320138, 2106.3786183075877, 19267.0, 19783.307584234626, 19795.30716424918, 19859.205631774712, 19877.30429434977, 2412.7345991940874, 2446.425090103824, 2502.399424138212, 2508.3102146909564, 2529.3297276221815, 129639.0, 130422.0, 133598.0, 134378.3281083598, 134749.32625336907, 13888.58334249954, 14343.78484322736, 14379.281035948161, 14864.581146336403, 14953.925230373778, 22724.97737601812, 22763.317100487013, 23022.7697723024, 23026.309210723706, 23062.65406018911, 9835.114839664464, 10012.199024078056, 10222.182225421948, 10295.536700848479, 10350.0, 9548.236141108695, 9594.376365536358, 9676.322657413913, 9677.612895484242, 9724.75688107803, 8647.221750042527, 8671.306295496344, 8958.86561701575, 9154.0, 9239.491827949392, 2623.3048242215827, 2729.481398534272, 2760.0, 2765.294849813325, 2774.625425567563, 941.1811723800338, 967.4388854464394, 968.651285537206, 974.8204672346399, 988.2983082011708, 19077.90461047686, 19452.0, 19559.217631294872, 19759.703604445946, 19799.60400791976, 999.3304485994402, 1026.9024442677933, 1039.932404393712, 1041.8853926068127, 1048.99475502622, 10707.571697132184, 10709.732256693645, 10721.0, 11067.889321106846, 11103.167262455348, 449380.0, 462655.0, 463474.0, 463931.0, 468392.0, 111734.44132779284, 112500.43749781199, 113212.43393782979, 113547.0, 115189.0, 1148.9253198542235, 1161.8663853656938, 1189.8096304591224, 1193.964181074569, 1199.8560172779323, 17087.82912170912, 17458.563535911704, 17612.0, 17748.822511774717, 18157.36449224271, 56217.71891140518, 56332.0, 56349.71825140848, 57537.0, 58614.4138558626, 72514.27485725076, 72788.0, 73102.0, 74025.0, 74133.0, 13893.374798133906, 14185.290735463392, 14247.358868851134, 14312.928435357757, 14356.353964071433, 22010.559788803814, 22081.668774968068, 22114.88942555277, 22117.889410552845, 22240.88879555592, 17685.38101166453, 17765.200565974304, 18023.188956496728, 18025.819741802414, 18028.459146225636, 16182.352705891632, 16323.59191020234, 16359.836401636307, 16689.49931502057, 17476.912615436842, 18806.717899231797, 19307.51731206731, 19388.903055484632, 19446.902765486084, 19709.60580788405, 13738.10702304347, 14406.55934406551, 14408.351624177147, 14417.42330306776, 14679.045862018935, 18020.18909149118, 18204.0, 18378.540536486697, 18429.0, 18709.0], "value": 11824.674441326459, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1280.5518068675963, 1312.2979206124726, 1336.7059246965664, 1339.3838834136297, 1344.0322967463424, 14212.431502739879, 14679.559613211608, 14758.704825903476, 14773.187474688888, 14789.408423663053, 12179.086568507375, 12631.684207894807, 14337.641558961031, 14876.404943802261, 14892.925535372335, 2757.296889293227, 2761.64098667174, 2781.193453898367, 2784.8050636455355, 2785.832850029005, 2682.450097729961, 2737.712540183282, 2764.04640399063, 2766.2392841968417, 2768.5708715149185, 2653.3764565327133, 2858.757005654522, 2866.641669791276, 2869.469148207588, 2871.1530098620956, 2796.4267325198284, 2827.7737780977473, 2835.0219174384806, 2854.314964408548, 2892.681805001448, 2713.8778754956074, 2735.6717193936656, 2737.931551711203, 2758.3517873299757, 2773.1541879726674, 14426.567202983822, 14561.563153105319, 14600.634984125376, 14705.191214483199, 14736.0, 7831.6475758591005, 8111.0, 8129.715459958932, 8142.674293028272, 8161.55111468869, 5354.9196762048605, 5389.380221274564, 5402.9729851350885, 5413.404525502231, 5515.393306736256, 1866.9066546672611, 1884.7172924061363, 1903.4194570655945, 1932.8357089647398, 1936.1868015433552, 25312.367190820194, 25612.615810762854, 25633.0, 25685.74314256871, 25753.35616609581, 6421.839454013687, 6590.044443555657, 6645.734170633121, 6776.796696099125, 6819.977003449522, 16652.250648720837, 16764.329426823035, 16782.0, 16784.741144414216, 16810.579735506464, 7378.40972722181, 7448.73929412468, 7448.813779655444, 7472.663730132157, 7490.250974902466, 6802.217744959294, 6869.416099631588, 6895.724171033203, 6951.409130223988, 7070.540414873017, 5035.212499562603, 5142.87142821425, 5204.375474943031, 5225.980933717965, 5270.051390749702, 1367.6375760423496, 1400.8319001719656, 1416.7237388709102, 1445.1329202478428, 1474.8525147485166, 796.9083555391088, 821.3429256594819, 829.6847198064818, 850.37497439382, 852.8976522817302, 7340.192578816326, 7643.7324693635455, 7708.344790692854, 7774.922250777533, 7875.881861772078, 955.6702937486556, 970.0638883477478, 972.91243788058, 976.9853452198223, 978.0366339155926, 4054.5525237521115, 4131.958680413218, 4150.252954468165, 4237.533871274158, 4240.279152544075, 19477.610447790965, 20513.794862051487, 20534.69197962032, 20645.380638580868, 20656.793432065788, 19036.952967586916, 19674.508137296394, 20040.398788036382, 20294.594108117755, 20381.898090509454, 10870.782584348426, 10929.453527323602, 11021.83467247976, 11138.3873886937, 11188.776224475465, 7187.9640601796655, 7434.7769566913075, 7443.218462061622, 7516.774496765106, 7550.131734850563, 17116.0, 17416.47750567485, 17639.911800440917, 17802.37691680785, 18003.639927201646, 17898.91050544739, 17975.0, 17999.910000449912, 18085.457436276934, 18152.45542633723, 10662.0, 10683.94658026705, 10700.197485188486, 10741.087007604128, 10757.784844303227, 13280.667983300495, 13330.800037999245, 13392.665183370495, 13452.058355915384, 13593.252371119683, 10822.080123189617, 10879.619213327496, 11132.1650876183, 11142.832857507306, 11321.09431245482, 10638.787224255626, 10743.623973160902, 10804.189685773441, 10925.617603383844, 11076.612318568812, 8343.249107580346, 8493.617787199468, 8516.063233044484, 8520.914790852012, 8523.573821309034, 6733.764318248838, 6792.422644075111, 6826.897596536155, 6907.620080895599, 6938.444924405934, 6331.96834015827, 6486.708098135501, 6614.140161778938, 6644.169478815148, 6655.101561289164], "value": 6398.48346651065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.84100484935203, 499.8745393626597, 515.7988384530032, 520.6485622205012, 522.5401646551035, 6217.347178546255, 6252.3122456529745, 6379.17070780799, 6383.297837237912, 6402.199725034371, 6050.788222412217, 6184.226971628546, 6201.379862013797, 6202.627842329463, 6305.621662700241, 5059.0, 5094.834695254502, 5116.462771409004, 5120.771014956402, 5147.639665223436, 5027.949720502785, 5061.595072394218, 5113.181890897457, 5113.335266415379, 5119.897602047957, 4764.331974094501, 4966.428860681014, 4996.425411077718, 5015.523525265112, 5017.397912250517, 5017.774200161001, 5022.447530771612, 5033.169527028036, 5039.420466646346, 5064.215046667772, 4495.505494395647, 4496.2581174106235, 4504.8873778155485, 4506.819727210907, 4531.705439146478, 6084.0, 6180.227471566054, 6215.850067737483, 6245.781397651106, 6257.186565746424, 5780.075187969905, 5781.075027995543, 5801.374141161774, 5827.621204621729, 5839.328477225131, 6594.175728033996, 6614.511734859666, 6630.933690663128, 6653.733850645968, 6656.633885136315, 4811.75941202942, 4843.636727245471, 4853.466118726938, 4860.125177468054, 4869.245266983623, 6880.277570855038, 6955.930440695579, 6973.93026069743, 7019.543729657556, 7020.473464490184, 5032.622553308516, 5060.3927528696795, 5065.746712664352, 5079.0, 5105.7191854448365, 5054.772535235923, 5161.561267292323, 5168.715720635326, 5173.586113110942, 5181.53366197044, 5617.775288988395, 5720.342160651495, 5761.3950535194035, 5762.135679648002, 5823.679697616587, 6583.80248592543, 6589.86820263592, 6646.468282537386, 6655.833604159838, 6712.765053223114, 1631.5105468359445, 1693.4073074423852, 1732.618823858749, 1741.921613527369, 1751.7722696049432, 1178.263585259213, 1198.4726720243066, 1206.957756478519, 1213.769383817072, 1225.3138242584182, 5910.52715782745, 5914.556408269397, 5975.342712301644, 5984.0, 5985.910211346833, 6506.60960342381, 6716.361945615159, 6753.696083676148, 6793.830154246183, 6795.320467953264, 5738.254026976467, 5881.941180588225, 5920.259967504062, 5939.792107276225, 5960.254968128984, 3582.265635544707, 3626.0934766308424, 3627.818609069536, 3630.927381452409, 3667.94498082529, 6562.507811914125, 6580.309067547934, 6616.371444712745, 6633.303503132198, 6637.236717777518, 7007.8598428031155, 7015.965370042631, 7021.717941668978, 7057.752978645723, 7085.2206257311655, 6453.386928241904, 6500.219973603009, 6580.80257592273, 6582.564451153008, 6592.274849766522, 6776.407544227004, 6842.521023528504, 6860.211075726355, 6899.655017249017, 6916.55042422241, 6746.595204287759, 6749.907528666129, 6756.662166891538, 6769.830754231184, 6771.830704232433, 6498.415142637185, 6501.187351581052, 6553.06815910469, 6601.240857301472, 6608.537402381787, 4954.355933728592, 4988.301637770787, 5016.172331565397, 5023.598112151092, 5075.568576670877, 6790.7623233186605, 6854.588724676336, 6907.792766217021, 6912.096919294009, 6912.377885990285, 6453.386928241809, 6550.181227346582, 6656.5340426169705, 6659.567128136656, 6681.832954176184, 6411.3909178627955, 6413.390727880844, 6573.3426657333885, 6626.966865165643, 6654.042738160102, 6223.502119830493, 6468.3855033771715, 6517.706703198272, 6541.443977261795, 6557.540972131905, 6143.569950103629, 6685.197776266879, 6701.396874281338, 6709.0, 6764.695588698422, 6432.35676432353, 6435.356464353527, 6483.319251478715, 6537.542372033912, 6538.705758240795], "value": 5107.8172610015245, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [880.5773228850152, 883.849745543258, 906.3157316226249, 909.0909090909091, 911.9407238529495, 18327.725084123733, 19203.23187072519, 19249.518762030955, 19251.614967700654, 19277.614447711072, 16728.41450549231, 16816.32734690614, 17319.48041558752, 20804.375868723924, 21099.789002109974, 918.673870775875, 946.6828612414849, 952.5808644196566, 953.918916892065, 954.8329042417583, 906.2930913887145, 955.0497255231055, 958.0993865766193, 959.4818797849153, 959.5633986536109, 989.4508547756028, 1002.578916854919, 1002.6340385759182, 1002.9548670309817, 1003.372891942536, 990.0990099009938, 990.9702708918744, 993.5231089077212, 994.443111857355, 995.1839491616877, 734.4895297768053, 735.0187499687923, 735.2757533829174, 735.3822788857385, 735.5696917303384, 18633.53416164593, 18983.90508047451, 19021.524461888424, 19342.129604167847, 19350.225990960345, 19705.507362315915, 19960.10179541924, 20080.0, 20146.899265503725, 20190.49523761903, 16117.113558754381, 16453.25960331776, 16514.2568584414, 16664.41674541385, 16668.166591670488, 3698.4822124902535, 3711.1649878777057, 3723.478712980185, 3736.7757934524016, 3737.5141231639986, 122736.0, 124997.37501312436, 125585.0, 126148.36925815404, 127987.0, 15123.621909452131, 15394.769078463833, 15530.145841978574, 15593.376264949502, 15813.288402021937, 28556.286092847844, 28950.855245723636, 29351.706482934896, 29506.55740163899, 29555.556666650016, 8307.584620768937, 8639.0, 8644.049154592989, 8647.913520864837, 8665.393422460493, 4125.154343359604, 4227.133437645275, 4256.893577660521, 4268.996785755345, 4278.422412974271, 5058.392992840809, 5170.250313704491, 5215.947840521546, 5259.211118332204, 5264.842054738364, 1000.7348052766023, 1006.8791744990648, 1015.619142821442, 1017.5878769098379, 1031.4017869635593, 678.091357580844, 690.7444245629149, 702.7083760239443, 709.7480394459892, 716.2407847681475, 10770.73073173177, 10997.0, 10999.890001100046, 11065.778684426266, 11182.552697892155, 679.3240725478082, 685.2120061928722, 691.789004353677, 699.9615021173782, 704.7181127549021, 5561.416051314634, 5579.358373786985, 5592.049351610236, 5596.300462442195, 5658.462446067617, 107889.0, 108513.0, 112798.43600781943, 116682.0, 118516.0, 91045.54477227571, 92126.53936730273, 96820.51589742006, 97031.0, 103059.0, 59355.40644593499, 81894.0, 83147.58426207952, 85619.0, 91450.0, 10949.87795198947, 11166.72083197894, 11191.720206994565, 11225.382603956861, 11309.660710178709, 39690.60309396869, 40647.796761016005, 40733.38899916445, 41014.38478422886, 42413.93965150896, 51940.74029629979, 52353.47646523486, 53643.46356536541, 54066.72966635141, 54132.72933635306, 17119.433612765723, 17340.56648583745, 17550.12249387551, 17564.82435175632, 17830.10849457496, 19286.324978625682, 19379.321723739602, 19391.903040484707, 19423.223071077566, 19482.123304451616, 15679.216039197767, 15985.920070399574, 16181.75727364114, 16253.0, 16451.58871028186, 15441.459548915735, 15579.688406231586, 15681.137537435094, 15730.764038539202, 15875.28561214725, 14892.180930048518, 14953.177575233467, 14996.47512337063, 15201.467948621745, 15298.388064477742, 10425.9478702606, 10565.154787617126, 10694.358338499715, 11109.555617775199, 11136.665900023012, 11288.604898828502, 11310.830337545109, 11411.486483108447, 11527.0, 11682.94158529202], "value": 8647.446571006803, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1517.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 101.0, 115.0, 117.0, 115.0, 109.0, 97.0, 113.0, 119.0], "value": 110.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 147.0, 178.0, 119.0, 167.0, 144.0, 121.0, 178.0, 125.0], "value": 144.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 169.0, 175.0, 170.0, 164.0, 167.0, 170.0, 165.0, 177.0, 167.0], "value": 168.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 78.0, 73.0, 170.0, 78.0, 74.0, 82.0, 98.0, 95.0, 75.0], "value": 89.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 136.0, 80.0, 79.0, 83.0, 122.0, 90.0, 80.0, 124.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 114.0, 118.0, 113.0, 115.0, 119.0, 120.0, 121.0, 114.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 157.0, 148.0, 149.0, 149.0, 149.0, 150.0, 149.0, 158.0, 149.0], "value": 150.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 102.0, 95.0, 94.0, 104.0, 94.0, 100.0, 100.0, 94.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 58.0, 54.0, 57.0, 52.0, 53.0, 54.0, 53.0, 55.0, 52.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [40.0, 38.0, 42.0, 38.0, 39.0, 39.0, 37.0, 42.0, 38.0, 45.0], "value": 39.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 81.0, 79.0, 80.0, 75.0, 83.0, 74.0, 89.0, 78.0, 76.0], "value": 79.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 133.0, 126.0, 118.0, 135.0, 135.0, 126.0, 119.0, 116.0, 156.0], "value": 129.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 184.0, 182.0, 173.0, 173.0, 172.0, 171.0, 174.0, 174.0, 178.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 64.0, 64.0, 67.0, 65.0, 66.0, 65.0, 64.0, 72.0], "value": 65.9, "unit": "score"}]}]} 19:31:43 INFO - Return code: 0 19:31:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:31:43 INFO - # TBPL SUCCESS # 19:31:43 INFO - Running post-action listener: _package_coverage_data 19:31:43 INFO - Running post-action listener: _resource_record_post_action 19:31:43 INFO - [mozharness: 2017-08-27 02:31:43.519880Z] Finished run-tests step (success) 19:31:43 INFO - Running post-run listener: _resource_record_post_run 19:31:43 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 430215168; Read time: 16; Write time: 327552 19:31:43 INFO - TinderboxPrint: CPU usage
13.3% 19:31:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:31:43 INFO - TinderboxPrint: I/O write bytes / time
430,215,168 / 327,552 19:31:43 INFO - TinderboxPrint: CPU idle
8,258.5 (86.7%) 19:31:43 INFO - TinderboxPrint: CPU user
1,203.5 (12.6%) 19:31:43 INFO - TinderboxPrint: Swap in / out
0 / 0 19:31:43 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 113758208; Read time: 0; Write time: 95868 19:31:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:31:43 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 316456960; Read time: 0; Write time: 231684 19:31:44 INFO - Running post-run listener: _upload_blobber_files 19:31:44 INFO - Blob upload gear active. 19:31:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:31:44 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 19:31:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:31:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:31:44 INFO - (blobuploader) - INFO - Open directory for files ... 19:31:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:31:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:31:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:31:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:31:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:31:48 INFO - (blobuploader) - INFO - Done attempting. 19:31:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:31:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:31:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:31:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:31:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:31:49 INFO - (blobuploader) - INFO - Done attempting. 19:31:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:31:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:31:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:31:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:31:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:31:49 INFO - (blobuploader) - INFO - Done attempting. 19:31:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:31:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:31:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:31:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:31:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:31:51 INFO - (blobuploader) - INFO - Done attempting. 19:31:51 INFO - (blobuploader) - INFO - Iteration through files over. 19:31:51 INFO - Return code: 0 19:31:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:31:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:31:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c868bdc194fcd8bfd1c2554922a21d9c409983e1b076a3e8576121cd0e261964a8d1869d7a36be54ae17054c8ff7b57f8ae7f4afee9cfa71357d6ae715645af2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ace967d9cb80f336933924b970e28c142ff16c1ff445acb942a21810c7c42b5242dcb39eff587e8639e07e82eac057c79d03ecbd25f2223c4b3995e3d8e6600b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1775ca75156998e5a55896e68905cdd9231075ad461fd6ea9b9a7e07e08fc4d5f45b4316d4f97d5eb08dfc7ea4f39831786074b545bcfe3146ff38462d0fe9c9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56ac830674b2b62db0866b5896c5dbd6e1cf33c8dc498727039444adb8e43f0b5639e1a93495eed7e589d4388384274f386350d61a125b2fa3ef51e1b2c88564"} 19:31:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:31:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:31:51 INFO - Contents: 19:31:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c868bdc194fcd8bfd1c2554922a21d9c409983e1b076a3e8576121cd0e261964a8d1869d7a36be54ae17054c8ff7b57f8ae7f4afee9cfa71357d6ae715645af2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ace967d9cb80f336933924b970e28c142ff16c1ff445acb942a21810c7c42b5242dcb39eff587e8639e07e82eac057c79d03ecbd25f2223c4b3995e3d8e6600b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1775ca75156998e5a55896e68905cdd9231075ad461fd6ea9b9a7e07e08fc4d5f45b4316d4f97d5eb08dfc7ea4f39831786074b545bcfe3146ff38462d0fe9c9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56ac830674b2b62db0866b5896c5dbd6e1cf33c8dc498727039444adb8e43f0b5639e1a93495eed7e589d4388384274f386350d61a125b2fa3ef51e1b2c88564"} 19:31:51 INFO - Running post-run listener: copy_logs_to_upload_dir 19:31:51 INFO - Copying logs to upload dir... 19:31:51 INFO - mkdir: /builds/slave/test/build/upload/logs 19:31:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.752102 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-08-26 19:31:51.056618) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-26 19:31:51.059989) ========= 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=199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c868bdc194fcd8bfd1c2554922a21d9c409983e1b076a3e8576121cd0e261964a8d1869d7a36be54ae17054c8ff7b57f8ae7f4afee9cfa71357d6ae715645af2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ace967d9cb80f336933924b970e28c142ff16c1ff445acb942a21810c7c42b5242dcb39eff587e8639e07e82eac057c79d03ecbd25f2223c4b3995e3d8e6600b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1775ca75156998e5a55896e68905cdd9231075ad461fd6ea9b9a7e07e08fc4d5f45b4316d4f97d5eb08dfc7ea4f39831786074b545bcfe3146ff38462d0fe9c9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56ac830674b2b62db0866b5896c5dbd6e1cf33c8dc498727039444adb8e43f0b5639e1a93495eed7e589d4388384274f386350d61a125b2fa3ef51e1b2c88564"} build_url:https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011331 build_url: 'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c868bdc194fcd8bfd1c2554922a21d9c409983e1b076a3e8576121cd0e261964a8d1869d7a36be54ae17054c8ff7b57f8ae7f4afee9cfa71357d6ae715645af2", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ace967d9cb80f336933924b970e28c142ff16c1ff445acb942a21810c7c42b5242dcb39eff587e8639e07e82eac057c79d03ecbd25f2223c4b3995e3d8e6600b", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1775ca75156998e5a55896e68905cdd9231075ad461fd6ea9b9a7e07e08fc4d5f45b4316d4f97d5eb08dfc7ea4f39831786074b545bcfe3146ff38462d0fe9c9", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56ac830674b2b62db0866b5896c5dbd6e1cf33c8dc498727039444adb8e43f0b5639e1a93495eed7e589d4388384274f386350d61a125b2fa3ef51e1b2c88564"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-26 19:31:51.086362) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:31:51.086696) ========= 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=199fb4c72b90437b990b419b000001c5-1503799856.231622-1841594226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004544 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:31:51.168477) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-26 19:31:51.168976) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-26 19:31:51.169287) ========= ========= Total master_lag: 0.18 =========