builder: mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s slave: talos-linux64-ix-020 starttime: 1503799903.88 results: success (0) revision: f819969d7619f01e806e2685b8b3196f64624551 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:43.884325) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:43.884755) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:43.885051) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:43.900761) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:43.901060) ========= 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=af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-26 19:11:44-- 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% 227M=0s 2017-08-26 19:11:44 (227 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127132 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:44.043346) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:44.043672) ========= 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=af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014704 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:44.096839) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:44.100181) ========= 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=af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-26 19:11:45,078 truncating revision to first 12 chars 2017-08-26 19:11:45,078 Setting DEBUG logging. 2017-08-26 19:11:45,078 attempt 1/10 2017-08-26 19:11:45,078 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:11:45,491 unpacking tar archive at: mozilla-central-f819969d7619/testing/mozharness/ program finished with exit code 0 elapsedTime=0.681783 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:44.797528) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:44.797846) ========= script_repo_revision: f819969d7619f01e806e2685b8b3196f64624551 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:44.798263) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:44.798530) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-26 19:11:44.812298) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-26 19:11:44.812697) ========= /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=af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:11:45 INFO - MultiFileLogger online at 20170826 19:11:45 in /builds/slave/test 19:11:45 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:11:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:11:45 INFO - {'append_to_log': False, 19:11:45 INFO - 'base_work_dir': '/builds/slave/test', 19:11:45 INFO - 'blob_upload_branch': 'Firefox', 19:11:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:11:45 INFO - 'branch': 'Firefox', 19:11:45 INFO - 'buildbot_json_path': 'buildprops.json', 19:11:45 INFO - 'code_coverage': False, 19:11:45 INFO - 'config_files': ('talos/linux_config.py',), 19:11:45 INFO - 'default_actions': ('clobber', 19:11:45 INFO - 'read-buildbot-config', 19:11:45 INFO - 'download-and-extract', 19:11:45 INFO - 'populate-webroot', 19:11:45 INFO - 'create-virtualenv', 19:11:45 INFO - 'install', 19:11:45 INFO - 'setup-mitmproxy', 19:11:45 INFO - 'run-tests'), 19:11:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:11:45 INFO - 'disable_ccov_upload': False, 19:11:45 INFO - 'download_minidump_stackwalk': True, 19:11:45 INFO - 'download_symbols': 'ondemand', 19:11:45 INFO - 'enable_stylo': False, 19:11:45 INFO - 'enable_webrender': False, 19:11:45 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 19:11:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:11:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:11:45 INFO - 'gecko_profile': False, 19:11:45 INFO - 'gecko_profile_interval': 0, 19:11:45 INFO - 'installer_path': 'installer.exe', 19:11:45 INFO - 'log_level': 'info', 19:11:45 INFO - 'log_name': 'talos', 19:11:45 INFO - 'log_to_console': True, 19:11:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:11:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:11:45 INFO - 'opt_config_files': (), 19:11:45 INFO - 'pip_index': False, 19:11:45 INFO - 'suite': 'dromaeojs-e10s', 19:11:45 INFO - 'system_bits': '32', 19:11:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:11:45 INFO - 'title': 'talos-linux64-ix-020', 19:11:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:11:45 INFO - 'use_talos_json': True, 19:11:45 INFO - 'verify': 'False', 19:11:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:11:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:11:45 INFO - 'work_dir': 'build'} 19:11:45 INFO - [mozharness: 2017-08-27 02:11:45.935365Z] Running clobber step. 19:11:45 INFO - Running pre-action listener: _resource_record_pre_action 19:11:45 INFO - Running main action method: clobber 19:11:45 INFO - rmtree: /builds/slave/test/build 19:11:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:11:47 INFO - Running post-action listener: _resource_record_post_action 19:11:47 INFO - [mozharness: 2017-08-27 02:11:47.099267Z] Finished clobber step (success) 19:11:47 INFO - [mozharness: 2017-08-27 02:11:47.099365Z] Running read-buildbot-config step. 19:11:47 INFO - Running pre-action listener: _resource_record_pre_action 19:11:47 INFO - Running main action method: read_buildbot_config 19:11:47 INFO - Using buildbot properties: 19:11:47 INFO - { 19:11:47 INFO - "project": "", 19:11:47 INFO - "product": "firefox", 19:11:47 INFO - "who": "philringnalda@gmail.com", 19:11:47 INFO - "installer_path": "public/build/target.tar.bz2", 19:11:47 INFO - "builddir": "mozilla-central_ubuntu64_hw_stylo_test-dromaeojs-e10s", 19:11:47 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 19:11:47 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo mozilla-central talos dromaeojs-e10s", 19:11:47 INFO - "stage_platform": "linux64-stylo", 19:11:47 INFO - "basedir": "/builds/slave/test", 19:11:47 INFO - "buildnumber": 107, 19:11:47 INFO - "platform": "ubuntu64_hw_stylo", 19:11:47 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:11:47 INFO - "slavebuilddir": "test", 19:11:47 INFO - "taskId": "M3EYY68OR6ymGi0Ctf68jw", 19:11:47 INFO - "branch": "mozilla-central", 19:11:47 INFO - "script_repo_revision": "production", 19:11:47 INFO - "revision": "f819969d7619f01e806e2685b8b3196f64624551", 19:11:47 INFO - "slavename": "talos-linux64-ix-020", 19:11:47 INFO - "repo_path": "mozilla-central" 19:11:47 INFO - } 19:11:47 INFO - Finding installer, test and symbols from parent task. 19:11:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M3EYY68OR6ymGi0Ctf68jw'}, attempt #1 19:11:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M3EYY68OR6ymGi0Ctf68jw'}, attempt #1 19:11:47 INFO - Task dependencies: TahGLksQSDWoPnEX28P56w 19:11:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w'}, attempt #1 19:11:48 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 19:11:48 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.test_packages.json 19:11:48 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.crashreporter-symbols.zip 19:11:48 INFO - Running post-action listener: _resource_record_post_action 19:11:48 INFO - [mozharness: 2017-08-27 02:11:48.436541Z] Finished read-buildbot-config step (success) 19:11:48 INFO - [mozharness: 2017-08-27 02:11:48.436759Z] Running download-and-extract step. 19:11:48 INFO - Running pre-action listener: _resource_record_pre_action 19:11:48 INFO - Running main action method: download_and_extract 19:11:48 INFO - mkdir: /builds/slave/test/build/tests 19:11:48 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:48 INFO - trying https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.test_packages.json 19:11:48 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:48 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:48 INFO - Downloaded 989 bytes. 19:11:48 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 19:11:48 INFO - Using the following test package requirements: 19:11:48 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 19:11:48 INFO - u'common': [u'target.common.tests.zip'], 19:11:48 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 19:11:48 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 19:11:48 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 19:11:48 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 19:11:48 INFO - u'mozbase': [u'target.common.tests.zip'], 19:11:48 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 19:11:48 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 19:11:48 INFO - u'web-platform': [u'target.common.tests.zip', 19:11:48 INFO - u'target.web-platform.tests.tar.gz'], 19:11:48 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 19:11:48 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 19:11:48 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:48 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:48 INFO - Fetch https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.common.tests.zip into memory 19:11:50 INFO - Content-Length response header: 54978016 19:11:50 INFO - Bytes received: 54978016 19:12:01 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 19:12: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:12: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:12:01 INFO - Fetch https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.common.tests.zip into memory 19:12:02 INFO - Content-Length response header: 54978016 19:12:02 INFO - Bytes received: 54978016 19:12:14 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:12:14 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:12:14 INFO - Fetch https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.talos.tests.zip into memory 19:12:15 INFO - Content-Length response header: 14496611 19:12:15 INFO - Bytes received: 14496611 19:12:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:15 INFO - trying https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 19:12:15 INFO - Downloading https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 19:12:15 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:12:16 INFO - Downloaded 61167640 bytes. 19:12:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 19:12:16 INFO - mkdir: /builds/slave/test/properties 19:12:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:12:16 INFO - Writing to file /builds/slave/test/properties/build_url 19:12:16 INFO - Contents: 19:12:16 INFO - build_url:https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 19:12:16 INFO - Running post-action listener: _resource_record_post_action 19:12:16 INFO - Running post-action listener: find_tests_for_verification 19:12:16 INFO - Running post-action listener: set_extra_try_arguments 19:12:16 INFO - [mozharness: 2017-08-27 02:12:16.546685Z] Finished download-and-extract step (success) 19:12:16 INFO - [mozharness: 2017-08-27 02:12:16.546808Z] Running populate-webroot step. 19:12:16 INFO - Running pre-action listener: _resource_record_pre_action 19:12:16 INFO - Running main action method: populate_webroot 19:12:16 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:12:16 INFO - 'chromez-stylo-e10s': {'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:12:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:12:16 INFO - 'dromaeojs-stylo-e10s': {'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:12:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:12:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:16 INFO - 'g1-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:12:16 INFO - 'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:12:16 INFO - 'tests': ['damp', 'tps']}, 19:12:16 INFO - 'g2-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:12:16 INFO - 'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['damp', 'tps']}, 19:12:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:12:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:12:16 INFO - 'g4-stylo-e10s': {'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:12:16 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:12:16 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:12:16 INFO - 'g5-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:12:16 INFO - 'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:12:16 INFO - 'other-e10s': {'tests': ['a11yr', 19:12:16 INFO - 'ts_paint', 19:12:16 INFO - 'tpaint', 19:12:16 INFO - 'sessionrestore', 19:12:16 INFO - 'sessionrestore_many_windows', 19:12:16 INFO - 'sessionrestore_no_auto_restore', 19:12:16 INFO - 'tabpaint']}, 19:12:16 INFO - 'other-stylo-e10s': {'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['a11yr', 19:12:16 INFO - 'ts_paint', 19:12:16 INFO - 'tpaint', 19:12:16 INFO - 'sessionrestore', 19:12:16 INFO - 'sessionrestore_many_windows', 19:12:16 INFO - 'sessionrestore_no_auto_restore', 19:12:16 INFO - 'tabpaint']}, 19:12:16 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 19:12:16 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 19:12:16 INFO - 'perf-reftest-singletons-stylo-e10s': {'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['bloom_basic_singleton']}, 19:12:16 INFO - 'perf-reftest-stylo-e10s': {'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['bloom_basic']}, 19:12:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:12:16 INFO - 'tsvgr_opacity', 19:12:16 INFO - 'tart', 19:12:16 INFO - 'tscrollx', 19:12:16 INFO - 'tsvg_static']}, 19:12:16 INFO - 'svgr-stylo-e10s': {'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['tsvgx', 19:12:16 INFO - 'tsvgr_opacity', 19:12:16 INFO - 'tart', 19:12:16 INFO - 'tscrollx', 19:12:16 INFO - 'tsvg_static']}, 19:12:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:12:16 INFO - 'tp5o-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:12:16 INFO - 'talos_options': ['--stylo'], 19:12:16 INFO - 'tests': ['tp5o']}, 19:12:16 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:12:16 INFO - 'talos_options': ['--mitmproxy', 19:12:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:12:16 INFO - '--firstNonBlankPaint'], 19:12:16 INFO - 'tests': ['tp6_google', 19:12:16 INFO - 'tp6_youtube', 19:12:16 INFO - 'tp6_amazon', 19:12:16 INFO - 'tp6_facebook']}, 19:12:16 INFO - 'tp6-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:12:16 INFO - 'talos_options': ['--stylo', 19:12:16 INFO - '--mitmproxy', 19:12:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:12:16 INFO - '--firstNonBlankPaint'], 19:12:16 INFO - 'tests': ['tp6_google', 19:12:16 INFO - 'tp6_youtube', 19:12:16 INFO - 'tp6_amazon', 19:12:16 INFO - 'tp6_facebook']}, 19:12:16 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 19:12:16 INFO - 'talos_options': ['--stylo', 19:12:16 INFO - '--stylo-threads=1', 19:12:16 INFO - '--mitmproxy', 19:12:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 19:12:16 INFO - '--firstNonBlankPaint'], 19:12:16 INFO - 'tests': ['tp6_google', 19:12:16 INFO - 'tp6_youtube', 19:12:16 INFO - 'tp6_amazon', 19:12:16 INFO - 'tp6_facebook']}, 19:12:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:12:16 INFO - 'talos_options': ['--xperf_path', 19:12:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:16 INFO - 'tests': ['tp5n']}, 19:12:16 INFO - 'xperf-stylo-e10s': {'pagesets_name': 'tp5n.zip', 19:12:16 INFO - 'talos_options': ['--stylo', 19:12:16 INFO - '--xperf_path', 19:12:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:16 INFO - 'tests': ['tp5n']}}} 19:12:16 INFO - Running post-action listener: _resource_record_post_action 19:12:16 INFO - [mozharness: 2017-08-27 02:12:16.562056Z] Finished populate-webroot step (success) 19:12:16 INFO - [mozharness: 2017-08-27 02:12:16.562155Z] Running create-virtualenv step. 19:12:16 INFO - Running pre-action listener: _resource_record_pre_action 19:12:16 INFO - Running main action method: create_virtualenv 19:12:16 INFO - Creating virtualenv /builds/slave/test/build/venv 19:12:16 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:12:16 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 19:12:16 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:12:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:12:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:12:17 INFO - Installing distribute.............................................................................................................................................................................................done. 19:12:17 INFO - Installing pip...............done. 19:12:17 INFO - Return code: 0 19:12:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:12:17 INFO - Reading from file tmpfile_stdout 19:12:17 INFO - Output received: 19:12:17 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:12:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:12:17 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:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:17 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:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:17 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 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12:17 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:12:17 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:12:17 INFO - Using env: {'DISPLAY': ':0', 19:12:17 INFO - 'HOME': '/home/cltbld', 19:12:17 INFO - 'LANG': 'en_US.UTF-8', 19:12:17 INFO - 'LANGUAGE': 'en_US:en', 19:12:17 INFO - 'LOGNAME': 'cltbld', 19:12:17 INFO - 'MAIL': '/var/mail/cltbld', 19:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:17 INFO - 'MOZ_NO_REMOTE': '1', 19:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:17 INFO - 'NO_EM_RESTART': '1', 19:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:17 INFO - 'PWD': '/builds/slave/test', 19:12:17 INFO - 'SHELL': '/bin/bash', 19:12:17 INFO - 'SHLVL': '1', 19:12:17 INFO - 'TERM': 'linux', 19:12:17 INFO - 'TMOUT': '86400', 19:12:17 INFO - 'USER': 'cltbld', 19:12:17 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:17 INFO - '_': '/tools/buildbot/bin/python'} 19:12:18 INFO - Ignoring indexes: http://pypi.python.org/simple/ 19:12:18 INFO - Downloading/unpacking pip>=1.5 19:12:18 INFO - Running setup.py egg_info for package pip 19:12:18 INFO - warning: no files found matching 'pip/cacert.pem' 19:12:18 INFO - warning: no files found matching '*.html' under directory 'docs' 19:12:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:12:18 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:12:18 INFO - Installing collected packages: pip 19:12:18 INFO - Found existing installation: pip 1.1 19:12:18 INFO - Uninstalling pip: 19:12:18 INFO - Successfully uninstalled pip 19:12:18 INFO - Running setup.py install for pip 19:12:18 INFO - warning: no files found matching 'pip/cacert.pem' 19:12:18 INFO - warning: no files found matching '*.html' under directory 'docs' 19:12:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 19:12:18 INFO - no previously-included directories found matching 'docs/_build/_sources' 19:12:18 INFO - Installing pip script to /builds/slave/test/build/venv/bin 19:12:18 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 19:12:18 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 19:12:18 INFO - Successfully installed pip 19:12:18 INFO - Cleaning up... 19:12:18 INFO - Return code: 0 19:12:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:12:18 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:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:18 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:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:18 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 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12:18 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:12:18 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:12:18 INFO - Using env: {'DISPLAY': ':0', 19:12:18 INFO - 'HOME': '/home/cltbld', 19:12:18 INFO - 'LANG': 'en_US.UTF-8', 19:12:18 INFO - 'LANGUAGE': 'en_US:en', 19:12:18 INFO - 'LOGNAME': 'cltbld', 19:12:18 INFO - 'MAIL': '/var/mail/cltbld', 19:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:18 INFO - 'MOZ_NO_REMOTE': '1', 19:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:18 INFO - 'NO_EM_RESTART': '1', 19:12:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:18 INFO - 'PWD': '/builds/slave/test', 19:12:18 INFO - 'SHELL': '/bin/bash', 19:12:18 INFO - 'SHLVL': '1', 19:12:18 INFO - 'TERM': 'linux', 19:12:18 INFO - 'TMOUT': '86400', 19:12:18 INFO - 'USER': 'cltbld', 19:12:18 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:18 INFO - '_': '/tools/buildbot/bin/python'} 19:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:18 INFO - Downloading/unpacking psutil>=3.1.1 19:12:18 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:12:18 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:12:19 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:12:19 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:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:12:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:21 INFO - Installing collected packages: psutil 19:12:21 INFO - Running setup.py install for psutil 19:12:21 INFO - building 'psutil._psutil_linux' extension 19:12:21 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:12:21 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:12:21 INFO - building 'psutil._psutil_posix' extension 19:12:21 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:12:21 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:12:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:21 INFO - Successfully installed psutil 19:12:21 INFO - Cleaning up... 19:12:21 INFO - Return code: 0 19:12:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:12:21 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:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:21 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:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:21 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 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12:21 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:12:21 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:12:21 INFO - Using env: {'DISPLAY': ':0', 19:12:21 INFO - 'HOME': '/home/cltbld', 19:12:21 INFO - 'LANG': 'en_US.UTF-8', 19:12:21 INFO - 'LANGUAGE': 'en_US:en', 19:12:21 INFO - 'LOGNAME': 'cltbld', 19:12:21 INFO - 'MAIL': '/var/mail/cltbld', 19:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:21 INFO - 'MOZ_NO_REMOTE': '1', 19:12:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:21 INFO - 'NO_EM_RESTART': '1', 19:12:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:21 INFO - 'PWD': '/builds/slave/test', 19:12:21 INFO - 'SHELL': '/bin/bash', 19:12:21 INFO - 'SHLVL': '1', 19:12:21 INFO - 'TERM': 'linux', 19:12:21 INFO - 'TMOUT': '86400', 19:12:21 INFO - 'USER': 'cltbld', 19:12:21 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:21 INFO - '_': '/tools/buildbot/bin/python'} 19:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:12:22 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:12:22 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:12:22 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:12:22 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:12:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:12:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:12:23 INFO - Installing collected packages: mozsystemmonitor 19:12:23 INFO - Running setup.py install for mozsystemmonitor 19:12:23 INFO - Successfully installed mozsystemmonitor 19:12:23 INFO - Cleaning up... 19:12:23 INFO - Return code: 0 19:12:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:23 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 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12:23 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:12:23 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:12:23 INFO - Using env: {'DISPLAY': ':0', 19:12:23 INFO - 'HOME': '/home/cltbld', 19:12:23 INFO - 'LANG': 'en_US.UTF-8', 19:12:23 INFO - 'LANGUAGE': 'en_US:en', 19:12:23 INFO - 'LOGNAME': 'cltbld', 19:12:23 INFO - 'MAIL': '/var/mail/cltbld', 19:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:23 INFO - 'MOZ_NO_REMOTE': '1', 19:12:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:23 INFO - 'NO_EM_RESTART': '1', 19:12:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:23 INFO - 'PWD': '/builds/slave/test', 19:12:23 INFO - 'SHELL': '/bin/bash', 19:12:23 INFO - 'SHLVL': '1', 19:12:23 INFO - 'TERM': 'linux', 19:12:23 INFO - 'TMOUT': '86400', 19:12:23 INFO - 'USER': 'cltbld', 19:12:23 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:23 INFO - '_': '/tools/buildbot/bin/python'} 19:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:23 INFO - Downloading/unpacking jsonschema==2.5.1 19:12:23 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:12:23 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:12:23 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:12:23 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:12:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:12:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:12:24 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:12:24 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:12:24 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:12:24 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:12:24 INFO - Downloading functools32-3.2.3-2.tar.gz 19:12:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:12:24 INFO - warning: no files found matching '*.txt' 19:12:24 INFO - no previously-included directories found matching 'build' 19:12:24 INFO - no previously-included directories found matching 'dist' 19:12:24 INFO - no previously-included directories found matching '.git*' 19:12:24 INFO - Installing collected packages: jsonschema, functools32 19:12:24 INFO - Running setup.py install for functools32 19:12:24 INFO - warning: no files found matching '*.txt' 19:12:24 INFO - no previously-included directories found matching 'build' 19:12:24 INFO - no previously-included directories found matching 'dist' 19:12:24 INFO - no previously-included directories found matching '.git*' 19:12:24 INFO - Successfully installed jsonschema functools32 19:12:24 INFO - Cleaning up... 19:12:25 INFO - Return code: 0 19:12:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:12:25 INFO - Using env: {'DISPLAY': ':0', 19:12:25 INFO - 'HOME': '/home/cltbld', 19:12:25 INFO - 'LANG': 'en_US.UTF-8', 19:12:25 INFO - 'LANGUAGE': 'en_US:en', 19:12:25 INFO - 'LOGNAME': 'cltbld', 19:12:25 INFO - 'MAIL': '/var/mail/cltbld', 19:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:25 INFO - 'MOZ_NO_REMOTE': '1', 19:12:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:25 INFO - 'NO_EM_RESTART': '1', 19:12:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:25 INFO - 'PWD': '/builds/slave/test', 19:12:25 INFO - 'SHELL': '/bin/bash', 19:12:25 INFO - 'SHLVL': '1', 19:12:25 INFO - 'TERM': 'linux', 19:12:25 INFO - 'TMOUT': '86400', 19:12:25 INFO - 'USER': 'cltbld', 19:12:25 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:25 INFO - '_': '/tools/buildbot/bin/python'} 19:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:12:25 INFO - Cleaning up... 19:12:25 INFO - Return code: 0 19:12:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:12:25 INFO - Using env: {'DISPLAY': ':0', 19:12:25 INFO - 'HOME': '/home/cltbld', 19:12:25 INFO - 'LANG': 'en_US.UTF-8', 19:12:25 INFO - 'LANGUAGE': 'en_US:en', 19:12:25 INFO - 'LOGNAME': 'cltbld', 19:12:25 INFO - 'MAIL': '/var/mail/cltbld', 19:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:25 INFO - 'MOZ_NO_REMOTE': '1', 19:12:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:25 INFO - 'NO_EM_RESTART': '1', 19:12:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:25 INFO - 'PWD': '/builds/slave/test', 19:12:25 INFO - 'SHELL': '/bin/bash', 19:12:25 INFO - 'SHLVL': '1', 19:12:25 INFO - 'TERM': 'linux', 19:12:25 INFO - 'TMOUT': '86400', 19:12:25 INFO - 'USER': 'cltbld', 19:12:25 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:25 INFO - '_': '/tools/buildbot/bin/python'} 19:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:25 INFO - Downloading/unpacking blobuploader==1.2.4 19:12:25 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:12:25 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:12:25 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:12:25 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:12:26 INFO - Downloading blobuploader-1.2.4.tar.gz 19:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:12:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:12:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:12:27 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:12:27 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:12:27 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:12:27 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:12:27 INFO - Downloading docopt-0.6.1.tar.gz 19:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:12:27 INFO - Installing collected packages: blobuploader, requests, docopt 19:12:27 INFO - Running setup.py install for blobuploader 19:12:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:12:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:12:27 INFO - Running setup.py install for requests 19:12:27 INFO - Running setup.py install for docopt 19:12:27 INFO - Successfully installed blobuploader requests docopt 19:12:27 INFO - Cleaning up... 19:12:27 INFO - Return code: 0 19:12:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12: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:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12: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:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:27 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 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12:27 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:12:27 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:12:27 INFO - Using env: {'DISPLAY': ':0', 19:12:27 INFO - 'HOME': '/home/cltbld', 19:12:27 INFO - 'LANG': 'en_US.UTF-8', 19:12:27 INFO - 'LANGUAGE': 'en_US:en', 19:12:27 INFO - 'LOGNAME': 'cltbld', 19:12:27 INFO - 'MAIL': '/var/mail/cltbld', 19:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:27 INFO - 'MOZ_NO_REMOTE': '1', 19:12:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:27 INFO - 'NO_EM_RESTART': '1', 19:12:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:27 INFO - 'PWD': '/builds/slave/test', 19:12:27 INFO - 'SHELL': '/bin/bash', 19:12:27 INFO - 'SHLVL': '1', 19:12:27 INFO - 'TERM': 'linux', 19:12:27 INFO - 'TMOUT': '86400', 19:12:27 INFO - 'USER': 'cltbld', 19:12:27 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:27 INFO - '_': '/tools/buildbot/bin/python'} 19:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:28 INFO - Running setup.py (path:/tmp/pip-95EKoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:28 INFO - Running setup.py (path:/tmp/pip-2CQwDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:28 INFO - Running setup.py (path:/tmp/pip-ANrrSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:28 INFO - Running setup.py (path:/tmp/pip-6q9hRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:28 INFO - Running setup.py (path:/tmp/pip-UUjWfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:28 INFO - Running setup.py (path:/tmp/pip-3tHRyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:28 INFO - Running setup.py (path:/tmp/pip-6O_Ahh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:28 INFO - Running setup.py (path:/tmp/pip-ZzOYvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:28 INFO - Running setup.py (path:/tmp/pip-uhldgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:28 INFO - Running setup.py (path:/tmp/pip-LtZR7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:28 INFO - Running setup.py (path:/tmp/pip-ViJbp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:28 INFO - Running setup.py (path:/tmp/pip-kfXMjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:28 INFO - Running setup.py (path:/tmp/pip-aT4OMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:28 INFO - Running setup.py (path:/tmp/pip-3cDW01-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:29 INFO - Running setup.py (path:/tmp/pip-X2fRCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:29 INFO - Running setup.py (path:/tmp/pip-0hFCvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:29 INFO - Running setup.py (path:/tmp/pip-L9OzEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:12:29 INFO - Running setup.py install for manifestparser 19:12:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:12:29 INFO - Running setup.py install for mozcrash 19:12:29 INFO - Running setup.py install for mozdebug 19:12:29 INFO - Running setup.py install for mozdevice 19:12:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:12:29 INFO - Running setup.py install for mozfile 19:12:29 INFO - Running setup.py install for mozhttpd 19:12:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:12:29 INFO - Running setup.py install for mozinfo 19:12:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:12:29 INFO - Running setup.py install for mozInstall 19:12:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Running setup.py install for mozleak 19:12:30 INFO - Running setup.py install for mozlog 19:12:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Running setup.py install for moznetwork 19:12:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Running setup.py install for mozprocess 19:12:30 INFO - Running setup.py install for mozprofile 19:12:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Running setup.py install for mozrunner 19:12:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Running setup.py install for mozscreenshot 19:12:30 INFO - Running setup.py install for moztest 19:12:30 INFO - Running setup.py install for mozversion 19:12:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:12:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:12:30 INFO - Cleaning up... 19:12:31 INFO - Return code: 0 19:12:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:31 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 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12:31 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:12:31 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:12:31 INFO - Using env: {'DISPLAY': ':0', 19:12:31 INFO - 'HOME': '/home/cltbld', 19:12:31 INFO - 'LANG': 'en_US.UTF-8', 19:12:31 INFO - 'LANGUAGE': 'en_US:en', 19:12:31 INFO - 'LOGNAME': 'cltbld', 19:12:31 INFO - 'MAIL': '/var/mail/cltbld', 19:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:31 INFO - 'MOZ_NO_REMOTE': '1', 19:12:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:31 INFO - 'NO_EM_RESTART': '1', 19:12:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:31 INFO - 'PWD': '/builds/slave/test', 19:12:31 INFO - 'SHELL': '/bin/bash', 19:12:31 INFO - 'SHLVL': '1', 19:12:31 INFO - 'TERM': 'linux', 19:12:31 INFO - 'TMOUT': '86400', 19:12:31 INFO - 'USER': 'cltbld', 19:12:31 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:31 INFO - '_': '/tools/buildbot/bin/python'} 19:12:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:12:31 INFO - Running setup.py (path:/tmp/pip-JjzDjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:12:31 INFO - Running setup.py (path:/tmp/pip-DmhWK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:12:31 INFO - Running setup.py (path:/tmp/pip-N5WRVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:12:31 INFO - Running setup.py (path:/tmp/pip-VdSiOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:12:31 INFO - Running setup.py (path:/tmp/pip-jcXgK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:12:31 INFO - Running setup.py (path:/tmp/pip-4ZDhji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:12:31 INFO - Running setup.py (path:/tmp/pip-tbcolY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:12:31 INFO - Running setup.py (path:/tmp/pip-iiDpIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:12:31 INFO - Running setup.py (path:/tmp/pip-pSj_Rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:12:31 INFO - Running setup.py (path:/tmp/pip-e4rFyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:12:31 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:12:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:12:31 INFO - Running setup.py (path:/tmp/pip-C6ZeMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:12:32 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:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:12:32 INFO - Running setup.py (path:/tmp/pip-KM_xhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:12:32 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:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:12:32 INFO - Running setup.py (path:/tmp/pip-XQsEV1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:12:32 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:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:12:32 INFO - Running setup.py (path:/tmp/pip-8adVT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:12:32 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:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:32 INFO - Running setup.py (path:/tmp/pip-jJXI83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:12:32 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:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:12:32 INFO - Running setup.py (path:/tmp/pip-NArIHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:12:32 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:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:12:32 INFO - Running setup.py (path:/tmp/pip-N7PhVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:12:32 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:12:32 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:12:32 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:12:32 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:12:32 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:12:32 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:12:32 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:12:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:12:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:12:33 INFO - Downloading blessings-1.6.tar.gz 19:12:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:12:33 INFO - Installing collected packages: blessings 19:12:33 INFO - Running setup.py install for blessings 19:12:33 INFO - Successfully installed blessings 19:12:33 INFO - Cleaning up... 19:12:33 INFO - Return code: 0 19:12:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:12:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:34 INFO - Reading from file tmpfile_stdout 19:12:34 INFO - Current package versions: 19:12:34 INFO - argparse == 1.2.1 19:12:34 INFO - blessings == 1.6 19:12:34 INFO - blobuploader == 1.2.4 19:12:34 INFO - docopt == 0.6.1 19:12:34 INFO - functools32 == 3.2.3-2 19:12:34 INFO - jsonschema == 2.5.1 19:12:34 INFO - manifestparser == 1.1 19:12:34 INFO - mozInstall == 1.13 19:12:34 INFO - mozcrash == 1.0 19:12:34 INFO - mozdebug == 0.1 19:12:34 INFO - mozdevice == 0.51 19:12:34 INFO - mozfile == 1.2 19:12:34 INFO - mozhttpd == 0.7 19:12:34 INFO - mozinfo == 0.10 19:12:34 INFO - mozleak == 0.1 19:12:34 INFO - mozlog == 3.5 19:12:34 INFO - moznetwork == 0.27 19:12:34 INFO - mozprocess == 0.25 19:12:34 INFO - mozprofile == 0.28 19:12:34 INFO - mozrunner == 6.13 19:12:34 INFO - mozscreenshot == 0.1 19:12:34 INFO - mozsystemmonitor == 0.3 19:12:34 INFO - moztest == 0.8 19:12:34 INFO - mozversion == 1.4 19:12:34 INFO - psutil == 3.1.1 19:12:34 INFO - requests == 1.2.3 19:12:34 INFO - wsgiref == 0.1.2 19:12:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:12: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:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12: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:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:34 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 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12:34 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:12:34 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:12:34 INFO - Using env: {'DISPLAY': ':0', 19:12:34 INFO - 'HOME': '/home/cltbld', 19:12:34 INFO - 'LANG': 'en_US.UTF-8', 19:12:34 INFO - 'LANGUAGE': 'en_US:en', 19:12:34 INFO - 'LOGNAME': 'cltbld', 19:12:34 INFO - 'MAIL': '/var/mail/cltbld', 19:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:34 INFO - 'MOZ_NO_REMOTE': '1', 19:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:34 INFO - 'NO_EM_RESTART': '1', 19:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:34 INFO - 'PWD': '/builds/slave/test', 19:12:34 INFO - 'SHELL': '/bin/bash', 19:12:34 INFO - 'SHLVL': '1', 19:12:34 INFO - 'TERM': 'linux', 19:12:34 INFO - 'TMOUT': '86400', 19:12:34 INFO - 'USER': 'cltbld', 19:12:34 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:34 INFO - '_': '/tools/buildbot/bin/python'} 19:12:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:34 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:12:34 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:12:34 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:12:34 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:12:34 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:12:34 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:12:34 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:12:34 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:12:34 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:12:34 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:12: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:12: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:12: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:12: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:12:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 19:12:35 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:12:35 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:12:35 INFO - Installing collected packages: simplejson 19:12:35 INFO - Running setup.py install for simplejson 19:12:35 INFO - building 'simplejson._speedups' extension 19:12:35 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:12:36 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:12:36 INFO - Successfully installed simplejson 19:12:36 INFO - Cleaning up... 19:12:36 INFO - Return code: 0 19:12:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:12: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:12:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12: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:12:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b92a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d35f90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, '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': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', '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:12: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', 'jsonschema'] in /builds/slave/test/build 19:12: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 jsonschema 19:12:36 INFO - Using env: {'DISPLAY': ':0', 19:12:36 INFO - 'HOME': '/home/cltbld', 19:12:36 INFO - 'LANG': 'en_US.UTF-8', 19:12:36 INFO - 'LANGUAGE': 'en_US:en', 19:12:36 INFO - 'LOGNAME': 'cltbld', 19:12:36 INFO - 'MAIL': '/var/mail/cltbld', 19:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:36 INFO - 'MOZ_NO_REMOTE': '1', 19:12:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:36 INFO - 'NO_EM_RESTART': '1', 19:12:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:36 INFO - 'PWD': '/builds/slave/test', 19:12:36 INFO - 'SHELL': '/bin/bash', 19:12:36 INFO - 'SHLVL': '1', 19:12:36 INFO - 'TERM': 'linux', 19:12:36 INFO - 'TMOUT': '86400', 19:12:36 INFO - 'USER': 'cltbld', 19:12:36 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:36 INFO - '_': '/tools/buildbot/bin/python'} 19:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:12:36 INFO - Cleaning up... 19:12:36 INFO - Return code: 0 19:12:36 INFO - Running post-action listener: _resource_record_post_action 19:12:36 INFO - Running post-action listener: _start_resource_monitoring 19:12:36 INFO - Starting resource monitoring. 19:12:36 INFO - [mozharness: 2017-08-27 02:12:36.826502Z] Finished create-virtualenv step (success) 19:12:36 INFO - [mozharness: 2017-08-27 02:12:36.827081Z] Running install step. 19:12:36 INFO - Running pre-action listener: _resource_record_pre_action 19:12:36 INFO - Running main action method: install 19:12:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:12:37 INFO - Reading from file tmpfile_stdout 19:12:37 INFO - Detecting whether we're running mozinstall >=1.0... 19:12:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:12:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:12:37 INFO - Reading from file tmpfile_stdout 19:12:37 INFO - Output received: 19:12:37 INFO - Usage: mozinstall [options] installer 19:12:37 INFO - Options: 19:12:37 INFO - -h, --help show this help message and exit 19:12:37 INFO - -d DEST, --destination=DEST 19:12:37 INFO - Directory to install application into. [default: 19:12:37 INFO - "/builds/slave/test"] 19:12:37 INFO - --app=APP Application being installed. [default: firefox] 19:12:37 INFO - mkdir: /builds/slave/test/build/application 19:12:37 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:12:37 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:54 INFO - Reading from file tmpfile_stdout 19:12:54 INFO - Output received: 19:12:54 INFO - /builds/slave/test/build/application/firefox/firefox 19:12:54 INFO - Running post-action listener: _resource_record_post_action 19:12:54 INFO - [mozharness: 2017-08-27 02:12:54.867186Z] Finished install step (success) 19:12:54 INFO - [mozharness: 2017-08-27 02:12:54.867466Z] Running setup-mitmproxy step. 19:12:54 INFO - Running pre-action listener: _resource_record_pre_action 19:12:54 INFO - Running main action method: setup_mitmproxy 19:12:54 INFO - Skipping: mitmproxy is not required 19:12:54 INFO - Running post-action listener: _resource_record_post_action 19:12:54 INFO - [mozharness: 2017-08-27 02:12:54.868598Z] Finished setup-mitmproxy step (success) 19:12:54 INFO - [mozharness: 2017-08-27 02:12:54.868819Z] Running run-tests step. 19:12:54 INFO - Running pre-action listener: _resource_record_pre_action 19:12:54 INFO - Running pre-action listener: _set_gcov_prefix 19:12:54 INFO - Running main action method: run_tests 19:12:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:12:54 INFO - Python 2.7.3 19:12:54 INFO - Return code: 0 19:12:54 INFO - grabbing minidump binary from tooltool 19:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:54 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 0x1d33130>, '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 0x1d34030>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d344c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:12:54 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:54 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:54 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:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:12:54 INFO - Return code: 0 19:12:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:12:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:12:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:12:54 INFO - ENV: RUST_BACKTRACE is now full 19:12:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:12:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:12:54 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-020', '--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:54 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-020 --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:55 INFO - Using env: {'DISPLAY': ':0', 19:12:55 INFO - 'HOME': '/home/cltbld', 19:12:55 INFO - 'LANG': 'en_US.UTF-8', 19:12:55 INFO - 'LANGUAGE': 'en_US:en', 19:12:55 INFO - 'LOGNAME': 'cltbld', 19:12:55 INFO - 'MAIL': '/var/mail/cltbld', 19:12:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:12:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:12:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:55 INFO - 'MOZ_NO_REMOTE': '1', 19:12:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:12:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:55 INFO - 'NO_EM_RESTART': '1', 19:12:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:55 INFO - 'PWD': '/builds/slave/test', 19:12:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:12:55 INFO - 'RUST_BACKTRACE': 'full', 19:12:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:12:55 INFO - 'SHELL': '/bin/bash', 19:12:55 INFO - 'SHLVL': '1', 19:12:55 INFO - 'STYLO_FORCE_ENABLED': '1', 19:12:55 INFO - 'TERM': 'linux', 19:12:55 INFO - 'TMOUT': '86400', 19:12:55 INFO - 'USER': 'cltbld', 19:12:55 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:12:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:55 INFO - '_': '/tools/buildbot/bin/python'} 19:12:55 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-020', '--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:55 INFO - mozversion application_buildid: 20170827011816 19:12:55 INFO - mozversion application_changeset: f819969d7619f01e806e2685b8b3196f64624551 19:12:55 INFO - mozversion application_display_name: Nightly 19:12:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:12:55 INFO - mozversion application_name: Firefox 19:12:55 INFO - mozversion application_remotingname: firefox 19:12:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:12:55 INFO - mozversion application_vendor: Mozilla 19:12:55 INFO - mozversion application_version: 57.0a1 19:12:55 INFO - mozversion platform_buildid: 20170827011816 19:12:55 INFO - mozversion platform_changeset: f819969d7619f01e806e2685b8b3196f64624551 19:12:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:12:55 INFO - mozversion platform_version: 57.0a1 19:12:55 INFO - using testdate: 1503799975 19:12:55 INFO - actual date: 1503799975 19:12:55 INFO - starting webserver on 'localhost:56282' 19:12:55 INFO - SUITE-START | Running 2 tests 19:12:55 INFO - TEST-START | dromaeo_css 19:12:55 INFO - Initialising browser for dromaeo_css test... 19:12:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56282/getInfo.html -profile /tmp/tmpLvtq38/profile 19:12:55 INFO - TEST-INFO | started process 32162 (/builds/slave/test/build/application/firefox/firefox http://localhost:56282/getInfo.html) 19:13:02 INFO - TEST-INFO | 32162: exit 0 19:13:02 INFO - Browser initialized. 19:13:02 INFO - Running cycle 1/1 for dromaeo_css test... 19:13:02 INFO - Using env: {'DISPLAY': ':0', 19:13:02 INFO - 'HOME': '/home/cltbld', 19:13:02 INFO - 'JSGC_DISABLE_POISONING': '1', 19:13:02 INFO - 'LANG': 'en_US.UTF-8', 19:13:02 INFO - 'LANGUAGE': 'en_US:en', 19:13:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:13:02 INFO - 'LOGNAME': 'cltbld', 19:13:02 INFO - 'MAIL': '/var/mail/cltbld', 19:13:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:13:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:13:02 INFO - 'MOZ_CRASHREPORTER': '1', 19:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:13:02 INFO - 'MOZ_NO_REMOTE': '1', 19:13:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:02 INFO - 'NO_EM_RESTART': '1', 19:13:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:02 INFO - 'PWD': '/builds/slave/test', 19:13:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:13:02 INFO - 'RUST_BACKTRACE': 'full', 19:13:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:13:02 INFO - 'SHELL': '/bin/bash', 19:13:02 INFO - 'SHLVL': '1', 19:13:02 INFO - 'STYLO_FORCE_ENABLED': '1', 19:13:02 INFO - 'TERM': 'linux', 19:13:02 INFO - 'TMOUT': '86400', 19:13:02 INFO - 'USER': 'cltbld', 19:13:02 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:02 INFO - '_': '/tools/buildbot/bin/python'} 19:13:02 INFO - TEST-INFO | started process 32351 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLvtq38/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:13:03 INFO - PID 32351 | 19:13:03 INFO - PID 32351 | (/builds/slave/test/build/application/firefox/firefox:32401): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:13:03 INFO - PID 32351 | 19:13:04 INFO - PID 32351 | 19:13:04 INFO - PID 32351 | (/builds/slave/test/build/application/firefox/firefox:32464): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:13:04 INFO - PID 32351 | 19:13:04 INFO - PID 32351 | __metrics Screen width/height:1600/1200 19:13:04 INFO - PID 32351 | colorDepth:24 19:13:04 INFO - PID 32351 | Browser inner width/height: 1024/768 19:13:04 INFO - PID 32351 | __metrics 19:13:14 INFO - PID 32351 | RSS: Main: 174776320 19:13:14 INFO - PID 32351 | 19:16:12 INFO - PID 32351 | Cycle 1(1): loaded http://localhost:56282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56282/tests/dromaeo/cssquery-ext.html) 19:16:14 INFO - PID 32351 | RSS: Main: 178188288 19:16:14 INFO - PID 32351 | 19:19:15 INFO - PID 32351 | Cycle 1(1): loaded http://localhost:56282/tests/dromaeo/cssquery-ext.html (next: http://localhost:56282/tests/dromaeo/cssquery-jquery.html) 19:19:16 INFO - PID 32351 | RSS: Main: 174837760 19:19:16 INFO - PID 32351 | 19:22:18 INFO - PID 32351 | Cycle 1(1): loaded http://localhost:56282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56282/tests/dromaeo/cssquery-mootools.html) 19:22:18 INFO - PID 32351 | RSS: Main: 177111040 19:22:18 INFO - PID 32351 | 19:25:20 INFO - PID 32351 | Cycle 1(1): loaded http://localhost:56282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56282/tests/dromaeo/cssquery-prototype.html) 19:25:20 INFO - PID 32351 | RSS: Main: 175419392 19:25:20 INFO - PID 32351 | 19:28:24 INFO - PID 32351 | Cycle 1(1): loaded http://localhost:56282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56282/tests/dromaeo/cssquery-yui.html) 19:28:24 INFO - PID 32351 | RSS: Main: 174845952 19:28:24 INFO - PID 32351 | 19:31:26 INFO - PID 32351 | Cycle 1(1): loaded http://localhost:56282/tests/dromaeo/cssquery-yui.html (next: http://localhost:56282/tests/dromaeo/cssquery-dojo.html) 19:31:26 INFO - PID 32351 | RSS: Main: 179187712 19:31:26 INFO - PID 32351 | 19:31:26 INFO - PID 32351 | __start_tp_report 19:31:26 INFO - PID 32351 | _x_x_mozilla_page_load 19:31:26 INFO - PID 32351 | _x_x_mozilla_page_load_details 19:31:26 INFO - PID 32351 | |i|pagename|runs| 19:31:26 INFO - PID 32351 | |0;dojo.html;3308.950365744513;3326.417876871548;3329.0345799718084;3354.480055591381;3355.848986795594;6297.149884765556;6389.648569328685;6393.392627700367;6421.165248517692;6437.613743175412;6239.812805615838;6347.36526347365;6348.984162533996;6363.66363063756;6381.2342518897685;5849.941500584982;5875.735591898353;5885.234919460486;5901.232839730829;5925.614835035709;5932.436418540232;5967.134765459005;5975.25309336333;5987.760489580412;6029.939700603004;5874.383189765077;5944.2866855977345;5970.6716130612795;5975.970120149394;6025.0058740307795;5750.741216645261;5818.301803783573;5902.675352855591;5909.320428150775;5975.1037344398255;5846.035404158309;5882.705864706748;5883.499902508292;5911.408859114097;5917.20117784098;6264;6272.466840318579;6381.425671689523;6428.646424446653;6431.067495213168;6772.458203343721;6822.4200942919915;6873.621950792704;6904.10246667935;6924.030635711005;6967.338102880219;7001.89497157538;7018.087648605649;7018.40343570792;7021.9297807021785;6365.427111559936;6387.393197646216;6392.169018027626;6468.3855033771715;6473.029045643191;7003.964980175067;7016.193137789118;7081.858362832818;7101.11236095488;7112.857742845114;6738.831529211809;6777.356151165632;6781.864362712817;6803.965980170166;6819.897701534481;6829.214640316328;6837.897431538431;6918.792436226921;6938.653067346612;6942.930570694229;6702.430293425016;6793.048973143764;6797.830054248683;6805.795826125224;6904.758333458305;6782.898256526058;6786.728530858809;6790.287019862843;6798.320167983261;6799.966000170067;5349.652272602346;5370.355557333145;5371.274877891513;5380.704061276668;5415.350157981067;4688.531146885352;4702.506236845151;4765.309030190603;4774.4509381421585;4841.661083724176;5972.790952316735;5973.462388385066;5975.133605627159;5976.043832986788;5985.730642121115;6752.594844309259;6794.8301292467095;6802.285759995229;6811.863762724718;6816.352446517573;5946.4648181663;6033.909491357633;6041.24484439445;6070.848228794227;6070.939290607126;5840.678762668094;5854.121881717776;5910.645361278337;5918.733656985446;5927.051671732588;6408.55140140195;6514.967425162844;6550.737970481223;6567.146270984741;6650.035744816974;6787.355201255873;6842.623655698984;6860.931390686229;6881.174259088942;6887.6556172190185;6571.5399922005;6601.438877695449;6616.139901812733;6648;6685.0306705526455;6744.730210791513;6750.0887380205;6830.829229269308;6839.69221385029;6843.863122737418;6854.074699915447;6857.348551887762;6878.243393226641;6906.929425938987;6994.230634630085;6457.418832305115;6602.10871532337;6645.568038077509;6682.899756503559;6697.698603562754;6649.60102393858;6663.566868153554;6697.196336439659;6703.02806093104;6703.93296067033;6521.934780652133;6542.54202205841;6613.173353330833;6637.369449902251;6683.63240021784;6504.772332968323;6621.503387246072;6683.498737594603;6712.160979877515;6784.76253331131;6386.872262554816;6451.70967306463;6507.12153859223;6531.085648009187;6539.934600653933 19:31:26 INFO - PID 32351 | |1;ext.html;825.8430898129355;860.9612567434467;882.9690960816373;889.5996801439354;1126.6394753678826;30244.243893902636;30975.69024309756;39052;40557.18885622293;40940.59059409412;40754;41062.384064238984;41154;41157.17685646285;41171.58828411715;7679.654415551314;7763.068431788193;7837.177096404881;7921.841563168762;7923.326517246041;7702.576358300291;7781.455298129134;7812.609369531529;7813.335866451358;7813.492123012014;7749.380049596047;7788.532688038707;7794.298513133816;7801.726939557117;7828.843423131534;7762.611869406535;7795.33739632132;7795.727149549795;7797.727079552217;7799.493032952868;7660.808479787994;7698.384129269645;7704.3451306639;7708.884366734504;7716.382689384835;37375.62624373749;37569.06077348061;37666.811665941495;37672.811635941645;37707.057323566856;3476.28736109097;3480.095175254427;3491.039964009892;3504.7196224301993;3507.1232191952013;31299.84350078258;31358.37283254345;31360.37279254425;31368.05895823129;31379.215519612193;5149.356330458692;5170.793168273302;5172.612054095958;5198.038362902875;5198.376194856603;184832;184978;185136;185648;186290;47250.05499889983;47360.28959565609;47467.28799068017;47477;47483;49392.753036235306;49477;49483.75258123686;49600;49647.25529117066;14434.133951962916;14622.26888655563;14629.195394253205;14665.48670796517;14682.265886705622;11908.702282443008;12126.27242365461;13359.198448093146;13605.387757550934;13637.181769093886;14055.297235138201;14057.648558786112;14068.71862562743;14072.366743496566;14197.78703319451;5781.19063331134;5816.767329306865;5856.4143585641095;5860.794872179538;5866.413358664185;999.9550020248961;1008.369768894441;1011.1556850030161;1020.9234307426973;1026.5688410867454;22525.887370563043;28643.28391790222;29040.97545868205;29506.11481655554;30036.849815750782;1073.812082885492;1081.621432498635;1087.1085709718113;1087.8640169978753;1093.4040947683372;12528.310942898046;12621.305828179538;12660.987121030295;16451.42420015318;16668.74996875048;234232;235616;235765;235980;236870;56302;56442;56515.71742141263;56822.43177568254;56974;1455.3169131023183;1458.7738900470445;1463.1934096492969;1473.4327283995524;1486.2791546100132;27462.725372746016;27489.31276718098;27687.723122768515;28132.296692582848;28383;38296.42555361728;38418.80790596029;38449.23101538009;38554.80722596369;38555.80722096372;146224;146674.26662867045;146863;147472;147604;35647.28705425929;37339.62660373362;38922;38952;39105.6089439102;45744.77127614341;46883.76558117187;47109.52890471051;47172.292415613105;47541;35136.29727405387;35225.29549408946;35316.646833532366;35391.292174156886;35905;34915.30169396649;34921.47617785684;35028.47457288194;35239.647603523634;35887.46168807522;39023;39354;39358.60641393549;39395.40906886342;39405.2118957625;33986.8300658505;34001.65998339985;34019.31961360709;34175.658243417245;34399.484007739404;24171.51656966886;24662.87668561646;24895.626565601167;25093.74906250914;25382.111626093 19:31:26 INFO - PID 32351 | |2;jquery.html;560.302423482764;564.3960961770907;565.3893794701723;574.7729646789521;574.8936446757349;21358.679619805716;22257.44356391091;22261;22305.776942230612;23784.5243095138;23705.170319038836;24015.51968960624;24076.398090047715;24146.637800433007;24155.275341739776;6471.838204044901;6604.900926486107;6610.669466526679;6623.9006414903815;6628.966855165718;5989.43100405463;6458.321876203002;6543.280239173687;6574.079628851964;6581.80254592363;6557.278699343068;6561.3438656134485;6561.507886908501;6562.671866406685;6564.343565643445;6547.9672601636685;6548.476121910236;6557.147570815787;6570.080188773575;6570.310117437672;6069.605475644069;6072.757089716406;6083.482903953125;6091.025435930274;6096.329403765582;28047.57928631072;28970.85514572435;29110.854445727637;29151;29495.262618434495;2000.0499762612642;2038.1337931379253;2039.8266147377342;2040.6938959156246;2043.5095577061397;18937.715934260996;19096.71354929677;19817.207311707516;19820.603587928304;19829.9008504958;2460.6801115855005;2465.704115506133;2520.4959008198252;2532.2023562577915;2536.771690547841;131605;136137;137724;144585;144977;14507.925534541462;14790.03864748788;14880.70238595222;14906;15011.924940375447;22644.660330095063;22710.772892271198;22727.772722272566;22757.772422275895;22771.544569108526;9371.875374955049;9430.292728045424;9465.384749991228;9501.334906556613;10187.490625468698;8474.406791524658;8500.617472213642;8548.187922147386;8642.135786421433;8646.13538646143;8852.822943541094;9073.274138068939;9091.545422728837;9098.818023639491;9109.590068446936;2679.383741739372;2688.9058882939394;2723.087765598547;2739.9726002740117;2837.262311798947;947.9431234125974;948.9715308540755;961.7307153996892;991.0931497679543;997.0378584665832;18651.90674046621;18914.24343026291;19142.904285478482;19193.712094318595;19500.51248718765;1017.3590637898225;1019.7093828258911;1035.5650626736788;1038.1539045677748;1040.3029969920021;10337.793244135226;10566.471676416148;10672.626458074086;10793.78412431747;10904.836427453594;461770;467350;467927;482421;484939;106580;109434;111222;111759.44120279509;112208.43895780633;1154.2382027861572;1154.3939431798206;1176.2651834484857;1188.2039033847343;1189.8750631183775;16871.91564042172;17435.47693569195;17453.38913138034;17503.03733294681;17969.910150449163;53673.46326536841;55864.720676396355;56548.71725641345;57513;59038.70480647569;66869.66565167143;68949.31050689428;69132.30867691258;69534.3046569548;70638;13943.930280348533;14099.718005639625;14202.644933876736;14219.64450888736;14284.42862285497;22015.559688806454;22429.5514089714;22458.213962511232;22482.887585561966;22500.66249006299;17501.737473937646;17562.82437175663;17711.557211069827;17847.821521785136;17864.91067544654;15768.975016624341;15958.840411595735;16125.435609754071;16369;16390;17984.2806287747;17986.190621421807;18327.541811454288;18405.355812546495;18487.90756046211;13902.096363736326;13996.580102596938;14010.78983815264;14017.859821402064;14034.368453419826;17659.381921633194;17716.025618590586;18090.45728628143;18117.45647630573;18129.093545322943 19:31:26 INFO - PID 32351 | |3;mootools.html;1303.4720938020102;1392.4778208171936;1392.7910813377998;1399.349302574303;1403.5087719298247;14254.928725356373;14505.347259373328;14902.92548537257;14908.329125189366;14944.85055149448;15040.774388384183;15360.539183824476;15365.539033829004;15372.154531500762;15404.306806193721;2748.395353022332;2796.6783819860775;2802.9159125226174;2805.635267415233;2827.9858600706966;2684.7583717465423;2755.6968733439307;2862.9427411451757;2873.4684083444527;2879.280179955011;2781.666200055996;2788.8326700398043;2819.6334476518023;2824.8305101693863;2831.2072619666424;2706.377533167373;2725.059854350246;2758.613794068832;2759.365345970428;2759.8344099354003;2675.357914100621;2701.716319786414;2747.917562473129;2754.352727109128;2759.862006899647;14120.576382708432;14390;14412.279386030657;14433.927830360886;14443.133411995314;7811.765647030598;8104.351651867836;8115.269625733712;8124.959375203145;8127.59362031896;5357.7589008494715;5567.969925563743;5649.915251271234;5667;5675.914861277084;1899.705545640428;1968.055333439957;1970.2020681623965;2004.1282042311495;2005.2780998840406;24571.262862114167;24985.875070624712;25007.62488562673;25074;25143.62284565733;7036.859262814715;7153.4992550520965;7186.820329491805;7308.5249458786075;7335;19216.807831921502;19274.518137046685;19292.4212273632;19300.71048934267;19305.51736206606;7960.880586791202;8240.13478584752;8242.546659933761;8242.587870606445;8279.337652987746;7313.89029164563;7343.449241306924;7447.851042979219;7470;7517.736879209201;5509.228707980886;5658.122990936411;5675.347335056439;5716.5998380113;5721.313442386857;1712.5632963594453;1782.073321872619;1785.2323500894481;1791.2029147029707;1804.8556115510728;859.5014891362995;882.6248844241172;896.9641214351353;900.8873890763655;908.8591268353283;7268.164161121433;7320.597367144752;7799.727009554638;7945.48354356965;8032.196780321921;963.8361478548524;964.1178321835582;978.2663002747939;987.3138168972495;992.6674564021134;4680.648951328664;5039.26930595069;5255.684658920477;5445.9727701362035;5481.890362192734;20033.198672052953;20600.896995515228;20724.378268651966;20725.067371968296;20842.895785520974;19417.32039378615;19941.900290498455;20398.28605998783;20579.07394167266;20627.8968605156;10951.397673127894;11271.718207044889;11286.532750742352;11286.943565282121;11398.715032124263;7631;7933.880991785012;8295.211954864279;8394.832103358021;8473.618687158969;17170.313187472362;17837.108144592978;17854.464366069038;17894.194761236035;17952.371666991592;18135.818641813414;18318.35134837871;18385.724214137066;18588.070596469854;18692.25230990745;11308.151888608223;11321.207515474167;11629.825552616887;11639.825402618799;11646.24299420535;13262.204267743966;13385.732285354434;13419;13433.865661343261;13447.260400677666;10903.56385744593;10937.070349020423;11032.44837758125;11086.334819910831;11156.10751139923;10592.152627789605;10649.467526623483;10731.087857532197;10847;11055.889441105486;8994.325425593237;9161.954190229006;9168.358214925158;9171.770705732144;9181.77045573866;6844.4524438043845;6940.236573976757;6969;6970.860582788215;6997.05539752127;6649.0691303218555;6678.365555272241;6700.966495167493;6746.224184218681;6824.317568243136 19:31:26 INFO - PID 32351 | |4;prototype.html;508.290934146865;509.44470527125424;516.9741512924353;528.9894202115958;529.4652401074916;6355;6449.484041276698;6462.709178086986;6478.54650174488;6490.592438969544;6341.04884267359;6373.362663733625;6391.6804159792055;6397.840053998652;6407.903881441782;5248.396434410034;5265.34141745349;5271.630985830993;5314.734263286839;5323.733813309338;4909.128160026371;5209.635325527214;5278.627556835231;5307.177387504943;5317.787288508455;4960.379952505937;5014.799408023675;5067.797288108472;5076.187809950408;5079.568236699885;5010.866035520674;5051.26756620288;5054.74726263689;5063.8480845574695;5077.1876499759865;4405.801738921757;4514.71102452668;4522.706024108423;4527.388802511652;4551.431071116111;6175.814725558241;6222.697601887286;6235.594686345373;6249.156363890862;6278.152449419315;5769.278840144982;5775.162601422812;5780.826575202751;5781.6295318919465;5800.4489573490455;6716.865662686719;6726.764563240312;6734.191896972395;6765.729370825161;6766.25571187169;4927.162382395001;4939.234418665113;4944.184209605411;4949.925751113737;4974.129527332702;6721.193456785169;6826.419754320839;6908.205556361033;6908.896366554506;7008.509404341698;5023.1711767557945;5033.723145226974;5035.597152227813;5046.318746969186;5056.6713163644245;5105.770240339194;5108.923366149511;5112.565431938253;5135.3324067870935;5138.434772175057;5747.25285712863;5764.481196692318;5780.277465316835;5787.131930210502;5805;6746.571234355628;6757.898631520531;6769.221539523018;6774.864502709918;6779.15260592426;1765.5674359781935;1769.5841477252836;1785.9196336164903;1795.44341254209;1796.9550761231073;1161.4076820821442;1218.4212499062874;1218.8476440444945;1231.903605790829;1236.449779847959;5965.7613695452565;6001;6017.638941663514;6041.667708275999;6066.181065556182;6869.175698916162;6870.839413869676;6937.826554336182;6950.026996220533;6966.5471744336455;5906.438888305604;5962.701864906738;5985.760569577168;5998.970005149947;6076.6631341104885;3605.2789442111684;3646.754388594153;3658.9451158232646;3660.8718694846084;3662.8901132966053;6557.639329836905;6586.934130658632;6589.660670462013;6622.701978410983;6657.700403481855;6568.737250510022;6769.864602708017;6999.12510936133;7003.2296447390745;7063.470239732041;6568.770093046721;6605.787061334507;6620.371064748938;6636.369544893228;6646.435053020354;6863.553868998499;6873.828154296182;6881.4494840413645;6913.9974703668695;6927;6774.458043356618;6789.355011273921;6815;6824.454043676395;6830.146231721035;6605.933940660532;6611.966940165461;6626.105475760711;6637.834054148491;6669.2997235289595;5033.219850923113;5050.141475949024;5050.671706339076;5059.569936555434;5076.898462030813;6659.966700166468;6694.518850197773;6871.793846184622;6928.826779330557;6942.826429339307;6587.934120658923;6610.2398224204835;6624.701888414937;6626.602403855784;6640.136782218474;6603.537752357481;6616.908210145272;6660.866782664222;6666.633350158139;6695.330466953265;6576.309487503934;6608.900866487103;6609.173853268341;6620.867582648417;6623.635700036351;6594.373534514213;6615.534514202797;6691.899621505584;6751.56114852533;6754.015131072691;6430.710618022106;6490.902636460362;6501.9674901625185;6503.967480162758;6516.5112616553 19:31:26 INFO - PID 32351 | |5;yui.html;887.6449420231907;890.4612709310865;890.674903660164;907.5507623726256;907.7866701325188;15769;16721.916390418046;16773.748393774073;16776.496705098867;19317;17596.912015439935;17729.822701772977;20186.69719954198;21224.57550848982;21314.786852131474;871.9215270625658;897.5871099294333;903.8101998580306;904.1862323908503;904.7240591619553;904.4573256046352;943.0333907744548;954.6467806911453;955.2883102088954;955.5890966884238;989.4904124375952;991.5637119667359;992.1665800727424;992.9900700993006;997.0627610046533;986.6743974488402;987.1017374189489;987.1855719031828;987.7530617345664;987.9456629885353;719.4172720096739;724.1274264511251;727.447140173472;728.0680728667314;728.8214387475102;18240.726389104173;18582.814171858245;18686.626267474574;18750.906245468686;18756.71864922027;20928;21074.262400815896;21613.783862161334;21617.56764864694;21693.891530542405;16723.247453864606;16742.41401550952;16830.24263908115;16851.157442127846;16932.492025239262;3756.779046809798;3786.413105968579;3787.772733635991;3790.5830358660523;3791;132306.04622791903;132676.33661831726;133308.99999999904;137044;137364.31317843447;16211.27049282785;16615.916920415322;16632;16830.83169168317;16841.41055063067;28941.276468088465;29307.560386594218;29838.40323193524;29884.10347689573;30241.395172096436;8886.155815197675;9031.683891063782;9059.773505662411;9077.137671921157;9092.318076144316;4080.367543030835;4186.1418409226035;4243.893902652397;4255.255330317182;4257;5034.345535080416;5131.5638170755155;5132.538071573576;5133.512316329943;5148.716820574904;1005.6832097889142;1038.5689938675516;1044.566504900473;1046.2467023742893;1050.968470945873;679.9150106236721;682.3517658224706;697.3096634332077;698.1412862179548;705.3510770091528;10822.40476773785;10999.725006874893;11079.944600276947;11298.322100673986;12402.379881006094;708.7838209346096;711.0507472524238;718.989215161773;723.4357201382929;725.0176011504441;5132.56373208274;5692.288463942007;5771.192033115367;5791.3339965903615;5845.210896528914;97980;104902.47548762207;105655;105959.47020264849;107310;93299.53350233205;95469;96794;97511;98601;84483;84767;85091;87880.12119878847;88570.55714721385;11101.222914396238;11103.111751060058;11276;11424.257423267461;11501.942490287494;39298;39711;40500.79749601233;40731.38902916399;41593.79203103965;50794.98410031853;52840;52906.735466322425;53620;53753.19370209372;17819.732704009708;18097.457076287734;18264.36074737378;18386;18438.631227375645;17270.913645431694;17291.221895015005;17347.306107756052;17376.652466950844;17541.210645521238;15672.529824105295;15770.13264270476;15782.447614333443;15883.047017179006;15892.443764468651;15071.09573425598;15087.170205638795;15138.394464221308;15245.923770381078;15315.846841531442;15476.9226153873;15548.84451155474;15603.609909752347;15663.216839157769;15678.608034798764;11809.350485723367;11844.348560829238;11941.16411851162;11948.880511195124;12052.457639406073;12004.639860804189;12226.510939562319;12226.87773122293;12351.505939762308;12590.496380145058 19:31:26 INFO - PID 32351 | __end_tp_report 19:31:26 INFO - PID 32351 | __start_cc_report 19:31:26 INFO - PID 32351 | _x_x_mozilla_cycle_collect,371 19:31:26 INFO - PID 32351 | __end_cc_report 19:31:26 INFO - PID 32351 | __startTimestamp1503801086872__endTimestamp 19:31:26 INFO - PID 32351 | 19:31:26 INFO - PID 32351 | ------- Summary: start ------- 19:31:26 INFO - PID 32351 | Number of tests: 6 19:31:26 INFO - PID 32351 | 19:31:26 INFO - PID 32351 | [#0] dojo.html Cycles:165 Average:6309.10 Median:6521.93 stddev:730.83 (11.2%) stddev-sans-first:694.15 19:31:26 INFO - PID 32351 | Values: 3309.0 3326.4 3329.0 3354.5 3355.8 6297.1 6389.6 6393.4 6421.2 6437.6 6239.8 6347.4 6349.0 6363.7 6381.2 5849.9 5875.7 5885.2 5901.2 5925.6 5932.4 5967.1 5975.3 5987.8 6029.9 5874.4 5944.3 5970.7 5976.0 6025.0 5750.7 5818.3 5902.7 5909.3 5975.1 5846.0 5882.7 5883.5 5911.4 5917.2 6264.0 6272.5 6381.4 6428.6 6431.1 6772.5 6822.4 6873.6 6904.1 6924.0 6967.3 7001.9 7018.1 7018.4 7021.9 6365.4 6387.4 6392.2 6468.4 6473.0 7004.0 7016.2 7081.9 7101.1 7112.9 6738.8 6777.4 6781.9 6804.0 6819.9 6829.2 6837.9 6918.8 6938.7 6942.9 6702.4 6793.0 6797.8 6805.8 6904.8 6782.9 6786.7 6790.3 6798.3 6800.0 5349.7 5370.4 5371.3 5380.7 5415.4 4688.5 4702.5 4765.3 4774.5 4841.7 5972.8 5973.5 5975.1 5976.0 5985.7 6752.6 6794.8 6802.3 6811.9 6816.4 5946.5 6033.9 6041.2 6070.8 6070.9 5840.7 5854.1 5910.6 5918.7 5927.1 6408.6 6515.0 6550.7 6567.1 6650.0 6787.4 6842.6 6860.9 6881.2 6887.7 6571.5 6601.4 6616.1 6648.0 6685.0 6744.7 6750.1 6830.8 6839.7 6843.9 6854.1 6857.3 6878.2 6906.9 6994.2 6457.4 6602.1 6645.6 6682.9 6697.7 6649.6 6663.6 6697.2 6703.0 6703.9 6521.9 6542.5 6613.2 6637.4 6683.6 6504.8 6621.5 6683.5 6712.2 6784.8 6386.9 6451.7 6507.1 6531.1 6539.9 19:31:26 INFO - PID 32351 | 19:31:26 INFO - PID 32351 | [#1] ext.html Cycles:180 Average:36933.59 Median:35767.37 stddev:49975.02 (139.7%) stddev-sans-first:50041.66 19:31:26 INFO - PID 32351 | Values: 825.8 861.0 883.0 889.6 1126.6 30244.2 30975.7 39052.0 40557.2 40940.6 40754.0 41062.4 41154.0 41157.2 41171.6 7679.7 7763.1 7837.2 7921.8 7923.3 7702.6 7781.5 7812.6 7813.3 7813.5 7749.4 7788.5 7794.3 7801.7 7828.8 7762.6 7795.3 7795.7 7797.7 7799.5 7660.8 7698.4 7704.3 7708.9 7716.4 37375.6 37569.1 37666.8 37672.8 37707.1 3476.3 3480.1 3491.0 3504.7 3507.1 31299.8 31358.4 31360.4 31368.1 31379.2 5149.4 5170.8 5172.6 5198.0 5198.4 184832.0 184978.0 185136.0 185648.0 186290.0 47250.1 47360.3 47467.3 47477.0 47483.0 49392.8 49477.0 49483.8 49600.0 49647.3 14434.1 14622.3 14629.2 14665.5 14682.3 11908.7 12126.3 13359.2 13605.4 13637.2 14055.3 14057.6 14068.7 14072.4 14197.8 5781.2 5816.8 5856.4 5860.8 5866.4 1000.0 1008.4 1011.2 1020.9 1026.6 22525.9 28643.3 29041.0 29506.1 30036.8 1073.8 1081.6 1087.1 1087.9 1093.4 12528.3 12621.3 12661.0 16451.4 16668.7 234232.0 235616.0 235765.0 235980.0 236870.0 56302.0 56442.0 56515.7 56822.4 56974.0 1455.3 1458.8 1463.2 1473.4 1486.3 27462.7 27489.3 27687.7 28132.3 28383.0 38296.4 38418.8 38449.2 38554.8 38555.8 146224.0 146674.3 146863.0 147472.0 147604.0 35647.3 37339.6 38922.0 38952.0 39105.6 45744.8 46883.8 47109.5 47172.3 47541.0 35136.3 35225.3 35316.6 35391.3 35905.0 34915.3 34921.5 35028.5 35239.6 35887.5 39023.0 39354.0 39358.6 39395.4 39405.2 33986.8 34001.7 34019.3 34175.7 34399.5 24171.5 24662.9 24895.6 25093.7 25382.1 19:31:26 INFO - PID 32351 | 19:31:26 INFO - PID 32351 | [#2] jquery.html Cycles:180 Average:33839.52 Median:22677.72 stddev:79867.90 (352.2%) stddev-sans-first:80052.87 19:31:26 INFO - PID 32351 | Values: 560.3 564.4 565.4 574.8 574.9 21358.7 22257.4 22261.0 22305.8 23784.5 23705.2 24015.5 24076.4 24146.6 24155.3 6471.8 6604.9 6610.7 6623.9 6629.0 5989.4 6458.3 6543.3 6574.1 6581.8 6557.3 6561.3 6561.5 6562.7 6564.3 6548.0 6548.5 6557.1 6570.1 6570.3 6069.6 6072.8 6083.5 6091.0 6096.3 28047.6 28970.9 29110.9 29151.0 29495.3 2000.0 2038.1 2039.8 2040.7 2043.5 18937.7 19096.7 19817.2 19820.6 19829.9 2460.7 2465.7 2520.5 2532.2 2536.8 131605.0 136137.0 137724.0 144585.0 144977.0 14507.9 14790.0 14880.7 14906.0 15011.9 22644.7 22710.8 22727.8 22757.8 22771.5 9371.9 9430.3 9465.4 9501.3 10187.5 8474.4 8500.6 8548.2 8642.1 8646.1 8852.8 9073.3 9091.5 9098.8 9109.6 2679.4 2688.9 2723.1 2740.0 2837.3 947.9 949.0 961.7 991.1 997.0 18651.9 18914.2 19142.9 19193.7 19500.5 1017.4 1019.7 1035.6 1038.2 1040.3 10337.8 10566.5 10672.6 10793.8 10904.8 461770.0 467350.0 467927.0 482421.0 484939.0 106580.0 109434.0 111222.0 111759.4 112208.4 1154.2 1154.4 1176.3 1188.2 1189.9 16871.9 17435.5 17453.4 17503.0 17969.9 53673.5 55864.7 56548.7 57513.0 59038.7 66869.7 68949.3 69132.3 69534.3 70638.0 13943.9 14099.7 14202.6 14219.6 14284.4 22015.6 22429.6 22458.2 22482.9 22500.7 17501.7 17562.8 17711.6 17847.8 17864.9 15769.0 15958.8 16125.4 16369.0 16390.0 17984.3 17986.2 18327.5 18405.4 18487.9 13902.1 13996.6 14010.8 14017.9 14034.4 17659.4 17716.0 18090.5 18117.5 18129.1 19:31:26 INFO - PID 32351 | 19:31:26 INFO - PID 32351 | [#3] mootools.html Cycles:180 Average:9174.29 Median:2748.16 stddev:6394.62 (232.7%) stddev-sans-first:6385.21 19:31:26 INFO - PID 32351 | Values: 1303.5 1392.5 1392.8 1399.3 1403.5 14254.9 14505.3 14902.9 14908.3 14944.9 15040.8 15360.5 15365.5 15372.2 15404.3 2748.4 2796.7 2802.9 2805.6 2828.0 2684.8 2755.7 2862.9 2873.5 2879.3 2781.7 2788.8 2819.6 2824.8 2831.2 2706.4 2725.1 2758.6 2759.4 2759.8 2675.4 2701.7 2747.9 2754.4 2759.9 14120.6 14390.0 14412.3 14433.9 14443.1 7811.8 8104.4 8115.3 8125.0 8127.6 5357.8 5568.0 5649.9 5667.0 5675.9 1899.7 1968.1 1970.2 2004.1 2005.3 24571.3 24985.9 25007.6 25074.0 25143.6 7036.9 7153.5 7186.8 7308.5 7335.0 19216.8 19274.5 19292.4 19300.7 19305.5 7960.9 8240.1 8242.5 8242.6 8279.3 7313.9 7343.4 7447.9 7470.0 7517.7 5509.2 5658.1 5675.3 5716.6 5721.3 1712.6 1782.1 1785.2 1791.2 1804.9 859.5 882.6 897.0 900.9 908.9 7268.2 7320.6 7799.7 7945.5 8032.2 963.8 964.1 978.3 987.3 992.7 4680.6 5039.3 5255.7 5446.0 5481.9 20033.2 20600.9 20724.4 20725.1 20842.9 19417.3 19941.9 20398.3 20579.1 20627.9 10951.4 11271.7 11286.5 11286.9 11398.7 7631.0 7933.9 8295.2 8394.8 8473.6 17170.3 17837.1 17854.5 17894.2 17952.4 18135.8 18318.4 18385.7 18588.1 18692.3 11308.2 11321.2 11629.8 11639.8 11646.2 13262.2 13385.7 13419.0 13433.9 13447.3 10903.6 10937.1 11032.4 11086.3 11156.1 10592.2 10649.5 10731.1 10847.0 11055.9 8994.3 9162.0 9168.4 9171.8 9181.8 6844.5 6940.2 6969.0 6970.9 6997.1 6649.1 6678.4 6701.0 6746.2 6824.3 19:31:26 INFO - PID 32351 | 19:31:26 INFO - PID 32351 | [#4] prototype.html Cycles:180 Average:5628.48 Median:6348.02 stddev:1580.95 (24.9%) stddev-sans-first:1537.96 19:31:26 INFO - PID 32351 | Values: 508.3 509.4 517.0 529.0 529.5 6355.0 6449.5 6462.7 6478.5 6490.6 6341.0 6373.4 6391.7 6397.8 6407.9 5248.4 5265.3 5271.6 5314.7 5323.7 4909.1 5209.6 5278.6 5307.2 5317.8 4960.4 5014.8 5067.8 5076.2 5079.6 5010.9 5051.3 5054.7 5063.8 5077.2 4405.8 4514.7 4522.7 4527.4 4551.4 6175.8 6222.7 6235.6 6249.2 6278.2 5769.3 5775.2 5780.8 5781.6 5800.4 6716.9 6726.8 6734.2 6765.7 6766.3 4927.2 4939.2 4944.2 4949.9 4974.1 6721.2 6826.4 6908.2 6908.9 7008.5 5023.2 5033.7 5035.6 5046.3 5056.7 5105.8 5108.9 5112.6 5135.3 5138.4 5747.3 5764.5 5780.3 5787.1 5805.0 6746.6 6757.9 6769.2 6774.9 6779.2 1765.6 1769.6 1785.9 1795.4 1797.0 1161.4 1218.4 1218.8 1231.9 1236.4 5965.8 6001.0 6017.6 6041.7 6066.2 6869.2 6870.8 6937.8 6950.0 6966.5 5906.4 5962.7 5985.8 5999.0 6076.7 3605.3 3646.8 3658.9 3660.9 3662.9 6557.6 6586.9 6589.7 6622.7 6657.7 6568.7 6769.9 6999.1 7003.2 7063.5 6568.8 6605.8 6620.4 6636.4 6646.4 6863.6 6873.8 6881.4 6914.0 6927.0 6774.5 6789.4 6815.0 6824.5 6830.1 6605.9 6612.0 6626.1 6637.8 6669.3 5033.2 5050.1 5050.7 5059.6 5076.9 6660.0 6694.5 6871.8 6928.8 6942.8 6587.9 6610.2 6624.7 6626.6 6640.1 6603.5 6616.9 6660.9 6666.6 6695.3 6576.3 6608.9 6609.2 6620.9 6623.6 6594.4 6615.5 6691.9 6751.6 6754.0 6430.7 6490.9 6502.0 6504.0 6516.5 19:31:26 INFO - PID 32351 | 19:31:26 INFO - PID 32351 | [#5] yui.html Cycles:180 Average:22747.28 Median:29572.98 stddev:31935.92 (108.0%) stddev-sans-first:31983.33 19:31:26 INFO - PID 32351 | Values: 887.6 890.5 890.7 907.6 907.8 15769.0 16721.9 16773.7 16776.5 19317.0 17596.9 17729.8 20186.7 21224.6 21314.8 871.9 897.6 903.8 904.2 904.7 904.5 943.0 954.6 955.3 955.6 989.5 991.6 992.2 993.0 997.1 986.7 987.1 987.2 987.8 987.9 719.4 724.1 727.4 728.1 728.8 18240.7 18582.8 18686.6 18750.9 18756.7 20928.0 21074.3 21613.8 21617.6 21693.9 16723.2 16742.4 16830.2 16851.2 16932.5 3756.8 3786.4 3787.8 3790.6 3791.0 132306.0 132676.3 133309.0 137044.0 137364.3 16211.3 16615.9 16632.0 16830.8 16841.4 28941.3 29307.6 29838.4 29884.1 30241.4 8886.2 9031.7 9059.8 9077.1 9092.3 4080.4 4186.1 4243.9 4255.3 4257.0 5034.3 5131.6 5132.5 5133.5 5148.7 1005.7 1038.6 1044.6 1046.2 1051.0 679.9 682.4 697.3 698.1 705.4 10822.4 10999.7 11079.9 11298.3 12402.4 708.8 711.1 719.0 723.4 725.0 5132.6 5692.3 5771.2 5791.3 5845.2 97980.0 104902.5 105655.0 105959.5 107310.0 93299.5 95469.0 96794.0 97511.0 98601.0 84483.0 84767.0 85091.0 87880.1 88570.6 11101.2 11103.1 11276.0 11424.3 11501.9 39298.0 39711.0 40500.8 40731.4 41593.8 50795.0 52840.0 52906.7 53620.0 53753.2 17819.7 18097.5 18264.4 18386.0 18438.6 17270.9 17291.2 17347.3 17376.7 17541.2 15672.5 15770.1 15782.4 15883.0 15892.4 15071.1 15087.2 15138.4 15245.9 15315.8 15476.9 15548.8 15603.6 15663.2 15678.6 11809.4 11844.3 11941.2 11948.9 12052.5 12004.6 12226.5 12226.9 12351.5 12590.5 19:31:26 INFO - PID 32351 | -------- Summary: end -------- 19:31:26 INFO - PID 32351 | 19:31:27 INFO - TEST-INFO | 32351: exit 0 19:31:27 INFO - TEST-OK | dromaeo_css | took 1112437ms 19:31:27 INFO - TEST-START | kraken 19:31:27 INFO - Initialising browser for kraken test... 19:31:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56282/getInfo.html -profile /tmp/tmpUDcJH5/profile 19:31:27 INFO - TEST-INFO | started process 315 (/builds/slave/test/build/application/firefox/firefox http://localhost:56282/getInfo.html) 19:31:34 INFO - TEST-INFO | 315: exit 0 19:31:34 INFO - Browser initialized. 19:31:34 INFO - Running cycle 1/1 for kraken test... 19:31:34 INFO - Using env: {'DISPLAY': ':0', 19:31:34 INFO - 'HOME': '/home/cltbld', 19:31:34 INFO - 'JSGC_DISABLE_POISONING': '1', 19:31:34 INFO - 'LANG': 'en_US.UTF-8', 19:31:34 INFO - 'LANGUAGE': 'en_US:en', 19:31:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 19:31:34 INFO - 'LOGNAME': 'cltbld', 19:31:34 INFO - 'MAIL': '/var/mail/cltbld', 19:31:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:31:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:31:34 INFO - 'MOZ_CRASHREPORTER': '1', 19:31:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:31:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 19:31:34 INFO - 'MOZ_NO_REMOTE': '1', 19:31:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:31:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:31:34 INFO - 'NO_EM_RESTART': '1', 19:31:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:31:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:31:34 INFO - 'PWD': '/builds/slave/test', 19:31:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:31:34 INFO - 'RUST_BACKTRACE': 'full', 19:31:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:31:34 INFO - 'SHELL': '/bin/bash', 19:31:34 INFO - 'SHLVL': '1', 19:31:34 INFO - 'STYLO_FORCE_ENABLED': '1', 19:31:34 INFO - 'TERM': 'linux', 19:31:34 INFO - 'TMOUT': '86400', 19:31:34 INFO - 'USER': 'cltbld', 19:31:34 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303', 19:31:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:31:34 INFO - '_': '/tools/buildbot/bin/python'} 19:31:34 INFO - TEST-INFO | started process 507 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUDcJH5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 19:31:35 INFO - PID 507 | 19:31:35 INFO - PID 507 | (/builds/slave/test/build/application/firefox/firefox:557): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:31:35 INFO - PID 507 | 19:31:36 INFO - PID 507 | 19:31:36 INFO - PID 507 | (/builds/slave/test/build/application/firefox/firefox:619): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 19:31:36 INFO - PID 507 | 19:31:36 INFO - PID 507 | __metrics Screen width/height:1600/1200 19:31:36 INFO - PID 507 | colorDepth:24 19:31:36 INFO - PID 507 | Browser inner width/height: 1024/768 19:31:36 INFO - PID 507 | __metrics 19:31:45 INFO - PID 507 | RSS: Main: 174755840 19:31:45 INFO - PID 507 | 19:31:48 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56282/tests/kraken/driver.html?testName=audio-beat-detection) 19:31:49 INFO - PID 507 | RSS: Main: 178667520 19:31:49 INFO - PID 507 | 19:31:51 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56282/tests/kraken/driver.html?testName=audio-dft) 19:31:52 INFO - PID 507 | RSS: Main: 179318784 19:31:52 INFO - PID 507 | 19:31:55 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56282/tests/kraken/driver.html?testName=audio-fft) 19:31:56 INFO - PID 507 | RSS: Main: 173817856 19:31:56 INFO - PID 507 | 19:31:58 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56282/tests/kraken/driver.html?testName=audio-oscillator) 19:31:58 INFO - PID 507 | RSS: Main: 174338048 19:31:58 INFO - PID 507 | 19:32:01 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:32:01 INFO - PID 507 | RSS: Main: 174768128 19:32:01 INFO - PID 507 | 19:32:04 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56282/tests/kraken/driver.html?testName=imaging-darkroom) 19:32:05 INFO - PID 507 | RSS: Main: 175423488 19:32:05 INFO - PID 507 | 19:32:09 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56282/tests/kraken/driver.html?testName=imaging-desaturate) 19:32:09 INFO - PID 507 | RSS: Main: 175460352 19:32:09 INFO - PID 507 | 19:32:12 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56282/tests/kraken/driver.html?testName=json-parse-financial) 19:32:13 INFO - PID 507 | RSS: Main: 177053696 19:32:13 INFO - PID 507 | 19:32:14 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:32:15 INFO - PID 507 | RSS: Main: 176214016 19:32:15 INFO - PID 507 | 19:32:16 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56282/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:32:17 INFO - PID 507 | RSS: Main: 176398336 19:32:17 INFO - PID 507 | 19:32:19 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:32:20 INFO - PID 507 | RSS: Main: 176463872 19:32:20 INFO - PID 507 | 19:32:23 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:32:23 INFO - PID 507 | RSS: Main: 176549888 19:32:23 INFO - PID 507 | 19:32:26 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:32:27 INFO - PID 507 | RSS: Main: 176709632 19:32:27 INFO - PID 507 | 19:32:29 INFO - PID 507 | Cycle 1(1): loaded http://localhost:56282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56282/tests/kraken/driver.html?testName=ai-astar) 19:32:29 INFO - PID 507 | RSS: Main: 178270208 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | __start_tp_report 19:32:29 INFO - PID 507 | _x_x_mozilla_page_load 19:32:29 INFO - PID 507 | _x_x_mozilla_page_load_details 19:32:29 INFO - PID 507 | |i|pagename|runs| 19:32:29 INFO - PID 507 | |0;ai-astar;99;107;99;107;106;108;110;98;107;108 19:32:29 INFO - PID 507 | |1;audio-beat-detection;149;121;144;175;122;144;143;117;172;120 19:32:29 INFO - PID 507 | |2;audio-dft;165;163;162;163;164;161;164;164;166;168 19:32:29 INFO - PID 507 | |3;audio-fft;76;73;73;164;75;74;72;73;158;75 19:32:29 INFO - PID 507 | |4;audio-oscillator;79;80;138;79;79;78;124;79;83;129 19:32:29 INFO - PID 507 | |5;imaging-gaussian-blur;117;114;114;114;113;113;114;118;115;115 19:32:29 INFO - PID 507 | |6;imaging-darkroom;148;150;148;148;149;148;150;149;151;148 19:32:29 INFO - PID 507 | |7;imaging-desaturate;93;94;94;94;94;94;94;94;93;94 19:32:29 INFO - PID 507 | |8;json-parse-financial;53;53;53;53;54;52;53;53;53;53 19:32:29 INFO - PID 507 | |9;json-stringify-tinderbox;38;38;39;38;38;39;38;38;38;39 19:32:29 INFO - PID 507 | |10;stanford-crypto-aes;74;79;74;74;91;77;78;76;76;76 19:32:29 INFO - PID 507 | |11;stanford-crypto-ccm;119;124;122;122;126;117;122;122;135;168 19:32:29 INFO - PID 507 | |12;stanford-crypto-pbkdf2;169;172;172;174;181;174;169;172;183;170 19:32:29 INFO - PID 507 | |13;stanford-crypto-sha256-iterative;75;63;74;63;65;66;65;68;69;63 19:32:29 INFO - PID 507 | __end_tp_report 19:32:29 INFO - PID 507 | __start_cc_report 19:32:29 INFO - PID 507 | _x_x_mozilla_cycle_collect,957 19:32:29 INFO - PID 507 | __end_cc_report 19:32:29 INFO - PID 507 | __startTimestamp1503801149457__endTimestamp 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | ------- Summary: start ------- 19:32:29 INFO - PID 507 | Number of tests: 14 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#0] ai-astar Cycles:10 Average:104.90 Median:109.00 stddev:4.43 (4.1%) stddev-sans-first:4.16 19:32:29 INFO - PID 507 | Values: 99.0 107.0 99.0 107.0 106.0 108.0 110.0 98.0 107.0 108.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:144.00 stddev:21.03 (14.6%) stddev-sans-first:22.09 19:32:29 INFO - PID 507 | Values: 149.0 121.0 144.0 175.0 122.0 144.0 143.0 117.0 172.0 120.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#2] audio-dft Cycles:10 Average:164.00 Median:164.00 stddev:2.00 (1.2%) stddev-sans-first:2.09 19:32:29 INFO - PID 507 | Values: 165.0 163.0 162.0 163.0 164.0 161.0 164.0 164.0 166.0 168.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#3] audio-fft Cycles:10 Average:91.30 Median:73.50 stddev:36.78 (50.0%) stddev-sans-first:38.59 19:32:29 INFO - PID 507 | Values: 76.0 73.0 73.0 164.0 75.0 74.0 72.0 73.0 158.0 75.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:79.00 stddev:24.78 (31.4%) stddev-sans-first:25.62 19:32:29 INFO - PID 507 | Values: 79.0 80.0 138.0 79.0 79.0 78.0 124.0 79.0 83.0 129.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:114.50 stddev:1.64 (1.4%) stddev-sans-first:1.51 19:32:29 INFO - PID 507 | Values: 117.0 114.0 114.0 114.0 113.0 113.0 114.0 118.0 115.0 115.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#6] imaging-darkroom Cycles:10 Average:148.90 Median:149.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 19:32:29 INFO - PID 507 | Values: 148.0 150.0 148.0 148.0 149.0 148.0 150.0 149.0 151.0 148.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 19:32:29 INFO - PID 507 | Values: 93.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.47 (0.9%) stddev-sans-first:0.50 19:32:29 INFO - PID 507 | Values: 53.0 53.0 53.0 53.0 54.0 52.0 53.0 53.0 53.0 53.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#9] json-stringify-tinderbox Cycles:10 Average:38.30 Median:38.00 stddev:0.48 (1.3%) stddev-sans-first:0.50 19:32:29 INFO - PID 507 | Values: 38.0 38.0 39.0 38.0 38.0 39.0 38.0 38.0 38.0 39.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#10] stanford-crypto-aes Cycles:10 Average:77.50 Median:76.50 stddev:5.04 (6.6%) stddev-sans-first:5.18 19:32:29 INFO - PID 507 | Values: 74.0 79.0 74.0 74.0 91.0 77.0 78.0 76.0 76.0 76.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#11] stanford-crypto-ccm Cycles:10 Average:127.70 Median:123.00 stddev:14.96 (12.2%) stddev-sans-first:15.53 19:32:29 INFO - PID 507 | Values: 119.0 124.0 122.0 122.0 126.0 117.0 122.0 122.0 135.0 168.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:173.00 stddev:4.79 (2.8%) stddev-sans-first:4.78 19:32:29 INFO - PID 507 | Values: 169.0 172.0 172.0 174.0 181.0 174.0 169.0 172.0 183.0 170.0 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:4.41 (6.6%) stddev-sans-first:3.63 19:32:29 INFO - PID 507 | Values: 75.0 63.0 74.0 63.0 65.0 66.0 65.0 68.0 69.0 63.0 19:32:29 INFO - PID 507 | -------- Summary: end -------- 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | 19:32:29 INFO - PID 507 | ###!!! [Child][MessageChannel] Error: (msgtype=0x150001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 19:32:29 INFO - PID 507 | 19:32:30 INFO - PID 507 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 19:32:30 INFO - TEST-INFO | 507: exit 0 19:32:30 INFO - TEST-OK | kraken | took 62790ms 19:32:30 INFO - SUITE-END | took 1175s 19:32:30 INFO - Completed test suite (00:19:35) 19:32:30 INFO - javascript benchmark 19:32:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8530.681867417828, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.950365744513, 3326.417876871548, 3329.0345799718084, 3354.480055591381, 3355.848986795594, 6297.149884765556, 6389.648569328685, 6393.392627700367, 6421.165248517692, 6437.613743175412, 6239.812805615838, 6347.36526347365, 6348.984162533996, 6363.66363063756, 6381.2342518897685, 5849.941500584982, 5875.735591898353, 5885.234919460486, 5901.232839730829, 5925.614835035709, 5932.436418540232, 5967.134765459005, 5975.25309336333, 5987.760489580412, 6029.939700603004, 5874.383189765077, 5944.2866855977345, 5970.6716130612795, 5975.970120149394, 6025.0058740307795, 5750.741216645261, 5818.301803783573, 5902.675352855591, 5909.320428150775, 5975.1037344398255, 5846.035404158309, 5882.705864706748, 5883.499902508292, 5911.408859114097, 5917.20117784098, 6264.0, 6272.466840318579, 6381.425671689523, 6428.646424446653, 6431.067495213168, 6772.458203343721, 6822.4200942919915, 6873.621950792704, 6904.10246667935, 6924.030635711005, 6967.338102880219, 7001.89497157538, 7018.087648605649, 7018.40343570792, 7021.9297807021785, 6365.427111559936, 6387.393197646216, 6392.169018027626, 6468.3855033771715, 6473.029045643191, 7003.964980175067, 7016.193137789118, 7081.858362832818, 7101.11236095488, 7112.857742845114, 6738.831529211809, 6777.356151165632, 6781.864362712817, 6803.965980170166, 6819.897701534481, 6829.214640316328, 6837.897431538431, 6918.792436226921, 6938.653067346612, 6942.930570694229, 6702.430293425016, 6793.048973143764, 6797.830054248683, 6805.795826125224, 6904.758333458305, 6782.898256526058, 6786.728530858809, 6790.287019862843, 6798.320167983261, 6799.966000170067, 5349.652272602346, 5370.355557333145, 5371.274877891513, 5380.704061276668, 5415.350157981067, 4688.531146885352, 4702.506236845151, 4765.309030190603, 4774.4509381421585, 4841.661083724176, 5972.790952316735, 5973.462388385066, 5975.133605627159, 5976.043832986788, 5985.730642121115, 6752.594844309259, 6794.8301292467095, 6802.285759995229, 6811.863762724718, 6816.352446517573, 5946.4648181663, 6033.909491357633, 6041.24484439445, 6070.848228794227, 6070.939290607126, 5840.678762668094, 5854.121881717776, 5910.645361278337, 5918.733656985446, 5927.051671732588, 6408.55140140195, 6514.967425162844, 6550.737970481223, 6567.146270984741, 6650.035744816974, 6787.355201255873, 6842.623655698984, 6860.931390686229, 6881.174259088942, 6887.6556172190185, 6571.5399922005, 6601.438877695449, 6616.139901812733, 6648.0, 6685.0306705526455, 6744.730210791513, 6750.0887380205, 6830.829229269308, 6839.69221385029, 6843.863122737418, 6854.074699915447, 6857.348551887762, 6878.243393226641, 6906.929425938987, 6994.230634630085, 6457.418832305115, 6602.10871532337, 6645.568038077509, 6682.899756503559, 6697.698603562754, 6649.60102393858, 6663.566868153554, 6697.196336439659, 6703.02806093104, 6703.93296067033, 6521.934780652133, 6542.54202205841, 6613.173353330833, 6637.369449902251, 6683.63240021784, 6504.772332968323, 6621.503387246072, 6683.498737594603, 6712.160979877515, 6784.76253331131, 6386.872262554816, 6451.70967306463, 6507.12153859223, 6531.085648009187, 6539.934600653933], "value": 6255.959892879113, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [825.8430898129355, 860.9612567434467, 882.9690960816373, 889.5996801439354, 1126.6394753678826, 30244.243893902636, 30975.69024309756, 39052.0, 40557.18885622293, 40940.59059409412, 40754.0, 41062.384064238984, 41154.0, 41157.17685646285, 41171.58828411715, 7679.654415551314, 7763.068431788193, 7837.177096404881, 7921.841563168762, 7923.326517246041, 7702.576358300291, 7781.455298129134, 7812.609369531529, 7813.335866451358, 7813.492123012014, 7749.380049596047, 7788.532688038707, 7794.298513133816, 7801.726939557117, 7828.843423131534, 7762.611869406535, 7795.33739632132, 7795.727149549795, 7797.727079552217, 7799.493032952868, 7660.808479787994, 7698.384129269645, 7704.3451306639, 7708.884366734504, 7716.382689384835, 37375.62624373749, 37569.06077348061, 37666.811665941495, 37672.811635941645, 37707.057323566856, 3476.28736109097, 3480.095175254427, 3491.039964009892, 3504.7196224301993, 3507.1232191952013, 31299.84350078258, 31358.37283254345, 31360.37279254425, 31368.05895823129, 31379.215519612193, 5149.356330458692, 5170.793168273302, 5172.612054095958, 5198.038362902875, 5198.376194856603, 184832.0, 184978.0, 185136.0, 185648.0, 186290.0, 47250.05499889983, 47360.28959565609, 47467.28799068017, 47477.0, 47483.0, 49392.753036235306, 49477.0, 49483.75258123686, 49600.0, 49647.25529117066, 14434.133951962916, 14622.26888655563, 14629.195394253205, 14665.48670796517, 14682.265886705622, 11908.702282443008, 12126.27242365461, 13359.198448093146, 13605.387757550934, 13637.181769093886, 14055.297235138201, 14057.648558786112, 14068.71862562743, 14072.366743496566, 14197.78703319451, 5781.19063331134, 5816.767329306865, 5856.4143585641095, 5860.794872179538, 5866.413358664185, 999.9550020248961, 1008.369768894441, 1011.1556850030161, 1020.9234307426973, 1026.5688410867454, 22525.887370563043, 28643.28391790222, 29040.97545868205, 29506.11481655554, 30036.849815750782, 1073.812082885492, 1081.621432498635, 1087.1085709718113, 1087.8640169978753, 1093.4040947683372, 12528.310942898046, 12621.305828179538, 12660.987121030295, 16451.42420015318, 16668.74996875048, 234232.0, 235616.0, 235765.0, 235980.0, 236870.0, 56302.0, 56442.0, 56515.71742141263, 56822.43177568254, 56974.0, 1455.3169131023183, 1458.7738900470445, 1463.1934096492969, 1473.4327283995524, 1486.2791546100132, 27462.725372746016, 27489.31276718098, 27687.723122768515, 28132.296692582848, 28383.0, 38296.42555361728, 38418.80790596029, 38449.23101538009, 38554.80722596369, 38555.80722096372, 146224.0, 146674.26662867045, 146863.0, 147472.0, 147604.0, 35647.28705425929, 37339.62660373362, 38922.0, 38952.0, 39105.6089439102, 45744.77127614341, 46883.76558117187, 47109.52890471051, 47172.292415613105, 47541.0, 35136.29727405387, 35225.29549408946, 35316.646833532366, 35391.292174156886, 35905.0, 34915.30169396649, 34921.47617785684, 35028.47457288194, 35239.647603523634, 35887.46168807522, 39023.0, 39354.0, 39358.60641393549, 39395.40906886342, 39405.2118957625, 33986.8300658505, 34001.65998339985, 34019.31961360709, 34175.658243417245, 34399.484007739404, 24171.51656966886, 24662.87668561646, 24895.626565601167, 25093.74906250914, 25382.111626093], "value": 17497.959494831586, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.302423482764, 564.3960961770907, 565.3893794701723, 574.7729646789521, 574.8936446757349, 21358.679619805716, 22257.44356391091, 22261.0, 22305.776942230612, 23784.5243095138, 23705.170319038836, 24015.51968960624, 24076.398090047715, 24146.637800433007, 24155.275341739776, 6471.838204044901, 6604.900926486107, 6610.669466526679, 6623.9006414903815, 6628.966855165718, 5989.43100405463, 6458.321876203002, 6543.280239173687, 6574.079628851964, 6581.80254592363, 6557.278699343068, 6561.3438656134485, 6561.507886908501, 6562.671866406685, 6564.343565643445, 6547.9672601636685, 6548.476121910236, 6557.147570815787, 6570.080188773575, 6570.310117437672, 6069.605475644069, 6072.757089716406, 6083.482903953125, 6091.025435930274, 6096.329403765582, 28047.57928631072, 28970.85514572435, 29110.854445727637, 29151.0, 29495.262618434495, 2000.0499762612642, 2038.1337931379253, 2039.8266147377342, 2040.6938959156246, 2043.5095577061397, 18937.715934260996, 19096.71354929677, 19817.207311707516, 19820.603587928304, 19829.9008504958, 2460.6801115855005, 2465.704115506133, 2520.4959008198252, 2532.2023562577915, 2536.771690547841, 131605.0, 136137.0, 137724.0, 144585.0, 144977.0, 14507.925534541462, 14790.03864748788, 14880.70238595222, 14906.0, 15011.924940375447, 22644.660330095063, 22710.772892271198, 22727.772722272566, 22757.772422275895, 22771.544569108526, 9371.875374955049, 9430.292728045424, 9465.384749991228, 9501.334906556613, 10187.490625468698, 8474.406791524658, 8500.617472213642, 8548.187922147386, 8642.135786421433, 8646.13538646143, 8852.822943541094, 9073.274138068939, 9091.545422728837, 9098.818023639491, 9109.590068446936, 2679.383741739372, 2688.9058882939394, 2723.087765598547, 2739.9726002740117, 2837.262311798947, 947.9431234125974, 948.9715308540755, 961.7307153996892, 991.0931497679543, 997.0378584665832, 18651.90674046621, 18914.24343026291, 19142.904285478482, 19193.712094318595, 19500.51248718765, 1017.3590637898225, 1019.7093828258911, 1035.5650626736788, 1038.1539045677748, 1040.3029969920021, 10337.793244135226, 10566.471676416148, 10672.626458074086, 10793.78412431747, 10904.836427453594, 461770.0, 467350.0, 467927.0, 482421.0, 484939.0, 106580.0, 109434.0, 111222.0, 111759.44120279509, 112208.43895780633, 1154.2382027861572, 1154.3939431798206, 1176.2651834484857, 1188.2039033847343, 1189.8750631183775, 16871.91564042172, 17435.47693569195, 17453.38913138034, 17503.03733294681, 17969.910150449163, 53673.46326536841, 55864.720676396355, 56548.71725641345, 57513.0, 59038.70480647569, 66869.66565167143, 68949.31050689428, 69132.30867691258, 69534.3046569548, 70638.0, 13943.930280348533, 14099.718005639625, 14202.644933876736, 14219.64450888736, 14284.42862285497, 22015.559688806454, 22429.5514089714, 22458.213962511232, 22482.887585561966, 22500.66249006299, 17501.737473937646, 17562.82437175663, 17711.557211069827, 17847.821521785136, 17864.91067544654, 15768.975016624341, 15958.840411595735, 16125.435609754071, 16369.0, 16390.0, 17984.2806287747, 17986.190621421807, 18327.541811454288, 18405.355812546495, 18487.90756046211, 13902.096363736326, 13996.580102596938, 14010.78983815264, 14017.859821402064, 14034.368453419826, 17659.381921633194, 17716.025618590586, 18090.45728628143, 18117.45647630573, 18129.093545322943], "value": 11742.70309129039, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.4720938020102, 1392.4778208171936, 1392.7910813377998, 1399.349302574303, 1403.5087719298247, 14254.928725356373, 14505.347259373328, 14902.92548537257, 14908.329125189366, 14944.85055149448, 15040.774388384183, 15360.539183824476, 15365.539033829004, 15372.154531500762, 15404.306806193721, 2748.395353022332, 2796.6783819860775, 2802.9159125226174, 2805.635267415233, 2827.9858600706966, 2684.7583717465423, 2755.6968733439307, 2862.9427411451757, 2873.4684083444527, 2879.280179955011, 2781.666200055996, 2788.8326700398043, 2819.6334476518023, 2824.8305101693863, 2831.2072619666424, 2706.377533167373, 2725.059854350246, 2758.613794068832, 2759.365345970428, 2759.8344099354003, 2675.357914100621, 2701.716319786414, 2747.917562473129, 2754.352727109128, 2759.862006899647, 14120.576382708432, 14390.0, 14412.279386030657, 14433.927830360886, 14443.133411995314, 7811.765647030598, 8104.351651867836, 8115.269625733712, 8124.959375203145, 8127.59362031896, 5357.7589008494715, 5567.969925563743, 5649.915251271234, 5667.0, 5675.914861277084, 1899.705545640428, 1968.055333439957, 1970.2020681623965, 2004.1282042311495, 2005.2780998840406, 24571.262862114167, 24985.875070624712, 25007.62488562673, 25074.0, 25143.62284565733, 7036.859262814715, 7153.4992550520965, 7186.820329491805, 7308.5249458786075, 7335.0, 19216.807831921502, 19274.518137046685, 19292.4212273632, 19300.71048934267, 19305.51736206606, 7960.880586791202, 8240.13478584752, 8242.546659933761, 8242.587870606445, 8279.337652987746, 7313.89029164563, 7343.449241306924, 7447.851042979219, 7470.0, 7517.736879209201, 5509.228707980886, 5658.122990936411, 5675.347335056439, 5716.5998380113, 5721.313442386857, 1712.5632963594453, 1782.073321872619, 1785.2323500894481, 1791.2029147029707, 1804.8556115510728, 859.5014891362995, 882.6248844241172, 896.9641214351353, 900.8873890763655, 908.8591268353283, 7268.164161121433, 7320.597367144752, 7799.727009554638, 7945.48354356965, 8032.196780321921, 963.8361478548524, 964.1178321835582, 978.2663002747939, 987.3138168972495, 992.6674564021134, 4680.648951328664, 5039.26930595069, 5255.684658920477, 5445.9727701362035, 5481.890362192734, 20033.198672052953, 20600.896995515228, 20724.378268651966, 20725.067371968296, 20842.895785520974, 19417.32039378615, 19941.900290498455, 20398.28605998783, 20579.07394167266, 20627.8968605156, 10951.397673127894, 11271.718207044889, 11286.532750742352, 11286.943565282121, 11398.715032124263, 7631.0, 7933.880991785012, 8295.211954864279, 8394.832103358021, 8473.618687158969, 17170.313187472362, 17837.108144592978, 17854.464366069038, 17894.194761236035, 17952.371666991592, 18135.818641813414, 18318.35134837871, 18385.724214137066, 18588.070596469854, 18692.25230990745, 11308.151888608223, 11321.207515474167, 11629.825552616887, 11639.825402618799, 11646.24299420535, 13262.204267743966, 13385.732285354434, 13419.0, 13433.865661343261, 13447.260400677666, 10903.56385744593, 10937.070349020423, 11032.44837758125, 11086.334819910831, 11156.10751139923, 10592.152627789605, 10649.467526623483, 10731.087857532197, 10847.0, 11055.889441105486, 8994.325425593237, 9161.954190229006, 9168.358214925158, 9171.770705732144, 9181.77045573866, 6844.4524438043845, 6940.236573976757, 6969.0, 6970.860582788215, 6997.05539752127, 6649.0691303218555, 6678.365555272241, 6700.966495167493, 6746.224184218681, 6824.317568243136], "value": 6669.170589575218, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.290934146865, 509.44470527125424, 516.9741512924353, 528.9894202115958, 529.4652401074916, 6355.0, 6449.484041276698, 6462.709178086986, 6478.54650174488, 6490.592438969544, 6341.04884267359, 6373.362663733625, 6391.6804159792055, 6397.840053998652, 6407.903881441782, 5248.396434410034, 5265.34141745349, 5271.630985830993, 5314.734263286839, 5323.733813309338, 4909.128160026371, 5209.635325527214, 5278.627556835231, 5307.177387504943, 5317.787288508455, 4960.379952505937, 5014.799408023675, 5067.797288108472, 5076.187809950408, 5079.568236699885, 5010.866035520674, 5051.26756620288, 5054.74726263689, 5063.8480845574695, 5077.1876499759865, 4405.801738921757, 4514.71102452668, 4522.706024108423, 4527.388802511652, 4551.431071116111, 6175.814725558241, 6222.697601887286, 6235.594686345373, 6249.156363890862, 6278.152449419315, 5769.278840144982, 5775.162601422812, 5780.826575202751, 5781.6295318919465, 5800.4489573490455, 6716.865662686719, 6726.764563240312, 6734.191896972395, 6765.729370825161, 6766.25571187169, 4927.162382395001, 4939.234418665113, 4944.184209605411, 4949.925751113737, 4974.129527332702, 6721.193456785169, 6826.419754320839, 6908.205556361033, 6908.896366554506, 7008.509404341698, 5023.1711767557945, 5033.723145226974, 5035.597152227813, 5046.318746969186, 5056.6713163644245, 5105.770240339194, 5108.923366149511, 5112.565431938253, 5135.3324067870935, 5138.434772175057, 5747.25285712863, 5764.481196692318, 5780.277465316835, 5787.131930210502, 5805.0, 6746.571234355628, 6757.898631520531, 6769.221539523018, 6774.864502709918, 6779.15260592426, 1765.5674359781935, 1769.5841477252836, 1785.9196336164903, 1795.44341254209, 1796.9550761231073, 1161.4076820821442, 1218.4212499062874, 1218.8476440444945, 1231.903605790829, 1236.449779847959, 5965.7613695452565, 6001.0, 6017.638941663514, 6041.667708275999, 6066.181065556182, 6869.175698916162, 6870.839413869676, 6937.826554336182, 6950.026996220533, 6966.5471744336455, 5906.438888305604, 5962.701864906738, 5985.760569577168, 5998.970005149947, 6076.6631341104885, 3605.2789442111684, 3646.754388594153, 3658.9451158232646, 3660.8718694846084, 3662.8901132966053, 6557.639329836905, 6586.934130658632, 6589.660670462013, 6622.701978410983, 6657.700403481855, 6568.737250510022, 6769.864602708017, 6999.12510936133, 7003.2296447390745, 7063.470239732041, 6568.770093046721, 6605.787061334507, 6620.371064748938, 6636.369544893228, 6646.435053020354, 6863.553868998499, 6873.828154296182, 6881.4494840413645, 6913.9974703668695, 6927.0, 6774.458043356618, 6789.355011273921, 6815.0, 6824.454043676395, 6830.146231721035, 6605.933940660532, 6611.966940165461, 6626.105475760711, 6637.834054148491, 6669.2997235289595, 5033.219850923113, 5050.141475949024, 5050.671706339076, 5059.569936555434, 5076.898462030813, 6659.966700166468, 6694.518850197773, 6871.793846184622, 6928.826779330557, 6942.826429339307, 6587.934120658923, 6610.2398224204835, 6624.701888414937, 6626.602403855784, 6640.136782218474, 6603.537752357481, 6616.908210145272, 6660.866782664222, 6666.633350158139, 6695.330466953265, 6576.309487503934, 6608.900866487103, 6609.173853268341, 6620.867582648417, 6623.635700036351, 6594.373534514213, 6615.534514202797, 6691.899621505584, 6751.56114852533, 6754.015131072691, 6430.710618022106, 6490.902636460362, 6501.9674901625185, 6503.967480162758, 6516.5112616553], "value": 5158.882957761945, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [887.6449420231907, 890.4612709310865, 890.674903660164, 907.5507623726256, 907.7866701325188, 15769.0, 16721.916390418046, 16773.748393774073, 16776.496705098867, 19317.0, 17596.912015439935, 17729.822701772977, 20186.69719954198, 21224.57550848982, 21314.786852131474, 871.9215270625658, 897.5871099294333, 903.8101998580306, 904.1862323908503, 904.7240591619553, 904.4573256046352, 943.0333907744548, 954.6467806911453, 955.2883102088954, 955.5890966884238, 989.4904124375952, 991.5637119667359, 992.1665800727424, 992.9900700993006, 997.0627610046533, 986.6743974488402, 987.1017374189489, 987.1855719031828, 987.7530617345664, 987.9456629885353, 719.4172720096739, 724.1274264511251, 727.447140173472, 728.0680728667314, 728.8214387475102, 18240.726389104173, 18582.814171858245, 18686.626267474574, 18750.906245468686, 18756.71864922027, 20928.0, 21074.262400815896, 21613.783862161334, 21617.56764864694, 21693.891530542405, 16723.247453864606, 16742.41401550952, 16830.24263908115, 16851.157442127846, 16932.492025239262, 3756.779046809798, 3786.413105968579, 3787.772733635991, 3790.5830358660523, 3791.0, 132306.04622791903, 132676.33661831726, 133308.99999999904, 137044.0, 137364.31317843447, 16211.27049282785, 16615.916920415322, 16632.0, 16830.83169168317, 16841.41055063067, 28941.276468088465, 29307.560386594218, 29838.40323193524, 29884.10347689573, 30241.395172096436, 8886.155815197675, 9031.683891063782, 9059.773505662411, 9077.137671921157, 9092.318076144316, 4080.367543030835, 4186.1418409226035, 4243.893902652397, 4255.255330317182, 4257.0, 5034.345535080416, 5131.5638170755155, 5132.538071573576, 5133.512316329943, 5148.716820574904, 1005.6832097889142, 1038.5689938675516, 1044.566504900473, 1046.2467023742893, 1050.968470945873, 679.9150106236721, 682.3517658224706, 697.3096634332077, 698.1412862179548, 705.3510770091528, 10822.40476773785, 10999.725006874893, 11079.944600276947, 11298.322100673986, 12402.379881006094, 708.7838209346096, 711.0507472524238, 718.989215161773, 723.4357201382929, 725.0176011504441, 5132.56373208274, 5692.288463942007, 5771.192033115367, 5791.3339965903615, 5845.210896528914, 97980.0, 104902.47548762207, 105655.0, 105959.47020264849, 107310.0, 93299.53350233205, 95469.0, 96794.0, 97511.0, 98601.0, 84483.0, 84767.0, 85091.0, 87880.12119878847, 88570.55714721385, 11101.222914396238, 11103.111751060058, 11276.0, 11424.257423267461, 11501.942490287494, 39298.0, 39711.0, 40500.79749601233, 40731.38902916399, 41593.79203103965, 50794.98410031853, 52840.0, 52906.735466322425, 53620.0, 53753.19370209372, 17819.732704009708, 18097.457076287734, 18264.36074737378, 18386.0, 18438.631227375645, 17270.913645431694, 17291.221895015005, 17347.306107756052, 17376.652466950844, 17541.210645521238, 15672.529824105295, 15770.13264270476, 15782.447614333443, 15883.047017179006, 15892.443764468651, 15071.09573425598, 15087.170205638795, 15138.394464221308, 15245.923770381078, 15315.846841531442, 15476.9226153873, 15548.84451155474, 15603.609909752347, 15663.216839157769, 15678.608034798764, 11809.350485723367, 11844.348560829238, 11941.16411851162, 11948.880511195124, 12052.457639406073, 12004.639860804189, 12226.510939562319, 12226.87773122293, 12351.505939762308, 12590.496380145058], "value": 8713.660366743243, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1490.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 107.0, 99.0, 107.0, 106.0, 108.0, 110.0, 98.0, 107.0, 108.0], "value": 104.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 144.0, 175.0, 122.0, 144.0, 143.0, 117.0, 172.0, 120.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 162.0, 163.0, 164.0, 161.0, 164.0, 164.0, 166.0, 168.0], "value": 164.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 73.0, 164.0, 75.0, 74.0, 72.0, 73.0, 158.0, 75.0], "value": 91.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 138.0, 79.0, 79.0, 78.0, 124.0, 79.0, 83.0, 129.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 114.0, 114.0, 114.0, 113.0, 113.0, 114.0, 118.0, 115.0, 115.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 150.0, 148.0, 148.0, 149.0, 148.0, 150.0, 149.0, 151.0, 148.0], "value": 148.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 54.0, 52.0, 53.0, 53.0, 53.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [38.0, 38.0, 39.0, 38.0, 38.0, 39.0, 38.0, 38.0, 38.0, 39.0], "value": 38.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 79.0, 74.0, 74.0, 91.0, 77.0, 78.0, 76.0, 76.0, 76.0], "value": 77.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 124.0, 122.0, 122.0, 126.0, 117.0, 122.0, 122.0, 135.0, 168.0], "value": 127.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 172.0, 172.0, 174.0, 181.0, 174.0, 169.0, 172.0, 183.0, 170.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 63.0, 74.0, 63.0, 65.0, 66.0, 65.0, 68.0, 69.0, 63.0], "value": 67.1, "unit": "score"}]}]} 19:32:30 INFO - Return code: 0 19:32:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:32:30 INFO - # TBPL SUCCESS # 19:32:30 INFO - Running post-action listener: _package_coverage_data 19:32:30 INFO - Running post-action listener: _resource_record_post_action 19:32:30 INFO - [mozharness: 2017-08-27 02:32:30.756151Z] Finished run-tests step (success) 19:32:30 INFO - Running post-run listener: _resource_record_post_run 19:32:30 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 426106880; Read time: 12; Write time: 585008 19:32:30 INFO - TinderboxPrint: CPU usage
13.1% 19:32:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 19:32:30 INFO - TinderboxPrint: I/O write bytes / time
426,106,880 / 585,008 19:32:30 INFO - TinderboxPrint: CPU idle
8,262.2 (86.9%) 19:32:30 INFO - TinderboxPrint: CPU user
1,195.1 (12.6%) 19:32:30 INFO - TinderboxPrint: Swap in / out
0 / 0 19:32:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 105345024; Read time: 0; Write time: 132112 19:32:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:32:30 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 320761856; Read time: 0; Write time: 452896 19:32:31 INFO - Running post-run listener: _upload_blobber_files 19:32:31 INFO - Blob upload gear active. 19:32:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:32:31 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:32:31 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:32:31 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:32:31 INFO - (blobuploader) - INFO - Open directory for files ... 19:32:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:32:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:32:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:38 INFO - (blobuploader) - INFO - Done attempting. 19:32:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:32:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:32:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:39 INFO - (blobuploader) - INFO - Done attempting. 19:32:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:32:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:32:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:40 INFO - (blobuploader) - INFO - Done attempting. 19:32:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:32:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:32:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:32:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:32:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:32:41 INFO - (blobuploader) - INFO - Done attempting. 19:32:41 INFO - (blobuploader) - INFO - Iteration through files over. 19:32:41 INFO - Return code: 0 19:32:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:32:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:32:41 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49115812227d01d6e867ebb5e844a8f20362622bd91d97748cfc970e793ed78cfee13d85c98af42a434b43fce2e747daf9cfc51a78e35c83463dd2b076023cef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4915d2a03acbcefd481d9efaf683e4e0fd8ebf7b9817a47b849d24c414207147597d1f4989ba6f690211cab9c2c15719851065bb97f0896586c3aec14633ba7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/052566e50fc2849264b04fab2b0f3eb542d2d6bcdc958f2eb78ab4605b3ae97101c6c321285fce4e407b0e245e41ac261601286628dbfcd767d029e1a9cc60e9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c84e78b5ff3ef4390b16625e0179ab34f94d60064f7aca8891e159b8a39a8423c94a505103e4f8d3f02a567cd56c2a743b360e235baccd7142501a8ad9a9fa4a"} 19:32:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:32:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:32:41 INFO - Contents: 19:32:41 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49115812227d01d6e867ebb5e844a8f20362622bd91d97748cfc970e793ed78cfee13d85c98af42a434b43fce2e747daf9cfc51a78e35c83463dd2b076023cef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4915d2a03acbcefd481d9efaf683e4e0fd8ebf7b9817a47b849d24c414207147597d1f4989ba6f690211cab9c2c15719851065bb97f0896586c3aec14633ba7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/052566e50fc2849264b04fab2b0f3eb542d2d6bcdc958f2eb78ab4605b3ae97101c6c321285fce4e407b0e245e41ac261601286628dbfcd767d029e1a9cc60e9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c84e78b5ff3ef4390b16625e0179ab34f94d60064f7aca8891e159b8a39a8423c94a505103e4f8d3f02a567cd56c2a743b360e235baccd7142501a8ad9a9fa4a"} 19:32:41 INFO - Running post-run listener: copy_logs_to_upload_dir 19:32:41 INFO - Copying logs to upload dir... 19:32:41 INFO - mkdir: /builds/slave/test/build/upload/logs 19:32:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.096012 ========= master_lag: 0.75 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-08-26 19:32:41.659071) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-26 19:32:41.663579) ========= 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=af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49115812227d01d6e867ebb5e844a8f20362622bd91d97748cfc970e793ed78cfee13d85c98af42a434b43fce2e747daf9cfc51a78e35c83463dd2b076023cef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4915d2a03acbcefd481d9efaf683e4e0fd8ebf7b9817a47b849d24c414207147597d1f4989ba6f690211cab9c2c15719851065bb97f0896586c3aec14633ba7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/052566e50fc2849264b04fab2b0f3eb542d2d6bcdc958f2eb78ab4605b3ae97101c6c321285fce4e407b0e245e41ac261601286628dbfcd767d029e1a9cc60e9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c84e78b5ff3ef4390b16625e0179ab34f94d60064f7aca8891e159b8a39a8423c94a505103e4f8d3f02a567cd56c2a743b360e235baccd7142501a8ad9a9fa4a"} build_url:https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011307 build_url: 'https://queue.taskcluster.net/v1/task/TahGLksQSDWoPnEX28P56w/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49115812227d01d6e867ebb5e844a8f20362622bd91d97748cfc970e793ed78cfee13d85c98af42a434b43fce2e747daf9cfc51a78e35c83463dd2b076023cef", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4915d2a03acbcefd481d9efaf683e4e0fd8ebf7b9817a47b849d24c414207147597d1f4989ba6f690211cab9c2c15719851065bb97f0896586c3aec14633ba7", "dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/052566e50fc2849264b04fab2b0f3eb542d2d6bcdc958f2eb78ab4605b3ae97101c6c321285fce4e407b0e245e41ac261601286628dbfcd767d029e1a9cc60e9", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c84e78b5ff3ef4390b16625e0179ab34f94d60064f7aca8891e159b8a39a8423c94a505103e4f8d3f02a567cd56c2a743b360e235baccd7142501a8ad9a9fa4a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-26 19:32:41.692934) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:32:41.693291) ========= 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=af724c981e2a0f424dbabf99000001e7-1503799903.697059-350884303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-26 19:32:41.773092) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-26 19:32:41.776429) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-26 19:32:41.776731) ========= ========= Total master_lag: 0.91 =========